-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-05-20 04:03:21 CEST. -- May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "network"... May 20 04:02:00 volumio volumio[25584]: info: Refreshing Cached IP Addresses May 20 04:02:00 volumio sudo[25627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:02:00 volumio sudo[25627]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:00 volumio sudo[25629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:02:00 volumio sudo[25629]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:00 volumio sudo[25627]: pam_unix(sudo:session): session closed for user root May 20 04:02:00 volumio sudo[25629]: pam_unix(sudo:session): session closed for user root May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "services"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "alsa_controller"... May 20 04:02:00 volumio sudo[25638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:02:00 volumio sudo[25638]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:00 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "wizard"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "networkfs"... May 20 04:02:00 volumio volumio[25584]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:00 volumio volumio[25584]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:00 volumio volumio[25584]: info: Starting Udev Watcher for removable devices May 20 04:02:00 volumio volumio[25584]: info: Ignoring mount for partition: boot May 20 04:02:00 volumio volumio[25584]: info: Ignoring mount for partition: volumio May 20 04:02:00 volumio volumio[25584]: info: Ignoring mount for partition: volumio_data May 20 04:02:00 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "volumio_command_line_client"... May 20 04:02:00 volumio volumio[25584]: info: Plugin upnp is not enabled May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "my_music"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "mpd"... May 20 04:02:00 volumio volumio[25584]: info: Plugin upnp_browser is not enabled May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "alarm-clock"... May 20 04:02:00 volumio volumio[25584]: info: Plugin airplay_emulation is not enabled May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "last_100"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "webradio"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "i2s_dacs"... May 20 04:02:00 volumio volumio[25584]: info: Loading plugin "volumiodiscovery"... May 20 04:02:00 volumio volumio[25584]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:00 volumio volumio[25584]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:00 volumio volumio[25584]: *** WARNING *** For more information see May 20 04:02:00 volumio node[25584]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:00 volumio volumio[25584]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:00 volumio volumio[25584]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:00 volumio volumio[25584]: *** WARNING *** For more information see May 20 04:02:00 volumio node[25584]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:00 volumio node[25584]: *** WARNING *** For more information see May 20 04:02:00 volumio node[25584]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:00 volumio node[25584]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:00 volumio node[25584]: *** WARNING *** For more information see May 20 04:02:00 volumio volumio[25584]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:02:00 volumio volumio[25584]: info: Discovery: Started advertising with name: Volumio May 20 04:02:01 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:01 volumio volumio[25584]: info: Loading plugin "soundcloud"... May 20 04:02:01 volumio volumio[25584]: info: Loading plugin "spop"... May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:01 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:02:01 volumio volumio-remote-updater[587]: [2025-05-20 04:02:01] [connect] Successful connection May 20 04:02:02 volumio volumio[25584]: info: Loading plugin "squeezelite_mc"... May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "outputs"... May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "albumart"... May 20 04:02:03 volumio volumio[25584]: info: Plugin example_plugin is not enabled May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "inputs"... May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "updater_comm"... May 20 04:02:03 volumio volumio[25584]: info: Plugin mpdemulation is not enabled May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "rest_api"... May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "websocket"... May 20 04:02:03 volumio volumio[25584]: info: Starting Socket.io Server version 2.3.0 May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "radio_paradise"... May 20 04:02:03 volumio volumio[25584]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:02:03 volumio volumio[25584]: Forking 3 albumart workers May 20 04:02:03 volumio volumio[25584]: info: [1747706523670] [RadioParadise] API delay: 3 May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "backup_restore"... May 20 04:02:03 volumio volumio[25584]: info: Applying required configuration parameters for plugin backup_restore May 20 04:02:03 volumio volumio[25584]: info: Loading plugin "music_services_shield"... May 20 04:02:04 volumio sudo[25638]: pam_unix(sudo:session): session closed for user root May 20 04:02:04 volumio volumio[25584]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:02:04 volumio volumio[25584]: info: Loading plugin "Systeminfo"... May 20 04:02:04 volumio volumio[25584]: info: Loading i18n strings for locale en May 20 04:02:04 volumio volumio[25584]: Updating browse sources language May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::initPlayerControls May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:04 volumio volumio[25584]: Starting albumart workers May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:04 volumio volumio[25584]: Express server listening on port 3000 May 20 04:02:04 volumio volumio[25584]: [Metrics] WebUI: 7s 14.60ms May 20 04:02:04 volumio volumio[25584]: Starting albumart workers May 20 04:02:04 volumio volumio[25584]: info: CoreStateMachine::resetVolumioState May 20 04:02:04 volumio volumio[25584]: info: CoreStateMachine::getcurrentVolume May 20 04:02:04 volumio volumio[25584]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:04 volumio volumio[25584]: Starting albumart workers May 20 04:02:05 volumio volumio[25584]: info: CoreStateMachine::pushState May 20 04:02:05 volumio volumio[25584]: info: CorePlayQueue::getTrack 0 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioPushState May 20 04:02:05 volumio volumio[25584]: info: Volumio Network Manager: Network status updated: 0 May 20 04:02:05 volumio volumio-remote-updater[587]: [2025-05-20 04:02:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747706521 101 May 20 04:02:05 volumio volumio[25584]: 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 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:05 volumio volumio[25584]: info: Reloading queue from file May 20 04:02:05 volumio volumio[25584]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:05 volumio volumio[25584]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:05 volumio volumio[25584]: info: CoreStateMachine::setRepeat false single undefined May 20 04:02:05 volumio volumio[25584]: info: CoreStateMachine::pushState May 20 04:02:05 volumio volumio[25584]: info: CorePlayQueue::getTrack 0 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioPushState May 20 04:02:05 volumio volumio[25584]: info: CoreStateMachine::setRandom false May 20 04:02:05 volumio volumio[25584]: info: CoreStateMachine::pushState May 20 04:02:05 volumio volumio[25584]: info: CorePlayQueue::getTrack 0 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioPushState May 20 04:02:05 volumio volumio[25584]: info: Setting Device type: Raspberry PI May 20 04:02:05 volumio volumio[25584]: info: Completed loading Core Plugins May 20 04:02:05 volumio volumio[25584]: info: Preparing to generate the ALSA configuration file May 20 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:05 volumio volumio[25584]: info: Asound.conf file unchanged, so no further update is needed May 20 04:02:05 volumio volumio[25584]: info: Output device has changed, restarting MPD May 20 04:02:05 volumio volumio[25584]: info: ___________ START PLUGINS ___________ May 20 04:02:05 volumio sudo[25699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:05 volumio sudo[25699]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:05 volumio sudo[25699]: pam_unix(sudo:session): session closed for user root May 20 04:02:05 volumio volumio[25584]: info: ControllerMpd::onStart: Initializing MPD May 20 04:02:05 volumio volumio[25584]: info: Creating MPD Configuration file May 20 04:02:05 volumio sudo[25704]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:05 volumio sudo[25704]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:05 volumio sudo[25711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:05 volumio volumio[25584]: info: [1747706525694] CoreMusicLibrary::Adding element Last_100 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:05 volumio volumio[25584]: info: [1747706525700] CoreMusicLibrary::Adding element Webradio May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:05 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:02:05 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:02:05 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:02:05 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:02:05 volumio sudo[25711]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:05 volumio sudo[25711]: pam_unix(sudo:session): session closed for user root May 20 04:02:05 volumio volumio[25584]: info: Initializing BBC Radios May 20 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:05 volumio sudo[25715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:05 volumio sudo[25715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:05 volumio systemd[1]: mpd.service: Succeeded. May 20 04:02:05 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:05 volumio volumio[25584]: info: [1747706525862] CoreMusicLibrary::Adding element SoundCloud May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:05 volumio volumio[25584]: Cannot find translation for source SoundCloud May 20 04:02:05 volumio systemd[1]: Starting Music Player Daemon... May 20 04:02:05 volumio volumio[25584]: info: Creating Spotify config file May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:05 volumio volumio[25584]: info: [squeezelite_mc] Starting proxy server... May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:05 volumio volumio[25584]: info: [1747706525924] CoreMusicLibrary::Adding element Radio Paradise May 20 04:02:05 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:05 volumio volumio[25584]: Cannot find translation for source SoundCloud May 20 04:02:05 volumio volumio[25584]: Cannot find translation for source Radio Paradise May 20 04:02:06 volumio sudo[25723]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:02:06 volumio sudo[25723]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:06 volumio sudo[25723]: pam_unix(sudo:session): session closed for user root May 20 04:02:06 volumio volumio[25584]: info: Volumio Calling Home May 20 04:02:06 volumio volumio[25584]: info: [squeezelite_mc] Proxy server started on port 45067 May 20 04:02:06 volumio sudo[25764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:02:06 volumio sudo[25764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:06 volumio volumio[25584]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:06 volumio volumio[25584]: info: CoreStateMachine::pushState May 20 04:02:06 volumio volumio[25584]: info: CorePlayQueue::getTrack 0 May 20 04:02:06 volumio volumio[25584]: info: CoreCommandRouter::volumioPushState May 20 04:02:06 volumio volumio[25584]: info: [squeezelite_mc] Server discovery started May 20 04:02:06 volumio volumio[25584]: info: [squeezelite_mc] Player finder started May 20 04:02:06 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:06 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:06 volumio volumio[25584]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:06 volumio volumio[25584]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:02:06 volumio volumio[25584]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:06 volumio volumio[25584]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:02:06 volumio volumio[25584]: at doSend (dgram.js:692:16) May 20 04:02:06 volumio volumio[25584]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:02:06 volumio volumio[25584]: at afterDns (dgram.js:638:5) May 20 04:02:06 volumio volumio[25584]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:02:06 volumio volumio[25584]: errno: -101, May 20 04:02:06 volumio volumio[25584]: code: 'ENETUNREACH', May 20 04:02:06 volumio volumio[25584]: syscall: 'send', May 20 04:02:06 volumio volumio[25584]: address: '255.255.255.255', May 20 04:02:06 volumio volumio[25584]: port: 3483 May 20 04:02:06 volumio volumio[25584]: } May 20 04:02:06 volumio volumio[25584]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:06 volumio sudo[25764]: pam_unix(sudo:session): session closed for user root May 20 04:02:07 volumio sudo[25796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:01 May 20 04:02:07 volumio sudo[25796]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:07 volumio sudo[25796]: pam_unix(sudo:session): session closed for user root May 20 04:02:07 volumio volumio-remote-updater[587]: [2025-05-20 04:02:07] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 20 04:02:07 volumio volumio-remote-updater[587]: [2025-05-20 04:02:07] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 20 04:02:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:02:07 volumio sudo[25715]: pam_unix(sudo:session): session closed for user root May 20 04:02:07 volumio sudo[25704]: pam_unix(sudo:session): session closed for user root May 20 04:02:07 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:02:07 volumio systemd[1]: Started dynamicswap service. May 20 04:02:07 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:07 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:02:07 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 88. May 20 04:02:07 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:02:07 volumio systemd[1]: Started Volumio Backend Module. May 20 04:02:07 volumio systemd[1]: Started dynamicswap service. May 20 04:02:07 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:07 volumio mpd[25749]: May 20 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:08 volumio systemd[1]: Started Music Player Daemon. May 20 04:02:08 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:02:09 volumio volumio[25824]: cset: --> shielding system active with May 20 04:02:09 volumio volumio[25824]: cset: "system" cpuset of CPUSPEC(1-3) with 64 tasks running May 20 04:02:09 volumio volumio[25824]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:02:09 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:02:09 volumio volumio[25809]: info: ------------------------------------------- May 20 04:02:09 volumio volumio[25809]: info: ----- Volumio3 ---- May 20 04:02:09 volumio volumio[25809]: info: ------------------------------------------- May 20 04:02:09 volumio volumio[25809]: info: ----- System startup ---- May 20 04:02:09 volumio volumio[25809]: info: ------------------------------------------- May 20 04:02:10 volumio kernel: hwmon hwmon1: Undervoltage detected! May 20 04:02:10 volumio autossh[18083]: starting ssh (count 17) May 20 04:02:10 volumio autossh[18083]: ssh child pid is 25849 May 20 04:02:10 volumio volumiossh-tunnel[18082]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy May 20 04:02:10 volumio autossh[18083]: ssh exited with error status 255; restarting ssh May 20 04:02:11 volumio volumio[25809]: info: MYVOLUMIO Environment detected May 20 04:02:11 volumio volumio[25809]: info: Plugin folders cleanup May 20 04:02:11 volumio volumio[25809]: info: Scanning into folder /volumio/app/plugins/ May 20 04:02:11 volumio volumio[25809]: info: Scanning category audio_interface May 20 04:02:11 volumio volumio[25809]: info: Scanning category miscellanea May 20 04:02:11 volumio volumio[25809]: info: Scanning category music_service May 20 04:02:11 volumio volumio[25809]: info: Scanning category plugins.json May 20 04:02:11 volumio volumio[25809]: info: Scanning category system_controller May 20 04:02:11 volumio volumio[25809]: info: Scanning category user_interface May 20 04:02:11 volumio volumio[25809]: info: Scanning into folder /data/plugins/ May 20 04:02:11 volumio volumio[25809]: info: Scanning category music_service May 20 04:02:11 volumio volumio[25809]: info: Scanning category system_controller May 20 04:02:11 volumio volumio[25809]: info: Scanning category system_hardware May 20 04:02:11 volumio volumio[25809]: info: Scanning category user_interface May 20 04:02:11 volumio volumio[25809]: info: Plugin folders cleanup completed May 20 04:02:11 volumio volumio[25809]: info: ------------------------------------------- May 20 04:02:11 volumio volumio[25809]: info: ----- Core plugins startup ---- May 20 04:02:11 volumio volumio[25809]: info: ------------------------------------------- May 20 04:02:11 volumio volumio[25809]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:02:11 volumio volumio[25809]: info: Adding plugin upnp to MyMusic Plugins May 20 04:02:11 volumio volumio[25809]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:02:11 volumio volumio[25809]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:02:11 volumio volumio[25809]: info: Loading plugins from folder /data/plugins/ May 20 04:02:11 volumio volumio[25809]: info: Loading plugin "system"... May 20 04:02:11 volumio volumio[25809]: info: Loading plugin "appearance"... May 20 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:12 volumio volumio-remote-updater[587]: [2025-05-20 04:02:12] [connect] Successful connection May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "network"... May 20 04:02:12 volumio volumio[25809]: info: Refreshing Cached IP Addresses May 20 04:02:12 volumio sudo[25856]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:02:12 volumio sudo[25856]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:12 volumio sudo[25858]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:02:12 volumio sudo[25858]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:12 volumio sudo[25856]: pam_unix(sudo:session): session closed for user root May 20 04:02:12 volumio sudo[25858]: pam_unix(sudo:session): session closed for user root May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "services"... May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "alsa_controller"... May 20 04:02:12 volumio sudo[25867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:02:12 volumio sudo[25867]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:12 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "wizard"... May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "networkfs"... May 20 04:02:12 volumio volumio[25809]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:12 volumio volumio[25809]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:12 volumio volumio[25809]: info: Starting Udev Watcher for removable devices May 20 04:02:12 volumio volumio[25809]: info: Ignoring mount for partition: boot May 20 04:02:12 volumio volumio[25809]: info: Ignoring mount for partition: volumio May 20 04:02:12 volumio volumio[25809]: info: Ignoring mount for partition: volumio_data May 20 04:02:12 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "volumio_command_line_client"... May 20 04:02:12 volumio volumio[25809]: info: Plugin upnp is not enabled May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "my_music"... May 20 04:02:12 volumio volumio[25809]: info: Loading plugin "mpd"... May 20 04:02:13 volumio volumio[25809]: info: Plugin upnp_browser is not enabled May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "alarm-clock"... May 20 04:02:13 volumio volumio[25809]: info: Plugin airplay_emulation is not enabled May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "last_100"... May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "webradio"... May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "i2s_dacs"... May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "volumiodiscovery"... May 20 04:02:13 volumio volumio[25809]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:13 volumio volumio[25809]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:13 volumio volumio[25809]: *** WARNING *** For more information see May 20 04:02:13 volumio node[25809]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:13 volumio volumio[25809]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:13 volumio volumio[25809]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:13 volumio volumio[25809]: *** WARNING *** For more information see May 20 04:02:13 volumio node[25809]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:13 volumio node[25809]: *** WARNING *** For more information see May 20 04:02:13 volumio node[25809]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:13 volumio node[25809]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:13 volumio node[25809]: *** WARNING *** For more information see May 20 04:02:13 volumio volumio[25809]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:02:13 volumio volumio[25809]: info: Discovery: Started advertising with name: Volumio May 20 04:02:13 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "soundcloud"... May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:13 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:02:13 volumio volumio[25809]: info: Loading plugin "spop"... May 20 04:02:14 volumio kernel: hwmon hwmon1: Voltage normalised May 20 04:02:14 volumio volumio[25809]: info: Loading plugin "squeezelite_mc"... May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "outputs"... May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "albumart"... May 20 04:02:15 volumio volumio[25809]: info: Plugin example_plugin is not enabled May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "inputs"... May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "updater_comm"... May 20 04:02:15 volumio volumio[25809]: info: Plugin mpdemulation is not enabled May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "rest_api"... May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "websocket"... May 20 04:02:15 volumio volumio[25809]: info: Starting Socket.io Server version 2.3.0 May 20 04:02:15 volumio volumio[25809]: info: Loading plugin "radio_paradise"... May 20 04:02:15 volumio volumio[25809]: Forking 3 albumart workers May 20 04:02:16 volumio volumio[25809]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:02:16 volumio volumio[25809]: info: [1747706536087] [RadioParadise] API delay: 3 May 20 04:02:16 volumio volumio[25809]: info: Loading plugin "backup_restore"... May 20 04:02:16 volumio sudo[25867]: pam_unix(sudo:session): session closed for user root May 20 04:02:16 volumio volumio[25809]: info: Applying required configuration parameters for plugin backup_restore May 20 04:02:16 volumio volumio[25809]: info: Loading plugin "music_services_shield"... May 20 04:02:16 volumio volumio[25809]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:02:16 volumio volumio[25809]: info: Loading plugin "Systeminfo"... May 20 04:02:17 volumio volumio[25809]: Starting albumart workers May 20 04:02:17 volumio volumio[25809]: Starting albumart workers May 20 04:02:17 volumio volumio[25809]: info: Loading i18n strings for locale en May 20 04:02:17 volumio volumio[25809]: Updating browse sources language May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:17 volumio volumio[25809]: Starting albumart workers May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::initPlayerControls May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:17 volumio volumio[25809]: Express server listening on port 3000 May 20 04:02:17 volumio volumio[25809]: [Metrics] WebUI: 8s 596.77ms May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::resetVolumioState May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::getcurrentVolume May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::pushState May 20 04:02:17 volumio volumio[25809]: info: CorePlayQueue::getTrack 0 May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::volumioPushState May 20 04:02:17 volumio volumio[25809]: info: Volumio Network Manager: Network status updated: 0 May 20 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:17 volumio volumio[25809]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:17 volumio volumio[25809]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:17 volumio volumio[25809]: info: Reloading queue from file May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::setRepeat false single undefined May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::pushState May 20 04:02:17 volumio volumio[25809]: info: CorePlayQueue::getTrack 0 May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::volumioPushState May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::setRandom false May 20 04:02:17 volumio volumio[25809]: info: CoreStateMachine::pushState May 20 04:02:17 volumio volumio[25809]: info: CorePlayQueue::getTrack 0 May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:17 volumio volumio[25809]: info: CoreCommandRouter::volumioPushState May 20 04:02:17 volumio volumio[25809]: info: Setting Device type: Raspberry PI May 20 04:02:17 volumio volumio[25809]: info: Completed loading Core Plugins May 20 04:02:17 volumio volumio[25809]: info: Preparing to generate the ALSA configuration file May 20 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:17 volumio volumio[25809]: info: Asound.conf file unchanged, so no further update is needed May 20 04:02:17 volumio volumio[25809]: info: Output device has changed, restarting MPD May 20 04:02:17 volumio sudo[25936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:17 volumio sudo[25936]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:17 volumio volumio[25809]: info: ___________ START PLUGINS ___________ May 20 04:02:17 volumio sudo[25936]: pam_unix(sudo:session): session closed for user root May 20 04:02:17 volumio volumio[25809]: info: ControllerMpd::onStart: Initializing MPD May 20 04:02:17 volumio volumio[25809]: info: Creating MPD Configuration file May 20 04:02:17 volumio sudo[25939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:17 volumio sudo[25939]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:18 volumio sudo[25942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:18 volumio sudo[25942]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:18 volumio sudo[25942]: pam_unix(sudo:session): session closed for user root May 20 04:02:18 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:02:18 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:02:18 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:02:18 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:18 volumio volumio[25809]: info: [1747706538021] CoreMusicLibrary::Adding element Last_100 May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:18 volumio volumio[25809]: info: [1747706538026] CoreMusicLibrary::Adding element Webradio May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:18 volumio sudo[25945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:18 volumio sudo[25945]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:18 volumio volumio[25809]: info: Initializing BBC Radios May 20 04:02:18 volumio systemd[1]: mpd.service: Succeeded. May 20 04:02:18 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:18 volumio systemd[1]: Starting Music Player Daemon... May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:18 volumio volumio[25809]: info: [1747706538174] CoreMusicLibrary::Adding element SoundCloud May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:18 volumio volumio[25809]: Cannot find translation for source SoundCloud May 20 04:02:18 volumio volumio[25809]: info: Creating Spotify config file May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:18 volumio volumio[25809]: info: [squeezelite_mc] Starting proxy server... May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:18 volumio volumio[25809]: info: [1747706538228] CoreMusicLibrary::Adding element Radio Paradise May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:18 volumio volumio[25809]: Cannot find translation for source SoundCloud May 20 04:02:18 volumio volumio[25809]: Cannot find translation for source Radio Paradise May 20 04:02:18 volumio sudo[25951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:02:18 volumio sudo[25951]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:18 volumio sudo[25951]: pam_unix(sudo:session): session closed for user root May 20 04:02:18 volumio volumio[25809]: info: Volumio Calling Home May 20 04:02:18 volumio sudo[25994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:02:18 volumio sudo[25994]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:18 volumio volumio[25809]: info: [squeezelite_mc] Proxy server started on port 33635 May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:18 volumio volumio[25809]: info: CoreStateMachine::pushState May 20 04:02:18 volumio volumio[25809]: info: CorePlayQueue::getTrack 0 May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::volumioPushState May 20 04:02:18 volumio volumio[25809]: info: [squeezelite_mc] Server discovery started May 20 04:02:18 volumio volumio[25809]: info: [squeezelite_mc] Player finder started May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:18 volumio volumio[25809]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:18 volumio volumio[25809]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:02:18 volumio volumio[25809]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:18 volumio volumio[25809]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:02:18 volumio volumio[25809]: at doSend (dgram.js:692:16) May 20 04:02:18 volumio volumio[25809]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:02:18 volumio volumio[25809]: at afterDns (dgram.js:638:5) May 20 04:02:18 volumio volumio[25809]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:02:18 volumio volumio[25809]: errno: -101, May 20 04:02:18 volumio volumio[25809]: code: 'ENETUNREACH', May 20 04:02:18 volumio volumio[25809]: syscall: 'send', May 20 04:02:18 volumio volumio[25809]: address: '255.255.255.255', May 20 04:02:18 volumio volumio[25809]: port: 3483 May 20 04:02:18 volumio volumio[25809]: } May 20 04:02:18 volumio volumio[25809]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:19 volumio sudo[25994]: pam_unix(sudo:session): session closed for user root May 20 04:02:19 volumio sudo[26024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:01 May 20 04:02:19 volumio sudo[26024]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:19 volumio sudo[26024]: pam_unix(sudo:session): session closed for user root May 20 04:02:19 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:02:19 volumio sudo[25945]: pam_unix(sudo:session): session closed for user root May 20 04:02:19 volumio sudo[25939]: pam_unix(sudo:session): session closed for user root May 20 04:02:19 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:02:19 volumio systemd[1]: Started dynamicswap service. May 20 04:02:19 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:19 volumio mpd[25977]: May 20 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:02:19 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:02:19 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 89. May 20 04:02:19 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:02:19 volumio systemd[1]: Started Volumio Backend Module. May 20 04:02:19 volumio systemd[1]: Started dynamicswap service. May 20 04:02:20 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:20 volumio kernel: hwmon hwmon1: Undervoltage detected! May 20 04:02:21 volumio systemd[1]: Started Music Player Daemon. May 20 04:02:21 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:02:22 volumio volumio[26054]: cset: --> shielding system active with May 20 04:02:22 volumio volumio[26054]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running May 20 04:02:22 volumio volumio[26054]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:02:22 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:02:23 volumio volumio[26038]: info: ------------------------------------------- May 20 04:02:23 volumio volumio[26038]: info: ----- Volumio3 ---- May 20 04:02:23 volumio volumio[26038]: info: ------------------------------------------- May 20 04:02:23 volumio volumio[26038]: info: ----- System startup ---- May 20 04:02:23 volumio volumio[26038]: info: ------------------------------------------- May 20 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:23 volumio volumio[26038]: info: MYVOLUMIO Environment detected May 20 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:24 volumio volumio[26038]: info: Plugin folders cleanup May 20 04:02:24 volumio volumio[26038]: info: Scanning into folder /volumio/app/plugins/ May 20 04:02:24 volumio volumio[26038]: info: Scanning category audio_interface May 20 04:02:24 volumio volumio[26038]: info: Scanning category miscellanea May 20 04:02:24 volumio volumio[26038]: info: Scanning category music_service May 20 04:02:24 volumio volumio[26038]: info: Scanning category plugins.json May 20 04:02:24 volumio volumio[26038]: info: Scanning category system_controller May 20 04:02:24 volumio volumio[26038]: info: Scanning category user_interface May 20 04:02:24 volumio volumio[26038]: info: Scanning into folder /data/plugins/ May 20 04:02:24 volumio volumio[26038]: info: Scanning category music_service May 20 04:02:24 volumio volumio[26038]: info: Scanning category system_controller May 20 04:02:24 volumio volumio[26038]: info: Scanning category system_hardware May 20 04:02:24 volumio volumio[26038]: info: Scanning category user_interface May 20 04:02:24 volumio volumio[26038]: info: Plugin folders cleanup completed May 20 04:02:24 volumio volumio[26038]: info: ------------------------------------------- May 20 04:02:24 volumio volumio[26038]: info: ----- Core plugins startup ---- May 20 04:02:24 volumio volumio[26038]: info: ------------------------------------------- May 20 04:02:24 volumio volumio[26038]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:02:24 volumio volumio[26038]: info: Adding plugin upnp to MyMusic Plugins May 20 04:02:24 volumio volumio[26038]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:02:24 volumio volumio[26038]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:02:24 volumio volumio[26038]: info: Loading plugins from folder /data/plugins/ May 20 04:02:24 volumio volumio[26038]: info: Loading plugin "system"... May 20 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:24 volumio volumio[26038]: info: Loading plugin "appearance"... May 20 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:24 volumio volumio-remote-updater[587]: [2025-05-20 04:02:24] [connect] Successful connection May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "network"... May 20 04:02:25 volumio volumio[26038]: info: Refreshing Cached IP Addresses May 20 04:02:25 volumio sudo[26083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:02:25 volumio sudo[26083]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:25 volumio sudo[26083]: pam_unix(sudo:session): session closed for user root May 20 04:02:25 volumio sudo[26085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:02:25 volumio sudo[26085]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:25 volumio sudo[26085]: pam_unix(sudo:session): session closed for user root May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "services"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "alsa_controller"... May 20 04:02:25 volumio sudo[26094]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:02:25 volumio sudo[26094]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:25 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "wizard"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "networkfs"... May 20 04:02:25 volumio volumio[26038]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:25 volumio volumio[26038]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:25 volumio volumio[26038]: info: Starting Udev Watcher for removable devices May 20 04:02:25 volumio volumio[26038]: info: Ignoring mount for partition: boot May 20 04:02:25 volumio volumio[26038]: info: Ignoring mount for partition: volumio May 20 04:02:25 volumio volumio[26038]: info: Ignoring mount for partition: volumio_data May 20 04:02:25 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "volumio_command_line_client"... May 20 04:02:25 volumio volumio[26038]: info: Plugin upnp is not enabled May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "my_music"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "mpd"... May 20 04:02:25 volumio volumio[26038]: info: Plugin upnp_browser is not enabled May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "alarm-clock"... May 20 04:02:25 volumio volumio[26038]: info: Plugin airplay_emulation is not enabled May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "last_100"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "webradio"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "i2s_dacs"... May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "volumiodiscovery"... May 20 04:02:25 volumio volumio[26038]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:25 volumio volumio[26038]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:25 volumio volumio[26038]: *** WARNING *** For more information see May 20 04:02:25 volumio node[26038]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:25 volumio volumio[26038]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:25 volumio volumio[26038]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:25 volumio volumio[26038]: *** WARNING *** For more information see May 20 04:02:25 volumio node[26038]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:25 volumio node[26038]: *** WARNING *** For more information see May 20 04:02:25 volumio node[26038]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:25 volumio node[26038]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:25 volumio node[26038]: *** WARNING *** For more information see May 20 04:02:25 volumio volumio[26038]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:02:25 volumio volumio[26038]: info: Discovery: Started advertising with name: Volumio May 20 04:02:25 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:25 volumio volumio[26038]: info: Loading plugin "soundcloud"... May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:25 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:02:26 volumio volumio[26038]: info: Loading plugin "spop"... May 20 04:02:26 volumio kernel: hwmon hwmon1: Voltage normalised May 20 04:02:27 volumio volumio[26038]: info: Loading plugin "squeezelite_mc"... May 20 04:02:27 volumio volumio[26038]: info: Loading plugin "outputs"... May 20 04:02:27 volumio volumio[26038]: info: Loading plugin "albumart"... May 20 04:02:28 volumio volumio[26038]: info: Plugin example_plugin is not enabled May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "inputs"... May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "updater_comm"... May 20 04:02:28 volumio volumio[26038]: info: Plugin mpdemulation is not enabled May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "rest_api"... May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "websocket"... May 20 04:02:28 volumio volumio[26038]: info: Starting Socket.io Server version 2.3.0 May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "radio_paradise"... May 20 04:02:28 volumio volumio[26038]: Forking 3 albumart workers May 20 04:02:28 volumio sudo[26094]: pam_unix(sudo:session): session closed for user root May 20 04:02:28 volumio volumio[26038]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:02:28 volumio volumio[26038]: info: [1747706548686] [RadioParadise] API delay: 3 May 20 04:02:28 volumio volumio[26038]: info: Loading plugin "backup_restore"... May 20 04:02:29 volumio volumio[26038]: info: Applying required configuration parameters for plugin backup_restore May 20 04:02:29 volumio volumio[26038]: info: Loading plugin "music_services_shield"... May 20 04:02:29 volumio volumio[26038]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:02:29 volumio volumio[26038]: Starting albumart workers May 20 04:02:29 volumio volumio[26038]: info: Loading plugin "Systeminfo"... May 20 04:02:29 volumio volumio[26038]: Starting albumart workers May 20 04:02:29 volumio volumio[26038]: Starting albumart workers May 20 04:02:29 volumio volumio[26038]: info: Loading i18n strings for locale en May 20 04:02:29 volumio volumio[26038]: Updating browse sources language May 20 04:02:29 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::initPlayerControls May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:30 volumio volumio[26038]: Express server listening on port 3000 May 20 04:02:30 volumio volumio[26038]: [Metrics] WebUI: 7s 974.70ms May 20 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::resetVolumioState May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::getcurrentVolume May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::pushState May 20 04:02:30 volumio volumio[26038]: info: CorePlayQueue::getTrack 0 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioPushState May 20 04:02:30 volumio volumio[26038]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:30 volumio volumio[26038]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:30 volumio volumio[26038]: info: Volumio Network Manager: Network status updated: 0 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: Reloading queue from file May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::setRepeat false single undefined May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::pushState May 20 04:02:30 volumio volumio[26038]: info: CorePlayQueue::getTrack 0 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioPushState May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::setRandom false May 20 04:02:30 volumio volumio[26038]: info: CoreStateMachine::pushState May 20 04:02:30 volumio volumio[26038]: info: CorePlayQueue::getTrack 0 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioPushState May 20 04:02:30 volumio volumio[26038]: info: Setting Device type: Raspberry PI May 20 04:02:30 volumio volumio[26038]: info: Completed loading Core Plugins May 20 04:02:30 volumio volumio[26038]: info: Preparing to generate the ALSA configuration file May 20 04:02:30 volumio volumio[26038]: info: Asound.conf file unchanged, so no further update is needed May 20 04:02:30 volumio volumio[26038]: info: Output device has changed, restarting MPD May 20 04:02:30 volumio sudo[26174]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:30 volumio sudo[26174]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:30 volumio volumio[26038]: info: ___________ START PLUGINS ___________ May 20 04:02:30 volumio sudo[26174]: pam_unix(sudo:session): session closed for user root May 20 04:02:30 volumio volumio[26038]: info: ControllerMpd::onStart: Initializing MPD May 20 04:02:30 volumio volumio[26038]: info: Creating MPD Configuration file May 20 04:02:30 volumio sudo[26176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:30 volumio sudo[26176]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:30 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:02:30 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:02:30 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:02:30 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:02:30 volumio sudo[26180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:30 volumio sudo[26180]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:30 volumio sudo[26180]: pam_unix(sudo:session): session closed for user root May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:30 volumio volumio[26038]: info: [1747706550671] CoreMusicLibrary::Adding element Last_100 May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:30 volumio volumio[26038]: info: [1747706550675] CoreMusicLibrary::Adding element Webradio May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:30 volumio sudo[26183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:30 volumio sudo[26183]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:30 volumio volumio[26038]: info: Initializing BBC Radios May 20 04:02:30 volumio systemd[1]: mpd.service: Succeeded. May 20 04:02:30 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:30 volumio volumio[26038]: info: [1747706550778] CoreMusicLibrary::Adding element SoundCloud May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:30 volumio volumio[26038]: Cannot find translation for source SoundCloud May 20 04:02:30 volumio volumio[26038]: info: Creating Spotify config file May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:30 volumio systemd[1]: Starting Music Player Daemon... May 20 04:02:30 volumio volumio[26038]: info: [squeezelite_mc] Starting proxy server... May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:30 volumio volumio[26038]: info: [1747706550820] CoreMusicLibrary::Adding element Radio Paradise May 20 04:02:30 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:30 volumio volumio[26038]: Cannot find translation for source SoundCloud May 20 04:02:30 volumio volumio[26038]: Cannot find translation for source Radio Paradise May 20 04:02:30 volumio sudo[26192]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:02:30 volumio sudo[26192]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:30 volumio sudo[26192]: pam_unix(sudo:session): session closed for user root May 20 04:02:31 volumio volumio[26038]: info: Volumio Calling Home May 20 04:02:31 volumio sudo[26224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:02:31 volumio sudo[26224]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:31 volumio volumio[26038]: info: [squeezelite_mc] Proxy server started on port 41321 May 20 04:02:31 volumio volumio[26038]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:31 volumio volumio[26038]: info: CoreStateMachine::pushState May 20 04:02:31 volumio volumio[26038]: info: CorePlayQueue::getTrack 0 May 20 04:02:31 volumio volumio[26038]: info: CoreCommandRouter::volumioPushState May 20 04:02:31 volumio volumio[26038]: info: [squeezelite_mc] Server discovery started May 20 04:02:31 volumio volumio[26038]: info: [squeezelite_mc] Player finder started May 20 04:02:31 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:31 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:31 volumio volumio[26038]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:31 volumio volumio[26038]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:02:31 volumio volumio[26038]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:31 volumio volumio[26038]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:02:31 volumio volumio[26038]: at doSend (dgram.js:692:16) May 20 04:02:31 volumio volumio[26038]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:02:31 volumio volumio[26038]: at afterDns (dgram.js:638:5) May 20 04:02:31 volumio volumio[26038]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:02:31 volumio volumio[26038]: errno: -101, May 20 04:02:31 volumio volumio[26038]: code: 'ENETUNREACH', May 20 04:02:31 volumio volumio[26038]: syscall: 'send', May 20 04:02:31 volumio volumio[26038]: address: '255.255.255.255', May 20 04:02:31 volumio volumio[26038]: port: 3483 May 20 04:02:31 volumio volumio[26038]: } May 20 04:02:31 volumio volumio[26038]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:31 volumio sudo[26224]: pam_unix(sudo:session): session closed for user root May 20 04:02:31 volumio sudo[26254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:01 May 20 04:02:31 volumio sudo[26254]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:32 volumio sudo[26254]: pam_unix(sudo:session): session closed for user root May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:02:32 volumio sudo[26176]: pam_unix(sudo:session): session closed for user root May 20 04:02:32 volumio sudo[26183]: pam_unix(sudo:session): session closed for user root May 20 04:02:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:02:32 volumio systemd[1]: Started dynamicswap service. May 20 04:02:32 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:32 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:02:32 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 90. May 20 04:02:32 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:02:32 volumio systemd[1]: Started Volumio Backend Module. May 20 04:02:32 volumio systemd[1]: Started dynamicswap service. May 20 04:02:32 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:32 volumio mpd[26216]: May 20 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:02:33 volumio kernel: hwmon hwmon1: Undervoltage detected! May 20 04:02:33 volumio systemd[1]: Started Music Player Daemon. May 20 04:02:34 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:02:35 volumio volumio[26283]: cset: --> shielding system active with May 20 04:02:35 volumio volumio[26283]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running May 20 04:02:35 volumio volumio[26283]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:02:35 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:02:35 volumio volumio[26267]: info: ------------------------------------------- May 20 04:02:35 volumio volumio[26267]: info: ----- Volumio3 ---- May 20 04:02:35 volumio volumio[26267]: info: ------------------------------------------- May 20 04:02:35 volumio volumio[26267]: info: ----- System startup ---- May 20 04:02:35 volumio volumio[26267]: info: ------------------------------------------- May 20 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:36 volumio volumio[26267]: info: MYVOLUMIO Environment detected May 20 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:36 volumio volumio[26267]: info: Plugin folders cleanup May 20 04:02:36 volumio volumio[26267]: info: Scanning into folder /volumio/app/plugins/ May 20 04:02:36 volumio volumio[26267]: info: Scanning category audio_interface May 20 04:02:36 volumio volumio[26267]: info: Scanning category miscellanea May 20 04:02:36 volumio volumio[26267]: info: Scanning category music_service May 20 04:02:36 volumio volumio[26267]: info: Scanning category plugins.json May 20 04:02:36 volumio volumio[26267]: info: Scanning category system_controller May 20 04:02:36 volumio volumio[26267]: info: Scanning category user_interface May 20 04:02:36 volumio volumio[26267]: info: Scanning into folder /data/plugins/ May 20 04:02:36 volumio volumio[26267]: info: Scanning category music_service May 20 04:02:36 volumio volumio[26267]: info: Scanning category system_controller May 20 04:02:36 volumio volumio[26267]: info: Scanning category system_hardware May 20 04:02:36 volumio volumio[26267]: info: Scanning category user_interface May 20 04:02:36 volumio volumio[26267]: info: Plugin folders cleanup completed May 20 04:02:36 volumio volumio[26267]: info: ------------------------------------------- May 20 04:02:36 volumio volumio[26267]: info: ----- Core plugins startup ---- May 20 04:02:36 volumio volumio[26267]: info: ------------------------------------------- May 20 04:02:36 volumio volumio[26267]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:02:36 volumio volumio[26267]: info: Adding plugin upnp to MyMusic Plugins May 20 04:02:36 volumio volumio[26267]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:02:36 volumio volumio[26267]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:02:36 volumio volumio[26267]: info: Loading plugins from folder /data/plugins/ May 20 04:02:36 volumio volumio[26267]: info: Loading plugin "system"... May 20 04:02:36 volumio volumio[26267]: info: Loading plugin "appearance"... May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "network"... May 20 04:02:37 volumio volumio[26267]: info: Refreshing Cached IP Addresses May 20 04:02:37 volumio sudo[26313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:02:37 volumio sudo[26313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:37 volumio sudo[26313]: pam_unix(sudo:session): session closed for user root May 20 04:02:37 volumio sudo[26315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:02:37 volumio sudo[26315]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:37 volumio sudo[26315]: pam_unix(sudo:session): session closed for user root May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "services"... May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "alsa_controller"... May 20 04:02:37 volumio sudo[26324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:02:37 volumio sudo[26324]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:37 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "wizard"... May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "networkfs"... May 20 04:02:37 volumio volumio[26267]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:37 volumio volumio[26267]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:37 volumio volumio[26267]: info: Starting Udev Watcher for removable devices May 20 04:02:37 volumio volumio[26267]: info: Ignoring mount for partition: boot May 20 04:02:37 volumio volumio[26267]: info: Ignoring mount for partition: volumio May 20 04:02:37 volumio volumio[26267]: info: Ignoring mount for partition: volumio_data May 20 04:02:37 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "volumio_command_line_client"... May 20 04:02:37 volumio volumio[26267]: info: Plugin upnp is not enabled May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "my_music"... May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "mpd"... May 20 04:02:37 volumio volumio[26267]: info: Plugin upnp_browser is not enabled May 20 04:02:37 volumio volumio[26267]: info: Loading plugin "alarm-clock"... May 20 04:02:38 volumio volumio[26267]: info: Plugin airplay_emulation is not enabled May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "last_100"... May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "webradio"... May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "i2s_dacs"... May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "volumiodiscovery"... May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:38 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:02:38 volumio volumio[26267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:38 volumio volumio[26267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:38 volumio volumio[26267]: *** WARNING *** For more information see May 20 04:02:38 volumio node[26267]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:38 volumio volumio[26267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:38 volumio volumio[26267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:38 volumio volumio[26267]: *** WARNING *** For more information see May 20 04:02:38 volumio node[26267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:38 volumio node[26267]: *** WARNING *** For more information see May 20 04:02:38 volumio node[26267]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:38 volumio node[26267]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:38 volumio node[26267]: *** WARNING *** For more information see May 20 04:02:38 volumio volumio[26267]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:02:38 volumio volumio[26267]: info: Discovery: Started advertising with name: Volumio May 20 04:02:38 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "soundcloud"... May 20 04:02:38 volumio volumio[26267]: info: Loading plugin "spop"... May 20 04:02:39 volumio kernel: hwmon hwmon1: Voltage normalised May 20 04:02:39 volumio volumio-remote-updater[587]: [2025-05-20 04:02:39] [connect] Successful connection May 20 04:02:39 volumio volumio[26267]: info: Loading plugin "squeezelite_mc"... May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "outputs"... May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "albumart"... May 20 04:02:40 volumio volumio[26267]: info: Plugin example_plugin is not enabled May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "inputs"... May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "updater_comm"... May 20 04:02:40 volumio volumio[26267]: info: Plugin mpdemulation is not enabled May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "rest_api"... May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "websocket"... May 20 04:02:40 volumio volumio[26267]: info: Starting Socket.io Server version 2.3.0 May 20 04:02:40 volumio volumio[26267]: info: Loading plugin "radio_paradise"... May 20 04:02:40 volumio sudo[26324]: pam_unix(sudo:session): session closed for user root May 20 04:02:40 volumio volumio[26267]: Forking 3 albumart workers May 20 04:02:41 volumio volumio[26267]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:02:41 volumio volumio[26267]: info: [1747706561127] [RadioParadise] API delay: 3 May 20 04:02:41 volumio volumio[26267]: info: Loading plugin "backup_restore"... May 20 04:02:41 volumio volumio[26267]: info: Applying required configuration parameters for plugin backup_restore May 20 04:02:41 volumio volumio[26267]: info: Loading plugin "music_services_shield"... May 20 04:02:41 volumio volumio[26267]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:02:41 volumio volumio[26267]: info: Loading plugin "Systeminfo"... May 20 04:02:41 volumio volumio[26267]: Starting albumart workers May 20 04:02:42 volumio volumio[26267]: Starting albumart workers May 20 04:02:42 volumio volumio[26267]: info: Loading i18n strings for locale en May 20 04:02:42 volumio volumio[26267]: Updating browse sources language May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:42 volumio volumio[26267]: Starting albumart workers May 20 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:42 volumio autossh[18083]: starting ssh (count 18) May 20 04:02:42 volumio autossh[18083]: ssh child pid is 26369 May 20 04:02:42 volumio volumiossh-tunnel[18082]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy May 20 04:02:42 volumio autossh[18083]: ssh exited with error status 255; restarting ssh May 20 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::initPlayerControls May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:42 volumio volumio[26267]: Express server listening on port 3000 May 20 04:02:42 volumio volumio[26267]: [Metrics] WebUI: 7s 911.87ms May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::resetVolumioState May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::getcurrentVolume May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::pushState May 20 04:02:42 volumio volumio[26267]: info: CorePlayQueue::getTrack 0 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::volumioPushState May 20 04:02:42 volumio volumio[26267]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:42 volumio volumio[26267]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:42 volumio volumio[26267]: info: Volumio Network Manager: Network status updated: 0 May 20 04:02:42 volumio volumio-remote-updater[587]: [2025-05-20 04:02:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747706559 101 May 20 04:02:42 volumio volumio[26267]: 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 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:42 volumio volumio[26267]: info: Reloading queue from file May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::setRepeat false single undefined May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::pushState May 20 04:02:42 volumio volumio[26267]: info: CorePlayQueue::getTrack 0 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::volumioPushState May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::setRandom false May 20 04:02:42 volumio volumio[26267]: info: CoreStateMachine::pushState May 20 04:02:42 volumio volumio[26267]: info: CorePlayQueue::getTrack 0 May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:42 volumio volumio[26267]: info: CoreCommandRouter::volumioPushState May 20 04:02:42 volumio volumio[26267]: info: Setting Device type: Raspberry PI May 20 04:02:42 volumio volumio[26267]: info: Completed loading Core Plugins May 20 04:02:42 volumio volumio[26267]: info: Preparing to generate the ALSA configuration file May 20 04:02:42 volumio volumio[26267]: info: Asound.conf file unchanged, so no further update is needed May 20 04:02:42 volumio volumio[26267]: info: Output device has changed, restarting MPD May 20 04:02:43 volumio volumio[26267]: info: ___________ START PLUGINS ___________ May 20 04:02:43 volumio sudo[26402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:43 volumio sudo[26402]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio sudo[26402]: pam_unix(sudo:session): session closed for user root May 20 04:02:43 volumio volumio[26267]: info: ControllerMpd::onStart: Initializing MPD May 20 04:02:43 volumio volumio[26267]: info: Creating MPD Configuration file May 20 04:02:43 volumio sudo[26404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:43 volumio sudo[26404]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:02:43 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:02:43 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:02:43 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:02:43 volumio sudo[26408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:43 volumio volumio[26267]: info: [1747706563111] CoreMusicLibrary::Adding element Last_100 May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:43 volumio volumio[26267]: info: [1747706563119] CoreMusicLibrary::Adding element Webradio May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:43 volumio sudo[26408]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:43 volumio sudo[26408]: pam_unix(sudo:session): session closed for user root May 20 04:02:43 volumio volumio[26267]: info: Initializing BBC Radios May 20 04:02:43 volumio sudo[26410]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:43 volumio sudo[26410]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio systemd[1]: mpd.service: Succeeded. May 20 04:02:43 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:43 volumio volumio[26267]: info: [1747706563239] CoreMusicLibrary::Adding element SoundCloud May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:43 volumio volumio[26267]: Cannot find translation for source SoundCloud May 20 04:02:43 volumio volumio[26267]: info: Creating Spotify config file May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:43 volumio systemd[1]: Starting Music Player Daemon... May 20 04:02:43 volumio volumio[26267]: info: [squeezelite_mc] Starting proxy server... May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:43 volumio volumio[26267]: info: [1747706563281] CoreMusicLibrary::Adding element Radio Paradise May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:43 volumio volumio[26267]: Cannot find translation for source SoundCloud May 20 04:02:43 volumio volumio[26267]: Cannot find translation for source Radio Paradise May 20 04:02:43 volumio sudo[26419]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:02:43 volumio sudo[26419]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio sudo[26419]: pam_unix(sudo:session): session closed for user root May 20 04:02:43 volumio volumio[26267]: info: Volumio Calling Home May 20 04:02:43 volumio sudo[26452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:02:43 volumio sudo[26452]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:43 volumio volumio[26267]: info: [squeezelite_mc] Proxy server started on port 35113 May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:43 volumio volumio[26267]: info: CoreStateMachine::pushState May 20 04:02:43 volumio volumio[26267]: info: CorePlayQueue::getTrack 0 May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::volumioPushState May 20 04:02:43 volumio volumio[26267]: info: [squeezelite_mc] Server discovery started May 20 04:02:43 volumio volumio[26267]: info: [squeezelite_mc] Player finder started May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:43 volumio volumio[26267]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:43 volumio volumio[26267]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:02:43 volumio volumio[26267]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:43 volumio volumio[26267]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:02:43 volumio volumio[26267]: at doSend (dgram.js:692:16) May 20 04:02:43 volumio volumio[26267]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:02:43 volumio volumio[26267]: at afterDns (dgram.js:638:5) May 20 04:02:43 volumio volumio[26267]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:02:43 volumio volumio[26267]: errno: -101, May 20 04:02:43 volumio volumio[26267]: code: 'ENETUNREACH', May 20 04:02:43 volumio volumio[26267]: syscall: 'send', May 20 04:02:43 volumio volumio[26267]: address: '255.255.255.255', May 20 04:02:43 volumio volumio[26267]: port: 3483 May 20 04:02:43 volumio volumio[26267]: } May 20 04:02:43 volumio volumio[26267]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:44 volumio sudo[26452]: pam_unix(sudo:session): session closed for user root May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:44 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:44 volumio sudo[26482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:01 May 20 04:02:44 volumio sudo[26482]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:44 volumio sudo[26482]: pam_unix(sudo:session): session closed for user root May 20 04:02:44 volumio volumio-remote-updater[587]: [2025-05-20 04:02:44] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 20 04:02:44 volumio volumio-remote-updater[587]: [2025-05-20 04:02:44] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 20 04:02:44 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:02:44 volumio sudo[26410]: pam_unix(sudo:session): session closed for user root May 20 04:02:44 volumio sudo[26404]: pam_unix(sudo:session): session closed for user root May 20 04:02:44 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:02:44 volumio systemd[1]: Started dynamicswap service. May 20 04:02:44 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:02:44 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 91. May 20 04:02:44 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:02:44 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:02:44 volumio systemd[1]: Started Volumio Backend Module. May 20 04:02:45 volumio mpd[26441]: May 20 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:02:45 volumio kernel: hwmon hwmon1: Undervoltage detected! May 20 04:02:46 volumio systemd[1]: Started Music Player Daemon. May 20 04:02:46 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:02:47 volumio volumio[26540]: cset: --> shielding system active with May 20 04:02:47 volumio volumio[26540]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running May 20 04:02:47 volumio volumio[26540]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:02:47 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:02:48 volumio volumio[26495]: info: ------------------------------------------- May 20 04:02:48 volumio volumio[26495]: info: ----- Volumio3 ---- May 20 04:02:48 volumio volumio[26495]: info: ------------------------------------------- May 20 04:02:48 volumio volumio[26495]: info: ----- System startup ---- May 20 04:02:48 volumio volumio[26495]: info: ------------------------------------------- May 20 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:48 volumio volumio[26495]: info: MYVOLUMIO Environment detected May 20 04:02:48 volumio volumio[26495]: info: Plugin folders cleanup May 20 04:02:48 volumio volumio[26495]: info: Scanning into folder /volumio/app/plugins/ May 20 04:02:48 volumio volumio[26495]: info: Scanning category audio_interface May 20 04:02:48 volumio volumio[26495]: info: Scanning category miscellanea May 20 04:02:48 volumio volumio[26495]: info: Scanning category music_service May 20 04:02:48 volumio volumio[26495]: info: Scanning category plugins.json May 20 04:02:48 volumio volumio[26495]: info: Scanning category system_controller May 20 04:02:48 volumio volumio[26495]: info: Scanning category user_interface May 20 04:02:48 volumio volumio[26495]: info: Scanning into folder /data/plugins/ May 20 04:02:48 volumio volumio[26495]: info: Scanning category music_service May 20 04:02:48 volumio volumio[26495]: info: Scanning category system_controller May 20 04:02:48 volumio volumio[26495]: info: Scanning category system_hardware May 20 04:02:48 volumio volumio[26495]: info: Scanning category user_interface May 20 04:02:48 volumio volumio[26495]: info: Plugin folders cleanup completed May 20 04:02:48 volumio volumio[26495]: info: ------------------------------------------- May 20 04:02:48 volumio volumio[26495]: info: ----- Core plugins startup ---- May 20 04:02:48 volumio volumio[26495]: info: ------------------------------------------- May 20 04:02:48 volumio volumio[26495]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:02:48 volumio volumio[26495]: info: Adding plugin upnp to MyMusic Plugins May 20 04:02:48 volumio volumio[26495]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:02:48 volumio volumio[26495]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:02:48 volumio volumio[26495]: info: Loading plugins from folder /data/plugins/ May 20 04:02:48 volumio volumio[26495]: info: Loading plugin "system"... May 20 04:02:48 volumio volumio[26495]: info: Loading plugin "appearance"... May 20 04:02:49 volumio volumio-remote-updater[587]: [2025-05-20 04:02:49] [connect] Successful connection May 20 04:02:49 volumio volumio[26495]: info: Loading plugin "network"... May 20 04:02:49 volumio volumio[26495]: info: Refreshing Cached IP Addresses May 20 04:02:49 volumio sudo[26572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:02:49 volumio sudo[26572]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:49 volumio sudo[26572]: pam_unix(sudo:session): session closed for user root May 20 04:02:49 volumio sudo[26574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:02:49 volumio sudo[26574]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:49 volumio sudo[26574]: pam_unix(sudo:session): session closed for user root May 20 04:02:49 volumio volumio[26495]: info: Loading plugin "services"... May 20 04:02:49 volumio volumio[26495]: info: Loading plugin "alsa_controller"... May 20 04:02:49 volumio sudo[26587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:02:49 volumio sudo[26587]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:50 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "wizard"... May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "networkfs"... May 20 04:02:50 volumio volumio[26495]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:50 volumio volumio[26495]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:02:50 volumio volumio[26495]: info: Starting Udev Watcher for removable devices May 20 04:02:50 volumio volumio[26495]: info: Ignoring mount for partition: boot May 20 04:02:50 volumio volumio[26495]: info: Ignoring mount for partition: volumio May 20 04:02:50 volumio volumio[26495]: info: Ignoring mount for partition: volumio_data May 20 04:02:50 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "volumio_command_line_client"... May 20 04:02:50 volumio volumio[26495]: info: Plugin upnp is not enabled May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "my_music"... May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "mpd"... May 20 04:02:50 volumio volumio[26495]: info: Plugin upnp_browser is not enabled May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "alarm-clock"... May 20 04:02:50 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:50 volumio volumio[26495]: info: Plugin airplay_emulation is not enabled May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "last_100"... May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "webradio"... May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "i2s_dacs"... May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "volumiodiscovery"... May 20 04:02:50 volumio volumio[26495]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:50 volumio volumio[26495]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:50 volumio volumio[26495]: *** WARNING *** For more information see May 20 04:02:50 volumio node[26495]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:02:50 volumio volumio[26495]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:50 volumio volumio[26495]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:50 volumio volumio[26495]: *** WARNING *** For more information see May 20 04:02:50 volumio node[26495]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:50 volumio node[26495]: *** WARNING *** For more information see May 20 04:02:50 volumio node[26495]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:02:50 volumio node[26495]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:02:50 volumio node[26495]: *** WARNING *** For more information see May 20 04:02:50 volumio volumio[26495]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:02:50 volumio volumio[26495]: info: Discovery: Started advertising with name: Volumio May 20 04:02:50 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:02:50 volumio volumio[26495]: info: Loading plugin "soundcloud"... May 20 04:02:51 volumio volumio[26495]: info: Loading plugin "spop"... May 20 04:02:53 volumio sudo[26587]: pam_unix(sudo:session): session closed for user root May 20 04:02:53 volumio volumio[26495]: info: Loading plugin "squeezelite_mc"... May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "outputs"... May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "albumart"... May 20 04:02:54 volumio volumio[26495]: info: Plugin example_plugin is not enabled May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "inputs"... May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "updater_comm"... May 20 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:02:54 volumio volumio[26495]: info: Plugin mpdemulation is not enabled May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "rest_api"... May 20 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "websocket"... May 20 04:02:54 volumio volumio[26495]: info: Starting Socket.io Server version 2.3.0 May 20 04:02:54 volumio volumio[26495]: info: Loading plugin "radio_paradise"... May 20 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:02:54 volumio volumio[26495]: Forking 3 albumart workers May 20 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:02:55 volumio volumio[26495]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:02:55 volumio volumio[26495]: info: [1747706575255] [RadioParadise] API delay: 3 May 20 04:02:55 volumio volumio[26495]: info: Loading plugin "backup_restore"... May 20 04:02:55 volumio volumio[26495]: info: Applying required configuration parameters for plugin backup_restore May 20 04:02:55 volumio volumio[26495]: info: Loading plugin "music_services_shield"... May 20 04:02:55 volumio volumio[26495]: Starting albumart workers May 20 04:02:55 volumio volumio[26495]: Starting albumart workers May 20 04:02:56 volumio volumio[26495]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:02:56 volumio volumio[26495]: info: Loading plugin "Systeminfo"... May 20 04:02:56 volumio volumio[26495]: Starting albumart workers May 20 04:02:56 volumio volumio[26495]: info: Loading i18n strings for locale en May 20 04:02:56 volumio volumio[26495]: Updating browse sources language May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::initPlayerControls May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:56 volumio volumio[26495]: Express server listening on port 3000 May 20 04:02:56 volumio volumio[26495]: [Metrics] WebUI: 9s 553.79ms May 20 04:02:56 volumio volumio[26495]: info: CoreStateMachine::resetVolumioState May 20 04:02:56 volumio volumio[26495]: info: CoreStateMachine::getcurrentVolume May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:56 volumio volumio[26495]: info: CoreStateMachine::pushState May 20 04:02:56 volumio volumio[26495]: info: CorePlayQueue::getTrack 0 May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:56 volumio volumio[26495]: info: CoreCommandRouter::volumioPushState May 20 04:02:56 volumio volumio[26495]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:56 volumio volumio[26495]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:02:56 volumio volumio[26495]: info: Volumio Network Manager: Network status updated: 0 May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:57 volumio volumio[26495]: info: Reloading queue from file May 20 04:02:57 volumio volumio[26495]: info: CoreStateMachine::setRepeat false single undefined May 20 04:02:57 volumio volumio[26495]: info: CoreStateMachine::pushState May 20 04:02:57 volumio volumio[26495]: info: CorePlayQueue::getTrack 0 May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioPushState May 20 04:02:57 volumio volumio[26495]: info: CoreStateMachine::setRandom false May 20 04:02:57 volumio volumio[26495]: info: CoreStateMachine::pushState May 20 04:02:57 volumio volumio[26495]: info: CorePlayQueue::getTrack 0 May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioPushState May 20 04:02:57 volumio volumio[26495]: info: Setting Device type: Raspberry PI May 20 04:02:57 volumio volumio[26495]: info: Completed loading Core Plugins May 20 04:02:57 volumio volumio[26495]: info: Preparing to generate the ALSA configuration file May 20 04:02:57 volumio volumio[26495]: info: Asound.conf file unchanged, so no further update is needed May 20 04:02:57 volumio volumio[26495]: info: Output device has changed, restarting MPD May 20 04:02:57 volumio sudo[26659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:57 volumio sudo[26659]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:57 volumio volumio[26495]: info: ___________ START PLUGINS ___________ May 20 04:02:57 volumio sudo[26659]: pam_unix(sudo:session): session closed for user root May 20 04:02:57 volumio volumio[26495]: info: ControllerMpd::onStart: Initializing MPD May 20 04:02:57 volumio volumio[26495]: info: Creating MPD Configuration file May 20 04:02:57 volumio sudo[26661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:57 volumio sudo[26661]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:57 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:02:57 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:02:57 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:02:57 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:02:57 volumio sudo[26665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:02:57 volumio sudo[26665]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:57 volumio sudo[26665]: pam_unix(sudo:session): session closed for user root May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:57 volumio volumio[26495]: info: [1747706577254] CoreMusicLibrary::Adding element Last_100 May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:57 volumio volumio[26495]: info: [1747706577258] CoreMusicLibrary::Adding element Webradio May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:57 volumio volumio[26495]: info: Initializing BBC Radios May 20 04:02:57 volumio sudo[26668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:02:57 volumio sudo[26668]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:57 volumio systemd[1]: mpd.service: Succeeded. May 20 04:02:57 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:57 volumio volumio[26495]: info: [1747706577367] CoreMusicLibrary::Adding element SoundCloud May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:57 volumio volumio[26495]: Cannot find translation for source SoundCloud May 20 04:02:57 volumio systemd[1]: Starting Music Player Daemon... May 20 04:02:57 volumio volumio[26495]: info: Creating Spotify config file May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:57 volumio volumio[26495]: info: [squeezelite_mc] Starting proxy server... May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:02:57 volumio volumio[26495]: info: [1747706577452] CoreMusicLibrary::Adding element Radio Paradise May 20 04:02:57 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:02:57 volumio volumio[26495]: Cannot find translation for source SoundCloud May 20 04:02:57 volumio volumio[26495]: Cannot find translation for source Radio Paradise May 20 04:02:57 volumio sudo[26676]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:02:57 volumio sudo[26676]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:57 volumio sudo[26676]: pam_unix(sudo:session): session closed for user root May 20 04:02:57 volumio volumio[26495]: info: Volumio Calling Home May 20 04:02:58 volumio volumio[26495]: info: [squeezelite_mc] Proxy server started on port 36133 May 20 04:02:58 volumio sudo[26709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:02:58 volumio sudo[26709]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:58 volumio volumio[26495]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:02:58 volumio volumio[26495]: info: CoreStateMachine::pushState May 20 04:02:58 volumio volumio[26495]: info: CorePlayQueue::getTrack 0 May 20 04:02:58 volumio volumio[26495]: info: CoreCommandRouter::volumioPushState May 20 04:02:58 volumio volumio[26495]: info: [squeezelite_mc] Server discovery started May 20 04:02:58 volumio volumio[26495]: info: [squeezelite_mc] Player finder started May 20 04:02:58 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:58 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:02:58 volumio volumio[26495]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:02:58 volumio volumio[26495]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:02:58 volumio volumio[26495]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:58 volumio volumio[26495]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:02:58 volumio volumio[26495]: at doSend (dgram.js:692:16) May 20 04:02:58 volumio volumio[26495]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:02:58 volumio volumio[26495]: at afterDns (dgram.js:638:5) May 20 04:02:58 volumio volumio[26495]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:02:58 volumio volumio[26495]: errno: -101, May 20 04:02:58 volumio volumio[26495]: code: 'ENETUNREACH', May 20 04:02:58 volumio volumio[26495]: syscall: 'send', May 20 04:02:58 volumio volumio[26495]: address: '255.255.255.255', May 20 04:02:58 volumio volumio[26495]: port: 3483 May 20 04:02:58 volumio volumio[26495]: } May 20 04:02:58 volumio volumio[26495]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:02:59 volumio sudo[26709]: pam_unix(sudo:session): session closed for user root May 20 04:02:59 volumio sudo[26739]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:01 May 20 04:02:59 volumio sudo[26739]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:02:59 volumio sudo[26739]: pam_unix(sudo:session): session closed for user root May 20 04:03:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:03:00 volumio sudo[26668]: pam_unix(sudo:session): session closed for user root May 20 04:03:00 volumio sudo[26661]: pam_unix(sudo:session): session closed for user root May 20 04:03:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:03:00 volumio systemd[1]: Started dynamicswap service. May 20 04:03:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:03:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:03:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 92. May 20 04:03:00 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:03:00 volumio systemd[1]: Started Volumio Backend Module. May 20 04:03:00 volumio systemd[1]: Started dynamicswap service. May 20 04:03:00 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:03:00 volumio mpd[26698]: May 20 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:03:01 volumio systemd[1]: Started Music Player Daemon. May 20 04:03:01 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:03:01 volumio volumio[26781]: cset: --> shielding system active with May 20 04:03:01 volumio volumio[26781]: cset: "system" cpuset of CPUSPEC(1-3) with 64 tasks running May 20 04:03:01 volumio volumio[26781]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:03:01 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:03:02 volumio volumio[26766]: info: ------------------------------------------- May 20 04:03:02 volumio volumio[26766]: info: ----- Volumio3 ---- May 20 04:03:02 volumio volumio[26766]: info: ------------------------------------------- May 20 04:03:02 volumio volumio[26766]: info: ----- System startup ---- May 20 04:03:02 volumio volumio[26766]: info: ------------------------------------------- May 20 04:03:02 volumio volumio-remote-updater[587]: [2025-05-20 04:03:02] [connect] Successful connection May 20 04:03:02 volumio volumio[26766]: info: MYVOLUMIO Environment detected May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:03:02 volumio wpa_supplicant[857]: wlan0: Failed to initiate sched scan May 20 04:03:02 volumio volumio[26766]: info: Plugin folders cleanup May 20 04:03:02 volumio volumio[26766]: info: Scanning into folder /volumio/app/plugins/ May 20 04:03:02 volumio volumio[26766]: info: Scanning category audio_interface May 20 04:03:02 volumio volumio[26766]: info: Scanning category miscellanea May 20 04:03:02 volumio volumio[26766]: info: Scanning category music_service May 20 04:03:02 volumio volumio[26766]: info: Scanning category plugins.json May 20 04:03:02 volumio volumio[26766]: info: Scanning category system_controller May 20 04:03:02 volumio volumio[26766]: info: Scanning category user_interface May 20 04:03:02 volumio volumio[26766]: info: Scanning into folder /data/plugins/ May 20 04:03:02 volumio volumio[26766]: info: Scanning category music_service May 20 04:03:02 volumio volumio[26766]: info: Scanning category system_controller May 20 04:03:02 volumio volumio[26766]: info: Scanning category system_hardware May 20 04:03:02 volumio volumio[26766]: info: Scanning category user_interface May 20 04:03:02 volumio volumio[26766]: info: Plugin folders cleanup completed May 20 04:03:02 volumio volumio[26766]: info: ------------------------------------------- May 20 04:03:02 volumio volumio[26766]: info: ----- Core plugins startup ---- May 20 04:03:02 volumio volumio[26766]: info: ------------------------------------------- May 20 04:03:02 volumio volumio[26766]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:03:02 volumio volumio[26766]: info: Adding plugin upnp to MyMusic Plugins May 20 04:03:02 volumio volumio[26766]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:03:02 volumio volumio[26766]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:03:02 volumio volumio[26766]: info: Loading plugins from folder /data/plugins/ May 20 04:03:02 volumio volumio[26766]: info: Loading plugin "system"... May 20 04:03:03 volumio volumio[26766]: info: Loading plugin "appearance"... May 20 04:03:03 volumio volumio[26766]: info: Loading plugin "network"... May 20 04:03:03 volumio volumio[26766]: info: Refreshing Cached IP Addresses May 20 04:03:03 volumio sudo[26810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:03:03 volumio sudo[26810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:03 volumio sudo[26810]: pam_unix(sudo:session): session closed for user root May 20 04:03:03 volumio sudo[26812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:03:03 volumio sudo[26812]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:03 volumio sudo[26812]: pam_unix(sudo:session): session closed for user root May 20 04:03:03 volumio volumio[26766]: info: Loading plugin "services"... May 20 04:03:03 volumio volumio[26766]: info: Loading plugin "alsa_controller"... May 20 04:03:04 volumio sudo[26821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:03:04 volumio sudo[26821]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:04 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "wizard"... May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "networkfs"... May 20 04:03:04 volumio volumio[26766]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:03:04 volumio volumio[26766]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:03:04 volumio volumio[26766]: info: Starting Udev Watcher for removable devices May 20 04:03:04 volumio volumio[26766]: info: Ignoring mount for partition: boot May 20 04:03:04 volumio volumio[26766]: info: Ignoring mount for partition: volumio May 20 04:03:04 volumio volumio[26766]: info: Ignoring mount for partition: volumio_data May 20 04:03:04 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "volumio_command_line_client"... May 20 04:03:04 volumio volumio[26766]: info: Plugin upnp is not enabled May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "my_music"... May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "mpd"... May 20 04:03:04 volumio kernel: hwmon hwmon1: Voltage normalised May 20 04:03:04 volumio volumio[26766]: info: Plugin upnp_browser is not enabled May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "alarm-clock"... May 20 04:03:04 volumio volumio[26766]: info: Plugin airplay_emulation is not enabled May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "last_100"... May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "webradio"... May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "i2s_dacs"... May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "volumiodiscovery"... May 20 04:03:04 volumio volumio[26766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:03:04 volumio volumio[26766]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:04 volumio volumio[26766]: *** WARNING *** For more information see May 20 04:03:04 volumio node[26766]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:03:04 volumio volumio[26766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:03:04 volumio volumio[26766]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:04 volumio volumio[26766]: *** WARNING *** For more information see May 20 04:03:04 volumio node[26766]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:04 volumio node[26766]: *** WARNING *** For more information see May 20 04:03:04 volumio node[26766]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:03:04 volumio node[26766]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:04 volumio node[26766]: *** WARNING *** For more information see May 20 04:03:04 volumio volumio[26766]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:03:04 volumio volumio[26766]: info: Discovery: Started advertising with name: Volumio May 20 04:03:04 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:03:04 volumio volumio[26766]: info: Loading plugin "soundcloud"... May 20 04:03:05 volumio volumio[26766]: info: Loading plugin "spop"... May 20 04:03:06 volumio volumio[26766]: info: Loading plugin "squeezelite_mc"... May 20 04:03:06 volumio sudo[26821]: pam_unix(sudo:session): session closed for user root May 20 04:03:06 volumio volumio[26766]: info: Loading plugin "outputs"... May 20 04:03:06 volumio volumio[26766]: info: Loading plugin "albumart"... May 20 04:03:06 volumio volumio[26766]: info: Plugin example_plugin is not enabled May 20 04:03:06 volumio volumio[26766]: info: Loading plugin "inputs"... May 20 04:03:06 volumio volumio[26766]: info: Loading plugin "updater_comm"... May 20 04:03:07 volumio volumio[26766]: info: Plugin mpdemulation is not enabled May 20 04:03:07 volumio volumio[26766]: info: Loading plugin "rest_api"... May 20 04:03:07 volumio volumio[26766]: info: Loading plugin "websocket"... May 20 04:03:07 volumio volumio[26766]: info: Starting Socket.io Server version 2.3.0 May 20 04:03:07 volumio volumio[26766]: info: Loading plugin "radio_paradise"... May 20 04:03:07 volumio volumio[26766]: Forking 3 albumart workers May 20 04:03:07 volumio volumio[26766]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:03:07 volumio volumio[26766]: info: [1747706587604] [RadioParadise] API delay: 3 May 20 04:03:07 volumio volumio[26766]: info: Loading plugin "backup_restore"... May 20 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:03:08 volumio volumio[26766]: info: Applying required configuration parameters for plugin backup_restore May 20 04:03:08 volumio volumio[26766]: info: Loading plugin "music_services_shield"... May 20 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:03:08 volumio volumio[26766]: Starting albumart workers May 20 04:03:08 volumio volumio[26766]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:03:08 volumio volumio[26766]: info: Loading plugin "Systeminfo"... May 20 04:03:08 volumio volumio[26766]: Starting albumart workers May 20 04:03:08 volumio volumio[26766]: Starting albumart workers May 20 04:03:08 volumio volumio[26766]: info: Loading i18n strings for locale en May 20 04:03:08 volumio volumio[26766]: Updating browse sources language May 20 04:03:08 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::initPlayerControls May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:09 volumio volumio[26766]: Express server listening on port 3000 May 20 04:03:09 volumio volumio[26766]: [Metrics] WebUI: 7s 341.88ms May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::resetVolumioState May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::getcurrentVolume May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::pushState May 20 04:03:09 volumio volumio[26766]: info: CorePlayQueue::getTrack 0 May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioPushState May 20 04:03:09 volumio volumio[26766]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:03:09 volumio volumio[26766]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:03:09 volumio volumio[26766]: info: Volumio Network Manager: Network status updated: 0 May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: Reloading queue from file May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::setRepeat false single undefined May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::pushState May 20 04:03:09 volumio volumio[26766]: info: CorePlayQueue::getTrack 0 May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioPushState May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::setRandom false May 20 04:03:09 volumio volumio[26766]: info: CoreStateMachine::pushState May 20 04:03:09 volumio volumio[26766]: info: CorePlayQueue::getTrack 0 May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioPushState May 20 04:03:09 volumio volumio[26766]: info: Setting Device type: Raspberry PI May 20 04:03:09 volumio volumio[26766]: info: Completed loading Core Plugins May 20 04:03:09 volumio volumio[26766]: info: Preparing to generate the ALSA configuration file May 20 04:03:09 volumio volumio[26766]: info: Asound.conf file unchanged, so no further update is needed May 20 04:03:09 volumio volumio[26766]: info: Output device has changed, restarting MPD May 20 04:03:09 volumio sudo[26893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:03:09 volumio volumio[26766]: info: ___________ START PLUGINS ___________ May 20 04:03:09 volumio sudo[26893]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:09 volumio sudo[26893]: pam_unix(sudo:session): session closed for user root May 20 04:03:09 volumio volumio[26766]: info: ControllerMpd::onStart: Initializing MPD May 20 04:03:09 volumio volumio[26766]: info: Creating MPD Configuration file May 20 04:03:09 volumio sudo[26895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:03:09 volumio sudo[26895]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:09 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:03:09 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:03:09 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:03:09 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:09 volumio volumio[26766]: info: [1747706589564] CoreMusicLibrary::Adding element Last_100 May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:09 volumio sudo[26899]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:03:09 volumio sudo[26899]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:09 volumio volumio[26766]: info: [1747706589575] CoreMusicLibrary::Adding element Webradio May 20 04:03:09 volumio sudo[26899]: pam_unix(sudo:session): session closed for user root May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:09 volumio volumio[26766]: info: Initializing BBC Radios May 20 04:03:09 volumio sudo[26901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:03:09 volumio sudo[26901]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:09 volumio systemd[1]: mpd.service: Succeeded. May 20 04:03:09 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:09 volumio volumio[26766]: info: [1747706589700] CoreMusicLibrary::Adding element SoundCloud May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:09 volumio volumio[26766]: Cannot find translation for source SoundCloud May 20 04:03:09 volumio volumio[26766]: info: Creating Spotify config file May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:09 volumio systemd[1]: Starting Music Player Daemon... May 20 04:03:09 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 20 04:03:09 volumio systemd[1]: mpd.service: Succeeded. May 20 04:03:09 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:03:09 volumio systemd[1]: Starting Music Player Daemon... May 20 04:03:09 volumio volumio[26766]: info: [squeezelite_mc] Starting proxy server... May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:09 volumio volumio[26766]: info: [1747706589759] CoreMusicLibrary::Adding element Radio Paradise May 20 04:03:09 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:09 volumio volumio[26766]: Cannot find translation for source SoundCloud May 20 04:03:09 volumio volumio[26766]: Cannot find translation for source Radio Paradise May 20 04:03:09 volumio sudo[26923]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:03:09 volumio sudo[26923]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:09 volumio sudo[26923]: pam_unix(sudo:session): session closed for user root May 20 04:03:09 volumio volumio[26766]: info: Volumio Calling Home May 20 04:03:10 volumio sudo[26952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:03:10 volumio sudo[26952]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:10 volumio volumio[26766]: info: [squeezelite_mc] Proxy server started on port 35693 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:03:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:03:10 volumio volumio[26766]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:03:10 volumio volumio[26766]: info: CoreStateMachine::pushState May 20 04:03:10 volumio volumio[26766]: info: CorePlayQueue::getTrack 0 May 20 04:03:10 volumio volumio[26766]: info: CoreCommandRouter::volumioPushState May 20 04:03:10 volumio volumio[26766]: info: [squeezelite_mc] Server discovery started May 20 04:03:10 volumio volumio[26766]: info: [squeezelite_mc] Player finder started May 20 04:03:10 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:10 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:10 volumio volumio[26766]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:10 volumio volumio[26766]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:03:10 volumio volumio[26766]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:03:10 volumio volumio[26766]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:03:10 volumio volumio[26766]: at doSend (dgram.js:692:16) May 20 04:03:10 volumio volumio[26766]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:03:10 volumio volumio[26766]: at afterDns (dgram.js:638:5) May 20 04:03:10 volumio volumio[26766]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:03:10 volumio volumio[26766]: errno: -101, May 20 04:03:10 volumio volumio[26766]: code: 'ENETUNREACH', May 20 04:03:10 volumio volumio[26766]: syscall: 'send', May 20 04:03:10 volumio volumio[26766]: address: '255.255.255.255', May 20 04:03:10 volumio volumio[26766]: port: 3483 May 20 04:03:10 volumio volumio[26766]: } May 20 04:03:10 volumio volumio[26766]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:03:10 volumio sudo[26952]: pam_unix(sudo:session): session closed for user root May 20 04:03:10 volumio sudo[26982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:02 May 20 04:03:10 volumio sudo[26982]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:11 volumio sudo[26982]: pam_unix(sudo:session): session closed for user root May 20 04:03:11 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 20 04:03:11 volumio sudo[26895]: pam_unix(sudo:session): session closed for user root May 20 04:03:11 volumio sudo[26901]: pam_unix(sudo:session): session closed for user root May 20 04:03:11 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 20 04:03:11 volumio systemd[1]: Started dynamicswap service. May 20 04:03:11 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:03:11 volumio mpd[26942]: May 20 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 20 04:03:11 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 20 04:03:11 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 93. May 20 04:03:11 volumio systemd[1]: Started dynamicswap service. May 20 04:03:11 volumio systemd[1]: Stopped Volumio Backend Module. May 20 04:03:11 volumio systemd[1]: Started Volumio Backend Module. May 20 04:03:11 volumio systemd[1]: dynamicswap.service: Succeeded. May 20 04:03:12 volumio systemd[1]: Started Music Player Daemon. May 20 04:03:12 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... May 20 04:03:12 volumio volumio[27010]: cset: --> shielding system active with May 20 04:03:12 volumio volumio[27010]: cset: "system" cpuset of CPUSPEC(1-3) with 64 tasks running May 20 04:03:12 volumio volumio[27010]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running May 20 04:03:12 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. May 20 04:03:13 volumio volumio[26996]: info: ------------------------------------------- May 20 04:03:13 volumio volumio[26996]: info: ----- Volumio3 ---- May 20 04:03:13 volumio volumio[26996]: info: ------------------------------------------- May 20 04:03:13 volumio volumio[26996]: info: ----- System startup ---- May 20 04:03:13 volumio volumio[26996]: info: ------------------------------------------- May 20 04:03:13 volumio volumio[26996]: info: MYVOLUMIO Environment detected May 20 04:03:13 volumio volumio[26996]: info: Plugin folders cleanup May 20 04:03:13 volumio volumio[26996]: info: Scanning into folder /volumio/app/plugins/ May 20 04:03:13 volumio volumio[26996]: info: Scanning category audio_interface May 20 04:03:13 volumio volumio[26996]: info: Scanning category miscellanea May 20 04:03:13 volumio volumio[26996]: info: Scanning category music_service May 20 04:03:13 volumio volumio[26996]: info: Scanning category plugins.json May 20 04:03:13 volumio volumio[26996]: info: Scanning category system_controller May 20 04:03:13 volumio volumio[26996]: info: Scanning category user_interface May 20 04:03:13 volumio volumio[26996]: info: Scanning into folder /data/plugins/ May 20 04:03:13 volumio volumio[26996]: info: Scanning category music_service May 20 04:03:13 volumio volumio[26996]: info: Scanning category system_controller May 20 04:03:13 volumio volumio[26996]: info: Scanning category system_hardware May 20 04:03:13 volumio volumio[26996]: info: Scanning category user_interface May 20 04:03:13 volumio volumio[26996]: info: Plugin folders cleanup completed May 20 04:03:13 volumio volumio[26996]: info: ------------------------------------------- May 20 04:03:13 volumio volumio[26996]: info: ----- Core plugins startup ---- May 20 04:03:13 volumio volumio[26996]: info: ------------------------------------------- May 20 04:03:13 volumio volumio[26996]: info: Loading plugins from folder /volumio/app/plugins/ May 20 04:03:13 volumio volumio[26996]: info: Adding plugin upnp to MyMusic Plugins May 20 04:03:13 volumio volumio[26996]: info: Adding plugin airplay_emulation to MyMusic Plugins May 20 04:03:13 volumio volumio[26996]: info: Adding plugin upnp_browser to MyMusic Plugins May 20 04:03:13 volumio volumio[26996]: info: Loading plugins from folder /data/plugins/ May 20 04:03:13 volumio volumio[26996]: info: Loading plugin "system"... May 20 04:03:13 volumio volumio[26996]: info: Loading plugin "appearance"... May 20 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 May 20 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 May 20 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 May 20 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 May 20 04:03:14 volumio volumio[26996]: info: Loading plugin "network"... May 20 04:03:14 volumio volumio[26996]: info: Refreshing Cached IP Addresses May 20 04:03:14 volumio sudo[27039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 20 04:03:14 volumio sudo[27039]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:14 volumio sudo[27039]: pam_unix(sudo:session): session closed for user root May 20 04:03:14 volumio sudo[27041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 20 04:03:14 volumio sudo[27041]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:14 volumio sudo[27041]: pam_unix(sudo:session): session closed for user root May 20 04:03:14 volumio volumio[26996]: info: Loading plugin "services"... May 20 04:03:14 volumio volumio[26996]: info: Loading plugin "alsa_controller"... May 20 04:03:14 volumio sudo[27050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 20 04:03:14 volumio sudo[27050]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:14 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:14 volumio volumio[26996]: info: Loading plugin "wizard"... May 20 04:03:14 volumio volumio[26996]: info: Loading plugin "networkfs"... May 20 04:03:15 volumio volumio[26996]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds May 20 04:03:15 volumio volumio[26996]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds May 20 04:03:15 volumio volumio[26996]: info: Starting Udev Watcher for removable devices May 20 04:03:15 volumio volumio[26996]: info: Ignoring mount for partition: boot May 20 04:03:15 volumio volumio[26996]: info: Ignoring mount for partition: volumio May 20 04:03:15 volumio volumio[26996]: info: Ignoring mount for partition: volumio_data May 20 04:03:15 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "volumio_command_line_client"... May 20 04:03:15 volumio volumio[26996]: info: Plugin upnp is not enabled May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "my_music"... May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "mpd"... May 20 04:03:15 volumio volumio[26996]: info: Plugin upnp_browser is not enabled May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "alarm-clock"... May 20 04:03:15 volumio volumio[26996]: info: Plugin airplay_emulation is not enabled May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "last_100"... May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "webradio"... May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "i2s_dacs"... May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "volumiodiscovery"... May 20 04:03:15 volumio volumio[26996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:03:15 volumio volumio[26996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:15 volumio volumio[26996]: *** WARNING *** For more information see May 20 04:03:15 volumio node[26996]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 20 04:03:15 volumio volumio[26996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:03:15 volumio volumio[26996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:15 volumio volumio[26996]: *** WARNING *** For more information see May 20 04:03:15 volumio node[26996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:15 volumio node[26996]: *** WARNING *** For more information see May 20 04:03:15 volumio node[26996]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 20 04:03:15 volumio node[26996]: *** WARNING *** Please fix your application to use the native API of Avahi! May 20 04:03:15 volumio node[26996]: *** WARNING *** For more information see May 20 04:03:15 volumio volumio[26996]: info: Applying required configuration parameters for plugin volumiodiscovery May 20 04:03:15 volumio volumio[26996]: info: Discovery: Started advertising with name: Volumio May 20 04:03:15 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 20 04:03:15 volumio volumio[26996]: info: Loading plugin "soundcloud"... May 20 04:03:16 volumio volumio[26996]: info: Loading plugin "spop"... May 20 04:03:16 volumio wpa_supplicant[857]: wlan0: Trying to associate with SSID 'DAVINCHI-5G' May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 May 20 04:03:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 May 20 04:03:16 volumio sudo[27050]: pam_unix(sudo:session): session closed for user root May 20 04:03:16 volumio wpa_supplicant[857]: wlan0: Associated with f0:b4:d2:6b:1f:7b May 20 04:03:16 volumio wpa_supplicant[857]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:b4:d2:6b:1f:7b completed [id=0 id_str=] May 20 04:03:16 volumio dhcpcd[869]: wlan0: carrier acquired May 20 04:03:16 volumio wpa_supplicant[857]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 20 04:03:16 volumio dhcpcd[869]: wlan0: IAID eb:2a:05:b2 May 20 04:03:16 volumio dhcpcd[869]: wlan0: carrier lost May 20 04:03:16 volumio dhcpcd[869]: wlan0: carrier acquired May 20 04:03:16 volumio dhcpcd[869]: wlan0: IAID eb:2a:05:b2 May 20 04:03:16 volumio dhcpcd[869]: wlan0: rebinding lease of 192.168.0.120 May 20 04:03:16 volumio dhcpcd[869]: wlan0: probing address 192.168.0.120/24 May 20 04:03:17 volumio volumio[26996]: info: Loading plugin "squeezelite_mc"... May 20 04:03:17 volumio volumio-remote-updater[587]: [2025-05-20 04:03:17] [connect] Successful connection May 20 04:03:17 volumio dhcpcd[869]: wlan0: soliciting an IPv6 router May 20 04:03:17 volumio volumio[26996]: info: Loading plugin "outputs"... May 20 04:03:17 volumio volumio[26996]: info: Loading plugin "albumart"... May 20 04:03:17 volumio volumio[26996]: info: Plugin example_plugin is not enabled May 20 04:03:17 volumio volumio[26996]: info: Loading plugin "inputs"... May 20 04:03:17 volumio volumio[26996]: info: Loading plugin "updater_comm"... May 20 04:03:18 volumio volumio[26996]: info: Plugin mpdemulation is not enabled May 20 04:03:18 volumio volumio[26996]: info: Loading plugin "rest_api"... May 20 04:03:18 volumio volumio[26996]: info: Loading plugin "websocket"... May 20 04:03:18 volumio volumio[26996]: info: Starting Socket.io Server version 2.3.0 May 20 04:03:18 volumio volumio[26996]: info: Loading plugin "radio_paradise"... May 20 04:03:18 volumio volumio[26996]: Forking 3 albumart workers May 20 04:03:18 volumio volumio[26996]: info: Applying required configuration parameters for plugin radio_paradise May 20 04:03:18 volumio volumio[26996]: info: [1747706598477] [RadioParadise] API delay: 3 May 20 04:03:18 volumio volumio[26996]: info: Loading plugin "backup_restore"... May 20 04:03:18 volumio volumio[26996]: info: Applying required configuration parameters for plugin backup_restore May 20 04:03:18 volumio volumio[26996]: info: Loading plugin "music_services_shield"... May 20 04:03:19 volumio volumio[26996]: Starting albumart workers May 20 04:03:19 volumio volumio[26996]: Starting albumart workers May 20 04:03:19 volumio volumio[26996]: info: Applying required configuration parameters for plugin music_services_shield May 20 04:03:19 volumio volumio[26996]: info: Loading plugin "Systeminfo"... May 20 04:03:19 volumio volumio[26996]: info: Loading i18n strings for locale en May 20 04:03:19 volumio volumio[26996]: Updating browse sources language May 20 04:03:19 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:19 volumio volumio[26996]: Starting albumart workers May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::initPlayerControls May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:20 volumio volumio[26996]: Express server listening on port 3000 May 20 04:03:20 volumio volumio[26996]: [Metrics] WebUI: 7s 441.02ms May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::resetVolumioState May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::getcurrentVolume May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::pushState May 20 04:03:20 volumio volumio[26996]: info: CorePlayQueue::getTrack 0 May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioPushState May 20 04:03:20 volumio volumio[26996]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds May 20 04:03:20 volumio volumio[26996]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds May 20 04:03:20 volumio volumio[26996]: info: Volumio Network Manager: Network status updated: 0 May 20 04:03:20 volumio volumio-remote-updater[587]: [2025-05-20 04:03:20] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747706597 101 May 20 04:03:20 volumio volumio[26996]: 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 May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: Reloading queue from file May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::setRepeat false single undefined May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::pushState May 20 04:03:20 volumio volumio[26996]: info: CorePlayQueue::getTrack 0 May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioPushState May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::setRandom false May 20 04:03:20 volumio volumio[26996]: info: CoreStateMachine::pushState May 20 04:03:20 volumio volumio[26996]: info: CorePlayQueue::getTrack 0 May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioPushState May 20 04:03:20 volumio volumio[26996]: info: Setting Device type: Raspberry PI May 20 04:03:20 volumio volumio[26996]: info: Completed loading Core Plugins May 20 04:03:20 volumio volumio[26996]: info: Preparing to generate the ALSA configuration file May 20 04:03:20 volumio volumio[26996]: info: Asound.conf file unchanged, so no further update is needed May 20 04:03:20 volumio volumio[26996]: info: Output device has changed, restarting MPD May 20 04:03:20 volumio sudo[27131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:03:20 volumio sudo[27131]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio volumio[26996]: info: ___________ START PLUGINS ___________ May 20 04:03:20 volumio sudo[27131]: pam_unix(sudo:session): session closed for user root May 20 04:03:20 volumio volumio[26996]: info: ControllerMpd::onStart: Initializing MPD May 20 04:03:20 volumio volumio[26996]: info: Creating MPD Configuration file May 20 04:03:20 volumio sudo[27133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:03:20 volumio sudo[27133]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:20 volumio volumio[26996]: info: [1747706600465] CoreMusicLibrary::Adding element Last_100 May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:20 volumio volumio[26996]: info: [1747706600472] CoreMusicLibrary::Adding element Webradio May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:20 volumio systemd[1]: musicservicesshield.service: Succeeded. May 20 04:03:20 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. May 20 04:03:20 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... May 20 04:03:20 volumio systemd[1]: Stopping Music Player Daemon... May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:20 volumio volumio[26996]: info: Initializing BBC Radios May 20 04:03:20 volumio sudo[27145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 20 04:03:20 volumio sudo[27145]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio sudo[27147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 20 04:03:20 volumio sudo[27147]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio sudo[27145]: pam_unix(sudo:session): session closed for user root May 20 04:03:20 volumio systemd[1]: mpd.service: Succeeded. May 20 04:03:20 volumio systemd[1]: Stopped Music Player Daemon. May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:20 volumio volumio[26996]: info: [1747706600603] CoreMusicLibrary::Adding element SoundCloud May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:20 volumio volumio[26996]: Cannot find translation for source SoundCloud May 20 04:03:20 volumio volumio[26996]: info: Creating Spotify config file May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:20 volumio volumio[26996]: info: [squeezelite_mc] Starting proxy server... May 20 04:03:20 volumio systemd[1]: Starting Music Player Daemon... May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 20 04:03:20 volumio volumio[26996]: info: [1747706600644] CoreMusicLibrary::Adding element Radio Paradise May 20 04:03:20 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 20 04:03:20 volumio volumio[26996]: Cannot find translation for source SoundCloud May 20 04:03:20 volumio volumio[26996]: Cannot find translation for source Radio Paradise May 20 04:03:20 volumio sudo[27160]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 20 04:03:20 volumio sudo[27160]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio sudo[27160]: pam_unix(sudo:session): session closed for user root May 20 04:03:20 volumio volumio[26996]: info: Volumio Calling Home May 20 04:03:20 volumio sudo[27189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh May 20 04:03:20 volumio sudo[27189]: pam_unix(sudo:session): session opened for user root by (uid=0) May 20 04:03:20 volumio volumio[26996]: info: [squeezelite_mc] Proxy server started on port 34887 May 20 04:03:21 volumio volumio[26996]: info: CoreCommandRouter::volumioRetrievevolume May 20 04:03:21 volumio volumio[26996]: info: CoreStateMachine::pushState May 20 04:03:21 volumio volumio[26996]: info: CorePlayQueue::getTrack 0 May 20 04:03:21 volumio volumio[26996]: info: CoreCommandRouter::volumioPushState May 20 04:03:21 volumio volumio[26996]: info: [squeezelite_mc] Server discovery started May 20 04:03:21 volumio volumio[26996]: info: [squeezelite_mc] Player finder started May 20 04:03:21 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 20 04:03:21 volumio volumio[26996]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 20 04:03:21 volumio volumio[26996]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 20 04:03:21 volumio volumio[26996]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:03:21 volumio dhcpcd[869]: wlan0: leased 192.168.0.120 for infinity May 20 04:03:21 volumio avahi-daemon[599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.120. May 20 04:03:21 volumio dhcpcd[869]: wlan0: adding route to 192.168.0.0/24 May 20 04:03:21 volumio dhcpcd[869]: wlan0: adding default route via 192.168.0.1 May 20 04:03:21 volumio avahi-daemon[599]: New relevant interface wlan0.IPv4 for mDNS. May 20 04:03:21 volumio avahi-daemon[599]: Registering new address record for 192.168.0.120 on wlan0.IPv4. May 20 04:03:21 volumio volumio[26996]: Error: send ENETUNREACH 255.255.255.255:3483 May 20 04:03:21 volumio volumio[26996]: at doSend (dgram.js:692:16) May 20 04:03:21 volumio volumio[26996]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 20 04:03:21 volumio volumio[26996]: at afterDns (dgram.js:638:5) May 20 04:03:21 volumio volumio[26996]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 20 04:03:21 volumio volumio[26996]: errno: -101, May 20 04:03:21 volumio volumio[26996]: code: 'ENETUNREACH', May 20 04:03:21 volumio volumio[26996]: syscall: 'send', May 20 04:03:21 volumio volumio[26996]: address: '255.255.255.255', May 20 04:03:21 volumio volumio[26996]: port: 3483 May 20 04:03:21 volumio volumio[26996]: } May 20 04:03:21 volumio volumio[26996]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 20 04:03:21 volumio sudo[27189]: pam_unix(sudo:session): session closed for user root May 20 04:03:21 volumio sudo[27240]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-20 04:02 May 20 04:03:21 volumio sudo[27240]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"