-- Logs begin at Thu 2019-02-14 13:11:58 MSK, end at Sat 2025-04-26 04:03:13 MSK. -- Apr 26 04:02:00 volumio sudo[620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:00 Apr 26 04:02:00 volumio sudo[620]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:00 volumio sudo[620]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:00 volumio volumio-remote-updater[587]: [2025-04-26 04:02:00] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:02:00 volumio volumio-remote-updater[587]: [2025-04-26 04:02:00] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:02:00 volumio volumio-remote-updater[587]: [2025-04-26 04:02:00] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:02:00 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:00 volumio sudo[540]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:00 volumio sudo[548]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:00 volumio sudo[487]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:00 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:00 volumio mpd[608]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:00 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:00 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:00 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:00 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 84. Apr 26 04:02:00 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:00 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:00 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:00 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:00 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:01 volumio volumio[634]: info: ------------------------------------------- Apr 26 04:02:01 volumio volumio[634]: info: ----- Volumio3 ---- Apr 26 04:02:01 volumio volumio[634]: info: ------------------------------------------- Apr 26 04:02:01 volumio volumio[634]: info: ----- System startup ---- Apr 26 04:02:01 volumio volumio[634]: info: ------------------------------------------- Apr 26 04:02:01 volumio volumio[634]: info: MYVOLUMIO Environment detected Apr 26 04:02:01 volumio volumio[634]: info: Plugin folders cleanup Apr 26 04:02:01 volumio volumio[634]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:01 volumio volumio[634]: info: Scanning category audio_interface Apr 26 04:02:01 volumio volumio[634]: info: Scanning category miscellanea Apr 26 04:02:01 volumio volumio[634]: info: Scanning category music_service Apr 26 04:02:01 volumio volumio[634]: info: Scanning category plugins.json Apr 26 04:02:01 volumio volumio[634]: info: Scanning category system_controller Apr 26 04:02:01 volumio volumio[634]: info: Scanning category user_interface Apr 26 04:02:01 volumio volumio[634]: info: Scanning into folder /data/plugins/ Apr 26 04:02:01 volumio volumio[634]: info: Scanning category music_service Apr 26 04:02:01 volumio volumio[634]: info: Plugin folders cleanup completed Apr 26 04:02:01 volumio volumio[634]: info: ------------------------------------------- Apr 26 04:02:01 volumio volumio[634]: info: ----- Core plugins startup ---- Apr 26 04:02:01 volumio volumio[634]: info: ------------------------------------------- Apr 26 04:02:01 volumio volumio[634]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:01 volumio volumio[634]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:01 volumio volumio[634]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:01 volumio volumio[634]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:01 volumio volumio[634]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:01 volumio volumio[634]: info: Loading plugin "system"... Apr 26 04:02:01 volumio volumio[634]: info: Loading plugin "appearance"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "network"... Apr 26 04:02:02 volumio volumio[634]: info: Refreshing Cached IP Addresses Apr 26 04:02:02 volumio sudo[665]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:02 volumio sudo[665]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:02 volumio sudo[665]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:02 volumio sudo[667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:02 volumio sudo[667]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:02 volumio sudo[667]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "services"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "alsa_controller"... Apr 26 04:02:02 volumio sudo[676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:02 volumio sudo[676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:02 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "wizard"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "networkfs"... Apr 26 04:02:02 volumio volumio[634]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:02 volumio volumio[634]: info: Starting Udev Watcher for removable devices Apr 26 04:02:02 volumio volumio[634]: info: Ignoring mount for partition: boot Apr 26 04:02:02 volumio volumio[634]: info: Ignoring mount for partition: volumio Apr 26 04:02:02 volumio volumio[634]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:02 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "upnp"... Apr 26 04:02:02 volumio volumio[634]: info: [1745629322530] Starting Upmpd Daemon Apr 26 04:02:02 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "my_music"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "mpd"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "upnp_browser"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "alarm-clock"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:02 volumio volumio[634]: info: Starting Shairport Sync Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "last_100"... Apr 26 04:02:02 volumio volumio[634]: info: Loading plugin "webradio"... Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:03 volumio node[634]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** For more information see Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:03 volumio volumio[634]: *** WARNING *** For more information see Apr 26 04:02:03 volumio node[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:03 volumio node[634]: *** WARNING *** For more information see Apr 26 04:02:03 volumio node[634]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:03 volumio node[634]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:03 volumio node[634]: *** WARNING *** For more information see Apr 26 04:02:03 volumio volumio[634]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:03 volumio volumio[634]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "outputs"... Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "albumart"... Apr 26 04:02:03 volumio volumio[634]: info: Plugin example_plugin is not enabled Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "inputs"... Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "updater_comm"... Apr 26 04:02:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:03 volumio volumio[634]: info: Plugin mpdemulation is not enabled Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "rest_api"... Apr 26 04:02:03 volumio volumio[634]: info: Loading plugin "websocket"... Apr 26 04:02:03 volumio volumio[634]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:03 volumio volumio[634]: info: Loading i18n strings for locale ru Apr 26 04:02:03 volumio volumio[634]: Updating browse sources language Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:03 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:03 volumio volumio[634]: Forking 3 albumart workers Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:03 volumio volumio[634]: Express server listening on port 3000 Apr 26 04:02:03 volumio volumio[634]: [Metrics] WebUI: 2s 788.14ms Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::pushState Apr 26 04:02:03 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:03 volumio volumio[634]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:03 volumio volumio[634]: info: Reloading queue from file Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::pushState Apr 26 04:02:03 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::setRandom null Apr 26 04:02:03 volumio volumio[634]: info: CoreStateMachine::pushState Apr 26 04:02:03 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:03 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:03 volumio volumio[634]: info: Setting Device type: Raspberry PI Apr 26 04:02:03 volumio volumio[634]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:03 volumio volumio[634]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:03 volumio volumio[634]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:03 volumio volumio[634]: info: Completed loading Core Plugins Apr 26 04:02:03 volumio volumio[634]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:04 volumio volumio[634]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:04 volumio volumio[634]: info: Output device has changed, restarting MPD Apr 26 04:02:04 volumio volumio[634]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:04 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:04 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:04 volumio volumio[634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:04 volumio volumio[634]: info: ___________ START PLUGINS ___________ Apr 26 04:02:04 volumio volumio[634]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:04 volumio volumio[634]: info: Creating MPD Configuration file Apr 26 04:02:04 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:04 volumio volumio[634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:04 volumio volumio[634]: info: [1745629324139] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:04 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:04 volumio volumio[634]: Starting albumart workers Apr 26 04:02:04 volumio volumio[634]: Starting albumart workers Apr 26 04:02:04 volumio volumio[634]: Starting albumart workers Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:05 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Apr 26 04:02:05 volumio sudo[730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:05 volumio sudo[730]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:05 volumio sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:05 volumio sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:05 volumio sudo[736]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:05 volumio sudo[736]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:05 volumio sudo[737]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:05 volumio sudo[737]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:05 volumio sudo[728]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:05 volumio sudo[736]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:05 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:05 volumio volumio[634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:05 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:05 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:05 volumio volumio[634]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:05 volumio volumio[634]: info: [1745629325639] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:05 volumio volumio[634]: info: [1745629325642] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:05 volumio volumio[634]: info: Initializing BBC Radios Apr 26 04:02:05 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:05 volumio volumio[634]: info: Volumio Calling Home Apr 26 04:02:05 volumio sudo[779]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:05 volumio sudo[779]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:05 volumio sudo[779]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:05 volumio volumio[634]: info: [squeezelite_mc] Proxy server started on port 41219 Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:05 volumio volumio[634]: info: CoreStateMachine::pushState Apr 26 04:02:05 volumio volumio[634]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:05 volumio volumio[634]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:05 volumio volumio[634]: info: [squeezelite_mc] Player finder started Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:05 volumio volumio[634]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:05 volumio volumio[634]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:05 volumio volumio[634]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:05 volumio volumio[634]: at doSend (dgram.js:692:16) Apr 26 04:02:05 volumio volumio[634]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:05 volumio volumio[634]: at afterDns (dgram.js:638:5) Apr 26 04:02:05 volumio volumio[634]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:05 volumio volumio[634]: errno: -101, Apr 26 04:02:05 volumio volumio[634]: code: 'ENETUNREACH', Apr 26 04:02:05 volumio volumio[634]: syscall: 'send', Apr 26 04:02:05 volumio volumio[634]: address: '255.255.255.255', Apr 26 04:02:05 volumio volumio[634]: port: 3483 Apr 26 04:02:05 volumio volumio[634]: } Apr 26 04:02:05 volumio volumio[634]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:06 volumio sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:06 volumio sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:06 volumio sudo[807]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:06 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:06 volumio sudo[730]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:06 volumio sudo[676]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:06 volumio sudo[737]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:06 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:06 volumio mpd[795]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:06 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:06 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:06 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:06 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 85. Apr 26 04:02:06 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:06 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:06 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:06 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:06 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:07 volumio volumio[821]: info: ------------------------------------------- Apr 26 04:02:07 volumio volumio[821]: info: ----- Volumio3 ---- Apr 26 04:02:07 volumio volumio[821]: info: ------------------------------------------- Apr 26 04:02:07 volumio volumio[821]: info: ----- System startup ---- Apr 26 04:02:07 volumio volumio[821]: info: ------------------------------------------- Apr 26 04:02:08 volumio volumio[821]: info: MYVOLUMIO Environment detected Apr 26 04:02:08 volumio volumio[821]: info: Plugin folders cleanup Apr 26 04:02:08 volumio volumio[821]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:08 volumio volumio[821]: info: Scanning category audio_interface Apr 26 04:02:08 volumio volumio[821]: info: Scanning category miscellanea Apr 26 04:02:08 volumio volumio[821]: info: Scanning category music_service Apr 26 04:02:08 volumio volumio[821]: info: Scanning category plugins.json Apr 26 04:02:08 volumio volumio[821]: info: Scanning category system_controller Apr 26 04:02:08 volumio volumio[821]: info: Scanning category user_interface Apr 26 04:02:08 volumio volumio[821]: info: Scanning into folder /data/plugins/ Apr 26 04:02:08 volumio volumio[821]: info: Scanning category music_service Apr 26 04:02:08 volumio volumio[821]: info: Plugin folders cleanup completed Apr 26 04:02:08 volumio volumio[821]: info: ------------------------------------------- Apr 26 04:02:08 volumio volumio[821]: info: ----- Core plugins startup ---- Apr 26 04:02:08 volumio volumio[821]: info: ------------------------------------------- Apr 26 04:02:08 volumio volumio[821]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:08 volumio volumio[821]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:08 volumio volumio[821]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:08 volumio volumio[821]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:08 volumio volumio[821]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "system"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "appearance"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "network"... Apr 26 04:02:08 volumio volumio[821]: info: Refreshing Cached IP Addresses Apr 26 04:02:08 volumio sudo[852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:08 volumio sudo[852]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:08 volumio sudo[852]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:08 volumio sudo[854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:08 volumio sudo[854]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:08 volumio sudo[854]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "services"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "alsa_controller"... Apr 26 04:02:08 volumio sudo[863]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:08 volumio sudo[863]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:08 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "wizard"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "networkfs"... Apr 26 04:02:08 volumio volumio[821]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:08 volumio volumio[821]: info: Starting Udev Watcher for removable devices Apr 26 04:02:08 volumio volumio[821]: info: Ignoring mount for partition: boot Apr 26 04:02:08 volumio volumio[821]: info: Ignoring mount for partition: volumio Apr 26 04:02:08 volumio volumio[821]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:08 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "upnp"... Apr 26 04:02:08 volumio volumio[821]: info: [1745629328780] Starting Upmpd Daemon Apr 26 04:02:08 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "my_music"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "mpd"... Apr 26 04:02:08 volumio volumio[821]: info: Loading plugin "upnp_browser"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "alarm-clock"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:09 volumio volumio[821]: info: Starting Shairport Sync Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "last_100"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "webradio"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:09 volumio node[821]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** For more information see Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:09 volumio volumio[821]: *** WARNING *** For more information see Apr 26 04:02:09 volumio node[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:09 volumio node[821]: *** WARNING *** For more information see Apr 26 04:02:09 volumio node[821]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:09 volumio node[821]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:09 volumio node[821]: *** WARNING *** For more information see Apr 26 04:02:09 volumio volumio[821]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:09 volumio volumio[821]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:09 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:09 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:09 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "outputs"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "albumart"... Apr 26 04:02:09 volumio volumio[821]: info: Plugin example_plugin is not enabled Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "inputs"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "updater_comm"... Apr 26 04:02:09 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:09 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:09 volumio volumio[821]: info: Plugin mpdemulation is not enabled Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "rest_api"... Apr 26 04:02:09 volumio volumio[821]: info: Loading plugin "websocket"... Apr 26 04:02:09 volumio volumio[821]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:09 volumio volumio[821]: info: Loading i18n strings for locale ru Apr 26 04:02:09 volumio volumio[821]: Updating browse sources language Apr 26 04:02:09 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:10 volumio volumio[821]: Forking 3 albumart workers Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:10 volumio volumio[821]: Express server listening on port 3000 Apr 26 04:02:10 volumio volumio[821]: [Metrics] WebUI: 2s 796.86ms Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::pushState Apr 26 04:02:10 volumio volumio[821]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:10 volumio volumio[821]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: Reloading queue from file Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::pushState Apr 26 04:02:10 volumio volumio[821]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::setRandom null Apr 26 04:02:10 volumio volumio[821]: info: CoreStateMachine::pushState Apr 26 04:02:10 volumio volumio[821]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:10 volumio volumio[821]: info: Setting Device type: Raspberry PI Apr 26 04:02:10 volumio volumio[821]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:10 volumio volumio[821]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:10 volumio volumio[821]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:10 volumio volumio[821]: info: Completed loading Core Plugins Apr 26 04:02:10 volumio volumio[821]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:10 volumio volumio[821]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:10 volumio volumio[821]: info: Output device has changed, restarting MPD Apr 26 04:02:10 volumio volumio[821]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:10 volumio volumio[821]: info: ___________ START PLUGINS ___________ Apr 26 04:02:10 volumio volumio[821]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:10 volumio volumio[821]: info: Creating MPD Configuration file Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:10 volumio volumio[821]: info: [1745629330398] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:10 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:10 volumio volumio[821]: Starting albumart workers Apr 26 04:02:10 volumio volumio[821]: Starting albumart workers Apr 26 04:02:10 volumio volumio[821]: Starting albumart workers Apr 26 04:02:11 volumio volumio-remote-updater[587]: [2025-04-26 04:02:11] [connect] Successful connection Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:11 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:11 volumio sudo[925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:11 volumio sudo[925]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:11 volumio sudo[918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:11 volumio sudo[918]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:11 volumio sudo[920]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:11 volumio sudo[920]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:11 volumio sudo[925]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:11 volumio sudo[927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:11 volumio sudo[927]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:11 volumio sudo[918]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:11 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:11 volumio volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:11 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:11 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:11 volumio volumio[821]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:11 volumio volumio[821]: info: [1745629331827] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:11 volumio volumio[821]: info: [1745629331829] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:11 volumio volumio[821]: info: Initializing BBC Radios Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:11 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:11 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:11 volumio volumio[821]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:11 volumio volumio[821]: info: Volumio Calling Home Apr 26 04:02:11 volumio sudo[962]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:11 volumio sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:11 volumio volumio[821]: info: [squeezelite_mc] Proxy server started on port 41643 Apr 26 04:02:11 volumio sudo[962]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:12 volumio volumio[821]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:12 volumio volumio[821]: info: CoreStateMachine::pushState Apr 26 04:02:12 volumio volumio[821]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:12 volumio volumio[821]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:12 volumio volumio[821]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:12 volumio volumio[821]: info: [squeezelite_mc] Player finder started Apr 26 04:02:12 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:12 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:12 volumio volumio[821]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:12 volumio volumio[821]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:12 volumio volumio[821]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:12 volumio volumio[821]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:12 volumio volumio[821]: at doSend (dgram.js:692:16) Apr 26 04:02:12 volumio volumio[821]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:12 volumio volumio[821]: at afterDns (dgram.js:638:5) Apr 26 04:02:12 volumio volumio[821]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:12 volumio volumio[821]: errno: -101, Apr 26 04:02:12 volumio volumio[821]: code: 'ENETUNREACH', Apr 26 04:02:12 volumio volumio[821]: syscall: 'send', Apr 26 04:02:12 volumio volumio[821]: address: '255.255.255.255', Apr 26 04:02:12 volumio volumio[821]: port: 3483 Apr 26 04:02:12 volumio volumio[821]: } Apr 26 04:02:12 volumio volumio[821]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:12 volumio sudo[989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:12 volumio sudo[989]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:12 volumio sudo[989]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:12 volumio volumio-remote-updater[587]: [2025-04-26 04:02:12] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:02:12 volumio volumio-remote-updater[587]: [2025-04-26 04:02:12] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:02:12 volumio volumio-remote-updater[587]: [2025-04-26 04:02:12] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:02:12 volumio sudo[863]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:12 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:12 volumio sudo[920]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:12 volumio sudo[927]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:12 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:12 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:12 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:12 volumio mpd[977]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:12 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:12 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 86. Apr 26 04:02:12 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:12 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:12 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:12 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:13 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:13 volumio volumio[1003]: info: ------------------------------------------- Apr 26 04:02:13 volumio volumio[1003]: info: ----- Volumio3 ---- Apr 26 04:02:13 volumio volumio[1003]: info: ------------------------------------------- Apr 26 04:02:13 volumio volumio[1003]: info: ----- System startup ---- Apr 26 04:02:13 volumio volumio[1003]: info: ------------------------------------------- Apr 26 04:02:14 volumio volumio[1003]: info: MYVOLUMIO Environment detected Apr 26 04:02:14 volumio volumio[1003]: info: Plugin folders cleanup Apr 26 04:02:14 volumio volumio[1003]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category audio_interface Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category miscellanea Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category music_service Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category plugins.json Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category system_controller Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category user_interface Apr 26 04:02:14 volumio volumio[1003]: info: Scanning into folder /data/plugins/ Apr 26 04:02:14 volumio volumio[1003]: info: Scanning category music_service Apr 26 04:02:14 volumio volumio[1003]: info: Plugin folders cleanup completed Apr 26 04:02:14 volumio volumio[1003]: info: ------------------------------------------- Apr 26 04:02:14 volumio volumio[1003]: info: ----- Core plugins startup ---- Apr 26 04:02:14 volumio volumio[1003]: info: ------------------------------------------- Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:14 volumio volumio[1003]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:14 volumio volumio[1003]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:14 volumio volumio[1003]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugin "system"... Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugin "appearance"... Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugin "network"... Apr 26 04:02:14 volumio volumio[1003]: info: Refreshing Cached IP Addresses Apr 26 04:02:14 volumio sudo[1032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:14 volumio sudo[1032]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:14 volumio sudo[1032]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:14 volumio sudo[1036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:14 volumio sudo[1036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:14 volumio sudo[1036]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugin "services"... Apr 26 04:02:14 volumio volumio[1003]: info: Loading plugin "alsa_controller"... Apr 26 04:02:14 volumio sudo[1050]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:14 volumio sudo[1050]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "wizard"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "networkfs"... Apr 26 04:02:15 volumio volumio[1003]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:15 volumio volumio[1003]: info: Starting Udev Watcher for removable devices Apr 26 04:02:15 volumio volumio[1003]: info: Ignoring mount for partition: boot Apr 26 04:02:15 volumio volumio[1003]: info: Ignoring mount for partition: volumio Apr 26 04:02:15 volumio volumio[1003]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "upnp"... Apr 26 04:02:15 volumio volumio[1003]: info: [1745629335079] Starting Upmpd Daemon Apr 26 04:02:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "my_music"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "mpd"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "upnp_browser"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "alarm-clock"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:15 volumio volumio[1003]: info: Starting Shairport Sync Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "last_100"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "webradio"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:15 volumio node[1003]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** For more information see Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:15 volumio volumio[1003]: *** WARNING *** For more information see Apr 26 04:02:15 volumio node[1003]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:15 volumio node[1003]: *** WARNING *** For more information see Apr 26 04:02:15 volumio node[1003]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:15 volumio node[1003]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:15 volumio node[1003]: *** WARNING *** For more information see Apr 26 04:02:15 volumio volumio[1003]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:15 volumio volumio[1003]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:15 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:15 volumio volumio[1003]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:15 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "outputs"... Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "albumart"... Apr 26 04:02:16 volumio volumio[1003]: info: Plugin example_plugin is not enabled Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "inputs"... Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "updater_comm"... Apr 26 04:02:16 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:16 volumio volumio[1003]: info: Plugin mpdemulation is not enabled Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "rest_api"... Apr 26 04:02:16 volumio volumio[1003]: info: Loading plugin "websocket"... Apr 26 04:02:16 volumio volumio[1003]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:16 volumio volumio[1003]: info: Loading i18n strings for locale ru Apr 26 04:02:16 volumio volumio[1003]: Updating browse sources language Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:16 volumio volumio[1003]: Forking 3 albumart workers Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: Express server listening on port 3000 Apr 26 04:02:16 volumio volumio[1003]: [Metrics] WebUI: 2s 809.02ms Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 26 04:02:16 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: Reloading queue from file Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 26 04:02:16 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::setRandom null Apr 26 04:02:16 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 26 04:02:16 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:16 volumio volumio[1003]: info: Setting Device type: Raspberry PI Apr 26 04:02:16 volumio volumio[1003]: info: Completed loading Core Plugins Apr 26 04:02:16 volumio volumio[1003]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:16 volumio volumio[1003]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:16 volumio volumio[1003]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:16 volumio volumio[1003]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:16 volumio volumio[1003]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:16 volumio volumio[1003]: info: Output device has changed, restarting MPD Apr 26 04:02:16 volumio volumio[1003]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:16 volumio volumio[1003]: info: ___________ START PLUGINS ___________ Apr 26 04:02:16 volumio volumio[1003]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:16 volumio volumio[1003]: info: Creating MPD Configuration file Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:16 volumio volumio[1003]: info: [1745629336720] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:16 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:16 volumio volumio[1003]: Starting albumart workers Apr 26 04:02:16 volumio volumio[1003]: Starting albumart workers Apr 26 04:02:16 volumio volumio[1003]: Starting albumart workers Apr 26 04:02:17 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:17 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:17 volumio sudo[1108]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:17 volumio sudo[1101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:17 volumio sudo[1110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:17 volumio sudo[1108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:17 volumio sudo[1101]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:17 volumio sudo[1110]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:17 volumio sudo[1103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:17 volumio sudo[1101]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:17 volumio sudo[1103]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:17 volumio sudo[1108]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:17 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:17 volumio volumio[1003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:17 volumio volumio[1003]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:17 volumio volumio[1003]: info: [1745629337979] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:17 volumio volumio[1003]: info: [1745629337981] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:17 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:17 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:17 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:17 volumio volumio[1003]: info: Initializing BBC Radios Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:18 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:18 volumio volumio[1003]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:18 volumio volumio[1003]: info: Volumio Calling Home Apr 26 04:02:18 volumio sudo[1154]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:18 volumio sudo[1154]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:18 volumio volumio[1003]: info: [squeezelite_mc] Proxy server started on port 38687 Apr 26 04:02:18 volumio sudo[1154]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:18 volumio volumio[1003]: info: CoreStateMachine::pushState Apr 26 04:02:18 volumio volumio[1003]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:18 volumio volumio[1003]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:18 volumio volumio[1003]: info: [squeezelite_mc] Player finder started Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:18 volumio volumio[1003]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:18 volumio volumio[1003]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:18 volumio volumio[1003]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:18 volumio volumio[1003]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:18 volumio volumio[1003]: at doSend (dgram.js:692:16) Apr 26 04:02:18 volumio volumio[1003]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:18 volumio volumio[1003]: at afterDns (dgram.js:638:5) Apr 26 04:02:18 volumio volumio[1003]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:18 volumio volumio[1003]: errno: -101, Apr 26 04:02:18 volumio volumio[1003]: code: 'ENETUNREACH', Apr 26 04:02:18 volumio volumio[1003]: syscall: 'send', Apr 26 04:02:18 volumio volumio[1003]: address: '255.255.255.255', Apr 26 04:02:18 volumio volumio[1003]: port: 3483 Apr 26 04:02:18 volumio volumio[1003]: } Apr 26 04:02:18 volumio volumio[1003]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:18 volumio sudo[1181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:18 volumio sudo[1181]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:18 volumio sudo[1181]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:18 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:18 volumio sudo[1103]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:18 volumio sudo[1110]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:18 volumio sudo[1050]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:18 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:18 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:18 volumio mpd[1169]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:18 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:18 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:18 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 87. Apr 26 04:02:18 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:18 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:18 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:18 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:19 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:19 volumio volumio[1194]: info: ------------------------------------------- Apr 26 04:02:19 volumio volumio[1194]: info: ----- Volumio3 ---- Apr 26 04:02:19 volumio volumio[1194]: info: ------------------------------------------- Apr 26 04:02:19 volumio volumio[1194]: info: ----- System startup ---- Apr 26 04:02:19 volumio volumio[1194]: info: ------------------------------------------- Apr 26 04:02:20 volumio volumio[1194]: info: MYVOLUMIO Environment detected Apr 26 04:02:20 volumio volumio[1194]: info: Plugin folders cleanup Apr 26 04:02:20 volumio volumio[1194]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category audio_interface Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category miscellanea Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category music_service Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category plugins.json Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category system_controller Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category user_interface Apr 26 04:02:20 volumio volumio[1194]: info: Scanning into folder /data/plugins/ Apr 26 04:02:20 volumio volumio[1194]: info: Scanning category music_service Apr 26 04:02:20 volumio volumio[1194]: info: Plugin folders cleanup completed Apr 26 04:02:20 volumio volumio[1194]: info: ------------------------------------------- Apr 26 04:02:20 volumio volumio[1194]: info: ----- Core plugins startup ---- Apr 26 04:02:20 volumio volumio[1194]: info: ------------------------------------------- Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:20 volumio volumio[1194]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:20 volumio volumio[1194]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:20 volumio volumio[1194]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "system"... Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "appearance"... Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "network"... Apr 26 04:02:20 volumio volumio[1194]: info: Refreshing Cached IP Addresses Apr 26 04:02:20 volumio sudo[1223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:20 volumio sudo[1223]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:20 volumio sudo[1223]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:20 volumio sudo[1225]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:20 volumio sudo[1225]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:20 volumio sudo[1225]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "services"... Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "alsa_controller"... Apr 26 04:02:20 volumio sudo[1236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:20 volumio sudo[1236]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:20 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "wizard"... Apr 26 04:02:20 volumio volumio[1194]: info: Loading plugin "networkfs"... Apr 26 04:02:21 volumio volumio[1194]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:21 volumio volumio[1194]: info: Starting Udev Watcher for removable devices Apr 26 04:02:21 volumio volumio[1194]: info: Ignoring mount for partition: boot Apr 26 04:02:21 volumio volumio[1194]: info: Ignoring mount for partition: volumio Apr 26 04:02:21 volumio volumio[1194]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:21 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "upnp"... Apr 26 04:02:21 volumio volumio[1194]: info: [1745629341038] Starting Upmpd Daemon Apr 26 04:02:21 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "my_music"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "mpd"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "upnp_browser"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "alarm-clock"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:21 volumio volumio[1194]: info: Starting Shairport Sync Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "last_100"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "webradio"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:21 volumio node[1194]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** For more information see Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:21 volumio volumio[1194]: *** WARNING *** For more information see Apr 26 04:02:21 volumio node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:21 volumio node[1194]: *** WARNING *** For more information see Apr 26 04:02:21 volumio node[1194]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:21 volumio node[1194]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:21 volumio node[1194]: *** WARNING *** For more information see Apr 26 04:02:21 volumio volumio[1194]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:21 volumio volumio[1194]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:21 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:21 volumio volumio[1194]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:21 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "outputs"... Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "albumart"... Apr 26 04:02:22 volumio volumio[1194]: info: Plugin example_plugin is not enabled Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "inputs"... Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "updater_comm"... Apr 26 04:02:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:22 volumio volumio[1194]: info: Plugin mpdemulation is not enabled Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "rest_api"... Apr 26 04:02:22 volumio volumio[1194]: info: Loading plugin "websocket"... Apr 26 04:02:22 volumio volumio[1194]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:22 volumio volumio[1194]: info: Loading i18n strings for locale ru Apr 26 04:02:22 volumio volumio[1194]: Updating browse sources language Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:22 volumio volumio[1194]: Forking 3 albumart workers Apr 26 04:02:22 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: Express server listening on port 3000 Apr 26 04:02:22 volumio volumio[1194]: [Metrics] WebUI: 2s 813.23ms Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::pushState Apr 26 04:02:22 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:22 volumio volumio[1194]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: Reloading queue from file Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::pushState Apr 26 04:02:22 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::setRandom null Apr 26 04:02:22 volumio volumio[1194]: info: CoreStateMachine::pushState Apr 26 04:02:22 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:22 volumio volumio[1194]: info: Setting Device type: Raspberry PI Apr 26 04:02:22 volumio volumio[1194]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:22 volumio volumio[1194]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:22 volumio volumio[1194]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:22 volumio volumio[1194]: info: Completed loading Core Plugins Apr 26 04:02:22 volumio volumio[1194]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:22 volumio volumio[1194]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:22 volumio volumio[1194]: info: Output device has changed, restarting MPD Apr 26 04:02:22 volumio volumio[1194]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:22 volumio volumio[1194]: info: ___________ START PLUGINS ___________ Apr 26 04:02:22 volumio volumio[1194]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:22 volumio volumio[1194]: info: Creating MPD Configuration file Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:22 volumio volumio[1194]: info: [1745629342669] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:22 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:22 volumio volumio[1194]: Starting albumart workers Apr 26 04:02:22 volumio volumio[1194]: Starting albumart workers Apr 26 04:02:22 volumio volumio[1194]: Starting albumart workers Apr 26 04:02:24 volumio volumio-remote-updater[587]: [2025-04-26 04:02:24] [connect] Successful connection Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:24 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:24 volumio sudo[1286]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:24 volumio sudo[1288]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:24 volumio sudo[1286]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio sudo[1288]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:24 volumio sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio sudo[1286]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:24 volumio sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:24 volumio sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:24 volumio sudo[1294]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:24 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:24 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:24 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:24 volumio volumio[1194]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:24 volumio volumio[1194]: info: [1745629344215] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:24 volumio volumio[1194]: info: [1745629344216] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:24 volumio volumio[1194]: info: Initializing BBC Radios Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:24 volumio volumio[1194]: info: Volumio Calling Home Apr 26 04:02:24 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:24 volumio volumio[1194]: info: [squeezelite_mc] Proxy server started on port 45871 Apr 26 04:02:24 volumio sudo[1333]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:24 volumio sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio sudo[1333]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:24 volumio volumio[1194]: info: CoreStateMachine::pushState Apr 26 04:02:24 volumio volumio[1194]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:24 volumio volumio[1194]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:24 volumio volumio[1194]: info: [squeezelite_mc] Player finder started Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:24 volumio volumio[1194]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:24 volumio volumio[1194]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:24 volumio volumio[1194]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:24 volumio volumio[1194]: at doSend (dgram.js:692:16) Apr 26 04:02:24 volumio volumio[1194]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:24 volumio volumio[1194]: at afterDns (dgram.js:638:5) Apr 26 04:02:24 volumio volumio[1194]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:24 volumio volumio[1194]: errno: -101, Apr 26 04:02:24 volumio volumio[1194]: code: 'ENETUNREACH', Apr 26 04:02:24 volumio volumio[1194]: syscall: 'send', Apr 26 04:02:24 volumio volumio[1194]: address: '255.255.255.255', Apr 26 04:02:24 volumio volumio[1194]: port: 3483 Apr 26 04:02:24 volumio volumio[1194]: } Apr 26 04:02:24 volumio volumio[1194]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:24 volumio sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:24 volumio sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:24 volumio sudo[1364]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:25 volumio volumio-remote-updater[587]: [2025-04-26 04:02:25] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:02:25 volumio volumio-remote-updater[587]: [2025-04-26 04:02:25] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:02:25 volumio volumio-remote-updater[587]: [2025-04-26 04:02:25] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:02:25 volumio sudo[1295]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:25 volumio sudo[1288]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:25 volumio sudo[1236]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:25 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:25 volumio mpd[1352]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:25 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:25 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:25 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:25 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 88. Apr 26 04:02:25 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:25 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:25 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:25 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:25 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:26 volumio volumio[1377]: info: ------------------------------------------- Apr 26 04:02:26 volumio volumio[1377]: info: ----- Volumio3 ---- Apr 26 04:02:26 volumio volumio[1377]: info: ------------------------------------------- Apr 26 04:02:26 volumio volumio[1377]: info: ----- System startup ---- Apr 26 04:02:26 volumio volumio[1377]: info: ------------------------------------------- Apr 26 04:02:26 volumio volumio[1377]: info: MYVOLUMIO Environment detected Apr 26 04:02:26 volumio volumio[1377]: info: Plugin folders cleanup Apr 26 04:02:26 volumio volumio[1377]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category audio_interface Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category miscellanea Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category music_service Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category plugins.json Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category system_controller Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category user_interface Apr 26 04:02:26 volumio volumio[1377]: info: Scanning into folder /data/plugins/ Apr 26 04:02:26 volumio volumio[1377]: info: Scanning category music_service Apr 26 04:02:26 volumio volumio[1377]: info: Plugin folders cleanup completed Apr 26 04:02:26 volumio volumio[1377]: info: ------------------------------------------- Apr 26 04:02:26 volumio volumio[1377]: info: ----- Core plugins startup ---- Apr 26 04:02:26 volumio volumio[1377]: info: ------------------------------------------- Apr 26 04:02:26 volumio volumio[1377]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:26 volumio volumio[1377]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:26 volumio volumio[1377]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:26 volumio volumio[1377]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:26 volumio volumio[1377]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:26 volumio volumio[1377]: info: Loading plugin "system"... Apr 26 04:02:26 volumio volumio[1377]: info: Loading plugin "appearance"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "network"... Apr 26 04:02:27 volumio volumio[1377]: info: Refreshing Cached IP Addresses Apr 26 04:02:27 volumio sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:27 volumio sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:27 volumio sudo[1414]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:27 volumio sudo[1414]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:27 volumio sudo[1412]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:27 volumio sudo[1414]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "services"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "alsa_controller"... Apr 26 04:02:27 volumio sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:27 volumio sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "wizard"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "networkfs"... Apr 26 04:02:27 volumio volumio[1377]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:27 volumio volumio[1377]: info: Starting Udev Watcher for removable devices Apr 26 04:02:27 volumio volumio[1377]: info: Ignoring mount for partition: boot Apr 26 04:02:27 volumio volumio[1377]: info: Ignoring mount for partition: volumio Apr 26 04:02:27 volumio volumio[1377]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "upnp"... Apr 26 04:02:27 volumio volumio[1377]: info: [1745629347277] Starting Upmpd Daemon Apr 26 04:02:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "my_music"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "mpd"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "upnp_browser"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "alarm-clock"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:27 volumio volumio[1377]: info: Starting Shairport Sync Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "last_100"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "webradio"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:27 volumio node[1377]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** For more information see Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:27 volumio volumio[1377]: *** WARNING *** For more information see Apr 26 04:02:27 volumio node[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:27 volumio node[1377]: *** WARNING *** For more information see Apr 26 04:02:27 volumio node[1377]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:27 volumio node[1377]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:27 volumio node[1377]: *** WARNING *** For more information see Apr 26 04:02:27 volumio volumio[1377]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:27 volumio volumio[1377]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:27 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:27 volumio volumio[1377]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "outputs"... Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "albumart"... Apr 26 04:02:28 volumio volumio[1377]: info: Plugin example_plugin is not enabled Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "inputs"... Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "updater_comm"... Apr 26 04:02:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:28 volumio volumio[1377]: info: Plugin mpdemulation is not enabled Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "rest_api"... Apr 26 04:02:28 volumio volumio[1377]: info: Loading plugin "websocket"... Apr 26 04:02:28 volumio volumio[1377]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:28 volumio volumio[1377]: info: Loading i18n strings for locale ru Apr 26 04:02:28 volumio volumio[1377]: Updating browse sources language Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:28 volumio volumio[1377]: Forking 3 albumart workers Apr 26 04:02:28 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: Express server listening on port 3000 Apr 26 04:02:28 volumio volumio[1377]: [Metrics] WebUI: 2s 846.47ms Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::pushState Apr 26 04:02:28 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:28 volumio volumio[1377]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: Reloading queue from file Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::pushState Apr 26 04:02:28 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::setRandom null Apr 26 04:02:28 volumio volumio[1377]: info: CoreStateMachine::pushState Apr 26 04:02:28 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:28 volumio volumio[1377]: info: Setting Device type: Raspberry PI Apr 26 04:02:28 volumio volumio[1377]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:28 volumio volumio[1377]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:28 volumio volumio[1377]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:28 volumio volumio[1377]: info: Completed loading Core Plugins Apr 26 04:02:28 volumio volumio[1377]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:28 volumio volumio[1377]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:28 volumio volumio[1377]: info: Output device has changed, restarting MPD Apr 26 04:02:28 volumio volumio[1377]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:28 volumio volumio[1377]: info: ___________ START PLUGINS ___________ Apr 26 04:02:28 volumio volumio[1377]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:28 volumio volumio[1377]: info: Creating MPD Configuration file Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:28 volumio volumio[1377]: info: [1745629348928] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:28 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:29 volumio volumio[1377]: Starting albumart workers Apr 26 04:02:29 volumio volumio[1377]: Starting albumart workers Apr 26 04:02:29 volumio volumio[1377]: Starting albumart workers Apr 26 04:02:30 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:30 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:30 volumio sudo[1489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:30 volumio sudo[1480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:30 volumio sudo[1489]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:30 volumio sudo[1487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:30 volumio sudo[1480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:30 volumio sudo[1487]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:30 volumio sudo[1480]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:30 volumio sudo[1482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:30 volumio sudo[1482]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:30 volumio sudo[1487]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:30 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:30 volumio volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:30 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:30 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:30 volumio volumio[1377]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:30 volumio volumio[1377]: info: [1745629350440] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:30 volumio volumio[1377]: info: [1745629350442] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:30 volumio volumio[1377]: info: Initializing BBC Radios Apr 26 04:02:30 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:30 volumio volumio[1377]: info: Volumio Calling Home Apr 26 04:02:30 volumio sudo[1524]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:30 volumio sudo[1524]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:30 volumio sudo[1524]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:30 volumio volumio[1377]: info: [squeezelite_mc] Proxy server started on port 42231 Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:30 volumio volumio[1377]: info: CoreStateMachine::pushState Apr 26 04:02:30 volumio volumio[1377]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:30 volumio volumio[1377]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:30 volumio volumio[1377]: info: [squeezelite_mc] Player finder started Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:30 volumio volumio[1377]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:30 volumio volumio[1377]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:30 volumio volumio[1377]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:30 volumio volumio[1377]: at doSend (dgram.js:692:16) Apr 26 04:02:30 volumio volumio[1377]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:30 volumio volumio[1377]: at afterDns (dgram.js:638:5) Apr 26 04:02:30 volumio volumio[1377]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:30 volumio volumio[1377]: errno: -101, Apr 26 04:02:30 volumio volumio[1377]: code: 'ENETUNREACH', Apr 26 04:02:30 volumio volumio[1377]: syscall: 'send', Apr 26 04:02:30 volumio volumio[1377]: address: '255.255.255.255', Apr 26 04:02:30 volumio volumio[1377]: port: 3483 Apr 26 04:02:30 volumio volumio[1377]: } Apr 26 04:02:30 volumio volumio[1377]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:31 volumio sudo[1551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:31 volumio sudo[1551]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:31 volumio sudo[1551]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:31 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:31 volumio sudo[1489]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:31 volumio sudo[1482]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:31 volumio sudo[1424]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:31 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:31 volumio mpd[1539]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:31 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:31 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:31 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:31 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 89. Apr 26 04:02:31 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:31 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:31 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:31 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:31 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:32 volumio volumio[1565]: info: ------------------------------------------- Apr 26 04:02:32 volumio volumio[1565]: info: ----- Volumio3 ---- Apr 26 04:02:32 volumio volumio[1565]: info: ------------------------------------------- Apr 26 04:02:32 volumio volumio[1565]: info: ----- System startup ---- Apr 26 04:02:32 volumio volumio[1565]: info: ------------------------------------------- Apr 26 04:02:32 volumio volumio[1565]: info: MYVOLUMIO Environment detected Apr 26 04:02:32 volumio volumio[1565]: info: Plugin folders cleanup Apr 26 04:02:32 volumio volumio[1565]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category audio_interface Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category miscellanea Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category music_service Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category plugins.json Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category system_controller Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category user_interface Apr 26 04:02:32 volumio volumio[1565]: info: Scanning into folder /data/plugins/ Apr 26 04:02:32 volumio volumio[1565]: info: Scanning category music_service Apr 26 04:02:32 volumio volumio[1565]: info: Plugin folders cleanup completed Apr 26 04:02:32 volumio volumio[1565]: info: ------------------------------------------- Apr 26 04:02:32 volumio volumio[1565]: info: ----- Core plugins startup ---- Apr 26 04:02:32 volumio volumio[1565]: info: ------------------------------------------- Apr 26 04:02:32 volumio volumio[1565]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:32 volumio volumio[1565]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:32 volumio volumio[1565]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:32 volumio volumio[1565]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:32 volumio volumio[1565]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:32 volumio volumio[1565]: info: Loading plugin "system"... Apr 26 04:02:32 volumio volumio[1565]: info: Loading plugin "appearance"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "network"... Apr 26 04:02:33 volumio volumio[1565]: info: Refreshing Cached IP Addresses Apr 26 04:02:33 volumio sudo[1593]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:33 volumio sudo[1593]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:33 volumio sudo[1593]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:33 volumio sudo[1595]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:33 volumio sudo[1595]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:33 volumio sudo[1595]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "services"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "alsa_controller"... Apr 26 04:02:33 volumio sudo[1604]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:33 volumio sudo[1604]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:33 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "wizard"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "networkfs"... Apr 26 04:02:33 volumio volumio[1565]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:33 volumio volumio[1565]: info: Starting Udev Watcher for removable devices Apr 26 04:02:33 volumio volumio[1565]: info: Ignoring mount for partition: boot Apr 26 04:02:33 volumio volumio[1565]: info: Ignoring mount for partition: volumio Apr 26 04:02:33 volumio volumio[1565]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:33 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "upnp"... Apr 26 04:02:33 volumio volumio[1565]: info: [1745629353564] Starting Upmpd Daemon Apr 26 04:02:33 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "my_music"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "mpd"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "upnp_browser"... Apr 26 04:02:33 volumio volumio[1565]: info: Loading plugin "alarm-clock"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:34 volumio volumio[1565]: info: Starting Shairport Sync Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "last_100"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "webradio"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** For more information see Apr 26 04:02:34 volumio node[1565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:34 volumio volumio[1565]: *** WARNING *** For more information see Apr 26 04:02:34 volumio node[1565]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:34 volumio node[1565]: *** WARNING *** For more information see Apr 26 04:02:34 volumio node[1565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:34 volumio node[1565]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:34 volumio node[1565]: *** WARNING *** For more information see Apr 26 04:02:34 volumio volumio[1565]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:34 volumio volumio[1565]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:34 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:34 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "outputs"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "albumart"... Apr 26 04:02:34 volumio volumio[1565]: info: Plugin example_plugin is not enabled Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "inputs"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "updater_comm"... Apr 26 04:02:34 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:34 volumio volumio[1565]: info: Plugin mpdemulation is not enabled Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "rest_api"... Apr 26 04:02:34 volumio volumio[1565]: info: Loading plugin "websocket"... Apr 26 04:02:34 volumio volumio[1565]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:34 volumio volumio[1565]: info: Loading i18n strings for locale ru Apr 26 04:02:34 volumio volumio[1565]: Updating browse sources language Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:34 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:34 volumio volumio[1565]: Forking 3 albumart workers Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:34 volumio volumio[1565]: Express server listening on port 3000 Apr 26 04:02:34 volumio volumio[1565]: [Metrics] WebUI: 2s 813.21ms Apr 26 04:02:34 volumio volumio[1565]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:34 volumio volumio[1565]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:34 volumio volumio[1565]: info: CoreStateMachine::pushState Apr 26 04:02:34 volumio volumio[1565]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:34 volumio volumio[1565]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:35 volumio volumio[1565]: info: Reloading queue from file Apr 26 04:02:35 volumio volumio[1565]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:35 volumio volumio[1565]: info: CoreStateMachine::pushState Apr 26 04:02:35 volumio volumio[1565]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:35 volumio volumio[1565]: info: CoreStateMachine::setRandom null Apr 26 04:02:35 volumio volumio[1565]: info: CoreStateMachine::pushState Apr 26 04:02:35 volumio volumio[1565]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:35 volumio volumio[1565]: info: Setting Device type: Raspberry PI Apr 26 04:02:35 volumio volumio[1565]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:35 volumio volumio[1565]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:35 volumio volumio[1565]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:35 volumio volumio[1565]: info: Completed loading Core Plugins Apr 26 04:02:35 volumio volumio[1565]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:35 volumio volumio[1565]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:35 volumio volumio[1565]: info: Output device has changed, restarting MPD Apr 26 04:02:35 volumio volumio[1565]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:35 volumio volumio[1565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:35 volumio volumio[1565]: info: ___________ START PLUGINS ___________ Apr 26 04:02:35 volumio volumio[1565]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:35 volumio volumio[1565]: info: Creating MPD Configuration file Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:35 volumio volumio[1565]: info: [1745629355200] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:35 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:35 volumio volumio[1565]: Starting albumart workers Apr 26 04:02:35 volumio volumio[1565]: Starting albumart workers Apr 26 04:02:35 volumio volumio[1565]: Starting albumart workers Apr 26 04:02:36 volumio volumio-remote-updater[587]: [2025-04-26 04:02:36] [connect] Successful connection Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:36 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:36 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:36 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:36 volumio sudo[1655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:36 volumio sudo[1655]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:36 volumio sudo[1662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:36 volumio sudo[1662]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:36 volumio sudo[1662]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:36 volumio sudo[1664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:36 volumio sudo[1664]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:36 volumio sudo[1655]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:36 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:36 volumio volumio[1565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:36 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:36 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:36 volumio volumio[1565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:36 volumio volumio[1565]: info: [1745629356705] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:36 volumio volumio[1565]: info: [1745629356706] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:36 volumio volumio[1565]: info: Initializing BBC Radios Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:36 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:36 volumio volumio[1565]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:36 volumio volumio[1565]: info: Volumio Calling Home Apr 26 04:02:36 volumio sudo[1738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:36 volumio sudo[1738]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:36 volumio sudo[1738]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:36 volumio volumio[1565]: info: [squeezelite_mc] Proxy server started on port 45955 Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:36 volumio volumio[1565]: info: CoreStateMachine::pushState Apr 26 04:02:36 volumio volumio[1565]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:36 volumio volumio[1565]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:36 volumio volumio[1565]: info: [squeezelite_mc] Player finder started Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:36 volumio volumio[1565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:36 volumio volumio[1565]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:36 volumio volumio[1565]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:36 volumio volumio[1565]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:36 volumio volumio[1565]: at doSend (dgram.js:692:16) Apr 26 04:02:36 volumio volumio[1565]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:36 volumio volumio[1565]: at afterDns (dgram.js:638:5) Apr 26 04:02:36 volumio volumio[1565]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:36 volumio volumio[1565]: errno: -101, Apr 26 04:02:36 volumio volumio[1565]: code: 'ENETUNREACH', Apr 26 04:02:36 volumio volumio[1565]: syscall: 'send', Apr 26 04:02:36 volumio volumio[1565]: address: '255.255.255.255', Apr 26 04:02:36 volumio volumio[1565]: port: 3483 Apr 26 04:02:36 volumio volumio[1565]: } Apr 26 04:02:36 volumio volumio[1565]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:37 volumio sudo[1766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:37 volumio sudo[1766]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:37 volumio sudo[1766]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:37 volumio volumio-remote-updater[587]: [2025-04-26 04:02:37] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:02:37 volumio volumio-remote-updater[587]: [2025-04-26 04:02:37] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:02:37 volumio volumio-remote-updater[587]: [2025-04-26 04:02:37] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:02:37 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:37 volumio sudo[1664]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:37 volumio sudo[1604]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:37 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:37 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:37 volumio mpd[1754]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:37 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:37 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 90. Apr 26 04:02:37 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:37 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:37 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:37 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:37 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:38 volumio volumio[1780]: info: ------------------------------------------- Apr 26 04:02:38 volumio volumio[1780]: info: ----- Volumio3 ---- Apr 26 04:02:38 volumio volumio[1780]: info: ------------------------------------------- Apr 26 04:02:38 volumio volumio[1780]: info: ----- System startup ---- Apr 26 04:02:38 volumio volumio[1780]: info: ------------------------------------------- Apr 26 04:02:39 volumio volumio[1780]: info: MYVOLUMIO Environment detected Apr 26 04:02:39 volumio volumio[1780]: info: Plugin folders cleanup Apr 26 04:02:39 volumio volumio[1780]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category audio_interface Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category miscellanea Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category music_service Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category plugins.json Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category system_controller Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category user_interface Apr 26 04:02:39 volumio volumio[1780]: info: Scanning into folder /data/plugins/ Apr 26 04:02:39 volumio volumio[1780]: info: Scanning category music_service Apr 26 04:02:39 volumio volumio[1780]: info: Plugin folders cleanup completed Apr 26 04:02:39 volumio volumio[1780]: info: ------------------------------------------- Apr 26 04:02:39 volumio volumio[1780]: info: ----- Core plugins startup ---- Apr 26 04:02:39 volumio volumio[1780]: info: ------------------------------------------- Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:39 volumio volumio[1780]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:39 volumio volumio[1780]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:39 volumio volumio[1780]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "system"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "appearance"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "network"... Apr 26 04:02:39 volumio volumio[1780]: info: Refreshing Cached IP Addresses Apr 26 04:02:39 volumio sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:39 volumio sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:39 volumio sudo[1810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:39 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:39 volumio sudo[1808]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:39 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "services"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "alsa_controller"... Apr 26 04:02:39 volumio sudo[1823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:39 volumio sudo[1823]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:39 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "wizard"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "networkfs"... Apr 26 04:02:39 volumio volumio[1780]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:39 volumio volumio[1780]: info: Starting Udev Watcher for removable devices Apr 26 04:02:39 volumio volumio[1780]: info: Ignoring mount for partition: boot Apr 26 04:02:39 volumio volumio[1780]: info: Ignoring mount for partition: volumio Apr 26 04:02:39 volumio volumio[1780]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:39 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "upnp"... Apr 26 04:02:39 volumio volumio[1780]: info: [1745629359790] Starting Upmpd Daemon Apr 26 04:02:39 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "my_music"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "mpd"... Apr 26 04:02:39 volumio volumio[1780]: info: Loading plugin "upnp_browser"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "alarm-clock"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:40 volumio volumio[1780]: info: Starting Shairport Sync Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "last_100"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "webradio"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** For more information see Apr 26 04:02:40 volumio node[1780]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:40 volumio volumio[1780]: *** WARNING *** For more information see Apr 26 04:02:40 volumio node[1780]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:40 volumio node[1780]: *** WARNING *** For more information see Apr 26 04:02:40 volumio node[1780]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:40 volumio node[1780]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:40 volumio node[1780]: *** WARNING *** For more information see Apr 26 04:02:40 volumio volumio[1780]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:40 volumio volumio[1780]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:40 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "outputs"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "albumart"... Apr 26 04:02:40 volumio volumio[1780]: info: Plugin example_plugin is not enabled Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "inputs"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "updater_comm"... Apr 26 04:02:40 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:40 volumio volumio[1780]: info: Plugin mpdemulation is not enabled Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "rest_api"... Apr 26 04:02:40 volumio volumio[1780]: info: Loading plugin "websocket"... Apr 26 04:02:40 volumio volumio[1780]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:40 volumio volumio[1780]: info: Loading i18n strings for locale ru Apr 26 04:02:41 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:41 volumio volumio[1780]: Updating browse sources language Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:41 volumio volumio[1780]: Forking 3 albumart workers Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: Express server listening on port 3000 Apr 26 04:02:41 volumio volumio[1780]: [Metrics] WebUI: 2s 815.75ms Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 26 04:02:41 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:41 volumio volumio[1780]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: Reloading queue from file Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 26 04:02:41 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::setRandom null Apr 26 04:02:41 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 26 04:02:41 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:41 volumio volumio[1780]: info: Setting Device type: Raspberry PI Apr 26 04:02:41 volumio volumio[1780]: info: Completed loading Core Plugins Apr 26 04:02:41 volumio volumio[1780]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:41 volumio volumio[1780]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:41 volumio volumio[1780]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:41 volumio volumio[1780]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:41 volumio volumio[1780]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:41 volumio volumio[1780]: info: Output device has changed, restarting MPD Apr 26 04:02:41 volumio volumio[1780]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:41 volumio volumio[1780]: info: ___________ START PLUGINS ___________ Apr 26 04:02:41 volumio volumio[1780]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:41 volumio volumio[1780]: info: Creating MPD Configuration file Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:41 volumio volumio[1780]: info: [1745629361440] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:41 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:41 volumio volumio[1780]: Starting albumart workers Apr 26 04:02:41 volumio volumio[1780]: Starting albumart workers Apr 26 04:02:41 volumio volumio[1780]: Starting albumart workers Apr 26 04:02:42 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:42 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:42 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:42 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:42 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:42 volumio sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:42 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:42 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:42 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Apr 26 04:02:42 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:42 volumio sudo[1880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:42 volumio sudo[1880]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:42 volumio volumio[1780]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:42 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:42 volumio volumio[1780]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:42 volumio volumio[1780]: info: [1745629362876] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:42 volumio volumio[1780]: info: [1745629362878] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:42 volumio volumio[1780]: info: Initializing BBC Radios Apr 26 04:02:42 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:42 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:42 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:42 volumio volumio[1780]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:42 volumio volumio[1780]: info: Volumio Calling Home Apr 26 04:02:42 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:43 volumio volumio[1780]: info: [squeezelite_mc] Proxy server started on port 44261 Apr 26 04:02:43 volumio sudo[1916]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:43 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:43 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:43 volumio volumio[1780]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:43 volumio volumio[1780]: info: CoreStateMachine::pushState Apr 26 04:02:43 volumio volumio[1780]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:43 volumio volumio[1780]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:43 volumio volumio[1780]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:43 volumio volumio[1780]: info: [squeezelite_mc] Player finder started Apr 26 04:02:43 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:43 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:43 volumio volumio[1780]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:43 volumio volumio[1780]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:43 volumio volumio[1780]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:43 volumio volumio[1780]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:43 volumio volumio[1780]: at doSend (dgram.js:692:16) Apr 26 04:02:43 volumio volumio[1780]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:43 volumio volumio[1780]: at afterDns (dgram.js:638:5) Apr 26 04:02:43 volumio volumio[1780]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:43 volumio volumio[1780]: errno: -101, Apr 26 04:02:43 volumio volumio[1780]: code: 'ENETUNREACH', Apr 26 04:02:43 volumio volumio[1780]: syscall: 'send', Apr 26 04:02:43 volumio volumio[1780]: address: '255.255.255.255', Apr 26 04:02:43 volumio volumio[1780]: port: 3483 Apr 26 04:02:43 volumio volumio[1780]: } Apr 26 04:02:43 volumio volumio[1780]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:43 volumio sudo[1942]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:43 volumio sudo[1942]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:43 volumio sudo[1942]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:43 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:43 volumio sudo[1880]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:43 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:43 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:43 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:43 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:43 volumio mpd[1930]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:43 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:43 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:43 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 91. Apr 26 04:02:43 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:43 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:43 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:44 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:44 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:44 volumio volumio[1955]: info: ------------------------------------------- Apr 26 04:02:44 volumio volumio[1955]: info: ----- Volumio3 ---- Apr 26 04:02:44 volumio volumio[1955]: info: ------------------------------------------- Apr 26 04:02:44 volumio volumio[1955]: info: ----- System startup ---- Apr 26 04:02:44 volumio volumio[1955]: info: ------------------------------------------- Apr 26 04:02:45 volumio volumio[1955]: info: MYVOLUMIO Environment detected Apr 26 04:02:45 volumio volumio[1955]: info: Plugin folders cleanup Apr 26 04:02:45 volumio volumio[1955]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category audio_interface Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category miscellanea Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category music_service Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category plugins.json Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category system_controller Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category user_interface Apr 26 04:02:45 volumio volumio[1955]: info: Scanning into folder /data/plugins/ Apr 26 04:02:45 volumio volumio[1955]: info: Scanning category music_service Apr 26 04:02:45 volumio volumio[1955]: info: Plugin folders cleanup completed Apr 26 04:02:45 volumio volumio[1955]: info: ------------------------------------------- Apr 26 04:02:45 volumio volumio[1955]: info: ----- Core plugins startup ---- Apr 26 04:02:45 volumio volumio[1955]: info: ------------------------------------------- Apr 26 04:02:45 volumio volumio[1955]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:45 volumio volumio[1955]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:45 volumio volumio[1955]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:45 volumio volumio[1955]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:45 volumio volumio[1955]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:45 volumio volumio[1955]: info: Loading plugin "system"... Apr 26 04:02:45 volumio volumio[1955]: info: Loading plugin "appearance"... Apr 26 04:02:45 volumio volumio[1955]: info: Loading plugin "network"... Apr 26 04:02:45 volumio volumio[1955]: info: Refreshing Cached IP Addresses Apr 26 04:02:45 volumio sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:45 volumio sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:45 volumio sudo[1984]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:45 volumio sudo[1986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:45 volumio sudo[1986]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:45 volumio sudo[1986]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "services"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "alsa_controller"... Apr 26 04:02:46 volumio sudo[1995]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:46 volumio sudo[1995]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:46 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "wizard"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "networkfs"... Apr 26 04:02:46 volumio volumio[1955]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:46 volumio volumio[1955]: info: Starting Udev Watcher for removable devices Apr 26 04:02:46 volumio volumio[1955]: info: Ignoring mount for partition: boot Apr 26 04:02:46 volumio volumio[1955]: info: Ignoring mount for partition: volumio Apr 26 04:02:46 volumio volumio[1955]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:46 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "upnp"... Apr 26 04:02:46 volumio volumio[1955]: info: [1745629366100] Starting Upmpd Daemon Apr 26 04:02:46 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "my_music"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "mpd"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "upnp_browser"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "alarm-clock"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:46 volumio volumio[1955]: info: Starting Shairport Sync Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "last_100"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "webradio"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** For more information see Apr 26 04:02:46 volumio node[1955]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:46 volumio volumio[1955]: *** WARNING *** For more information see Apr 26 04:02:46 volumio node[1955]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:46 volumio node[1955]: *** WARNING *** For more information see Apr 26 04:02:46 volumio node[1955]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:46 volumio node[1955]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:46 volumio node[1955]: *** WARNING *** For more information see Apr 26 04:02:46 volumio volumio[1955]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:46 volumio volumio[1955]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:46 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:46 volumio volumio[1955]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:46 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:46 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "outputs"... Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "albumart"... Apr 26 04:02:47 volumio volumio[1955]: info: Plugin example_plugin is not enabled Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "inputs"... Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "updater_comm"... Apr 26 04:02:47 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:47 volumio volumio[1955]: info: Plugin mpdemulation is not enabled Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "rest_api"... Apr 26 04:02:47 volumio volumio[1955]: info: Loading plugin "websocket"... Apr 26 04:02:47 volumio volumio[1955]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:47 volumio volumio[1955]: info: Loading i18n strings for locale ru Apr 26 04:02:47 volumio volumio[1955]: Updating browse sources language Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:47 volumio volumio[1955]: Forking 3 albumart workers Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: Express server listening on port 3000 Apr 26 04:02:47 volumio volumio[1955]: [Metrics] WebUI: 2s 824.58ms Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::pushState Apr 26 04:02:47 volumio volumio[1955]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: Reloading queue from file Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::pushState Apr 26 04:02:47 volumio volumio[1955]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::setRandom null Apr 26 04:02:47 volumio volumio[1955]: info: CoreStateMachine::pushState Apr 26 04:02:47 volumio volumio[1955]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:47 volumio volumio[1955]: info: Setting Device type: Raspberry PI Apr 26 04:02:47 volumio volumio[1955]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:47 volumio volumio[1955]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:47 volumio volumio[1955]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:47 volumio volumio[1955]: info: Completed loading Core Plugins Apr 26 04:02:47 volumio volumio[1955]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:47 volumio volumio[1955]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:47 volumio volumio[1955]: info: Output device has changed, restarting MPD Apr 26 04:02:47 volumio volumio[1955]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:47 volumio volumio[1955]: info: ___________ START PLUGINS ___________ Apr 26 04:02:47 volumio volumio[1955]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:47 volumio volumio[1955]: info: Creating MPD Configuration file Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:47 volumio volumio[1955]: info: [1745629367744] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:47 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:47 volumio volumio[1955]: Starting albumart workers Apr 26 04:02:47 volumio volumio[1955]: Starting albumart workers Apr 26 04:02:47 volumio volumio[1955]: Starting albumart workers Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:49 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:49 volumio volumio-remote-updater[587]: [2025-04-26 04:02:49] [connect] Successful connection Apr 26 04:02:49 volumio sudo[2053]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:49 volumio sudo[2048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:49 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2048]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:49 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio sudo[2046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:49 volumio sudo[2046]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2046]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:49 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:49 volumio volumio[1955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:49 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:49 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:49 volumio volumio[1955]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:49 volumio volumio[1955]: info: [1745629369109] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:49 volumio volumio[1955]: info: [1745629369110] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:49 volumio volumio[1955]: info: Initializing BBC Radios Apr 26 04:02:49 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:49 volumio volumio[1955]: info: Volumio Calling Home Apr 26 04:02:49 volumio sudo[2094]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:49 volumio sudo[2094]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2094]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio volumio[1955]: info: [squeezelite_mc] Proxy server started on port 36381 Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:49 volumio volumio[1955]: info: CoreStateMachine::pushState Apr 26 04:02:49 volumio volumio[1955]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:49 volumio volumio[1955]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:49 volumio volumio[1955]: info: [squeezelite_mc] Player finder started Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:49 volumio volumio[1955]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:49 volumio volumio[1955]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:49 volumio volumio[1955]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:49 volumio volumio[1955]: at doSend (dgram.js:692:16) Apr 26 04:02:49 volumio volumio[1955]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:49 volumio volumio[1955]: at afterDns (dgram.js:638:5) Apr 26 04:02:49 volumio volumio[1955]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:49 volumio volumio[1955]: errno: -101, Apr 26 04:02:49 volumio volumio[1955]: code: 'ENETUNREACH', Apr 26 04:02:49 volumio volumio[1955]: syscall: 'send', Apr 26 04:02:49 volumio volumio[1955]: address: '255.255.255.255', Apr 26 04:02:49 volumio volumio[1955]: port: 3483 Apr 26 04:02:49 volumio volumio[1955]: } Apr 26 04:02:49 volumio volumio[1955]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:49 volumio sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:49 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:49 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio volumio-remote-updater[587]: [2025-04-26 04:02:49] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:02:49 volumio volumio-remote-updater[587]: [2025-04-26 04:02:49] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:02:49 volumio volumio-remote-updater[587]: [2025-04-26 04:02:49] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:02:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:49 volumio sudo[2048]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio sudo[1995]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:49 volumio mpd[2109]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:50 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:50 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:50 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 92. Apr 26 04:02:50 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:50 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:50 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:50 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:50 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:51 volumio volumio[2135]: info: ------------------------------------------- Apr 26 04:02:51 volumio volumio[2135]: info: ----- Volumio3 ---- Apr 26 04:02:51 volumio volumio[2135]: info: ------------------------------------------- Apr 26 04:02:51 volumio volumio[2135]: info: ----- System startup ---- Apr 26 04:02:51 volumio volumio[2135]: info: ------------------------------------------- Apr 26 04:02:51 volumio volumio[2135]: info: MYVOLUMIO Environment detected Apr 26 04:02:51 volumio volumio[2135]: info: Plugin folders cleanup Apr 26 04:02:51 volumio volumio[2135]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category audio_interface Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category miscellanea Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category music_service Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category plugins.json Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category system_controller Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category user_interface Apr 26 04:02:51 volumio volumio[2135]: info: Scanning into folder /data/plugins/ Apr 26 04:02:51 volumio volumio[2135]: info: Scanning category music_service Apr 26 04:02:51 volumio volumio[2135]: info: Plugin folders cleanup completed Apr 26 04:02:51 volumio volumio[2135]: info: ------------------------------------------- Apr 26 04:02:51 volumio volumio[2135]: info: ----- Core plugins startup ---- Apr 26 04:02:51 volumio volumio[2135]: info: ------------------------------------------- Apr 26 04:02:51 volumio volumio[2135]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:51 volumio volumio[2135]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:51 volumio volumio[2135]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:51 volumio volumio[2135]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:51 volumio volumio[2135]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:51 volumio volumio[2135]: info: Loading plugin "system"... Apr 26 04:02:51 volumio volumio[2135]: info: Loading plugin "appearance"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "network"... Apr 26 04:02:52 volumio volumio[2135]: info: Refreshing Cached IP Addresses Apr 26 04:02:52 volumio sudo[2164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:02:52 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:52 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:52 volumio sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:02:52 volumio sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:52 volumio sudo[2166]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "services"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "alsa_controller"... Apr 26 04:02:52 volumio sudo[2175]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:02:52 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:52 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "wizard"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "networkfs"... Apr 26 04:02:52 volumio volumio[2135]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:52 volumio volumio[2135]: info: Starting Udev Watcher for removable devices Apr 26 04:02:52 volumio volumio[2135]: info: Ignoring mount for partition: boot Apr 26 04:02:52 volumio volumio[2135]: info: Ignoring mount for partition: volumio Apr 26 04:02:52 volumio volumio[2135]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:52 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "upnp"... Apr 26 04:02:52 volumio volumio[2135]: info: [1745629372336] Starting Upmpd Daemon Apr 26 04:02:52 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "my_music"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "mpd"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "upnp_browser"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "alarm-clock"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:52 volumio volumio[2135]: info: Starting Shairport Sync Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "last_100"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "webradio"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:52 volumio node[2135]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** For more information see Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:52 volumio volumio[2135]: *** WARNING *** For more information see Apr 26 04:02:52 volumio node[2135]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:52 volumio node[2135]: *** WARNING *** For more information see Apr 26 04:02:52 volumio node[2135]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:52 volumio node[2135]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:52 volumio node[2135]: *** WARNING *** For more information see Apr 26 04:02:52 volumio volumio[2135]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:52 volumio volumio[2135]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:52 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:52 volumio volumio[2135]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:53 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:53 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:53 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "outputs"... Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "albumart"... Apr 26 04:02:53 volumio volumio[2135]: info: Plugin example_plugin is not enabled Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "inputs"... Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "updater_comm"... Apr 26 04:02:53 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:53 volumio volumio[2135]: info: Plugin mpdemulation is not enabled Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "rest_api"... Apr 26 04:02:53 volumio volumio[2135]: info: Loading plugin "websocket"... Apr 26 04:02:53 volumio volumio[2135]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:53 volumio volumio[2135]: info: Loading i18n strings for locale ru Apr 26 04:02:53 volumio volumio[2135]: Updating browse sources language Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:53 volumio volumio[2135]: Forking 3 albumart workers Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: Express server listening on port 3000 Apr 26 04:02:53 volumio volumio[2135]: [Metrics] WebUI: 2s 825.59ms Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::pushState Apr 26 04:02:53 volumio volumio[2135]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:53 volumio volumio[2135]: info: Reloading queue from file Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::pushState Apr 26 04:02:53 volumio volumio[2135]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::setRandom null Apr 26 04:02:53 volumio volumio[2135]: info: CoreStateMachine::pushState Apr 26 04:02:53 volumio volumio[2135]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:53 volumio volumio[2135]: info: Setting Device type: Raspberry PI Apr 26 04:02:53 volumio volumio[2135]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:02:53 volumio volumio[2135]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:02:53 volumio volumio[2135]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:02:53 volumio volumio[2135]: info: Completed loading Core Plugins Apr 26 04:02:53 volumio volumio[2135]: info: Preparing to generate the ALSA configuration file Apr 26 04:02:53 volumio volumio[2135]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:02:53 volumio volumio[2135]: info: Output device has changed, restarting MPD Apr 26 04:02:53 volumio volumio[2135]: info: Output device has changed, restarting Shairport Sync Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:53 volumio volumio[2135]: info: ___________ START PLUGINS ___________ Apr 26 04:02:53 volumio volumio[2135]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:02:53 volumio volumio[2135]: info: Creating MPD Configuration file Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:53 volumio volumio[2135]: info: [1745629373989] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:02:53 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:54 volumio volumio[2135]: Starting albumart workers Apr 26 04:02:54 volumio volumio[2135]: Starting albumart workers Apr 26 04:02:54 volumio volumio[2135]: Starting albumart workers Apr 26 04:02:55 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:02:55 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:02:55 volumio sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:55 volumio sudo[2233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:02:55 volumio sudo[2233]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:55 volumio sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:55 volumio sudo[2228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:55 volumio sudo[2235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:02:55 volumio sudo[2228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:55 volumio sudo[2226]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:55 volumio sudo[2235]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:55 volumio sudo[2233]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:55 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:02:55 volumio volumio[2135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:55 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:02:55 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:02:55 volumio volumio[2135]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:55 volumio volumio[2135]: info: [1745629375303] CoreMusicLibrary::Adding element Last_100 Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:02:55 volumio volumio[2135]: info: [1745629375306] CoreMusicLibrary::Adding element Webradio Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:55 volumio volumio[2135]: info: Initializing BBC Radios Apr 26 04:02:55 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:02:55 volumio volumio[2135]: info: Volumio Calling Home Apr 26 04:02:55 volumio sudo[2271]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:02:55 volumio sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:55 volumio sudo[2271]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:55 volumio volumio[2135]: info: [squeezelite_mc] Proxy server started on port 42585 Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:55 volumio volumio[2135]: info: CoreStateMachine::pushState Apr 26 04:02:55 volumio volumio[2135]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::volumioPushState Apr 26 04:02:55 volumio volumio[2135]: info: [squeezelite_mc] Server discovery started Apr 26 04:02:55 volumio volumio[2135]: info: [squeezelite_mc] Player finder started Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:55 volumio volumio[2135]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:02:55 volumio volumio[2135]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:55 volumio volumio[2135]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:02:55 volumio volumio[2135]: at doSend (dgram.js:692:16) Apr 26 04:02:55 volumio volumio[2135]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:02:55 volumio volumio[2135]: at afterDns (dgram.js:638:5) Apr 26 04:02:55 volumio volumio[2135]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:02:55 volumio volumio[2135]: errno: -101, Apr 26 04:02:55 volumio volumio[2135]: code: 'ENETUNREACH', Apr 26 04:02:55 volumio volumio[2135]: syscall: 'send', Apr 26 04:02:55 volumio volumio[2135]: address: '255.255.255.255', Apr 26 04:02:55 volumio volumio[2135]: port: 3483 Apr 26 04:02:55 volumio volumio[2135]: } Apr 26 04:02:55 volumio volumio[2135]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:02:55 volumio sudo[2298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:01 Apr 26 04:02:55 volumio sudo[2298]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:02:56 volumio sudo[2298]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:56 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:02:56 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:56 volumio sudo[2235]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:56 volumio sudo[2228]: pam_unix(sudo:session): session closed for user root Apr 26 04:02:56 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:02:56 volumio mpd[2286]: Apr 26 04:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:02:56 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:56 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:56 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:02:56 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 93. Apr 26 04:02:56 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:02:56 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:02:56 volumio systemd[1]: Started dynamicswap service. Apr 26 04:02:56 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:02:56 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:02:57 volumio volumio[2311]: info: ------------------------------------------- Apr 26 04:02:57 volumio volumio[2311]: info: ----- Volumio3 ---- Apr 26 04:02:57 volumio volumio[2311]: info: ------------------------------------------- Apr 26 04:02:57 volumio volumio[2311]: info: ----- System startup ---- Apr 26 04:02:57 volumio volumio[2311]: info: ------------------------------------------- Apr 26 04:02:57 volumio volumio[2311]: info: MYVOLUMIO Environment detected Apr 26 04:02:57 volumio volumio[2311]: info: Plugin folders cleanup Apr 26 04:02:57 volumio volumio[2311]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category audio_interface Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category miscellanea Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category music_service Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category plugins.json Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category system_controller Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category user_interface Apr 26 04:02:57 volumio volumio[2311]: info: Scanning into folder /data/plugins/ Apr 26 04:02:57 volumio volumio[2311]: info: Scanning category music_service Apr 26 04:02:57 volumio volumio[2311]: info: Plugin folders cleanup completed Apr 26 04:02:57 volumio volumio[2311]: info: ------------------------------------------- Apr 26 04:02:57 volumio volumio[2311]: info: ----- Core plugins startup ---- Apr 26 04:02:57 volumio volumio[2311]: info: ------------------------------------------- Apr 26 04:02:57 volumio volumio[2311]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:02:57 volumio volumio[2311]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:02:57 volumio volumio[2311]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:02:57 volumio volumio[2311]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:02:57 volumio volumio[2311]: info: Loading plugins from folder /data/plugins/ Apr 26 04:02:57 volumio volumio[2311]: info: Loading plugin "system"... Apr 26 04:02:57 volumio volumio[2311]: info: Loading plugin "appearance"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "network"... Apr 26 04:02:58 volumio volumio[2311]: info: Refreshing Cached IP Addresses Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "services"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "alsa_controller"... Apr 26 04:02:58 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "wizard"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "networkfs"... Apr 26 04:02:58 volumio volumio[2311]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:02:58 volumio volumio[2311]: info: Starting Udev Watcher for removable devices Apr 26 04:02:58 volumio volumio[2311]: info: Ignoring mount for partition: boot Apr 26 04:02:58 volumio volumio[2311]: info: Ignoring mount for partition: volumio Apr 26 04:02:58 volumio volumio[2311]: info: Ignoring mount for partition: volumio_data Apr 26 04:02:58 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "upnp"... Apr 26 04:02:58 volumio volumio[2311]: info: [1745629378557] Starting Upmpd Daemon Apr 26 04:02:58 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "my_music"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "mpd"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "upnp_browser"... Apr 26 04:02:58 volumio volumio[2311]: info: Loading plugin "alarm-clock"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "airplay_emulation"... Apr 26 04:02:59 volumio volumio[2311]: info: Starting Shairport Sync Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "last_100"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "webradio"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "i2s_dacs"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "volumiodiscovery"... Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:59 volumio node[2311]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** For more information see Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:59 volumio volumio[2311]: *** WARNING *** For more information see Apr 26 04:02:59 volumio node[2311]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:59 volumio node[2311]: *** WARNING *** For more information see Apr 26 04:02:59 volumio node[2311]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:02:59 volumio node[2311]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:02:59 volumio node[2311]: *** WARNING *** For more information see Apr 26 04:02:59 volumio volumio[2311]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:02:59 volumio volumio[2311]: info: Discovery: Started advertising with name: Volumio Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "squeezelite_mc"... Apr 26 04:02:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:02:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:02:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "outputs"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "albumart"... Apr 26 04:02:59 volumio volumio[2311]: info: Plugin example_plugin is not enabled Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "inputs"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "updater_comm"... Apr 26 04:02:59 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:02:59 volumio volumio[2311]: info: Plugin mpdemulation is not enabled Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "rest_api"... Apr 26 04:02:59 volumio volumio[2311]: info: Loading plugin "websocket"... Apr 26 04:02:59 volumio volumio[2311]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:02:59 volumio volumio[2311]: info: Loading i18n strings for locale ru Apr 26 04:02:59 volumio volumio[2311]: Updating browse sources language Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:02:59 volumio volumio[2311]: Forking 3 albumart workers Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::initPlayerControls Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:02:59 volumio volumio[2311]: Express server listening on port 3000 Apr 26 04:02:59 volumio volumio[2311]: [Metrics] WebUI: 2s 780.06ms Apr 26 04:02:59 volumio volumio[2311]: info: CoreStateMachine::resetVolumioState Apr 26 04:02:59 volumio volumio[2311]: info: CoreStateMachine::getcurrentVolume Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:02:59 volumio volumio[2311]: info: CoreStateMachine::pushState Apr 26 04:02:59 volumio volumio[2311]: info: CorePlayQueue::getTrack 0 Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:02:59 volumio volumio[2311]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:00 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:00 volumio volumio[2311]: info: Reloading queue from file Apr 26 04:03:00 volumio volumio[2311]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:03:00 volumio volumio[2311]: info: CoreStateMachine::pushState Apr 26 04:03:00 volumio volumio[2311]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:00 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:03:00 volumio volumio[2311]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:00 volumio volumio[2311]: info: CoreStateMachine::setRandom null Apr 26 04:03:00 volumio volumio[2311]: info: CoreStateMachine::pushState Apr 26 04:03:00 volumio volumio[2311]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:00 volumio volumio[2311]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:00 volumio volumio[2311]: info: Setting Device type: Raspberry PI Apr 26 04:03:00 volumio volumio[2311]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:03:00 volumio volumio[2311]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:03:00 volumio volumio[2311]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:03:00 volumio volumio[2311]: Starting albumart workers Apr 26 04:03:00 volumio volumio[2311]: Starting albumart workers Apr 26 04:03:00 volumio volumio[2311]: Starting albumart workers Apr 26 04:03:01 volumio volumio-remote-updater[587]: [2025-04-26 04:03:01] [connect] Successful connection Apr 26 04:03:01 volumio sudo[2340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:03:01 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:03:01 volumio sudo[2340]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:03:01 volumio sudo[2342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:03:01 volumio sudo[2349]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2342]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2349]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio sudo[2340]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio sudo[2342]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio volumio-remote-updater[587]: [2025-04-26 04:03:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745629379 101 Apr 26 04:03:01 volumio volumio[2311]: 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 Apr 26 04:03:01 volumio volumio[2311]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:03:01 volumio volumio[2311]: info: Completed loading Core Plugins Apr 26 04:03:01 volumio volumio[2311]: info: Preparing to generate the ALSA configuration file Apr 26 04:03:01 volumio volumio[2311]: error: An error occurred while scanning: Error: Command failed: sudo /sbin/iwlist wlan0 scan Apr 26 04:03:01 volumio volumio[2311]: wlan0 Interface doesn't support scanning : Device or resource busy Apr 26 04:03:01 volumio volumio[2311]: info: Cannot use regular scanning, forcing with ap-force Apr 26 04:03:01 volumio sudo[2427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw dev wlan0 scan ap-force Apr 26 04:03:01 volumio sudo[2427]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2427]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio volumio[2311]: command failed: Device or resource busy (-16) Apr 26 04:03:01 volumio volumio[2311]: error: Cannot use fallback scanning method: Error: Command failed: /usr/bin/sudo /sbin/iw dev wlan0 scan ap-force Apr 26 04:03:01 volumio volumio[2311]: command failed: Device or resource busy (-16) Apr 26 04:03:01 volumio volumio[2311]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:03:01 volumio volumio[2311]: info: Output device has changed, restarting MPD Apr 26 04:03:01 volumio sudo[2430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:01 volumio volumio[2311]: info: Output device has changed, restarting Shairport Sync Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:01 volumio sudo[2430]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2430]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio sudo[2432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:01 volumio sudo[2432]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:03:01 volumio volumio[2311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:01 volumio volumio[2311]: info: ___________ START PLUGINS ___________ Apr 26 04:03:01 volumio volumio[2311]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:03:01 volumio volumio[2311]: info: Creating MPD Configuration file Apr 26 04:03:01 volumio sudo[2439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:01 volumio volumio[2311]: info: [1745629381588] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:01 volumio sudo[2439]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:03:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:03:01 volumio sudo[2439]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:01 volumio sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:01 volumio volumio[2311]: info: [1745629381666] CoreMusicLibrary::Adding element Last_100 Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:01 volumio volumio[2311]: info: [1745629381668] CoreMusicLibrary::Adding element Webradio Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:01 volumio volumio[2311]: info: Initializing BBC Radios Apr 26 04:03:01 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:03:01 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 26 04:03:01 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:03:01 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:03:01 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:03:01 volumio volumio[2311]: info: Volumio Calling Home Apr 26 04:03:01 volumio sudo[2454]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:03:01 volumio sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:01 volumio sudo[2454]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:01 volumio volumio[2311]: info: [squeezelite_mc] Proxy server started on port 42375 Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:03:01 volumio volumio[2311]: info: CoreStateMachine::pushState Apr 26 04:03:01 volumio volumio[2311]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:01 volumio volumio[2311]: info: [squeezelite_mc] Server discovery started Apr 26 04:03:01 volumio volumio[2311]: info: [squeezelite_mc] Player finder started Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:01 volumio volumio[2311]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:03:01 volumio volumio[2311]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:01 volumio volumio[2311]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:03:01 volumio volumio[2311]: at doSend (dgram.js:692:16) Apr 26 04:03:01 volumio volumio[2311]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:03:01 volumio volumio[2311]: at afterDns (dgram.js:638:5) Apr 26 04:03:01 volumio volumio[2311]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:03:01 volumio volumio[2311]: errno: -101, Apr 26 04:03:01 volumio volumio[2311]: code: 'ENETUNREACH', Apr 26 04:03:01 volumio volumio[2311]: syscall: 'send', Apr 26 04:03:01 volumio volumio[2311]: address: '255.255.255.255', Apr 26 04:03:01 volumio volumio[2311]: port: 3483 Apr 26 04:03:01 volumio volumio[2311]: } Apr 26 04:03:01 volumio volumio[2311]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:02 volumio sudo[2478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:02 Apr 26 04:03:02 volumio sudo[2478]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:02 volumio sudo[2478]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:02 volumio volumio-remote-updater[587]: [2025-04-26 04:03:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 26 04:03:02 volumio volumio-remote-updater[587]: [2025-04-26 04:03:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 26 04:03:02 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:03:02 volumio sudo[2441]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:02 volumio sudo[2432]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:02 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:03:02 volumio systemd[1]: Started dynamicswap service. Apr 26 04:03:02 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:03:02 volumio mpd[2466]: Apr 26 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:03:02 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:03:02 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 94. Apr 26 04:03:02 volumio systemd[1]: Started dynamicswap service. Apr 26 04:03:02 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:03:02 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:03:02 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:03:02 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:03:03 volumio volumio[2492]: info: ------------------------------------------- Apr 26 04:03:03 volumio volumio[2492]: info: ----- Volumio3 ---- Apr 26 04:03:03 volumio volumio[2492]: info: ------------------------------------------- Apr 26 04:03:03 volumio volumio[2492]: info: ----- System startup ---- Apr 26 04:03:03 volumio volumio[2492]: info: ------------------------------------------- Apr 26 04:03:04 volumio volumio[2492]: info: MYVOLUMIO Environment detected Apr 26 04:03:04 volumio volumio[2492]: info: Plugin folders cleanup Apr 26 04:03:04 volumio volumio[2492]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category audio_interface Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category miscellanea Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category music_service Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category plugins.json Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category system_controller Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category user_interface Apr 26 04:03:04 volumio volumio[2492]: info: Scanning into folder /data/plugins/ Apr 26 04:03:04 volumio volumio[2492]: info: Scanning category music_service Apr 26 04:03:04 volumio volumio[2492]: info: Plugin folders cleanup completed Apr 26 04:03:04 volumio volumio[2492]: info: ------------------------------------------- Apr 26 04:03:04 volumio volumio[2492]: info: ----- Core plugins startup ---- Apr 26 04:03:04 volumio volumio[2492]: info: ------------------------------------------- Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:03:04 volumio volumio[2492]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:03:04 volumio volumio[2492]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:03:04 volumio volumio[2492]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugins from folder /data/plugins/ Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "system"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "appearance"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "network"... Apr 26 04:03:04 volumio volumio[2492]: info: Refreshing Cached IP Addresses Apr 26 04:03:04 volumio sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:03:04 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:04 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:04 volumio sudo[2522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:03:04 volumio sudo[2522]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:04 volumio sudo[2522]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "services"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "alsa_controller"... Apr 26 04:03:04 volumio sudo[2535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:03:04 volumio sudo[2535]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:04 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "wizard"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "networkfs"... Apr 26 04:03:04 volumio volumio[2492]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:03:04 volumio volumio[2492]: info: Starting Udev Watcher for removable devices Apr 26 04:03:04 volumio volumio[2492]: info: Ignoring mount for partition: boot Apr 26 04:03:04 volumio volumio[2492]: info: Ignoring mount for partition: volumio Apr 26 04:03:04 volumio volumio[2492]: info: Ignoring mount for partition: volumio_data Apr 26 04:03:04 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "upnp"... Apr 26 04:03:04 volumio volumio[2492]: info: [1745629384799] Starting Upmpd Daemon Apr 26 04:03:04 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "my_music"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "mpd"... Apr 26 04:03:04 volumio volumio[2492]: info: Loading plugin "upnp_browser"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "alarm-clock"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "airplay_emulation"... Apr 26 04:03:05 volumio volumio[2492]: info: Starting Shairport Sync Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "last_100"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "webradio"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "i2s_dacs"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "volumiodiscovery"... Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** For more information see Apr 26 04:03:05 volumio node[2492]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:05 volumio volumio[2492]: *** WARNING *** For more information see Apr 26 04:03:05 volumio node[2492]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:05 volumio node[2492]: *** WARNING *** For more information see Apr 26 04:03:05 volumio node[2492]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:05 volumio node[2492]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:05 volumio node[2492]: *** WARNING *** For more information see Apr 26 04:03:05 volumio volumio[2492]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:03:05 volumio volumio[2492]: info: Discovery: Started advertising with name: Volumio Apr 26 04:03:05 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "squeezelite_mc"... Apr 26 04:03:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd022 fail, reason -52 Apr 26 04:03:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd026 fail, reason -52 Apr 26 04:03:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02a fail, reason -52 Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "outputs"... Apr 26 04:03:05 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd02e fail, reason -52 Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "albumart"... Apr 26 04:03:05 volumio volumio[2492]: info: Plugin example_plugin is not enabled Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "inputs"... Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "updater_comm"... Apr 26 04:03:05 volumio volumio[2492]: info: Plugin mpdemulation is not enabled Apr 26 04:03:05 volumio volumio[2492]: info: Loading plugin "rest_api"... Apr 26 04:03:06 volumio volumio[2492]: info: Loading plugin "websocket"... Apr 26 04:03:06 volumio volumio[2492]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:03:06 volumio volumio[2492]: info: Loading i18n strings for locale ru Apr 26 04:03:06 volumio volumio[2492]: Updating browse sources language Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:06 volumio volumio[2492]: Forking 3 albumart workers Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::initPlayerControls Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: Express server listening on port 3000 Apr 26 04:03:06 volumio volumio[2492]: [Metrics] WebUI: 2s 822.06ms Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::resetVolumioState Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::getcurrentVolume Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::pushState Apr 26 04:03:06 volumio volumio[2492]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:06 volumio volumio[2492]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: Reloading queue from file Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::pushState Apr 26 04:03:06 volumio volumio[2492]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::setRandom null Apr 26 04:03:06 volumio volumio[2492]: info: CoreStateMachine::pushState Apr 26 04:03:06 volumio volumio[2492]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:06 volumio volumio[2492]: info: Setting Device type: Raspberry PI Apr 26 04:03:06 volumio volumio[2492]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:03:06 volumio volumio[2492]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:03:06 volumio volumio[2492]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:03:06 volumio volumio[2492]: info: Completed loading Core Plugins Apr 26 04:03:06 volumio volumio[2492]: info: Preparing to generate the ALSA configuration file Apr 26 04:03:06 volumio volumio[2492]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:03:06 volumio volumio[2492]: info: Output device has changed, restarting MPD Apr 26 04:03:06 volumio volumio[2492]: info: Output device has changed, restarting Shairport Sync Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:06 volumio volumio[2492]: info: ___________ START PLUGINS ___________ Apr 26 04:03:06 volumio volumio[2492]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:03:06 volumio volumio[2492]: info: Creating MPD Configuration file Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:06 volumio volumio[2492]: info: [1745629386463] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:03:06 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:06 volumio volumio[2492]: Starting albumart workers Apr 26 04:03:06 volumio volumio[2492]: Starting albumart workers Apr 26 04:03:06 volumio volumio[2492]: Starting albumart workers Apr 26 04:03:07 volumio sudo[2590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd090 fail, reason -52 Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd095 fail, reason -52 Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd099 fail, reason -52 Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd09d fail, reason -52 Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a1 fail, reason -52 Apr 26 04:03:07 volumio kernel: brcmfmac: brcmf_set_channel: set chanspec 0xd0a5 fail, reason -52 Apr 26 04:03:07 volumio sudo[2585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:07 volumio sudo[2590]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:07 volumio sudo[2585]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:07 volumio sudo[2592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:07 volumio sudo[2592]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:07 volumio volumio-remote-updater[587]: [2025-04-26 04:03:07] [connect] Successful connection Apr 26 04:03:07 volumio sudo[2590]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:07 volumio sudo[2583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:07 volumio wpa_supplicant[871]: wlan0: Trying to associate with SSID 'SWEETHOME_5G' Apr 26 04:03:07 volumio sudo[2583]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:07 volumio sudo[2583]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:07 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:03:07 volumio volumio[2492]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:07 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:03:07 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:03:07 volumio volumio[2492]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:07 volumio volumio[2492]: info: [1745629387687] CoreMusicLibrary::Adding element Last_100 Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:07 volumio volumio[2492]: info: [1745629387689] CoreMusicLibrary::Adding element Webradio Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:07 volumio volumio[2492]: info: Initializing BBC Radios Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:07 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:03:07 volumio volumio[2492]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:03:07 volumio volumio[2492]: info: Volumio Calling Home Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: carrier acquired Apr 26 04:03:07 volumio wpa_supplicant[871]: wlan0: Associated with cc:2d:21:1f:c3:d5 Apr 26 04:03:07 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-CONNECTED - Connection to cc:2d:21:1f:c3:d5 completed [id=0 id_str=] Apr 26 04:03:07 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 26 04:03:07 volumio wpa_supplicant[871]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=RU Apr 26 04:03:07 volumio kernel: ieee80211 phy0: brcmf_cfg80211_reg_notifier: Firmware rejected country setting Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: IAID 01:06:df:0b Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: carrier lost Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: carrier acquired Apr 26 04:03:07 volumio sudo[2627]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:03:07 volumio sudo[2627]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: IAID 01:06:df:0b Apr 26 04:03:07 volumio sudo[2627]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:07 volumio volumio[2492]: info: [squeezelite_mc] Proxy server started on port 39703 Apr 26 04:03:07 volumio sudo[2535]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: soliciting an IPv6 router Apr 26 04:03:07 volumio volumio[2492]: info: CoreStateMachine::pushState Apr 26 04:03:07 volumio volumio[2492]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:07 volumio volumio[2492]: info: [squeezelite_mc] Server discovery started Apr 26 04:03:07 volumio volumio[2492]: info: [squeezelite_mc] Player finder started Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:07 volumio volumio[2492]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:07 volumio volumio[2492]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:03:07 volumio volumio[2492]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:07 volumio volumio[2492]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:03:07 volumio volumio[2492]: at doSend (dgram.js:692:16) Apr 26 04:03:07 volumio volumio[2492]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:03:07 volumio volumio[2492]: at afterDns (dgram.js:638:5) Apr 26 04:03:07 volumio volumio[2492]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:03:07 volumio volumio[2492]: errno: -101, Apr 26 04:03:07 volumio volumio[2492]: code: 'ENETUNREACH', Apr 26 04:03:07 volumio volumio[2492]: syscall: 'send', Apr 26 04:03:07 volumio volumio[2492]: address: '255.255.255.255', Apr 26 04:03:07 volumio volumio[2492]: port: 3483 Apr 26 04:03:07 volumio volumio[2492]: } Apr 26 04:03:07 volumio volumio[2492]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:07 volumio dhcpcd[883]: wlan0: rebinding lease of 192.168.0.16 Apr 26 04:03:08 volumio dhcpcd[883]: wlan0: probing address 192.168.0.16/24 Apr 26 04:03:08 volumio sudo[2666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:02 Apr 26 04:03:08 volumio sudo[2666]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:08 volumio sudo[2666]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:08 volumio volumio-remote-updater[587]: [2025-04-26 04:03:08] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Apr 26 04:03:08 volumio volumio-remote-updater[587]: [2025-04-26 04:03:08] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Apr 26 04:03:08 volumio volumio-remote-updater[587]: [2025-04-26 04:03:08] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 26 04:03:08 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 26 04:03:08 volumio sudo[2592]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:08 volumio sudo[2585]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:08 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 26 04:03:08 volumio mpd[2654]: Apr 26 04:03 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 26 04:03:08 volumio systemd[1]: Started dynamicswap service. Apr 26 04:03:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:03:08 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 26 04:03:08 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 95. Apr 26 04:03:08 volumio systemd[1]: Stopped Volumio Backend Module. Apr 26 04:03:08 volumio systemd[1]: Started Volumio Backend Module. Apr 26 04:03:08 volumio systemd[1]: Started dynamicswap service. Apr 26 04:03:08 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 26 04:03:08 volumio systemd[1]: Started Music Player Daemon. Apr 26 04:03:09 volumio volumio[2681]: info: ------------------------------------------- Apr 26 04:03:09 volumio volumio[2681]: info: ----- Volumio3 ---- Apr 26 04:03:09 volumio volumio[2681]: info: ------------------------------------------- Apr 26 04:03:09 volumio volumio[2681]: info: ----- System startup ---- Apr 26 04:03:09 volumio volumio[2681]: info: ------------------------------------------- Apr 26 04:03:10 volumio volumio[2681]: info: MYVOLUMIO Environment detected Apr 26 04:03:10 volumio volumio[2681]: info: Plugin folders cleanup Apr 26 04:03:10 volumio volumio[2681]: info: Scanning into folder /volumio/app/plugins/ Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category audio_interface Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category miscellanea Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category music_service Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category plugins.json Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category system_controller Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category user_interface Apr 26 04:03:10 volumio volumio[2681]: info: Scanning into folder /data/plugins/ Apr 26 04:03:10 volumio volumio[2681]: info: Scanning category music_service Apr 26 04:03:10 volumio volumio[2681]: info: Plugin folders cleanup completed Apr 26 04:03:10 volumio volumio[2681]: info: ------------------------------------------- Apr 26 04:03:10 volumio volumio[2681]: info: ----- Core plugins startup ---- Apr 26 04:03:10 volumio volumio[2681]: info: ------------------------------------------- Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugins from folder /volumio/app/plugins/ Apr 26 04:03:10 volumio volumio[2681]: info: Adding plugin upnp to MyMusic Plugins Apr 26 04:03:10 volumio volumio[2681]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 26 04:03:10 volumio volumio[2681]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugins from folder /data/plugins/ Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "system"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "appearance"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "network"... Apr 26 04:03:10 volumio volumio[2681]: info: Refreshing Cached IP Addresses Apr 26 04:03:10 volumio sudo[2711]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 26 04:03:10 volumio sudo[2711]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:10 volumio sudo[2711]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:10 volumio sudo[2713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 26 04:03:10 volumio sudo[2713]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:10 volumio sudo[2713]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "services"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "alsa_controller"... Apr 26 04:03:10 volumio sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 26 04:03:10 volumio sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:10 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "wizard"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "networkfs"... Apr 26 04:03:10 volumio volumio[2681]: info: Cannot mount NAS MUZZZIKA at system boot, trial number 1 ,retrying in 5 seconds Apr 26 04:03:10 volumio volumio[2681]: info: Starting Udev Watcher for removable devices Apr 26 04:03:10 volumio volumio[2681]: info: Ignoring mount for partition: boot Apr 26 04:03:10 volumio volumio[2681]: info: Ignoring mount for partition: volumio Apr 26 04:03:10 volumio volumio[2681]: info: Ignoring mount for partition: volumio_data Apr 26 04:03:10 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "volumio_command_line_client"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "upnp"... Apr 26 04:03:10 volumio volumio[2681]: info: [1745629390851] Starting Upmpd Daemon Apr 26 04:03:10 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "my_music"... Apr 26 04:03:10 volumio volumio[2681]: info: Loading plugin "mpd"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "upnp_browser"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "alarm-clock"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "airplay_emulation"... Apr 26 04:03:11 volumio volumio[2681]: info: Starting Shairport Sync Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "last_100"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "webradio"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "i2s_dacs"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "volumiodiscovery"... Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:11 volumio node[2681]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** For more information see Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:11 volumio volumio[2681]: *** WARNING *** For more information see Apr 26 04:03:11 volumio node[2681]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:11 volumio node[2681]: *** WARNING *** For more information see Apr 26 04:03:11 volumio node[2681]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 26 04:03:11 volumio node[2681]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 26 04:03:11 volumio node[2681]: *** WARNING *** For more information see Apr 26 04:03:11 volumio volumio[2681]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 26 04:03:11 volumio volumio[2681]: info: Discovery: Started advertising with name: Volumio Apr 26 04:03:11 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "squeezelite_mc"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "outputs"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "albumart"... Apr 26 04:03:11 volumio volumio[2681]: info: Plugin example_plugin is not enabled Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "inputs"... Apr 26 04:03:11 volumio volumio[2681]: info: Loading plugin "updater_comm"... Apr 26 04:03:11 volumio volumio[2681]: info: Plugin mpdemulation is not enabled Apr 26 04:03:12 volumio volumio[2681]: info: Loading plugin "rest_api"... Apr 26 04:03:12 volumio volumio[2681]: info: Loading plugin "websocket"... Apr 26 04:03:12 volumio volumio[2681]: info: Starting Socket.io Server version 2.3.0 Apr 26 04:03:12 volumio volumio[2681]: info: Loading i18n strings for locale ru Apr 26 04:03:12 volumio volumio[2681]: Updating browse sources language Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:12 volumio volumio[2681]: Forking 3 albumart workers Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::initPlayerControls Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: Express server listening on port 3000 Apr 26 04:03:12 volumio volumio[2681]: [Metrics] WebUI: 2s 802.05ms Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::resetVolumioState Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::getcurrentVolume Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::pushState Apr 26 04:03:12 volumio volumio[2681]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:12 volumio volumio[2681]: info: Volumio Network Manager: Network status updated: 0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: Reloading queue from file Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::setRepeat null single undefined Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::pushState Apr 26 04:03:12 volumio volumio[2681]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::setRandom null Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::pushState Apr 26 04:03:12 volumio volumio[2681]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:12 volumio volumio[2681]: info: Setting Device type: Raspberry PI Apr 26 04:03:12 volumio volumio[2681]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Apr 26 04:03:12 volumio volumio[2681]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03114 Apr 26 04:03:12 volumio volumio[2681]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Apr 26 04:03:12 volumio volumio[2681]: info: Completed loading Core Plugins Apr 26 04:03:12 volumio volumio[2681]: info: Preparing to generate the ALSA configuration file Apr 26 04:03:12 volumio volumio[2681]: info: Asound.conf file unchanged, so no further update is needed Apr 26 04:03:12 volumio volumio[2681]: info: Output device has changed, restarting MPD Apr 26 04:03:12 volumio volumio[2681]: info: Output device has changed, restarting Shairport Sync Apr 26 04:03:12 volumio sudo[2774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:12 volumio sudo[2774]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:12 volumio sudo[2774]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:12 volumio sudo[2776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:12 volumio sudo[2776]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:12 volumio volumio[2681]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:12 volumio volumio[2681]: info: ___________ START PLUGINS ___________ Apr 26 04:03:12 volumio systemd[1]: Stopping Music Player Daemon... Apr 26 04:03:12 volumio volumio[2681]: info: ControllerMpd::onStart: Initializing MPD Apr 26 04:03:12 volumio volumio[2681]: info: Creating MPD Configuration file Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:12 volumio volumio[2681]: info: [1745629392491] CoreMusicLibrary::Adding element Медиа-серверы Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:12 volumio sudo[2783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 26 04:03:12 volumio sudo[2783]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:12 volumio sudo[2783]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:12 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:03:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:03:12 volumio sudo[2785]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 26 04:03:12 volumio sudo[2785]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:12 volumio volumio[2681]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:12 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:03:12 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 26 04:03:12 volumio systemd[1]: mpd.service: Succeeded. Apr 26 04:03:12 volumio systemd[1]: Stopped Music Player Daemon. Apr 26 04:03:12 volumio volumio[2681]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 26 04:03:12 volumio systemd[1]: Starting Music Player Daemon... Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:12 volumio volumio[2681]: info: [1745629392638] CoreMusicLibrary::Adding element Last_100 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 26 04:03:12 volumio volumio[2681]: info: [1745629392641] CoreMusicLibrary::Adding element Webradio Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:12 volumio volumio[2681]: info: Initializing BBC Radios Apr 26 04:03:12 volumio sudo[2795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 26 04:03:12 volumio sudo[2795]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 26 04:03:12 volumio sudo[2795]: pam_unix(sudo:session): session closed for user root Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: [squeezelite_mc] Starting proxy server... Apr 26 04:03:12 volumio volumio[2681]: info: Volumio Calling Home Apr 26 04:03:12 volumio volumio[2681]: Starting albumart workers Apr 26 04:03:12 volumio volumio[2681]: info: [squeezelite_mc] Proxy server started on port 43523 Apr 26 04:03:12 volumio volumio[2681]: Starting albumart workers Apr 26 04:03:12 volumio volumio[2681]: Starting albumart workers Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioRetrievevolume Apr 26 04:03:12 volumio volumio[2681]: info: CoreStateMachine::pushState Apr 26 04:03:12 volumio volumio[2681]: info: CorePlayQueue::getTrack 0 Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::volumioPushState Apr 26 04:03:12 volumio volumio[2681]: info: [squeezelite_mc] Server discovery started Apr 26 04:03:12 volumio volumio[2681]: info: [squeezelite_mc] Player finder started Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 26 04:03:12 volumio volumio[2681]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 26 04:03:12 volumio volumio[2681]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:12 volumio volumio[2681]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 26 04:03:12 volumio volumio[2681]: at doSend (dgram.js:692:16) Apr 26 04:03:12 volumio volumio[2681]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 26 04:03:12 volumio volumio[2681]: at afterDns (dgram.js:638:5) Apr 26 04:03:12 volumio volumio[2681]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 26 04:03:12 volumio volumio[2681]: errno: -101, Apr 26 04:03:12 volumio volumio[2681]: code: 'ENETUNREACH', Apr 26 04:03:12 volumio volumio[2681]: syscall: 'send', Apr 26 04:03:12 volumio volumio[2681]: address: '255.255.255.255', Apr 26 04:03:12 volumio volumio[2681]: port: 3483 Apr 26 04:03:12 volumio volumio[2681]: } Apr 26 04:03:12 volumio volumio[2681]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 26 04:03:13 volumio dhcpcd[883]: wlan0: leased 192.168.0.16 for 86400 seconds Apr 26 04:03:13 volumio avahi-daemon[581]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.16. Apr 26 04:03:13 volumio dhcpcd[883]: wlan0: adding route to 192.168.0.0/24 Apr 26 04:03:13 volumio dhcpcd[883]: wlan0: adding default route via 192.168.0.1 Apr 26 04:03:13 volumio avahi-daemon[581]: New relevant interface wlan0.IPv4 for mDNS. Apr 26 04:03:13 volumio avahi-daemon[581]: Registering new address record for 192.168.0.16 on wlan0.IPv4. Apr 26 04:03:13 volumio sudo[2867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-26 04:02 Apr 26 04:03:13 volumio sudo[2867]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"