-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Tue 2025-06-10 04:03:28 CEST. -- Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:00 volumio volumio[30585]: info: [1749520920022] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:00 volumio volumio[30585]: Cannot find translation for source SoundCloud Jun 10 04:02:00 volumio volumio[30585]: info: Creating Spotify config file Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:00 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:02:00 volumio volumio[30585]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:00 volumio volumio[30585]: info: [1749520920068] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:00 volumio volumio[30585]: Cannot find translation for source SoundCloud Jun 10 04:02:00 volumio volumio[30585]: Cannot find translation for source Radio Paradise Jun 10 04:02:00 volumio sudo[30743]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:02:00 volumio sudo[30743]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:00 volumio sudo[30743]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:00 volumio volumio[30585]: info: Volumio Calling Home Jun 10 04:02:00 volumio volumio[30585]: info: [squeezelite_mc] Proxy server started on port 35225 Jun 10 04:02:00 volumio sudo[30776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:02:00 volumio sudo[30776]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:00 volumio volumio[30585]: info: CoreStateMachine::pushState Jun 10 04:02:00 volumio volumio[30585]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:00 volumio volumio[30585]: info: [squeezelite_mc] Server discovery started Jun 10 04:02:00 volumio volumio[30585]: info: [squeezelite_mc] Player finder started Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:00 volumio volumio[30585]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:00 volumio volumio[30585]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:02:00 volumio volumio[30585]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:00 volumio volumio[30585]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:02:00 volumio volumio[30585]: at doSend (dgram.js:692:16) Jun 10 04:02:00 volumio volumio[30585]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:02:00 volumio volumio[30585]: at afterDns (dgram.js:638:5) Jun 10 04:02:00 volumio volumio[30585]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:02:00 volumio volumio[30585]: errno: -101, Jun 10 04:02:00 volumio volumio[30585]: code: 'ENETUNREACH', Jun 10 04:02:00 volumio volumio[30585]: syscall: 'send', Jun 10 04:02:00 volumio volumio[30585]: address: '255.255.255.255', Jun 10 04:02:00 volumio volumio[30585]: port: 3483 Jun 10 04:02:00 volumio volumio[30585]: } Jun 10 04:02:00 volumio volumio[30585]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:01 volumio sudo[30776]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:01 volumio sudo[30806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:01 Jun 10 04:02:01 volumio sudo[30806]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:01 volumio sudo[30806]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:02 volumio volumio-remote-updater[560]: [2025-06-10 04:02:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 10 04:02:02 volumio volumio-remote-updater[560]: [2025-06-10 04:02:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 10 04:02:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:02:02 volumio sudo[30735]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:02 volumio sudo[30728]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:02:02 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:02:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 39. Jun 10 04:02:02 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:02 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:02:02 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:02:02 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 10 04:02:02 volumio mpd[30764]: Jun 10 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:02:04 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:02:04 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:04 volumio volumio[30829]: cset: --> shielding system active with Jun 10 04:02:04 volumio volumio[30829]: cset: "system" cpuset of CPUSPEC(1-3) with 70 tasks running Jun 10 04:02:04 volumio volumio[30829]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:02:04 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:04 volumio volumio[30819]: info: ------------------------------------------- Jun 10 04:02:04 volumio volumio[30819]: info: ----- Volumio3 ---- Jun 10 04:02:04 volumio volumio[30819]: info: ------------------------------------------- Jun 10 04:02:04 volumio volumio[30819]: info: ----- System startup ---- Jun 10 04:02:04 volumio volumio[30819]: info: ------------------------------------------- Jun 10 04:02:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:05 volumio autossh[30040]: starting ssh (count 27) Jun 10 04:02:05 volumio autossh[30040]: ssh child pid is 30852 Jun 10 04:02:05 volumio volumiossh-tunnel[30035]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 10 04:02:05 volumio autossh[30040]: ssh exited with error status 255; restarting ssh Jun 10 04:02:05 volumio volumio[30819]: info: MYVOLUMIO Environment detected Jun 10 04:02:05 volumio volumio[30819]: info: Plugin folders cleanup Jun 10 04:02:05 volumio volumio[30819]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category audio_interface Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category miscellanea Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category music_service Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category plugins.json Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category system_controller Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category user_interface Jun 10 04:02:05 volumio volumio[30819]: info: Scanning into folder /data/plugins/ Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category music_service Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category system_controller Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category system_hardware Jun 10 04:02:05 volumio volumio[30819]: info: Scanning category user_interface Jun 10 04:02:05 volumio volumio[30819]: info: Plugin folders cleanup completed Jun 10 04:02:05 volumio volumio[30819]: info: ------------------------------------------- Jun 10 04:02:05 volumio volumio[30819]: info: ----- Core plugins startup ---- Jun 10 04:02:05 volumio volumio[30819]: info: ------------------------------------------- Jun 10 04:02:05 volumio volumio[30819]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:02:05 volumio volumio[30819]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:02:05 volumio volumio[30819]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:02:05 volumio volumio[30819]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:02:05 volumio volumio[30819]: info: Loading plugins from folder /data/plugins/ Jun 10 04:02:05 volumio volumio[30819]: info: Loading plugin "system"... Jun 10 04:02:05 volumio volumio[30819]: info: Loading plugin "appearance"... Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "network"... Jun 10 04:02:06 volumio volumio[30819]: info: Refreshing Cached IP Addresses Jun 10 04:02:06 volumio sudo[30859]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:02:06 volumio sudo[30859]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:06 volumio sudo[30859]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:06 volumio sudo[30861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:02:06 volumio sudo[30861]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:06 volumio sudo[30861]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "services"... Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "alsa_controller"... Jun 10 04:02:06 volumio sudo[30870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:02:06 volumio sudo[30870]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:06 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "wizard"... Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "networkfs"... Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:06 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:02:06 volumio volumio[30819]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:06 volumio volumio[30819]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:06 volumio volumio[30819]: info: Starting Udev Watcher for removable devices Jun 10 04:02:06 volumio volumio[30819]: info: Ignoring mount for partition: boot Jun 10 04:02:06 volumio volumio[30819]: info: Ignoring mount for partition: volumio Jun 10 04:02:06 volumio volumio[30819]: info: Ignoring mount for partition: volumio_data Jun 10 04:02:06 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:02:06 volumio volumio[30819]: info: Plugin upnp is not enabled Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "my_music"... Jun 10 04:02:06 volumio volumio[30819]: info: Loading plugin "mpd"... Jun 10 04:02:06 volumio kernel: hwmon hwmon1: Voltage normalised Jun 10 04:02:07 volumio volumio-remote-updater[560]: [2025-06-10 04:02:07] [connect] Successful connection Jun 10 04:02:07 volumio volumio[30819]: info: Plugin upnp_browser is not enabled Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "alarm-clock"... Jun 10 04:02:07 volumio volumio[30819]: info: Plugin airplay_emulation is not enabled Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "last_100"... Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "webradio"... Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "i2s_dacs"... Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "volumiodiscovery"... Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** For more information see Jun 10 04:02:07 volumio node[30819]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:07 volumio volumio[30819]: *** WARNING *** For more information see Jun 10 04:02:07 volumio node[30819]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:07 volumio node[30819]: *** WARNING *** For more information see Jun 10 04:02:07 volumio node[30819]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:07 volumio node[30819]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:07 volumio node[30819]: *** WARNING *** For more information see Jun 10 04:02:07 volumio volumio[30819]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:02:07 volumio volumio[30819]: info: Discovery: Started advertising with name: Volumio Jun 10 04:02:07 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "soundcloud"... Jun 10 04:02:07 volumio volumio[30819]: info: Loading plugin "spop"... Jun 10 04:02:08 volumio volumio[30819]: info: Loading plugin "squeezelite_mc"... Jun 10 04:02:09 volumio sudo[30870]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "outputs"... Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "albumart"... Jun 10 04:02:09 volumio volumio[30819]: info: Plugin example_plugin is not enabled Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "inputs"... Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "updater_comm"... Jun 10 04:02:09 volumio volumio[30819]: info: Plugin mpdemulation is not enabled Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "rest_api"... Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "websocket"... Jun 10 04:02:09 volumio volumio[30819]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:02:09 volumio volumio[30819]: info: Loading plugin "radio_paradise"... Jun 10 04:02:09 volumio volumio[30819]: Forking 3 albumart workers Jun 10 04:02:10 volumio volumio[30819]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:02:10 volumio volumio[30819]: info: [1749520930203] [RadioParadise] API delay: 3 Jun 10 04:02:10 volumio volumio[30819]: info: Loading plugin "backup_restore"... Jun 10 04:02:10 volumio volumio[30819]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:02:10 volumio volumio[30819]: info: Loading plugin "music_services_shield"... Jun 10 04:02:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:10 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:11 volumio volumio[30819]: Starting albumart workers Jun 10 04:02:11 volumio volumio[30819]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:02:11 volumio volumio[30819]: info: Loading plugin "Systeminfo"... Jun 10 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:11 volumio volumio[30819]: Starting albumart workers Jun 10 04:02:11 volumio volumio[30819]: Starting albumart workers Jun 10 04:02:11 volumio volumio[30819]: info: Loading i18n strings for locale en Jun 10 04:02:11 volumio volumio[30819]: Updating browse sources language Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::initPlayerControls Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:11 volumio volumio[30819]: Express server listening on port 3000 Jun 10 04:02:11 volumio volumio[30819]: [Metrics] WebUI: 7s 451.85ms Jun 10 04:02:11 volumio volumio[30819]: info: CoreStateMachine::resetVolumioState Jun 10 04:02:11 volumio volumio[30819]: info: CoreStateMachine::getcurrentVolume Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:11 volumio volumio[30819]: info: CoreStateMachine::pushState Jun 10 04:02:11 volumio volumio[30819]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:11 volumio volumio[30819]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:11 volumio volumio[30819]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:11 volumio volumio[30819]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:11 volumio volumio[30819]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:02:11 volumio volumio-remote-updater[560]: [2025-06-10 04:02:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749520927 101 Jun 10 04:02:12 volumio volumio[30819]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: Reloading queue from file Jun 10 04:02:12 volumio volumio[30819]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:02:12 volumio volumio[30819]: info: CoreStateMachine::pushState Jun 10 04:02:12 volumio volumio[30819]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:12 volumio volumio[30819]: info: CoreStateMachine::setRandom false Jun 10 04:02:12 volumio volumio[30819]: info: CoreStateMachine::pushState Jun 10 04:02:12 volumio volumio[30819]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:12 volumio volumio[30819]: info: Setting Device type: Raspberry PI Jun 10 04:02:12 volumio volumio[30819]: info: Completed loading Core Plugins Jun 10 04:02:12 volumio volumio[30819]: info: Preparing to generate the ALSA configuration file Jun 10 04:02:12 volumio volumio[30819]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:02:12 volumio volumio[30819]: info: Output device has changed, restarting MPD Jun 10 04:02:12 volumio sudo[30950]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:12 volumio sudo[30950]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio volumio[30819]: info: ___________ START PLUGINS ___________ Jun 10 04:02:12 volumio sudo[30950]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:12 volumio volumio[30819]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:02:12 volumio volumio[30819]: info: Creating MPD Configuration file Jun 10 04:02:12 volumio sudo[30952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:12 volumio sudo[30952]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:02:12 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:12 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:12 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:12 volumio sudo[30956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:12 volumio volumio[30819]: info: [1749520932248] CoreMusicLibrary::Adding element Last_100 Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:12 volumio sudo[30956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio sudo[30956]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:12 volumio volumio[30819]: info: [1749520932260] CoreMusicLibrary::Adding element Webradio Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:12 volumio volumio[30819]: info: Initializing BBC Radios Jun 10 04:02:12 volumio sudo[30958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:12 volumio sudo[30958]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:02:12 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:12 volumio volumio[30819]: info: [1749520932362] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:12 volumio volumio[30819]: Cannot find translation for source SoundCloud Jun 10 04:02:12 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:02:12 volumio volumio[30819]: info: Creating Spotify config file Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:12 volumio volumio[30819]: info: [1749520932417] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:12 volumio volumio[30819]: Cannot find translation for source SoundCloud Jun 10 04:02:12 volumio volumio[30819]: Cannot find translation for source Radio Paradise Jun 10 04:02:12 volumio sudo[30968]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:02:12 volumio sudo[30968]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio sudo[30968]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:12 volumio volumio[30819]: info: Volumio Calling Home Jun 10 04:02:12 volumio volumio[30819]: info: [squeezelite_mc] Proxy server started on port 38419 Jun 10 04:02:12 volumio sudo[31000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:02:12 volumio sudo[31000]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:12 volumio volumio[30819]: info: CoreStateMachine::pushState Jun 10 04:02:12 volumio volumio[30819]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:12 volumio volumio[30819]: info: [squeezelite_mc] Server discovery started Jun 10 04:02:12 volumio volumio[30819]: info: [squeezelite_mc] Player finder started Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:12 volumio volumio[30819]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:02:12 volumio volumio[30819]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:12 volumio volumio[30819]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:02:12 volumio volumio[30819]: at doSend (dgram.js:692:16) Jun 10 04:02:12 volumio volumio[30819]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:02:12 volumio volumio[30819]: at afterDns (dgram.js:638:5) Jun 10 04:02:12 volumio volumio[30819]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:02:12 volumio volumio[30819]: errno: -101, Jun 10 04:02:12 volumio volumio[30819]: code: 'ENETUNREACH', Jun 10 04:02:12 volumio volumio[30819]: syscall: 'send', Jun 10 04:02:12 volumio volumio[30819]: address: '255.255.255.255', Jun 10 04:02:12 volumio volumio[30819]: port: 3483 Jun 10 04:02:12 volumio volumio[30819]: } Jun 10 04:02:12 volumio volumio[30819]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:13 volumio sudo[31000]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:13 volumio sudo[31030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:01 Jun 10 04:02:13 volumio sudo[31030]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:13 volumio sudo[31030]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:13 volumio volumio-remote-updater[560]: [2025-06-10 04:02:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 10 04:02:13 volumio volumio-remote-updater[560]: [2025-06-10 04:02:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 10 04:02:13 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:02:13 volumio sudo[30952]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:13 volumio sudo[30958]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:13 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:02:13 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:13 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:02:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 40. Jun 10 04:02:14 volumio mpd[30984]: Jun 10 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:02:14 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:14 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:02:14 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:02:14 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:14 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:02:14 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:15 volumio volumio[31059]: cset: --> shielding system active with Jun 10 04:02:15 volumio volumio[31059]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running Jun 10 04:02:15 volumio volumio[31059]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:02:15 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:15 volumio volumio[31044]: info: ------------------------------------------- Jun 10 04:02:15 volumio volumio[31044]: info: ----- Volumio3 ---- Jun 10 04:02:15 volumio volumio[31044]: info: ------------------------------------------- Jun 10 04:02:15 volumio volumio[31044]: info: ----- System startup ---- Jun 10 04:02:15 volumio volumio[31044]: info: ------------------------------------------- Jun 10 04:02:16 volumio volumio[31044]: info: MYVOLUMIO Environment detected Jun 10 04:02:16 volumio volumio[31044]: info: Plugin folders cleanup Jun 10 04:02:16 volumio volumio[31044]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category audio_interface Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category miscellanea Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category music_service Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category plugins.json Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category system_controller Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category user_interface Jun 10 04:02:16 volumio volumio[31044]: info: Scanning into folder /data/plugins/ Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category music_service Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category system_controller Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category system_hardware Jun 10 04:02:16 volumio volumio[31044]: info: Scanning category user_interface Jun 10 04:02:16 volumio volumio[31044]: info: Plugin folders cleanup completed Jun 10 04:02:16 volumio volumio[31044]: info: ------------------------------------------- Jun 10 04:02:16 volumio volumio[31044]: info: ----- Core plugins startup ---- Jun 10 04:02:16 volumio volumio[31044]: info: ------------------------------------------- Jun 10 04:02:16 volumio volumio[31044]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:02:16 volumio volumio[31044]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:02:16 volumio volumio[31044]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:02:16 volumio volumio[31044]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:02:16 volumio volumio[31044]: info: Loading plugins from folder /data/plugins/ Jun 10 04:02:16 volumio volumio[31044]: info: Loading plugin "system"... Jun 10 04:02:16 volumio volumio[31044]: info: Loading plugin "appearance"... Jun 10 04:02:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "network"... Jun 10 04:02:17 volumio volumio[31044]: info: Refreshing Cached IP Addresses Jun 10 04:02:17 volumio sudo[31088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:02:17 volumio sudo[31088]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:17 volumio sudo[31090]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:02:17 volumio sudo[31088]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:17 volumio sudo[31090]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:17 volumio sudo[31090]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "services"... Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "alsa_controller"... Jun 10 04:02:17 volumio sudo[31099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:02:17 volumio sudo[31099]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:17 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "wizard"... Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "networkfs"... Jun 10 04:02:17 volumio volumio[31044]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:17 volumio volumio[31044]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:17 volumio volumio[31044]: info: Starting Udev Watcher for removable devices Jun 10 04:02:17 volumio volumio[31044]: info: Ignoring mount for partition: boot Jun 10 04:02:17 volumio volumio[31044]: info: Ignoring mount for partition: volumio Jun 10 04:02:17 volumio volumio[31044]: info: Ignoring mount for partition: volumio_data Jun 10 04:02:17 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:02:17 volumio volumio[31044]: info: Plugin upnp is not enabled Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "my_music"... Jun 10 04:02:17 volumio volumio[31044]: info: Loading plugin "mpd"... Jun 10 04:02:18 volumio volumio[31044]: info: Plugin upnp_browser is not enabled Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "alarm-clock"... Jun 10 04:02:18 volumio volumio[31044]: info: Plugin airplay_emulation is not enabled Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "last_100"... Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "webradio"... Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "i2s_dacs"... Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "volumiodiscovery"... Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** For more information see Jun 10 04:02:18 volumio node[31044]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:18 volumio volumio[31044]: *** WARNING *** For more information see Jun 10 04:02:18 volumio node[31044]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:18 volumio node[31044]: *** WARNING *** For more information see Jun 10 04:02:18 volumio node[31044]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:18 volumio node[31044]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:18 volumio node[31044]: *** WARNING *** For more information see Jun 10 04:02:18 volumio volumio[31044]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:02:18 volumio volumio[31044]: info: Discovery: Started advertising with name: Volumio Jun 10 04:02:18 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "soundcloud"... Jun 10 04:02:18 volumio volumio[31044]: info: Loading plugin "spop"... Jun 10 04:02:19 volumio volumio-remote-updater[560]: [2025-06-10 04:02:19] [connect] Successful connection Jun 10 04:02:19 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:19 volumio volumio[31044]: info: Loading plugin "squeezelite_mc"... Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "outputs"... Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "albumart"... Jun 10 04:02:20 volumio volumio[31044]: info: Plugin example_plugin is not enabled Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "inputs"... Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "updater_comm"... Jun 10 04:02:20 volumio volumio[31044]: info: Plugin mpdemulation is not enabled Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "rest_api"... Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "websocket"... Jun 10 04:02:20 volumio volumio[31044]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:02:20 volumio volumio[31044]: info: Loading plugin "radio_paradise"... Jun 10 04:02:20 volumio volumio[31044]: Forking 3 albumart workers Jun 10 04:02:21 volumio volumio[31044]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:02:21 volumio volumio[31044]: info: [1749520941123] [RadioParadise] API delay: 3 Jun 10 04:02:21 volumio volumio[31044]: info: Loading plugin "backup_restore"... Jun 10 04:02:21 volumio volumio[31044]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:02:21 volumio volumio[31044]: info: Loading plugin "music_services_shield"... Jun 10 04:02:21 volumio volumio[31044]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:02:21 volumio volumio[31044]: info: Loading plugin "Systeminfo"... Jun 10 04:02:21 volumio volumio[31044]: Starting albumart workers Jun 10 04:02:21 volumio sudo[31099]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:22 volumio volumio[31044]: info: Loading i18n strings for locale en Jun 10 04:02:22 volumio volumio[31044]: Updating browse sources language Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::initPlayerControls Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: Starting albumart workers Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: Express server listening on port 3000 Jun 10 04:02:22 volumio volumio[31044]: [Metrics] WebUI: 7s 84.66ms Jun 10 04:02:22 volumio volumio[31044]: Starting albumart workers Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::resetVolumioState Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::getcurrentVolume Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::pushState Jun 10 04:02:22 volumio volumio[31044]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:22 volumio volumio[31044]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:02:22 volumio volumio-remote-updater[560]: [2025-06-10 04:02:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749520938 101 Jun 10 04:02:22 volumio volumio[31044]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:22 volumio volumio[31044]: info: Reloading queue from file Jun 10 04:02:22 volumio volumio[31044]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:22 volumio volumio[31044]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::pushState Jun 10 04:02:22 volumio volumio[31044]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::setRandom false Jun 10 04:02:22 volumio volumio[31044]: info: CoreStateMachine::pushState Jun 10 04:02:22 volumio volumio[31044]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:22 volumio volumio[31044]: info: Setting Device type: Raspberry PI Jun 10 04:02:22 volumio volumio[31044]: info: Completed loading Core Plugins Jun 10 04:02:22 volumio volumio[31044]: info: Preparing to generate the ALSA configuration file Jun 10 04:02:22 volumio volumio[31044]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:02:22 volumio volumio[31044]: info: Output device has changed, restarting MPD Jun 10 04:02:22 volumio sudo[31159]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:22 volumio sudo[31159]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:22 volumio volumio[31044]: info: ___________ START PLUGINS ___________ Jun 10 04:02:22 volumio sudo[31159]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:22 volumio volumio[31044]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:02:22 volumio volumio[31044]: info: Creating MPD Configuration file Jun 10 04:02:22 volumio sudo[31162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:22 volumio sudo[31162]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:22 volumio sudo[31165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:22 volumio volumio[31044]: info: [1749520942922] CoreMusicLibrary::Adding element Last_100 Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:22 volumio volumio[31044]: info: [1749520942928] CoreMusicLibrary::Adding element Webradio Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:22 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:02:22 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:22 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:22 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:02:22 volumio sudo[31165]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:22 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:22 volumio volumio[31044]: info: Initializing BBC Radios Jun 10 04:02:22 volumio sudo[31165]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:22 volumio sudo[31167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:22 volumio sudo[31167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:23 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:02:23 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:02:23 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:23 volumio volumio[31044]: info: [1749520943110] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:23 volumio volumio[31044]: Cannot find translation for source SoundCloud Jun 10 04:02:23 volumio volumio[31044]: info: Creating Spotify config file Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:23 volumio volumio[31044]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:23 volumio volumio[31044]: info: [1749520943179] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:23 volumio volumio[31044]: Cannot find translation for source SoundCloud Jun 10 04:02:23 volumio volumio[31044]: Cannot find translation for source Radio Paradise Jun 10 04:02:23 volumio sudo[31176]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:02:23 volumio sudo[31176]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:23 volumio sudo[31176]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:23 volumio volumio[31044]: info: Volumio Calling Home Jun 10 04:02:23 volumio autossh[30040]: starting ssh (count 28) Jun 10 04:02:23 volumio autossh[30040]: ssh child pid is 31228 Jun 10 04:02:23 volumio sudo[31225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:02:23 volumio sudo[31225]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:23 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:23 volumio volumiossh-tunnel[30035]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 10 04:02:23 volumio autossh[30040]: ssh exited with error status 255; restarting ssh Jun 10 04:02:23 volumio volumio[31044]: info: [squeezelite_mc] Proxy server started on port 36885 Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:23 volumio volumio[31044]: info: CoreStateMachine::pushState Jun 10 04:02:23 volumio volumio[31044]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:23 volumio volumio[31044]: info: [squeezelite_mc] Server discovery started Jun 10 04:02:23 volumio volumio[31044]: info: [squeezelite_mc] Player finder started Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:23 volumio volumio[31044]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:23 volumio volumio[31044]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:02:23 volumio volumio[31044]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:23 volumio volumio[31044]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:02:23 volumio volumio[31044]: at doSend (dgram.js:692:16) Jun 10 04:02:23 volumio volumio[31044]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:02:23 volumio volumio[31044]: at afterDns (dgram.js:638:5) Jun 10 04:02:23 volumio volumio[31044]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:02:23 volumio volumio[31044]: errno: -101, Jun 10 04:02:23 volumio volumio[31044]: code: 'ENETUNREACH', Jun 10 04:02:23 volumio volumio[31044]: syscall: 'send', Jun 10 04:02:23 volumio volumio[31044]: address: '255.255.255.255', Jun 10 04:02:23 volumio volumio[31044]: port: 3483 Jun 10 04:02:23 volumio volumio[31044]: } Jun 10 04:02:23 volumio volumio[31044]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:24 volumio sudo[31225]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:24 volumio sudo[31256]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:01 Jun 10 04:02:24 volumio sudo[31256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:24 volumio sudo[31256]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:24 volumio volumio-remote-updater[560]: [2025-06-10 04:02:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 10 04:02:24 volumio volumio-remote-updater[560]: [2025-06-10 04:02:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 10 04:02:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:02:24 volumio sudo[31167]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:24 volumio sudo[31162]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:02:24 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:24 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:02:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 41. Jun 10 04:02:24 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:24 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:02:24 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:02:24 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:24 volumio mpd[31202]: Jun 10 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:25 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:02:25 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:26 volumio volumio[31284]: cset: --> shielding system active with Jun 10 04:02:26 volumio volumio[31284]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running Jun 10 04:02:26 volumio volumio[31284]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:02:26 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:26 volumio volumio[31270]: info: ------------------------------------------- Jun 10 04:02:26 volumio volumio[31270]: info: ----- Volumio3 ---- Jun 10 04:02:26 volumio volumio[31270]: info: ------------------------------------------- Jun 10 04:02:26 volumio volumio[31270]: info: ----- System startup ---- Jun 10 04:02:26 volumio volumio[31270]: info: ------------------------------------------- Jun 10 04:02:27 volumio volumio[31270]: info: MYVOLUMIO Environment detected Jun 10 04:02:27 volumio volumio[31270]: info: Plugin folders cleanup Jun 10 04:02:27 volumio volumio[31270]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category audio_interface Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category miscellanea Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category music_service Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category plugins.json Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category system_controller Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category user_interface Jun 10 04:02:27 volumio volumio[31270]: info: Scanning into folder /data/plugins/ Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category music_service Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category system_controller Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category system_hardware Jun 10 04:02:27 volumio volumio[31270]: info: Scanning category user_interface Jun 10 04:02:27 volumio volumio[31270]: info: Plugin folders cleanup completed Jun 10 04:02:27 volumio volumio[31270]: info: ------------------------------------------- Jun 10 04:02:27 volumio volumio[31270]: info: ----- Core plugins startup ---- Jun 10 04:02:27 volumio volumio[31270]: info: ------------------------------------------- Jun 10 04:02:27 volumio volumio[31270]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:02:27 volumio volumio[31270]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:02:27 volumio volumio[31270]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:02:27 volumio volumio[31270]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:02:27 volumio volumio[31270]: info: Loading plugins from folder /data/plugins/ Jun 10 04:02:27 volumio volumio[31270]: info: Loading plugin "system"... Jun 10 04:02:27 volumio volumio[31270]: info: Loading plugin "appearance"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "network"... Jun 10 04:02:28 volumio volumio[31270]: info: Refreshing Cached IP Addresses Jun 10 04:02:28 volumio sudo[31313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:02:28 volumio sudo[31313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:28 volumio sudo[31313]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:28 volumio sudo[31315]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:02:28 volumio sudo[31315]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:28 volumio sudo[31315]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "services"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "alsa_controller"... Jun 10 04:02:28 volumio sudo[31324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:02:28 volumio sudo[31324]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:28 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "wizard"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "networkfs"... Jun 10 04:02:28 volumio volumio[31270]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:28 volumio volumio[31270]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:28 volumio volumio[31270]: info: Starting Udev Watcher for removable devices Jun 10 04:02:28 volumio volumio[31270]: info: Ignoring mount for partition: boot Jun 10 04:02:28 volumio volumio[31270]: info: Ignoring mount for partition: volumio Jun 10 04:02:28 volumio volumio[31270]: info: Ignoring mount for partition: volumio_data Jun 10 04:02:28 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:02:28 volumio volumio[31270]: info: Plugin upnp is not enabled Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "my_music"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "mpd"... Jun 10 04:02:28 volumio volumio[31270]: info: Plugin upnp_browser is not enabled Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "alarm-clock"... Jun 10 04:02:28 volumio volumio[31270]: info: Plugin airplay_emulation is not enabled Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "last_100"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "webradio"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "i2s_dacs"... Jun 10 04:02:28 volumio volumio[31270]: info: Loading plugin "volumiodiscovery"... Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** For more information see Jun 10 04:02:29 volumio node[31270]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:29 volumio volumio[31270]: *** WARNING *** For more information see Jun 10 04:02:29 volumio node[31270]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:29 volumio node[31270]: *** WARNING *** For more information see Jun 10 04:02:29 volumio node[31270]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:29 volumio node[31270]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:29 volumio node[31270]: *** WARNING *** For more information see Jun 10 04:02:29 volumio volumio[31270]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:02:29 volumio volumio[31270]: info: Discovery: Started advertising with name: Volumio Jun 10 04:02:29 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:29 volumio volumio[31270]: info: Loading plugin "soundcloud"... Jun 10 04:02:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:29 volumio volumio[31270]: info: Loading plugin "spop"... Jun 10 04:02:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:29 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:30 volumio volumio[31270]: info: Loading plugin "squeezelite_mc"... Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "outputs"... Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "albumart"... Jun 10 04:02:31 volumio volumio[31270]: info: Plugin example_plugin is not enabled Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "inputs"... Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "updater_comm"... Jun 10 04:02:31 volumio volumio[31270]: info: Plugin mpdemulation is not enabled Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "rest_api"... Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "websocket"... Jun 10 04:02:31 volumio volumio[31270]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:02:31 volumio volumio[31270]: info: Loading plugin "radio_paradise"... Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:31 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:31 volumio volumio-remote-updater[560]: [2025-06-10 04:02:31] [connect] Successful connection Jun 10 04:02:31 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:02:31 volumio volumio[31270]: Forking 3 albumart workers Jun 10 04:02:32 volumio volumio[31270]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:02:32 volumio volumio[31270]: info: [1749520952054] [RadioParadise] API delay: 3 Jun 10 04:02:32 volumio volumio[31270]: info: Loading plugin "backup_restore"... Jun 10 04:02:32 volumio volumio[31270]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:02:32 volumio volumio[31270]: info: Loading plugin "music_services_shield"... Jun 10 04:02:33 volumio volumio[31270]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:02:33 volumio volumio[31270]: info: Loading plugin "Systeminfo"... Jun 10 04:02:33 volumio volumio[31270]: Starting albumart workers Jun 10 04:02:33 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 10 04:02:33 volumio volumio[31270]: Starting albumart workers Jun 10 04:02:34 volumio sudo[31324]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:34 volumio volumio[31270]: info: Loading i18n strings for locale en Jun 10 04:02:34 volumio volumio[31270]: Updating browse sources language Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:34 volumio volumio[31270]: Starting albumart workers Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::initPlayerControls Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:34 volumio volumio[31270]: Express server listening on port 3000 Jun 10 04:02:34 volumio volumio[31270]: [Metrics] WebUI: 8s 830.43ms Jun 10 04:02:34 volumio volumio[31270]: info: CoreStateMachine::resetVolumioState Jun 10 04:02:34 volumio volumio[31270]: info: CoreStateMachine::getcurrentVolume Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:34 volumio volumio[31270]: info: CoreStateMachine::pushState Jun 10 04:02:34 volumio volumio[31270]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:34 volumio volumio[31270]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:34 volumio volumio[31270]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:34 volumio volumio[31270]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:35 volumio volumio[31270]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:02:35 volumio volumio-remote-updater[560]: [2025-06-10 04:02:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749520949 101 Jun 10 04:02:35 volumio volumio[31270]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:35 volumio volumio[31270]: info: Reloading queue from file Jun 10 04:02:35 volumio volumio[31270]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:02:35 volumio volumio[31270]: info: CoreStateMachine::pushState Jun 10 04:02:35 volumio volumio[31270]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:35 volumio volumio[31270]: info: CoreStateMachine::setRandom false Jun 10 04:02:35 volumio volumio[31270]: info: CoreStateMachine::pushState Jun 10 04:02:35 volumio volumio[31270]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:35 volumio volumio[31270]: info: Setting Device type: Raspberry PI Jun 10 04:02:35 volumio volumio[31270]: info: Completed loading Core Plugins Jun 10 04:02:35 volumio volumio[31270]: info: Preparing to generate the ALSA configuration file Jun 10 04:02:35 volumio volumio[31270]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:02:35 volumio volumio[31270]: info: Output device has changed, restarting MPD Jun 10 04:02:35 volumio volumio[31270]: info: ___________ START PLUGINS ___________ Jun 10 04:02:35 volumio volumio[31270]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:02:35 volumio volumio[31270]: info: Creating MPD Configuration file Jun 10 04:02:35 volumio sudo[31431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:35 volumio sudo[31431]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio sudo[31431]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:35 volumio sudo[31437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:35 volumio sudo[31437]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:35 volumio volumio[31270]: info: [1749520955373] CoreMusicLibrary::Adding element Last_100 Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:35 volumio volumio[31270]: info: [1749520955382] CoreMusicLibrary::Adding element Webradio Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:35 volumio sudo[31440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:35 volumio sudo[31440]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio sudo[31443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:35 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:02:35 volumio volumio[31270]: info: Initializing BBC Radios Jun 10 04:02:35 volumio sudo[31440]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:35 volumio sudo[31443]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:35 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:35 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:35 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:35 volumio volumio[31270]: info: [1749520955515] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:35 volumio volumio[31270]: Cannot find translation for source SoundCloud Jun 10 04:02:35 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:02:35 volumio volumio[31270]: info: Creating Spotify config file Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:35 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:02:35 volumio volumio[31270]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:02:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:35 volumio volumio[31270]: info: [1749520955592] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:02:35 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:35 volumio volumio[31270]: Cannot find translation for source SoundCloud Jun 10 04:02:35 volumio volumio[31270]: Cannot find translation for source Radio Paradise Jun 10 04:02:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:35 volumio sudo[31456]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:02:35 volumio sudo[31456]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio sudo[31456]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:35 volumio volumio[31270]: info: Volumio Calling Home Jun 10 04:02:35 volumio sudo[31493]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:02:35 volumio sudo[31493]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:35 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:35 volumio volumio[31270]: info: [squeezelite_mc] Proxy server started on port 34243 Jun 10 04:02:36 volumio volumio[31270]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:36 volumio volumio[31270]: info: CoreStateMachine::pushState Jun 10 04:02:36 volumio volumio[31270]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:36 volumio volumio[31270]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:36 volumio volumio[31270]: info: [squeezelite_mc] Server discovery started Jun 10 04:02:36 volumio volumio[31270]: info: [squeezelite_mc] Player finder started Jun 10 04:02:36 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:36 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:36 volumio volumio[31270]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:36 volumio volumio[31270]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:02:36 volumio volumio[31270]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:36 volumio volumio[31270]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:02:36 volumio volumio[31270]: at doSend (dgram.js:692:16) Jun 10 04:02:36 volumio volumio[31270]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:02:36 volumio volumio[31270]: at afterDns (dgram.js:638:5) Jun 10 04:02:36 volumio volumio[31270]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:02:36 volumio volumio[31270]: errno: -101, Jun 10 04:02:36 volumio volumio[31270]: code: 'ENETUNREACH', Jun 10 04:02:36 volumio volumio[31270]: syscall: 'send', Jun 10 04:02:36 volumio volumio[31270]: address: '255.255.255.255', Jun 10 04:02:36 volumio volumio[31270]: port: 3483 Jun 10 04:02:36 volumio volumio[31270]: } Jun 10 04:02:36 volumio volumio[31270]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:37 volumio sudo[31493]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:37 volumio sudo[31523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:01 Jun 10 04:02:37 volumio sudo[31523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:37 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:37 volumio sudo[31523]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:38 volumio volumio-remote-updater[560]: [2025-06-10 04:02:38] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 10 04:02:38 volumio volumio-remote-updater[560]: [2025-06-10 04:02:38] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 10 04:02:38 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:02:38 volumio sudo[31443]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:38 volumio sudo[31437]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:38 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:02:38 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:38 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:38 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:02:38 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 42. Jun 10 04:02:38 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:38 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:02:38 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:02:38 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:38 volumio mpd[31481]: Jun 10 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:02:39 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:02:39 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:40 volumio volumio[31551]: cset: --> shielding system active with Jun 10 04:02:40 volumio volumio[31551]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running Jun 10 04:02:40 volumio volumio[31551]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:02:40 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:40 volumio volumio[31537]: info: ------------------------------------------- Jun 10 04:02:40 volumio volumio[31537]: info: ----- Volumio3 ---- Jun 10 04:02:40 volumio volumio[31537]: info: ------------------------------------------- Jun 10 04:02:40 volumio volumio[31537]: info: ----- System startup ---- Jun 10 04:02:40 volumio volumio[31537]: info: ------------------------------------------- Jun 10 04:02:41 volumio volumio[31537]: info: MYVOLUMIO Environment detected Jun 10 04:02:41 volumio volumio[31537]: info: Plugin folders cleanup Jun 10 04:02:41 volumio volumio[31537]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category audio_interface Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category miscellanea Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category music_service Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category plugins.json Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category system_controller Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category user_interface Jun 10 04:02:41 volumio volumio[31537]: info: Scanning into folder /data/plugins/ Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category music_service Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category system_controller Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category system_hardware Jun 10 04:02:41 volumio volumio[31537]: info: Scanning category user_interface Jun 10 04:02:41 volumio volumio[31537]: info: Plugin folders cleanup completed Jun 10 04:02:41 volumio volumio[31537]: info: ------------------------------------------- Jun 10 04:02:41 volumio volumio[31537]: info: ----- Core plugins startup ---- Jun 10 04:02:41 volumio volumio[31537]: info: ------------------------------------------- Jun 10 04:02:41 volumio volumio[31537]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:02:41 volumio volumio[31537]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:02:41 volumio volumio[31537]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:02:41 volumio volumio[31537]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:02:41 volumio volumio[31537]: info: Loading plugins from folder /data/plugins/ Jun 10 04:02:41 volumio volumio[31537]: info: Loading plugin "system"... Jun 10 04:02:41 volumio volumio[31537]: info: Loading plugin "appearance"... Jun 10 04:02:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "network"... Jun 10 04:02:42 volumio volumio[31537]: info: Refreshing Cached IP Addresses Jun 10 04:02:42 volumio kernel: hwmon hwmon1: Voltage normalised Jun 10 04:02:42 volumio sudo[31581]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:02:42 volumio sudo[31581]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:42 volumio sudo[31581]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:42 volumio sudo[31583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:02:42 volumio sudo[31583]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "services"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "alsa_controller"... Jun 10 04:02:42 volumio sudo[31583]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:42 volumio sudo[31594]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:02:42 volumio sudo[31594]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:42 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "wizard"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "networkfs"... Jun 10 04:02:42 volumio volumio[31537]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:42 volumio volumio[31537]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:42 volumio volumio[31537]: info: Starting Udev Watcher for removable devices Jun 10 04:02:42 volumio volumio[31537]: info: Ignoring mount for partition: boot Jun 10 04:02:42 volumio volumio[31537]: info: Ignoring mount for partition: volumio Jun 10 04:02:42 volumio volumio[31537]: info: Ignoring mount for partition: volumio_data Jun 10 04:02:42 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:02:42 volumio volumio[31537]: info: Plugin upnp is not enabled Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "my_music"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "mpd"... Jun 10 04:02:42 volumio volumio[31537]: info: Plugin upnp_browser is not enabled Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "alarm-clock"... Jun 10 04:02:42 volumio volumio[31537]: info: Plugin airplay_emulation is not enabled Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "last_100"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "webradio"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "i2s_dacs"... Jun 10 04:02:42 volumio volumio[31537]: info: Loading plugin "volumiodiscovery"... Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** For more information see Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:42 volumio node[31537]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:42 volumio volumio[31537]: *** WARNING *** For more information see Jun 10 04:02:42 volumio node[31537]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:42 volumio node[31537]: *** WARNING *** For more information see Jun 10 04:02:42 volumio node[31537]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:42 volumio node[31537]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:42 volumio node[31537]: *** WARNING *** For more information see Jun 10 04:02:42 volumio volumio[31537]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:02:43 volumio volumio[31537]: info: Discovery: Started advertising with name: Volumio Jun 10 04:02:43 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:43 volumio volumio[31537]: info: Loading plugin "soundcloud"... Jun 10 04:02:43 volumio volumio[31537]: info: Loading plugin "spop"... Jun 10 04:02:43 volumio volumio-remote-updater[560]: [2025-06-10 04:02:43] [connect] Successful connection Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:43 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:43 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:02:44 volumio volumio[31537]: info: Loading plugin "squeezelite_mc"... Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "outputs"... Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "albumart"... Jun 10 04:02:45 volumio volumio[31537]: info: Plugin example_plugin is not enabled Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "inputs"... Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "updater_comm"... Jun 10 04:02:45 volumio volumio[31537]: info: Plugin mpdemulation is not enabled Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "rest_api"... Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "websocket"... Jun 10 04:02:45 volumio volumio[31537]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "radio_paradise"... Jun 10 04:02:45 volumio volumio[31537]: Forking 3 albumart workers Jun 10 04:02:45 volumio volumio[31537]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:02:45 volumio volumio[31537]: info: [1749520965796] [RadioParadise] API delay: 3 Jun 10 04:02:45 volumio volumio[31537]: info: Loading plugin "backup_restore"... Jun 10 04:02:46 volumio volumio[31537]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:02:46 volumio volumio[31537]: info: Loading plugin "music_services_shield"... Jun 10 04:02:46 volumio volumio[31537]: Starting albumart workers Jun 10 04:02:46 volumio volumio[31537]: Starting albumart workers Jun 10 04:02:46 volumio sudo[31594]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:46 volumio volumio[31537]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:02:46 volumio volumio[31537]: info: Loading plugin "Systeminfo"... Jun 10 04:02:47 volumio volumio[31537]: info: Loading i18n strings for locale en Jun 10 04:02:47 volumio volumio[31537]: Updating browse sources language Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:47 volumio volumio[31537]: Starting albumart workers Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::initPlayerControls Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: Express server listening on port 3000 Jun 10 04:02:47 volumio volumio[31537]: [Metrics] WebUI: 7s 434.52ms Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::resetVolumioState Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::getcurrentVolume Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::pushState Jun 10 04:02:47 volumio volumio[31537]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:47 volumio volumio[31537]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:47 volumio volumio[31537]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:02:47 volumio volumio[31537]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:02:47 volumio volumio-remote-updater[560]: [2025-06-10 04:02:47] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749520963 101 Jun 10 04:02:47 volumio volumio[31537]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: info: Reloading queue from file Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::pushState Jun 10 04:02:47 volumio volumio[31537]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::setRandom false Jun 10 04:02:47 volumio volumio[31537]: info: CoreStateMachine::pushState Jun 10 04:02:47 volumio volumio[31537]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:47 volumio volumio[31537]: info: Setting Device type: Raspberry PI Jun 10 04:02:47 volumio volumio[31537]: info: Completed loading Core Plugins Jun 10 04:02:47 volumio volumio[31537]: info: Preparing to generate the ALSA configuration file Jun 10 04:02:47 volumio volumio[31537]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:02:47 volumio volumio[31537]: info: Output device has changed, restarting MPD Jun 10 04:02:47 volumio sudo[31660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:47 volumio sudo[31660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:47 volumio volumio[31537]: info: ___________ START PLUGINS ___________ Jun 10 04:02:47 volumio sudo[31660]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:47 volumio volumio[31537]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:02:47 volumio volumio[31537]: info: Creating MPD Configuration file Jun 10 04:02:47 volumio sudo[31668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:47 volumio sudo[31668]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:47 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:02:47 volumio sudo[31674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:02:47 volumio sudo[31674]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:47 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:47 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:47 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:47 volumio sudo[31674]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:47 volumio volumio[31537]: info: [1749520967774] CoreMusicLibrary::Adding element Last_100 Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:47 volumio volumio[31537]: info: [1749520967793] CoreMusicLibrary::Adding element Webradio Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:47 volumio sudo[31677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:02:47 volumio sudo[31677]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:47 volumio volumio[31537]: info: Initializing BBC Radios Jun 10 04:02:47 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:02:47 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:02:47 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:47 volumio volumio[31537]: info: [1749520967910] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:47 volumio volumio[31537]: Cannot find translation for source SoundCloud Jun 10 04:02:47 volumio volumio[31537]: info: Creating Spotify config file Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:47 volumio volumio[31537]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:02:47 volumio volumio[31537]: info: [1749520967954] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:02:47 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:02:47 volumio volumio[31537]: Cannot find translation for source SoundCloud Jun 10 04:02:47 volumio volumio[31537]: Cannot find translation for source Radio Paradise Jun 10 04:02:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:48 volumio sudo[31682]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:02:48 volumio sudo[31682]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:48 volumio sudo[31682]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:48 volumio volumio[31537]: info: Volumio Calling Home Jun 10 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:48 volumio sudo[31718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:02:48 volumio sudo[31718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:48 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:48 volumio volumio[31537]: info: [squeezelite_mc] Proxy server started on port 45533 Jun 10 04:02:48 volumio volumio[31537]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:02:48 volumio volumio[31537]: info: CoreStateMachine::pushState Jun 10 04:02:48 volumio volumio[31537]: info: CorePlayQueue::getTrack 0 Jun 10 04:02:48 volumio volumio[31537]: info: CoreCommandRouter::volumioPushState Jun 10 04:02:48 volumio volumio[31537]: info: [squeezelite_mc] Server discovery started Jun 10 04:02:48 volumio volumio[31537]: info: [squeezelite_mc] Player finder started Jun 10 04:02:48 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:48 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:02:48 volumio volumio[31537]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:02:48 volumio volumio[31537]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:02:48 volumio volumio[31537]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:48 volumio volumio[31537]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:02:48 volumio volumio[31537]: at doSend (dgram.js:692:16) Jun 10 04:02:48 volumio volumio[31537]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:02:48 volumio volumio[31537]: at afterDns (dgram.js:638:5) Jun 10 04:02:48 volumio volumio[31537]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:02:48 volumio volumio[31537]: errno: -101, Jun 10 04:02:48 volumio volumio[31537]: code: 'ENETUNREACH', Jun 10 04:02:48 volumio volumio[31537]: syscall: 'send', Jun 10 04:02:48 volumio volumio[31537]: address: '255.255.255.255', Jun 10 04:02:48 volumio volumio[31537]: port: 3483 Jun 10 04:02:48 volumio volumio[31537]: } Jun 10 04:02:48 volumio volumio[31537]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:02:48 volumio sudo[31718]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:49 volumio sudo[31748]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:01 Jun 10 04:02:49 volumio sudo[31748]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:49 volumio sudo[31748]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:49 volumio volumio-remote-updater[560]: [2025-06-10 04:02:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jun 10 04:02:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:02:49 volumio volumio-remote-updater[560]: [2025-06-10 04:02:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jun 10 04:02:49 volumio sudo[31677]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:49 volumio sudo[31668]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:02:49 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:49 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:49 volumio mpd[31700]: Jun 10 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:02:49 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:02:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 43. Jun 10 04:02:49 volumio systemd[1]: Started dynamicswap service. Jun 10 04:02:49 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:02:49 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:02:49 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:02:50 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:50 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:50 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:02:50 volumio volumio[31776]: cset: --> shielding system active with Jun 10 04:02:50 volumio volumio[31776]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running Jun 10 04:02:50 volumio volumio[31776]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:02:50 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:02:52 volumio volumio[31762]: info: ------------------------------------------- Jun 10 04:02:52 volumio volumio[31762]: info: ----- Volumio3 ---- Jun 10 04:02:52 volumio volumio[31762]: info: ------------------------------------------- Jun 10 04:02:52 volumio volumio[31762]: info: ----- System startup ---- Jun 10 04:02:52 volumio volumio[31762]: info: ------------------------------------------- Jun 10 04:02:52 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 10 04:02:53 volumio volumio[31762]: info: MYVOLUMIO Environment detected Jun 10 04:02:53 volumio volumio[31762]: info: Plugin folders cleanup Jun 10 04:02:53 volumio volumio[31762]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category audio_interface Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category miscellanea Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category music_service Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category plugins.json Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category system_controller Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category user_interface Jun 10 04:02:53 volumio volumio[31762]: info: Scanning into folder /data/plugins/ Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category music_service Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category system_controller Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category system_hardware Jun 10 04:02:53 volumio volumio[31762]: info: Scanning category user_interface Jun 10 04:02:53 volumio volumio[31762]: info: Plugin folders cleanup completed Jun 10 04:02:53 volumio volumio[31762]: info: ------------------------------------------- Jun 10 04:02:53 volumio volumio[31762]: info: ----- Core plugins startup ---- Jun 10 04:02:53 volumio volumio[31762]: info: ------------------------------------------- Jun 10 04:02:53 volumio volumio[31762]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:02:53 volumio volumio[31762]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:02:53 volumio volumio[31762]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:02:53 volumio volumio[31762]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:02:53 volumio volumio[31762]: info: Loading plugins from folder /data/plugins/ Jun 10 04:02:53 volumio volumio[31762]: info: Loading plugin "system"... Jun 10 04:02:53 volumio volumio[31762]: info: Loading plugin "appearance"... Jun 10 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:02:54 volumio volumio-remote-updater[560]: [2025-06-10 04:02:54] [connect] Successful connection Jun 10 04:02:54 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:02:54 volumio volumio[31762]: info: Loading plugin "network"... Jun 10 04:02:54 volumio volumio[31762]: info: Refreshing Cached IP Addresses Jun 10 04:02:54 volumio sudo[31809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:02:54 volumio sudo[31809]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:54 volumio sudo[31811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:02:54 volumio sudo[31811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:54 volumio sudo[31809]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:54 volumio sudo[31811]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:54 volumio volumio[31762]: info: Loading plugin "services"... Jun 10 04:02:54 volumio volumio[31762]: info: Loading plugin "alsa_controller"... Jun 10 04:02:54 volumio sudo[31819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:02:54 volumio sudo[31819]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:02:54 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:02:54 volumio volumio[31762]: info: Loading plugin "wizard"... Jun 10 04:02:54 volumio volumio[31762]: info: Loading plugin "networkfs"... Jun 10 04:02:55 volumio volumio[31762]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:55 volumio volumio[31762]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:02:55 volumio volumio[31762]: info: Starting Udev Watcher for removable devices Jun 10 04:02:55 volumio volumio[31762]: info: Ignoring mount for partition: boot Jun 10 04:02:55 volumio volumio[31762]: info: Ignoring mount for partition: volumio Jun 10 04:02:55 volumio volumio[31762]: info: Ignoring mount for partition: volumio_data Jun 10 04:02:55 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:02:55 volumio volumio[31762]: info: Plugin upnp is not enabled Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "my_music"... Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "mpd"... Jun 10 04:02:55 volumio volumio[31762]: info: Plugin upnp_browser is not enabled Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "alarm-clock"... Jun 10 04:02:55 volumio volumio[31762]: info: Plugin airplay_emulation is not enabled Jun 10 04:02:55 volumio autossh[30040]: starting ssh (count 29) Jun 10 04:02:55 volumio autossh[30040]: ssh child pid is 31833 Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "last_100"... Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "webradio"... Jun 10 04:02:55 volumio volumiossh-tunnel[30035]: ssh: Could not resolve hostname eu7.myvolumio.org: Device or resource busy Jun 10 04:02:55 volumio autossh[30040]: ssh exited with error status 255; restarting ssh Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "i2s_dacs"... Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "volumiodiscovery"... Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** For more information see Jun 10 04:02:55 volumio node[31762]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:55 volumio volumio[31762]: *** WARNING *** For more information see Jun 10 04:02:55 volumio node[31762]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:55 volumio node[31762]: *** WARNING *** For more information see Jun 10 04:02:55 volumio node[31762]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:02:55 volumio node[31762]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:02:55 volumio node[31762]: *** WARNING *** For more information see Jun 10 04:02:55 volumio volumio[31762]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:02:55 volumio volumio[31762]: info: Discovery: Started advertising with name: Volumio Jun 10 04:02:55 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:02:55 volumio volumio[31762]: info: Loading plugin "soundcloud"... Jun 10 04:02:56 volumio volumio[31762]: info: Loading plugin "spop"... Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:02:56 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:02:56 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:02:56 volumio kernel: hwmon hwmon1: Voltage normalised Jun 10 04:02:57 volumio volumio[31762]: info: Loading plugin "squeezelite_mc"... Jun 10 04:02:57 volumio volumio[31762]: info: Loading plugin "outputs"... Jun 10 04:02:57 volumio volumio[31762]: info: Loading plugin "albumart"... Jun 10 04:02:57 volumio volumio[31762]: info: Plugin example_plugin is not enabled Jun 10 04:02:57 volumio volumio[31762]: info: Loading plugin "inputs"... Jun 10 04:02:57 volumio volumio[31762]: info: Loading plugin "updater_comm"... Jun 10 04:02:58 volumio volumio[31762]: info: Plugin mpdemulation is not enabled Jun 10 04:02:58 volumio volumio[31762]: info: Loading plugin "rest_api"... Jun 10 04:02:58 volumio volumio[31762]: info: Loading plugin "websocket"... Jun 10 04:02:58 volumio volumio[31762]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:02:58 volumio volumio[31762]: info: Loading plugin "radio_paradise"... Jun 10 04:02:58 volumio volumio[31762]: Forking 3 albumart workers Jun 10 04:02:58 volumio volumio[31762]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:02:58 volumio volumio[31762]: info: [1749520978573] [RadioParadise] API delay: 3 Jun 10 04:02:58 volumio volumio[31762]: info: Loading plugin "backup_restore"... Jun 10 04:02:58 volumio sudo[31819]: pam_unix(sudo:session): session closed for user root Jun 10 04:02:58 volumio volumio[31762]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:02:58 volumio volumio[31762]: info: Loading plugin "music_services_shield"... Jun 10 04:02:59 volumio volumio[31762]: Starting albumart workers Jun 10 04:02:59 volumio volumio[31762]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:02:59 volumio volumio[31762]: info: Loading plugin "Systeminfo"... Jun 10 04:02:59 volumio volumio[31762]: Starting albumart workers Jun 10 04:02:59 volumio volumio[31762]: Starting albumart workers Jun 10 04:02:59 volumio volumio[31762]: info: Loading i18n strings for locale en Jun 10 04:02:59 volumio volumio[31762]: Updating browse sources language Jun 10 04:02:59 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::initPlayerControls Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: Express server listening on port 3000 Jun 10 04:03:00 volumio volumio[31762]: [Metrics] WebUI: 8s 865.41ms Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::resetVolumioState Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::getcurrentVolume Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::pushState Jun 10 04:03:00 volumio volumio[31762]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:00 volumio volumio[31762]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:00 volumio volumio[31762]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:00 volumio volumio[31762]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: Reloading queue from file Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::pushState Jun 10 04:03:00 volumio volumio[31762]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::setRandom false Jun 10 04:03:00 volumio volumio[31762]: info: CoreStateMachine::pushState Jun 10 04:03:00 volumio volumio[31762]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:00 volumio volumio[31762]: info: Setting Device type: Raspberry PI Jun 10 04:03:00 volumio volumio[31762]: info: Completed loading Core Plugins Jun 10 04:03:00 volumio volumio[31762]: info: Preparing to generate the ALSA configuration file Jun 10 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:03:00 volumio volumio[31762]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:03:00 volumio volumio[31762]: info: Output device has changed, restarting MPD Jun 10 04:03:00 volumio sudo[31898]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:00 volumio sudo[31898]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:00 volumio volumio[31762]: info: ___________ START PLUGINS ___________ Jun 10 04:03:00 volumio sudo[31898]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:00 volumio volumio[31762]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:03:00 volumio volumio[31762]: info: Creating MPD Configuration file Jun 10 04:03:00 volumio sudo[31900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:00 volumio sudo[31900]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:00 volumio sudo[31904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:00 volumio sudo[31904]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:00 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:03:00 volumio sudo[31904]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:00 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:03:00 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:03:00 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:00 volumio volumio[31762]: info: [1749520980537] CoreMusicLibrary::Adding element Last_100 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:00 volumio volumio[31762]: info: [1749520980542] CoreMusicLibrary::Adding element Webradio Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:00 volumio sudo[31907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:00 volumio sudo[31907]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:00 volumio volumio[31762]: info: Initializing BBC Radios Jun 10 04:03:00 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:03:00 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:03:00 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:00 volumio volumio[31762]: info: [1749520980649] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:00 volumio volumio[31762]: Cannot find translation for source SoundCloud Jun 10 04:03:00 volumio volumio[31762]: info: Creating Spotify config file Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:00 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:03:00 volumio volumio[31762]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:00 volumio volumio[31762]: info: [1749520980693] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:03:00 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:00 volumio volumio[31762]: Cannot find translation for source SoundCloud Jun 10 04:03:00 volumio volumio[31762]: Cannot find translation for source Radio Paradise Jun 10 04:03:00 volumio sudo[31915]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:03:00 volumio sudo[31915]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:00 volumio sudo[31915]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:00 volumio volumio[31762]: info: Volumio Calling Home Jun 10 04:03:00 volumio sudo[31948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:03:00 volumio sudo[31948]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:01 volumio volumio[31762]: info: [squeezelite_mc] Proxy server started on port 35991 Jun 10 04:03:01 volumio volumio[31762]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:01 volumio volumio[31762]: info: CoreStateMachine::pushState Jun 10 04:03:01 volumio volumio[31762]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:01 volumio volumio[31762]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:01 volumio volumio[31762]: info: [squeezelite_mc] Server discovery started Jun 10 04:03:01 volumio volumio[31762]: info: [squeezelite_mc] Player finder started Jun 10 04:03:01 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:01 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:01 volumio volumio[31762]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:01 volumio volumio[31762]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:03:01 volumio volumio[31762]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:01 volumio volumio[31762]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:03:01 volumio volumio[31762]: at doSend (dgram.js:692:16) Jun 10 04:03:01 volumio volumio[31762]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:03:01 volumio volumio[31762]: at afterDns (dgram.js:638:5) Jun 10 04:03:01 volumio volumio[31762]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:03:01 volumio volumio[31762]: errno: -101, Jun 10 04:03:01 volumio volumio[31762]: code: 'ENETUNREACH', Jun 10 04:03:01 volumio volumio[31762]: syscall: 'send', Jun 10 04:03:01 volumio volumio[31762]: address: '255.255.255.255', Jun 10 04:03:01 volumio volumio[31762]: port: 3483 Jun 10 04:03:01 volumio volumio[31762]: } Jun 10 04:03:01 volumio volumio[31762]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:01 volumio sudo[31948]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:01 volumio sudo[31978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:02 Jun 10 04:03:01 volumio sudo[31978]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:01 volumio sudo[31978]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:03:02 volumio sudo[31900]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:02 volumio sudo[31907]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:03:02 volumio systemd[1]: Started dynamicswap service. Jun 10 04:03:02 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:03:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:03:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 44. Jun 10 04:03:02 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:03:02 volumio mpd[31937]: Jun 10 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:03:02 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:03:02 volumio systemd[1]: Started dynamicswap service. Jun 10 04:03:02 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:03:02 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:03:02 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:03:03 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:03:04 volumio volumio[32006]: cset: --> shielding system active with Jun 10 04:03:04 volumio volumio[32006]: cset: "system" cpuset of CPUSPEC(1-3) with 65 tasks running Jun 10 04:03:04 volumio volumio[32006]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:03:04 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:03:04 volumio volumio[31991]: info: ------------------------------------------- Jun 10 04:03:04 volumio volumio[31991]: info: ----- Volumio3 ---- Jun 10 04:03:04 volumio volumio[31991]: info: ------------------------------------------- Jun 10 04:03:04 volumio volumio[31991]: info: ----- System startup ---- Jun 10 04:03:04 volumio volumio[31991]: info: ------------------------------------------- Jun 10 04:03:05 volumio kernel: hwmon hwmon1: Undervoltage detected! Jun 10 04:03:06 volumio volumio[31991]: info: MYVOLUMIO Environment detected Jun 10 04:03:06 volumio volumio[31991]: info: Plugin folders cleanup Jun 10 04:03:06 volumio volumio[31991]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category audio_interface Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category miscellanea Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category music_service Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category plugins.json Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category system_controller Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category user_interface Jun 10 04:03:06 volumio volumio[31991]: info: Scanning into folder /data/plugins/ Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category music_service Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category system_controller Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category system_hardware Jun 10 04:03:06 volumio volumio[31991]: info: Scanning category user_interface Jun 10 04:03:06 volumio volumio[31991]: info: Plugin folders cleanup completed Jun 10 04:03:06 volumio volumio[31991]: info: ------------------------------------------- Jun 10 04:03:06 volumio volumio[31991]: info: ----- Core plugins startup ---- Jun 10 04:03:06 volumio volumio[31991]: info: ------------------------------------------- Jun 10 04:03:06 volumio volumio[31991]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:03:06 volumio volumio[31991]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:03:06 volumio volumio[31991]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:03:06 volumio volumio[31991]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:03:06 volumio volumio[31991]: info: Loading plugins from folder /data/plugins/ Jun 10 04:03:06 volumio volumio[31991]: info: Loading plugin "system"... Jun 10 04:03:06 volumio volumio[31991]: info: Loading plugin "appearance"... Jun 10 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:03:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:03:06 volumio volumio-remote-updater[560]: [2025-06-10 04:03:06] [connect] Successful connection Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "network"... Jun 10 04:03:07 volumio volumio[31991]: info: Refreshing Cached IP Addresses Jun 10 04:03:07 volumio sudo[32036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:03:07 volumio sudo[32036]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:07 volumio sudo[32036]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:07 volumio sudo[32038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:03:07 volumio sudo[32038]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:07 volumio sudo[32038]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "services"... Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "alsa_controller"... Jun 10 04:03:07 volumio sudo[32047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:03:07 volumio sudo[32047]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:07 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "wizard"... Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "networkfs"... Jun 10 04:03:07 volumio volumio[31991]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:03:07 volumio volumio[31991]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:03:07 volumio volumio[31991]: info: Starting Udev Watcher for removable devices Jun 10 04:03:07 volumio volumio[31991]: info: Ignoring mount for partition: boot Jun 10 04:03:07 volumio volumio[31991]: info: Ignoring mount for partition: volumio Jun 10 04:03:07 volumio volumio[31991]: info: Ignoring mount for partition: volumio_data Jun 10 04:03:07 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:03:07 volumio volumio[31991]: info: Plugin upnp is not enabled Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "my_music"... Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "mpd"... Jun 10 04:03:07 volumio volumio[31991]: info: Plugin upnp_browser is not enabled Jun 10 04:03:07 volumio volumio[31991]: info: Loading plugin "alarm-clock"... Jun 10 04:03:08 volumio volumio[31991]: info: Plugin airplay_emulation is not enabled Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "last_100"... Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "webradio"... Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "i2s_dacs"... Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "volumiodiscovery"... Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** For more information see Jun 10 04:03:08 volumio node[31991]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:08 volumio volumio[31991]: *** WARNING *** For more information see Jun 10 04:03:08 volumio node[31991]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:08 volumio node[31991]: *** WARNING *** For more information see Jun 10 04:03:08 volumio node[31991]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:08 volumio node[31991]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:08 volumio node[31991]: *** WARNING *** For more information see Jun 10 04:03:08 volumio volumio[31991]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:03:08 volumio volumio[31991]: info: Discovery: Started advertising with name: Volumio Jun 10 04:03:08 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "soundcloud"... Jun 10 04:03:08 volumio volumio[31991]: info: Loading plugin "spop"... Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:03:08 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:03:08 volumio wpa_supplicant[862]: wlan0: Failed to initiate sched scan Jun 10 04:03:10 volumio volumio[31991]: info: Loading plugin "squeezelite_mc"... Jun 10 04:03:11 volumio sudo[32047]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:11 volumio volumio[31991]: info: Loading plugin "outputs"... Jun 10 04:03:11 volumio volumio[31991]: info: Loading plugin "albumart"... Jun 10 04:03:11 volumio volumio[31991]: info: Plugin example_plugin is not enabled Jun 10 04:03:11 volumio volumio[31991]: info: Loading plugin "inputs"... Jun 10 04:03:11 volumio volumio[31991]: info: Loading plugin "updater_comm"... Jun 10 04:03:12 volumio volumio[31991]: info: Plugin mpdemulation is not enabled Jun 10 04:03:12 volumio volumio[31991]: info: Loading plugin "rest_api"... Jun 10 04:03:12 volumio volumio[31991]: info: Loading plugin "websocket"... Jun 10 04:03:12 volumio volumio[31991]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:03:12 volumio volumio[31991]: info: Loading plugin "radio_paradise"... Jun 10 04:03:12 volumio volumio[31991]: Forking 3 albumart workers Jun 10 04:03:12 volumio volumio[31991]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:03:12 volumio volumio[31991]: info: [1749520992472] [RadioParadise] API delay: 3 Jun 10 04:03:12 volumio volumio[31991]: info: Loading plugin "backup_restore"... Jun 10 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:03:12 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:03:12 volumio volumio[31991]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:03:12 volumio volumio[31991]: info: Loading plugin "music_services_shield"... Jun 10 04:03:13 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:03:13 volumio volumio[31991]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:03:13 volumio volumio[31991]: info: Loading plugin "Systeminfo"... Jun 10 04:03:13 volumio volumio[31991]: Starting albumart workers Jun 10 04:03:13 volumio volumio[31991]: Starting albumart workers Jun 10 04:03:13 volumio volumio[31991]: Starting albumart workers Jun 10 04:03:13 volumio volumio[31991]: info: Loading i18n strings for locale en Jun 10 04:03:13 volumio volumio[31991]: Updating browse sources language Jun 10 04:03:13 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::initPlayerControls Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: Express server listening on port 3000 Jun 10 04:03:14 volumio volumio[31991]: [Metrics] WebUI: 10s 307.78ms Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::resetVolumioState Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::getcurrentVolume Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::pushState Jun 10 04:03:14 volumio volumio[31991]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:14 volumio volumio[31991]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:14 volumio volumio[31991]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:14 volumio volumio[31991]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: Reloading queue from file Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::pushState Jun 10 04:03:14 volumio volumio[31991]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::setRandom false Jun 10 04:03:14 volumio volumio[31991]: info: CoreStateMachine::pushState Jun 10 04:03:14 volumio volumio[31991]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:14 volumio volumio[31991]: info: Setting Device type: Raspberry PI Jun 10 04:03:14 volumio volumio[31991]: info: Completed loading Core Plugins Jun 10 04:03:14 volumio volumio[31991]: info: Preparing to generate the ALSA configuration file Jun 10 04:03:14 volumio volumio[31991]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:03:14 volumio volumio[31991]: info: Output device has changed, restarting MPD Jun 10 04:03:14 volumio sudo[32126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:14 volumio sudo[32126]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:14 volumio volumio[31991]: info: ___________ START PLUGINS ___________ Jun 10 04:03:14 volumio sudo[32126]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:14 volumio volumio[31991]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:03:14 volumio volumio[31991]: info: Creating MPD Configuration file Jun 10 04:03:14 volumio sudo[32128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:14 volumio sudo[32128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:14 volumio sudo[32132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:14 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:03:14 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:03:14 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:03:14 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:03:14 volumio sudo[32132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:14 volumio volumio[31991]: info: [1749520994595] CoreMusicLibrary::Adding element Last_100 Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:14 volumio sudo[32132]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:14 volumio volumio[31991]: info: [1749520994600] CoreMusicLibrary::Adding element Webradio Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:14 volumio volumio[31991]: info: Initializing BBC Radios Jun 10 04:03:14 volumio sudo[32135]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:14 volumio sudo[32135]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:14 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:03:14 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:14 volumio volumio[31991]: info: [1749520994703] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:14 volumio volumio[31991]: Cannot find translation for source SoundCloud Jun 10 04:03:14 volumio volumio[31991]: info: Creating Spotify config file Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:14 volumio volumio[31991]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:14 volumio volumio[31991]: info: [1749520994743] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:03:14 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:14 volumio volumio[31991]: Cannot find translation for source SoundCloud Jun 10 04:03:14 volumio volumio[31991]: Cannot find translation for source Radio Paradise Jun 10 04:03:14 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:03:14 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:03:14 volumio sudo[32144]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:03:14 volumio sudo[32144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:14 volumio sudo[32144]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:14 volumio volumio[31991]: info: Volumio Calling Home Jun 10 04:03:15 volumio sudo[32177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:03:15 volumio volumio[31991]: info: [squeezelite_mc] Proxy server started on port 41531 Jun 10 04:03:15 volumio sudo[32177]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:15 volumio volumio[31991]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:15 volumio volumio[31991]: info: CoreStateMachine::pushState Jun 10 04:03:15 volumio volumio[31991]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:15 volumio volumio[31991]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:15 volumio volumio[31991]: info: [squeezelite_mc] Server discovery started Jun 10 04:03:15 volumio volumio[31991]: info: [squeezelite_mc] Player finder started Jun 10 04:03:15 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:15 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:15 volumio volumio[31991]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:15 volumio volumio[31991]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:03:15 volumio volumio[31991]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:15 volumio volumio[31991]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:03:15 volumio volumio[31991]: at doSend (dgram.js:692:16) Jun 10 04:03:15 volumio volumio[31991]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:03:15 volumio volumio[31991]: at afterDns (dgram.js:638:5) Jun 10 04:03:15 volumio volumio[31991]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:03:15 volumio volumio[31991]: errno: -101, Jun 10 04:03:15 volumio volumio[31991]: code: 'ENETUNREACH', Jun 10 04:03:15 volumio volumio[31991]: syscall: 'send', Jun 10 04:03:15 volumio volumio[31991]: address: '255.255.255.255', Jun 10 04:03:15 volumio volumio[31991]: port: 3483 Jun 10 04:03:15 volumio volumio[31991]: } Jun 10 04:03:15 volumio volumio[31991]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:16 volumio sudo[32177]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:16 volumio sudo[32207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:02 Jun 10 04:03:16 volumio sudo[32207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:16 volumio sudo[32207]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:17 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jun 10 04:03:17 volumio sudo[32128]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:17 volumio sudo[32135]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:17 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Jun 10 04:03:17 volumio systemd[1]: Started dynamicswap service. Jun 10 04:03:17 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jun 10 04:03:17 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 45. Jun 10 04:03:17 volumio systemd[1]: Stopped Volumio Backend Module. Jun 10 04:03:17 volumio systemd[1]: Started Volumio Backend Module. Jun 10 04:03:17 volumio systemd[1]: dynamicswap.service: Succeeded. Jun 10 04:03:17 volumio mpd[32170]: Jun 10 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 10 04:03:18 volumio systemd[1]: Started Music Player Daemon. Jun 10 04:03:18 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Jun 10 04:03:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Jun 10 04:03:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Jun 10 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Jun 10 04:03:19 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Jun 10 04:03:19 volumio volumio[32229]: cset: --> shielding system active with Jun 10 04:03:19 volumio volumio[32229]: cset: "system" cpuset of CPUSPEC(1-3) with 69 tasks running Jun 10 04:03:19 volumio volumio[32229]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Jun 10 04:03:19 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Jun 10 04:03:19 volumio volumio[32218]: info: ------------------------------------------- Jun 10 04:03:19 volumio volumio[32218]: info: ----- Volumio3 ---- Jun 10 04:03:19 volumio volumio[32218]: info: ------------------------------------------- Jun 10 04:03:19 volumio volumio[32218]: info: ----- System startup ---- Jun 10 04:03:19 volumio volumio[32218]: info: ------------------------------------------- Jun 10 04:03:20 volumio volumio[32218]: info: MYVOLUMIO Environment detected Jun 10 04:03:20 volumio volumio[32218]: info: Plugin folders cleanup Jun 10 04:03:20 volumio volumio[32218]: info: Scanning into folder /volumio/app/plugins/ Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category audio_interface Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category miscellanea Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category music_service Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category plugins.json Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category system_controller Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category user_interface Jun 10 04:03:20 volumio volumio[32218]: info: Scanning into folder /data/plugins/ Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category music_service Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category system_controller Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category system_hardware Jun 10 04:03:20 volumio volumio[32218]: info: Scanning category user_interface Jun 10 04:03:20 volumio volumio[32218]: info: Plugin folders cleanup completed Jun 10 04:03:20 volumio volumio[32218]: info: ------------------------------------------- Jun 10 04:03:20 volumio volumio[32218]: info: ----- Core plugins startup ---- Jun 10 04:03:20 volumio volumio[32218]: info: ------------------------------------------- Jun 10 04:03:20 volumio volumio[32218]: info: Loading plugins from folder /volumio/app/plugins/ Jun 10 04:03:20 volumio volumio[32218]: info: Adding plugin upnp to MyMusic Plugins Jun 10 04:03:20 volumio volumio[32218]: info: Adding plugin airplay_emulation to MyMusic Plugins Jun 10 04:03:20 volumio volumio[32218]: info: Adding plugin upnp_browser to MyMusic Plugins Jun 10 04:03:20 volumio volumio[32218]: info: Loading plugins from folder /data/plugins/ Jun 10 04:03:20 volumio volumio[32218]: info: Loading plugin "system"... Jun 10 04:03:20 volumio volumio[32218]: info: Loading plugin "appearance"... Jun 10 04:03:20 volumio wpa_supplicant[862]: wlan0: Trying to associate with SSID 'DAVINCHI-5G' Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Jun 10 04:03:20 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "network"... Jun 10 04:03:21 volumio volumio[32218]: info: Refreshing Cached IP Addresses Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: carrier acquired Jun 10 04:03:21 volumio wpa_supplicant[862]: wlan0: Associated with f0:b4:d2:6b:1f:7b Jun 10 04:03:21 volumio wpa_supplicant[862]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:b4:d2:6b:1f:7b completed [id=0 id_str=] Jun 10 04:03:21 volumio wpa_supplicant[862]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: IAID eb:2a:05:b2 Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: carrier lost Jun 10 04:03:21 volumio sudo[32258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 10 04:03:21 volumio sudo[32258]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:21 volumio sudo[32261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 10 04:03:21 volumio sudo[32261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:21 volumio sudo[32258]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:21 volumio sudo[32261]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "services"... Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: carrier acquired Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "alsa_controller"... Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: IAID eb:2a:05:b2 Jun 10 04:03:21 volumio sudo[32281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 10 04:03:21 volumio sudo[32281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:21 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "wizard"... Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "networkfs"... Jun 10 04:03:21 volumio volumio[32218]: info: Cannot mount NAS NasTas at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:03:21 volumio volumio[32218]: info: Cannot mount NAS Music-Dieter at system boot, trial number 1 ,retrying in 5 seconds Jun 10 04:03:21 volumio volumio[32218]: info: Starting Udev Watcher for removable devices Jun 10 04:03:21 volumio volumio[32218]: info: Ignoring mount for partition: boot Jun 10 04:03:21 volumio volumio[32218]: info: Ignoring mount for partition: volumio Jun 10 04:03:21 volumio volumio[32218]: info: Ignoring mount for partition: volumio_data Jun 10 04:03:21 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "volumio_command_line_client"... Jun 10 04:03:21 volumio volumio[32218]: info: Plugin upnp is not enabled Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "my_music"... Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "mpd"... Jun 10 04:03:21 volumio kernel: hwmon hwmon1: Voltage normalised Jun 10 04:03:21 volumio dhcpcd[874]: wlan0: soliciting an IPv6 router Jun 10 04:03:21 volumio volumio[32218]: info: Plugin upnp_browser is not enabled Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "alarm-clock"... Jun 10 04:03:21 volumio volumio-remote-updater[560]: [2025-06-10 04:03:21] [connect] Successful connection Jun 10 04:03:21 volumio volumio[32218]: info: Plugin airplay_emulation is not enabled Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "last_100"... Jun 10 04:03:21 volumio volumio[32218]: info: Loading plugin "webradio"... Jun 10 04:03:22 volumio volumio[32218]: info: Loading plugin "i2s_dacs"... Jun 10 04:03:22 volumio volumio[32218]: info: Loading plugin "volumiodiscovery"... Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** For more information see Jun 10 04:03:22 volumio node[32218]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:22 volumio volumio[32218]: *** WARNING *** For more information see Jun 10 04:03:22 volumio node[32218]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:22 volumio node[32218]: *** WARNING *** For more information see Jun 10 04:03:22 volumio node[32218]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jun 10 04:03:22 volumio node[32218]: *** WARNING *** Please fix your application to use the native API of Avahi! Jun 10 04:03:22 volumio node[32218]: *** WARNING *** For more information see Jun 10 04:03:22 volumio volumio[32218]: info: Applying required configuration parameters for plugin volumiodiscovery Jun 10 04:03:22 volumio volumio[32218]: info: Discovery: Started advertising with name: Volumio Jun 10 04:03:22 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jun 10 04:03:22 volumio volumio[32218]: info: Loading plugin "soundcloud"... Jun 10 04:03:22 volumio dhcpcd[874]: wlan0: rebinding lease of 192.168.0.120 Jun 10 04:03:22 volumio dhcpcd[874]: wlan0: probing address 192.168.0.120/24 Jun 10 04:03:22 volumio volumio[32218]: info: Loading plugin "spop"... Jun 10 04:03:23 volumio volumio[32218]: info: Loading plugin "squeezelite_mc"... Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "outputs"... Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "albumart"... Jun 10 04:03:24 volumio volumio[32218]: info: Plugin example_plugin is not enabled Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "inputs"... Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "updater_comm"... Jun 10 04:03:24 volumio volumio[32218]: info: Plugin mpdemulation is not enabled Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "rest_api"... Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "websocket"... Jun 10 04:03:24 volumio volumio[32218]: info: Starting Socket.io Server version 2.3.0 Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "radio_paradise"... Jun 10 04:03:24 volumio volumio[32218]: Forking 3 albumart workers Jun 10 04:03:24 volumio sudo[32281]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:24 volumio volumio[32218]: info: Applying required configuration parameters for plugin radio_paradise Jun 10 04:03:24 volumio volumio[32218]: info: [1749521004858] [RadioParadise] API delay: 3 Jun 10 04:03:24 volumio volumio[32218]: info: Loading plugin "backup_restore"... Jun 10 04:03:25 volumio volumio[32218]: info: Applying required configuration parameters for plugin backup_restore Jun 10 04:03:25 volumio volumio[32218]: info: Loading plugin "music_services_shield"... Jun 10 04:03:25 volumio volumio[32218]: info: Applying required configuration parameters for plugin music_services_shield Jun 10 04:03:25 volumio volumio[32218]: info: Loading plugin "Systeminfo"... Jun 10 04:03:25 volumio volumio[32218]: Starting albumart workers Jun 10 04:03:25 volumio volumio[32218]: Starting albumart workers Jun 10 04:03:26 volumio volumio[32218]: info: Loading i18n strings for locale en Jun 10 04:03:26 volumio volumio[32218]: Updating browse sources language Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:26 volumio volumio[32218]: Starting albumart workers Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::initPlayerControls Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:26 volumio volumio[32218]: Express server listening on port 3000 Jun 10 04:03:26 volumio volumio[32218]: [Metrics] WebUI: 7s 543.59ms Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::resetVolumioState Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::getcurrentVolume Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::pushState Jun 10 04:03:26 volumio volumio[32218]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:26 volumio volumio[32218]: info: Cannot mount NAS NasTas at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:26 volumio volumio[32218]: info: Cannot mount NAS Music-Dieter at system boot, trial number 2 ,retrying in 5 seconds Jun 10 04:03:26 volumio volumio[32218]: info: Volumio Network Manager: Network status updated: 0 Jun 10 04:03:26 volumio volumio-remote-updater[560]: [2025-06-10 04:03:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1749521001 101 Jun 10 04:03:26 volumio volumio[32218]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:26 volumio volumio[32218]: info: Reloading queue from file Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::setRepeat false single undefined Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::pushState Jun 10 04:03:26 volumio volumio[32218]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::setRandom false Jun 10 04:03:26 volumio volumio[32218]: info: CoreStateMachine::pushState Jun 10 04:03:26 volumio volumio[32218]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:26 volumio volumio[32218]: info: Setting Device type: Raspberry PI Jun 10 04:03:26 volumio volumio[32218]: info: Completed loading Core Plugins Jun 10 04:03:26 volumio volumio[32218]: info: Preparing to generate the ALSA configuration file Jun 10 04:03:26 volumio volumio[32218]: info: Asound.conf file unchanged, so no further update is needed Jun 10 04:03:26 volumio volumio[32218]: info: Output device has changed, restarting MPD Jun 10 04:03:26 volumio sudo[32358]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:26 volumio sudo[32358]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:26 volumio volumio[32218]: info: ___________ START PLUGINS ___________ Jun 10 04:03:26 volumio sudo[32358]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:26 volumio volumio[32218]: info: ControllerMpd::onStart: Initializing MPD Jun 10 04:03:26 volumio volumio[32218]: info: Creating MPD Configuration file Jun 10 04:03:26 volumio sudo[32360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:26 volumio sudo[32360]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:26 volumio systemd[1]: musicservicesshield.service: Succeeded. Jun 10 04:03:26 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Jun 10 04:03:26 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Jun 10 04:03:26 volumio systemd[1]: Stopping Music Player Daemon... Jun 10 04:03:26 volumio sudo[32364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 10 04:03:26 volumio sudo[32364]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:26 volumio sudo[32364]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:26 volumio volumio[32218]: info: [1749521006904] CoreMusicLibrary::Adding element Last_100 Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:26 volumio volumio[32218]: info: [1749521006911] CoreMusicLibrary::Adding element Webradio Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:26 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:26 volumio sudo[32367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 10 04:03:26 volumio sudo[32367]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:26 volumio volumio[32218]: info: Initializing BBC Radios Jun 10 04:03:26 volumio systemd[1]: mpd.service: Succeeded. Jun 10 04:03:26 volumio systemd[1]: Stopped Music Player Daemon. Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:27 volumio volumio[32218]: info: [1749521007021] CoreMusicLibrary::Adding element SoundCloud Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:27 volumio volumio[32218]: Cannot find translation for source SoundCloud Jun 10 04:03:27 volumio volumio[32218]: info: Creating Spotify config file Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:27 volumio volumio[32218]: info: [squeezelite_mc] Starting proxy server... Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 10 04:03:27 volumio volumio[32218]: info: [1749521007059] CoreMusicLibrary::Adding element Radio Paradise Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 10 04:03:27 volumio volumio[32218]: Cannot find translation for source SoundCloud Jun 10 04:03:27 volumio volumio[32218]: Cannot find translation for source Radio Paradise Jun 10 04:03:27 volumio systemd[1]: Starting Music Player Daemon... Jun 10 04:03:27 volumio sudo[32372]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 10 04:03:27 volumio sudo[32372]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:27 volumio sudo[32372]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:27 volumio volumio[32218]: info: Volumio Calling Home Jun 10 04:03:27 volumio sudo[32408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Jun 10 04:03:27 volumio sudo[32408]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 10 04:03:27 volumio volumio[32218]: info: [squeezelite_mc] Proxy server started on port 39759 Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::volumioRetrievevolume Jun 10 04:03:27 volumio volumio[32218]: info: CoreStateMachine::pushState Jun 10 04:03:27 volumio volumio[32218]: info: CorePlayQueue::getTrack 0 Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::volumioPushState Jun 10 04:03:27 volumio volumio[32218]: info: [squeezelite_mc] Server discovery started Jun 10 04:03:27 volumio volumio[32218]: info: [squeezelite_mc] Player finder started Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 04:03:27 volumio volumio[32218]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 10 04:03:27 volumio volumio[32218]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Jun 10 04:03:27 volumio volumio[32218]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:27 volumio volumio[32218]: Error: send ENETUNREACH 255.255.255.255:3483 Jun 10 04:03:27 volumio volumio[32218]: at doSend (dgram.js:692:16) Jun 10 04:03:27 volumio volumio[32218]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Jun 10 04:03:27 volumio volumio[32218]: at afterDns (dgram.js:638:5) Jun 10 04:03:27 volumio volumio[32218]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Jun 10 04:03:27 volumio volumio[32218]: errno: -101, Jun 10 04:03:27 volumio volumio[32218]: code: 'ENETUNREACH', Jun 10 04:03:27 volumio volumio[32218]: syscall: 'send', Jun 10 04:03:27 volumio volumio[32218]: address: '255.255.255.255', Jun 10 04:03:27 volumio volumio[32218]: port: 3483 Jun 10 04:03:27 volumio volumio[32218]: } Jun 10 04:03:27 volumio volumio[32218]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 04:03:27 volumio dhcpcd[874]: wlan0: leased 192.168.0.120 for infinity Jun 10 04:03:27 volumio avahi-daemon[564]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.120. Jun 10 04:03:27 volumio dhcpcd[874]: wlan0: adding route to 192.168.0.0/24 Jun 10 04:03:27 volumio avahi-daemon[564]: New relevant interface wlan0.IPv4 for mDNS. Jun 10 04:03:27 volumio avahi-daemon[564]: Registering new address record for 192.168.0.120 on wlan0.IPv4. Jun 10 04:03:27 volumio dhcpcd[874]: wlan0: adding default route via 192.168.0.1 Jun 10 04:03:28 volumio sudo[32408]: pam_unix(sudo:session): session closed for user root Jun 10 04:03:28 volumio sudo[32459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-10 04:02 Jun 10 04:03:28 volumio sudo[32459]: 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="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"