-- Logs begin at Fri 2024-10-11 00:52:47 EEST, end at Fri 2024-10-11 13:30:51 EEST. -- Oct 11 13:30:00 volumio dhcpcd[907]: eth0: carrier lost Oct 11 13:30:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 11 13:30:00 volumio avahi-daemon[679]: Withdrawing address record for 192.168.31.163 on eth0. Oct 11 13:30:00 volumio avahi-daemon[679]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 11 13:30:00 volumio avahi-daemon[679]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 11 13:30:00 volumio dhcpcd[907]: eth0: deleting route to 192.168.31.0/24 Oct 11 13:30:00 volumio dhcpcd[907]: eth0: deleting default route via 192.168.31.1 Oct 11 13:30:00 volumio volumio[864]: info: Discovery: A device disappeared from network Oct 11 13:30:00 volumio volumio[864]: info: Discovery: Device volumio disappeared from network Oct 11 13:30:01 volumio ifplugd(eth0)[814]: Link beat lost. Oct 11 13:30:02 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 11 13:30:02 volumio dhcpcd[907]: eth0: carrier acquired Oct 11 13:30:02 volumio dhcpcd[907]: eth0: IAID eb:5d:71:15 Oct 11 13:30:02 volumio dhcpcd[907]: eth0: soliciting an IPv6 router Oct 11 13:30:02 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 11 13:30:03 volumio dhcpcd[907]: eth0: rebinding lease of 192.168.31.163 Oct 11 13:30:03 volumio dhcpcd[907]: eth0: carrier lost Oct 11 13:30:04 volumio ntpd[801]: Deleting interface #4 eth0, 192.168.31.163#123, interface stats: received=100, sent=100, dropped=1, active_time=2925 secs Oct 11 13:30:04 volumio ntpd[801]: 162.159.200.123 local addr 192.168.31.163 -> Oct 11 13:30:04 volumio ntpd[801]: 79.142.192.130 local addr 192.168.31.163 -> Oct 11 13:30:04 volumio ntpd[801]: 91.236.251.24 local addr 192.168.31.163 -> Oct 11 13:30:04 volumio ntpd[801]: 193.84.22.254 local addr 192.168.31.163 -> Oct 11 13:30:04 volumio ntpd[801]: 193.106.144.6 local addr 192.168.31.163 -> Oct 11 13:30:04 volumio ntpd[801]: 91.236.251.31 local addr 192.168.31.163 -> Oct 11 13:30:11 volumio ifplugd(eth0)[814]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Oct 11 13:30:11 volumio ifplugd(eth0)[814]: client: sending signal ALRM to pid 907 Oct 11 13:30:11 volumio dhcpcd[27829]: sending signal ALRM to pid 907 Oct 11 13:30:11 volumio dhcpcd[907]: received SIGALRM, releasing Oct 11 13:30:11 volumio dhcpcd[907]: eth0: removing interface Oct 11 13:30:11 volumio ifplugd(eth0)[814]: client: waiting for pid 907 to exit Oct 11 13:30:11 volumio dhcpcd[27829]: waiting for pid 907 to exit Oct 11 13:30:11 volumio dhcpcd[907]: dhcpcd exited Oct 11 13:30:11 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 11 13:30:11 volumio ifplugd(eth0)[814]: Program executed successfully. Oct 11 13:30:11 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 11 13:30:11 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 11 13:30:23 volumio volumio[864]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:23 volumio volumio[864]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 11 13:30:23 volumio volumio[864]: at doSend (dgram.js:692:16) Oct 11 13:30:23 volumio volumio[864]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 11 13:30:23 volumio volumio[864]: at afterDns (dgram.js:638:5) Oct 11 13:30:23 volumio volumio[864]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 11 13:30:23 volumio volumio[864]: errno: -101, Oct 11 13:30:23 volumio volumio[864]: code: 'ENETUNREACH', Oct 11 13:30:23 volumio volumio[864]: syscall: 'send', Oct 11 13:30:23 volumio volumio[864]: address: '255.255.255.255', Oct 11 13:30:23 volumio volumio[864]: port: 3483 Oct 11 13:30:23 volumio volumio[864]: } Oct 11 13:30:23 volumio volumio[864]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:24 volumio sudo[27884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 13:29 Oct 11 13:30:24 volumio sudo[27884]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:24 volumio sudo[27884]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:24 volumio volumio-remote-updater[641]: [2024-10-11 13:30:24] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 11 13:30:24 volumio volumio-remote-updater[641]: [2024-10-11 13:30:24] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 11 13:30:24 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 13:30:24 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 13:30:24 volumio systemd[1]: Started dynamicswap service. Oct 11 13:30:24 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 13:30:24 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 13:30:24 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Oct 11 13:30:24 volumio systemd[1]: Started dynamicswap service. Oct 11 13:30:24 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 13:30:24 volumio systemd[1]: Started Volumio Backend Module. Oct 11 13:30:24 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 13:30:26 volumio volumio[27899]: info: ------------------------------------------- Oct 11 13:30:26 volumio volumio[27899]: info: ----- Volumio3 ---- Oct 11 13:30:26 volumio volumio[27899]: info: ------------------------------------------- Oct 11 13:30:26 volumio volumio[27899]: info: ----- System startup ---- Oct 11 13:30:26 volumio volumio[27899]: info: ------------------------------------------- Oct 11 13:30:27 volumio volumio[27899]: info: MYVOLUMIO Environment detected Oct 11 13:30:27 volumio volumio[27899]: info: Plugin folders cleanup Oct 11 13:30:27 volumio volumio[27899]: info: Scanning into folder /volumio/app/plugins/ Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category audio_interface Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category miscellanea Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category music_service Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category plugins.json Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category system_controller Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category user_interface Oct 11 13:30:27 volumio volumio[27899]: info: Scanning into folder /data/plugins/ Oct 11 13:30:27 volumio volumio[27899]: info: Scanning category music_service Oct 11 13:30:27 volumio volumio[27899]: info: Plugin folders cleanup completed Oct 11 13:30:27 volumio volumio[27899]: info: ------------------------------------------- Oct 11 13:30:27 volumio volumio[27899]: info: ----- Core plugins startup ---- Oct 11 13:30:27 volumio volumio[27899]: info: ------------------------------------------- Oct 11 13:30:27 volumio volumio[27899]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 13:30:27 volumio volumio[27899]: info: Adding plugin upnp to MyMusic Plugins Oct 11 13:30:27 volumio volumio[27899]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 13:30:27 volumio volumio[27899]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 13:30:27 volumio volumio[27899]: info: Loading plugins from folder /data/plugins/ Oct 11 13:30:27 volumio volumio[27899]: info: Loading plugin "system"... Oct 11 13:30:27 volumio volumio[27899]: info: Loading plugin "appearance"... Oct 11 13:30:28 volumio volumio[27899]: info: Loading plugin "network"... Oct 11 13:30:28 volumio volumio[27899]: info: Refreshing Cached IP Addresses Oct 11 13:30:28 volumio volumio[27899]: info: Loading plugin "services"... Oct 11 13:30:28 volumio sudo[27926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 13:30:28 volumio sudo[27926]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:28 volumio volumio[27899]: info: Loading plugin "alsa_controller"... Oct 11 13:30:28 volumio sudo[27926]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:28 volumio sudo[27928]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 13:30:28 volumio sudo[27928]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:28 volumio sudo[27928]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:28 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:28 volumio volumio[27899]: info: Loading plugin "wizard"... Oct 11 13:30:28 volumio volumio[27899]: info: Loading plugin "networkfs"... Oct 11 13:30:28 volumio volumio[27899]: info: Starting Udev Watcher for removable devices Oct 11 13:30:28 volumio volumio[27899]: info: Ignoring mount for partition: boot Oct 11 13:30:28 volumio volumio[27899]: info: Ignoring mount for partition: volumio Oct 11 13:30:28 volumio volumio[27899]: info: Ignoring mount for partition: volumio_data Oct 11 13:30:28 volumio volumio[27899]: info: Mounting Device CES_X64FREO Oct 11 13:30:28 volumio sudo[27938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 11 13:30:28 volumio sudo[27938]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:29 volumio sudo[27938]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:29 volumio volumio[27899]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 11 13:30:29 volumio volumio[27899]: error: Failed to mount CES_X64FREO: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda4" "/mnt/USB/CES_X64FREO" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 11 13:30:29 volumio volumio[27899]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 11 13:30:29 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "volumio_command_line_client"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "upnp"... Oct 11 13:30:29 volumio volumio[27899]: info: [1728642629046] Starting Upmpd Daemon Oct 11 13:30:29 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "my_music"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "mpd"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "upnp_browser"... Oct 11 13:30:29 volumio volumio-remote-updater[641]: [2024-10-11 13:30:29] [connect] Successful connection Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "alarm-clock"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "airplay_emulation"... Oct 11 13:30:29 volumio volumio[27899]: info: Starting Shairport Sync Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "last_100"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "webradio"... Oct 11 13:30:29 volumio volumio[27899]: info: Loading plugin "i2s_dacs"... Oct 11 13:30:30 volumio volumio[27899]: info: Loading plugin "volumiodiscovery"... Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** For more information see Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:30 volumio node[27899]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:30 volumio volumio[27899]: *** WARNING *** For more information see Oct 11 13:30:30 volumio node[27899]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:30 volumio node[27899]: *** WARNING *** For more information see Oct 11 13:30:30 volumio node[27899]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:30 volumio node[27899]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:30 volumio node[27899]: *** WARNING *** For more information see Oct 11 13:30:30 volumio volumio[27899]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 13:30:30 volumio volumio[27899]: info: Discovery: Started advertising with name: Volumio Oct 11 13:30:30 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:30 volumio volumio[27899]: info: Loading plugin "squeezelite_mc"... Oct 11 13:30:30 volumio volumio[27899]: info: Loading plugin "ytcr"... Oct 11 13:30:33 volumio volumio[27899]: info: Loading plugin "ytmusic"... Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "outputs"... Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "albumart"... Oct 11 13:30:34 volumio volumio[27899]: info: Plugin example_plugin is not enabled Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "inputs"... Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "updater_comm"... Oct 11 13:30:34 volumio volumio[27899]: info: Plugin mpdemulation is not enabled Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "rest_api"... Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "websocket"... Oct 11 13:30:34 volumio volumio[27899]: info: Starting Socket.io Server version 2.3.0 Oct 11 13:30:34 volumio volumio[27899]: info: Loading plugin "minidlna"... Oct 11 13:30:34 volumio volumio[27899]: info: Applying required configuration parameters for plugin minidlna Oct 11 13:30:35 volumio volumio[27899]: Forking 3 albumart workers Oct 11 13:30:35 volumio volumio[27899]: info: Loading i18n strings for locale ru Oct 11 13:30:35 volumio volumio[27899]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 13:30:35 volumio volumio[27899]: Updating browse sources language Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::initPlayerControls Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: Express server listening on port 3000 Oct 11 13:30:35 volumio volumio[27899]: [Metrics] WebUI: 9s 256.35ms Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::resetVolumioState Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::getcurrentVolume Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::pushState Oct 11 13:30:35 volumio volumio[27899]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::setRepeat true single undefined Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::pushState Oct 11 13:30:35 volumio volumio[27899]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::setRandom false Oct 11 13:30:35 volumio volumio[27899]: info: CoreStateMachine::pushState Oct 11 13:30:35 volumio volumio[27899]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:35 volumio volumio[27899]: info: Setting Device type: Raspberry PI Oct 11 13:30:35 volumio volumio[27899]: info: Completed loading Core Plugins Oct 11 13:30:35 volumio volumio[27899]: info: Preparing to generate the ALSA configuration file Oct 11 13:30:35 volumio volumio[27899]: info: Asound.conf file unchanged, so no further update is needed Oct 11 13:30:35 volumio volumio[27899]: info: Output device has changed, restarting MPD Oct 11 13:30:35 volumio volumio[27899]: info: Output device has changed, restarting Shairport Sync Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:35 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:35 volumio sudo[27981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 13:30:35 volumio sudo[27981]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:35 volumio sudo[27981]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:35 volumio sudo[27983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 13:30:35 volumio sudo[27983]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:35 volumio volumio[27899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:35 volumio volumio[27899]: info: ___________ START PLUGINS ___________ Oct 11 13:30:35 volumio volumio[27899]: info: ControllerMpd::onStart: Initializing MPD Oct 11 13:30:35 volumio volumio[27899]: info: Creating MPD Configuration file Oct 11 13:30:35 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:36 volumio volumio[27899]: info: [1728642636008] CoreMusicLibrary::Adding element Медиа-серверы Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:36 volumio sudo[27990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 13:30:36 volumio sudo[27990]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:36 volumio sudo[27990]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:36 volumio systemd[1]: mpd.service: Succeeded. Oct 11 13:30:36 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 13:30:36 volumio sudo[27991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 13:30:36 volumio sudo[27991]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:36 volumio systemd[1]: Starting Music Player Daemon... Oct 11 13:30:36 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 13:30:36 volumio systemd[1]: mpd.service: Succeeded. Oct 11 13:30:36 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 13:30:36 volumio systemd[1]: Starting Music Player Daemon... Oct 11 13:30:36 volumio volumio[27899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:36 volumio volumio[27899]: Starting albumart workers Oct 11 13:30:36 volumio volumio[27899]: Starting albumart workers Oct 11 13:30:36 volumio volumio[27899]: Starting albumart workers Oct 11 13:30:36 volumio sudo[27998]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 13:30:36 volumio sudo[27998]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:36 volumio volumio[27899]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:36 volumio volumio[27899]: info: [1728642636321] CoreMusicLibrary::Adding element Last_100 Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:36 volumio sudo[27998]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:36 volumio volumio[27899]: info: [1728642636326] CoreMusicLibrary::Adding element Webradio Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:36 volumio volumio[27899]: info: Initializing BBC Radios Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:36 volumio volumio[27899]: info: [squeezelite_mc] Starting proxy server... Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:36 volumio volumio[27899]: info: [1728642636774] CoreMusicLibrary::Adding element YouTube Music Oct 11 13:30:36 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:36 volumio volumio[27899]: Cannot find translation for source YouTube Music Oct 11 13:30:36 volumio volumio[27899]: info: Loading i18n strings for locale ru Oct 11 13:30:36 volumio volumio[27899]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 13:30:36 volumio volumio[27899]: info: Volumio Calling Home Oct 11 13:30:37 volumio volumio[27899]: info: [squeezelite_mc] Proxy server started on port 36663 Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::volumioGetState Oct 11 13:30:37 volumio volumio[27899]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:37 volumio volumio[27899]: info: CoreStateMachine::pushState Oct 11 13:30:37 volumio volumio[27899]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:37 volumio volumio[27899]: info: [squeezelite_mc] Server discovery started Oct 11 13:30:37 volumio volumio[27899]: info: [squeezelite_mc] Player finder started Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:37 volumio volumio[27899]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:37 volumio volumio[27899]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 11 13:30:37 volumio volumio[27899]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:37 volumio volumio[27899]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 11 13:30:37 volumio volumio[27899]: at doSend (dgram.js:692:16) Oct 11 13:30:37 volumio volumio[27899]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 11 13:30:37 volumio volumio[27899]: at afterDns (dgram.js:638:5) Oct 11 13:30:37 volumio volumio[27899]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 11 13:30:37 volumio volumio[27899]: errno: -101, Oct 11 13:30:37 volumio volumio[27899]: code: 'ENETUNREACH', Oct 11 13:30:37 volumio volumio[27899]: syscall: 'send', Oct 11 13:30:37 volumio volumio[27899]: address: '255.255.255.255', Oct 11 13:30:37 volumio volumio[27899]: port: 3483 Oct 11 13:30:37 volumio volumio[27899]: } Oct 11 13:30:37 volumio volumio[27899]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:38 volumio sudo[28055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 13:29 Oct 11 13:30:38 volumio sudo[28055]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:38 volumio sudo[28055]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:38 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 11 13:30:38 volumio sudo[27983]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:38 volumio sudo[27991]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:38 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 11 13:30:38 volumio systemd[1]: Started dynamicswap service. Oct 11 13:30:38 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 13:30:38 volumio mpd[28003]: Oct 11 13:30 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 11 13:30:38 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 11 13:30:38 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Oct 11 13:30:38 volumio systemd[1]: Started dynamicswap service. Oct 11 13:30:38 volumio systemd[1]: Stopped Volumio Backend Module. Oct 11 13:30:38 volumio systemd[1]: Started Volumio Backend Module. Oct 11 13:30:38 volumio systemd[1]: Started Music Player Daemon. Oct 11 13:30:38 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 11 13:30:40 volumio volumio[28073]: info: ------------------------------------------- Oct 11 13:30:40 volumio volumio[28073]: info: ----- Volumio3 ---- Oct 11 13:30:40 volumio volumio[28073]: info: ------------------------------------------- Oct 11 13:30:40 volumio volumio[28073]: info: ----- System startup ---- Oct 11 13:30:40 volumio volumio[28073]: info: ------------------------------------------- Oct 11 13:30:41 volumio volumio[28073]: info: MYVOLUMIO Environment detected Oct 11 13:30:41 volumio volumio[28073]: info: Plugin folders cleanup Oct 11 13:30:41 volumio volumio[28073]: info: Scanning into folder /volumio/app/plugins/ Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category audio_interface Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category miscellanea Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category music_service Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category plugins.json Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category system_controller Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category user_interface Oct 11 13:30:41 volumio volumio[28073]: info: Scanning into folder /data/plugins/ Oct 11 13:30:41 volumio volumio[28073]: info: Scanning category music_service Oct 11 13:30:41 volumio volumio[28073]: info: Plugin folders cleanup completed Oct 11 13:30:41 volumio volumio[28073]: info: ------------------------------------------- Oct 11 13:30:41 volumio volumio[28073]: info: ----- Core plugins startup ---- Oct 11 13:30:41 volumio volumio[28073]: info: ------------------------------------------- Oct 11 13:30:41 volumio volumio[28073]: info: Loading plugins from folder /volumio/app/plugins/ Oct 11 13:30:41 volumio volumio[28073]: info: Adding plugin upnp to MyMusic Plugins Oct 11 13:30:41 volumio volumio[28073]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 11 13:30:41 volumio volumio[28073]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 11 13:30:41 volumio volumio[28073]: info: Loading plugins from folder /data/plugins/ Oct 11 13:30:41 volumio volumio[28073]: info: Loading plugin "system"... Oct 11 13:30:41 volumio volumio[28073]: info: Loading plugin "appearance"... Oct 11 13:30:42 volumio volumio-remote-updater[641]: [2024-10-11 13:30:42] [connect] Successful connection Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "network"... Oct 11 13:30:42 volumio volumio[28073]: info: Refreshing Cached IP Addresses Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "services"... Oct 11 13:30:42 volumio sudo[28099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 11 13:30:42 volumio sudo[28099]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "alsa_controller"... Oct 11 13:30:42 volumio sudo[28099]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:42 volumio sudo[28101]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 11 13:30:42 volumio sudo[28101]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:42 volumio sudo[28101]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:42 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "wizard"... Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "networkfs"... Oct 11 13:30:42 volumio volumio[28073]: info: Starting Udev Watcher for removable devices Oct 11 13:30:42 volumio volumio[28073]: info: Ignoring mount for partition: boot Oct 11 13:30:42 volumio volumio[28073]: info: Ignoring mount for partition: volumio Oct 11 13:30:42 volumio volumio[28073]: info: Ignoring mount for partition: volumio_data Oct 11 13:30:42 volumio volumio[28073]: info: Mounting Device CES_X64FREO Oct 11 13:30:42 volumio sudo[28110]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 11 13:30:42 volumio sudo[28110]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:42 volumio sudo[28110]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:42 volumio volumio[28073]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 11 13:30:42 volumio volumio[28073]: error: Failed to mount CES_X64FREO: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda4" "/mnt/USB/CES_X64FREO" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 11 13:30:42 volumio volumio[28073]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 11 13:30:42 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "volumio_command_line_client"... Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "upnp"... Oct 11 13:30:42 volumio volumio[28073]: info: [1728642642535] Starting Upmpd Daemon Oct 11 13:30:42 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "my_music"... Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "mpd"... Oct 11 13:30:42 volumio volumio[28073]: info: Loading plugin "upnp_browser"... Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "alarm-clock"... Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "airplay_emulation"... Oct 11 13:30:43 volumio volumio[28073]: info: Starting Shairport Sync Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "last_100"... Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "webradio"... Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "i2s_dacs"... Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "volumiodiscovery"... Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** For more information see Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:43 volumio node[28073]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:43 volumio volumio[28073]: *** WARNING *** For more information see Oct 11 13:30:43 volumio node[28073]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:43 volumio node[28073]: *** WARNING *** For more information see Oct 11 13:30:43 volumio node[28073]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 11 13:30:43 volumio node[28073]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 11 13:30:43 volumio node[28073]: *** WARNING *** For more information see Oct 11 13:30:43 volumio volumio[28073]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 11 13:30:43 volumio volumio[28073]: info: Discovery: Started advertising with name: Volumio Oct 11 13:30:43 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 11 13:30:43 volumio volumio[28073]: info: Loading plugin "squeezelite_mc"... Oct 11 13:30:44 volumio volumio[28073]: info: Loading plugin "ytcr"... Oct 11 13:30:45 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 11 13:30:45 volumio ifplugd(eth0)[814]: Link beat detected. Oct 11 13:30:45 volumio ifplugd(eth0)[814]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 11 13:30:45 volumio dhcpcd[28133]: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 11 13:30:45 volumio ifplugd(eth0)[814]: client: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 11 13:30:45 volumio dhcpcd[28133]: eth0: IAID eb:5d:71:15 Oct 11 13:30:45 volumio dhcpcd[28133]: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 11 13:30:45 volumio ifplugd(eth0)[814]: client: eth0: IAID eb:5d:71:15 Oct 11 13:30:45 volumio dhcpcd[28133]: ipv6_addaddr1: Permission denied Oct 11 13:30:45 volumio ifplugd(eth0)[814]: client: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 11 13:30:45 volumio ifplugd(eth0)[814]: client: ipv6_addaddr1: Permission denied Oct 11 13:30:46 volumio dhcpcd[28133]: eth0: rebinding lease of 192.168.31.163 Oct 11 13:30:46 volumio ifplugd(eth0)[814]: client: eth0: rebinding lease of 192.168.31.163 Oct 11 13:30:46 volumio dhcpcd[28133]: eth0: probing address 192.168.31.163/24 Oct 11 13:30:46 volumio ifplugd(eth0)[814]: client: eth0: probing address 192.168.31.163/24 Oct 11 13:30:46 volumio dhcpcd[28133]: eth0: soliciting an IPv6 router Oct 11 13:30:46 volumio ifplugd(eth0)[814]: client: eth0: soliciting an IPv6 router Oct 11 13:30:46 volumio volumio[28073]: info: Loading plugin "ytmusic"... Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "outputs"... Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "albumart"... Oct 11 13:30:48 volumio volumio[28073]: info: Plugin example_plugin is not enabled Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "inputs"... Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "updater_comm"... Oct 11 13:30:48 volumio volumio[28073]: info: Plugin mpdemulation is not enabled Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "rest_api"... Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "websocket"... Oct 11 13:30:48 volumio volumio[28073]: info: Starting Socket.io Server version 2.3.0 Oct 11 13:30:48 volumio volumio[28073]: info: Loading plugin "minidlna"... Oct 11 13:30:48 volumio volumio[28073]: info: Applying required configuration parameters for plugin minidlna Oct 11 13:30:48 volumio volumio[28073]: Forking 3 albumart workers Oct 11 13:30:48 volumio volumio[28073]: info: Loading i18n strings for locale ru Oct 11 13:30:48 volumio volumio[28073]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 13:30:48 volumio volumio[28073]: Updating browse sources language Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::initPlayerControls Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:48 volumio volumio[28073]: Express server listening on port 3000 Oct 11 13:30:48 volumio volumio[28073]: [Metrics] WebUI: 9s 124.92ms Oct 11 13:30:48 volumio volumio[28073]: info: CoreStateMachine::resetVolumioState Oct 11 13:30:48 volumio volumio[28073]: info: CoreStateMachine::getcurrentVolume Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 13:30:48 volumio volumio[28073]: info: CoreStateMachine::pushState Oct 11 13:30:48 volumio volumio[28073]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:48 volumio volumio[28073]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: info: CoreStateMachine::setRepeat true single undefined Oct 11 13:30:49 volumio volumio[28073]: info: CoreStateMachine::pushState Oct 11 13:30:49 volumio volumio[28073]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:49 volumio volumio[28073]: info: CoreStateMachine::setRandom false Oct 11 13:30:49 volumio volumio[28073]: info: CoreStateMachine::pushState Oct 11 13:30:49 volumio volumio[28073]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:49 volumio volumio[28073]: info: Setting Device type: Raspberry PI Oct 11 13:30:49 volumio volumio[28073]: info: Completed loading Core Plugins Oct 11 13:30:49 volumio volumio[28073]: info: Preparing to generate the ALSA configuration file Oct 11 13:30:49 volumio volumio[28073]: info: Asound.conf file unchanged, so no further update is needed Oct 11 13:30:49 volumio volumio[28073]: info: Output device has changed, restarting MPD Oct 11 13:30:49 volumio sudo[28173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 13:30:49 volumio sudo[28173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:49 volumio volumio[28073]: info: Output device has changed, restarting Shairport Sync Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:49 volumio sudo[28173]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:49 volumio sudo[28176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 13:30:49 volumio sudo[28176]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:49 volumio systemd[1]: Stopping Music Player Daemon... Oct 11 13:30:49 volumio volumio[28073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:49 volumio volumio[28073]: info: ___________ START PLUGINS ___________ Oct 11 13:30:49 volumio volumio[28073]: info: ControllerMpd::onStart: Initializing MPD Oct 11 13:30:49 volumio volumio[28073]: info: Creating MPD Configuration file Oct 11 13:30:49 volumio systemd[1]: mpd.service: Succeeded. Oct 11 13:30:49 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 13:30:49 volumio systemd[1]: Starting Music Player Daemon... Oct 11 13:30:49 volumio sudo[28184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 11 13:30:49 volumio sudo[28184]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:49 volumio sudo[28186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 11 13:30:49 volumio sudo[28186]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:49 volumio volumio[28073]: info: [1728642649577] CoreMusicLibrary::Adding element Медиа-серверы Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:49 volumio sudo[28184]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:49 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 11 13:30:49 volumio systemd[1]: mpd.service: Succeeded. Oct 11 13:30:49 volumio systemd[1]: Stopped Music Player Daemon. Oct 11 13:30:49 volumio systemd[1]: Starting Music Player Daemon... Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: Starting albumart workers Oct 11 13:30:49 volumio volumio[28073]: Starting albumart workers Oct 11 13:30:49 volumio sudo[28189]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 11 13:30:49 volumio sudo[28189]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 11 13:30:49 volumio volumio[28073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:49 volumio sudo[28189]: pam_unix(sudo:session): session closed for user root Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:49 volumio volumio[28073]: Starting albumart workers Oct 11 13:30:49 volumio volumio[28073]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:49 volumio volumio[28073]: info: [1728642649860] CoreMusicLibrary::Adding element Last_100 Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:49 volumio volumio[28073]: info: [1728642649865] CoreMusicLibrary::Adding element Webradio Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:49 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:49 volumio volumio[28073]: info: Initializing BBC Radios Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 11 13:30:50 volumio volumio[28073]: info: [squeezelite_mc] Starting proxy server... Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 11 13:30:50 volumio volumio[28073]: info: [1728642650260] CoreMusicLibrary::Adding element YouTube Music Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 11 13:30:50 volumio volumio[28073]: Cannot find translation for source YouTube Music Oct 11 13:30:50 volumio volumio[28073]: info: Loading i18n strings for locale ru Oct 11 13:30:50 volumio volumio[28073]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 11 13:30:50 volumio volumio[28073]: info: Volumio Calling Home Oct 11 13:30:50 volumio volumio[28073]: info: [squeezelite_mc] Proxy server started on port 43081 Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::volumioRetrievevolume Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::volumioGetState Oct 11 13:30:50 volumio volumio[28073]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:50 volumio volumio[28073]: info: CoreStateMachine::pushState Oct 11 13:30:50 volumio volumio[28073]: info: CorePlayQueue::getTrack 0 Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::volumioPushState Oct 11 13:30:50 volumio volumio[28073]: info: [squeezelite_mc] Server discovery started Oct 11 13:30:50 volumio volumio[28073]: info: [squeezelite_mc] Player finder started Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 11 13:30:50 volumio volumio[28073]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 11 13:30:50 volumio volumio[28073]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 11 13:30:51 volumio volumio[28073]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:51 volumio volumio[28073]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 11 13:30:51 volumio volumio[28073]: at doSend (dgram.js:692:16) Oct 11 13:30:51 volumio volumio[28073]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 11 13:30:51 volumio volumio[28073]: at afterDns (dgram.js:638:5) Oct 11 13:30:51 volumio volumio[28073]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 11 13:30:51 volumio volumio[28073]: errno: -101, Oct 11 13:30:51 volumio volumio[28073]: code: 'ENETUNREACH', Oct 11 13:30:51 volumio volumio[28073]: syscall: 'send', Oct 11 13:30:51 volumio volumio[28073]: address: '255.255.255.255', Oct 11 13:30:51 volumio volumio[28073]: port: 3483 Oct 11 13:30:51 volumio volumio[28073]: } Oct 11 13:30:51 volumio volumio[28073]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 11 13:30:51 volumio dhcpcd[28133]: eth0: leased 192.168.31.163 for 43200 seconds Oct 11 13:30:51 volumio ifplugd(eth0)[814]: client: eth0: leased 192.168.31.163 for 43200 seconds Oct 11 13:30:51 volumio avahi-daemon[679]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 11 13:30:51 volumio avahi-daemon[679]: New relevant interface eth0.IPv4 for mDNS. Oct 11 13:30:51 volumio dhcpcd[28133]: eth0: adding route to 192.168.31.0/24 Oct 11 13:30:51 volumio avahi-daemon[679]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 11 13:30:51 volumio dhcpcd[28133]: eth0: adding default route via 192.168.31.1 Oct 11 13:30:51 volumio ifplugd(eth0)[814]: client: eth0: adding route to 192.168.31.0/24 Oct 11 13:30:51 volumio ifplugd(eth0)[814]: client: eth0: adding default route via 192.168.31.1 Oct 11 13:30:51 volumio dhcpcd[28133]: forked to background, child pid 28283 Oct 11 13:30:51 volumio ifplugd(eth0)[814]: client: forked to background, child pid 28283 Oct 11 13:30:51 volumio sudo[28331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-11 13:29 Oct 11 13:30:51 volumio sudo[28331]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"