-- Logs begin at Thu 2019-02-14 13:11:59 MSK, end at Thu 2026-02-26 20:09:27 MSK. -- Feb 26 20:08:02 usic volumio-remote-updater[1880]: [2026-02-26 20:08:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 26 20:08:02 usic volumio-remote-updater[1880]: [2026-02-26 20:08:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 26 20:08:02 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:08:02 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:08:02 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:02 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:02 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:08:02 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Feb 26 20:08:02 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:08:02 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:08:02 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:02 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:05 usic volumio[4772]: info: ------------------------------------------- Feb 26 20:08:05 usic volumio[4772]: info: ----- Volumio3 ---- Feb 26 20:08:05 usic volumio[4772]: info: ------------------------------------------- Feb 26 20:08:05 usic volumio[4772]: info: ----- System startup ---- Feb 26 20:08:05 usic volumio[4772]: info: ------------------------------------------- Feb 26 20:08:07 usic volumio[4772]: info: MYVOLUMIO Environment detected Feb 26 20:08:07 usic volumio-remote-updater[1880]: [2026-02-26 20:08:07] [connect] Successful connection Feb 26 20:08:07 usic volumio[4772]: info: Plugin folders cleanup Feb 26 20:08:07 usic volumio[4772]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:08:07 usic volumio[4772]: info: Scanning category audio_interface Feb 26 20:08:07 usic volumio[4772]: info: Scanning category miscellanea Feb 26 20:08:07 usic volumio[4772]: info: Scanning category music_service Feb 26 20:08:07 usic volumio[4772]: info: Scanning category plugins.json Feb 26 20:08:07 usic volumio[4772]: info: Scanning category system_controller Feb 26 20:08:07 usic volumio[4772]: info: Scanning category user_interface Feb 26 20:08:07 usic volumio[4772]: info: Scanning into folder /data/plugins/ Feb 26 20:08:07 usic volumio[4772]: info: Plugin folders cleanup completed Feb 26 20:08:07 usic volumio[4772]: info: ------------------------------------------- Feb 26 20:08:07 usic volumio[4772]: info: ----- Core plugins startup ---- Feb 26 20:08:07 usic volumio[4772]: info: ------------------------------------------- Feb 26 20:08:07 usic volumio[4772]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:08:07 usic volumio[4772]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:08:07 usic volumio[4772]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:08:07 usic volumio[4772]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:08:07 usic volumio[4772]: info: Loading plugins from folder /data/plugins/ Feb 26 20:08:07 usic volumio[4772]: info: Loading plugin "system"... Feb 26 20:08:07 usic volumio[4772]: info: Loading plugin "appearance"... Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "network"... Feb 26 20:08:09 usic volumio[4772]: info: Refreshing Cached IP Addresses Feb 26 20:08:09 usic sudo[4801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:08:09 usic sudo[4801]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:09 usic sudo[4801]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:09 usic sudo[4803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:08:09 usic sudo[4803]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "services"... Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "alsa_controller"... Feb 26 20:08:09 usic sudo[4803]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:09 usic sudo[4813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:08:09 usic sudo[4813]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:09 usic sudo[4813]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:09 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "wizard"... Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "networkfs"... Feb 26 20:08:09 usic volumio[4772]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:08:09 usic volumio[4772]: info: Starting Udev Watcher for removable devices Feb 26 20:08:09 usic volumio[4772]: info: Ignoring mount for partition: boot Feb 26 20:08:09 usic volumio[4772]: info: Ignoring mount for partition: volumio Feb 26 20:08:09 usic volumio[4772]: info: Ignoring mount for partition: volumio_data Feb 26 20:08:09 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:08:09 usic volumio[4772]: info: Plugin upnp is not enabled Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "my_music"... Feb 26 20:08:09 usic volumio[4772]: info: Loading plugin "mpd"... Feb 26 20:08:10 usic volumio[4772]: info: Plugin upnp_browser is not enabled Feb 26 20:08:10 usic volumio[4772]: info: Loading plugin "alarm-clock"... Feb 26 20:08:10 usic volumio[4772]: info: Plugin airplay_emulation is not enabled Feb 26 20:08:10 usic volumio[4772]: info: Loading plugin "last_100"... Feb 26 20:08:10 usic volumio[4772]: info: Loading plugin "webradio"... Feb 26 20:08:10 usic volumio[4772]: info: Loading plugin "i2s_dacs"... Feb 26 20:08:11 usic volumio[4772]: info: I2S DAC not set, start Auto-detection Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "volumiodiscovery"... Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:11 usic node[4772]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** For more information see Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:11 usic volumio[4772]: *** WARNING *** For more information see Feb 26 20:08:11 usic node[4772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:11 usic node[4772]: *** WARNING *** For more information see Feb 26 20:08:11 usic node[4772]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:11 usic node[4772]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:11 usic node[4772]: *** WARNING *** For more information see Feb 26 20:08:11 usic volumio[4772]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:08:11 usic volumio[4772]: info: Discovery: Started advertising with name: usic Feb 26 20:08:11 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "outputs"... Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "albumart"... Feb 26 20:08:11 usic volumio[4772]: info: Plugin example_plugin is not enabled Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "inputs"... Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "updater_comm"... Feb 26 20:08:11 usic volumio[4772]: info: Plugin mpdemulation is not enabled Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "rest_api"... Feb 26 20:08:11 usic volumio[4772]: info: Loading plugin "websocket"... Feb 26 20:08:11 usic volumio[4772]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:08:11 usic volumio[4772]: info: Loading i18n strings for locale ru Feb 26 20:08:11 usic volumio[4772]: Updating browse sources language Feb 26 20:08:11 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:11 usic volumio[4772]: Forking 3 albumart workers Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::initPlayerControls Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:12 usic volumio[4772]: Express server listening on port 3000 Feb 26 20:08:12 usic volumio[4772]: [Metrics] WebUI: 7s 714.24ms Feb 26 20:08:12 usic volumio[4772]: info: CoreStateMachine::resetVolumioState Feb 26 20:08:12 usic volumio[4772]: info: CoreStateMachine::getcurrentVolume Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:12 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:12 usic volumio[4772]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:08:12 usic volumio[4772]: info: Completed loading Core Plugins Feb 26 20:08:12 usic volumio[4772]: info: Preparing to generate the ALSA configuration file Feb 26 20:08:12 usic volumio[4772]: info: Cannot read play queue from file Feb 26 20:08:12 usic volumio[4772]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:08:12 usic volumio[4772]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:08:12 usic volumio[4772]: wlan0 Interface doesn't support scanning. Feb 26 20:08:12 usic volumio[4772]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:08:12 usic sudo[4870]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:08:12 usic sudo[4870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:12 usic sudo[4870]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:12 usic volumio[4772]: command failed: No such device (-19) Feb 26 20:08:12 usic volumio[4772]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:08:12 usic volumio[4772]: command failed: No such device (-19) Feb 26 20:08:13 usic volumio[4772]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:13 usic volumio[4772]: info: CoreStateMachine::pushState Feb 26 20:08:13 usic volumio[4772]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:13 usic volumio[4772]: info: CoreStateMachine::updateTrackBlock Feb 26 20:08:13 usic volumio[4772]: info: CorePlayQueue::getTrackBlock Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:13 usic volumio[4772]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:08:13 usic volumio[4772]: Starting albumart workers Feb 26 20:08:13 usic volumio[4772]: info: Output device has changed, restarting MPD Feb 26 20:08:13 usic volumio[4772]: Starting albumart workers Feb 26 20:08:13 usic volumio[4772]: info: ___________ START PLUGINS ___________ Feb 26 20:08:13 usic volumio[4772]: Starting albumart workers Feb 26 20:08:13 usic volumio[4772]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:08:13 usic volumio[4772]: info: Creating MPD Configuration file Feb 26 20:08:13 usic sudo[4882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:13 usic sudo[4882]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:13 usic sudo[4883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:13 usic sudo[4882]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:13 usic sudo[4883]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:13 usic volumio[4772]: info: [1772125693653] CoreMusicLibrary::Adding element Last_100 Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:13 usic volumio[4772]: info: [1772125693667] CoreMusicLibrary::Adding element Webradio Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:13 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:08:13 usic sudo[4886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:13 usic sudo[4886]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:13 usic volumio[4772]: info: Initializing BBC Radios Feb 26 20:08:13 usic sudo[4886]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:13 usic sudo[4889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:13 usic sudo[4889]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:13 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:13 usic volumio[4772]: info: Volumio Calling Home Feb 26 20:08:14 usic volumio[4772]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:08:14 usic volumio[4772]: info: CoreStateMachine::pushState Feb 26 20:08:14 usic volumio[4772]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:14 usic volumio[4772]: info: CoreStateMachine::setRandom null Feb 26 20:08:14 usic volumio[4772]: info: CoreStateMachine::pushState Feb 26 20:08:14 usic volumio[4772]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:14 usic volumio[4772]: info: MPD Permissions set Feb 26 20:08:14 usic volumio[4772]: info: MPD Permissions set Feb 26 20:08:14 usic volumio[4772]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:14 usic volumio[4772]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:08:14 usic volumio[4772]: info: Discovery: Found device usic Feb 26 20:08:14 usic volumio[4772]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:14 usic volumio[4772]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:15 usic volumio[4772]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:15 usic volumio[4772]: info: CoreStateMachine::pushState Feb 26 20:08:15 usic volumio[4772]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:15 usic volumio[4772]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:15 usic volumio[4772]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:08:15 usic volumio[4772]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:15 usic volumio[4772]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Feb 26 20:08:15 usic volumio[4772]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:08:15 usic volumio[4772]: errno: -3007, Feb 26 20:08:15 usic volumio[4772]: code: 'ENOTFOUND', Feb 26 20:08:15 usic volumio[4772]: syscall: 'getaddrinfo', Feb 26 20:08:15 usic volumio[4772]: hostname: 'cdn-images.dzcdn.net' Feb 26 20:08:15 usic volumio[4772]: } Feb 26 20:08:15 usic volumio[4772]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:15 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:15 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:15 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:15 usic sudo[4951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:08:15 usic sudo[4951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:15 usic sudo[4951]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:16 usic sudo[4955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:07 Feb 26 20:08:16 usic sudo[4955]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:16 usic sudo[4955]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:16 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:08:16 usic sudo[4889]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:16 usic sudo[4883]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:16 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:08:16 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:16 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:16 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:08:16 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Feb 26 20:08:16 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:16 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:08:16 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:08:16 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:18 usic mpd[4953]: Feb 26 20:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:08:18 usic systemd[1]: Started Music Player Daemon. Feb 26 20:08:19 usic volumio[4966]: info: ------------------------------------------- Feb 26 20:08:19 usic volumio[4966]: info: ----- Volumio3 ---- Feb 26 20:08:19 usic volumio[4966]: info: ------------------------------------------- Feb 26 20:08:19 usic volumio[4966]: info: ----- System startup ---- Feb 26 20:08:19 usic volumio[4966]: info: ------------------------------------------- Feb 26 20:08:19 usic volumio-remote-updater[1880]: [2026-02-26 20:08:19] [connect] Successful connection Feb 26 20:08:20 usic volumio[4966]: info: MYVOLUMIO Environment detected Feb 26 20:08:20 usic volumio[4966]: info: Plugin folders cleanup Feb 26 20:08:20 usic volumio[4966]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:08:20 usic volumio[4966]: info: Scanning category audio_interface Feb 26 20:08:20 usic volumio[4966]: info: Scanning category miscellanea Feb 26 20:08:20 usic volumio[4966]: info: Scanning category music_service Feb 26 20:08:20 usic volumio[4966]: info: Scanning category plugins.json Feb 26 20:08:20 usic volumio[4966]: info: Scanning category system_controller Feb 26 20:08:20 usic volumio[4966]: info: Scanning category user_interface Feb 26 20:08:20 usic volumio[4966]: info: Scanning into folder /data/plugins/ Feb 26 20:08:20 usic volumio[4966]: info: Plugin folders cleanup completed Feb 26 20:08:20 usic volumio[4966]: info: ------------------------------------------- Feb 26 20:08:20 usic volumio[4966]: info: ----- Core plugins startup ---- Feb 26 20:08:20 usic volumio[4966]: info: ------------------------------------------- Feb 26 20:08:20 usic volumio[4966]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:08:20 usic volumio[4966]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:08:20 usic volumio[4966]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:08:20 usic volumio[4966]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:08:20 usic volumio[4966]: info: Loading plugins from folder /data/plugins/ Feb 26 20:08:20 usic volumio[4966]: info: Loading plugin "system"... Feb 26 20:08:20 usic volumio[4966]: info: Loading plugin "appearance"... Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "network"... Feb 26 20:08:22 usic volumio[4966]: info: Refreshing Cached IP Addresses Feb 26 20:08:22 usic sudo[4994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:08:22 usic sudo[4994]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:22 usic sudo[4994]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:22 usic sudo[4996]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:08:22 usic sudo[4996]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "services"... Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "alsa_controller"... Feb 26 20:08:22 usic sudo[4996]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:22 usic sudo[5010]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:08:22 usic sudo[5010]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:22 usic sudo[5010]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:22 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "wizard"... Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "networkfs"... Feb 26 20:08:22 usic volumio[4966]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:08:22 usic volumio[4966]: info: Starting Udev Watcher for removable devices Feb 26 20:08:22 usic volumio[4966]: info: Ignoring mount for partition: boot Feb 26 20:08:22 usic volumio[4966]: info: Ignoring mount for partition: volumio Feb 26 20:08:22 usic volumio[4966]: info: Ignoring mount for partition: volumio_data Feb 26 20:08:22 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:08:22 usic volumio[4966]: info: Plugin upnp is not enabled Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "my_music"... Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "mpd"... Feb 26 20:08:22 usic volumio[4966]: info: Plugin upnp_browser is not enabled Feb 26 20:08:22 usic volumio[4966]: info: Loading plugin "alarm-clock"... Feb 26 20:08:23 usic volumio[4966]: info: Plugin airplay_emulation is not enabled Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "last_100"... Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "webradio"... Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "i2s_dacs"... Feb 26 20:08:23 usic volumio[4966]: info: I2S DAC not set, start Auto-detection Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "volumiodiscovery"... Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:23 usic node[4966]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** For more information see Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:23 usic volumio[4966]: *** WARNING *** For more information see Feb 26 20:08:23 usic node[4966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:23 usic node[4966]: *** WARNING *** For more information see Feb 26 20:08:23 usic node[4966]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:23 usic node[4966]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:23 usic node[4966]: *** WARNING *** For more information see Feb 26 20:08:23 usic volumio[4966]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:08:23 usic volumio[4966]: info: Discovery: Started advertising with name: usic Feb 26 20:08:23 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "outputs"... Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "albumart"... Feb 26 20:08:23 usic volumio[4966]: info: Plugin example_plugin is not enabled Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "inputs"... Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "updater_comm"... Feb 26 20:08:23 usic volumio[4966]: info: Plugin mpdemulation is not enabled Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "rest_api"... Feb 26 20:08:23 usic volumio[4966]: info: Loading plugin "websocket"... Feb 26 20:08:23 usic volumio[4966]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:08:23 usic volumio[4966]: info: Loading i18n strings for locale ru Feb 26 20:08:23 usic volumio[4966]: Updating browse sources language Feb 26 20:08:23 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:24 usic volumio[4966]: Forking 3 albumart workers Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::initPlayerControls Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:24 usic volumio[4966]: Express server listening on port 3000 Feb 26 20:08:24 usic volumio[4966]: [Metrics] WebUI: 5s 764.80ms Feb 26 20:08:24 usic volumio[4966]: info: CoreStateMachine::resetVolumioState Feb 26 20:08:24 usic volumio[4966]: info: CoreStateMachine::getcurrentVolume Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:24 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:24 usic volumio[4966]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:08:24 usic volumio[4966]: info: Completed loading Core Plugins Feb 26 20:08:24 usic volumio[4966]: info: Preparing to generate the ALSA configuration file Feb 26 20:08:24 usic volumio[4966]: info: Cannot read play queue from file Feb 26 20:08:24 usic volumio[4966]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:08:24 usic volumio[4966]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:08:24 usic volumio[4966]: wlan0 Interface doesn't support scanning. Feb 26 20:08:24 usic volumio[4966]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:08:24 usic sudo[5060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:08:24 usic sudo[5060]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:24 usic sudo[5060]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:24 usic volumio[4966]: command failed: No such device (-19) Feb 26 20:08:24 usic volumio[4966]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:08:24 usic volumio[4966]: command failed: No such device (-19) Feb 26 20:08:25 usic volumio[4966]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:25 usic volumio[4966]: info: CoreStateMachine::pushState Feb 26 20:08:25 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:25 usic volumio[4966]: info: CoreStateMachine::updateTrackBlock Feb 26 20:08:25 usic volumio[4966]: info: CorePlayQueue::getTrackBlock Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:25 usic volumio[4966]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:08:25 usic volumio[4966]: info: Output device has changed, restarting MPD Feb 26 20:08:25 usic volumio[4966]: info: ___________ START PLUGINS ___________ Feb 26 20:08:25 usic volumio[4966]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:08:25 usic volumio[4966]: info: Creating MPD Configuration file Feb 26 20:08:25 usic sudo[5072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:25 usic sudo[5073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:25 usic sudo[5073]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:25 usic sudo[5072]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:25 usic sudo[5072]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:25 usic volumio[4966]: info: [1772125705666] CoreMusicLibrary::Adding element Last_100 Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:25 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:08:25 usic volumio[4966]: info: [1772125705687] CoreMusicLibrary::Adding element Webradio Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:25 usic sudo[5077]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:25 usic sudo[5077]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:25 usic volumio[4966]: info: Initializing BBC Radios Feb 26 20:08:25 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:25 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:25 usic sudo[5079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:25 usic sudo[5079]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:25 usic sudo[5077]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:25 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:25 usic systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 26 20:08:25 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:25 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:25 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:25 usic volumio[4966]: Starting albumart workers Feb 26 20:08:25 usic volumio[4966]: Starting albumart workers Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:25 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:25 usic volumio[4966]: info: Volumio Calling Home Feb 26 20:08:25 usic volumio[4966]: Starting albumart workers Feb 26 20:08:26 usic sudo[5090]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:08:26 usic sudo[5090]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:26 usic sudo[5090]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:26 usic volumio[4966]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:08:26 usic volumio[4966]: info: CoreStateMachine::pushState Feb 26 20:08:26 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:26 usic volumio[4966]: info: CoreStateMachine::setRandom null Feb 26 20:08:26 usic volumio[4966]: info: CoreStateMachine::pushState Feb 26 20:08:26 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:26 usic volumio[4966]: info: MPD Permissions set Feb 26 20:08:26 usic volumio[4966]: info: MPD Permissions set Feb 26 20:08:26 usic volumio[4966]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Feb 26 20:08:26 usic volumio[4966]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:26 usic volumio[4966]: info: CoreStateMachine::pushState Feb 26 20:08:26 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:26 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:27 usic volumio[4966]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:27 usic volumio[4966]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:08:27 usic volumio[4966]: info: Discovery: Found device usic Feb 26 20:08:27 usic volumio[4966]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:27 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:27 usic volumio[4966]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:27 usic volumio[4966]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:27 usic volumio[4966]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:08:27 usic volumio[4966]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:27 usic volumio[4966]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Feb 26 20:08:27 usic volumio[4966]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:08:27 usic volumio[4966]: errno: -3007, Feb 26 20:08:27 usic volumio[4966]: code: 'ENOTFOUND', Feb 26 20:08:27 usic volumio[4966]: syscall: 'getaddrinfo', Feb 26 20:08:27 usic volumio[4966]: hostname: 'cdn-images.dzcdn.net' Feb 26 20:08:27 usic volumio[4966]: } Feb 26 20:08:27 usic volumio[4966]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:28 usic sudo[5147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:07 Feb 26 20:08:28 usic sudo[5147]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:28 usic sudo[5147]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:28 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:08:28 usic sudo[5079]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:28 usic sudo[5073]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:28 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:08:28 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:28 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:28 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:08:28 usic mpd[5098]: Feb 26 20:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:08:28 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 3. Feb 26 20:08:28 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:28 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:08:28 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:08:28 usic systemd[1]: Started Music Player Daemon. Feb 26 20:08:28 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:31 usic volumio[5158]: info: ------------------------------------------- Feb 26 20:08:31 usic volumio[5158]: info: ----- Volumio3 ---- Feb 26 20:08:31 usic volumio[5158]: info: ------------------------------------------- Feb 26 20:08:31 usic volumio[5158]: info: ----- System startup ---- Feb 26 20:08:31 usic volumio[5158]: info: ------------------------------------------- Feb 26 20:08:32 usic volumio[5158]: info: MYVOLUMIO Environment detected Feb 26 20:08:32 usic volumio[5158]: info: Plugin folders cleanup Feb 26 20:08:32 usic volumio[5158]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:08:32 usic volumio[5158]: info: Scanning category audio_interface Feb 26 20:08:32 usic volumio[5158]: info: Scanning category miscellanea Feb 26 20:08:32 usic volumio[5158]: info: Scanning category music_service Feb 26 20:08:32 usic volumio[5158]: info: Scanning category plugins.json Feb 26 20:08:32 usic volumio[5158]: info: Scanning category system_controller Feb 26 20:08:32 usic volumio[5158]: info: Scanning category user_interface Feb 26 20:08:32 usic volumio[5158]: info: Scanning into folder /data/plugins/ Feb 26 20:08:32 usic volumio[5158]: info: Plugin folders cleanup completed Feb 26 20:08:32 usic volumio[5158]: info: ------------------------------------------- Feb 26 20:08:32 usic volumio[5158]: info: ----- Core plugins startup ---- Feb 26 20:08:32 usic volumio[5158]: info: ------------------------------------------- Feb 26 20:08:32 usic volumio[5158]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:08:32 usic volumio[5158]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:08:32 usic volumio[5158]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:08:32 usic volumio[5158]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:08:32 usic volumio[5158]: info: Loading plugins from folder /data/plugins/ Feb 26 20:08:32 usic volumio[5158]: info: Loading plugin "system"... Feb 26 20:08:32 usic volumio[5158]: info: Loading plugin "appearance"... Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "network"... Feb 26 20:08:34 usic volumio[5158]: info: Refreshing Cached IP Addresses Feb 26 20:08:34 usic sudo[5189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:08:34 usic sudo[5189]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:34 usic sudo[5189]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:34 usic sudo[5191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:08:34 usic sudo[5191]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "services"... Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "alsa_controller"... Feb 26 20:08:34 usic sudo[5191]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:34 usic sudo[5201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:08:34 usic sudo[5201]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:34 usic sudo[5201]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:34 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "wizard"... Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "networkfs"... Feb 26 20:08:34 usic volumio[5158]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:08:34 usic volumio[5158]: info: Starting Udev Watcher for removable devices Feb 26 20:08:34 usic volumio[5158]: info: Ignoring mount for partition: boot Feb 26 20:08:34 usic volumio[5158]: info: Ignoring mount for partition: volumio Feb 26 20:08:34 usic volumio[5158]: info: Ignoring mount for partition: volumio_data Feb 26 20:08:34 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:08:34 usic volumio[5158]: info: Plugin upnp is not enabled Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "my_music"... Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "mpd"... Feb 26 20:08:34 usic volumio-remote-updater[1880]: [2026-02-26 20:08:34] [connect] Successful connection Feb 26 20:08:34 usic volumio[5158]: info: Plugin upnp_browser is not enabled Feb 26 20:08:34 usic volumio[5158]: info: Loading plugin "alarm-clock"... Feb 26 20:08:35 usic volumio[5158]: info: Plugin airplay_emulation is not enabled Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "last_100"... Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "webradio"... Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "i2s_dacs"... Feb 26 20:08:35 usic volumio[5158]: info: I2S DAC not set, start Auto-detection Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "volumiodiscovery"... Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** For more information see Feb 26 20:08:35 usic node[5158]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:35 usic volumio[5158]: *** WARNING *** For more information see Feb 26 20:08:35 usic node[5158]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:35 usic node[5158]: *** WARNING *** For more information see Feb 26 20:08:35 usic node[5158]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:35 usic node[5158]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:35 usic node[5158]: *** WARNING *** For more information see Feb 26 20:08:35 usic volumio[5158]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:08:35 usic volumio[5158]: info: Discovery: Started advertising with name: usic Feb 26 20:08:35 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "outputs"... Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "albumart"... Feb 26 20:08:35 usic volumio[5158]: info: Plugin example_plugin is not enabled Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "inputs"... Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "updater_comm"... Feb 26 20:08:35 usic volumio[5158]: info: Plugin mpdemulation is not enabled Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "rest_api"... Feb 26 20:08:35 usic volumio[5158]: info: Loading plugin "websocket"... Feb 26 20:08:35 usic volumio[5158]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:08:35 usic volumio[5158]: info: Loading i18n strings for locale ru Feb 26 20:08:35 usic volumio[5158]: Updating browse sources language Feb 26 20:08:35 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:36 usic volumio[5158]: Forking 3 albumart workers Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::initPlayerControls Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:36 usic volumio[5158]: Express server listening on port 3000 Feb 26 20:08:36 usic volumio[5158]: [Metrics] WebUI: 5s 775.00ms Feb 26 20:08:36 usic volumio[5158]: info: CoreStateMachine::resetVolumioState Feb 26 20:08:36 usic volumio[5158]: info: CoreStateMachine::getcurrentVolume Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:36 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:36 usic volumio[5158]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:08:36 usic volumio[5158]: info: Completed loading Core Plugins Feb 26 20:08:36 usic volumio[5158]: info: Preparing to generate the ALSA configuration file Feb 26 20:08:36 usic volumio[5158]: info: Cannot read play queue from file Feb 26 20:08:36 usic volumio[5158]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:08:36 usic volumio[5158]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:08:36 usic volumio[5158]: wlan0 Interface doesn't support scanning. Feb 26 20:08:36 usic volumio[5158]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:08:36 usic sudo[5255]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:08:36 usic sudo[5255]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:36 usic sudo[5255]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:36 usic volumio[5158]: command failed: No such device (-19) Feb 26 20:08:36 usic volumio[5158]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:08:36 usic volumio[5158]: command failed: No such device (-19) Feb 26 20:08:36 usic volumio-remote-updater[1880]: [2026-02-26 20:08:36] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1772125714 101 Feb 26 20:08:37 usic volumio[5158]: 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 Feb 26 20:08:37 usic volumio[5158]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:37 usic volumio[5158]: info: CoreStateMachine::pushState Feb 26 20:08:37 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:37 usic volumio[5158]: info: CoreStateMachine::updateTrackBlock Feb 26 20:08:37 usic volumio[5158]: info: CorePlayQueue::getTrackBlock Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:37 usic volumio[5158]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:08:37 usic volumio[5158]: info: Output device has changed, restarting MPD Feb 26 20:08:37 usic volumio[5158]: info: ___________ START PLUGINS ___________ Feb 26 20:08:37 usic volumio[5158]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:08:37 usic volumio[5158]: info: Creating MPD Configuration file Feb 26 20:08:37 usic sudo[5267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:37 usic sudo[5267]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:37 usic sudo[5267]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:37 usic sudo[5268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:37 usic sudo[5271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:37 usic sudo[5268]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:37 usic volumio[5158]: info: [1772125717745] CoreMusicLibrary::Adding element Last_100 Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:37 usic sudo[5271]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:37 usic volumio[5158]: info: [1772125717767] CoreMusicLibrary::Adding element Webradio Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:37 usic sudo[5271]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:37 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:37 usic volumio[5158]: info: Initializing BBC Radios Feb 26 20:08:37 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:08:37 usic sudo[5274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:37 usic sudo[5274]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:37 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:37 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:37 usic volumio[5158]: Starting albumart workers Feb 26 20:08:37 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:37 usic systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 26 20:08:37 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:37 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:37 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:37 usic volumio[5158]: Starting albumart workers Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: Volumio Calling Home Feb 26 20:08:38 usic volumio[5158]: Starting albumart workers Feb 26 20:08:38 usic sudo[5285]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:08:38 usic sudo[5285]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:38 usic sudo[5285]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:38 usic volumio[5158]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:08:38 usic volumio[5158]: info: CoreStateMachine::pushState Feb 26 20:08:38 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:38 usic volumio[5158]: info: CoreStateMachine::setRandom null Feb 26 20:08:38 usic volumio[5158]: info: CoreStateMachine::pushState Feb 26 20:08:38 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:38 usic volumio[5158]: info: MPD Permissions set Feb 26 20:08:38 usic volumio[5158]: info: MPD Permissions set Feb 26 20:08:38 usic volumio[5158]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 26 20:08:38 usic volumio[5158]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:38 usic volumio[5158]: info: CoreStateMachine::pushState Feb 26 20:08:38 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:38 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:39 usic volumio[5158]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:08:39 usic volumio[5158]: info: Discovery: Found device usic Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:39 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:39 usic volumio[5158]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:08:39 usic volumio[5158]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:39 usic volumio[5158]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:40 usic volumio[5158]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:40 usic volumio[5158]: Error: getaddrinfo ENOTFOUND assets.fanart.tv Feb 26 20:08:40 usic volumio[5158]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:08:40 usic volumio[5158]: errno: -3007, Feb 26 20:08:40 usic volumio[5158]: code: 'ENOTFOUND', Feb 26 20:08:40 usic volumio[5158]: syscall: 'getaddrinfo', Feb 26 20:08:40 usic volumio[5158]: hostname: 'assets.fanart.tv' Feb 26 20:08:40 usic volumio[5158]: } Feb 26 20:08:40 usic volumio[5158]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:41 usic mpd[5293]: Feb 26 20:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:08:41 usic systemd[1]: Started Music Player Daemon. Feb 26 20:08:41 usic sudo[5274]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:41 usic sudo[5268]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:41 usic sudo[5344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:07 Feb 26 20:08:41 usic sudo[5344]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:41 usic sudo[5344]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:43 usic ntpd[4028]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Feb 26 20:08:47 usic volumio-remote-updater[1880]: [2026-02-26 20:08:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 26 20:08:47 usic volumio-remote-updater[1880]: [2026-02-26 20:08:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 26 20:08:47 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:08:47 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:08:47 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:47 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:47 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:08:47 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 4. Feb 26 20:08:47 usic systemd[1]: Started dynamicswap service. Feb 26 20:08:47 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:08:47 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:08:47 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:08:50 usic volumio[5410]: info: ------------------------------------------- Feb 26 20:08:50 usic volumio[5410]: info: ----- Volumio3 ---- Feb 26 20:08:50 usic volumio[5410]: info: ------------------------------------------- Feb 26 20:08:50 usic volumio[5410]: info: ----- System startup ---- Feb 26 20:08:50 usic volumio[5410]: info: ------------------------------------------- Feb 26 20:08:51 usic volumio[5410]: info: MYVOLUMIO Environment detected Feb 26 20:08:51 usic volumio[5410]: info: Plugin folders cleanup Feb 26 20:08:51 usic volumio[5410]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:08:51 usic volumio[5410]: info: Scanning category audio_interface Feb 26 20:08:51 usic volumio[5410]: info: Scanning category miscellanea Feb 26 20:08:51 usic volumio[5410]: info: Scanning category music_service Feb 26 20:08:51 usic volumio[5410]: info: Scanning category plugins.json Feb 26 20:08:51 usic volumio[5410]: info: Scanning category system_controller Feb 26 20:08:51 usic volumio[5410]: info: Scanning category user_interface Feb 26 20:08:51 usic volumio[5410]: info: Scanning into folder /data/plugins/ Feb 26 20:08:51 usic volumio[5410]: info: Plugin folders cleanup completed Feb 26 20:08:51 usic volumio[5410]: info: ------------------------------------------- Feb 26 20:08:51 usic volumio[5410]: info: ----- Core plugins startup ---- Feb 26 20:08:51 usic volumio[5410]: info: ------------------------------------------- Feb 26 20:08:51 usic volumio[5410]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:08:51 usic volumio[5410]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:08:51 usic volumio[5410]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:08:51 usic volumio[5410]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:08:51 usic volumio[5410]: info: Loading plugins from folder /data/plugins/ Feb 26 20:08:51 usic volumio[5410]: info: Loading plugin "system"... Feb 26 20:08:51 usic volumio[5410]: info: Loading plugin "appearance"... Feb 26 20:08:52 usic volumio-remote-updater[1880]: [2026-02-26 20:08:52] [connect] Successful connection Feb 26 20:08:52 usic volumio[5410]: info: Loading plugin "network"... Feb 26 20:08:52 usic volumio[5410]: info: Refreshing Cached IP Addresses Feb 26 20:08:52 usic sudo[5440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:08:52 usic sudo[5440]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:52 usic sudo[5442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:08:52 usic sudo[5440]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:52 usic sudo[5442]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:52 usic volumio[5410]: info: Loading plugin "services"... Feb 26 20:08:52 usic volumio[5410]: info: Loading plugin "alsa_controller"... Feb 26 20:08:52 usic sudo[5442]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:52 usic sudo[5452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:08:52 usic sudo[5452]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:52 usic sudo[5452]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:53 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "wizard"... Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "networkfs"... Feb 26 20:08:53 usic volumio[5410]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:08:53 usic volumio[5410]: info: Starting Udev Watcher for removable devices Feb 26 20:08:53 usic volumio[5410]: info: Ignoring mount for partition: boot Feb 26 20:08:53 usic volumio[5410]: info: Ignoring mount for partition: volumio Feb 26 20:08:53 usic volumio[5410]: info: Ignoring mount for partition: volumio_data Feb 26 20:08:53 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:08:53 usic volumio[5410]: info: Plugin upnp is not enabled Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "my_music"... Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "mpd"... Feb 26 20:08:53 usic volumio[5410]: info: Plugin upnp_browser is not enabled Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "alarm-clock"... Feb 26 20:08:53 usic volumio[5410]: info: Plugin airplay_emulation is not enabled Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "last_100"... Feb 26 20:08:53 usic volumio[5410]: info: Loading plugin "webradio"... Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "i2s_dacs"... Feb 26 20:08:54 usic volumio[5410]: info: I2S DAC not set, start Auto-detection Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "volumiodiscovery"... Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** For more information see Feb 26 20:08:54 usic node[5410]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:54 usic volumio[5410]: *** WARNING *** For more information see Feb 26 20:08:54 usic node[5410]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:54 usic node[5410]: *** WARNING *** For more information see Feb 26 20:08:54 usic node[5410]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:08:54 usic node[5410]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:08:54 usic node[5410]: *** WARNING *** For more information see Feb 26 20:08:54 usic volumio[5410]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:08:54 usic volumio[5410]: info: Discovery: Started advertising with name: usic Feb 26 20:08:54 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "outputs"... Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "albumart"... Feb 26 20:08:54 usic volumio[5410]: info: Plugin example_plugin is not enabled Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "inputs"... Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "updater_comm"... Feb 26 20:08:54 usic volumio[5410]: info: Plugin mpdemulation is not enabled Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "rest_api"... Feb 26 20:08:54 usic volumio[5410]: info: Loading plugin "websocket"... Feb 26 20:08:54 usic volumio[5410]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:08:54 usic volumio[5410]: info: Loading i18n strings for locale ru Feb 26 20:08:54 usic volumio[5410]: Updating browse sources language Feb 26 20:08:54 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:54 usic volumio[5410]: Forking 3 albumart workers Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::initPlayerControls Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:08:55 usic volumio[5410]: Express server listening on port 3000 Feb 26 20:08:55 usic volumio[5410]: [Metrics] WebUI: 5s 794.78ms Feb 26 20:08:55 usic volumio[5410]: info: CoreStateMachine::resetVolumioState Feb 26 20:08:55 usic volumio[5410]: info: CoreStateMachine::getcurrentVolume Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:55 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:55 usic volumio[5410]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:08:55 usic volumio[5410]: info: Completed loading Core Plugins Feb 26 20:08:55 usic volumio[5410]: info: Preparing to generate the ALSA configuration file Feb 26 20:08:55 usic volumio[5410]: info: Cannot read play queue from file Feb 26 20:08:55 usic volumio[5410]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:08:55 usic volumio[5410]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:08:55 usic volumio[5410]: wlan0 Interface doesn't support scanning. Feb 26 20:08:55 usic volumio[5410]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:08:55 usic sudo[5507]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:08:55 usic sudo[5507]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:55 usic sudo[5507]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:55 usic volumio[5410]: command failed: No such device (-19) Feb 26 20:08:55 usic volumio[5410]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:08:55 usic volumio[5410]: command failed: No such device (-19) Feb 26 20:08:55 usic volumio-remote-updater[1880]: [2026-02-26 20:08:55] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1772125732 101 Feb 26 20:08:55 usic volumio[5410]: 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 Feb 26 20:08:56 usic volumio[5410]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:56 usic volumio[5410]: info: CoreStateMachine::pushState Feb 26 20:08:56 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:56 usic volumio[5410]: info: CoreStateMachine::updateTrackBlock Feb 26 20:08:56 usic volumio[5410]: info: CorePlayQueue::getTrackBlock Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:08:56 usic volumio[5410]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:08:56 usic volumio[5410]: info: Output device has changed, restarting MPD Feb 26 20:08:56 usic volumio[5410]: info: ___________ START PLUGINS ___________ Feb 26 20:08:56 usic sudo[5519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:56 usic sudo[5519]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:56 usic sudo[5519]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:56 usic sudo[5520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:56 usic volumio[5410]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:08:56 usic volumio[5410]: info: Creating MPD Configuration file Feb 26 20:08:56 usic sudo[5520]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:56 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:56 usic volumio[5410]: info: [1772125736486] CoreMusicLibrary::Adding element Last_100 Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:08:56 usic volumio[5410]: info: [1772125736504] CoreMusicLibrary::Adding element Webradio Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:08:56 usic sudo[5524]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:08:56 usic sudo[5524]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:56 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:56 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:56 usic sudo[5524]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:56 usic volumio[5410]: info: Initializing BBC Radios Feb 26 20:08:56 usic sudo[5526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:08:56 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:56 usic sudo[5526]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:56 usic volumio[5410]: Starting albumart workers Feb 26 20:08:56 usic systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 26 20:08:56 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:08:56 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:08:56 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:08:56 usic volumio[5410]: Starting albumart workers Feb 26 20:08:56 usic volumio[5410]: Starting albumart workers Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:08:56 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:56 usic volumio[5410]: info: Volumio Calling Home Feb 26 20:08:56 usic sudo[5537]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:08:56 usic sudo[5537]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:08:56 usic sudo[5537]: pam_unix(sudo:session): session closed for user root Feb 26 20:08:57 usic volumio[5410]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:08:57 usic volumio[5410]: info: CoreStateMachine::pushState Feb 26 20:08:57 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:57 usic volumio[5410]: info: CoreStateMachine::setRandom null Feb 26 20:08:57 usic volumio[5410]: info: CoreStateMachine::pushState Feb 26 20:08:57 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:57 usic volumio[5410]: info: MPD Permissions set Feb 26 20:08:57 usic volumio[5410]: info: MPD Permissions set Feb 26 20:08:57 usic volumio[5410]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Feb 26 20:08:57 usic volumio[5410]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:08:57 usic volumio[5410]: info: CoreStateMachine::pushState Feb 26 20:08:57 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::volumioPushState Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:08:57 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:08:58 usic volumio[5410]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:08:58 usic volumio[5410]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:08:58 usic volumio[5410]: info: Discovery: Found device usic Feb 26 20:08:58 usic volumio[5410]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:58 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:58 usic volumio[5410]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:08:58 usic volumio[5410]: info: CoreCommandRouter::volumioGetState Feb 26 20:08:58 usic volumio[5410]: info: CorePlayQueue::getTrack 0 Feb 26 20:08:59 usic volumio[5410]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:08:59 usic volumio[5410]: Error: getaddrinfo ENOTFOUND assets.fanart.tv Feb 26 20:08:59 usic volumio[5410]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:08:59 usic volumio[5410]: errno: -3007, Feb 26 20:08:59 usic volumio[5410]: code: 'ENOTFOUND', Feb 26 20:08:59 usic volumio[5410]: syscall: 'getaddrinfo', Feb 26 20:08:59 usic volumio[5410]: hostname: 'assets.fanart.tv' Feb 26 20:08:59 usic volumio[5410]: } Feb 26 20:08:59 usic volumio[5410]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:09:00 usic mpd[5545]: Feb 26 20:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:09:00 usic systemd[1]: Started Music Player Daemon. Feb 26 20:09:00 usic sudo[5526]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:00 usic sudo[5520]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:00 usic sudo[5596]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:08 Feb 26 20:09:00 usic sudo[5596]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:00 usic sudo[5596]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:00 usic volumio-remote-updater[1880]: [2026-02-26 20:09:00] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 26 20:09:00 usic volumio-remote-updater[1880]: [2026-02-26 20:09:00] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 26 20:09:00 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:09:00 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:09:00 usic systemd[1]: Started dynamicswap service. Feb 26 20:09:00 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:09:00 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:09:00 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 5. Feb 26 20:09:00 usic systemd[1]: Started dynamicswap service. Feb 26 20:09:00 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:09:00 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:09:00 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:09:03 usic volumio[5607]: info: ------------------------------------------- Feb 26 20:09:03 usic volumio[5607]: info: ----- Volumio3 ---- Feb 26 20:09:03 usic volumio[5607]: info: ------------------------------------------- Feb 26 20:09:03 usic volumio[5607]: info: ----- System startup ---- Feb 26 20:09:03 usic volumio[5607]: info: ------------------------------------------- Feb 26 20:09:04 usic volumio[5607]: info: MYVOLUMIO Environment detected Feb 26 20:09:04 usic volumio[5607]: info: Plugin folders cleanup Feb 26 20:09:04 usic volumio[5607]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:09:04 usic volumio[5607]: info: Scanning category audio_interface Feb 26 20:09:04 usic volumio[5607]: info: Scanning category miscellanea Feb 26 20:09:04 usic volumio[5607]: info: Scanning category music_service Feb 26 20:09:04 usic volumio[5607]: info: Scanning category plugins.json Feb 26 20:09:04 usic volumio[5607]: info: Scanning category system_controller Feb 26 20:09:04 usic volumio[5607]: info: Scanning category user_interface Feb 26 20:09:04 usic volumio[5607]: info: Scanning into folder /data/plugins/ Feb 26 20:09:04 usic volumio[5607]: info: Plugin folders cleanup completed Feb 26 20:09:04 usic volumio[5607]: info: ------------------------------------------- Feb 26 20:09:04 usic volumio[5607]: info: ----- Core plugins startup ---- Feb 26 20:09:04 usic volumio[5607]: info: ------------------------------------------- Feb 26 20:09:04 usic volumio[5607]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:09:04 usic volumio[5607]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:09:04 usic volumio[5607]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:09:04 usic volumio[5607]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:09:04 usic volumio[5607]: info: Loading plugins from folder /data/plugins/ Feb 26 20:09:04 usic volumio[5607]: info: Loading plugin "system"... Feb 26 20:09:04 usic volumio[5607]: info: Loading plugin "appearance"... Feb 26 20:09:05 usic volumio-remote-updater[1880]: [2026-02-26 20:09:05] [connect] Successful connection Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "network"... Feb 26 20:09:06 usic volumio[5607]: info: Refreshing Cached IP Addresses Feb 26 20:09:06 usic sudo[5633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:09:06 usic sudo[5633]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:06 usic sudo[5633]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:06 usic sudo[5635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:09:06 usic sudo[5635]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "services"... Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "alsa_controller"... Feb 26 20:09:06 usic sudo[5635]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:06 usic sudo[5650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:09:06 usic sudo[5650]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:06 usic sudo[5650]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:06 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "wizard"... Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "networkfs"... Feb 26 20:09:06 usic volumio[5607]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:09:06 usic volumio[5607]: info: Starting Udev Watcher for removable devices Feb 26 20:09:06 usic volumio[5607]: info: Ignoring mount for partition: boot Feb 26 20:09:06 usic volumio[5607]: info: Ignoring mount for partition: volumio Feb 26 20:09:06 usic volumio[5607]: info: Ignoring mount for partition: volumio_data Feb 26 20:09:06 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:09:06 usic volumio[5607]: info: Plugin upnp is not enabled Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "my_music"... Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "mpd"... Feb 26 20:09:06 usic volumio[5607]: info: Plugin upnp_browser is not enabled Feb 26 20:09:06 usic volumio[5607]: info: Loading plugin "alarm-clock"... Feb 26 20:09:07 usic volumio[5607]: info: Plugin airplay_emulation is not enabled Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "last_100"... Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "webradio"... Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "i2s_dacs"... Feb 26 20:09:07 usic volumio[5607]: info: I2S DAC not set, start Auto-detection Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "volumiodiscovery"... Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:07 usic node[5607]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** For more information see Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:07 usic volumio[5607]: *** WARNING *** For more information see Feb 26 20:09:07 usic node[5607]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:07 usic node[5607]: *** WARNING *** For more information see Feb 26 20:09:07 usic node[5607]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:07 usic node[5607]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:07 usic node[5607]: *** WARNING *** For more information see Feb 26 20:09:07 usic volumio[5607]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:09:07 usic volumio[5607]: info: Discovery: Started advertising with name: usic Feb 26 20:09:07 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "outputs"... Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "albumart"... Feb 26 20:09:07 usic volumio[5607]: info: Plugin example_plugin is not enabled Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "inputs"... Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "updater_comm"... Feb 26 20:09:07 usic volumio[5607]: info: Plugin mpdemulation is not enabled Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "rest_api"... Feb 26 20:09:07 usic volumio[5607]: info: Loading plugin "websocket"... Feb 26 20:09:07 usic volumio[5607]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:09:07 usic volumio[5607]: info: Loading i18n strings for locale ru Feb 26 20:09:07 usic volumio[5607]: Updating browse sources language Feb 26 20:09:07 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:08 usic volumio[5607]: Forking 3 albumart workers Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::initPlayerControls Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:09:08 usic volumio[5607]: Express server listening on port 3000 Feb 26 20:09:08 usic volumio[5607]: [Metrics] WebUI: 5s 970.01ms Feb 26 20:09:08 usic volumio[5607]: info: CoreStateMachine::resetVolumioState Feb 26 20:09:08 usic volumio[5607]: info: CoreStateMachine::getcurrentVolume Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:09:08 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:08 usic volumio[5607]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:09:08 usic volumio[5607]: info: Completed loading Core Plugins Feb 26 20:09:08 usic volumio[5607]: info: Preparing to generate the ALSA configuration file Feb 26 20:09:08 usic volumio[5607]: info: Cannot read play queue from file Feb 26 20:09:08 usic volumio[5607]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:09:08 usic volumio[5607]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:09:08 usic volumio[5607]: wlan0 Interface doesn't support scanning. Feb 26 20:09:08 usic volumio[5607]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:09:08 usic sudo[5700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:09:08 usic sudo[5700]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:08 usic sudo[5700]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:08 usic volumio[5607]: command failed: No such device (-19) Feb 26 20:09:08 usic volumio[5607]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:09:08 usic volumio[5607]: command failed: No such device (-19) Feb 26 20:09:08 usic volumio-remote-updater[1880]: [2026-02-26 20:09:08] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1772125745 101 Feb 26 20:09:08 usic volumio[5607]: 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 Feb 26 20:09:09 usic volumio[5607]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:09:09 usic volumio[5607]: info: CoreStateMachine::pushState Feb 26 20:09:09 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:09 usic volumio[5607]: info: CoreStateMachine::updateTrackBlock Feb 26 20:09:09 usic volumio[5607]: info: CorePlayQueue::getTrackBlock Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:09:09 usic volumio[5607]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:09:09 usic volumio[5607]: info: Output device has changed, restarting MPD Feb 26 20:09:09 usic volumio[5607]: info: ___________ START PLUGINS ___________ Feb 26 20:09:09 usic volumio[5607]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:09:09 usic volumio[5607]: info: Creating MPD Configuration file Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:09:09 usic volumio[5607]: info: [1772125749677] CoreMusicLibrary::Adding element Last_100 Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:09:09 usic volumio[5607]: info: [1772125749695] CoreMusicLibrary::Adding element Webradio Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:09 usic volumio[5607]: info: Initializing BBC Radios Feb 26 20:09:09 usic sudo[5712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:09:09 usic sudo[5712]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:09 usic sudo[5717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:09:09 usic sudo[5716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:09:09 usic sudo[5716]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:09 usic sudo[5713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:09:09 usic sudo[5717]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:09 usic sudo[5712]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:09 usic sudo[5713]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:09 usic sudo[5716]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:09 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:09 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:09 usic volumio[5607]: info: Volumio Calling Home Feb 26 20:09:10 usic volumio[5607]: Starting albumart workers Feb 26 20:09:10 usic volumio[5607]: Starting albumart workers Feb 26 20:09:10 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:09:10 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:09:10 usic volumio[5607]: Starting albumart workers Feb 26 20:09:10 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:09:10 usic volumio[5607]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:09:10 usic volumio[5607]: info: CoreStateMachine::pushState Feb 26 20:09:10 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:10 usic volumio[5607]: info: CoreStateMachine::setRandom null Feb 26 20:09:10 usic volumio[5607]: info: CoreStateMachine::pushState Feb 26 20:09:10 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:10 usic sudo[5737]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:09:10 usic sudo[5737]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:10 usic sudo[5737]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:10 usic volumio[5607]: info: MPD Permissions set Feb 26 20:09:10 usic volumio[5607]: info: MPD Permissions set Feb 26 20:09:10 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Feb 26 20:09:10 usic volumio[5607]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 26 20:09:10 usic volumio[5607]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:09:10 usic volumio[5607]: info: CoreStateMachine::pushState Feb 26 20:09:10 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:10 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:11 usic volumio[5607]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:09:11 usic volumio[5607]: info: Discovery: Found device usic Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:11 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 26 20:09:11 usic volumio[5607]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 7 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 8 Feb 26 20:09:11 usic volumio[5607]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:11 usic volumio[5607]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 9 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 10 Feb 26 20:09:11 usic volumio[5607]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 11 Feb 26 20:09:12 usic volumio[5607]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:09:12 usic volumio[5607]: Error: getaddrinfo ENOTFOUND assets.fanart.tv Feb 26 20:09:12 usic volumio[5607]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:09:12 usic volumio[5607]: errno: -3007, Feb 26 20:09:12 usic volumio[5607]: code: 'ENOTFOUND', Feb 26 20:09:12 usic volumio[5607]: syscall: 'getaddrinfo', Feb 26 20:09:12 usic volumio[5607]: hostname: 'assets.fanart.tv' Feb 26 20:09:12 usic volumio[5607]: } Feb 26 20:09:12 usic volumio[5607]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:09:13 usic sudo[5789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:08 Feb 26 20:09:13 usic sudo[5789]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:13 usic mpd[5741]: Feb 26 20:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:09:13 usic systemd[1]: Started Music Player Daemon. Feb 26 20:09:13 usic sudo[5717]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:13 usic sudo[5713]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:13 usic sudo[5789]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:13 usic volumio-remote-updater[1880]: [2026-02-26 20:09:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 26 20:09:13 usic volumio-remote-updater[1880]: [2026-02-26 20:09:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 26 20:09:13 usic systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 26 20:09:13 usic systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 26 20:09:13 usic systemd[1]: Started dynamicswap service. Feb 26 20:09:13 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:09:13 usic systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 26 20:09:13 usic systemd[1]: volumio.service: Scheduled restart job, restart counter is at 6. Feb 26 20:09:13 usic systemd[1]: Stopped Volumio Backend Module. Feb 26 20:09:13 usic systemd[1]: Started Volumio Backend Module. Feb 26 20:09:13 usic systemd[1]: Started dynamicswap service. Feb 26 20:09:13 usic systemd[1]: dynamicswap.service: Succeeded. Feb 26 20:09:16 usic volumio[5801]: info: ------------------------------------------- Feb 26 20:09:16 usic volumio[5801]: info: ----- Volumio3 ---- Feb 26 20:09:16 usic volumio[5801]: info: ------------------------------------------- Feb 26 20:09:16 usic volumio[5801]: info: ----- System startup ---- Feb 26 20:09:16 usic volumio[5801]: info: ------------------------------------------- Feb 26 20:09:17 usic volumio[5801]: info: MYVOLUMIO Environment detected Feb 26 20:09:17 usic volumio[5801]: info: Plugin folders cleanup Feb 26 20:09:17 usic volumio[5801]: info: Scanning into folder /volumio/app/plugins/ Feb 26 20:09:17 usic volumio[5801]: info: Scanning category audio_interface Feb 26 20:09:17 usic volumio[5801]: info: Scanning category miscellanea Feb 26 20:09:17 usic volumio[5801]: info: Scanning category music_service Feb 26 20:09:17 usic volumio[5801]: info: Scanning category plugins.json Feb 26 20:09:17 usic volumio[5801]: info: Scanning category system_controller Feb 26 20:09:17 usic volumio[5801]: info: Scanning category user_interface Feb 26 20:09:17 usic volumio[5801]: info: Scanning into folder /data/plugins/ Feb 26 20:09:17 usic volumio[5801]: info: Plugin folders cleanup completed Feb 26 20:09:17 usic volumio[5801]: info: ------------------------------------------- Feb 26 20:09:17 usic volumio[5801]: info: ----- Core plugins startup ---- Feb 26 20:09:17 usic volumio[5801]: info: ------------------------------------------- Feb 26 20:09:17 usic volumio[5801]: info: Loading plugins from folder /volumio/app/plugins/ Feb 26 20:09:18 usic volumio[5801]: info: Adding plugin upnp to MyMusic Plugins Feb 26 20:09:18 usic volumio[5801]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 26 20:09:18 usic volumio[5801]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 26 20:09:18 usic volumio[5801]: info: Loading plugins from folder /data/plugins/ Feb 26 20:09:18 usic volumio[5801]: info: Loading plugin "system"... Feb 26 20:09:18 usic volumio[5801]: info: Loading plugin "appearance"... Feb 26 20:09:18 usic volumio-remote-updater[1880]: [2026-02-26 20:09:18] [connect] Successful connection Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "network"... Feb 26 20:09:19 usic volumio[5801]: info: Refreshing Cached IP Addresses Feb 26 20:09:19 usic sudo[5828]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 26 20:09:19 usic sudo[5828]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:19 usic sudo[5830]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 26 20:09:19 usic sudo[5830]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:19 usic sudo[5828]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "services"... Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "alsa_controller"... Feb 26 20:09:19 usic sudo[5830]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:19 usic sudo[5840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 26 20:09:19 usic sudo[5840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:19 usic sudo[5840]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:19 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "wizard"... Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "networkfs"... Feb 26 20:09:19 usic volumio[5801]: info: Cannot mount NAS NAS73 at system boot, trial number 1 ,retrying in 5 seconds Feb 26 20:09:19 usic volumio[5801]: info: Starting Udev Watcher for removable devices Feb 26 20:09:19 usic volumio[5801]: info: Ignoring mount for partition: boot Feb 26 20:09:19 usic volumio[5801]: info: Ignoring mount for partition: volumio Feb 26 20:09:19 usic volumio[5801]: info: Ignoring mount for partition: volumio_data Feb 26 20:09:19 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "volumio_command_line_client"... Feb 26 20:09:19 usic volumio[5801]: info: Plugin upnp is not enabled Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "my_music"... Feb 26 20:09:19 usic volumio[5801]: info: Loading plugin "mpd"... Feb 26 20:09:20 usic volumio[5801]: info: Plugin upnp_browser is not enabled Feb 26 20:09:20 usic volumio[5801]: info: Loading plugin "alarm-clock"... Feb 26 20:09:20 usic volumio[5801]: info: Plugin airplay_emulation is not enabled Feb 26 20:09:20 usic volumio[5801]: info: Loading plugin "last_100"... Feb 26 20:09:20 usic volumio[5801]: info: Loading plugin "webradio"... Feb 26 20:09:20 usic volumio[5801]: info: Loading plugin "i2s_dacs"... Feb 26 20:09:20 usic volumio[5801]: info: I2S DAC not set, start Auto-detection Feb 26 20:09:20 usic volumio[5801]: info: Loading plugin "volumiodiscovery"... Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:20 usic node[5801]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** For more information see Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:20 usic volumio[5801]: *** WARNING *** For more information see Feb 26 20:09:20 usic node[5801]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:20 usic node[5801]: *** WARNING *** For more information see Feb 26 20:09:20 usic node[5801]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 26 20:09:20 usic node[5801]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 26 20:09:20 usic node[5801]: *** WARNING *** For more information see Feb 26 20:09:21 usic volumio[5801]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 26 20:09:21 usic volumio[5801]: info: Discovery: Started advertising with name: usic Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "outputs"... Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "albumart"... Feb 26 20:09:21 usic volumio[5801]: info: Plugin example_plugin is not enabled Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "inputs"... Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "updater_comm"... Feb 26 20:09:21 usic volumio[5801]: info: Plugin mpdemulation is not enabled Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "rest_api"... Feb 26 20:09:21 usic volumio[5801]: info: Loading plugin "websocket"... Feb 26 20:09:21 usic volumio[5801]: info: Starting Socket.io Server version 2.3.0 Feb 26 20:09:21 usic volumio[5801]: info: Loading i18n strings for locale ru Feb 26 20:09:21 usic volumio[5801]: Updating browse sources language Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:21 usic volumio[5801]: Forking 3 albumart workers Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::initPlayerControls Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 26 20:09:21 usic volumio[5801]: Express server listening on port 3000 Feb 26 20:09:21 usic volumio[5801]: [Metrics] WebUI: 6s 200.22ms Feb 26 20:09:21 usic volumio[5801]: info: CoreStateMachine::resetVolumioState Feb 26 20:09:21 usic volumio[5801]: info: CoreStateMachine::getcurrentVolume Feb 26 20:09:21 usic volumio[5801]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:09:22 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:22 usic volumio[5801]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 26 20:09:22 usic volumio[5801]: info: Completed loading Core Plugins Feb 26 20:09:22 usic volumio[5801]: info: Preparing to generate the ALSA configuration file Feb 26 20:09:22 usic volumio[5801]: info: Cannot read play queue from file Feb 26 20:09:22 usic volumio[5801]: info: Volumio Network Manager: Network status updated: 1 Feb 26 20:09:22 usic volumio[5801]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Feb 26 20:09:22 usic volumio[5801]: wlan0 Interface doesn't support scanning. Feb 26 20:09:22 usic volumio[5801]: info: Cannot use regular scanning, forcing with ap-force Feb 26 20:09:22 usic sudo[5894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Feb 26 20:09:22 usic sudo[5894]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:22 usic sudo[5894]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:22 usic volumio[5801]: command failed: No such device (-19) Feb 26 20:09:22 usic volumio[5801]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Feb 26 20:09:22 usic volumio[5801]: command failed: No such device (-19) Feb 26 20:09:22 usic volumio[5801]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 1 Feb 26 20:09:22 usic volumio[5801]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 2 Feb 26 20:09:22 usic volumio-remote-updater[1880]: [2026-02-26 20:09:22] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1772125758 101 Feb 26 20:09:22 usic volumio[5801]: 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: 3 Feb 26 20:09:22 usic volumio[5801]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 4 Feb 26 20:09:22 usic volumio[5801]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Juno%20Reactor/9245b602-85f8-4f62-a7b6-ddec35261bb5.jpg' Feb 26 20:09:23 usic volumio[5801]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Efterklang/b28b11fb-bcea-4cad-b6bc-057ca511cf88.jpg' Feb 26 20:09:23 usic volumio[5801]: verbose: New Socket.io Connection to 192.168.10.48 from 192.168.10.254 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/145.0.0.0 Safari/537.36 Edg/145.0.0.0 Engine version: 3 Transport: polling Total Clients: 5 Feb 26 20:09:23 usic volumio[5801]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::pushState Feb 26 20:09:23 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::updateTrackBlock Feb 26 20:09:23 usic volumio[5801]: info: CorePlayQueue::getTrackBlock Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioRetrievevolume Feb 26 20:09:23 usic volumio[5801]: info: Asound.conf file unchanged, so no further update is needed Feb 26 20:09:23 usic volumio[5801]: info: Output device has changed, restarting MPD Feb 26 20:09:23 usic volumio[5801]: info: ___________ START PLUGINS ___________ Feb 26 20:09:23 usic volumio[5801]: info: ControllerMpd::onStart: Initializing MPD Feb 26 20:09:23 usic volumio[5801]: info: Creating MPD Configuration file Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:09:23 usic volumio[5801]: info: [1772125763305] CoreMusicLibrary::Adding element Last_100 Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 26 20:09:23 usic volumio[5801]: info: [1772125763323] CoreMusicLibrary::Adding element Webradio Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:23 usic volumio[5801]: info: Initializing BBC Radios Feb 26 20:09:23 usic sudo[5907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:09:23 usic sudo[5910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:09:23 usic sudo[5910]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:23 usic sudo[5907]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:23 usic sudo[5905]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 26 20:09:23 usic sudo[5905]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:23 usic sudo[5911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 26 20:09:23 usic sudo[5911]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:23 usic sudo[5905]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:23 usic sudo[5910]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:23 usic systemd[1]: Stopping Music Player Daemon... Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:23 usic volumio[5801]: info: Volumio Calling Home Feb 26 20:09:23 usic volumio[5801]: Starting albumart workers Feb 26 20:09:23 usic volumio[5801]: Starting albumart workers Feb 26 20:09:23 usic volumio[5801]: Starting albumart workers Feb 26 20:09:23 usic systemd[1]: mpd.service: Succeeded. Feb 26 20:09:23 usic systemd[1]: Stopped Music Player Daemon. Feb 26 20:09:23 usic systemd[1]: Starting Music Player Daemon... Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::setRepeat null single undefined Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::pushState Feb 26 20:09:23 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::setRandom null Feb 26 20:09:23 usic volumio[5801]: info: CoreStateMachine::pushState Feb 26 20:09:23 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:23 usic volumio[5801]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:24 usic sudo[5928]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 26 20:09:24 usic sudo[5928]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 26 20:09:24 usic sudo[5928]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:24 usic volumio[5801]: info: MPD Permissions set Feb 26 20:09:24 usic volumio[5801]: info: MPD Permissions set Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 26 20:09:24 usic volumio[5801]: info: Discovery: adding 4f54dfed-c19c-47a1-bae2-83b3668bd707 Feb 26 20:09:24 usic volumio[5801]: info: Discovery: Found device usic Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:24 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:24 usic volumio[5801]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 26 20:09:24 usic volumio[5801]: info: VolumeController:: Volume=100 Mute =false Feb 26 20:09:24 usic volumio[5801]: info: CoreStateMachine::pushState Feb 26 20:09:24 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::volumioPushState Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 26 20:09:24 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 26 20:09:25 usic volumio[5801]: info: Cannot mount NAS NAS73 at system boot, trial number 2 ,retrying in 5 seconds Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetVisibleSources Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 26 20:09:25 usic volumio[5801]: info: Received Get System Info Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 26 20:09:25 usic volumio[5801]: info: Discovery: Getting this device information Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:25 usic volumio[5801]: info: Listing playlists Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetQueue Feb 26 20:09:25 usic volumio[5801]: info: CoreStateMachine::getQueue Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getQueue Feb 26 20:09:25 usic volumio[5801]: info: CoreCommandRouter::volumioGetState Feb 26 20:09:25 usic volumio[5801]: info: CorePlayQueue::getTrack 0 Feb 26 20:09:26 usic volumio[5801]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:09:26 usic volumio[5801]: Error: getaddrinfo ENOTFOUND cdn-images.dzcdn.net Feb 26 20:09:26 usic volumio[5801]: at GetAddrInfoReqWrap.onlookup [as oncomplete] (dns.js:71:26) { Feb 26 20:09:26 usic volumio[5801]: errno: -3007, Feb 26 20:09:26 usic volumio[5801]: code: 'ENOTFOUND', Feb 26 20:09:26 usic volumio[5801]: syscall: 'getaddrinfo', Feb 26 20:09:26 usic volumio[5801]: hostname: 'cdn-images.dzcdn.net' Feb 26 20:09:26 usic volumio[5801]: } Feb 26 20:09:26 usic volumio[5801]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 26 20:09:27 usic mpd[5932]: Feb 26 20:09 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 26 20:09:27 usic systemd[1]: Started Music Player Daemon. Feb 26 20:09:27 usic sudo[5911]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:27 usic sudo[5907]: pam_unix(sudo:session): session closed for user root Feb 26 20:09:27 usic sudo[5983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-26 20:08 Feb 26 20:09:27 usic sudo[5983]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 17 Nov 2025 09:38:04 PM CET" VOLUMIO_VERSION="3.875" VOLUMIO_HARDWARE="orangepipc" VOLUMIO_DEVICENAME="Orange Pi PC" VOLUMIO_HASH="f14a1b6604a50dfb96eb025c18ac7629"