-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Thu 2024-10-10 13:15:53 EEST. -- Oct 10 13:14:00 volumio ntfs-3g[25776]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 10 13:14:00 volumio ntfs-3g[25776]: Mounted /dev/sdb2 (Read-Write, label "ADATA HD650", NTFS 3.1) Oct 10 13:14:00 volumio ntfs-3g[25776]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:00 volumio ntfs-3g[25776]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb2,blkdev,blksize=4096 Oct 10 13:14:00 volumio ntfs-3g[25776]: Global ownership and permissions enforced, configuration type 7 Oct 10 13:14:00 volumio sudo[25768]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:00 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:00 volumio volumio[25725]: info: Loading plugin "volumio_command_line_client"... Oct 10 13:14:00 volumio volumio[25725]: info: Loading plugin "upnp"... Oct 10 13:14:00 volumio volumio[25725]: info: [1728555240751] Starting Upmpd Daemon Oct 10 13:14:00 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:00 volumio volumio[25725]: info: Loading plugin "my_music"... Oct 10 13:14:00 volumio volumio[25725]: info: Loading plugin "mpd"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "upnp_browser"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "alarm-clock"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "airplay_emulation"... Oct 10 13:14:01 volumio volumio[25725]: info: Starting Shairport Sync Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "last_100"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "webradio"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "i2s_dacs"... Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "volumiodiscovery"... Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** For more information see Oct 10 13:14:01 volumio node[25725]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:01 volumio volumio[25725]: *** WARNING *** For more information see Oct 10 13:14:01 volumio node[25725]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:01 volumio node[25725]: *** WARNING *** For more information see Oct 10 13:14:01 volumio node[25725]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:01 volumio node[25725]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:01 volumio node[25725]: *** WARNING *** For more information see Oct 10 13:14:01 volumio volumio[25725]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 13:14:01 volumio volumio[25725]: info: Discovery: Started advertising with name: Volumio Oct 10 13:14:01 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:01 volumio volumio[25725]: info: Loading plugin "squeezelite_mc"... Oct 10 13:14:02 volumio volumio[25725]: info: Loading plugin "ytcr"... Oct 10 13:14:02 volumio volumio-remote-updater[674]: [2024-10-10 13:14:02] [connect] Successful connection Oct 10 13:14:05 volumio volumio[25725]: info: Loading plugin "ytmusic"... Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "outputs"... Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "albumart"... Oct 10 13:14:06 volumio volumio[25725]: info: Plugin example_plugin is not enabled Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "inputs"... Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "updater_comm"... Oct 10 13:14:06 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:06 volumio volumio[25725]: info: Plugin mpdemulation is not enabled Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "rest_api"... Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "websocket"... Oct 10 13:14:06 volumio volumio[25725]: info: Starting Socket.io Server version 2.3.0 Oct 10 13:14:06 volumio volumio[25725]: info: Loading plugin "minidlna"... Oct 10 13:14:06 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:06 volumio volumio[25725]: info: Applying required configuration parameters for plugin minidlna Oct 10 13:14:06 volumio ifplugd(eth0)[816]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 10 13:14:06 volumio volumio[25725]: Forking 3 albumart workers Oct 10 13:14:06 volumio volumio[25725]: info: Loading i18n strings for locale ru Oct 10 13:14:06 volumio volumio[25725]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:06 volumio volumio[25725]: Updating browse sources language Oct 10 13:14:06 volumio ifplugd(eth0)[816]: client: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 10 13:14:06 volumio dhcpcd[25807]: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 10 13:14:06 volumio ifplugd(eth0)[816]: client: eth0: IAID eb:5d:71:15 Oct 10 13:14:06 volumio dhcpcd[25807]: eth0: IAID eb:5d:71:15 Oct 10 13:14:06 volumio ifplugd(eth0)[816]: client: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 10 13:14:06 volumio dhcpcd[25807]: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 10 13:14:06 volumio ifplugd(eth0)[816]: client: ipv6_addaddr1: Permission denied Oct 10 13:14:06 volumio dhcpcd[25807]: ipv6_addaddr1: Permission denied Oct 10 13:14:06 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:06 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::initPlayerControls Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: Express server listening on port 3000 Oct 10 13:14:07 volumio volumio[25725]: [Metrics] WebUI: 12s 760.48ms Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::resetVolumioState Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::getcurrentVolume Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::pushState Oct 10 13:14:07 volumio volumio[25725]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:07 volumio volumio-remote-updater[674]: [2024-10-10 13:14:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728555242 101 Oct 10 13:14:07 volumio volumio[25725]: 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 Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::setRepeat true single undefined Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::pushState Oct 10 13:14:07 volumio volumio[25725]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::setRandom false Oct 10 13:14:07 volumio volumio[25725]: info: CoreStateMachine::pushState Oct 10 13:14:07 volumio volumio[25725]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:07 volumio volumio[25725]: info: Setting Device type: Raspberry PI Oct 10 13:14:07 volumio volumio[25725]: info: Completed loading Core Plugins Oct 10 13:14:07 volumio volumio[25725]: info: Preparing to generate the ALSA configuration file Oct 10 13:14:07 volumio volumio[25725]: info: Asound.conf file unchanged, so no further update is needed Oct 10 13:14:07 volumio volumio[25725]: info: Output device has changed, restarting MPD Oct 10 13:14:07 volumio volumio[25725]: info: Output device has changed, restarting Shairport Sync Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:07 volumio ifplugd(eth0)[816]: client: eth0: soliciting an IPv6 router Oct 10 13:14:07 volumio dhcpcd[25807]: eth0: soliciting an IPv6 router Oct 10 13:14:07 volumio sudo[25840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:07 volumio sudo[25842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:07 volumio sudo[25842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:07 volumio sudo[25840]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:07 volumio sudo[25840]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:07 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 13:14:07 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:07 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:07 volumio volumio[25725]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:07 volumio volumio[25725]: info: ___________ START PLUGINS ___________ Oct 10 13:14:07 volumio volumio[25725]: info: ControllerMpd::onStart: Initializing MPD Oct 10 13:14:07 volumio volumio[25725]: info: Creating MPD Configuration file Oct 10 13:14:07 volumio ifplugd(eth0)[816]: client: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:07 volumio dhcpcd[25807]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:07 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:07 volumio volumio[25725]: info: [1728555247713] CoreMusicLibrary::Adding element Медиа-серверы Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:07 volumio ifplugd(eth0)[816]: client: eth0: probing address 192.168.31.163/24 Oct 10 13:14:07 volumio dhcpcd[25807]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:07 volumio sudo[25851]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:07 volumio sudo[25851]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:07 volumio sudo[25855]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:07 volumio sudo[25855]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:07 volumio sudo[25851]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: Starting albumart workers Oct 10 13:14:07 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 13:14:07 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:07 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:07 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:07 volumio volumio[25725]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:07 volumio volumio[25725]: Starting albumart workers Oct 10 13:14:07 volumio volumio[25725]: Starting albumart workers Oct 10 13:14:08 volumio sudo[25859]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:14:08 volumio sudo[25859]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:08 volumio sudo[25859]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:08 volumio volumio[25725]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:08 volumio volumio[25725]: info: [1728555248081] CoreMusicLibrary::Adding element Last_100 Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:08 volumio volumio[25725]: info: [1728555248087] CoreMusicLibrary::Adding element Webradio Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:08 volumio volumio[25725]: info: Initializing BBC Radios Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:08 volumio volumio[25725]: info: [squeezelite_mc] Starting proxy server... Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:08 volumio volumio[25725]: info: [1728555248688] CoreMusicLibrary::Adding element YouTube Music Oct 10 13:14:08 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:08 volumio volumio[25725]: Cannot find translation for source YouTube Music Oct 10 13:14:08 volumio volumio[25725]: info: Loading i18n strings for locale ru Oct 10 13:14:08 volumio volumio[25725]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:08 volumio volumio[25725]: info: Volumio Calling Home Oct 10 13:14:08 volumio volumio[25725]: info: [squeezelite_mc] Proxy server started on port 41339 Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:09 volumio volumio[25725]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:09 volumio volumio[25725]: info: CoreStateMachine::pushState Oct 10 13:14:09 volumio volumio[25725]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:09 volumio volumio[25725]: info: [squeezelite_mc] Server discovery started Oct 10 13:14:09 volumio volumio[25725]: info: [squeezelite_mc] Player finder started Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:09 volumio volumio[25725]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:09 volumio volumio[25725]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 10 13:14:09 volumio volumio[25725]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:14:09 volumio volumio[25725]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 10 13:14:09 volumio volumio[25725]: at doSend (dgram.js:692:16) Oct 10 13:14:09 volumio volumio[25725]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 10 13:14:09 volumio volumio[25725]: at afterDns (dgram.js:638:5) Oct 10 13:14:09 volumio volumio[25725]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 10 13:14:09 volumio volumio[25725]: errno: -101, Oct 10 13:14:09 volumio volumio[25725]: code: 'ENETUNREACH', Oct 10 13:14:09 volumio volumio[25725]: syscall: 'send', Oct 10 13:14:09 volumio volumio[25725]: address: '255.255.255.255', Oct 10 13:14:09 volumio volumio[25725]: port: 3483 Oct 10 13:14:09 volumio volumio[25725]: } Oct 10 13:14:09 volumio volumio[25725]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:14:09 volumio sudo[25917]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 13:13 Oct 10 13:14:09 volumio sudo[25917]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:09 volumio sudo[25917]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:10 volumio mpd[25866]: Oct 10 13:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 13:14:10 volumio systemd[1]: Started Music Player Daemon. Oct 10 13:14:10 volumio sudo[25842]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:10 volumio sudo[25855]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:10 volumio volumio-remote-updater[674]: [2024-10-10 13:14:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 10 13:14:10 volumio volumio-remote-updater[674]: [2024-10-10 13:14:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 10 13:14:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 10 13:14:10 volumio ntfs-3g[25776]: Unmounting /dev/sdb2 (ADATA HD650) Oct 10 13:14:10 volumio systemd[1]: media-ADATA_HD650.mount: Succeeded. Oct 10 13:14:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 10 13:14:10 volumio systemd[1]: Started dynamicswap service. Oct 10 13:14:10 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:14:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 10 13:14:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 7. Oct 10 13:14:10 volumio systemd[1]: Stopped Volumio Backend Module. Oct 10 13:14:10 volumio systemd[1]: Started Volumio Backend Module. Oct 10 13:14:10 volumio systemd[1]: Started dynamicswap service. Oct 10 13:14:10 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:14:12 volumio volumio[25933]: info: ------------------------------------------- Oct 10 13:14:12 volumio volumio[25933]: info: ----- Volumio3 ---- Oct 10 13:14:12 volumio volumio[25933]: info: ------------------------------------------- Oct 10 13:14:12 volumio volumio[25933]: info: ----- System startup ---- Oct 10 13:14:12 volumio volumio[25933]: info: ------------------------------------------- Oct 10 13:14:12 volumio ifplugd(eth0)[816]: client: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:14:12 volumio dhcpcd[25807]: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:14:12 volumio ifplugd(eth0)[816]: client: eth0: adding route to 192.168.31.0/24 Oct 10 13:14:12 volumio avahi-daemon[683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:12 volumio dhcpcd[25807]: eth0: adding route to 192.168.31.0/24 Oct 10 13:14:12 volumio avahi-daemon[683]: New relevant interface eth0.IPv4 for mDNS. Oct 10 13:14:12 volumio avahi-daemon[683]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 10 13:14:12 volumio ifplugd(eth0)[816]: client: eth0: adding default route via 192.168.31.1 Oct 10 13:14:12 volumio dhcpcd[25807]: eth0: adding default route via 192.168.31.1 Oct 10 13:14:12 volumio dhcpcd[25807]: forked to background, child pid 25976 Oct 10 13:14:12 volumio ifplugd(eth0)[816]: client: forked to background, child pid 25976 Oct 10 13:14:12 volumio ifplugd(eth0)[816]: Program executed successfully. Oct 10 13:14:12 volumio volumio[25933]: info: MYVOLUMIO Environment detected Oct 10 13:14:12 volumio volumio[25933]: info: Plugin folders cleanup Oct 10 13:14:12 volumio volumio[25933]: info: Scanning into folder /volumio/app/plugins/ Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category audio_interface Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category miscellanea Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category music_service Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category plugins.json Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category system_controller Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category user_interface Oct 10 13:14:12 volumio volumio[25933]: info: Scanning into folder /data/plugins/ Oct 10 13:14:12 volumio volumio[25933]: info: Scanning category music_service Oct 10 13:14:12 volumio volumio[25933]: info: Plugin folders cleanup completed Oct 10 13:14:12 volumio volumio[25933]: info: ------------------------------------------- Oct 10 13:14:12 volumio volumio[25933]: info: ----- Core plugins startup ---- Oct 10 13:14:12 volumio volumio[25933]: info: ------------------------------------------- Oct 10 13:14:12 volumio volumio[25933]: info: Loading plugins from folder /volumio/app/plugins/ Oct 10 13:14:12 volumio volumio[25933]: info: Adding plugin upnp to MyMusic Plugins Oct 10 13:14:12 volumio volumio[25933]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 10 13:14:12 volumio volumio[25933]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 10 13:14:12 volumio volumio[25933]: info: Loading plugins from folder /data/plugins/ Oct 10 13:14:12 volumio volumio[25933]: info: Loading plugin "system"... Oct 10 13:14:13 volumio volumio[25933]: info: Loading plugin "appearance"... Oct 10 13:14:13 volumio ntpd[803]: Listen normally on 4 eth0 192.168.31.163:123 Oct 10 13:14:13 volumio ntpd[803]: new interface(s) found: waking up resolver Oct 10 13:14:14 volumio volumio[25933]: info: Loading plugin "network"... Oct 10 13:14:14 volumio volumio[25933]: info: Refreshing Cached IP Addresses Oct 10 13:14:14 volumio volumio[25933]: info: Loading plugin "services"... Oct 10 13:14:14 volumio sudo[26011]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 13:14:14 volumio sudo[26011]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:14 volumio volumio[25933]: info: Loading plugin "alsa_controller"... Oct 10 13:14:14 volumio sudo[26011]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:14 volumio sudo[26013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 13:14:14 volumio sudo[26013]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:14 volumio sudo[26013]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:14 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:14 volumio volumio[25933]: info: Loading plugin "wizard"... Oct 10 13:14:14 volumio volumio[25933]: info: Loading plugin "networkfs"... Oct 10 13:14:14 volumio volumio[25933]: info: Starting Udev Watcher for removable devices Oct 10 13:14:14 volumio volumio[25933]: info: Ignoring mount for partition: boot Oct 10 13:14:14 volumio volumio[25933]: info: Ignoring mount for partition: volumio Oct 10 13:14:14 volumio volumio[25933]: info: Ignoring mount for partition: volumio_data Oct 10 13:14:14 volumio volumio[25933]: info: Mounting Device CES_X64FREO Oct 10 13:14:14 volumio sudo[26022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:14 volumio sudo[26022]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:14 volumio sudo[26022]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:14 volumio volumio[25933]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:14:14 volumio volumio[25933]: 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 10 13:14:14 volumio volumio[25933]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:14:14 volumio volumio[25933]: error: Cannot associate FS Label, not mounting Oct 10 13:14:14 volumio volumio[25933]: info: Mounting Device ADATA_HD650 Oct 10 13:14:14 volumio sudo[26028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/ADATA_HD650 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:14 volumio sudo[26028]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:15 volumio volumio-remote-updater[674]: [2024-10-10 13:14:15] [connect] Successful connection Oct 10 13:14:15 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:15 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:15 volumio avahi-daemon[683]: Withdrawing address record for 192.168.31.163 on eth0. Oct 10 13:14:15 volumio avahi-daemon[683]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:15 volumio avahi-daemon[683]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 10 13:14:15 volumio dhcpcd[25976]: eth0: deleting route to 192.168.31.0/24 Oct 10 13:14:15 volumio dhcpcd[25976]: eth0: deleting default route via 192.168.31.1 Oct 10 13:14:16 volumio ntpd[803]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 10 13:14:16 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:14:17 volumio ntpd[803]: Deleting interface #4 eth0, 192.168.31.163#123, interface stats: received=3, sent=3, dropped=6, active_time=4 secs Oct 10 13:14:17 volumio ntpd[803]: 162.159.200.1 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio ntpd[803]: 176.120.61.236 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio ntpd[803]: 162.159.200.123 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio ntpd[803]: 91.236.251.31 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio ntpd[803]: 62.149.2.7 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio ntpd[803]: 91.236.251.13 local addr 192.168.31.163 -> Oct 10 13:14:17 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:17 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:17 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:17 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:17 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:17 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:18 volumio ntfs-3g[26064]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 10 13:14:18 volumio ntfs-3g[26064]: Mounted /dev/sdb2 (Read-Write, label "ADATA HD650", NTFS 3.1) Oct 10 13:14:18 volumio ntfs-3g[26064]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:18 volumio ntfs-3g[26064]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb2,blkdev,blksize=4096 Oct 10 13:14:18 volumio ntfs-3g[26064]: Global ownership and permissions enforced, configuration type 7 Oct 10 13:14:18 volumio sudo[26028]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:18 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "volumio_command_line_client"... Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "upnp"... Oct 10 13:14:18 volumio volumio[25933]: info: [1728555258271] Starting Upmpd Daemon Oct 10 13:14:18 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "my_music"... Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "mpd"... Oct 10 13:14:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:18 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "upnp_browser"... Oct 10 13:14:18 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:14:18 volumio volumio[25933]: info: Loading plugin "alarm-clock"... Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "airplay_emulation"... Oct 10 13:14:19 volumio volumio[25933]: info: Starting Shairport Sync Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "last_100"... Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "webradio"... Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "i2s_dacs"... Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "volumiodiscovery"... Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** For more information see Oct 10 13:14:19 volumio node[25933]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:19 volumio volumio[25933]: *** WARNING *** For more information see Oct 10 13:14:19 volumio node[25933]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:19 volumio node[25933]: *** WARNING *** For more information see Oct 10 13:14:19 volumio node[25933]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:19 volumio node[25933]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:19 volumio node[25933]: *** WARNING *** For more information see Oct 10 13:14:19 volumio volumio[25933]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 13:14:19 volumio volumio[25933]: info: Discovery: Started advertising with name: Volumio Oct 10 13:14:19 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:19 volumio volumio[25933]: info: Loading plugin "squeezelite_mc"... Oct 10 13:14:20 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:20 volumio volumio[25933]: info: Loading plugin "ytcr"... Oct 10 13:14:20 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:20 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:20 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:20 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:20 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:14:20 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:22 volumio volumio[25933]: info: Loading plugin "ytmusic"... Oct 10 13:14:23 volumio volumio[25933]: info: Loading plugin "outputs"... Oct 10 13:14:23 volumio volumio[25933]: info: Loading plugin "albumart"... Oct 10 13:14:23 volumio volumio[25933]: info: Plugin example_plugin is not enabled Oct 10 13:14:23 volumio volumio[25933]: info: Loading plugin "inputs"... Oct 10 13:14:23 volumio volumio[25933]: info: Loading plugin "updater_comm"... Oct 10 13:14:23 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:23 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:24 volumio volumio[25933]: info: Plugin mpdemulation is not enabled Oct 10 13:14:24 volumio volumio[25933]: info: Loading plugin "rest_api"... Oct 10 13:14:24 volumio volumio[25933]: info: Loading plugin "websocket"... Oct 10 13:14:24 volumio volumio[25933]: info: Starting Socket.io Server version 2.3.0 Oct 10 13:14:24 volumio volumio[25933]: info: Loading plugin "minidlna"... Oct 10 13:14:24 volumio volumio[25933]: info: Applying required configuration parameters for plugin minidlna Oct 10 13:14:24 volumio volumio[25933]: Forking 3 albumart workers Oct 10 13:14:24 volumio volumio[25933]: info: Loading i18n strings for locale ru Oct 10 13:14:24 volumio volumio[25933]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:24 volumio volumio[25933]: Updating browse sources language Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::initPlayerControls Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: Express server listening on port 3000 Oct 10 13:14:24 volumio volumio[25933]: [Metrics] WebUI: 13s 114.12ms Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::resetVolumioState Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::getcurrentVolume Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::pushState Oct 10 13:14:24 volumio volumio[25933]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::setRepeat true single undefined Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::pushState Oct 10 13:14:24 volumio volumio[25933]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::setRandom false Oct 10 13:14:24 volumio volumio[25933]: info: CoreStateMachine::pushState Oct 10 13:14:24 volumio volumio[25933]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:24 volumio volumio[25933]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:24 volumio volumio[25933]: info: Setting Device type: Raspberry PI Oct 10 13:14:24 volumio volumio[25933]: info: Completed loading Core Plugins Oct 10 13:14:24 volumio volumio[25933]: info: Preparing to generate the ALSA configuration file Oct 10 13:14:24 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:14:24 volumio volumio[25933]: info: Asound.conf file unchanged, so no further update is needed Oct 10 13:14:24 volumio volumio[25933]: info: Output device has changed, restarting MPD Oct 10 13:14:25 volumio volumio[25933]: info: Output device has changed, restarting Shairport Sync Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:25 volumio sudo[26149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:25 volumio sudo[26149]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:25 volumio sudo[26147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:25 volumio sudo[26147]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:25 volumio sudo[26147]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:25 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 13:14:25 volumio volumio[25933]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:25 volumio volumio[25933]: info: ___________ START PLUGINS ___________ Oct 10 13:14:25 volumio volumio[25933]: info: ControllerMpd::onStart: Initializing MPD Oct 10 13:14:25 volumio volumio[25933]: info: Creating MPD Configuration file Oct 10 13:14:25 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:25 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:25 volumio volumio[25933]: info: [1728555265242] CoreMusicLibrary::Adding element Медиа-серверы Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:25 volumio sudo[26156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:25 volumio sudo[26156]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:25 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:25 volumio sudo[26156]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:25 volumio sudo[26158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:25 volumio sudo[26158]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:25 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 13:14:25 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:25 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:25 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:25 volumio volumio[25933]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: Starting albumart workers Oct 10 13:14:25 volumio volumio[25933]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:25 volumio volumio[25933]: info: [1728555265455] CoreMusicLibrary::Adding element Last_100 Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:25 volumio volumio[25933]: info: [1728555265459] CoreMusicLibrary::Adding element Webradio Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:25 volumio volumio[25933]: info: Initializing BBC Radios Oct 10 13:14:25 volumio sudo[26162]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:14:25 volumio sudo[26162]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:25 volumio sudo[26162]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:25 volumio volumio[25933]: Starting albumart workers Oct 10 13:14:25 volumio volumio[25933]: Starting albumart workers Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:25 volumio volumio[25933]: info: [squeezelite_mc] Starting proxy server... Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:25 volumio volumio[25933]: info: [1728555265818] CoreMusicLibrary::Adding element YouTube Music Oct 10 13:14:25 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:25 volumio volumio[25933]: Cannot find translation for source YouTube Music Oct 10 13:14:25 volumio volumio[25933]: info: Loading i18n strings for locale ru Oct 10 13:14:25 volumio volumio[25933]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:25 volumio volumio[25933]: info: Volumio Calling Home Oct 10 13:14:26 volumio volumio[25933]: info: [squeezelite_mc] Proxy server started on port 36721 Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:26 volumio volumio[25933]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:26 volumio volumio[25933]: info: CoreStateMachine::pushState Oct 10 13:14:26 volumio volumio[25933]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:26 volumio volumio[25933]: info: [squeezelite_mc] Server discovery started Oct 10 13:14:26 volumio volumio[25933]: info: [squeezelite_mc] Player finder started Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:26 volumio volumio[25933]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:26 volumio volumio[25933]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 10 13:14:26 volumio volumio[25933]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:14:26 volumio volumio[25933]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 10 13:14:26 volumio volumio[25933]: at doSend (dgram.js:692:16) Oct 10 13:14:26 volumio volumio[25933]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 10 13:14:26 volumio volumio[25933]: at afterDns (dgram.js:638:5) Oct 10 13:14:26 volumio volumio[25933]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 10 13:14:26 volumio volumio[25933]: errno: -101, Oct 10 13:14:26 volumio volumio[25933]: code: 'ENETUNREACH', Oct 10 13:14:26 volumio volumio[25933]: syscall: 'send', Oct 10 13:14:26 volumio volumio[25933]: address: '255.255.255.255', Oct 10 13:14:26 volumio volumio[25933]: port: 3483 Oct 10 13:14:26 volumio volumio[25933]: } Oct 10 13:14:26 volumio volumio[25933]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:14:27 volumio sudo[26220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 13:13 Oct 10 13:14:27 volumio sudo[26220]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:27 volumio sudo[26220]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 10 13:14:27 volumio ntfs-3g[26064]: Unmounting /dev/sdb2 (ADATA HD650) Oct 10 13:14:27 volumio systemd[1]: media-ADATA_HD650.mount: Succeeded. Oct 10 13:14:27 volumio sudo[26149]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:27 volumio mpd[26171]: Oct 10 13:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 13:14:27 volumio sudo[26158]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:27 volumio systemd[1]: Started Music Player Daemon. Oct 10 13:14:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 10 13:14:27 volumio volumio-remote-updater[674]: [2024-10-10 13:14:27] [info] asio async_connect error: system:111 (Connection refused) Oct 10 13:14:27 volumio volumio-remote-updater[674]: [2024-10-10 13:14:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 10 13:14:27 volumio volumio-remote-updater[674]: [2024-10-10 13:14:27] [error] handle_connect error: Underlying Transport Error Oct 10 13:14:27 volumio systemd[1]: Started dynamicswap service. Oct 10 13:14:27 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:14:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 10 13:14:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 8. Oct 10 13:14:27 volumio systemd[1]: Stopped Volumio Backend Module. Oct 10 13:14:27 volumio systemd[1]: Started Volumio Backend Module. Oct 10 13:14:27 volumio systemd[1]: Started dynamicswap service. Oct 10 13:14:27 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:14:28 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:28 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:28 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:29 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:29 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:29 volumio volumio[26236]: info: ----- Volumio3 ---- Oct 10 13:14:29 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:29 volumio volumio[26236]: info: ----- System startup ---- Oct 10 13:14:29 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:29 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:29 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:29 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:14:30 volumio volumio[26236]: info: MYVOLUMIO Environment detected Oct 10 13:14:30 volumio volumio[26236]: info: Plugin folders cleanup Oct 10 13:14:30 volumio volumio[26236]: info: Scanning into folder /volumio/app/plugins/ Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category audio_interface Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category miscellanea Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category music_service Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category plugins.json Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category system_controller Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category user_interface Oct 10 13:14:30 volumio volumio[26236]: info: Scanning into folder /data/plugins/ Oct 10 13:14:30 volumio volumio[26236]: info: Scanning category music_service Oct 10 13:14:30 volumio volumio[26236]: info: Plugin folders cleanup completed Oct 10 13:14:30 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:30 volumio volumio[26236]: info: ----- Core plugins startup ---- Oct 10 13:14:30 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:30 volumio volumio[26236]: info: Loading plugins from folder /volumio/app/plugins/ Oct 10 13:14:30 volumio volumio[26236]: info: Adding plugin upnp to MyMusic Plugins Oct 10 13:14:30 volumio volumio[26236]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 10 13:14:30 volumio volumio[26236]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 10 13:14:30 volumio volumio[26236]: info: Loading plugins from folder /data/plugins/ Oct 10 13:14:30 volumio volumio[26236]: info: Loading plugin "system"... Oct 10 13:14:30 volumio volumio[26236]: info: Loading plugin "appearance"... Oct 10 13:14:31 volumio volumio[26236]: info: Loading plugin "network"... Oct 10 13:14:31 volumio volumio[26236]: info: Refreshing Cached IP Addresses Oct 10 13:14:31 volumio nmbd[767]: [2024/10/10 13:14:31.526897, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:31 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:31 volumio nmbd[767]: [2024/10/10 13:14:31.527175, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Oct 10 13:14:31 volumio nmbd[767]: send_netbios_packet: send_packet() to IP 192.168.31.255 port 137 failed Oct 10 13:14:31 volumio nmbd[767]: [2024/10/10 13:14:31.527267, 0] ../source3/nmbd/nmbd_namequery.c:245(query_name) Oct 10 13:14:31 volumio nmbd[767]: query_name: Failed to send packet trying to query name WORKGROUP<1d> Oct 10 13:14:31 volumio nmbd[767]: [2024/10/10 13:14:31.530819, 0] ../source3/nmbd/nmbd.c:361(reload_interfaces) Oct 10 13:14:31 volumio nmbd[767]: reload_interfaces: No subnets to listen to. Waiting.. Oct 10 13:14:31 volumio volumio[26236]: info: Loading plugin "services"... Oct 10 13:14:31 volumio sudo[26264]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 13:14:31 volumio volumio[26236]: info: Loading plugin "alsa_controller"... Oct 10 13:14:31 volumio sudo[26264]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:31 volumio sudo[26267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 13:14:31 volumio sudo[26267]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:31 volumio sudo[26264]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:31 volumio sudo[26267]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:31 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:31 volumio volumio[26236]: info: Loading plugin "wizard"... Oct 10 13:14:31 volumio volumio[26236]: info: Loading plugin "networkfs"... Oct 10 13:14:31 volumio volumio[26236]: info: Starting Udev Watcher for removable devices Oct 10 13:14:31 volumio volumio[26236]: info: Ignoring mount for partition: boot Oct 10 13:14:31 volumio volumio[26236]: info: Ignoring mount for partition: volumio Oct 10 13:14:31 volumio volumio[26236]: info: Ignoring mount for partition: volumio_data Oct 10 13:14:31 volumio volumio[26236]: info: Mounting Device CES_X64FREO Oct 10 13:14:31 volumio sudo[26276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:31 volumio sudo[26276]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:31 volumio sudo[26276]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:31 volumio volumio[26236]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:14:31 volumio volumio[26236]: 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 10 13:14:31 volumio volumio[26236]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:14:31 volumio volumio[26236]: error: Cannot associate FS Label, not mounting Oct 10 13:14:31 volumio volumio[26236]: info: Mounting Device ADATA_HD650 Oct 10 13:14:31 volumio sudo[26282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/ADATA_HD650 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:31 volumio sudo[26282]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: adding route to 192.168.31.0/24 Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: adding default route via 192.168.31.1 Oct 10 13:14:35 volumio avahi-daemon[683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:35 volumio avahi-daemon[683]: New relevant interface eth0.IPv4 for mDNS. Oct 10 13:14:35 volumio avahi-daemon[683]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:35 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:35 volumio avahi-daemon[683]: Withdrawing address record for 192.168.31.163 on eth0. Oct 10 13:14:35 volumio avahi-daemon[683]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: deleting route to 192.168.31.0/24 Oct 10 13:14:35 volumio dhcpcd[25976]: eth0: deleting default route via 192.168.31.1 Oct 10 13:14:35 volumio avahi-daemon[683]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 10 13:14:35 volumio ntfs-3g[26336]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 10 13:14:35 volumio ntfs-3g[26336]: Mounted /dev/sdb2 (Read-Write, label "ADATA HD650", NTFS 3.1) Oct 10 13:14:35 volumio ntfs-3g[26336]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:14:35 volumio ntfs-3g[26336]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb2,blkdev,blksize=4096 Oct 10 13:14:35 volumio ntfs-3g[26336]: Global ownership and permissions enforced, configuration type 7 Oct 10 13:14:35 volumio sudo[26282]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:36 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "volumio_command_line_client"... Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "upnp"... Oct 10 13:14:36 volumio volumio[26236]: info: [1728555276017] Starting Upmpd Daemon Oct 10 13:14:36 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "my_music"... Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "mpd"... Oct 10 13:14:36 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "upnp_browser"... Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "alarm-clock"... Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "airplay_emulation"... Oct 10 13:14:36 volumio volumio[26236]: info: Starting Shairport Sync Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "last_100"... Oct 10 13:14:36 volumio volumio[26236]: info: Loading plugin "webradio"... Oct 10 13:14:37 volumio volumio[26236]: info: Loading plugin "i2s_dacs"... Oct 10 13:14:37 volumio volumio[26236]: info: Loading plugin "volumiodiscovery"... Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:37 volumio node[26236]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:37 volumio node[26236]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** For more information see Oct 10 13:14:37 volumio node[26236]: *** WARNING *** For more information see Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:37 volumio node[26236]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:37 volumio node[26236]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:14:37 volumio volumio[26236]: *** WARNING *** For more information see Oct 10 13:14:37 volumio node[26236]: *** WARNING *** For more information see Oct 10 13:14:37 volumio volumio[26236]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 13:14:37 volumio volumio[26236]: info: Discovery: Started advertising with name: Volumio Oct 10 13:14:37 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:14:37 volumio volumio[26236]: info: Loading plugin "squeezelite_mc"... Oct 10 13:14:37 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:37 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:37 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:37 volumio volumio-remote-updater[674]: [2024-10-10 13:14:37] [connect] Successful connection Oct 10 13:14:37 volumio volumio[26236]: info: Loading plugin "ytcr"... Oct 10 13:14:38 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:38 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:38 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:38 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:14:40 volumio volumio[26236]: info: Loading plugin "ytmusic"... Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "outputs"... Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "albumart"... Oct 10 13:14:41 volumio volumio[26236]: info: Plugin example_plugin is not enabled Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "inputs"... Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "updater_comm"... Oct 10 13:14:41 volumio volumio[26236]: info: Plugin mpdemulation is not enabled Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "rest_api"... Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "websocket"... Oct 10 13:14:41 volumio volumio[26236]: info: Starting Socket.io Server version 2.3.0 Oct 10 13:14:41 volumio volumio[26236]: info: Loading plugin "minidlna"... Oct 10 13:14:42 volumio volumio[26236]: info: Applying required configuration parameters for plugin minidlna Oct 10 13:14:42 volumio volumio[26236]: Forking 3 albumart workers Oct 10 13:14:42 volumio volumio[26236]: info: Loading i18n strings for locale ru Oct 10 13:14:42 volumio volumio[26236]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:42 volumio volumio[26236]: Updating browse sources language Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::initPlayerControls Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: Express server listening on port 3000 Oct 10 13:14:42 volumio volumio[26236]: [Metrics] WebUI: 13s 409.28ms Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::resetVolumioState Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::getcurrentVolume Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::pushState Oct 10 13:14:42 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: Setting Device type: Raspberry PI Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::setRepeat true single undefined Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::pushState Oct 10 13:14:42 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::setRandom false Oct 10 13:14:42 volumio volumio[26236]: info: CoreStateMachine::pushState Oct 10 13:14:42 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:42 volumio volumio[26236]: info: Completed loading Core Plugins Oct 10 13:14:42 volumio volumio[26236]: info: Preparing to generate the ALSA configuration file Oct 10 13:14:42 volumio volumio[26236]: info: Asound.conf file unchanged, so no further update is needed Oct 10 13:14:42 volumio volumio[26236]: info: Output device has changed, restarting MPD Oct 10 13:14:42 volumio sudo[26416]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:42 volumio sudo[26416]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:42 volumio volumio[26236]: info: Output device has changed, restarting Shairport Sync Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:42 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:42 volumio sudo[26416]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:42 volumio sudo[26419]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:42 volumio sudo[26419]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:42 volumio volumio[26236]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:42 volumio volumio[26236]: info: ___________ START PLUGINS ___________ Oct 10 13:14:42 volumio volumio[26236]: info: ControllerMpd::onStart: Initializing MPD Oct 10 13:14:42 volumio volumio[26236]: info: Creating MPD Configuration file Oct 10 13:14:43 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:43 volumio volumio[26236]: info: [1728555283048] CoreMusicLibrary::Adding element Медиа-серверы Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:43 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:43 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:43 volumio sudo[26425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:14:43 volumio sudo[26425]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:43 volumio sudo[26427]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:14:43 volumio sudo[26427]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:43 volumio sudo[26425]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:43 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:43 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 13:14:43 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:14:43 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:14:43 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:14:43 volumio volumio[26236]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:43 volumio volumio[26236]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:43 volumio volumio[26236]: info: [1728555283263] CoreMusicLibrary::Adding element Last_100 Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:43 volumio volumio[26236]: info: [1728555283268] CoreMusicLibrary::Adding element Webradio Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:43 volumio volumio[26236]: info: Initializing BBC Radios Oct 10 13:14:43 volumio sudo[26434]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:14:43 volumio sudo[26434]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:43 volumio volumio[26236]: Starting albumart workers Oct 10 13:14:43 volumio sudo[26434]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:43 volumio volumio[26236]: Starting albumart workers Oct 10 13:14:43 volumio volumio[26236]: Starting albumart workers Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:43 volumio volumio[26236]: info: [squeezelite_mc] Starting proxy server... Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:14:43 volumio volumio[26236]: info: [1728555283491] CoreMusicLibrary::Adding element YouTube Music Oct 10 13:14:43 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:14:43 volumio volumio[26236]: Cannot find translation for source YouTube Music Oct 10 13:14:43 volumio volumio[26236]: info: Loading i18n strings for locale ru Oct 10 13:14:43 volumio volumio[26236]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:14:43 volumio volumio[26236]: info: Volumio Calling Home Oct 10 13:14:43 volumio dhcpcd[25976]: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:14:43 volumio dhcpcd[25976]: eth0: adding route to 192.168.31.0/24 Oct 10 13:14:43 volumio dhcpcd[25976]: eth0: adding default route via 192.168.31.1 Oct 10 13:14:43 volumio avahi-daemon[683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:43 volumio avahi-daemon[683]: New relevant interface eth0.IPv4 for mDNS. Oct 10 13:14:43 volumio avahi-daemon[683]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 10 13:14:43 volumio volumio[26236]: info: [squeezelite_mc] Proxy server started on port 34113 Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:44 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:44 volumio volumio[26236]: info: CoreStateMachine::pushState Oct 10 13:14:44 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::volumioPushState Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Server discovery started Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Player finder started Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 10 13:14:44 volumio volumio[26236]: Unhandled rejection Error: No sockets available, cannot start. Oct 10 13:14:44 volumio volumio[26236]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Oct 10 13:14:44 volumio volumio[26236]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Oct 10 13:14:44 volumio volumio[26236]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Oct 10 13:14:44 volumio volumio[26236]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Oct 10 13:14:44 volumio volumio[26236]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Oct 10 13:14:44 volumio volumio[26236]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Oct 10 13:14:44 volumio volumio[26236]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Oct 10 13:14:44 volumio volumio[26236]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Oct 10 13:14:44 volumio volumio[26236]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Oct 10 13:14:44 volumio volumio[26236]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Oct 10 13:14:44 volumio volumio[26236]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Oct 10 13:14:44 volumio volumio[26236]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Oct 10 13:14:44 volumio volumio[26236]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Oct 10 13:14:44 volumio volumio[26236]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Oct 10 13:14:44 volumio volumio[26236]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Oct 10 13:14:44 volumio volumio[26236]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 10 13:14:44 volumio volumio[26236]: info: minidlna: Found miniDLNA version 1.2.1 Oct 10 13:14:44 volumio volumio[26236]: info: minidlna: Starting minidlna.service Oct 10 13:14:44 volumio volumio[26236]: info: MPD Permissions set Oct 10 13:14:44 volumio volumio[26236]: info: MPD Permissions set Oct 10 13:14:44 volumio volumio[26236]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio sudo[26483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio sudo[26483]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Oct 10 13:14:44 volumio sudo[26483]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE"] Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","card":"2","mixerType":"None","mixer":null,"dsdFormat":null} Oct 10 13:14:44 volumio volumio[26236]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 10 13:14:44 volumio volumio[26236]: info: minidlna: systemctl start minidlna.service succeeded. Oct 10 13:14:44 volumio sudo[26517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Oct 10 13:14:44 volumio sudo[26517]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:44 volumio sudo[26517]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:44 volumio volumio[26236]: info: Starting Shairport Sync Oct 10 13:14:45 volumio volumio[26236]: info: Starting Shairport Sync Oct 10 13:14:45 volumio sudo[26525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 13:14:45 volumio sudo[26525]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:45 volumio volumio[26236]: info: Starting Shairport Sync Oct 10 13:14:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 13:14:45 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 13:14:45 volumio sudo[26528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 13:14:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 13:14:45 volumio sudo[26528]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:45 volumio volumio[26236]: info: Discovery: adding d2eb0488-5305-4b69-b65c-6f4fbdd498eb Oct 10 13:14:45 volumio volumio[26236]: info: Discovery: Found device Volumio Oct 10 13:14:45 volumio volumio[26236]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:45 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:45 volumio sudo[26532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 10 13:14:45 volumio sudo[26532]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 13:14:45 volumio sudo[26525]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:45 volumio volumio[26236]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:45 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:14:45 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 10 13:14:45 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 10 13:14:45 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 10 13:14:45 volumio volumio[26236]: info: Shairport-Sync Started Oct 10 13:14:45 volumio volumio[26236]: Error adding Membership: Error: addMembership EINVAL Oct 10 13:14:45 volumio volumio[26236]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 10 13:14:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 10 13:14:45 volumio sudo[26532]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:45 volumio sudo[26528]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:45 volumio volumio[26236]: info: Shairport-Sync Started Oct 10 13:14:45 volumio volumio[26236]: info: Shairport-Sync Started Oct 10 13:14:45 volumio sudo[26540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Oct 10 13:14:45 volumio sudo[26540]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:45 volumio sudo[26540]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:45 volumio ntpd[803]: Listen normally on 5 eth0 192.168.31.163:123 Oct 10 13:14:45 volumio ntpd[803]: new interface(s) found: waking up resolver Oct 10 13:14:45 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Oct 10 13:14:45 volumio sudo[26546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Oct 10 13:14:45 volumio sudo[26546]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:45 volumio systemd[1]: Reloading. Oct 10 13:14:45 volumio mpd[26441]: Oct 10 13:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 13:14:45 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Oct 10 13:14:46 volumio sudo[26564]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 13:14:46 volumio sudo[26564]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:46 volumio sudo[26564]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio sudo[26569]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 10 13:14:46 volumio sudo[26569]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:46 volumio sudo[26567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 13:14:46 volumio sudo[26567]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:46 volumio sudo[26567]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio volumio[26236]: info: [yt-cast-receiver] DIAL server listening on port 8098 Oct 10 13:14:46 volumio systemd[1]: Started Music Player Daemon. Oct 10 13:14:46 volumio sudo[26419]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio sudo[26546]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio sudo[26427]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:46 volumio sudo[26569]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio sudo[26574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:46 volumio sudo[26574]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:46 volumio sudo[26574]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:46 volumio volumio[26236]: error: MPD error: The expression evaluated to a falsy value: Oct 10 13:14:46 volumio volumio[26236]: assert.ok(self.idling) Oct 10 13:14:46 volumio volumio[26236]: error: The expression evaluated to a falsy value: Oct 10 13:14:46 volumio volumio[26236]: assert.ok(self.idling) Oct 10 13:14:46 volumio volumio[26236]: info: Upmpdcli Daemon Started Oct 10 13:14:46 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Oct 10 13:14:46 volumio volumio[26236]: info: MPD running with PID26441 Oct 10 13:14:46 volumio volumio[26236]: ,establishing connection Oct 10 13:14:46 volumio volumio[26236]: error: updateQueue error: null Oct 10 13:14:46 volumio volumio[26236]: error: updateQueue error: null Oct 10 13:14:46 volumio sudo[26579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Oct 10 13:14:46 volumio sudo[26579]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:46 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Oct 10 13:14:46 volumio systemd[1]: squeezelite.service: Succeeded. Oct 10 13:14:46 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Oct 10 13:14:46 volumio sudo[26579]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:47 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:47 volumio sudo[26583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:47 volumio sudo[26583]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:47 volumio sudo[26583]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:47 volumio volumio[26236]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Oct 10 13:14:47 volumio sudo[26587]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Oct 10 13:14:47 volumio sudo[26587]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:47 volumio sudo[26587]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:47 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Oct 10 13:14:47 volumio sudo[26590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Oct 10 13:14:47 volumio sudo[26590]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:47 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Oct 10 13:14:47 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Oct 10 13:14:47 volumio sudo[26590]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:48 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:48 volumio sudo[26599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:48 volumio sudo[26599]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:48 volumio sudo[26599]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:48 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:48 volumio sudo[26602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:48 volumio sudo[26602]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:48 volumio sudo[26602]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:49 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:49 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:49 volumio avahi-daemon[683]: Withdrawing address record for 192.168.31.163 on eth0. Oct 10 13:14:49 volumio avahi-daemon[683]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:49 volumio dhcpcd[25976]: eth0: deleting route to 192.168.31.0/24 Oct 10 13:14:49 volumio dhcpcd[25976]: eth0: deleting default route via 192.168.31.1 Oct 10 13:14:49 volumio avahi-daemon[683]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 10 13:14:49 volumio volumio[26236]: info: Discovery: A device disappeared from network Oct 10 13:14:49 volumio volumio[26236]: info: Discovery: Device volumio disappeared from network Oct 10 13:14:49 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:14:49 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:49 volumio sudo[26635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:49 volumio sudo[26635]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:49 volumio sudo[26635]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:50 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:50 volumio sudo[26638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:50 volumio sudo[26638]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:50 volumio sudo[26638]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:50 volumio ntpd[803]: Deleting interface #5 eth0, 192.168.31.163#123, interface stats: received=9, sent=9, dropped=6, active_time=5 secs Oct 10 13:14:50 volumio ntpd[803]: 162.159.200.1 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio ntpd[803]: 176.120.61.236 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio ntpd[803]: 162.159.200.123 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio ntpd[803]: 91.236.251.31 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio ntpd[803]: 62.149.2.7 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio ntpd[803]: 91.236.251.13 local addr 192.168.31.163 -> Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.555615, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:50 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.555823, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 10 13:14:50 volumio nmbd[767]: retransmit_or_expire_response_records: Failed to resend packet id 28676 to IP 192.168.31.255 on subnet 192.168.31.163 Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.555910, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:50 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.555986, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 10 13:14:50 volumio nmbd[767]: retransmit_or_expire_response_records: Failed to resend packet id 28677 to IP 192.168.31.255 on subnet 192.168.31.163 Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556065, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:50 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556141, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 10 13:14:50 volumio nmbd[767]: retransmit_or_expire_response_records: Failed to resend packet id 28678 to IP 192.168.31.255 on subnet 192.168.31.163 Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556222, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:50 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556297, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 10 13:14:50 volumio nmbd[767]: retransmit_or_expire_response_records: Failed to resend packet id 28679 to IP 192.168.31.255 on subnet 192.168.31.163 Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556378, 0] ../source3/libsmb/nmblib.c:917(send_udp) Oct 10 13:14:50 volumio nmbd[767]: Packet send failed to 192.168.31.255(137) ERRNO=Network is unreachable Oct 10 13:14:50 volumio nmbd[767]: [2024/10/10 13:14:50.556453, 0] ../source3/nmbd/nmbd_packets.c:1639(retransmit_or_expire_response_records) Oct 10 13:14:50 volumio nmbd[767]: retransmit_or_expire_response_records: Failed to resend packet id 28680 to IP 192.168.31.255 on subnet 192.168.31.163 Oct 10 13:14:50 volumio volumio[26236]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Oct 10 13:14:50 volumio sudo[26641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Oct 10 13:14:50 volumio sudo[26641]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:14:50 volumio sudo[26641]: pam_unix(sudo:session): session closed for user root Oct 10 13:14:50 volumio volumio[26236]: info: Completed starting Core Plugins Oct 10 13:14:50 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:50 volumio volumio[26236]: info: ----- MyVolumio plugins startup ---- Oct 10 13:14:50 volumio volumio[26236]: info: ------------------------------------------- Oct 10 13:14:50 volumio volumio[26236]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 10 13:14:50 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:50 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:50 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:50 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:14:50 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:14:51 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:51 volumio ntpd[803]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Oct 10 13:14:51 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:14:52 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:14:52 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:14:52 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:14:52 volumio volumio-remote-updater[674]: [2024-10-10 13:14:52] [connect] Successful connection Oct 10 13:14:52 volumio volumio-remote-updater[674]: [2024-10-10 13:14:52] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1728555292 101 Oct 10 13:14:52 volumio volumio[26236]: 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: 2 Oct 10 13:14:53 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:14:53 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:14:53 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:14:53 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:14:54 volumio volumio[26236]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.757&uuid=f6f5190ffd10d6041e40e6cacc535d7d" http://updates.volumio.org/downloader-v1/track-device Oct 10 13:14:54 volumio volumio[26236]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 10 13:14:54 volumio volumio[26236]: Dload Upload Total Spent Left Speed Oct 10 13:14:54 volumio volumio[26236]: [132B blob data] Oct 10 13:14:54 volumio volumio[26236]: retrying in 5 seconds, trial 0 Oct 10 13:14:54 volumio volumio[26236]: info: Volumio Calling Home Oct 10 13:14:58 volumio dhcpcd[25976]: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:14:58 volumio avahi-daemon[683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:14:58 volumio dhcpcd[25976]: eth0: adding route to 192.168.31.0/24 Oct 10 13:14:58 volumio avahi-daemon[683]: New relevant interface eth0.IPv4 for mDNS. Oct 10 13:14:58 volumio dhcpcd[25976]: eth0: adding default route via 192.168.31.1 Oct 10 13:14:58 volumio avahi-daemon[683]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 10 13:14:59 volumio volumio[26236]: info: Discovery: adding d2eb0488-5305-4b69-b65c-6f4fbdd498eb Oct 10 13:14:59 volumio volumio[26236]: info: Discovery: Found device Volumio Oct 10 13:14:59 volumio volumio[26236]: info: CoreCommandRouter::volumioGetState Oct 10 13:14:59 volumio volumio[26236]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:00 volumio ntpd[803]: Listen normally on 6 eth0 192.168.31.163:123 Oct 10 13:15:00 volumio ntpd[803]: new interface(s) found: waking up resolver Oct 10 13:15:00 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:15:00 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:00 volumio dhcpcd[25976]: eth0: deleting route to 192.168.31.0/24 Oct 10 13:15:00 volumio dhcpcd[25976]: eth0: deleting default route via 192.168.31.1 Oct 10 13:15:00 volumio avahi-daemon[683]: Withdrawing address record for 192.168.31.163 on eth0. Oct 10 13:15:00 volumio avahi-daemon[683]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:15:00 volumio avahi-daemon[683]: Interface eth0.IPv4 no longer relevant for mDNS. Oct 10 13:15:00 volumio volumio[26236]: info: Discovery: A device disappeared from network Oct 10 13:15:00 volumio volumio[26236]: info: Discovery: Device volumio disappeared from network Oct 10 13:15:01 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:15:02 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:15:02 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:02 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:15:02 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:02 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:02 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:15:03 volumio ntpd[803]: Deleting interface #6 eth0, 192.168.31.163#123, interface stats: received=0, sent=0, dropped=5, active_time=3 secs Oct 10 13:15:03 volumio ntpd[803]: 162.159.200.1 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio ntpd[803]: 176.120.61.236 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio ntpd[803]: 162.159.200.123 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio ntpd[803]: 91.236.251.31 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio ntpd[803]: 62.149.2.7 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio ntpd[803]: 91.236.251.13 local addr 192.168.31.163 -> Oct 10 13:15:03 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:15:04 volumio volumio[26236]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=3.757&uuid=f6f5190ffd10d6041e40e6cacc535d7d" http://updates.volumio.org/downloader-v1/track-device Oct 10 13:15:04 volumio volumio[26236]: % Total % Received % Xferd Average Speed Time Time Time Current Oct 10 13:15:04 volumio volumio[26236]: Dload Upload Total Spent Left Speed Oct 10 13:15:04 volumio volumio[26236]: [132B blob data] Oct 10 13:15:04 volumio volumio[26236]: retrying in 5 seconds, trial 1 Oct 10 13:15:04 volumio volumio[26236]: info: Volumio Calling Home Oct 10 13:15:06 volumio dhcpcd[25976]: eth0: carrier acquired Oct 10 13:15:06 volumio dhcpcd[25976]: eth0: IAID eb:5d:71:15 Oct 10 13:15:06 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:06 volumio dhcpcd[25976]: eth0: soliciting an IPv6 router Oct 10 13:15:06 volumio dhcpcd[25976]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:06 volumio dhcpcd[25976]: eth0: probing address 192.168.31.163/24 Oct 10 13:15:06 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:15:07 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:07 volumio dhcpcd[25976]: eth0: carrier lost Oct 10 13:15:07 volumio ifplugd(eth0)[816]: Link beat lost. Oct 10 13:15:10 volumio volumio[26236]: error: MyVolumio Plugin failed to start in a timely fashion Oct 10 13:15:10 volumio volumio[26236]: info: BOOT COMPLETED Oct 10 13:15:10 volumio volumio[26236]: [Metrics] CommandRouter: 41s 103.16ms Oct 10 13:15:10 volumio volumio[26236]: info: CoreCommandRouter::volumiosetStartupVolume Oct 10 13:15:10 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:10 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:10 volumio volumio[26236]: info: CoreCommandRouter::Close All Modals sent Oct 10 13:15:10 volumio volumio[26236]: info: CoreCommandRouter::Close All Modals sent Oct 10 13:15:10 volumio kernel: bcm2835-i2s 3f203000.i2s: I2S SYNC error! Oct 10 13:15:11 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 10 13:15:11 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 10 13:15:11 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 10 13:15:13 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:13 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:13 volumio volumio[26236]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 10 13:15:14 volumio volumio[26236]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:14 volumio volumio[26236]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 10 13:15:14 volumio volumio[26236]: at doSend (dgram.js:692:16) Oct 10 13:15:14 volumio volumio[26236]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 10 13:15:14 volumio volumio[26236]: at afterDns (dgram.js:638:5) Oct 10 13:15:14 volumio volumio[26236]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 10 13:15:14 volumio volumio[26236]: errno: -101, Oct 10 13:15:14 volumio volumio[26236]: code: 'ENETUNREACH', Oct 10 13:15:14 volumio volumio[26236]: syscall: 'send', Oct 10 13:15:14 volumio volumio[26236]: address: '255.255.255.255', Oct 10 13:15:14 volumio volumio[26236]: port: 3483 Oct 10 13:15:14 volumio volumio[26236]: } Oct 10 13:15:14 volumio volumio[26236]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:14 volumio sudo[26780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 13:14 Oct 10 13:15:14 volumio sudo[26780]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:15 volumio sudo[26780]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:15 volumio volumio-remote-updater[674]: [2024-10-10 13:15:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Oct 10 13:15:15 volumio volumio-remote-updater[674]: [2024-10-10 13:15:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Oct 10 13:15:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 10 13:15:15 volumio systemd[1]: media-ADATA_HD650.mount: Succeeded. Oct 10 13:15:15 volumio ntfs-3g[26336]: Unmounting /dev/sdb2 (ADATA HD650) Oct 10 13:15:15 volumio ntpd[803]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Oct 10 13:15:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 10 13:15:15 volumio systemd[1]: Started dynamicswap service. Oct 10 13:15:15 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:15:15 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 10 13:15:15 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 9. Oct 10 13:15:15 volumio systemd[1]: Stopped Volumio Backend Module. Oct 10 13:15:15 volumio systemd[1]: Started Volumio Backend Module. Oct 10 13:15:15 volumio systemd[1]: Started dynamicswap service. Oct 10 13:15:15 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:15:16 volumio ntpd[803]: Soliciting pool server 2a00:fc00:e000::6 Oct 10 13:15:17 volumio volumio[26794]: info: ------------------------------------------- Oct 10 13:15:17 volumio volumio[26794]: info: ----- Volumio3 ---- Oct 10 13:15:17 volumio volumio[26794]: info: ------------------------------------------- Oct 10 13:15:17 volumio volumio[26794]: info: ----- System startup ---- Oct 10 13:15:17 volumio volumio[26794]: info: ------------------------------------------- Oct 10 13:15:17 volumio ifplugd(eth0)[816]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Oct 10 13:15:18 volumio dhcpcd[26823]: sending signal ALRM to pid 25976 Oct 10 13:15:18 volumio dhcpcd[26823]: waiting for pid 25976 to exit Oct 10 13:15:18 volumio dhcpcd[25976]: received SIGALRM, releasing Oct 10 13:15:18 volumio dhcpcd[25976]: eth0: removing interface Oct 10 13:15:18 volumio ifplugd(eth0)[816]: client: sending signal ALRM to pid 25976 Oct 10 13:15:18 volumio ifplugd(eth0)[816]: client: waiting for pid 25976 to exit Oct 10 13:15:18 volumio dhcpcd[25976]: dhcpcd exited Oct 10 13:15:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 10 13:15:18 volumio ifplugd(eth0)[816]: Program executed successfully. Oct 10 13:15:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup Oct 10 13:15:18 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:18 volumio volumio[26794]: info: MYVOLUMIO Environment detected Oct 10 13:15:18 volumio volumio[26794]: info: Plugin folders cleanup Oct 10 13:15:18 volumio volumio[26794]: info: Scanning into folder /volumio/app/plugins/ Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category audio_interface Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category miscellanea Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category music_service Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category plugins.json Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category system_controller Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category user_interface Oct 10 13:15:18 volumio volumio[26794]: info: Scanning into folder /data/plugins/ Oct 10 13:15:18 volumio volumio[26794]: info: Scanning category music_service Oct 10 13:15:18 volumio volumio[26794]: info: Plugin folders cleanup completed Oct 10 13:15:18 volumio volumio[26794]: info: ------------------------------------------- Oct 10 13:15:18 volumio volumio[26794]: info: ----- Core plugins startup ---- Oct 10 13:15:18 volumio volumio[26794]: info: ------------------------------------------- Oct 10 13:15:18 volumio volumio[26794]: info: Loading plugins from folder /volumio/app/plugins/ Oct 10 13:15:18 volumio volumio[26794]: info: Adding plugin upnp to MyMusic Plugins Oct 10 13:15:18 volumio volumio[26794]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 10 13:15:18 volumio volumio[26794]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 10 13:15:18 volumio volumio[26794]: info: Loading plugins from folder /data/plugins/ Oct 10 13:15:18 volumio volumio[26794]: info: Loading plugin "system"... Oct 10 13:15:18 volumio volumio[26794]: info: Loading plugin "appearance"... Oct 10 13:15:19 volumio volumio[26794]: info: Loading plugin "network"... Oct 10 13:15:19 volumio volumio[26794]: info: Refreshing Cached IP Addresses Oct 10 13:15:19 volumio volumio[26794]: info: Loading plugin "services"... Oct 10 13:15:19 volumio sudo[26850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 13:15:19 volumio sudo[26850]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:19 volumio volumio[26794]: info: Loading plugin "alsa_controller"... Oct 10 13:15:19 volumio sudo[26850]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:19 volumio sudo[26852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 13:15:19 volumio sudo[26852]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:19 volumio sudo[26852]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:19 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:19 volumio volumio[26794]: info: Loading plugin "wizard"... Oct 10 13:15:19 volumio volumio[26794]: info: Loading plugin "networkfs"... Oct 10 13:15:19 volumio volumio[26794]: info: Starting Udev Watcher for removable devices Oct 10 13:15:20 volumio volumio[26794]: info: Ignoring mount for partition: boot Oct 10 13:15:20 volumio volumio[26794]: info: Ignoring mount for partition: volumio Oct 10 13:15:20 volumio volumio[26794]: info: Ignoring mount for partition: volumio_data Oct 10 13:15:20 volumio volumio[26794]: info: Mounting Device CES_X64FREO Oct 10 13:15:20 volumio sudo[26861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:20 volumio sudo[26861]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:20 volumio sudo[26861]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:20 volumio volumio[26794]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:15:20 volumio volumio[26794]: 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 10 13:15:20 volumio volumio[26794]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:15:20 volumio volumio[26794]: error: Cannot associate FS Label, not mounting Oct 10 13:15:20 volumio volumio[26794]: info: Mounting Device ADATA_HD650 Oct 10 13:15:20 volumio sudo[26867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/ADATA_HD650 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:20 volumio sudo[26867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:20 volumio volumio-remote-updater[674]: [2024-10-10 13:15:20] [connect] Successful connection Oct 10 13:15:21 volumio ntpd[803]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Oct 10 13:15:23 volumio ntfs-3g[26871]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 10 13:15:23 volumio ntfs-3g[26871]: Mounted /dev/sdb2 (Read-Write, label "ADATA HD650", NTFS 3.1) Oct 10 13:15:23 volumio ntfs-3g[26871]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:23 volumio ntfs-3g[26871]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb2,blkdev,blksize=4096 Oct 10 13:15:23 volumio ntfs-3g[26871]: Global ownership and permissions enforced, configuration type 7 Oct 10 13:15:23 volumio sudo[26867]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:23 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:23 volumio volumio[26794]: info: Loading plugin "volumio_command_line_client"... Oct 10 13:15:23 volumio volumio[26794]: info: Loading plugin "upnp"... Oct 10 13:15:23 volumio volumio[26794]: info: [1728555323857] Starting Upmpd Daemon Oct 10 13:15:23 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:23 volumio volumio[26794]: info: Loading plugin "my_music"... Oct 10 13:15:23 volumio volumio[26794]: info: Loading plugin "mpd"... Oct 10 13:15:24 volumio volumio[26794]: info: Loading plugin "upnp_browser"... Oct 10 13:15:24 volumio volumio[26794]: info: Loading plugin "alarm-clock"... Oct 10 13:15:24 volumio volumio[26794]: info: Loading plugin "airplay_emulation"... Oct 10 13:15:24 volumio volumio[26794]: info: Starting Shairport Sync Oct 10 13:15:24 volumio volumio[26794]: info: Loading plugin "last_100"... Oct 10 13:15:24 volumio volumio[26794]: info: Loading plugin "webradio"... Oct 10 13:15:25 volumio volumio[26794]: info: Loading plugin "i2s_dacs"... Oct 10 13:15:25 volumio volumio[26794]: info: Loading plugin "volumiodiscovery"... Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** For more information see Oct 10 13:15:25 volumio node[26794]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:25 volumio volumio[26794]: *** WARNING *** For more information see Oct 10 13:15:25 volumio node[26794]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:25 volumio node[26794]: *** WARNING *** For more information see Oct 10 13:15:25 volumio node[26794]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:25 volumio node[26794]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:25 volumio node[26794]: *** WARNING *** For more information see Oct 10 13:15:25 volumio volumio[26794]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 13:15:25 volumio volumio[26794]: info: Discovery: Started advertising with name: Volumio Oct 10 13:15:25 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:25 volumio volumio[26794]: info: Loading plugin "squeezelite_mc"... Oct 10 13:15:26 volumio volumio[26794]: info: Loading plugin "ytcr"... Oct 10 13:15:28 volumio volumio[26794]: info: Loading plugin "ytmusic"... Oct 10 13:15:29 volumio volumio[26794]: info: Loading plugin "outputs"... Oct 10 13:15:29 volumio volumio[26794]: info: Loading plugin "albumart"... Oct 10 13:15:29 volumio volumio[26794]: info: Plugin example_plugin is not enabled Oct 10 13:15:29 volumio volumio[26794]: info: Loading plugin "inputs"... Oct 10 13:15:29 volumio volumio[26794]: info: Loading plugin "updater_comm"... Oct 10 13:15:30 volumio volumio[26794]: info: Plugin mpdemulation is not enabled Oct 10 13:15:30 volumio volumio[26794]: info: Loading plugin "rest_api"... Oct 10 13:15:30 volumio volumio[26794]: info: Loading plugin "websocket"... Oct 10 13:15:30 volumio volumio[26794]: info: Starting Socket.io Server version 2.3.0 Oct 10 13:15:30 volumio volumio[26794]: info: Loading plugin "minidlna"... Oct 10 13:15:30 volumio volumio[26794]: info: Applying required configuration parameters for plugin minidlna Oct 10 13:15:30 volumio volumio[26794]: Forking 3 albumart workers Oct 10 13:15:30 volumio volumio[26794]: info: Loading i18n strings for locale ru Oct 10 13:15:30 volumio volumio[26794]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:15:30 volumio volumio[26794]: Updating browse sources language Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::initPlayerControls Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:30 volumio volumio[26794]: Express server listening on port 3000 Oct 10 13:15:30 volumio volumio[26794]: [Metrics] WebUI: 13s 761.74ms Oct 10 13:15:30 volumio volumio[26794]: info: CoreStateMachine::resetVolumioState Oct 10 13:15:30 volumio volumio[26794]: info: CoreStateMachine::getcurrentVolume Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:15:30 volumio volumio[26794]: info: CoreStateMachine::pushState Oct 10 13:15:30 volumio volumio[26794]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:30 volumio volumio[26794]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: info: CoreStateMachine::setRepeat true single undefined Oct 10 13:15:31 volumio volumio[26794]: info: CoreStateMachine::pushState Oct 10 13:15:31 volumio volumio[26794]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:31 volumio volumio[26794]: info: CoreStateMachine::setRandom false Oct 10 13:15:31 volumio volumio[26794]: info: CoreStateMachine::pushState Oct 10 13:15:31 volumio volumio[26794]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:31 volumio volumio[26794]: info: Setting Device type: Raspberry PI Oct 10 13:15:31 volumio volumio[26794]: info: Completed loading Core Plugins Oct 10 13:15:31 volumio volumio[26794]: info: Preparing to generate the ALSA configuration file Oct 10 13:15:31 volumio volumio[26794]: info: Asound.conf file unchanged, so no further update is needed Oct 10 13:15:31 volumio volumio[26794]: info: Output device has changed, restarting MPD Oct 10 13:15:31 volumio sudo[26912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:15:31 volumio sudo[26912]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio sudo[26912]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:31 volumio volumio[26794]: info: Output device has changed, restarting Shairport Sync Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:31 volumio sudo[26915]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:15:31 volumio sudo[26915]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 13:15:31 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:15:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:15:31 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:15:31 volumio volumio[26794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:31 volumio volumio[26794]: info: ___________ START PLUGINS ___________ Oct 10 13:15:31 volumio volumio[26794]: info: ControllerMpd::onStart: Initializing MPD Oct 10 13:15:31 volumio volumio[26794]: info: Creating MPD Configuration file Oct 10 13:15:31 volumio sudo[26922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:15:31 volumio sudo[26922]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:31 volumio sudo[26922]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:31 volumio sudo[26920]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:15:31 volumio volumio[26794]: info: [1728555331641] CoreMusicLibrary::Adding element Медиа-серверы Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:31 volumio sudo[26920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio sudo[26920]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:31 volumio sudo[26925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:15:31 volumio sudo[26925]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:31 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 10 13:15:31 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:15:31 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:15:31 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:15:31 volumio volumio[26794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:31 volumio volumio[26794]: Starting albumart workers Oct 10 13:15:31 volumio sudo[26930]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:15:31 volumio sudo[26930]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:31 volumio sudo[26930]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:31 volumio volumio[26794]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:31 volumio volumio[26794]: info: [1728555331941] CoreMusicLibrary::Adding element Last_100 Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:31 volumio volumio[26794]: info: [1728555331945] CoreMusicLibrary::Adding element Webradio Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:31 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:31 volumio volumio[26794]: info: Initializing BBC Radios Oct 10 13:15:32 volumio volumio[26794]: Starting albumart workers Oct 10 13:15:32 volumio volumio[26794]: Starting albumart workers Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:32 volumio volumio[26794]: info: [squeezelite_mc] Starting proxy server... Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:32 volumio volumio[26794]: info: [1728555332348] CoreMusicLibrary::Adding element YouTube Music Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:32 volumio volumio[26794]: Cannot find translation for source YouTube Music Oct 10 13:15:32 volumio volumio[26794]: info: Loading i18n strings for locale ru Oct 10 13:15:32 volumio volumio[26794]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:15:32 volumio volumio[26794]: info: Volumio Calling Home Oct 10 13:15:32 volumio volumio[26794]: info: [squeezelite_mc] Proxy server started on port 33225 Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::volumioGetState Oct 10 13:15:32 volumio volumio[26794]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:32 volumio volumio[26794]: info: CoreStateMachine::pushState Oct 10 13:15:32 volumio volumio[26794]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:32 volumio volumio-remote-updater[674]: [2024-10-10 13:15:32] [connect] Successful connection Oct 10 13:15:32 volumio volumio[26794]: info: [squeezelite_mc] Server discovery started Oct 10 13:15:32 volumio volumio[26794]: info: [squeezelite_mc] Player finder started Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:32 volumio volumio[26794]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:32 volumio volumio[26794]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 10 13:15:32 volumio volumio[26794]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:32 volumio volumio[26794]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 10 13:15:32 volumio volumio[26794]: at doSend (dgram.js:692:16) Oct 10 13:15:32 volumio volumio[26794]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 10 13:15:32 volumio volumio[26794]: at afterDns (dgram.js:638:5) Oct 10 13:15:32 volumio volumio[26794]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 10 13:15:32 volumio volumio[26794]: errno: -101, Oct 10 13:15:32 volumio volumio[26794]: code: 'ENETUNREACH', Oct 10 13:15:32 volumio volumio[26794]: syscall: 'send', Oct 10 13:15:32 volumio volumio[26794]: address: '255.255.255.255', Oct 10 13:15:32 volumio volumio[26794]: port: 3483 Oct 10 13:15:32 volumio volumio[26794]: } Oct 10 13:15:32 volumio volumio[26794]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:33 volumio sudo[26988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 13:14 Oct 10 13:15:33 volumio sudo[26988]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:34 volumio sudo[26988]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:34 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Oct 10 13:15:34 volumio volumio-remote-updater[674]: [2024-10-10 13:15:34] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Oct 10 13:15:34 volumio volumio-remote-updater[674]: [2024-10-10 13:15:34] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Oct 10 13:15:34 volumio volumio-remote-updater[674]: [2024-10-10 13:15:34] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Oct 10 13:15:34 volumio ntfs-3g[26871]: Unmounting /dev/sdb2 (ADATA HD650) Oct 10 13:15:34 volumio systemd[1]: media-ADATA_HD650.mount: Succeeded. Oct 10 13:15:34 volumio sudo[26925]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:34 volumio sudo[26915]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:34 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Oct 10 13:15:34 volumio systemd[1]: Started dynamicswap service. Oct 10 13:15:34 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Oct 10 13:15:34 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 10. Oct 10 13:15:34 volumio systemd[1]: dynamicswap.service: Succeeded. Oct 10 13:15:34 volumio systemd[1]: Stopped Volumio Backend Module. Oct 10 13:15:34 volumio systemd[1]: Started Volumio Backend Module. Oct 10 13:15:34 volumio mpd[26937]: Oct 10 13:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 13:15:34 volumio systemd[1]: Started Music Player Daemon. Oct 10 13:15:35 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:36 volumio ifplugd(eth0)[816]: Link beat detected. Oct 10 13:15:36 volumio volumio[27002]: info: ------------------------------------------- Oct 10 13:15:36 volumio volumio[27002]: info: ----- Volumio3 ---- Oct 10 13:15:36 volumio volumio[27002]: info: ------------------------------------------- Oct 10 13:15:36 volumio volumio[27002]: info: ----- System startup ---- Oct 10 13:15:36 volumio volumio[27002]: info: ------------------------------------------- Oct 10 13:15:36 volumio ifplugd(eth0)[816]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Oct 10 13:15:36 volumio dhcpcd[27038]: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 10 13:15:36 volumio dhcpcd[27038]: eth0: IAID eb:5d:71:15 Oct 10 13:15:36 volumio dhcpcd[27038]: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 10 13:15:36 volumio dhcpcd[27038]: ipv6_addaddr1: Permission denied Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: DUID 00:01:00:01:2c:c5:40:e6:b8:27:eb:5d:71:15 Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: eth0: IAID eb:5d:71:15 Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: eth0: adding address fe80::ba27:ebff:fe5d:7115 Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: ipv6_addaddr1: Permission denied Oct 10 13:15:36 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:36 volumio dhcpcd[27038]: eth0: soliciting an IPv6 router Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: eth0: soliciting an IPv6 router Oct 10 13:15:36 volumio dhcpcd[27038]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:36 volumio dhcpcd[27038]: eth0: carrier lost Oct 10 13:15:36 volumio ifplugd(eth0)[816]: client: eth0: carrier lost Oct 10 13:15:37 volumio volumio[27002]: info: MYVOLUMIO Environment detected Oct 10 13:15:37 volumio volumio[27002]: info: Plugin folders cleanup Oct 10 13:15:37 volumio volumio[27002]: info: Scanning into folder /volumio/app/plugins/ Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category audio_interface Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category miscellanea Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category music_service Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category plugins.json Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category system_controller Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category user_interface Oct 10 13:15:37 volumio volumio[27002]: info: Scanning into folder /data/plugins/ Oct 10 13:15:37 volumio volumio[27002]: info: Scanning category music_service Oct 10 13:15:37 volumio volumio[27002]: info: Plugin folders cleanup completed Oct 10 13:15:37 volumio volumio[27002]: info: ------------------------------------------- Oct 10 13:15:37 volumio volumio[27002]: info: ----- Core plugins startup ---- Oct 10 13:15:37 volumio volumio[27002]: info: ------------------------------------------- Oct 10 13:15:37 volumio volumio[27002]: info: Loading plugins from folder /volumio/app/plugins/ Oct 10 13:15:37 volumio volumio[27002]: info: Adding plugin upnp to MyMusic Plugins Oct 10 13:15:37 volumio volumio[27002]: info: Adding plugin airplay_emulation to MyMusic Plugins Oct 10 13:15:37 volumio volumio[27002]: info: Adding plugin upnp_browser to MyMusic Plugins Oct 10 13:15:37 volumio volumio[27002]: info: Loading plugins from folder /data/plugins/ Oct 10 13:15:37 volumio volumio[27002]: info: Loading plugin "system"... Oct 10 13:15:37 volumio volumio[27002]: info: Loading plugin "appearance"... Oct 10 13:15:38 volumio dhcpcd[27038]: eth0: carrier acquired Oct 10 13:15:38 volumio ifplugd(eth0)[816]: client: eth0: carrier acquired Oct 10 13:15:38 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:38 volumio dhcpcd[27038]: eth0: IAID eb:5d:71:15 Oct 10 13:15:38 volumio ifplugd(eth0)[816]: client: eth0: IAID eb:5d:71:15 Oct 10 13:15:38 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:38 volumio volumio[27002]: info: Loading plugin "network"... Oct 10 13:15:38 volumio volumio[27002]: info: Refreshing Cached IP Addresses Oct 10 13:15:38 volumio volumio[27002]: info: Loading plugin "services"... Oct 10 13:15:38 volumio sudo[27109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 10 13:15:38 volumio volumio[27002]: info: Loading plugin "alsa_controller"... Oct 10 13:15:38 volumio sudo[27109]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:38 volumio sudo[27109]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:38 volumio sudo[27111]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 10 13:15:38 volumio sudo[27111]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:38 volumio sudo[27111]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:38 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:38 volumio volumio[27002]: info: Loading plugin "wizard"... Oct 10 13:15:38 volumio volumio[27002]: info: Loading plugin "networkfs"... Oct 10 13:15:38 volumio volumio[27002]: info: Starting Udev Watcher for removable devices Oct 10 13:15:38 volumio volumio[27002]: info: Ignoring mount for partition: boot Oct 10 13:15:38 volumio volumio[27002]: info: Ignoring mount for partition: volumio Oct 10 13:15:38 volumio volumio[27002]: info: Ignoring mount for partition: volumio_data Oct 10 13:15:38 volumio volumio[27002]: info: Mounting Device CES_X64FREO Oct 10 13:15:38 volumio sudo[27120]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda4 /mnt/USB/CES_X64FREO -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:38 volumio sudo[27120]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:38 volumio sudo[27120]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:38 volumio volumio[27002]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:15:38 volumio volumio[27002]: 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 10 13:15:38 volumio volumio[27002]: mount: /media/CES_X64FREO: /dev/sda4 already mounted on /media/CES_X64FREO. Oct 10 13:15:38 volumio volumio[27002]: error: Cannot associate FS Label, not mounting Oct 10 13:15:38 volumio volumio[27002]: info: Mounting Device ADATA_HD650 Oct 10 13:15:38 volumio sudo[27126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb2 /mnt/USB/ADATA_HD650 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:38 volumio sudo[27126]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:39 volumio dhcpcd[27038]: eth0: soliciting an IPv6 router Oct 10 13:15:39 volumio ifplugd(eth0)[816]: client: eth0: soliciting an IPv6 router Oct 10 13:15:39 volumio dhcpcd[27038]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:39 volumio ifplugd(eth0)[816]: client: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:39 volumio dhcpcd[27038]: eth0: carrier lost Oct 10 13:15:39 volumio ifplugd(eth0)[816]: client: eth0: carrier lost Oct 10 13:15:42 volumio dhcpcd[27038]: eth0: carrier acquired Oct 10 13:15:42 volumio ifplugd(eth0)[816]: client: eth0: carrier acquired Oct 10 13:15:42 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:42 volumio dhcpcd[27038]: eth0: IAID eb:5d:71:15 Oct 10 13:15:42 volumio ifplugd(eth0)[816]: client: eth0: IAID eb:5d:71:15 Oct 10 13:15:42 volumio dhcpcd[27038]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:42 volumio ifplugd(eth0)[816]: client: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:42 volumio dhcpcd[27038]: eth0: probing address 192.168.31.163/24 Oct 10 13:15:42 volumio ifplugd(eth0)[816]: client: eth0: probing address 192.168.31.163/24 Oct 10 13:15:42 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down Oct 10 13:15:42 volumio dhcpcd[27038]: eth0: soliciting an IPv6 router Oct 10 13:15:42 volumio ifplugd(eth0)[816]: client: eth0: soliciting an IPv6 router Oct 10 13:15:42 volumio ntfs-3g[27152]: Version 2017.3.23AR.3 integrated FUSE 28 Oct 10 13:15:42 volumio ntfs-3g[27152]: Mounted /dev/sdb2 (Read-Write, label "ADATA HD650", NTFS 3.1) Oct 10 13:15:42 volumio ntfs-3g[27152]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Oct 10 13:15:42 volumio ntfs-3g[27152]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb2,blkdev,blksize=4096 Oct 10 13:15:42 volumio ntfs-3g[27152]: Global ownership and permissions enforced, configuration type 7 Oct 10 13:15:42 volumio sudo[27126]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:42 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:42 volumio volumio[27002]: info: Loading plugin "volumio_command_line_client"... Oct 10 13:15:42 volumio volumio[27002]: info: Loading plugin "upnp"... Oct 10 13:15:42 volumio volumio[27002]: info: [1728555342945] Starting Upmpd Daemon Oct 10 13:15:42 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:42 volumio volumio[27002]: info: Loading plugin "my_music"... Oct 10 13:15:42 volumio volumio[27002]: info: Loading plugin "mpd"... Oct 10 13:15:43 volumio dhcpcd[27038]: eth0: carrier lost Oct 10 13:15:43 volumio ifplugd(eth0)[816]: client: eth0: carrier lost Oct 10 13:15:43 volumio volumio[27002]: info: Loading plugin "upnp_browser"... Oct 10 13:15:43 volumio volumio[27002]: info: Loading plugin "alarm-clock"... Oct 10 13:15:43 volumio volumio[27002]: info: Loading plugin "airplay_emulation"... Oct 10 13:15:43 volumio volumio[27002]: info: Starting Shairport Sync Oct 10 13:15:43 volumio volumio[27002]: info: Loading plugin "last_100"... Oct 10 13:15:43 volumio volumio[27002]: info: Loading plugin "webradio"... Oct 10 13:15:44 volumio volumio[27002]: info: Loading plugin "i2s_dacs"... Oct 10 13:15:44 volumio volumio[27002]: info: Loading plugin "volumiodiscovery"... Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** For more information see Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:44 volumio node[27002]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:44 volumio volumio[27002]: *** WARNING *** For more information see Oct 10 13:15:44 volumio node[27002]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:44 volumio node[27002]: *** WARNING *** For more information see Oct 10 13:15:44 volumio node[27002]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 10 13:15:44 volumio node[27002]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 10 13:15:44 volumio node[27002]: *** WARNING *** For more information see Oct 10 13:15:44 volumio volumio[27002]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 10 13:15:44 volumio volumio[27002]: info: Discovery: Started advertising with name: Volumio Oct 10 13:15:44 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 10 13:15:44 volumio volumio[27002]: info: Loading plugin "squeezelite_mc"... Oct 10 13:15:44 volumio volumio-remote-updater[674]: [2024-10-10 13:15:44] [connect] Successful connection Oct 10 13:15:44 volumio volumio[27002]: info: Loading plugin "ytcr"... Oct 10 13:15:47 volumio dhcpcd[27038]: eth0: carrier acquired Oct 10 13:15:47 volumio ifplugd(eth0)[816]: client: eth0: carrier acquired Oct 10 13:15:47 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Up - 100Mbps/Full - flow control off Oct 10 13:15:47 volumio dhcpcd[27038]: eth0: IAID eb:5d:71:15 Oct 10 13:15:47 volumio ifplugd(eth0)[816]: client: eth0: IAID eb:5d:71:15 Oct 10 13:15:47 volumio volumio[27002]: info: Loading plugin "ytmusic"... Oct 10 13:15:47 volumio dhcpcd[27038]: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:47 volumio ifplugd(eth0)[816]: client: eth0: rebinding lease of 192.168.31.163 Oct 10 13:15:47 volumio dhcpcd[27038]: eth0: probing address 192.168.31.163/24 Oct 10 13:15:47 volumio ifplugd(eth0)[816]: client: eth0: probing address 192.168.31.163/24 Oct 10 13:15:48 volumio dhcpcd[27038]: eth0: soliciting an IPv6 router Oct 10 13:15:48 volumio ifplugd(eth0)[816]: client: eth0: soliciting an IPv6 router Oct 10 13:15:48 volumio volumio[27002]: info: Loading plugin "outputs"... Oct 10 13:15:48 volumio volumio[27002]: info: Loading plugin "albumart"... Oct 10 13:15:48 volumio volumio[27002]: info: Plugin example_plugin is not enabled Oct 10 13:15:48 volumio volumio[27002]: info: Loading plugin "inputs"... Oct 10 13:15:48 volumio volumio[27002]: info: Loading plugin "updater_comm"... Oct 10 13:15:49 volumio volumio[27002]: info: Plugin mpdemulation is not enabled Oct 10 13:15:49 volumio volumio[27002]: info: Loading plugin "rest_api"... Oct 10 13:15:49 volumio volumio[27002]: info: Loading plugin "websocket"... Oct 10 13:15:49 volumio volumio[27002]: info: Starting Socket.io Server version 2.3.0 Oct 10 13:15:49 volumio volumio[27002]: info: Loading plugin "minidlna"... Oct 10 13:15:49 volumio volumio[27002]: info: Applying required configuration parameters for plugin minidlna Oct 10 13:15:49 volumio volumio[27002]: Forking 3 albumart workers Oct 10 13:15:49 volumio volumio[27002]: info: Loading i18n strings for locale ru Oct 10 13:15:49 volumio volumio[27002]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:15:49 volumio volumio[27002]: Updating browse sources language Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::initPlayerControls Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: Express server listening on port 3000 Oct 10 13:15:49 volumio volumio[27002]: [Metrics] WebUI: 13s 817.89ms Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::resetVolumioState Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::getcurrentVolume Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::pushState Oct 10 13:15:49 volumio volumio[27002]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::setRepeat true single undefined Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::pushState Oct 10 13:15:49 volumio volumio[27002]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::setRandom false Oct 10 13:15:49 volumio volumio[27002]: info: CoreStateMachine::pushState Oct 10 13:15:49 volumio volumio[27002]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 10 13:15:49 volumio volumio[27002]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:49 volumio volumio[27002]: info: Setting Device type: Raspberry PI Oct 10 13:15:49 volumio volumio[27002]: info: Completed loading Core Plugins Oct 10 13:15:49 volumio volumio[27002]: info: Preparing to generate the ALSA configuration file Oct 10 13:15:50 volumio volumio[27002]: info: Asound.conf file unchanged, so no further update is needed Oct 10 13:15:50 volumio volumio[27002]: info: Output device has changed, restarting MPD Oct 10 13:15:50 volumio volumio[27002]: info: Output device has changed, restarting Shairport Sync Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:50 volumio sudo[27214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:15:50 volumio sudo[27214]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:50 volumio sudo[27214]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:50 volumio sudo[27215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:15:50 volumio sudo[27215]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:50 volumio volumio[27002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:50 volumio volumio[27002]: info: ___________ START PLUGINS ___________ Oct 10 13:15:50 volumio volumio[27002]: info: ControllerMpd::onStart: Initializing MPD Oct 10 13:15:50 volumio volumio[27002]: info: Creating MPD Configuration file Oct 10 13:15:50 volumio systemd[1]: Stopping Music Player Daemon... Oct 10 13:15:50 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:15:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:15:50 volumio sudo[27222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 10 13:15:50 volumio sudo[27222]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:50 volumio volumio[27002]: info: [1728555350325] CoreMusicLibrary::Adding element Медиа-серверы Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:50 volumio sudo[27222]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:50 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:15:50 volumio sudo[27226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 10 13:15:50 volumio sudo[27226]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:50 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Oct 10 13:15:50 volumio systemd[1]: mpd.service: Succeeded. Oct 10 13:15:50 volumio systemd[1]: Stopped Music Player Daemon. Oct 10 13:15:50 volumio systemd[1]: Starting Music Player Daemon... Oct 10 13:15:50 volumio volumio[27002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:50 volumio volumio[27002]: info: [1728555350572] CoreMusicLibrary::Adding element Last_100 Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:50 volumio volumio[27002]: info: [1728555350577] CoreMusicLibrary::Adding element Webradio Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:50 volumio volumio[27002]: info: Initializing BBC Radios Oct 10 13:15:50 volumio sudo[27231]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 10 13:15:50 volumio sudo[27231]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 10 13:15:50 volumio sudo[27231]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 10 13:15:50 volumio volumio[27002]: info: [squeezelite_mc] Starting proxy server... Oct 10 13:15:50 volumio volumio[27002]: Starting albumart workers Oct 10 13:15:50 volumio volumio[27002]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 10 13:15:51 volumio volumio[27002]: info: [1728555351007] CoreMusicLibrary::Adding element YouTube Music Oct 10 13:15:51 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 10 13:15:51 volumio volumio[27002]: Cannot find translation for source YouTube Music Oct 10 13:15:51 volumio volumio[27002]: info: Loading i18n strings for locale ru Oct 10 13:15:51 volumio volumio[27002]: Starting albumart workers Oct 10 13:15:51 volumio volumio[27002]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. Oct 10 13:15:51 volumio volumio[27002]: Starting albumart workers Oct 10 13:15:51 volumio volumio[27002]: info: Volumio Calling Home Oct 10 13:15:51 volumio volumio[27002]: info: [squeezelite_mc] Proxy server started on port 37441 Oct 10 13:15:51 volumio volumio[27002]: info: CoreCommandRouter::volumioRetrievevolume Oct 10 13:15:52 volumio volumio[27002]: info: CoreCommandRouter::volumioGetState Oct 10 13:15:52 volumio volumio[27002]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:52 volumio volumio[27002]: info: CoreStateMachine::pushState Oct 10 13:15:52 volumio volumio[27002]: info: CorePlayQueue::getTrack 0 Oct 10 13:15:52 volumio volumio[27002]: info: CoreCommandRouter::volumioPushState Oct 10 13:15:52 volumio volumio[27002]: info: [squeezelite_mc] Server discovery started Oct 10 13:15:52 volumio volumio[27002]: info: [squeezelite_mc] Player finder started Oct 10 13:15:52 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:52 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 10 13:15:52 volumio volumio[27002]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 10 13:15:52 volumio volumio[27002]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Oct 10 13:15:52 volumio volumio[27002]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:52 volumio volumio[27002]: Error: send ENETUNREACH 255.255.255.255:3483 Oct 10 13:15:52 volumio volumio[27002]: at doSend (dgram.js:692:16) Oct 10 13:15:52 volumio volumio[27002]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Oct 10 13:15:52 volumio volumio[27002]: at afterDns (dgram.js:638:5) Oct 10 13:15:52 volumio volumio[27002]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Oct 10 13:15:52 volumio volumio[27002]: errno: -101, Oct 10 13:15:52 volumio volumio[27002]: code: 'ENETUNREACH', Oct 10 13:15:52 volumio volumio[27002]: syscall: 'send', Oct 10 13:15:52 volumio volumio[27002]: address: '255.255.255.255', Oct 10 13:15:52 volumio volumio[27002]: port: 3483 Oct 10 13:15:52 volumio volumio[27002]: } Oct 10 13:15:52 volumio volumio[27002]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 10 13:15:52 volumio mpd[27237]: Oct 10 13:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 10 13:15:52 volumio systemd[1]: Started Music Player Daemon. Oct 10 13:15:52 volumio sudo[27215]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:52 volumio sudo[27226]: pam_unix(sudo:session): session closed for user root Oct 10 13:15:52 volumio dhcpcd[27038]: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:15:52 volumio ifplugd(eth0)[816]: client: eth0: leased 192.168.31.163 for 43200 seconds Oct 10 13:15:52 volumio avahi-daemon[683]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.31.163. Oct 10 13:15:52 volumio avahi-daemon[683]: New relevant interface eth0.IPv4 for mDNS. Oct 10 13:15:52 volumio ifplugd(eth0)[816]: client: eth0: adding route to 192.168.31.0/24 Oct 10 13:15:52 volumio avahi-daemon[683]: Registering new address record for 192.168.31.163 on eth0.IPv4. Oct 10 13:15:52 volumio dhcpcd[27038]: eth0: adding route to 192.168.31.0/24 Oct 10 13:15:52 volumio ifplugd(eth0)[816]: client: eth0: adding default route via 192.168.31.1 Oct 10 13:15:52 volumio dhcpcd[27038]: eth0: adding default route via 192.168.31.1 Oct 10 13:15:52 volumio dhcpcd[27038]: forked to background, child pid 27309 Oct 10 13:15:52 volumio ifplugd(eth0)[816]: client: forked to background, child pid 27309 Oct 10 13:15:53 volumio sudo[27317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-10 13:14 Oct 10 13:15:53 volumio sudo[27317]: 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"