-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-09-04 06:11:23 UTC. -- Sep 04 06:10:27 volumiorp dhcpcd[690]: eth0: carrier lost Sep 04 06:10:27 volumiorp avahi-daemon[456]: Withdrawing address record for 192.168.0.4 on eth0. Sep 04 06:10:27 volumiorp avahi-daemon[456]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.4. Sep 04 06:10:27 volumiorp dhcpcd[690]: eth0: deleting route to 192.168.0.0/24 Sep 04 06:10:27 volumiorp dhcpcd[690]: eth0: deleting default route via 192.168.0.1 Sep 04 06:10:27 volumiorp avahi-daemon[456]: Interface eth0.IPv4 no longer relevant for mDNS. Sep 04 06:10:27 volumiorp volumio[796]: info: mDNS: A device disapperared from network Sep 04 06:10:27 volumiorp ifplugd(eth0)[636]: Link beat lost. Sep 04 06:10:29 volumiorp ntpd[645]: Deleting interface #3 eth0, 192.168.0.4#123, interface stats: received=5110, sent=5169, dropped=0, active_time=846180 secs Sep 04 06:10:29 volumiorp ntpd[645]: 175.195.167.194 local addr 192.168.0.4 -> Sep 04 06:10:29 volumiorp ntpd[645]: 193.123.243.2 local addr 192.168.0.4 -> Sep 04 06:10:29 volumiorp ntpd[645]: 106.247.248.106 local addr 192.168.0.4 -> Sep 04 06:10:29 volumiorp ntpd[645]: 194.0.5.123 local addr 192.168.0.4 -> Sep 04 06:10:29 volumiorp ntpd[645]: 121.174.142.81 local addr 192.168.0.4 -> Sep 04 06:10:29 volumiorp ntpd[645]: 175.193.3.234 local addr 192.168.0.4 -> Sep 04 06:10:37 volumiorp ifplugd(eth0)[636]: Executing '/etc/ifplugd/ifplugd.action eth0 down'. Sep 04 06:10:37 volumiorp dhcpcd[29255]: sending signal ALRM to pid 690 Sep 04 06:10:37 volumiorp dhcpcd[29255]: waiting for pid 690 to exit Sep 04 06:10:37 volumiorp dhcpcd[690]: received SIGALRM, releasing Sep 04 06:10:37 volumiorp dhcpcd[690]: eth0: removing interface Sep 04 06:10:37 volumiorp ifplugd(eth0)[636]: client: sending signal ALRM to pid 690 Sep 04 06:10:37 volumiorp ifplugd(eth0)[636]: client: waiting for pid 690 to exit Sep 04 06:10:37 volumiorp dhcpcd[690]: dhcpcd exited Sep 04 06:10:37 volumiorp ifplugd(eth0)[636]: Program executed successfully. Sep 04 06:10:37 volumiorp kernel: 8021q: adding VLAN 0 to HW filter on device eth0 Sep 04 06:10:56 volumiorp volumio[796]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 06:10:56 volumiorp volumio[796]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 04 06:10:56 volumiorp volumio[796]: at doSend (dgram.js:692:16) Sep 04 06:10:56 volumiorp volumio[796]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 04 06:10:56 volumiorp volumio[796]: at afterDns (dgram.js:638:5) Sep 04 06:10:56 volumiorp volumio[796]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 04 06:10:56 volumiorp volumio[796]: errno: -101, Sep 04 06:10:56 volumiorp volumio[796]: code: 'ENETUNREACH', Sep 04 06:10:56 volumiorp volumio[796]: syscall: 'send', Sep 04 06:10:56 volumiorp volumio[796]: address: '255.255.255.255', Sep 04 06:10:56 volumiorp volumio[796]: port: 3483 Sep 04 06:10:56 volumiorp volumio[796]: } Sep 04 06:10:56 volumiorp volumio[796]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 06:10:56 volumiorp ifplugd(eth0)[636]: Link beat detected. Sep 04 06:10:56 volumiorp ifplugd(eth0)[636]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Sep 04 06:10:56 volumiorp ifplugd(eth0)[636]: client: eth0: waiting for carrier Sep 04 06:10:56 volumiorp dhcpcd[29307]: eth0: waiting for carrier Sep 04 06:10:56 volumiorp sudo[29320]: volumio : unable to resolve host volumiorp Sep 04 06:10:56 volumiorp sudo[29320]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:10:56 volumiorp sudo[29320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 06:09 Sep 04 06:10:56 volumiorp sudo[29320]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:10:56 volumiorp sudo[29320]: pam_unix(sudo:session): session closed for user root Sep 04 06:10:57 volumiorp dhcpcd[29307]: eth0: carrier acquired Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: eth0: carrier acquired Sep 04 06:10:57 volumiorp dhcpcd[29307]: DUID 00:01:00:01:2a:5e:eb:66:b8:27:eb:86:4e:b3 Sep 04 06:10:57 volumiorp dhcpcd[29307]: eth0: IAID eb:86:4e:b3 Sep 04 06:10:57 volumiorp dhcpcd[29307]: eth0: adding address fe80::ba27:ebff:fe86:4eb3 Sep 04 06:10:57 volumiorp dhcpcd[29307]: ipv6_addaddr1: Permission denied Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: DUID 00:01:00:01:2a:5e:eb:66:b8:27:eb:86:4e:b3 Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: eth0: IAID eb:86:4e:b3 Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: eth0: adding address fe80::ba27:ebff:fe86:4eb3 Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: ipv6_addaddr1: Permission denied Sep 04 06:10:57 volumiorp volumio[796]: sudo: unable to resolve host volumiorp: System error Sep 04 06:10:57 volumiorp volumio-remote-updater[461]: [2024-09-04 06:10:57] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Sep 04 06:10:57 volumiorp volumio-remote-updater[461]: [2024-09-04 06:10:57] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Sep 04 06:10:57 volumiorp systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Sep 04 06:10:57 volumiorp systemd[1]: volumio.service: Failed with result 'exit-code'. Sep 04 06:10:57 volumiorp systemd[1]: Started dynamicswap service. Sep 04 06:10:57 volumiorp systemd[1]: dynamicswap.service: Succeeded. Sep 04 06:10:57 volumiorp dhcpcd[29307]: eth0: rebinding lease of 192.168.0.4 Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: eth0: rebinding lease of 192.168.0.4 Sep 04 06:10:57 volumiorp systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Sep 04 06:10:57 volumiorp systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Sep 04 06:10:57 volumiorp systemd[1]: Started dynamicswap service. Sep 04 06:10:57 volumiorp systemd[1]: Stopped Volumio Backend Module. Sep 04 06:10:57 volumiorp systemd[1]: Started Volumio Backend Module. Sep 04 06:10:57 volumiorp systemd[1]: dynamicswap.service: Succeeded. Sep 04 06:10:57 volumiorp dhcpcd[29307]: eth0: soliciting an IPv6 router Sep 04 06:10:57 volumiorp ifplugd(eth0)[636]: client: eth0: soliciting an IPv6 router Sep 04 06:10:59 volumiorp volumio[29336]: info: ------------------------------------------- Sep 04 06:10:59 volumiorp volumio[29336]: info: ----- Volumio3 ---- Sep 04 06:10:59 volumiorp volumio[29336]: info: ------------------------------------------- Sep 04 06:10:59 volumiorp volumio[29336]: info: ----- System startup ---- Sep 04 06:10:59 volumiorp volumio[29336]: info: ------------------------------------------- Sep 04 06:10:59 volumiorp volumio[29336]: info: MYVOLUMIO Environment detected Sep 04 06:10:59 volumiorp volumio[29336]: info: Plugin folders cleanup Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning into folder /volumio/app/plugins/ Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category audio_interface Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category miscellanea Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category music_service Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category plugins.json Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category system_controller Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category user_interface Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning into folder /data/plugins/ Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category music_service Sep 04 06:10:59 volumiorp volumio[29336]: info: Scanning category system_hardware Sep 04 06:10:59 volumiorp volumio[29336]: info: Plugin folders cleanup completed Sep 04 06:10:59 volumiorp volumio[29336]: info: ------------------------------------------- Sep 04 06:10:59 volumiorp volumio[29336]: info: ----- Core plugins startup ---- Sep 04 06:10:59 volumiorp volumio[29336]: info: ------------------------------------------- Sep 04 06:10:59 volumiorp volumio[29336]: info: Loading plugins from folder /volumio/app/plugins/ Sep 04 06:10:59 volumiorp volumio[29336]: info: Adding plugin upnp to MyMusic Plugins Sep 04 06:10:59 volumiorp volumio[29336]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 04 06:10:59 volumiorp volumio[29336]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 04 06:10:59 volumiorp volumio[29336]: info: Loading plugins from folder /data/plugins/ Sep 04 06:10:59 volumiorp volumio[29336]: info: Loading plugin "system"... Sep 04 06:10:59 volumiorp volumio[29336]: info: Loading plugin "appearance"... Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "network"... Sep 04 06:11:00 volumiorp volumio[29336]: info: Refreshing Cached IP Addresses Sep 04 06:11:00 volumiorp sudo[29359]: volumio : unable to resolve host volumiorp Sep 04 06:11:00 volumiorp sudo[29359]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:00 volumiorp sudo[29359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 04 06:11:00 volumiorp sudo[29359]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "services"... Sep 04 06:11:00 volumiorp sudo[29359]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "alsa_controller"... Sep 04 06:11:00 volumiorp sudo[29361]: volumio : unable to resolve host volumiorp Sep 04 06:11:00 volumiorp sudo[29361]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:00 volumiorp sudo[29361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 04 06:11:00 volumiorp sudo[29361]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:00 volumiorp sudo[29361]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "wizard"... Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "volumio_command_line_client"... Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "upnp"... Sep 04 06:11:00 volumiorp volumio[29336]: info: [1725430260940] Starting Upmpd Daemon Sep 04 06:11:00 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "my_music"... Sep 04 06:11:00 volumiorp volumio[29336]: info: Loading plugin "mpd"... Sep 04 06:11:01 volumiorp volumio[29336]: info: Creating MPD Configuration file Sep 04 06:11:01 volumiorp sudo[29367]: volumio : unable to resolve host volumiorp Sep 04 06:11:01 volumiorp sudo[29367]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:01 volumiorp volumio[29336]: info: Loading plugin "upnp_browser"... Sep 04 06:11:01 volumiorp sudo[29367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 06:11:01 volumiorp sudo[29367]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:01 volumiorp sudo[29369]: volumio : unable to resolve host volumiorp Sep 04 06:11:01 volumiorp sudo[29369]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:01 volumiorp sudo[29367]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:01 volumiorp sudo[29369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 06:11:01 volumiorp sudo[29369]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:01 volumiorp systemd[1]: musicservicesshield.service: Succeeded. Sep 04 06:11:01 volumiorp systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 04 06:11:01 volumiorp systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 04 06:11:01 volumiorp systemd[1]: Stopping Music Player Daemon... Sep 04 06:11:01 volumiorp systemd[1]: mpd.service: Succeeded. Sep 04 06:11:01 volumiorp systemd[1]: Stopped Music Player Daemon. Sep 04 06:11:01 volumiorp systemd[1]: Starting Music Player Daemon... Sep 04 06:11:01 volumiorp volumio[29336]: info: Loading plugin "networkfs"... Sep 04 06:11:01 volumiorp volumio[29336]: info: Starting Udev Watcher for removable devices Sep 04 06:11:01 volumiorp sudo[29378]: volumio : unable to resolve host volumiorp Sep 04 06:11:01 volumiorp sudo[29378]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:01 volumiorp sudo[29378]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=apsum,password=Conrad02!!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.7/HomeDisk /mnt/NAS/NAS Sep 04 06:11:01 volumiorp sudo[29378]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:01 volumiorp sudo[29378]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:01 volumiorp kernel: CIFS: Attempting to mount //192.168.0.7/HomeDisk Sep 04 06:11:01 volumiorp kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 04 06:11:01 volumiorp kernel: CIFS: VFS: cifs_mount failed w/return code = -101 Sep 04 06:11:02 volumiorp volumio-remote-updater[461]: [2024-09-04 06:11:02] [connect] Successful connection Sep 04 06:11:02 volumiorp volumio[29336]: info: Ignoring mount for partition: boot Sep 04 06:11:02 volumiorp volumio[29336]: info: Ignoring mount for partition: volumio Sep 04 06:11:02 volumiorp volumio[29336]: info: Ignoring mount for partition: volumio_data Sep 04 06:11:02 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "alarm-clock"... Sep 04 06:11:02 volumiorp dhcpcd[29307]: eth0: probing for an IPv4LL address Sep 04 06:11:02 volumiorp ifplugd(eth0)[636]: client: eth0: probing for an IPv4LL address Sep 04 06:11:02 volumiorp dhcpcd[29307]: eth0: DHCP lease expired Sep 04 06:11:02 volumiorp ifplugd(eth0)[636]: client: eth0: DHCP lease expired Sep 04 06:11:02 volumiorp ifplugd(eth0)[636]: client: eth0: soliciting a DHCP lease Sep 04 06:11:02 volumiorp dhcpcd[29307]: eth0: soliciting a DHCP lease Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "airplay_emulation"... Sep 04 06:11:02 volumiorp volumio[29336]: info: Starting Shairport Sync Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "last_100"... Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "webradio"... Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "i2s_dacs"... Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "volumiodiscovery"... Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** For more information see Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 06:11:02 volumiorp volumio[29336]: *** WARNING *** For more information see Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** For more information see Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 04 06:11:02 volumiorp node[29336]: *** WARNING *** For more information see Sep 04 06:11:02 volumiorp volumio[29336]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 04 06:11:02 volumiorp volumio[29336]: info: Discovery: Started advertising with name: Volumio_RP Sep 04 06:11:02 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 04 06:11:02 volumiorp volumio[29336]: info: Loading plugin "squeezelite_mc"... Sep 04 06:11:02 volumiorp mpd[29374]: Sep 04 06:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 06:11:02 volumiorp mpd[29374]: Sep 04 06:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 04 06:11:02 volumiorp mpd[29374]: Sep 04 06:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 04 06:11:02 volumiorp systemd[1]: Started Music Player Daemon. Sep 04 06:11:02 volumiorp sudo[29369]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:02 volumiorp systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "outputs"... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "albumart"... Sep 04 06:11:03 volumiorp volumio[29396]: cset: --> shielding system active with Sep 04 06:11:03 volumiorp volumio[29396]: cset: "system" cpuset of CPUSPEC(1-3) with 78 tasks running Sep 04 06:11:03 volumiorp volumio[29396]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 04 06:11:03 volumiorp volumio[29336]: info: Plugin example_plugin is not enabled Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "inputs"... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "updater_comm"... Sep 04 06:11:03 volumiorp systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 04 06:11:03 volumiorp volumio[29336]: info: Plugin mpdemulation is not enabled Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "rest_api"... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "websocket"... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "lms"... Sep 04 06:11:03 volumiorp volumio[29336]: info: Loading plugin "personal_radio"... Sep 04 06:11:03 volumiorp volumio[29336]: Forking 3 albumart workers Sep 04 06:11:04 volumiorp volumio[29336]: info: ControllerPersonalRadio::constructor Sep 04 06:11:04 volumiorp volumio[29336]: info: Loading plugin "music_services_shield"... Sep 04 06:11:04 volumiorp volumio[29336]: info: Applying required configuration parameters for plugin music_services_shield Sep 04 06:11:04 volumiorp volumio[29336]: info: Loading i18n strings for locale ko Sep 04 06:11:04 volumiorp volumio[29336]: Updating browse sources language Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::initPlayerControls Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 06:11:04 volumiorp volumio[29336]: Express server listening on port 3000 Sep 04 06:11:04 volumiorp volumio[29336]: [Metrics] WebUI: 6s 338.10ms Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreStateMachine::resetVolumioState Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreStateMachine::getcurrentVolume Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreStateMachine::pushState Sep 04 06:11:04 volumiorp volumio[29336]: Starting albumart workers Sep 04 06:11:04 volumiorp volumio[29336]: info: CorePlayQueue::getTrack 0 Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 06:11:04 volumiorp volumio[29336]: info: CoreCommandRouter::volumioPushState Sep 04 06:11:05 volumiorp volumio[29336]: Starting albumart workers Sep 04 06:11:05 volumiorp volumio[29336]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Sep 04 06:11:05 volumiorp volumio[29336]: Starting albumart workers Sep 04 06:11:05 volumiorp volumio[29336]: info: MPD Permissions set Sep 04 06:11:05 volumiorp volumio-remote-updater[461]: [2024-09-04 06:11:05] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1725430262 101 Sep 04 06:11:05 volumiorp volumio[29336]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Sep 04 06:11:05 volumiorp volumio[29336]: info: MPD running with PID29374 Sep 04 06:11:05 volumiorp volumio[29336]: ,establishing connection Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 06:11:05 volumiorp volumio[29336]: info: Reloading queue from file Sep 04 06:11:05 volumiorp volumio[29336]: error: updateQueue error: null Sep 04 06:11:05 volumiorp volumio[29336]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 04 06:11:05 volumiorp volumio[29336]: info: Setting Device type: Raspberry PI Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreStateMachine::setRepeat null single undefined Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreStateMachine::pushState Sep 04 06:11:05 volumiorp volumio[29336]: info: CorePlayQueue::getTrack 0 Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::volumioPushState Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreStateMachine::setRandom null Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreStateMachine::pushState Sep 04 06:11:05 volumiorp volumio[29336]: info: CorePlayQueue::getTrack 0 Sep 04 06:11:05 volumiorp volumio[29336]: info: CoreCommandRouter::volumioPushState Sep 04 06:11:05 volumiorp volumio[29336]: info: Completed loading Core Plugins Sep 04 06:11:05 volumiorp volumio[29336]: info: Preparing to generate the ALSA configuration file Sep 04 06:11:05 volumiorp volumio[29336]: error: updateQueue error: null Sep 04 06:11:06 volumiorp volumio[29336]: info: Asound.conf file unchanged, so no further update is needed Sep 04 06:11:06 volumiorp volumio[29336]: info: Output device has changed, restarting MPD Sep 04 06:11:06 volumiorp sudo[29477]: volumio : unable to resolve host volumiorp Sep 04 06:11:06 volumiorp sudo[29477]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:06 volumiorp sudo[29477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 04 06:11:06 volumiorp volumio[29336]: info: Output device has changed, restarting Shairport Sync Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:06 volumiorp sudo[29477]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 06:11:06 volumiorp sudo[29477]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:06 volumiorp sudo[29479]: volumio : unable to resolve host volumiorp Sep 04 06:11:06 volumiorp sudo[29479]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:06 volumiorp sudo[29479]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 04 06:11:06 volumiorp sudo[29479]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:06 volumiorp systemd[1]: musicservicesshield.service: Succeeded. Sep 04 06:11:06 volumiorp systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Sep 04 06:11:06 volumiorp systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Sep 04 06:11:06 volumiorp systemd[1]: Stopping Music Player Daemon... Sep 04 06:11:06 volumiorp volumio[29336]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 06:11:06 volumiorp volumio[29336]: info: ___________ START PLUGINS ___________ Sep 04 06:11:06 volumiorp systemd[1]: mpd.service: Succeeded. Sep 04 06:11:06 volumiorp systemd[1]: Stopped Music Player Daemon. Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 06:11:06 volumiorp volumio[29336]: info: [1725430266520] CoreMusicLibrary::Adding element Media Servers Sep 04 06:11:06 volumiorp systemd[1]: Starting Music Player Daemon... Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 06:11:06 volumiorp volumio[29336]: Cannot find translation for sourceMedia Servers Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 06:11:06 volumiorp volumio[29336]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 04 06:11:06 volumiorp volumio[29336]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 06:11:06 volumiorp volumio[29336]: info: [1725430266661] CoreMusicLibrary::Adding element Last_100 Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 06:11:06 volumiorp volumio[29336]: Cannot find translation for sourceMedia Servers Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 06:11:06 volumiorp volumio[29336]: info: [1725430266665] CoreMusicLibrary::Adding element Webradio Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 06:11:06 volumiorp volumio[29336]: Cannot find translation for sourceMedia Servers Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 04 06:11:06 volumiorp volumio[29336]: info: [squeezelite_mc] Starting proxy server... Sep 04 06:11:06 volumiorp sudo[29494]: volumio : unable to resolve host volumiorp Sep 04 06:11:06 volumiorp sudo[29494]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:06 volumiorp sudo[29494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Sep 04 06:11:06 volumiorp sudo[29494]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:06 volumiorp systemd[1]: Stopping Logitech Media Server Daemon... Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 04 06:11:06 volumiorp volumio[29336]: info: [1725430266848] CoreMusicLibrary::Adding element 개인 라디오 Sep 04 06:11:06 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 04 06:11:06 volumiorp volumio[29336]: Cannot find translation for sourceMedia Servers Sep 04 06:11:06 volumiorp volumio[29336]: Cannot find translation for source개인 라디오 Sep 04 06:11:07 volumiorp volumio[29336]: info: Volumio Calling Home Sep 04 06:11:07 volumiorp systemd[1]: logitechmediaserver.service: Succeeded. Sep 04 06:11:07 volumiorp systemd[1]: Stopped Logitech Media Server Daemon. Sep 04 06:11:07 volumiorp systemd[1]: Starting Logitech Media Server Daemon... Sep 04 06:11:07 volumiorp sudo[29525]: volumio : unable to resolve host volumiorp Sep 04 06:11:07 volumiorp sudo[29525]: volumio : problem with defaults entries ; TTY=unknown ; PWD=/ ; USER=root ; Sep 04 06:11:07 volumiorp systemd[1]: Started Logitech Media Server Daemon. Sep 04 06:11:07 volumiorp sudo[29494]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:07 volumiorp volumio[29336]: info: [squeezelite_mc] Proxy server started on port 44377 Sep 04 06:11:07 volumiorp sudo[29525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/data/plugins/system_hardware/music_services_shield/moveallprocesses.sh Sep 04 06:11:07 volumiorp sudo[29525]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreCommandRouter::volumioRetrievevolume Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreStateMachine::pushState Sep 04 06:11:07 volumiorp volumio[29336]: info: CorePlayQueue::getTrack 0 Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreCommandRouter::volumioPushState Sep 04 06:11:07 volumiorp volumio[29336]: info: [squeezelite_mc] Server discovery started Sep 04 06:11:07 volumiorp volumio[29336]: info: [squeezelite_mc] Player finder started Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 04 06:11:07 volumiorp volumio[29336]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 04 06:11:07 volumiorp volumio[29336]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true Sep 04 06:11:07 volumiorp volumio[29336]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 06:11:07 volumiorp volumio[29336]: Error: send ENETUNREACH 255.255.255.255:3483 Sep 04 06:11:07 volumiorp volumio[29336]: at doSend (dgram.js:692:16) Sep 04 06:11:07 volumiorp volumio[29336]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Sep 04 06:11:07 volumiorp volumio[29336]: at afterDns (dgram.js:638:5) Sep 04 06:11:07 volumiorp volumio[29336]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Sep 04 06:11:07 volumiorp volumio[29336]: errno: -101, Sep 04 06:11:07 volumiorp volumio[29336]: code: 'ENETUNREACH', Sep 04 06:11:07 volumiorp volumio[29336]: syscall: 'send', Sep 04 06:11:07 volumiorp volumio[29336]: address: '255.255.255.255', Sep 04 06:11:07 volumiorp volumio[29336]: port: 3483 Sep 04 06:11:07 volumiorp volumio[29336]: } Sep 04 06:11:07 volumiorp volumio[29336]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 04 06:11:07 volumiorp ifplugd(eth0)[636]: client: eth0: using IPv4LL address 169.254.131.17 Sep 04 06:11:07 volumiorp dhcpcd[29307]: eth0: using IPv4LL address 169.254.131.17 Sep 04 06:11:07 volumiorp ifplugd(eth0)[636]: client: eth0: adding route to 169.254.0.0/16 Sep 04 06:11:07 volumiorp dhcpcd[29307]: eth0: adding route to 169.254.0.0/16 Sep 04 06:11:07 volumiorp ifplugd(eth0)[636]: client: eth0: adding default route Sep 04 06:11:07 volumiorp dhcpcd[29307]: eth0: adding default route Sep 04 06:11:07 volumiorp avahi-daemon[456]: Joining mDNS multicast group on interface eth0.IPv4 with address 169.254.131.17. Sep 04 06:11:07 volumiorp avahi-daemon[456]: New relevant interface eth0.IPv4 for mDNS. Sep 04 06:11:07 volumiorp avahi-daemon[456]: Registering new address record for 169.254.131.17 on eth0.IPv4. Sep 04 06:11:07 volumiorp dhcpcd[29307]: forked to background, child pid 29554 Sep 04 06:11:07 volumiorp ifplugd(eth0)[636]: client: forked to background, child pid 29554 Sep 04 06:11:07 volumiorp ifplugd(eth0)[636]: Program executed successfully. Sep 04 06:11:08 volumiorp mpd[29496]: Sep 04 06:11 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 04 06:11:08 volumiorp mpd[29496]: Sep 04 06:11 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Sep 04 06:11:08 volumiorp mpd[29496]: Sep 04 06:11 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Sep 04 06:11:08 volumiorp systemd[1]: Started Music Player Daemon. Sep 04 06:11:08 volumiorp sudo[29479]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:08 volumiorp systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Sep 04 06:11:08 volumiorp sudo[29525]: pam_unix(sudo:session): session closed for user root Sep 04 06:11:08 volumiorp volumio[29593]: cset: --> shielding system active with Sep 04 06:11:08 volumiorp volumio[29593]: cset: "system" cpuset of CPUSPEC(1-3) with 124 tasks running Sep 04 06:11:08 volumiorp volumio[29593]: cset: "user" cpuset of CPUSPEC(0) with 3 tasks running Sep 04 06:11:08 volumiorp systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Sep 04 06:11:09 volumiorp ntpd[645]: Listen normally on 4 eth0 169.254.131.17:123 Sep 04 06:11:09 volumiorp ntpd[645]: new interface(s) found: waking up resolver Sep 04 06:11:13 volumiorp dhcpcd[29554]: eth0: offered 192.168.0.4 from 192.168.0.1 Sep 04 06:11:13 volumiorp dhcpcd[29554]: eth0: probing address 192.168.0.4/24 Sep 04 06:11:18 volumiorp dhcpcd[29554]: eth0: leased 192.168.0.4 for 86400 seconds Sep 04 06:11:18 volumiorp avahi-daemon[456]: Registering new address record for 192.168.0.4 on eth0.IPv4. Sep 04 06:11:18 volumiorp dhcpcd[29554]: eth0: adding route to 192.168.0.0/24 Sep 04 06:11:18 volumiorp dhcpcd[29554]: eth0: changing default route via 192.168.0.1 Sep 04 06:11:18 volumiorp avahi-daemon[456]: Withdrawing address record for 169.254.131.17 on eth0. Sep 04 06:11:18 volumiorp dhcpcd[29554]: eth0: deleting route to 169.254.0.0/16 Sep 04 06:11:18 volumiorp avahi-daemon[456]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.131.17. Sep 04 06:11:18 volumiorp avahi-daemon[456]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.0.4. Sep 04 06:11:18 volumiorp dhcpcd[29554]: eth0: pid 29554 deleted default route via 192.168.0.1 Sep 04 06:11:20 volumiorp ntpd[645]: Listen normally on 5 eth0 192.168.0.4:123 Sep 04 06:11:20 volumiorp ntpd[645]: Deleting interface #4 eth0, 169.254.131.17#123, interface stats: received=0, sent=6, dropped=0, active_time=11 secs Sep 04 06:11:20 volumiorp ntpd[645]: 175.193.3.234 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: 121.174.142.81 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: 194.0.5.123 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: 106.247.248.106 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: 193.123.243.2 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: 175.195.167.194 local addr 169.254.131.17 -> Sep 04 06:11:20 volumiorp ntpd[645]: new interface(s) found: waking up resolver Sep 04 06:11:23 volumiorp sudo[29607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-04 06:10 Sep 04 06:11:23 volumiorp sudo[29607]: 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="1f061e035f7cb2799b32d3520aa8e55cd07df6f9" VOLUMIO_FE_VERSION="9971a09df9c3edfdedf2ecf438999be8aba9db68" VOLUMIO_FE3_VERSION="3974def988c7b2392ddc85f9b8cb4692d5e8fda9" VOLUMIO_BE_VERSION="b3c53a818b9b3d0e5c13c9d346c4d613c4ca426f" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 11 Jul 2022 04:03:33 PM CEST" VOLUMIO_VERSION="3.324" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5b2f59e6e90e57f326ca25c7ca839d52"