-- Logs begin at Sun 2025-04-13 07:46:17 KST, end at Sun 2025-04-20 13:57:33 KST. -- Apr 20 13:56:52 volumiorp ifplugd(eth0)[772]: Link beat lost. Apr 20 13:56:52 volumiorp dhcpcd[1833]: eth0: carrier lost Apr 20 13:56:52 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Apr 20 13:56:52 volumiorp dhcpcd[1833]: eth0: deleting route to 192.168.0.0/24 Apr 20 13:56:52 volumiorp avahi-daemon[512]: Withdrawing address record for 192.168.0.4 on eth0. Apr 20 13:56:52 volumiorp avahi-daemon[512]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.4. Apr 20 13:56:52 volumiorp avahi-daemon[512]: Interface eth0.IPv4 no longer relevant for mDNS. Apr 20 13:56:52 volumiorp volumio[1757]: info: Discovery: A device disappeared from network Apr 20 13:56:52 volumiorp volumio[1757]: info: Discovery: Device volumiorp disappeared from network Apr 20 13:56:54 volumiorp ntpd[779]: Deleting interface #33 eth0, 192.168.0.4#123, interface stats: received=33, sent=57, dropped=0, active_time=601 secs Apr 20 13:56:54 volumiorp ntpd[779]: 158.247.202.103 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 121.174.142.81 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 121.174.142.82 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 193.123.243.2 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 175.195.167.194 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 221.151.118.78 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp ntpd[779]: 106.247.248.106 local addr 192.168.0.4 -> Apr 20 13:56:54 volumiorp volumio[1757]: info: Volumio Network Manager: Network status updated: 0 Apr 20 13:56:58 volumiorp volumio[1757]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:56:58 volumiorp volumio[1757]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 20 13:56:58 volumiorp volumio[1757]: at doSend (dgram.js:692:16) Apr 20 13:56:58 volumiorp volumio[1757]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 20 13:56:58 volumiorp volumio[1757]: at afterDns (dgram.js:638:5) Apr 20 13:56:58 volumiorp volumio[1757]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 20 13:56:58 volumiorp volumio[1757]: errno: -101, Apr 20 13:56:58 volumiorp volumio[1757]: code: 'ENETUNREACH', Apr 20 13:56:58 volumiorp volumio[1757]: syscall: 'send', Apr 20 13:56:58 volumiorp volumio[1757]: address: '255.255.255.255', Apr 20 13:56:58 volumiorp volumio[1757]: port: 3483 Apr 20 13:56:58 volumiorp volumio[1757]: } Apr 20 13:56:58 volumiorp volumio[1757]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:56:59 volumiorp sudo[3674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 13:55 Apr 20 13:56:59 volumiorp sudo[3674]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:56:59 volumiorp sudo[3674]: pam_unix(sudo:session): session closed for user root Apr 20 13:56:59 volumiorp volumio-remote-updater[503]: [2025-04-20 13:56:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 20 13:56:59 volumiorp volumio-remote-updater[503]: [2025-04-20 13:56:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 20 13:56:59 volumiorp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 13:56:59 volumiorp systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 13:57:00 volumiorp systemd[1]: Started dynamicswap service. Apr 20 13:57:00 volumiorp systemd[1]: dynamicswap.service: Succeeded. Apr 20 13:57:00 volumiorp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 13:57:00 volumiorp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. Apr 20 13:57:00 volumiorp systemd[1]: Started dynamicswap service. Apr 20 13:57:00 volumiorp systemd[1]: Stopped Volumio Backend Module. Apr 20 13:57:00 volumiorp systemd[1]: Started Volumio Backend Module. Apr 20 13:57:00 volumiorp systemd[1]: dynamicswap.service: Succeeded. Apr 20 13:57:02 volumiorp ifplugd(eth0)[772]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Apr 20 13:57:02 volumiorp dhcpcd[3713]: sending signal ALRM to pid 1833 Apr 20 13:57:02 volumiorp dhcpcd[3713]: waiting for pid 1833 to exit Apr 20 13:57:02 volumiorp ifplugd(eth0)[772]: client: sending signal ALRM to pid 1833 Apr 20 13:57:02 volumiorp ifplugd(eth0)[772]: client: waiting for pid 1833 to exit Apr 20 13:57:02 volumiorp dhcpcd[1833]: received SIGALRM, releasing Apr 20 13:57:02 volumiorp dhcpcd[1833]: eth0: removing interface Apr 20 13:57:02 volumiorp dhcpcd[1833]: dhcpcd exited Apr 20 13:57:02 volumiorp ifplugd(eth0)[772]: Program executed successfully. Apr 20 13:57:02 volumiorp kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Apr 20 13:57:02 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Down Apr 20 13:57:03 volumiorp volumio[3688]: info: ------------------------------------------- Apr 20 13:57:03 volumiorp volumio[3688]: info: ----- Volumio3 ---- Apr 20 13:57:03 volumiorp volumio[3688]: info: ------------------------------------------- Apr 20 13:57:03 volumiorp volumio[3688]: info: ----- System startup ---- Apr 20 13:57:03 volumiorp volumio[3688]: info: ------------------------------------------- Apr 20 13:57:04 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:04] [connect] Successful connection Apr 20 13:57:05 volumiorp volumio[3688]: info: MYVOLUMIO Environment detected Apr 20 13:57:05 volumiorp volumio[3688]: info: Plugin folders cleanup Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning into folder /volumio/app/plugins/ Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category audio_interface Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category miscellanea Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category music_service Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category plugins.json Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category system_controller Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category user_interface Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning into folder /data/plugins/ Apr 20 13:57:05 volumiorp volumio[3688]: info: Scanning category music_service Apr 20 13:57:05 volumiorp volumio[3688]: info: Plugin folders cleanup completed Apr 20 13:57:05 volumiorp volumio[3688]: info: ------------------------------------------- Apr 20 13:57:05 volumiorp volumio[3688]: info: ----- Core plugins startup ---- Apr 20 13:57:05 volumiorp volumio[3688]: info: ------------------------------------------- Apr 20 13:57:05 volumiorp volumio[3688]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 13:57:05 volumiorp volumio[3688]: info: Adding plugin upnp to MyMusic Plugins Apr 20 13:57:05 volumiorp volumio[3688]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 13:57:05 volumiorp volumio[3688]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 13:57:05 volumiorp volumio[3688]: info: Loading plugins from folder /data/plugins/ Apr 20 13:57:05 volumiorp volumio[3688]: info: Loading plugin "system"... Apr 20 13:57:05 volumiorp volumio[3688]: info: Loading plugin "appearance"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "network"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Refreshing Cached IP Addresses Apr 20 13:57:07 volumiorp sudo[3752]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 13:57:07 volumiorp sudo[3752]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "services"... Apr 20 13:57:07 volumiorp sudo[3754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 13:57:07 volumiorp sudo[3752]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:07 volumiorp sudo[3754]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:07 volumiorp sudo[3754]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "alsa_controller"... Apr 20 13:57:07 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "wizard"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "networkfs"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Starting Udev Watcher for removable devices Apr 20 13:57:07 volumiorp volumio[3688]: info: Ignoring mount for partition: boot Apr 20 13:57:07 volumiorp volumio[3688]: info: Ignoring mount for partition: volumio Apr 20 13:57:07 volumiorp volumio[3688]: info: Ignoring mount for partition: volumio_data Apr 20 13:57:07 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "volumio_command_line_client"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Plugin upnp is not enabled Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "my_music"... Apr 20 13:57:07 volumiorp volumio[3688]: info: Loading plugin "mpd"... Apr 20 13:57:08 volumiorp volumio[3688]: info: Plugin upnp_browser is not enabled Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "alarm-clock"... Apr 20 13:57:08 volumiorp volumio[3688]: info: Plugin airplay_emulation is not enabled Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "last_100"... Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "webradio"... Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "i2s_dacs"... Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "volumiodiscovery"... Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** For more information see Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:08 volumiorp volumio[3688]: *** WARNING *** For more information see Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** For more information see Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:08 volumiorp node[3688]: *** WARNING *** For more information see Apr 20 13:57:08 volumiorp volumio[3688]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 13:57:08 volumiorp volumio[3688]: info: Discovery: Started advertising with name: VolumioRP Apr 20 13:57:08 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 13:57:08 volumiorp volumio[3688]: info: Loading plugin "lmsde"... Apr 20 13:57:09 volumiorp volumio[3688]: info: Loading plugin "squeezelite_mc"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "outputs"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "albumart"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Plugin example_plugin is not enabled Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "inputs"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "updater_comm"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Plugin mpdemulation is not enabled Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "rest_api"... Apr 20 13:57:10 volumiorp volumio[3688]: info: Loading plugin "websocket"... Apr 20 13:57:11 volumiorp volumio[3688]: info: Starting Socket.io Server version 2.3.0 Apr 20 13:57:11 volumiorp volumio[3688]: info: Loading i18n strings for locale ko Apr 20 13:57:11 volumiorp volumio[3688]: Updating browse sources language Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:11 volumiorp volumio[3688]: Forking 3 albumart workers Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::initPlayerControls Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:11 volumiorp volumio[3688]: Express server listening on port 3000 Apr 20 13:57:11 volumiorp volumio[3688]: [Metrics] WebUI: 8s 908.01ms Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreStateMachine::resetVolumioState Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreStateMachine::getcurrentVolume Apr 20 13:57:11 volumiorp volumio[3688]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:11 volumiorp volumio[3688]: info: Volumio Network Manager: Network status updated: 0 Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:12 volumiorp volumio[3688]: info: Reloading queue from file Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::setRepeat null single undefined Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::pushState Apr 20 13:57:12 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::setRandom null Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::pushState Apr 20 13:57:12 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:12 volumiorp volumio[3688]: info: Setting Device type: Raspberry PI Apr 20 13:57:12 volumiorp volumio[3688]: info: Completed loading Core Plugins Apr 20 13:57:12 volumiorp volumio[3688]: info: Preparing to generate the ALSA configuration file Apr 20 13:57:12 volumiorp volumio[3688]: info: VolumeController:: Volume=86 Mute =false Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::pushState Apr 20 13:57:12 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreStateMachine::updateTrackBlock Apr 20 13:57:12 volumiorp volumio[3688]: info: CorePlayQueue::getTrackBlock Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:12 volumiorp volumio[3688]: info: Asound.conf file unchanged, so no further update is needed Apr 20 13:57:12 volumiorp volumio[3688]: info: Output device has changed, restarting MPD Apr 20 13:57:12 volumiorp volumio[3688]: info: ___________ START PLUGINS ___________ Apr 20 13:57:12 volumiorp volumio[3688]: info: ControllerMpd::onStart: Initializing MPD Apr 20 13:57:12 volumiorp volumio[3688]: info: Creating MPD Configuration file Apr 20 13:57:12 volumiorp sudo[3819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 13:57:12 volumiorp sudo[3819]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:12 volumiorp sudo[3819]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:12 volumiorp sudo[3821]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 13:57:12 volumiorp sudo[3821]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 13:57:12 volumiorp volumio[3688]: info: [1745125032429] CoreMusicLibrary::Adding element Last_100 Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 13:57:12 volumiorp volumio[3688]: info: [1745125032447] CoreMusicLibrary::Adding element Webradio Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:12 volumiorp sudo[3824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:12 volumiorp sudo[3824]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:12 volumiorp systemd[1]: Stopping Music Player Daemon... Apr 20 13:57:12 volumiorp sudo[3824]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:12 volumiorp volumio[3688]: info: Initializing BBC Radios Apr 20 13:57:12 volumiorp sudo[3827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 13:57:12 volumiorp sudo[3827]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:12 volumiorp systemd[1]: mpd.service: Succeeded. Apr 20 13:57:12 volumiorp systemd[1]: Stopped Music Player Daemon. Apr 20 13:57:12 volumiorp systemd[1]: Starting Music Player Daemon... Apr 20 13:57:12 volumiorp systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 20 13:57:12 volumiorp systemd[1]: mpd.service: Succeeded. Apr 20 13:57:12 volumiorp systemd[1]: Stopped Music Player Daemon. Apr 20 13:57:12 volumiorp systemd[1]: Starting Music Player Daemon... Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:12 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:12 volumiorp volumio[3688]: info: [lmsde] Executing script: /opt/lmsde/lms.sh start Apr 20 13:57:12 volumiorp volumio[3688]: info: [squeezelite_mc] Starting proxy server... Apr 20 13:57:12 volumiorp volumio[3688]: info: Volumio Calling Home Apr 20 13:57:12 volumiorp sudo[3838]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 20 13:57:12 volumiorp sudo[3838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:12 volumiorp sudo[3838]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:13 volumiorp sudo[3844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/opt/lmsde/lms.sh start Apr 20 13:57:13 volumiorp sudo[3844]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:13 volumiorp volumio[3688]: info: [squeezelite_mc] Proxy server started on port 44403 Apr 20 13:57:13 volumiorp volumio[3688]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:13 volumiorp volumio[3688]: Starting albumart workers Apr 20 13:57:13 volumiorp volumio[3688]: info: MPD Permissions set Apr 20 13:57:13 volumiorp volumio[3688]: info: MPD Permissions set Apr 20 13:57:13 volumiorp volumio[3688]: info: Discovery: adding 2891122c-60ff-48be-84d8-7957b3a3680b Apr 20 13:57:13 volumiorp volumio[3688]: info: Discovery: Found device VolumioRP Apr 20 13:57:13 volumiorp volumio[3688]: info: CoreCommandRouter::volumioGetState Apr 20 13:57:13 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:14 volumiorp volumio[3688]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Apr 20 13:57:14 volumiorp volumio[3688]: info: VolumeController:: Volume=86 Mute =false Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreStateMachine::pushState Apr 20 13:57:14 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: Starting albumart workers Apr 20 13:57:14 volumiorp volumio[3688]: info: VolumeController:: Volume=86 Mute =false Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreStateMachine::pushState Apr 20 13:57:14 volumiorp volumio[3688]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:14 volumiorp volumio[3688]: Starting albumart workers Apr 20 13:57:14 volumiorp volumio[3688]: info: [squeezelite_mc] Server discovery started Apr 20 13:57:14 volumiorp volumio[3688]: info: [squeezelite_mc] Player finder started Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:14 volumiorp volumio[3688]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 20 13:57:14 volumiorp volumio[3688]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:57:14 volumiorp volumio[3688]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 20 13:57:14 volumiorp volumio[3688]: at doSend (dgram.js:692:16) Apr 20 13:57:14 volumiorp volumio[3688]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 20 13:57:14 volumiorp volumio[3688]: at afterDns (dgram.js:638:5) Apr 20 13:57:14 volumiorp volumio[3688]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 20 13:57:14 volumiorp volumio[3688]: errno: -101, Apr 20 13:57:14 volumiorp volumio[3688]: code: 'ENETUNREACH', Apr 20 13:57:14 volumiorp volumio[3688]: syscall: 'send', Apr 20 13:57:14 volumiorp volumio[3688]: address: '255.255.255.255', Apr 20 13:57:14 volumiorp volumio[3688]: port: 3483 Apr 20 13:57:14 volumiorp volumio[3688]: } Apr 20 13:57:14 volumiorp volumio[3688]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:57:16 volumiorp mpd[3848]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 13:57:16 volumiorp mpd[3848]: output: No 'audio_output' defined in config file Apr 20 13:57:16 volumiorp mpd[3848]: output: Successfully detected a sndio audio device Apr 20 13:57:16 volumiorp mpd[3848]: zeroconf: No global port, disabling zeroconf Apr 20 13:57:16 volumiorp systemd[1]: Started Music Player Daemon. Apr 20 13:57:16 volumiorp sudo[3827]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:16 volumiorp sudo[3821]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:16 volumiorp sudo[3903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 13:56 Apr 20 13:57:16 volumiorp sudo[3903]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:16 volumiorp sudo[3903]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:16 volumiorp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 20 13:57:16 volumiorp sudo[3844]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:16 volumiorp systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 20 13:57:17 volumiorp systemd[1]: Started dynamicswap service. Apr 20 13:57:17 volumiorp systemd[1]: dynamicswap.service: Succeeded. Apr 20 13:57:17 volumiorp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 20 13:57:17 volumiorp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 35. Apr 20 13:57:17 volumiorp systemd[1]: Started dynamicswap service. Apr 20 13:57:17 volumiorp systemd[1]: Stopped Volumio Backend Module. Apr 20 13:57:17 volumiorp systemd[1]: Started Volumio Backend Module. Apr 20 13:57:17 volumiorp systemd[1]: dynamicswap.service: Succeeded. Apr 20 13:57:17 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:17] [info] asio async_connect error: system:111 (Connection refused) Apr 20 13:57:17 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:17] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Apr 20 13:57:17 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:17] [error] handle_connect error: Underlying Transport Error Apr 20 13:57:20 volumiorp volumio[3919]: info: ------------------------------------------- Apr 20 13:57:20 volumiorp volumio[3919]: info: ----- Volumio3 ---- Apr 20 13:57:20 volumiorp volumio[3919]: info: ------------------------------------------- Apr 20 13:57:20 volumiorp volumio[3919]: info: ----- System startup ---- Apr 20 13:57:20 volumiorp volumio[3919]: info: ------------------------------------------- Apr 20 13:57:21 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Apr 20 13:57:21 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Apr 20 13:57:21 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Apr 20 13:57:21 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: kevent 4 may have been dropped Apr 20 13:57:21 volumiorp kernel: lan78xx 1-1.1.1:1.0 eth0: Link is Up - 1Gbps/Full - flow control rx/tx Apr 20 13:57:21 volumiorp volumio[3919]: info: MYVOLUMIO Environment detected Apr 20 13:57:21 volumiorp volumio[3919]: info: Plugin folders cleanup Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning into folder /volumio/app/plugins/ Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category audio_interface Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category miscellanea Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category music_service Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category plugins.json Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category system_controller Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category user_interface Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning into folder /data/plugins/ Apr 20 13:57:21 volumiorp volumio[3919]: info: Scanning category music_service Apr 20 13:57:21 volumiorp ifplugd(eth0)[772]: Link beat detected. Apr 20 13:57:21 volumiorp volumio[3919]: info: Plugin folders cleanup completed Apr 20 13:57:21 volumiorp volumio[3919]: info: ------------------------------------------- Apr 20 13:57:21 volumiorp volumio[3919]: info: ----- Core plugins startup ---- Apr 20 13:57:21 volumiorp volumio[3919]: info: ------------------------------------------- Apr 20 13:57:21 volumiorp volumio[3919]: info: Loading plugins from folder /volumio/app/plugins/ Apr 20 13:57:21 volumiorp volumio[3919]: info: Adding plugin upnp to MyMusic Plugins Apr 20 13:57:21 volumiorp volumio[3919]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 20 13:57:21 volumiorp volumio[3919]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 20 13:57:21 volumiorp volumio[3919]: info: Loading plugins from folder /data/plugins/ Apr 20 13:57:21 volumiorp volumio[3919]: info: Loading plugin "system"... Apr 20 13:57:21 volumiorp ifplugd(eth0)[772]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Apr 20 13:57:21 volumiorp volumio[3919]: info: Loading plugin "appearance"... Apr 20 13:57:22 volumiorp dhcpcd[3966]: DUID 00:01:00:01:2a:5e:eb:67:b8:27:eb:86:4e:b3 Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: DUID 00:01:00:01:2a:5e:eb:67:b8:27:eb:86:4e:b3 Apr 20 13:57:22 volumiorp dhcpcd[3966]: eth0: IAID eb:86:4e:b3 Apr 20 13:57:22 volumiorp dhcpcd[3966]: eth0: adding address fe80::ba27:ebff:fe86:4eb3 Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: eth0: IAID eb:86:4e:b3 Apr 20 13:57:22 volumiorp dhcpcd[3966]: ipv6_addaddr1: Permission denied Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: eth0: adding address fe80::ba27:ebff:fe86:4eb3 Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: ipv6_addaddr1: Permission denied Apr 20 13:57:22 volumiorp dhcpcd[3966]: eth0: soliciting an IPv6 router Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: eth0: soliciting an IPv6 router Apr 20 13:57:22 volumiorp dhcpcd[3966]: eth0: rebinding lease of 192.168.0.4 Apr 20 13:57:22 volumiorp ifplugd(eth0)[772]: client: eth0: rebinding lease of 192.168.0.4 Apr 20 13:57:23 volumiorp volumio[3919]: info: Loading plugin "network"... Apr 20 13:57:23 volumiorp volumio[3919]: info: Refreshing Cached IP Addresses Apr 20 13:57:23 volumiorp sudo[3972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 20 13:57:23 volumiorp sudo[3972]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:23 volumiorp sudo[3972]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:23 volumiorp sudo[3974]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 20 13:57:23 volumiorp volumio[3919]: info: Loading plugin "services"... Apr 20 13:57:23 volumiorp sudo[3974]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:23 volumiorp volumio[3919]: info: Loading plugin "alsa_controller"... Apr 20 13:57:23 volumiorp sudo[3974]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:24 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "wizard"... Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "networkfs"... Apr 20 13:57:24 volumiorp volumio[3919]: info: Starting Udev Watcher for removable devices Apr 20 13:57:24 volumiorp volumio[3919]: info: Ignoring mount for partition: boot Apr 20 13:57:24 volumiorp volumio[3919]: info: Ignoring mount for partition: volumio Apr 20 13:57:24 volumiorp volumio[3919]: info: Ignoring mount for partition: volumio_data Apr 20 13:57:24 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "volumio_command_line_client"... Apr 20 13:57:24 volumiorp volumio[3919]: info: Plugin upnp is not enabled Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "my_music"... Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "mpd"... Apr 20 13:57:24 volumiorp volumio[3919]: info: Plugin upnp_browser is not enabled Apr 20 13:57:24 volumiorp volumio[3919]: info: Loading plugin "alarm-clock"... Apr 20 13:57:25 volumiorp volumio[3919]: info: Plugin airplay_emulation is not enabled Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "last_100"... Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "webradio"... Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "i2s_dacs"... Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "volumiodiscovery"... Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** For more information see Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:25 volumiorp volumio[3919]: *** WARNING *** For more information see Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** For more information see Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 20 13:57:25 volumiorp node[3919]: *** WARNING *** For more information see Apr 20 13:57:25 volumiorp volumio[3919]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 20 13:57:25 volumiorp volumio[3919]: info: Discovery: Started advertising with name: VolumioRP Apr 20 13:57:25 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "lmsde"... Apr 20 13:57:25 volumiorp volumio[3919]: info: Loading plugin "squeezelite_mc"... Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: NAK: from 192.168.0.1 Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: NAK: from 192.168.0.1 Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: soliciting a DHCP lease Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: soliciting a DHCP lease Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: offered 192.168.0.7 from 192.168.0.1 Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: offered 192.168.0.7 from 192.168.0.1 Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: probing address 192.168.0.7/24 Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: probing address 192.168.0.7/24 Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: hardware address 00:11:32:ac:03:ce claims 192.168.0.7 Apr 20 13:57:26 volumiorp dhcpcd[3966]: eth0: DAD detected 192.168.0.7 Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: hardware address 00:11:32:ac:03:ce claims 192.168.0.7 Apr 20 13:57:26 volumiorp ifplugd(eth0)[772]: client: eth0: DAD detected 192.168.0.7 Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "outputs"... Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "albumart"... Apr 20 13:57:27 volumiorp volumio[3919]: info: Plugin example_plugin is not enabled Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "inputs"... Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "updater_comm"... Apr 20 13:57:27 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:27] [connect] Successful connection Apr 20 13:57:27 volumiorp volumio[3919]: info: Plugin mpdemulation is not enabled Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "rest_api"... Apr 20 13:57:27 volumiorp dhcpcd[3966]: eth0: soliciting a DHCP lease Apr 20 13:57:27 volumiorp ifplugd(eth0)[772]: client: eth0: soliciting a DHCP lease Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading plugin "websocket"... Apr 20 13:57:27 volumiorp volumio[3919]: info: Starting Socket.io Server version 2.3.0 Apr 20 13:57:27 volumiorp volumio[3919]: info: Loading i18n strings for locale ko Apr 20 13:57:27 volumiorp volumio[3919]: Updating browse sources language Apr 20 13:57:27 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:27 volumiorp dhcpcd[3966]: eth0: offered 192.168.0.14 from 192.168.0.1 Apr 20 13:57:27 volumiorp ifplugd(eth0)[772]: client: eth0: offered 192.168.0.14 from 192.168.0.1 Apr 20 13:57:28 volumiorp volumio[3919]: Forking 3 albumart workers Apr 20 13:57:28 volumiorp dhcpcd[3966]: eth0: probing address 192.168.0.14/24 Apr 20 13:57:28 volumiorp ifplugd(eth0)[772]: client: eth0: probing address 192.168.0.14/24 Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::initPlayerControls Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: Express server listening on port 3000 Apr 20 13:57:28 volumiorp volumio[3919]: [Metrics] WebUI: 8s 854.17ms Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::resetVolumioState Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::getcurrentVolume Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:28 volumiorp volumio[3919]: info: Volumio Network Manager: Network status updated: 0 Apr 20 13:57:28 volumiorp volumio-remote-updater[503]: [2025-04-20 13:57:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745125047 101 Apr 20 13:57:28 volumiorp volumio[3919]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:28 volumiorp volumio[3919]: info: Reloading queue from file Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::setRepeat null single undefined Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::pushState Apr 20 13:57:28 volumiorp volumio[3919]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::setRandom null Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::pushState Apr 20 13:57:28 volumiorp volumio[3919]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:28 volumiorp volumio[3919]: info: Setting Device type: Raspberry PI Apr 20 13:57:28 volumiorp volumio[3919]: info: Completed loading Core Plugins Apr 20 13:57:28 volumiorp volumio[3919]: info: Preparing to generate the ALSA configuration file Apr 20 13:57:28 volumiorp volumio[3919]: info: VolumeController:: Volume=86 Mute =false Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::pushState Apr 20 13:57:28 volumiorp volumio[3919]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreStateMachine::updateTrackBlock Apr 20 13:57:28 volumiorp volumio[3919]: info: CorePlayQueue::getTrackBlock Apr 20 13:57:28 volumiorp volumio[3919]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:28 volumiorp volumio[3919]: info: Asound.conf file unchanged, so no further update is needed Apr 20 13:57:28 volumiorp volumio[3919]: info: Output device has changed, restarting MPD Apr 20 13:57:29 volumiorp sudo[4049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 13:57:29 volumiorp sudo[4049]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:29 volumiorp sudo[4049]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:29 volumiorp volumio[3919]: info: ___________ START PLUGINS ___________ Apr 20 13:57:29 volumiorp volumio[3919]: info: ControllerMpd::onStart: Initializing MPD Apr 20 13:57:29 volumiorp volumio[3919]: info: Creating MPD Configuration file Apr 20 13:57:29 volumiorp sudo[4052]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 13:57:29 volumiorp sudo[4052]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 13:57:29 volumiorp volumio[3919]: info: [1745125049162] CoreMusicLibrary::Adding element Last_100 Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 20 13:57:29 volumiorp volumio[3919]: info: [1745125049176] CoreMusicLibrary::Adding element Webradio Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 20 13:57:29 volumiorp sudo[4054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 20 13:57:29 volumiorp sudo[4054]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:29 volumiorp volumio[3919]: info: Initializing BBC Radios Apr 20 13:57:29 volumiorp sudo[4054]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:29 volumiorp systemd[1]: Stopping Music Player Daemon... Apr 20 13:57:29 volumiorp sudo[4057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 20 13:57:29 volumiorp sudo[4057]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:29 volumiorp systemd[1]: mpd.service: Succeeded. Apr 20 13:57:29 volumiorp systemd[1]: Stopped Music Player Daemon. Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 20 13:57:29 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:29 volumiorp systemd[1]: Starting Music Player Daemon... Apr 20 13:57:29 volumiorp volumio[3919]: info: [lmsde] Executing script: /opt/lmsde/lms.sh start Apr 20 13:57:29 volumiorp volumio[3919]: info: [squeezelite_mc] Starting proxy server... Apr 20 13:57:29 volumiorp volumio[3919]: info: Volumio Calling Home Apr 20 13:57:29 volumiorp sudo[4062]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 20 13:57:29 volumiorp sudo[4062]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:29 volumiorp sudo[4062]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:29 volumiorp sudo[4073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/opt/lmsde/lms.sh start Apr 20 13:57:29 volumiorp sudo[4073]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 20 13:57:30 volumiorp volumio[3919]: info: [squeezelite_mc] Proxy server started on port 36205 Apr 20 13:57:30 volumiorp volumio[3919]: Starting albumart workers Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::volumioRetrievevolume Apr 20 13:57:30 volumiorp volumio[3919]: Starting albumart workers Apr 20 13:57:30 volumiorp volumio[3919]: info: Discovery: adding 2891122c-60ff-48be-84d8-7957b3a3680b Apr 20 13:57:30 volumiorp volumio[3919]: info: Discovery: Found device VolumioRP Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::volumioGetState Apr 20 13:57:30 volumiorp volumio[3919]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:30 volumiorp volumio[3919]: Starting albumart workers Apr 20 13:57:30 volumiorp volumio[3919]: info: MPD Permissions set Apr 20 13:57:30 volumiorp volumio[3919]: info: MPD Permissions set Apr 20 13:57:30 volumiorp volumio[3919]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:30 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: VolumeController:: Volume=86 Mute =false Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreStateMachine::pushState Apr 20 13:57:31 volumiorp volumio[3919]: info: CorePlayQueue::getTrack 0 Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::volumioPushState Apr 20 13:57:31 volumiorp volumio[3919]: info: [squeezelite_mc] Server discovery started Apr 20 13:57:31 volumiorp volumio[3919]: info: [squeezelite_mc] Player finder started Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 20 13:57:31 volumiorp volumio[3919]: info: [squeezelite_mc] Executing aplay -D hw:1 --nonblock -f MPEG /dev/zero 2>&1 || true Apr 20 13:57:31 volumiorp volumio[3919]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:57:31 volumiorp volumio[3919]: Error: send ENETUNREACH 255.255.255.255:3483 Apr 20 13:57:31 volumiorp volumio[3919]: at doSend (dgram.js:692:16) Apr 20 13:57:31 volumiorp volumio[3919]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Apr 20 13:57:31 volumiorp volumio[3919]: at afterDns (dgram.js:638:5) Apr 20 13:57:31 volumiorp volumio[3919]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Apr 20 13:57:31 volumiorp volumio[3919]: errno: -101, Apr 20 13:57:31 volumiorp volumio[3919]: code: 'ENETUNREACH', Apr 20 13:57:31 volumiorp volumio[3919]: syscall: 'send', Apr 20 13:57:31 volumiorp volumio[3919]: address: '255.255.255.255', Apr 20 13:57:31 volumiorp volumio[3919]: port: 3483 Apr 20 13:57:31 volumiorp volumio[3919]: } Apr 20 13:57:31 volumiorp volumio[3919]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 20 13:57:32 volumiorp ifplugd(eth0)[772]: client: eth0: leased 192.168.0.14 for 7200 seconds Apr 20 13:57:32 volumiorp dhcpcd[3966]: eth0: leased 192.168.0.14 for 7200 seconds Apr 20 13:57:32 volumiorp avahi-daemon[512]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.14. Apr 20 13:57:32 volumiorp avahi-daemon[512]: New relevant interface eth0.IPv4 for mDNS. Apr 20 13:57:32 volumiorp dhcpcd[3966]: eth0: adding route to 192.168.0.0/24 Apr 20 13:57:32 volumiorp dhcpcd[3966]: eth0: adding default route via 192.168.0.1 Apr 20 13:57:32 volumiorp avahi-daemon[512]: Registering new address record for 192.168.0.14 on eth0.IPv4. Apr 20 13:57:33 volumiorp ifplugd(eth0)[772]: client: eth0: adding route to 192.168.0.0/24 Apr 20 13:57:33 volumiorp ifplugd(eth0)[772]: client: eth0: adding default route via 192.168.0.1 Apr 20 13:57:33 volumiorp dhcpcd[3966]: forked to background, child pid 4153 Apr 20 13:57:33 volumiorp ifplugd(eth0)[772]: client: forked to background, child pid 4153 Apr 20 13:57:33 volumiorp mpd[4078]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 20 13:57:33 volumiorp mpd[4078]: output: No 'audio_output' defined in config file Apr 20 13:57:33 volumiorp mpd[4078]: output: Successfully detected a sndio audio device Apr 20 13:57:33 volumiorp mpd[4078]: zeroconf: No global port, disabling zeroconf Apr 20 13:57:33 volumiorp sudo[4052]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:33 volumiorp systemd[1]: Started Music Player Daemon. Apr 20 13:57:33 volumiorp sudo[4057]: pam_unix(sudo:session): session closed for user root Apr 20 13:57:33 volumiorp sudo[4177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-20 13:56 Apr 20 13:57:33 volumiorp sudo[4177]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"