-- Logs begin at Thu 2019-02-14 10:11:58 UTC, end at Wed 2025-12-03 00:54:14 UTC. -- Dec 03 00:53:00 volumio sudo[3008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:00 volumio sudo[3010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:00 volumio sudo[3008]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:00 volumio sudo[3010]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:00 volumio sudo[3006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:00 volumio sudo[3006]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:00 volumio sudo[3004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:00 volumio wpa_supplicant[883]: wlan0: Failed to initiate sched scan Dec 03 00:53:00 volumio sudo[3008]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:00 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:00 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:00 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:00 volumio volumio[2872]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 03 00:53:00 volumio volumio[2872]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:00 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:00 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:00 volumio volumio[2872]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:00 volumio volumio[2872]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:00 volumio volumio[2872]: errno: -16, Dec 03 00:53:00 volumio volumio[2872]: code: 'EBUSY', Dec 03 00:53:00 volumio volumio[2872]: syscall: 'getaddrinfo', Dec 03 00:53:00 volumio volumio[2872]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:00 volumio volumio[2872]: } Dec 03 00:53:00 volumio volumio[2872]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:00 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:00 volumio sudo[3060]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:00 volumio sudo[3060]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:00 volumio sudo[3060]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:00 volumio sudo[3072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:00 volumio sudo[3072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:01 volumio sudo[3072]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:01 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:01 volumio sudo[3010]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:01 volumio sudo[3006]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:01 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:01 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:01 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:01 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:01 volumio mpd[3070]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:01 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:01 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Dec 03 00:53:01 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:01 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:01 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:01 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:01 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:01 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:01 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:01 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:01 volumio volumio-remote-updater[602]: [2025-12-03 00:53:01] [info] asio async_connect error: system:111 (Connection refused) Dec 03 00:53:01 volumio volumio-remote-updater[602]: [2025-12-03 00:53:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 03 00:53:01 volumio volumio-remote-updater[602]: [2025-12-03 00:53:01] [error] handle_connect error: Underlying Transport Error Dec 03 00:53:02 volumio volumio[3088]: info: ------------------------------------------- Dec 03 00:53:02 volumio volumio[3088]: info: ----- Volumio3 ---- Dec 03 00:53:02 volumio volumio[3088]: info: ------------------------------------------- Dec 03 00:53:02 volumio volumio[3088]: info: ----- System startup ---- Dec 03 00:53:02 volumio volumio[3088]: info: ------------------------------------------- Dec 03 00:53:02 volumio volumio[3088]: info: MYVOLUMIO Environment detected Dec 03 00:53:02 volumio volumio[3088]: info: Plugin folders cleanup Dec 03 00:53:02 volumio volumio[3088]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category audio_interface Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category miscellanea Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category music_service Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category plugins.json Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category system_controller Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category user_interface Dec 03 00:53:02 volumio volumio[3088]: info: Scanning into folder /data/plugins/ Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category music_service Dec 03 00:53:02 volumio volumio[3088]: info: Scanning category user_interface Dec 03 00:53:02 volumio volumio[3088]: info: Plugin folders cleanup completed Dec 03 00:53:02 volumio volumio[3088]: info: ------------------------------------------- Dec 03 00:53:02 volumio volumio[3088]: info: ----- Core plugins startup ---- Dec 03 00:53:02 volumio volumio[3088]: info: ------------------------------------------- Dec 03 00:53:02 volumio volumio[3088]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:02 volumio volumio[3088]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:02 volumio volumio[3088]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:02 volumio volumio[3088]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:02 volumio volumio[3088]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:02 volumio volumio[3088]: info: Loading plugin "system"... Dec 03 00:53:02 volumio volumio[3088]: info: Loading plugin "appearance"... Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "network"... Dec 03 00:53:03 volumio volumio[3088]: info: Refreshing Cached IP Addresses Dec 03 00:53:03 volumio sudo[3119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:03 volumio sudo[3119]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:03 volumio sudo[3119]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:03 volumio sudo[3121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:03 volumio sudo[3121]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:03 volumio sudo[3121]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "services"... Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "alsa_controller"... Dec 03 00:53:03 volumio sudo[3134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:03 volumio sudo[3134]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:03 volumio volumio[3088]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:03 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "wizard"... Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "networkfs"... Dec 03 00:53:03 volumio volumio[3088]: info: Starting Udev Watcher for removable devices Dec 03 00:53:03 volumio volumio[3088]: info: Ignoring mount for partition: boot Dec 03 00:53:03 volumio volumio[3088]: info: Ignoring mount for partition: volumio Dec 03 00:53:03 volumio volumio[3088]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:03 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:03 volumio volumio[3088]: info: Plugin upnp is not enabled Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "my_music"... Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "mpd"... Dec 03 00:53:03 volumio volumio[3088]: info: Plugin upnp_browser is not enabled Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "alarm-clock"... Dec 03 00:53:03 volumio volumio[3088]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "last_100"... Dec 03 00:53:03 volumio volumio[3088]: info: Loading plugin "webradio"... Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:04 volumio volumio[3088]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** For more information see Dec 03 00:53:04 volumio node[3088]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:04 volumio volumio[3088]: *** WARNING *** For more information see Dec 03 00:53:04 volumio node[3088]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:04 volumio node[3088]: *** WARNING *** For more information see Dec 03 00:53:04 volumio node[3088]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:04 volumio node[3088]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:04 volumio node[3088]: *** WARNING *** For more information see Dec 03 00:53:04 volumio volumio[3088]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:04 volumio volumio[3088]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "soundcloud"... Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "outputs"... Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "albumart"... Dec 03 00:53:04 volumio volumio[3088]: info: Plugin example_plugin is not enabled Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "inputs"... Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "updater_comm"... Dec 03 00:53:04 volumio volumio[3088]: info: Plugin mpdemulation is not enabled Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "rest_api"... Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "websocket"... Dec 03 00:53:04 volumio volumio[3088]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:04 volumio volumio[3088]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:04 volumio volumio[3088]: info: Loading plugin "lastfm"... Dec 03 00:53:04 volumio volumio[3088]: Forking 3 albumart workers Dec 03 00:53:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:04 volumio volumio[3088]: info: Loading i18n strings for locale en Dec 03 00:53:04 volumio volumio[3088]: Updating browse sources language Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:04 volumio volumio[3088]: Express server listening on port 3000 Dec 03 00:53:04 volumio volumio[3088]: [Metrics] WebUI: 2s 760.38ms Dec 03 00:53:04 volumio volumio[3088]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:04 volumio volumio[3088]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:04 volumio volumio[3088]: info: CoreStateMachine::pushState Dec 03 00:53:04 volumio volumio[3088]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:04 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:04 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: Starting albumart workers Dec 03 00:53:05 volumio volumio[3088]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:05 volumio volumio[3088]: Starting albumart workers Dec 03 00:53:05 volumio volumio[3088]: info: Reloading queue from file Dec 03 00:53:05 volumio volumio[3088]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:05 volumio volumio[3088]: info: CoreStateMachine::pushState Dec 03 00:53:05 volumio volumio[3088]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:05 volumio volumio[3088]: info: CoreStateMachine::setRandom false Dec 03 00:53:05 volumio volumio[3088]: info: CoreStateMachine::pushState Dec 03 00:53:05 volumio volumio[3088]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:05 volumio volumio[3088]: info: Setting Device type: Raspberry PI Dec 03 00:53:05 volumio volumio[3088]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:05 volumio volumio[3088]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:05 volumio volumio[3088]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:05 volumio volumio[3088]: info: Completed loading Core Plugins Dec 03 00:53:05 volumio volumio[3088]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:05 volumio volumio[3088]: Starting albumart workers Dec 03 00:53:05 volumio volumio[3088]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:05 volumio volumio[3088]: info: Output device has changed, restarting MPD Dec 03 00:53:05 volumio volumio[3088]: info: ___________ START PLUGINS ___________ Dec 03 00:53:05 volumio volumio[3088]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:05 volumio volumio[3088]: info: Creating MPD Configuration file Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:05 volumio volumio[3088]: info: [1764723185235] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:05 volumio volumio[3088]: info: [1764723185238] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:05 volumio volumio[3088]: info: Initializing BBC Radios Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:05 volumio volumio[3088]: info: [1764723185335] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:05 volumio volumio[3088]: Cannot find translation for source SoundCloud Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:05 volumio volumio[3088]: info: [1764723185340] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:05 volumio volumio[3088]: Cannot find translation for source SoundCloud Dec 03 00:53:05 volumio volumio[3088]: Cannot find translation for source LastFM Dec 03 00:53:05 volumio volumio[3088]: info: [LastFM] scrobbler initiated! Dec 03 00:53:05 volumio volumio[3088]: info: [LastFM] extended logging: false Dec 03 00:53:05 volumio volumio[3088]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:05 volumio volumio[3088]: info: [LastFM] Left init routine Dec 03 00:53:05 volumio volumio[3088]: info: [LastFM] Socket already connected: false Dec 03 00:53:05 volumio volumio[3088]: info: Volumio Calling Home Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:05 volumio volumio[3088]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:06 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:06 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:06 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:07 volumio sudo[3188]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:07 volumio sudo[3188]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:07 volumio sudo[3184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:07 volumio sudo[3184]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:07 volumio sudo[3182]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3186]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3182]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:07 volumio sudo[3186]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:07 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:07 volumio volumio[3088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 03 00:53:07 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:07 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:07 volumio volumio[3088]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 03 00:53:07 volumio volumio[3088]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:07 volumio volumio[3088]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:07 volumio volumio[3088]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:07 volumio volumio[3088]: errno: -16, Dec 03 00:53:07 volumio volumio[3088]: code: 'EBUSY', Dec 03 00:53:07 volumio volumio[3088]: syscall: 'getaddrinfo', Dec 03 00:53:07 volumio volumio[3088]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:07 volumio volumio[3088]: } Dec 03 00:53:07 volumio volumio[3088]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:07 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:07 volumio sudo[3243]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:07 volumio sudo[3243]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3243]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:07 volumio sudo[3255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:07 volumio sudo[3255]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:07 volumio sudo[3255]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:07 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:07 volumio sudo[3188]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:07 volumio sudo[3134]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:08 volumio sudo[3184]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:08 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:08 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Dec 03 00:53:08 volumio mpd[3253]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:08 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:08 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:08 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:08 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:08 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:09 volumio volumio[3271]: info: ------------------------------------------- Dec 03 00:53:09 volumio volumio[3271]: info: ----- Volumio3 ---- Dec 03 00:53:09 volumio volumio[3271]: info: ------------------------------------------- Dec 03 00:53:09 volumio volumio[3271]: info: ----- System startup ---- Dec 03 00:53:09 volumio volumio[3271]: info: ------------------------------------------- Dec 03 00:53:09 volumio volumio[3271]: info: MYVOLUMIO Environment detected Dec 03 00:53:09 volumio volumio[3271]: info: Plugin folders cleanup Dec 03 00:53:09 volumio volumio[3271]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category audio_interface Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category miscellanea Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category music_service Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category plugins.json Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category system_controller Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category user_interface Dec 03 00:53:09 volumio volumio[3271]: info: Scanning into folder /data/plugins/ Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category music_service Dec 03 00:53:09 volumio volumio[3271]: info: Scanning category user_interface Dec 03 00:53:09 volumio volumio[3271]: info: Plugin folders cleanup completed Dec 03 00:53:09 volumio volumio[3271]: info: ------------------------------------------- Dec 03 00:53:09 volumio volumio[3271]: info: ----- Core plugins startup ---- Dec 03 00:53:09 volumio volumio[3271]: info: ------------------------------------------- Dec 03 00:53:09 volumio volumio[3271]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:09 volumio volumio[3271]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:09 volumio volumio[3271]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:09 volumio volumio[3271]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:09 volumio volumio[3271]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:09 volumio volumio[3271]: info: Loading plugin "system"... Dec 03 00:53:09 volumio volumio[3271]: info: Loading plugin "appearance"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "network"... Dec 03 00:53:10 volumio volumio[3271]: info: Refreshing Cached IP Addresses Dec 03 00:53:10 volumio sudo[3297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:10 volumio sudo[3297]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:10 volumio sudo[3297]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:10 volumio sudo[3299]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:10 volumio sudo[3299]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:10 volumio sudo[3299]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "services"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "alsa_controller"... Dec 03 00:53:10 volumio sudo[3312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:10 volumio sudo[3312]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:10 volumio volumio[3271]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:10 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "wizard"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "networkfs"... Dec 03 00:53:10 volumio volumio[3271]: info: Starting Udev Watcher for removable devices Dec 03 00:53:10 volumio volumio[3271]: info: Ignoring mount for partition: boot Dec 03 00:53:10 volumio volumio[3271]: info: Ignoring mount for partition: volumio Dec 03 00:53:10 volumio volumio[3271]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:10 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:10 volumio volumio[3271]: info: Plugin upnp is not enabled Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "my_music"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "mpd"... Dec 03 00:53:10 volumio volumio[3271]: info: Plugin upnp_browser is not enabled Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "alarm-clock"... Dec 03 00:53:10 volumio volumio[3271]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "last_100"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "webradio"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:10 volumio volumio[3271]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** For more information see Dec 03 00:53:10 volumio node[3271]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:10 volumio volumio[3271]: *** WARNING *** For more information see Dec 03 00:53:10 volumio node[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:10 volumio node[3271]: *** WARNING *** For more information see Dec 03 00:53:10 volumio node[3271]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:10 volumio node[3271]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:10 volumio node[3271]: *** WARNING *** For more information see Dec 03 00:53:10 volumio volumio[3271]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:10 volumio volumio[3271]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:10 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "soundcloud"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "outputs"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "albumart"... Dec 03 00:53:10 volumio volumio[3271]: info: Plugin example_plugin is not enabled Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "inputs"... Dec 03 00:53:10 volumio volumio[3271]: info: Loading plugin "updater_comm"... Dec 03 00:53:11 volumio volumio[3271]: info: Plugin mpdemulation is not enabled Dec 03 00:53:11 volumio volumio[3271]: info: Loading plugin "rest_api"... Dec 03 00:53:11 volumio volumio[3271]: info: Loading plugin "websocket"... Dec 03 00:53:11 volumio volumio[3271]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:11 volumio volumio[3271]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:11 volumio volumio[3271]: info: Loading plugin "lastfm"... Dec 03 00:53:11 volumio volumio[3271]: Forking 3 albumart workers Dec 03 00:53:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:11 volumio volumio[3271]: info: Loading i18n strings for locale en Dec 03 00:53:11 volumio volumio[3271]: Updating browse sources language Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:11 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:11 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:11 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: Express server listening on port 3000 Dec 03 00:53:11 volumio volumio[3271]: [Metrics] WebUI: 2s 816.89ms Dec 03 00:53:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::pushState Dec 03 00:53:11 volumio volumio[3271]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:11 volumio volumio[3271]: Starting albumart workers Dec 03 00:53:11 volumio volumio[3271]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:11 volumio volumio[3271]: Starting albumart workers Dec 03 00:53:11 volumio volumio[3271]: Starting albumart workers Dec 03 00:53:11 volumio volumio[3271]: info: Reloading queue from file Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::pushState Dec 03 00:53:11 volumio volumio[3271]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::setRandom false Dec 03 00:53:11 volumio volumio[3271]: info: CoreStateMachine::pushState Dec 03 00:53:11 volumio volumio[3271]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:11 volumio volumio[3271]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:11 volumio volumio[3271]: info: Setting Device type: Raspberry PI Dec 03 00:53:11 volumio volumio[3271]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:11 volumio volumio[3271]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:11 volumio volumio[3271]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:11 volumio volumio[3271]: info: Completed loading Core Plugins Dec 03 00:53:11 volumio volumio[3271]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:11 volumio volumio[3271]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:11 volumio volumio[3271]: info: Output device has changed, restarting MPD Dec 03 00:53:12 volumio volumio[3271]: info: ___________ START PLUGINS ___________ Dec 03 00:53:12 volumio volumio[3271]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:12 volumio volumio[3271]: info: Creating MPD Configuration file Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:12 volumio volumio[3271]: info: [1764723192074] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:12 volumio volumio[3271]: info: [1764723192083] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:12 volumio volumio[3271]: info: Initializing BBC Radios Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:12 volumio volumio[3271]: info: [1764723192181] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:12 volumio volumio[3271]: Cannot find translation for source SoundCloud Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:12 volumio volumio[3271]: info: [1764723192186] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:12 volumio volumio[3271]: Cannot find translation for source SoundCloud Dec 03 00:53:12 volumio volumio[3271]: Cannot find translation for source LastFM Dec 03 00:53:12 volumio volumio[3271]: info: [LastFM] scrobbler initiated! Dec 03 00:53:12 volumio volumio[3271]: info: [LastFM] extended logging: false Dec 03 00:53:12 volumio volumio[3271]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:12 volumio volumio[3271]: info: [LastFM] Left init routine Dec 03 00:53:12 volumio volumio[3271]: info: [LastFM] Socket already connected: false Dec 03 00:53:12 volumio volumio[3271]: info: Volumio Calling Home Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:12 volumio volumio[3271]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:14 volumio volumio-remote-updater[602]: [2025-12-03 00:53:14] [connect] Successful connection Dec 03 00:53:14 volumio volumio[3271]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:14 volumio sudo[3365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:14 volumio sudo[3365]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Dec 03 00:53:14 volumio sudo[3365]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio sudo[3371]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:14 volumio sudo[3369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:14 volumio sudo[3371]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio sudo[3369]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio sudo[3367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:14 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio sudo[3369]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio volumio[3271]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:14 volumio volumio[3271]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:14 volumio volumio[3271]: errno: -16, Dec 03 00:53:14 volumio volumio[3271]: code: 'EBUSY', Dec 03 00:53:14 volumio volumio[3271]: syscall: 'getaddrinfo', Dec 03 00:53:14 volumio volumio[3271]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:14 volumio volumio[3271]: } Dec 03 00:53:14 volumio volumio[3271]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:14 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:14 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:14 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:14 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:14 volumio sudo[3428]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:14 volumio sudo[3428]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio sudo[3428]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio sudo[3433]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:14 volumio sudo[3433]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:14 volumio sudo[3433]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio volumio-remote-updater[602]: [2025-12-03 00:53:14] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Dec 03 00:53:14 volumio volumio-remote-updater[602]: [2025-12-03 00:53:14] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Dec 03 00:53:14 volumio volumio-remote-updater[602]: [2025-12-03 00:53:14] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 03 00:53:14 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:14 volumio sudo[3312]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio sudo[3371]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:14 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:14 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:14 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:14 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:14 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Dec 03 00:53:15 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:15 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:15 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:15 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:15 volumio mpd[3431]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:15 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:16 volumio volumio[3447]: info: ------------------------------------------- Dec 03 00:53:16 volumio volumio[3447]: info: ----- Volumio3 ---- Dec 03 00:53:16 volumio volumio[3447]: info: ------------------------------------------- Dec 03 00:53:16 volumio volumio[3447]: info: ----- System startup ---- Dec 03 00:53:16 volumio volumio[3447]: info: ------------------------------------------- Dec 03 00:53:16 volumio volumio[3447]: info: MYVOLUMIO Environment detected Dec 03 00:53:16 volumio volumio[3447]: info: Plugin folders cleanup Dec 03 00:53:16 volumio volumio[3447]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category audio_interface Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category miscellanea Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category music_service Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category plugins.json Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category system_controller Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category user_interface Dec 03 00:53:16 volumio volumio[3447]: info: Scanning into folder /data/plugins/ Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category music_service Dec 03 00:53:16 volumio volumio[3447]: info: Scanning category user_interface Dec 03 00:53:16 volumio volumio[3447]: info: Plugin folders cleanup completed Dec 03 00:53:16 volumio volumio[3447]: info: ------------------------------------------- Dec 03 00:53:16 volumio volumio[3447]: info: ----- Core plugins startup ---- Dec 03 00:53:16 volumio volumio[3447]: info: ------------------------------------------- Dec 03 00:53:16 volumio volumio[3447]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:16 volumio volumio[3447]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:16 volumio volumio[3447]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:16 volumio volumio[3447]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:16 volumio volumio[3447]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:16 volumio volumio[3447]: info: Loading plugin "system"... Dec 03 00:53:16 volumio volumio[3447]: info: Loading plugin "appearance"... Dec 03 00:53:16 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:16 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:16 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "network"... Dec 03 00:53:17 volumio volumio[3447]: info: Refreshing Cached IP Addresses Dec 03 00:53:17 volumio sudo[3480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:17 volumio sudo[3480]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:17 volumio sudo[3482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:17 volumio sudo[3482]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:17 volumio sudo[3480]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:17 volumio sudo[3482]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "services"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "alsa_controller"... Dec 03 00:53:17 volumio sudo[3495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:17 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:17 volumio volumio[3447]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:17 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "wizard"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "networkfs"... Dec 03 00:53:17 volumio volumio[3447]: info: Starting Udev Watcher for removable devices Dec 03 00:53:17 volumio volumio[3447]: info: Ignoring mount for partition: boot Dec 03 00:53:17 volumio volumio[3447]: info: Ignoring mount for partition: volumio Dec 03 00:53:17 volumio volumio[3447]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:17 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:17 volumio volumio[3447]: info: Plugin upnp is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "my_music"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "mpd"... Dec 03 00:53:17 volumio volumio[3447]: info: Plugin upnp_browser is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "alarm-clock"... Dec 03 00:53:17 volumio volumio[3447]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "last_100"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "webradio"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:17 volumio volumio[3447]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:17 volumio node[3447]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** For more information see Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:17 volumio volumio[3447]: *** WARNING *** For more information see Dec 03 00:53:17 volumio node[3447]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:17 volumio node[3447]: *** WARNING *** For more information see Dec 03 00:53:17 volumio node[3447]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:17 volumio node[3447]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:17 volumio node[3447]: *** WARNING *** For more information see Dec 03 00:53:17 volumio volumio[3447]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:17 volumio volumio[3447]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:17 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "soundcloud"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "outputs"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "albumart"... Dec 03 00:53:17 volumio volumio[3447]: info: Plugin example_plugin is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "inputs"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "updater_comm"... Dec 03 00:53:17 volumio volumio[3447]: info: Plugin mpdemulation is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "rest_api"... Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "websocket"... Dec 03 00:53:17 volumio volumio[3447]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:17 volumio volumio[3447]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:17 volumio volumio[3447]: info: Loading plugin "lastfm"... Dec 03 00:53:17 volumio volumio[3447]: Forking 3 albumart workers Dec 03 00:53:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:18 volumio volumio[3447]: info: Loading i18n strings for locale en Dec 03 00:53:18 volumio volumio[3447]: Updating browse sources language Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: Express server listening on port 3000 Dec 03 00:53:18 volumio volumio[3447]: [Metrics] WebUI: 2s 823.81ms Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::pushState Dec 03 00:53:18 volumio volumio[3447]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:18 volumio volumio[3447]: Starting albumart workers Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: Starting albumart workers Dec 03 00:53:18 volumio volumio[3447]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:18 volumio volumio[3447]: info: Reloading queue from file Dec 03 00:53:18 volumio volumio[3447]: Starting albumart workers Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::pushState Dec 03 00:53:18 volumio volumio[3447]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::setRandom false Dec 03 00:53:18 volumio volumio[3447]: info: CoreStateMachine::pushState Dec 03 00:53:18 volumio volumio[3447]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:18 volumio volumio[3447]: info: Setting Device type: Raspberry PI Dec 03 00:53:18 volumio volumio[3447]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:18 volumio volumio[3447]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:18 volumio volumio[3447]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:18 volumio volumio[3447]: info: Completed loading Core Plugins Dec 03 00:53:18 volumio volumio[3447]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:18 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:18 volumio volumio[3447]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:18 volumio volumio[3447]: info: Output device has changed, restarting MPD Dec 03 00:53:18 volumio volumio[3447]: info: ___________ START PLUGINS ___________ Dec 03 00:53:18 volumio volumio[3447]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:18 volumio volumio[3447]: info: Creating MPD Configuration file Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:18 volumio volumio[3447]: info: [1764723198785] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:18 volumio volumio[3447]: info: [1764723198788] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:18 volumio volumio[3447]: info: Initializing BBC Radios Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:18 volumio volumio[3447]: info: [1764723198867] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:18 volumio volumio[3447]: Cannot find translation for source SoundCloud Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:18 volumio volumio[3447]: info: [1764723198871] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:18 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:18 volumio volumio[3447]: Cannot find translation for source SoundCloud Dec 03 00:53:18 volumio volumio[3447]: Cannot find translation for source LastFM Dec 03 00:53:18 volumio volumio[3447]: info: [LastFM] scrobbler initiated! Dec 03 00:53:18 volumio volumio[3447]: info: [LastFM] extended logging: false Dec 03 00:53:18 volumio volumio[3447]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:18 volumio volumio[3447]: info: [LastFM] Left init routine Dec 03 00:53:18 volumio volumio[3447]: info: [LastFM] Socket already connected: false Dec 03 00:53:18 volumio volumio[3447]: info: Volumio Calling Home Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:19 volumio volumio[3447]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:21 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:21 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:21 volumio sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio volumio[3447]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:21 volumio sudo[3547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:21 volumio sudo[3547]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:21 volumio sudo[3549]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3547]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:21 volumio volumio[3447]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:21 volumio volumio[3447]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:21 volumio volumio[3447]: errno: -16, Dec 03 00:53:21 volumio volumio[3447]: code: 'EBUSY', Dec 03 00:53:21 volumio volumio[3447]: syscall: 'getaddrinfo', Dec 03 00:53:21 volumio volumio[3447]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:21 volumio volumio[3447]: } Dec 03 00:53:21 volumio volumio[3447]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:21 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:21 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:21 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:21 volumio sudo[3601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:21 volumio sudo[3601]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3601]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:21 volumio sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:21 volumio sudo[3611]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:21 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:21 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:21 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:21 volumio sudo[3549]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio sudo[3545]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:21 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:21 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:21 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 14. Dec 03 00:53:21 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:21 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:21 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:21 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:22 volumio mpd[3609]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:22 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:22 volumio volumio[3630]: info: ------------------------------------------- Dec 03 00:53:22 volumio volumio[3630]: info: ----- Volumio3 ---- Dec 03 00:53:22 volumio volumio[3630]: info: ------------------------------------------- Dec 03 00:53:22 volumio volumio[3630]: info: ----- System startup ---- Dec 03 00:53:22 volumio volumio[3630]: info: ------------------------------------------- Dec 03 00:53:23 volumio volumio[3630]: info: MYVOLUMIO Environment detected Dec 03 00:53:23 volumio volumio[3630]: info: Plugin folders cleanup Dec 03 00:53:23 volumio volumio[3630]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category audio_interface Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category miscellanea Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category music_service Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category plugins.json Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category system_controller Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category user_interface Dec 03 00:53:23 volumio volumio[3630]: info: Scanning into folder /data/plugins/ Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category music_service Dec 03 00:53:23 volumio volumio[3630]: info: Scanning category user_interface Dec 03 00:53:23 volumio volumio[3630]: info: Plugin folders cleanup completed Dec 03 00:53:23 volumio volumio[3630]: info: ------------------------------------------- Dec 03 00:53:23 volumio volumio[3630]: info: ----- Core plugins startup ---- Dec 03 00:53:23 volumio volumio[3630]: info: ------------------------------------------- Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:23 volumio volumio[3630]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:23 volumio volumio[3630]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:23 volumio volumio[3630]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "system"... Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "appearance"... Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "network"... Dec 03 00:53:23 volumio volumio[3630]: info: Refreshing Cached IP Addresses Dec 03 00:53:23 volumio sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:23 volumio sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:23 volumio sudo[3658]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:23 volumio sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:23 volumio sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:23 volumio sudo[3660]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "services"... Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "alsa_controller"... Dec 03 00:53:23 volumio sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:23 volumio sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:23 volumio volumio[3630]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:23 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "wizard"... Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "networkfs"... Dec 03 00:53:23 volumio volumio[3630]: info: Starting Udev Watcher for removable devices Dec 03 00:53:23 volumio volumio[3630]: info: Ignoring mount for partition: boot Dec 03 00:53:23 volumio volumio[3630]: info: Ignoring mount for partition: volumio Dec 03 00:53:23 volumio volumio[3630]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:23 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:23 volumio volumio[3630]: info: Plugin upnp is not enabled Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "my_music"... Dec 03 00:53:23 volumio volumio[3630]: info: Loading plugin "mpd"... Dec 03 00:53:24 volumio volumio[3630]: info: Plugin upnp_browser is not enabled Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "alarm-clock"... Dec 03 00:53:24 volumio volumio[3630]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "last_100"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "webradio"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:24 volumio volumio[3630]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** For more information see Dec 03 00:53:24 volumio node[3630]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:24 volumio volumio[3630]: *** WARNING *** For more information see Dec 03 00:53:24 volumio node[3630]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:24 volumio node[3630]: *** WARNING *** For more information see Dec 03 00:53:24 volumio node[3630]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:24 volumio node[3630]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:24 volumio node[3630]: *** WARNING *** For more information see Dec 03 00:53:24 volumio volumio[3630]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:24 volumio volumio[3630]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:24 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "soundcloud"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "outputs"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "albumart"... Dec 03 00:53:24 volumio volumio[3630]: info: Plugin example_plugin is not enabled Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "inputs"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "updater_comm"... Dec 03 00:53:24 volumio volumio[3630]: info: Plugin mpdemulation is not enabled Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "rest_api"... Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "websocket"... Dec 03 00:53:24 volumio volumio[3630]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:24 volumio volumio[3630]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:24 volumio volumio[3630]: info: Loading plugin "lastfm"... Dec 03 00:53:24 volumio volumio[3630]: Forking 3 albumart workers Dec 03 00:53:25 volumio volumio[3630]: info: Loading i18n strings for locale en Dec 03 00:53:25 volumio volumio[3630]: Updating browse sources language Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: Express server listening on port 3000 Dec 03 00:53:25 volumio volumio[3630]: [Metrics] WebUI: 2s 799.33ms Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::pushState Dec 03 00:53:25 volumio volumio[3630]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: Starting albumart workers Dec 03 00:53:25 volumio volumio[3630]: Starting albumart workers Dec 03 00:53:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:25 volumio volumio[3630]: Starting albumart workers Dec 03 00:53:25 volumio volumio[3630]: info: Reloading queue from file Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::pushState Dec 03 00:53:25 volumio volumio[3630]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::setRandom false Dec 03 00:53:25 volumio volumio[3630]: info: CoreStateMachine::pushState Dec 03 00:53:25 volumio volumio[3630]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:25 volumio volumio[3630]: info: Setting Device type: Raspberry PI Dec 03 00:53:25 volumio volumio[3630]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:25 volumio volumio[3630]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:25 volumio volumio[3630]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:25 volumio volumio[3630]: info: Completed loading Core Plugins Dec 03 00:53:25 volumio volumio[3630]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:25 volumio volumio[3630]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:25 volumio volumio[3630]: info: Output device has changed, restarting MPD Dec 03 00:53:25 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:25 volumio volumio[3630]: info: ___________ START PLUGINS ___________ Dec 03 00:53:25 volumio volumio[3630]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:25 volumio volumio[3630]: info: Creating MPD Configuration file Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:25 volumio volumio[3630]: info: [1764723205554] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:25 volumio volumio[3630]: info: [1764723205557] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:25 volumio volumio[3630]: info: Initializing BBC Radios Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:25 volumio volumio[3630]: info: [1764723205657] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:25 volumio volumio[3630]: Cannot find translation for source SoundCloud Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:25 volumio volumio[3630]: info: [1764723205663] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:25 volumio volumio[3630]: Cannot find translation for source SoundCloud Dec 03 00:53:25 volumio volumio[3630]: Cannot find translation for source LastFM Dec 03 00:53:25 volumio volumio[3630]: info: [LastFM] scrobbler initiated! Dec 03 00:53:25 volumio volumio[3630]: info: [LastFM] extended logging: false Dec 03 00:53:25 volumio volumio[3630]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:25 volumio volumio[3630]: info: [LastFM] Left init routine Dec 03 00:53:25 volumio volumio[3630]: info: [LastFM] Socket already connected: false Dec 03 00:53:25 volumio volumio[3630]: info: Volumio Calling Home Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:25 volumio volumio[3630]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:26 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:26 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:26 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:27 volumio volumio-remote-updater[602]: [2025-12-03 00:53:27] [connect] Successful connection Dec 03 00:53:27 volumio ntpd[768]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 03 00:53:27 volumio sudo[3722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:27 volumio sudo[3720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:27 volumio sudo[3722]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:27 volumio sudo[3720]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:27 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Dec 03 00:53:27 volumio sudo[3720]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:27 volumio sudo[3724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:27 volumio sudo[3724]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:27 volumio sudo[3726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:27 volumio sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:27 volumio volumio-remote-updater[602]: [2025-12-03 00:53:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1764723204 101 Dec 03 00:53:27 volumio sudo[3724]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:27 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:27 volumio volumio[3630]: 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 Dec 03 00:53:27 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:27 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:27 volumio volumio[3630]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:27 volumio volumio[3630]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:27 volumio volumio[3630]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:27 volumio volumio[3630]: errno: -16, Dec 03 00:53:27 volumio volumio[3630]: code: 'EBUSY', Dec 03 00:53:27 volumio volumio[3630]: syscall: 'getaddrinfo', Dec 03 00:53:27 volumio volumio[3630]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:27 volumio volumio[3630]: } Dec 03 00:53:27 volumio volumio[3630]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:28 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:28 volumio sudo[3782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:28 volumio sudo[3782]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:28 volumio sudo[3782]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:28 volumio ntpd[768]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 03 00:53:28 volumio ntpd[768]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 03 00:53:28 volumio sudo[3794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:28 volumio sudo[3794]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:28 volumio sudo[3794]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:28 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:28 volumio volumio-remote-updater[602]: [2025-12-03 00:53:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 03 00:53:28 volumio volumio-remote-updater[602]: [2025-12-03 00:53:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 03 00:53:28 volumio sudo[3669]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:28 volumio sudo[3722]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:28 volumio sudo[3726]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:28 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:28 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:28 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:28 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:28 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 15. Dec 03 00:53:28 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:28 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:28 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:28 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:28 volumio mpd[3792]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:28 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:29 volumio volumio[3807]: info: ------------------------------------------- Dec 03 00:53:29 volumio volumio[3807]: info: ----- Volumio3 ---- Dec 03 00:53:29 volumio volumio[3807]: info: ------------------------------------------- Dec 03 00:53:29 volumio volumio[3807]: info: ----- System startup ---- Dec 03 00:53:29 volumio volumio[3807]: info: ------------------------------------------- Dec 03 00:53:30 volumio volumio[3807]: info: MYVOLUMIO Environment detected Dec 03 00:53:30 volumio volumio[3807]: info: Plugin folders cleanup Dec 03 00:53:30 volumio volumio[3807]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category audio_interface Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category miscellanea Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category music_service Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category plugins.json Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category system_controller Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category user_interface Dec 03 00:53:30 volumio volumio[3807]: info: Scanning into folder /data/plugins/ Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category music_service Dec 03 00:53:30 volumio volumio[3807]: info: Scanning category user_interface Dec 03 00:53:30 volumio volumio[3807]: info: Plugin folders cleanup completed Dec 03 00:53:30 volumio volumio[3807]: info: ------------------------------------------- Dec 03 00:53:30 volumio volumio[3807]: info: ----- Core plugins startup ---- Dec 03 00:53:30 volumio volumio[3807]: info: ------------------------------------------- Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:30 volumio volumio[3807]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:30 volumio volumio[3807]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:30 volumio volumio[3807]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "system"... Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "appearance"... Dec 03 00:53:30 volumio ntpd[768]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "network"... Dec 03 00:53:30 volumio volumio[3807]: info: Refreshing Cached IP Addresses Dec 03 00:53:30 volumio sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:30 volumio sudo[3836]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:30 volumio sudo[3836]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:30 volumio sudo[3838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:30 volumio sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:30 volumio sudo[3838]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "services"... Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "alsa_controller"... Dec 03 00:53:30 volumio sudo[3847]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:30 volumio sudo[3847]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:30 volumio volumio[3807]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:30 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "wizard"... Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "networkfs"... Dec 03 00:53:30 volumio volumio[3807]: info: Starting Udev Watcher for removable devices Dec 03 00:53:30 volumio volumio[3807]: info: Ignoring mount for partition: boot Dec 03 00:53:30 volumio volumio[3807]: info: Ignoring mount for partition: volumio Dec 03 00:53:30 volumio volumio[3807]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:30 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:30 volumio volumio[3807]: info: Plugin upnp is not enabled Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "my_music"... Dec 03 00:53:30 volumio volumio[3807]: info: Loading plugin "mpd"... Dec 03 00:53:31 volumio volumio[3807]: info: Plugin upnp_browser is not enabled Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "alarm-clock"... Dec 03 00:53:31 volumio volumio[3807]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "last_100"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "webradio"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:31 volumio volumio[3807]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** For more information see Dec 03 00:53:31 volumio node[3807]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:31 volumio volumio[3807]: *** WARNING *** For more information see Dec 03 00:53:31 volumio node[3807]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:31 volumio node[3807]: *** WARNING *** For more information see Dec 03 00:53:31 volumio node[3807]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:31 volumio node[3807]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:31 volumio node[3807]: *** WARNING *** For more information see Dec 03 00:53:31 volumio volumio[3807]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:31 volumio volumio[3807]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:31 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "soundcloud"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "outputs"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "albumart"... Dec 03 00:53:31 volumio volumio[3807]: info: Plugin example_plugin is not enabled Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "inputs"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "updater_comm"... Dec 03 00:53:31 volumio volumio[3807]: info: Plugin mpdemulation is not enabled Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "rest_api"... Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "websocket"... Dec 03 00:53:31 volumio volumio[3807]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:31 volumio volumio[3807]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:31 volumio volumio[3807]: info: Loading plugin "lastfm"... Dec 03 00:53:31 volumio volumio[3807]: Forking 3 albumart workers Dec 03 00:53:31 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:31 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:31 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:32 volumio volumio[3807]: info: Loading i18n strings for locale en Dec 03 00:53:32 volumio volumio[3807]: Updating browse sources language Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: Express server listening on port 3000 Dec 03 00:53:32 volumio volumio[3807]: [Metrics] WebUI: 2s 805.45ms Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::pushState Dec 03 00:53:32 volumio volumio[3807]: Starting albumart workers Dec 03 00:53:32 volumio volumio[3807]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:32 volumio volumio[3807]: Starting albumart workers Dec 03 00:53:32 volumio volumio[3807]: info: Reloading queue from file Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::pushState Dec 03 00:53:32 volumio volumio[3807]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::setRandom false Dec 03 00:53:32 volumio volumio[3807]: info: CoreStateMachine::pushState Dec 03 00:53:32 volumio volumio[3807]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:32 volumio volumio[3807]: info: Setting Device type: Raspberry PI Dec 03 00:53:32 volumio volumio[3807]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:32 volumio volumio[3807]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:32 volumio volumio[3807]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:32 volumio volumio[3807]: info: Completed loading Core Plugins Dec 03 00:53:32 volumio volumio[3807]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:32 volumio volumio[3807]: Starting albumart workers Dec 03 00:53:32 volumio volumio[3807]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:32 volumio volumio[3807]: info: Output device has changed, restarting MPD Dec 03 00:53:32 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:32 volumio volumio[3807]: info: ___________ START PLUGINS ___________ Dec 03 00:53:32 volumio volumio[3807]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:32 volumio volumio[3807]: info: Creating MPD Configuration file Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:32 volumio volumio[3807]: info: [1764723212441] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:32 volumio volumio[3807]: info: [1764723212446] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:32 volumio volumio[3807]: info: Initializing BBC Radios Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:32 volumio volumio[3807]: info: [1764723212538] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:32 volumio volumio[3807]: Cannot find translation for source SoundCloud Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:32 volumio volumio[3807]: info: [1764723212544] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:32 volumio volumio[3807]: Cannot find translation for source SoundCloud Dec 03 00:53:32 volumio volumio[3807]: Cannot find translation for source LastFM Dec 03 00:53:32 volumio volumio[3807]: info: [LastFM] scrobbler initiated! Dec 03 00:53:32 volumio volumio[3807]: info: [LastFM] extended logging: false Dec 03 00:53:32 volumio volumio[3807]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:32 volumio volumio[3807]: info: [LastFM] Left init routine Dec 03 00:53:32 volumio volumio[3807]: info: [LastFM] Socket already connected: false Dec 03 00:53:32 volumio volumio[3807]: info: Volumio Calling Home Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:32 volumio volumio[3807]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:34 volumio volumio-remote-updater[602]: [2025-12-03 00:53:34] [connect] Successful connection Dec 03 00:53:34 volumio sudo[3909]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:34 volumio sudo[3909]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:34 volumio sudo[3905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:34 volumio sudo[3908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:34 volumio sudo[3905]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:34 volumio sudo[3908]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:34 volumio volumio[3807]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:34 volumio sudo[3904]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:34 volumio sudo[3904]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:34 volumio sudo[3908]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:34 volumio sudo[3904]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:34 volumio volumio[3807]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:34 volumio volumio[3807]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:34 volumio volumio[3807]: errno: -16, Dec 03 00:53:34 volumio volumio[3807]: code: 'EBUSY', Dec 03 00:53:34 volumio volumio[3807]: syscall: 'getaddrinfo', Dec 03 00:53:34 volumio volumio[3807]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:34 volumio volumio[3807]: } Dec 03 00:53:34 volumio volumio[3807]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:34 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:34 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:34 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:34 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:34 volumio sudo[3967]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:34 volumio sudo[3967]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:34 volumio sudo[3967]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:35 volumio sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:35 volumio sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:35 volumio sudo[3972]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:35 volumio volumio-remote-updater[602]: [2025-12-03 00:53:35] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Dec 03 00:53:35 volumio volumio-remote-updater[602]: [2025-12-03 00:53:35] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Dec 03 00:53:35 volumio volumio-remote-updater[602]: [2025-12-03 00:53:35] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 03 00:53:35 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:35 volumio sudo[3847]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:35 volumio sudo[3909]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:35 volumio sudo[3905]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:35 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:35 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:35 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:35 volumio mpd[3970]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:35 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:35 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 16. Dec 03 00:53:35 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:35 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:35 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:35 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:35 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:36 volumio volumio[3987]: info: ------------------------------------------- Dec 03 00:53:36 volumio volumio[3987]: info: ----- Volumio3 ---- Dec 03 00:53:36 volumio volumio[3987]: info: ------------------------------------------- Dec 03 00:53:36 volumio volumio[3987]: info: ----- System startup ---- Dec 03 00:53:36 volumio volumio[3987]: info: ------------------------------------------- Dec 03 00:53:36 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:36 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:36 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:37 volumio volumio[3987]: info: MYVOLUMIO Environment detected Dec 03 00:53:37 volumio volumio[3987]: info: Plugin folders cleanup Dec 03 00:53:37 volumio volumio[3987]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category audio_interface Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category miscellanea Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category music_service Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category plugins.json Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category system_controller Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category user_interface Dec 03 00:53:37 volumio volumio[3987]: info: Scanning into folder /data/plugins/ Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category music_service Dec 03 00:53:37 volumio volumio[3987]: info: Scanning category user_interface Dec 03 00:53:37 volumio volumio[3987]: info: Plugin folders cleanup completed Dec 03 00:53:37 volumio volumio[3987]: info: ------------------------------------------- Dec 03 00:53:37 volumio volumio[3987]: info: ----- Core plugins startup ---- Dec 03 00:53:37 volumio volumio[3987]: info: ------------------------------------------- Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:37 volumio volumio[3987]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:37 volumio volumio[3987]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:37 volumio volumio[3987]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "system"... Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "appearance"... Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "network"... Dec 03 00:53:37 volumio volumio[3987]: info: Refreshing Cached IP Addresses Dec 03 00:53:37 volumio sudo[4019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:37 volumio sudo[4019]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:37 volumio sudo[4019]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:37 volumio sudo[4021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:37 volumio sudo[4021]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:37 volumio sudo[4021]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "services"... Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "alsa_controller"... Dec 03 00:53:37 volumio sudo[4030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:37 volumio sudo[4030]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:37 volumio volumio[3987]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:37 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "wizard"... Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "networkfs"... Dec 03 00:53:37 volumio volumio[3987]: info: Starting Udev Watcher for removable devices Dec 03 00:53:37 volumio volumio[3987]: info: Ignoring mount for partition: boot Dec 03 00:53:37 volumio volumio[3987]: info: Ignoring mount for partition: volumio Dec 03 00:53:37 volumio volumio[3987]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:37 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:37 volumio volumio[3987]: info: Plugin upnp is not enabled Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "my_music"... Dec 03 00:53:37 volumio volumio[3987]: info: Loading plugin "mpd"... Dec 03 00:53:38 volumio volumio[3987]: info: Plugin upnp_browser is not enabled Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "alarm-clock"... Dec 03 00:53:38 volumio volumio[3987]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "last_100"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "webradio"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:38 volumio volumio[3987]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** For more information see Dec 03 00:53:38 volumio node[3987]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:38 volumio volumio[3987]: *** WARNING *** For more information see Dec 03 00:53:38 volumio node[3987]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:38 volumio node[3987]: *** WARNING *** For more information see Dec 03 00:53:38 volumio node[3987]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:38 volumio node[3987]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:38 volumio node[3987]: *** WARNING *** For more information see Dec 03 00:53:38 volumio volumio[3987]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:38 volumio volumio[3987]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:38 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "soundcloud"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "outputs"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "albumart"... Dec 03 00:53:38 volumio volumio[3987]: info: Plugin example_plugin is not enabled Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "inputs"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "updater_comm"... Dec 03 00:53:38 volumio volumio[3987]: info: Plugin mpdemulation is not enabled Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "rest_api"... Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "websocket"... Dec 03 00:53:38 volumio volumio[3987]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:38 volumio volumio[3987]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:38 volumio volumio[3987]: info: Loading plugin "lastfm"... Dec 03 00:53:38 volumio volumio[3987]: Forking 3 albumart workers Dec 03 00:53:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:38 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:39 volumio volumio[3987]: info: Loading i18n strings for locale en Dec 03 00:53:39 volumio volumio[3987]: Updating browse sources language Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: Express server listening on port 3000 Dec 03 00:53:39 volumio volumio[3987]: [Metrics] WebUI: 2s 789.86ms Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::pushState Dec 03 00:53:39 volumio volumio[3987]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:39 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:39 volumio volumio[3987]: Starting albumart workers Dec 03 00:53:39 volumio volumio[3987]: Starting albumart workers Dec 03 00:53:39 volumio volumio[3987]: info: Reloading queue from file Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::pushState Dec 03 00:53:39 volumio volumio[3987]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::setRandom false Dec 03 00:53:39 volumio volumio[3987]: info: CoreStateMachine::pushState Dec 03 00:53:39 volumio volumio[3987]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:39 volumio volumio[3987]: info: Setting Device type: Raspberry PI Dec 03 00:53:39 volumio volumio[3987]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:39 volumio volumio[3987]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:39 volumio volumio[3987]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:39 volumio volumio[3987]: info: Completed loading Core Plugins Dec 03 00:53:39 volumio volumio[3987]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:39 volumio volumio[3987]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:39 volumio volumio[3987]: info: Output device has changed, restarting MPD Dec 03 00:53:39 volumio volumio[3987]: info: ___________ START PLUGINS ___________ Dec 03 00:53:39 volumio volumio[3987]: Starting albumart workers Dec 03 00:53:39 volumio volumio[3987]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:39 volumio volumio[3987]: info: Creating MPD Configuration file Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:39 volumio volumio[3987]: info: [1764723219413] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:39 volumio volumio[3987]: info: [1764723219418] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:39 volumio volumio[3987]: info: Initializing BBC Radios Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:39 volumio volumio[3987]: info: [1764723219519] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:39 volumio volumio[3987]: Cannot find translation for source SoundCloud Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:39 volumio volumio[3987]: info: [1764723219544] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:39 volumio volumio[3987]: Cannot find translation for source SoundCloud Dec 03 00:53:39 volumio volumio[3987]: Cannot find translation for source LastFM Dec 03 00:53:39 volumio volumio[3987]: info: [LastFM] scrobbler initiated! Dec 03 00:53:39 volumio volumio[3987]: info: [LastFM] extended logging: false Dec 03 00:53:39 volumio volumio[3987]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:39 volumio volumio[3987]: info: [LastFM] Left init routine Dec 03 00:53:39 volumio volumio[3987]: info: [LastFM] Socket already connected: false Dec 03 00:53:39 volumio volumio[3987]: info: Volumio Calling Home Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:39 volumio volumio[3987]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:41 volumio sudo[4085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:41 volumio volumio[3987]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:41 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Dec 03 00:53:41 volumio sudo[4085]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:41 volumio sudo[4089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:41 volumio sudo[4089]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:41 volumio sudo[4083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:41 volumio sudo[4083]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:41 volumio sudo[4087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:41 volumio sudo[4087]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:41 volumio sudo[4083]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:41 volumio volumio[3987]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:41 volumio volumio[3987]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:41 volumio volumio[3987]: errno: -16, Dec 03 00:53:41 volumio volumio[3987]: code: 'EBUSY', Dec 03 00:53:41 volumio volumio[3987]: syscall: 'getaddrinfo', Dec 03 00:53:41 volumio volumio[3987]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:41 volumio volumio[3987]: } Dec 03 00:53:41 volumio volumio[3987]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:41 volumio sudo[4087]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:41 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:41 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:41 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:41 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:41 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:41 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:41 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:41 volumio sudo[4146]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:41 volumio sudo[4146]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:41 volumio sudo[4146]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:42 volumio sudo[4156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:42 volumio sudo[4156]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:42 volumio sudo[4156]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:42 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:42 volumio sudo[4030]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:42 volumio sudo[4085]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:42 volumio sudo[4089]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:42 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:42 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:42 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:42 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:42 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 17. Dec 03 00:53:42 volumio mpd[4154]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:42 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:42 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:42 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:42 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:42 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:42 volumio volumio-remote-updater[602]: [2025-12-03 00:53:42] [info] asio async_connect error: system:111 (Connection refused) Dec 03 00:53:42 volumio volumio-remote-updater[602]: [2025-12-03 00:53:42] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 03 00:53:42 volumio volumio-remote-updater[602]: [2025-12-03 00:53:42] [error] handle_connect error: Underlying Transport Error Dec 03 00:53:43 volumio volumio[4172]: info: ------------------------------------------- Dec 03 00:53:43 volumio volumio[4172]: info: ----- Volumio3 ---- Dec 03 00:53:43 volumio volumio[4172]: info: ------------------------------------------- Dec 03 00:53:43 volumio volumio[4172]: info: ----- System startup ---- Dec 03 00:53:43 volumio volumio[4172]: info: ------------------------------------------- Dec 03 00:53:43 volumio volumio[4172]: info: MYVOLUMIO Environment detected Dec 03 00:53:43 volumio volumio[4172]: info: Plugin folders cleanup Dec 03 00:53:43 volumio volumio[4172]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category audio_interface Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category miscellanea Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category music_service Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category plugins.json Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category system_controller Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category user_interface Dec 03 00:53:43 volumio volumio[4172]: info: Scanning into folder /data/plugins/ Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category music_service Dec 03 00:53:43 volumio volumio[4172]: info: Scanning category user_interface Dec 03 00:53:43 volumio volumio[4172]: info: Plugin folders cleanup completed Dec 03 00:53:43 volumio volumio[4172]: info: ------------------------------------------- Dec 03 00:53:43 volumio volumio[4172]: info: ----- Core plugins startup ---- Dec 03 00:53:43 volumio volumio[4172]: info: ------------------------------------------- Dec 03 00:53:43 volumio volumio[4172]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:43 volumio volumio[4172]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:43 volumio volumio[4172]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:43 volumio volumio[4172]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:43 volumio volumio[4172]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:43 volumio volumio[4172]: info: Loading plugin "system"... Dec 03 00:53:43 volumio volumio[4172]: info: Loading plugin "appearance"... Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "network"... Dec 03 00:53:44 volumio volumio[4172]: info: Refreshing Cached IP Addresses Dec 03 00:53:44 volumio sudo[4198]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:44 volumio sudo[4198]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:44 volumio sudo[4198]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:44 volumio sudo[4200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:44 volumio sudo[4200]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:44 volumio sudo[4200]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "services"... Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "alsa_controller"... Dec 03 00:53:44 volumio sudo[4209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:44 volumio sudo[4209]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:44 volumio volumio[4172]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:44 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "wizard"... Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "networkfs"... Dec 03 00:53:44 volumio volumio[4172]: info: Starting Udev Watcher for removable devices Dec 03 00:53:44 volumio volumio[4172]: info: Ignoring mount for partition: boot Dec 03 00:53:44 volumio volumio[4172]: info: Ignoring mount for partition: volumio Dec 03 00:53:44 volumio volumio[4172]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:44 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:44 volumio volumio[4172]: info: Plugin upnp is not enabled Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "my_music"... Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "mpd"... Dec 03 00:53:44 volumio volumio[4172]: info: Plugin upnp_browser is not enabled Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "alarm-clock"... Dec 03 00:53:44 volumio volumio[4172]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "last_100"... Dec 03 00:53:44 volumio volumio[4172]: info: Loading plugin "webradio"... Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:45 volumio volumio[4172]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:45 volumio node[4172]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** For more information see Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:45 volumio volumio[4172]: *** WARNING *** For more information see Dec 03 00:53:45 volumio node[4172]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:45 volumio node[4172]: *** WARNING *** For more information see Dec 03 00:53:45 volumio node[4172]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:45 volumio node[4172]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:45 volumio node[4172]: *** WARNING *** For more information see Dec 03 00:53:45 volumio volumio[4172]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:45 volumio volumio[4172]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:45 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "soundcloud"... Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "outputs"... Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "albumart"... Dec 03 00:53:45 volumio volumio[4172]: info: Plugin example_plugin is not enabled Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "inputs"... Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "updater_comm"... Dec 03 00:53:45 volumio volumio[4172]: info: Plugin mpdemulation is not enabled Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "rest_api"... Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "websocket"... Dec 03 00:53:45 volumio volumio[4172]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:45 volumio volumio[4172]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:45 volumio volumio[4172]: info: Loading plugin "lastfm"... Dec 03 00:53:45 volumio volumio[4172]: Forking 3 albumart workers Dec 03 00:53:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:45 volumio volumio[4172]: info: Loading i18n strings for locale en Dec 03 00:53:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:45 volumio volumio[4172]: Updating browse sources language Dec 03 00:53:45 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:45 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: Express server listening on port 3000 Dec 03 00:53:46 volumio volumio[4172]: [Metrics] WebUI: 2s 843.91ms Dec 03 00:53:46 volumio volumio[4172]: Starting albumart workers Dec 03 00:53:46 volumio volumio[4172]: Starting albumart workers Dec 03 00:53:46 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::pushState Dec 03 00:53:46 volumio volumio[4172]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: Starting albumart workers Dec 03 00:53:46 volumio volumio[4172]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:46 volumio volumio[4172]: info: Reloading queue from file Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::pushState Dec 03 00:53:46 volumio volumio[4172]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::setRandom false Dec 03 00:53:46 volumio volumio[4172]: info: CoreStateMachine::pushState Dec 03 00:53:46 volumio volumio[4172]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:46 volumio volumio[4172]: info: Setting Device type: Raspberry PI Dec 03 00:53:46 volumio volumio[4172]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:53:46 volumio volumio[4172]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:53:46 volumio volumio[4172]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:53:46 volumio volumio[4172]: info: Completed loading Core Plugins Dec 03 00:53:46 volumio volumio[4172]: info: Preparing to generate the ALSA configuration file Dec 03 00:53:46 volumio volumio[4172]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:53:46 volumio volumio[4172]: info: Output device has changed, restarting MPD Dec 03 00:53:46 volumio volumio[4172]: info: ___________ START PLUGINS ___________ Dec 03 00:53:46 volumio volumio[4172]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:53:46 volumio volumio[4172]: info: Creating MPD Configuration file Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:46 volumio volumio[4172]: info: [1764723226306] CoreMusicLibrary::Adding element Last_100 Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:46 volumio volumio[4172]: info: [1764723226309] CoreMusicLibrary::Adding element Webradio Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:46 volumio volumio[4172]: info: Initializing BBC Radios Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:46 volumio volumio[4172]: info: [1764723226426] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:46 volumio volumio[4172]: Cannot find translation for source SoundCloud Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:53:46 volumio volumio[4172]: info: [1764723226435] CoreMusicLibrary::Adding element LastFM Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:46 volumio volumio[4172]: Cannot find translation for source SoundCloud Dec 03 00:53:46 volumio volumio[4172]: Cannot find translation for source LastFM Dec 03 00:53:46 volumio volumio[4172]: info: [LastFM] scrobbler initiated! Dec 03 00:53:46 volumio volumio[4172]: info: [LastFM] extended logging: false Dec 03 00:53:46 volumio volumio[4172]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:53:46 volumio volumio[4172]: info: [LastFM] Left init routine Dec 03 00:53:46 volumio volumio[4172]: info: [LastFM] Socket already connected: false Dec 03 00:53:46 volumio volumio[4172]: info: Volumio Calling Home Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:46 volumio volumio[4172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:46 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:46 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:46 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:48 volumio sudo[4267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:48 volumio sudo[4267]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio sudo[4265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:48 volumio sudo[4263]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:53:48 volumio sudo[4263]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio sudo[4265]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio volumio[4172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:48 volumio sudo[4265]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:48 volumio volumio[4172]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:53:48 volumio volumio[4172]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:53:48 volumio volumio[4172]: errno: -16, Dec 03 00:53:48 volumio volumio[4172]: code: 'EBUSY', Dec 03 00:53:48 volumio volumio[4172]: syscall: 'getaddrinfo', Dec 03 00:53:48 volumio volumio[4172]: hostname: 'ws.audioscrobbler.com' Dec 03 00:53:48 volumio volumio[4172]: } Dec 03 00:53:48 volumio volumio[4172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:53:48 volumio sudo[4261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:53:48 volumio sudo[4261]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:53:48 volumio sudo[4261]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:48 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:53:48 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:53:48 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:53:48 volumio sudo[4329]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:53:48 volumio sudo[4329]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio sudo[4329]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:48 volumio sudo[4334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:52 Dec 03 00:53:48 volumio sudo[4334]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:48 volumio sudo[4334]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:53:49 volumio sudo[4209]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:49 volumio sudo[4267]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:49 volumio sudo[4263]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:53:49 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:49 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:49 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:53:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 18. Dec 03 00:53:49 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:53:49 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:53:49 volumio systemd[1]: Started dynamicswap service. Dec 03 00:53:49 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:53:49 volumio mpd[4332]: Dec 03 00:53 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:53:49 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:53:50 volumio volumio[4347]: info: ------------------------------------------- Dec 03 00:53:50 volumio volumio[4347]: info: ----- Volumio3 ---- Dec 03 00:53:50 volumio volumio[4347]: info: ------------------------------------------- Dec 03 00:53:50 volumio volumio[4347]: info: ----- System startup ---- Dec 03 00:53:50 volumio volumio[4347]: info: ------------------------------------------- Dec 03 00:53:50 volumio volumio[4347]: info: MYVOLUMIO Environment detected Dec 03 00:53:50 volumio volumio[4347]: info: Plugin folders cleanup Dec 03 00:53:50 volumio volumio[4347]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category audio_interface Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category miscellanea Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category music_service Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category plugins.json Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category system_controller Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category user_interface Dec 03 00:53:50 volumio volumio[4347]: info: Scanning into folder /data/plugins/ Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category music_service Dec 03 00:53:50 volumio volumio[4347]: info: Scanning category user_interface Dec 03 00:53:50 volumio volumio[4347]: info: Plugin folders cleanup completed Dec 03 00:53:50 volumio volumio[4347]: info: ------------------------------------------- Dec 03 00:53:50 volumio volumio[4347]: info: ----- Core plugins startup ---- Dec 03 00:53:50 volumio volumio[4347]: info: ------------------------------------------- Dec 03 00:53:50 volumio volumio[4347]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:53:50 volumio volumio[4347]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:53:50 volumio volumio[4347]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:53:50 volumio volumio[4347]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:53:50 volumio volumio[4347]: info: Loading plugins from folder /data/plugins/ Dec 03 00:53:50 volumio volumio[4347]: info: Loading plugin "system"... Dec 03 00:53:50 volumio volumio[4347]: info: Loading plugin "appearance"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "network"... Dec 03 00:53:51 volumio volumio[4347]: info: Refreshing Cached IP Addresses Dec 03 00:53:51 volumio sudo[4376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:53:51 volumio sudo[4376]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:51 volumio sudo[4376]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:51 volumio sudo[4378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:53:51 volumio sudo[4378]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:51 volumio sudo[4378]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "services"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "alsa_controller"... Dec 03 00:53:51 volumio sudo[4387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:53:51 volumio sudo[4387]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:51 volumio sudo[4387]: pam_unix(sudo:session): session closed for user root Dec 03 00:53:51 volumio volumio[4347]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:53:51 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "wizard"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "networkfs"... Dec 03 00:53:51 volumio volumio[4347]: info: Starting Udev Watcher for removable devices Dec 03 00:53:51 volumio volumio[4347]: info: Ignoring mount for partition: boot Dec 03 00:53:51 volumio volumio[4347]: info: Ignoring mount for partition: volumio Dec 03 00:53:51 volumio volumio[4347]: info: Ignoring mount for partition: volumio_data Dec 03 00:53:51 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:53:51 volumio volumio[4347]: info: Plugin upnp is not enabled Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "my_music"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "mpd"... Dec 03 00:53:51 volumio volumio[4347]: info: Plugin upnp_browser is not enabled Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "alarm-clock"... Dec 03 00:53:51 volumio volumio[4347]: info: Plugin airplay_emulation is not enabled Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "last_100"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "webradio"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "i2s_dacs"... Dec 03 00:53:51 volumio volumio[4347]: info: I2S DAC not set, start Auto-detection Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "volumiodiscovery"... Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:51 volumio node[4347]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** For more information see Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:51 volumio volumio[4347]: *** WARNING *** For more information see Dec 03 00:53:51 volumio node[4347]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:51 volumio node[4347]: *** WARNING *** For more information see Dec 03 00:53:51 volumio node[4347]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:53:51 volumio node[4347]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:53:51 volumio node[4347]: *** WARNING *** For more information see Dec 03 00:53:51 volumio volumio[4347]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:53:51 volumio volumio[4347]: info: Discovery: Started advertising with name: Volumio Dec 03 00:53:51 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "soundcloud"... Dec 03 00:53:51 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:51 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:51 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "outputs"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "albumart"... Dec 03 00:53:51 volumio volumio[4347]: info: Plugin example_plugin is not enabled Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "inputs"... Dec 03 00:53:51 volumio volumio[4347]: info: Loading plugin "updater_comm"... Dec 03 00:53:52 volumio volumio[4347]: info: Plugin mpdemulation is not enabled Dec 03 00:53:52 volumio volumio[4347]: info: Loading plugin "rest_api"... Dec 03 00:53:52 volumio volumio[4347]: info: Loading plugin "websocket"... Dec 03 00:53:52 volumio volumio[4347]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:53:52 volumio volumio[4347]: info: Plugin volspotconnect2 is not enabled Dec 03 00:53:52 volumio volumio[4347]: info: Loading plugin "lastfm"... Dec 03 00:53:52 volumio volumio[4347]: Forking 3 albumart workers Dec 03 00:53:52 volumio volumio[4347]: info: Loading i18n strings for locale en Dec 03 00:53:52 volumio volumio[4347]: Updating browse sources language Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:53:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::initPlayerControls Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: Express server listening on port 3000 Dec 03 00:53:52 volumio volumio[4347]: [Metrics] WebUI: 2s 740.24ms Dec 03 00:53:52 volumio volumio[4347]: info: CoreStateMachine::resetVolumioState Dec 03 00:53:52 volumio volumio[4347]: info: CoreStateMachine::getcurrentVolume Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:53:52 volumio volumio[4347]: info: CoreStateMachine::pushState Dec 03 00:53:52 volumio volumio[4347]: info: CorePlayQueue::getTrack 0 Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:53:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::volumioPushState Dec 03 00:53:52 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:53:52 volumio volumio[4347]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:53:52 volumio volumio[4347]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Dec 03 00:53:52 volumio volumio[4347]: wlan0 Interface doesn't support scanning : Device or resource busy Dec 03 00:53:52 volumio volumio[4347]: info: Cannot use regular scanning, forcing with ap-force Dec 03 00:53:52 volumio volumio[4347]: Starting albumart workers Dec 03 00:53:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:52 volumio sudo[4440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Dec 03 00:53:52 volumio sudo[4440]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:53:52 volumio volumio[4347]: Starting albumart workers Dec 03 00:53:52 volumio volumio[4347]: Starting albumart workers Dec 03 00:53:52 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:53:55 volumio volumio-remote-updater[602]: [2025-12-03 00:53:55] [connect] Successful connection Dec 03 00:53:55 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Dec 03 00:53:56 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:53:56 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:53:56 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:53:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:53:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:53:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:53:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:54:01 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:54:01 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:54:01 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:54:02 volumio sudo[4440]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:02 volumio volumio[4347]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 03 00:54:02 volumio volumio[4347]: info: Reloading queue from file Dec 03 00:54:02 volumio volumio[4347]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:54:02 volumio volumio[4347]: info: CoreStateMachine::pushState Dec 03 00:54:02 volumio volumio[4347]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:02 volumio volumio[4347]: info: CoreStateMachine::setRandom false Dec 03 00:54:02 volumio volumio[4347]: info: CoreStateMachine::pushState Dec 03 00:54:02 volumio volumio[4347]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:02 volumio volumio[4347]: info: Setting Device type: Raspberry PI Dec 03 00:54:02 volumio volumio[4347]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:54:02 volumio volumio[4347]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:54:02 volumio volumio[4347]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:54:02 volumio volumio[4347]: info: Completed loading Core Plugins Dec 03 00:54:02 volumio volumio[4347]: info: Preparing to generate the ALSA configuration file Dec 03 00:54:02 volumio volumio[4347]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:54:02 volumio volumio[4347]: info: Output device has changed, restarting MPD Dec 03 00:54:02 volumio sudo[4533]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:02 volumio sudo[4533]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio volumio[4347]: info: ___________ START PLUGINS ___________ Dec 03 00:54:02 volumio volumio[4347]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:54:02 volumio volumio[4347]: info: Creating MPD Configuration file Dec 03 00:54:02 volumio sudo[4533]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:02 volumio sudo[4536]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:02 volumio sudo[4536]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:54:02 volumio sudo[4539]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:02 volumio sudo[4539]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:02 volumio sudo[4539]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:02 volumio volumio[4347]: info: [1764723242321] CoreMusicLibrary::Adding element Last_100 Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:02 volumio volumio[4347]: info: [1764723242327] CoreMusicLibrary::Adding element Webradio Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:02 volumio sudo[4541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:02 volumio sudo[4541]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio volumio[4347]: info: Initializing BBC Radios Dec 03 00:54:02 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:54:02 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:02 volumio volumio[4347]: info: [1764723242386] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:02 volumio volumio[4347]: Cannot find translation for source SoundCloud Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:02 volumio volumio[4347]: info: [1764723242389] CoreMusicLibrary::Adding element LastFM Dec 03 00:54:02 volumio volumio[4347]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:02 volumio volumio[4347]: Cannot find translation for source SoundCloud Dec 03 00:54:02 volumio volumio[4347]: Cannot find translation for source LastFM Dec 03 00:54:02 volumio volumio[4347]: info: [LastFM] scrobbler initiated! Dec 03 00:54:02 volumio volumio[4347]: info: [LastFM] extended logging: false Dec 03 00:54:02 volumio volumio[4347]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:54:02 volumio volumio[4347]: info: [LastFM] Left init routine Dec 03 00:54:02 volumio volumio[4347]: info: [LastFM] Socket already connected: true Dec 03 00:54:02 volumio volumio[4347]: info: Volumio Calling Home Dec 03 00:54:02 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:54:02 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 03 00:54:02 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:54:02 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:54:02 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:54:02 volumio volumio[4347]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:02 volumio volumio[4347]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:54:02 volumio volumio[4347]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:54:02 volumio volumio[4347]: errno: -16, Dec 03 00:54:02 volumio volumio[4347]: code: 'EBUSY', Dec 03 00:54:02 volumio volumio[4347]: syscall: 'getaddrinfo', Dec 03 00:54:02 volumio volumio[4347]: hostname: 'ws.audioscrobbler.com' Dec 03 00:54:02 volumio volumio[4347]: } Dec 03 00:54:02 volumio volumio[4347]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:02 volumio sudo[4558]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:54:02 volumio sudo[4558]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio sudo[4558]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:02 volumio sudo[4570]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:53 Dec 03 00:54:02 volumio sudo[4570]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:02 volumio sudo[4570]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:03 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:54:03 volumio sudo[4536]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:03 volumio sudo[4541]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:03 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:54:03 volumio systemd[1]: Started dynamicswap service. Dec 03 00:54:03 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:54:03 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:54:03 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 19. Dec 03 00:54:03 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:54:03 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:54:03 volumio systemd[1]: Started dynamicswap service. Dec 03 00:54:03 volumio mpd[4562]: Dec 03 00:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:54:03 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:54:03 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:54:04 volumio volumio[4583]: info: ------------------------------------------- Dec 03 00:54:04 volumio volumio[4583]: info: ----- Volumio3 ---- Dec 03 00:54:04 volumio volumio[4583]: info: ------------------------------------------- Dec 03 00:54:04 volumio volumio[4583]: info: ----- System startup ---- Dec 03 00:54:04 volumio volumio[4583]: info: ------------------------------------------- Dec 03 00:54:04 volumio volumio[4583]: info: MYVOLUMIO Environment detected Dec 03 00:54:04 volumio volumio[4583]: info: Plugin folders cleanup Dec 03 00:54:04 volumio volumio[4583]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category audio_interface Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category miscellanea Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category music_service Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category plugins.json Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category system_controller Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category user_interface Dec 03 00:54:04 volumio volumio[4583]: info: Scanning into folder /data/plugins/ Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category music_service Dec 03 00:54:04 volumio volumio[4583]: info: Scanning category user_interface Dec 03 00:54:04 volumio volumio[4583]: info: Plugin folders cleanup completed Dec 03 00:54:04 volumio volumio[4583]: info: ------------------------------------------- Dec 03 00:54:04 volumio volumio[4583]: info: ----- Core plugins startup ---- Dec 03 00:54:04 volumio volumio[4583]: info: ------------------------------------------- Dec 03 00:54:04 volumio volumio[4583]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:54:04 volumio volumio[4583]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:54:04 volumio volumio[4583]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:54:04 volumio volumio[4583]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:54:04 volumio volumio[4583]: info: Loading plugins from folder /data/plugins/ Dec 03 00:54:04 volumio volumio[4583]: info: Loading plugin "system"... Dec 03 00:54:04 volumio volumio[4583]: info: Loading plugin "appearance"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "network"... Dec 03 00:54:05 volumio volumio[4583]: info: Refreshing Cached IP Addresses Dec 03 00:54:05 volumio sudo[4612]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:54:05 volumio sudo[4612]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:05 volumio sudo[4612]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:05 volumio sudo[4614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:54:05 volumio sudo[4614]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:05 volumio sudo[4614]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "services"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "alsa_controller"... Dec 03 00:54:05 volumio sudo[4625]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:54:05 volumio sudo[4625]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:05 volumio volumio[4583]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:54:05 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "wizard"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "networkfs"... Dec 03 00:54:05 volumio volumio[4583]: info: Starting Udev Watcher for removable devices Dec 03 00:54:05 volumio volumio[4583]: info: Ignoring mount for partition: boot Dec 03 00:54:05 volumio volumio[4583]: info: Ignoring mount for partition: volumio Dec 03 00:54:05 volumio volumio[4583]: info: Ignoring mount for partition: volumio_data Dec 03 00:54:05 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:54:05 volumio volumio[4583]: info: Plugin upnp is not enabled Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "my_music"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "mpd"... Dec 03 00:54:05 volumio volumio[4583]: info: Plugin upnp_browser is not enabled Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "alarm-clock"... Dec 03 00:54:05 volumio volumio[4583]: info: Plugin airplay_emulation is not enabled Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "last_100"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "webradio"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "i2s_dacs"... Dec 03 00:54:05 volumio volumio[4583]: info: I2S DAC not set, start Auto-detection Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "volumiodiscovery"... Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** For more information see Dec 03 00:54:05 volumio node[4583]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:05 volumio volumio[4583]: *** WARNING *** For more information see Dec 03 00:54:05 volumio node[4583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:05 volumio node[4583]: *** WARNING *** For more information see Dec 03 00:54:05 volumio node[4583]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:05 volumio node[4583]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:05 volumio node[4583]: *** WARNING *** For more information see Dec 03 00:54:05 volumio volumio[4583]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:54:05 volumio volumio[4583]: info: Discovery: Started advertising with name: Volumio Dec 03 00:54:05 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "soundcloud"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "outputs"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "albumart"... Dec 03 00:54:05 volumio volumio[4583]: info: Plugin example_plugin is not enabled Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "inputs"... Dec 03 00:54:05 volumio volumio[4583]: info: Loading plugin "updater_comm"... Dec 03 00:54:06 volumio volumio[4583]: info: Plugin mpdemulation is not enabled Dec 03 00:54:06 volumio volumio[4583]: info: Loading plugin "rest_api"... Dec 03 00:54:06 volumio volumio[4583]: info: Loading plugin "websocket"... Dec 03 00:54:06 volumio volumio[4583]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:54:06 volumio volumio[4583]: info: Plugin volspotconnect2 is not enabled Dec 03 00:54:06 volumio volumio[4583]: info: Loading plugin "lastfm"... Dec 03 00:54:06 volumio volumio[4583]: Forking 3 albumart workers Dec 03 00:54:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Dec 03 00:54:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Dec 03 00:54:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Dec 03 00:54:06 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Dec 03 00:54:06 volumio volumio[4583]: info: Loading i18n strings for locale en Dec 03 00:54:06 volumio volumio[4583]: Updating browse sources language Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::initPlayerControls Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: Express server listening on port 3000 Dec 03 00:54:06 volumio volumio[4583]: [Metrics] WebUI: 2s 808.53ms Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::resetVolumioState Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::getcurrentVolume Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::pushState Dec 03 00:54:06 volumio volumio[4583]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:06 volumio volumio[4583]: Starting albumart workers Dec 03 00:54:06 volumio volumio[4583]: Starting albumart workers Dec 03 00:54:06 volumio volumio[4583]: Starting albumart workers Dec 03 00:54:06 volumio volumio[4583]: info: Reloading queue from file Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::pushState Dec 03 00:54:06 volumio volumio[4583]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::setRandom false Dec 03 00:54:06 volumio volumio[4583]: info: CoreStateMachine::pushState Dec 03 00:54:06 volumio volumio[4583]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:06 volumio volumio[4583]: info: Setting Device type: Raspberry PI Dec 03 00:54:06 volumio volumio[4583]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:54:06 volumio volumio[4583]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:54:06 volumio volumio[4583]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:54:06 volumio volumio[4583]: info: Completed loading Core Plugins Dec 03 00:54:06 volumio volumio[4583]: info: Preparing to generate the ALSA configuration file Dec 03 00:54:06 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:54:06 volumio volumio[4583]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:54:06 volumio volumio[4583]: info: Output device has changed, restarting MPD Dec 03 00:54:06 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:54:06 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:54:06 volumio volumio[4583]: info: ___________ START PLUGINS ___________ Dec 03 00:54:06 volumio volumio[4583]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:54:06 volumio volumio[4583]: info: Creating MPD Configuration file Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:06 volumio volumio[4583]: info: [1764723246976] CoreMusicLibrary::Adding element Last_100 Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:06 volumio volumio[4583]: info: [1764723246979] CoreMusicLibrary::Adding element Webradio Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:06 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:07 volumio volumio[4583]: info: Initializing BBC Radios Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:07 volumio volumio[4583]: info: [1764723247084] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:07 volumio volumio[4583]: Cannot find translation for source SoundCloud Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:07 volumio volumio[4583]: info: [1764723247090] CoreMusicLibrary::Adding element LastFM Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:07 volumio volumio[4583]: Cannot find translation for source SoundCloud Dec 03 00:54:07 volumio volumio[4583]: Cannot find translation for source LastFM Dec 03 00:54:07 volumio volumio[4583]: info: [LastFM] scrobbler initiated! Dec 03 00:54:07 volumio volumio[4583]: info: [LastFM] extended logging: false Dec 03 00:54:07 volumio volumio[4583]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:54:07 volumio volumio[4583]: info: [LastFM] Left init routine Dec 03 00:54:07 volumio volumio[4583]: info: [LastFM] Socket already connected: false Dec 03 00:54:07 volumio volumio[4583]: info: Volumio Calling Home Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:07 volumio volumio[4583]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:08 volumio sudo[4681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:08 volumio sudo[4679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:08 volumio sudo[4681]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:08 volumio wpa_supplicant[883]: wlan0: Trying to associate with SSID 'MamesNet' Dec 03 00:54:08 volumio sudo[4679]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:08 volumio sudo[4683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:08 volumio sudo[4683]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:08 volumio volumio[4583]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:08 volumio sudo[4685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:08 volumio sudo[4685]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:08 volumio sudo[4683]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:08 volumio sudo[4679]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:08 volumio volumio[4583]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:54:08 volumio volumio[4583]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:54:08 volumio volumio[4583]: errno: -16, Dec 03 00:54:08 volumio volumio[4583]: code: 'EBUSY', Dec 03 00:54:08 volumio volumio[4583]: syscall: 'getaddrinfo', Dec 03 00:54:08 volumio volumio[4583]: hostname: 'ws.audioscrobbler.com' Dec 03 00:54:08 volumio volumio[4583]: } Dec 03 00:54:08 volumio volumio[4583]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:08 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:54:09 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:54:09 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:54:09 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:54:09 volumio sudo[4743]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:54:09 volumio sudo[4743]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:09 volumio sudo[4743]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: carrier acquired Dec 03 00:54:09 volumio wpa_supplicant[883]: wlan0: Associated with 58:96:71:f8:80:ea Dec 03 00:54:09 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-CONNECTED - Connection to 58:96:71:f8:80:ea completed [id=0 id_str=] Dec 03 00:54:09 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Dec 03 00:54:09 volumio wpa_supplicant[883]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Dec 03 00:54:09 volumio dhcpcd[895]: DUID 00:01:00:01:29:81:35:4e:e4:5f:01:95:b6:e4 Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: IAID 01:95:b6:e4 Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: adding address fe80::d306:9396:7f4d:6d0e Dec 03 00:54:09 volumio dhcpcd[895]: ipv6_addaddr1: Permission denied Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: carrier lost Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: carrier acquired Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: connected to Access Point `MamesNet' Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: IAID 01:95:b6:e4 Dec 03 00:54:09 volumio sudo[4625]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: rebinding lease of 192.168.1.164 Dec 03 00:54:09 volumio sudo[4761]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:53 Dec 03 00:54:09 volumio sudo[4761]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:09 volumio sudo[4761]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:09 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Dec 03 00:54:09 volumio sudo[4681]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:09 volumio dhcpcd[895]: wlan0: probing address 192.168.1.164/24 Dec 03 00:54:09 volumio sudo[4685]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:09 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Dec 03 00:54:09 volumio systemd[1]: Started dynamicswap service. Dec 03 00:54:09 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:54:09 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Dec 03 00:54:09 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 20. Dec 03 00:54:09 volumio systemd[1]: Stopped Volumio Backend Module. Dec 03 00:54:09 volumio systemd[1]: Started Volumio Backend Module. Dec 03 00:54:09 volumio systemd[1]: Started dynamicswap service. Dec 03 00:54:09 volumio systemd[1]: dynamicswap.service: Succeeded. Dec 03 00:54:09 volumio mpd[4746]: Dec 03 00:54 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 03 00:54:09 volumio systemd[1]: Started Music Player Daemon. Dec 03 00:54:10 volumio dhcpcd[895]: wlan0: soliciting an IPv6 router Dec 03 00:54:10 volumio volumio-remote-updater[602]: [2025-12-03 00:54:10] [info] asio async_connect error: system:111 (Connection refused) Dec 03 00:54:10 volumio volumio-remote-updater[602]: [2025-12-03 00:54:10] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Dec 03 00:54:10 volumio volumio-remote-updater[602]: [2025-12-03 00:54:10] [error] handle_connect error: Underlying Transport Error Dec 03 00:54:10 volumio volumio[4775]: info: ------------------------------------------- Dec 03 00:54:10 volumio volumio[4775]: info: ----- Volumio3 ---- Dec 03 00:54:10 volumio volumio[4775]: info: ------------------------------------------- Dec 03 00:54:10 volumio volumio[4775]: info: ----- System startup ---- Dec 03 00:54:10 volumio volumio[4775]: info: ------------------------------------------- Dec 03 00:54:11 volumio volumio[4775]: info: MYVOLUMIO Environment detected Dec 03 00:54:11 volumio volumio[4775]: info: Plugin folders cleanup Dec 03 00:54:11 volumio volumio[4775]: info: Scanning into folder /volumio/app/plugins/ Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category audio_interface Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category miscellanea Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category music_service Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category plugins.json Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category system_controller Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category user_interface Dec 03 00:54:11 volumio volumio[4775]: info: Scanning into folder /data/plugins/ Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category music_service Dec 03 00:54:11 volumio volumio[4775]: info: Scanning category user_interface Dec 03 00:54:11 volumio volumio[4775]: info: Plugin folders cleanup completed Dec 03 00:54:11 volumio volumio[4775]: info: ------------------------------------------- Dec 03 00:54:11 volumio volumio[4775]: info: ----- Core plugins startup ---- Dec 03 00:54:11 volumio volumio[4775]: info: ------------------------------------------- Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugins from folder /volumio/app/plugins/ Dec 03 00:54:11 volumio volumio[4775]: info: Adding plugin upnp to MyMusic Plugins Dec 03 00:54:11 volumio volumio[4775]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 03 00:54:11 volumio volumio[4775]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugins from folder /data/plugins/ Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "system"... Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "appearance"... Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "network"... Dec 03 00:54:11 volumio volumio[4775]: info: Refreshing Cached IP Addresses Dec 03 00:54:11 volumio sudo[4804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 03 00:54:11 volumio sudo[4804]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:11 volumio sudo[4804]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:11 volumio sudo[4806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 03 00:54:11 volumio sudo[4806]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:11 volumio sudo[4806]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "services"... Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "alsa_controller"... Dec 03 00:54:11 volumio sudo[4815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 03 00:54:11 volumio sudo[4815]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:11 volumio volumio[4775]: warn: Unable to locate the audio output device CA DacMagic 200M 2.0. Please configure a valid output device. Dec 03 00:54:11 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "wizard"... Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "networkfs"... Dec 03 00:54:11 volumio volumio[4775]: info: Starting Udev Watcher for removable devices Dec 03 00:54:11 volumio volumio[4775]: info: Ignoring mount for partition: boot Dec 03 00:54:11 volumio volumio[4775]: info: Ignoring mount for partition: volumio Dec 03 00:54:11 volumio volumio[4775]: info: Ignoring mount for partition: volumio_data Dec 03 00:54:11 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "volumio_command_line_client"... Dec 03 00:54:11 volumio volumio[4775]: info: Plugin upnp is not enabled Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "my_music"... Dec 03 00:54:11 volumio volumio-time-update[595]: volumio-time-update-util: Fetching time from Volumio... Dec 03 00:54:11 volumio volumio[4775]: info: Loading plugin "mpd"... Dec 03 00:54:11 volumio volumio-time-update[595]: volumio-time-update-util: Date not found in response Dec 03 00:54:11 volumio volumio-time-update[595]: volumio-time-update-util: Retrying in 5 seconds... Dec 03 00:54:12 volumio volumio[4775]: info: Plugin upnp_browser is not enabled Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "alarm-clock"... Dec 03 00:54:12 volumio volumio[4775]: info: Plugin airplay_emulation is not enabled Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "last_100"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "webradio"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "i2s_dacs"... Dec 03 00:54:12 volumio volumio[4775]: info: I2S DAC not set, start Auto-detection Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "volumiodiscovery"... Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** For more information see Dec 03 00:54:12 volumio node[4775]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:12 volumio volumio[4775]: *** WARNING *** For more information see Dec 03 00:54:12 volumio node[4775]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:12 volumio node[4775]: *** WARNING *** For more information see Dec 03 00:54:12 volumio node[4775]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 03 00:54:12 volumio node[4775]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 03 00:54:12 volumio node[4775]: *** WARNING *** For more information see Dec 03 00:54:12 volumio volumio[4775]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 03 00:54:12 volumio volumio[4775]: info: Discovery: Started advertising with name: Volumio Dec 03 00:54:12 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "soundcloud"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "outputs"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "albumart"... Dec 03 00:54:12 volumio volumio[4775]: info: Plugin example_plugin is not enabled Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "inputs"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "updater_comm"... Dec 03 00:54:12 volumio volumio[4775]: info: Plugin mpdemulation is not enabled Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "rest_api"... Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "websocket"... Dec 03 00:54:12 volumio volumio[4775]: info: Starting Socket.io Server version 2.3.0 Dec 03 00:54:12 volumio volumio[4775]: info: Plugin volspotconnect2 is not enabled Dec 03 00:54:12 volumio volumio[4775]: info: Loading plugin "lastfm"... Dec 03 00:54:12 volumio volumio[4775]: Forking 3 albumart workers Dec 03 00:54:13 volumio volumio[4775]: info: Loading i18n strings for locale en Dec 03 00:54:13 volumio volumio[4775]: Updating browse sources language Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::initPlayerControls Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: Express server listening on port 3000 Dec 03 00:54:13 volumio volumio[4775]: [Metrics] WebUI: 2s 817.79ms Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::resetVolumioState Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::getcurrentVolume Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioRetrievevolume Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::pushState Dec 03 00:54:13 volumio volumio[4775]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: Starting albumart workers Dec 03 00:54:13 volumio volumio[4775]: info: Volumio Network Manager: Network status updated: 0 Dec 03 00:54:13 volumio volumio[4775]: Starting albumart workers Dec 03 00:54:13 volumio volumio[4775]: Starting albumart workers Dec 03 00:54:13 volumio volumio[4775]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Dec 03 00:54:13 volumio volumio[4775]: info: Reloading queue from file Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::setRepeat false single undefined Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::pushState Dec 03 00:54:13 volumio volumio[4775]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::setRandom false Dec 03 00:54:13 volumio volumio[4775]: info: CoreStateMachine::pushState Dec 03 00:54:13 volumio volumio[4775]: info: CorePlayQueue::getTrack 0 Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioPushState Dec 03 00:54:13 volumio volumio[4775]: info: Setting Device type: Raspberry PI Dec 03 00:54:13 volumio volumio[4775]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 03 00:54:13 volumio volumio[4775]: info: USB Boot Capable - System SBC Revision found in cpuinfo: d03115 Dec 03 00:54:13 volumio volumio[4775]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 03 00:54:13 volumio volumio[4775]: info: Completed loading Core Plugins Dec 03 00:54:13 volumio volumio[4775]: info: Preparing to generate the ALSA configuration file Dec 03 00:54:13 volumio volumio[4775]: info: Asound.conf file unchanged, so no further update is needed Dec 03 00:54:13 volumio volumio[4775]: info: Output device has changed, restarting MPD Dec 03 00:54:13 volumio volumio[4775]: info: ___________ START PLUGINS ___________ Dec 03 00:54:13 volumio sudo[4872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:13 volumio sudo[4872]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:13 volumio volumio[4775]: info: ControllerMpd::onStart: Initializing MPD Dec 03 00:54:13 volumio volumio[4775]: info: Creating MPD Configuration file Dec 03 00:54:13 volumio sudo[4872]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:13 volumio sudo[4874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:13 volumio sudo[4874]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:13 volumio volumio[4775]: info: [1764723253619] CoreMusicLibrary::Adding element Last_100 Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:13 volumio volumio[4775]: info: [1764723253625] CoreMusicLibrary::Adding element Webradio Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:13 volumio systemd[1]: Stopping Music Player Daemon... Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:13 volumio sudo[4878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 03 00:54:13 volumio sudo[4878]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:13 volumio volumio[4775]: info: Initializing BBC Radios Dec 03 00:54:13 volumio sudo[4878]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:13 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:54:13 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:54:13 volumio sudo[4880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 03 00:54:13 volumio sudo[4880]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:13 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:54:13 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Dec 03 00:54:13 volumio systemd[1]: mpd.service: Succeeded. Dec 03 00:54:13 volumio systemd[1]: Stopped Music Player Daemon. Dec 03 00:54:13 volumio systemd[1]: Starting Music Player Daemon... Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:13 volumio volumio[4775]: info: [1764723253750] CoreMusicLibrary::Adding element SoundCloud Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:13 volumio volumio[4775]: Cannot find translation for source SoundCloud Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 03 00:54:13 volumio volumio[4775]: info: [1764723253758] CoreMusicLibrary::Adding element LastFM Dec 03 00:54:13 volumio volumio[4775]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 03 00:54:13 volumio volumio[4775]: Cannot find translation for source SoundCloud Dec 03 00:54:13 volumio volumio[4775]: Cannot find translation for source LastFM Dec 03 00:54:13 volumio volumio[4775]: info: [LastFM] scrobbler initiated! Dec 03 00:54:13 volumio volumio[4775]: info: [LastFM] extended logging: false Dec 03 00:54:13 volumio volumio[4775]: info: [LastFM] try scrobble stream/radio plays: true Dec 03 00:54:13 volumio volumio[4775]: info: [LastFM] Left init routine Dec 03 00:54:13 volumio volumio[4775]: info: [LastFM] Socket already connected: true Dec 03 00:54:13 volumio volumio[4775]: info: Volumio Calling Home Dec 03 00:54:13 volumio sudo[4903]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 03 00:54:13 volumio sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 03 00:54:13 volumio sudo[4903]: pam_unix(sudo:session): session closed for user root Dec 03 00:54:13 volumio volumio[4775]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:13 volumio volumio[4775]: Error: getaddrinfo EBUSY ws.audioscrobbler.com Dec 03 00:54:13 volumio volumio[4775]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:67:26) { Dec 03 00:54:13 volumio volumio[4775]: errno: -16, Dec 03 00:54:13 volumio volumio[4775]: code: 'EBUSY', Dec 03 00:54:13 volumio volumio[4775]: syscall: 'getaddrinfo', Dec 03 00:54:13 volumio volumio[4775]: hostname: 'ws.audioscrobbler.com' Dec 03 00:54:13 volumio volumio[4775]: } Dec 03 00:54:13 volumio volumio[4775]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 03 00:54:14 volumio dhcpcd[895]: wlan0: leased 192.168.1.164 for 86400 seconds Dec 03 00:54:14 volumio avahi-daemon[599]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.164. Dec 03 00:54:14 volumio dhcpcd[895]: wlan0: adding route to 192.168.1.0/24 Dec 03 00:54:14 volumio dhcpcd[895]: wlan0: adding default route via 192.168.1.1 Dec 03 00:54:14 volumio avahi-daemon[599]: New relevant interface wlan0.IPv4 for mDNS. Dec 03 00:54:14 volumio avahi-daemon[599]: Registering new address record for 192.168.1.164 on wlan0.IPv4. Dec 03 00:54:14 volumio sudo[4954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-03 00:53 Dec 03 00:54:14 volumio sudo[4954]: 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="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 06:58:02 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4ae6504ed6f22e44c01a6e93b16badbd"