-- Logs begin at Thu 2019-02-14 19:11:59 JST, end at Wed 2025-05-21 01:06:13 JST. -- May 21 01:05:02 volumio volumio[961]: info: CoreCommandRouter::getUIConfigOnPlugin May 21 01:05:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 21 01:05:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork May 21 01:05:02 volumio sudo[2687]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 May 21 01:05:02 volumio sudo[2687]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:02 volumio sudo[2687]: pam_unix(sudo:session): session closed for user root May 21 01:05:02 volumio sudo[2692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 21 01:05:02 volumio sudo[2692]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:02 volumio sudo[2692]: pam_unix(sudo:session): session closed for user root May 21 01:05:02 volumio sudo[2700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 21 01:05:02 volumio sudo[2700]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:02 volumio sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 May 21 01:05:02 volumio sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:02 volumio sudo[2700]: pam_unix(sudo:session): session closed for user root May 21 01:05:02 volumio sudo[2705]: pam_unix(sudo:session): session closed for user root May 21 01:05:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache May 21 01:05:02 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks May 21 01:05:02 volumio sudo[2712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 01:05:02 volumio sudo[2712]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:03 volumio sudo[2712]: pam_unix(sudo:session): session closed for user root May 21 01:05:03 volumio sudo[2715]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:03 volumio sudo[2715]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:03 volumio sudo[2715]: pam_unix(sudo:session): session closed for user root May 21 01:05:03 volumio sudo[2719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 21 01:05:03 volumio sudo[2719]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:03 volumio sudo[2719]: pam_unix(sudo:session): session closed for user root May 21 01:05:21 volumio volumio[961]: info: CALLMETHOD: system_controller network saveDnsSettings [object Object] May 21 01:05:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: network , saveDnsSettings May 21 01:05:21 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 21 01:05:22 volumio volumio[961]: info: Discovery: Restarting Advertising due to device name change May 21 01:05:22 volumio sudo[2754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/unlink /etc/resolv.conf.tail May 21 01:05:22 volumio sudo[2754]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio sudo[2754]: pam_unix(sudo:session): session closed for user root May 21 01:05:22 volumio sudo[2756]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service May 21 01:05:22 volumio sudo[2756]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio volumio[961]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart May 21 01:05:22 volumio volumio[961]: info: Discovery: Restarting Advertising due to device name change May 21 01:05:22 volumio sudo[2759]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/ip addr flush dev eth0 May 21 01:05:22 volumio sudo[2759]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio systemd[1]: wireless.service: Killing process 846 (wpa_supplicant) with signal SIGKILL. May 21 01:05:22 volumio sudo[2759]: pam_unix(sudo:session): session closed for user root May 21 01:05:22 volumio systemd[1]: wireless.service: Killing process 956 (dhcpcd) with signal SIGKILL. May 21 01:05:22 volumio systemd[1]: Stopping Wireless Services... May 21 01:05:22 volumio sudo[2762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 down May 21 01:05:22 volumio sudo[2762]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio sudo[2762]: pam_unix(sudo:session): session closed for user root May 21 01:05:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 21 01:05:22 volumio sudo[2764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 up May 21 01:05:22 volumio sudo[2764]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio sudo[2764]: pam_unix(sudo:session): session closed for user root May 21 01:05:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: hardware isn't capable of remote wakeup May 21 01:05:22 volumio kernel: smsc95xx 1-1.1:1.0 eth0: Link is Down May 21 01:05:22 volumio systemd[1]: wireless.service: Succeeded. May 21 01:05:22 volumio systemd[1]: Stopped Wireless Services. May 21 01:05:22 volumio systemd[1]: Starting Wireless Services... May 21 01:05:22 volumio wireless.js[2768]: Cleaning previous... May 21 01:05:22 volumio sudo[2782]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 21 01:05:22 volumio sudo[2782]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:22 volumio avahi-daemon[624]: Withdrawing address record for 192.168.10.128 on wlan0. May 21 01:05:22 volumio avahi-daemon[624]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.128. May 21 01:05:22 volumio avahi-daemon[624]: Interface wlan0.IPv4 no longer relevant for mDNS. May 21 01:05:22 volumio sudo[2782]: pam_unix(sudo:session): session closed for user root May 21 01:05:22 volumio volumio[961]: info: Discovery: A device disappeared from network May 21 01:05:22 volumio volumio[961]: info: Discovery: Device volumio disappeared from network May 21 01:05:22 volumio sudo[2784]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 21 01:05:22 volumio sudo[2784]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:23 volumio sudo[2784]: pam_unix(sudo:session): session closed for user root May 21 01:05:23 volumio wireless.js[2768]: Stopped aP May 21 01:05:23 volumio sudo[2792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 21 01:05:23 volumio sudo[2792]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:23 volumio sudo[2792]: pam_unix(sudo:session): session closed for user root May 21 01:05:23 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 21 01:05:23 volumio sudo[2794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get May 21 01:05:23 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:23 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root May 21 01:05:23 volumio sudo[2801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 21 01:05:23 volumio sudo[2801]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:23 volumio sudo[2801]: pam_unix(sudo:session): session closed for user root May 21 01:05:23 volumio sudo[2803]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan May 21 01:05:23 volumio sudo[2803]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio sudo[2803]: pam_unix(sudo:session): session closed for user root May 21 01:05:24 volumio wireless.js[2768]: SETTING APPROPRIATE REG DOMAIN: GB May 21 01:05:24 volumio sudo[2808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up May 21 01:05:24 volumio sudo[2808]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio sudo[2808]: pam_unix(sudo:session): session closed for user root May 21 01:05:24 volumio sudo[2810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set GB May 21 01:05:24 volumio sudo[2810]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio sudo[2810]: pam_unix(sudo:session): session closed for user root May 21 01:05:24 volumio wireless.js[2768]: SUCCESSFULLY SET NEW REGDOMAIN: GB May 21 01:05:24 volumio wireless.js[2768]: Start wireless flow May 21 01:05:24 volumio wireless.js[2768]: Stopped hotspot (if there).. May 21 01:05:24 volumio sudo[2815]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 May 21 01:05:24 volumio sudo[2815]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio sudo[2815]: pam_unix(sudo:session): session closed for user root May 21 01:05:24 volumio sudo[2817]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down May 21 01:05:24 volumio sudo[2817]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio sudo[2817]: pam_unix(sudo:session): session closed for user root May 21 01:05:24 volumio wireless.js[2768]: DHCP IP May 21 01:05:24 volumio wireless.js[2768]: Start ap May 21 01:05:24 volumio wpa_supplicant[2819]: Successfully initialized wpa_supplicant May 21 01:05:24 volumio sudo[2820]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd May 21 01:05:24 volumio sudo[2820]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:24 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 21 01:05:24 volumio dhcpcd[2821]: main: control_open: Connection refused May 21 01:05:24 volumio dhcpcd[2821]: dev: loaded udev May 21 01:05:24 volumio dhcpcd-run-hooks[2847]: wlan0: starting wpa_supplicant May 21 01:05:25 volumio volumio[961]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 01:05:25 volumio volumio[961]: Error: send ENETUNREACH 255.255.255.255:3483 May 21 01:05:25 volumio volumio[961]: at doSend (dgram.js:692:16) May 21 01:05:25 volumio volumio[961]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) May 21 01:05:25 volumio volumio[961]: at afterDns (dgram.js:638:5) May 21 01:05:25 volumio volumio[961]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { May 21 01:05:25 volumio volumio[961]: errno: -101, May 21 01:05:25 volumio volumio[961]: code: 'ENETUNREACH', May 21 01:05:25 volumio volumio[961]: syscall: 'send', May 21 01:05:25 volumio volumio[961]: address: '255.255.255.255', May 21 01:05:25 volumio volumio[961]: port: 3483 May 21 01:05:25 volumio volumio[961]: } May 21 01:05:25 volumio volumio[961]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 01:05:25 volumio dhcpcd-run-hooks[2860]: wlan0: failed to start wpa_supplicant May 21 01:05:25 volumio dhcpcd-run-hooks[2861]: wlan0: Successfully initialized wpa_supplicant nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Match already configured nl80211: kernel reports: Ma May 21 01:05:25 volumio dhcpcd[2821]: wlan0: connected to Access Point `' May 21 01:05:25 volumio dhcpcd[2821]: no interfaces have a carrier May 21 01:05:25 volumio dhcpcd[2821]: forked to background, child pid 2878 May 21 01:05:25 volumio dhcpcd[2878]: eth0: waiting for carrier May 21 01:05:25 volumio dhcpcd[2878]: wlan0: waiting for carrier May 21 01:05:25 volumio sudo[2820]: pam_unix(sudo:session): session closed for user root May 21 01:05:25 volumio wpa_supplicant[2851]: wlan0: Trying to associate with SSID 'aterm-5ea727-g' May 21 01:05:25 volumio sudo[2880]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 01:04 May 21 01:05:25 volumio sudo[2880]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:25 volumio dhcpcd[2878]: wlan0: carrier acquired May 21 01:05:25 volumio wpa_supplicant[2851]: wlan0: Associated with 80:22:a7:df:08:5e May 21 01:05:25 volumio wpa_supplicant[2851]: wlan0: CTRL-EVENT-CONNECTED - Connection to 80:22:a7:df:08:5e completed [id=0 id_str=] May 21 01:05:25 volumio dhcpcd[2878]: wlan0: connected to Access Point `aterm-5ea727-g' May 21 01:05:25 volumio wpa_supplicant[2851]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 21 01:05:25 volumio wpa_supplicant[2851]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=JP May 21 01:05:25 volumio dhcpcd[2878]: DUID 00:01:00:01:2f:9c:a7:d6:b8:27:eb:fb:dd:df May 21 01:05:25 volumio dhcpcd[2878]: wlan0: IAID eb:fb:dd:df May 21 01:05:25 volumio dhcpcd[2878]: wlan0: adding address fe80::9ddc:ae3c:5da6:9e7b May 21 01:05:25 volumio dhcpcd[2878]: ipv6_addaddr1: Permission denied May 21 01:05:25 volumio dhcpcd[2878]: wlan0: carrier lost May 21 01:05:25 volumio sudo[2880]: pam_unix(sudo:session): session closed for user root May 21 01:05:25 volumio wireless.js[2768]: trying... May 21 01:05:25 volumio sudo[2895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:25 volumio dhcpcd[2878]: wlan0: carrier acquired May 21 01:05:25 volumio sudo[2895]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:25 volumio sudo[2895]: pam_unix(sudo:session): session closed for user root May 21 01:05:25 volumio dhcpcd[2878]: wlan0: IAID eb:fb:dd:df May 21 01:05:25 volumio volumio-remote-updater[627]: [2025-05-21 01:05:25] [error] handle_read_frame error: websocketpp.transport:7 (End of File) May 21 01:05:25 volumio volumio-remote-updater[627]: [2025-05-21 01:05:25] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] May 21 01:05:25 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE May 21 01:05:25 volumio sudo[2756]: pam_unix(sudo:session): session closed for user root May 21 01:05:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. May 21 01:05:26 volumio systemd[1]: Started dynamicswap service. May 21 01:05:26 volumio systemd[1]: dynamicswap.service: Succeeded. May 21 01:05:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. May 21 01:05:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. May 21 01:05:26 volumio systemd[1]: Stopped Volumio Backend Module. May 21 01:05:26 volumio systemd[1]: Started dynamicswap service. May 21 01:05:26 volumio systemd[1]: dynamicswap.service: Succeeded. May 21 01:05:26 volumio dhcpcd[2878]: wlan0: soliciting a DHCP lease May 21 01:05:26 volumio dhcpcd[2878]: wlan0: offered 192.168.10.128 from 192.168.10.1 May 21 01:05:26 volumio dhcpcd[2878]: wlan0: probing address 192.168.10.128/24 May 21 01:05:26 volumio dhcpcd[2878]: wlan0: soliciting an IPv6 router May 21 01:05:26 volumio wireless.js[2768]: trying... May 21 01:05:26 volumio sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:26 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:26 volumio sudo[2921]: pam_unix(sudo:session): session closed for user root May 21 01:05:27 volumio ntpd[766]: Deleting interface #3 wlan0, 192.168.10.128#123, interface stats: received=152, sent=162, dropped=1, active_time=526 secs May 21 01:05:27 volumio ntpd[766]: 23.81.40.154 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 133.130.121.141 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 162.159.200.123 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 167.179.119.205 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 108.160.132.224 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 162.159.200.1 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 45.77.20.103 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 18.180.64.47 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 160.16.113.133 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 194.0.5.123 local addr 192.168.10.128 -> May 21 01:05:27 volumio ntpd[766]: 129.250.35.251 local addr 192.168.10.128 -> May 21 01:05:27 volumio wireless.js[2768]: trying... May 21 01:05:27 volumio sudo[2924]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:27 volumio sudo[2924]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:27 volumio sudo[2924]: pam_unix(sudo:session): session closed for user root May 21 01:05:28 volumio wireless.js[2768]: trying... May 21 01:05:28 volumio sudo[2968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:28 volumio sudo[2968]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:28 volumio sudo[2968]: pam_unix(sudo:session): session closed for user root May 21 01:05:29 volumio wireless.js[2768]: trying... May 21 01:05:29 volumio sudo[2971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:29 volumio sudo[2971]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:29 volumio sudo[2971]: pam_unix(sudo:session): session closed for user root May 21 01:05:30 volumio wireless.js[2768]: trying... May 21 01:05:30 volumio sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:30 volumio sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:30 volumio sudo[2975]: pam_unix(sudo:session): session closed for user root May 21 01:05:30 volumio volumio-remote-updater[627]: [2025-05-21 01:05:30] [info] asio async_connect error: system:111 (Connection refused) May 21 01:05:30 volumio volumio-remote-updater[627]: [2025-05-21 01:05:30] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 21 01:05:30 volumio volumio-remote-updater[627]: [2025-05-21 01:05:30] [error] handle_connect error: Underlying Transport Error May 21 01:05:31 volumio dhcpcd[2878]: wlan0: leased 192.168.10.128 for 86400 seconds May 21 01:05:31 volumio avahi-daemon[624]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.10.128. May 21 01:05:31 volumio avahi-daemon[624]: New relevant interface wlan0.IPv4 for mDNS. May 21 01:05:31 volumio dhcpcd[2878]: wlan0: adding route to 192.168.10.0/24 May 21 01:05:31 volumio avahi-daemon[624]: Registering new address record for 192.168.10.128 on wlan0.IPv4. May 21 01:05:31 volumio dhcpcd[2878]: wlan0: adding default route via 192.168.10.1 May 21 01:05:31 volumio wireless.js[2768]: trying... May 21 01:05:31 volumio sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 21 01:05:31 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:31 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root May 21 01:05:31 volumio wireless.js[2768]: Connected to: ----aterm-5ea727-g May 21 01:05:31 volumio wireless.js[2768]: ---- May 21 01:05:31 volumio sudo[3000]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:31 volumio sudo[3000]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:31 volumio sudo[3000]: pam_unix(sudo:session): session closed for user root May 21 01:05:31 volumio wireless.js[2768]: ... joined AP, wlan0 IPv4 is 192.168.10.128, ipV6 is undefined May 21 01:05:31 volumio wireless.js[2768]: It's done! AP May 21 01:05:32 volumio systemd[1]: Started Wireless Services. May 21 01:05:32 volumio kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 21 01:05:32 volumio systemd[1]: Started Volumio Backend Module. May 21 01:05:32 volumio ntpd[766]: Listen normally on 4 wlan0 192.168.10.128:123 May 21 01:05:32 volumio ntpd[766]: new interface(s) found: waking up resolver May 21 01:05:33 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:33 volumio volumio[3005]: info: ----- Volumio3 ---- May 21 01:05:33 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:33 volumio volumio[3005]: info: ----- System startup ---- May 21 01:05:33 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:33 volumio ntpd[766]: 162.159.200.1 local addr 192.168.10.128 -> May 21 01:05:34 volumio volumio[3005]: info: MYVOLUMIO Environment detected May 21 01:05:34 volumio volumio[3005]: info: Plugin folders cleanup May 21 01:05:34 volumio volumio[3005]: info: Scanning into folder /volumio/app/plugins/ May 21 01:05:34 volumio volumio[3005]: info: Scanning category audio_interface May 21 01:05:34 volumio volumio[3005]: info: Scanning category miscellanea May 21 01:05:34 volumio volumio[3005]: info: Scanning category music_service May 21 01:05:34 volumio volumio[3005]: info: Scanning category plugins.json May 21 01:05:34 volumio volumio[3005]: info: Scanning category system_controller May 21 01:05:34 volumio volumio[3005]: info: Scanning category user_interface May 21 01:05:34 volumio volumio[3005]: info: Scanning into folder /data/plugins/ May 21 01:05:34 volumio volumio[3005]: info: Scanning category music_service May 21 01:05:34 volumio volumio[3005]: info: Scanning category user_interface May 21 01:05:34 volumio volumio[3005]: info: Plugin folders cleanup completed May 21 01:05:34 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:34 volumio volumio[3005]: info: ----- Core plugins startup ---- May 21 01:05:34 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:34 volumio volumio[3005]: info: Loading plugins from folder /volumio/app/plugins/ May 21 01:05:34 volumio volumio[3005]: info: Adding plugin upnp to MyMusic Plugins May 21 01:05:34 volumio volumio[3005]: info: Adding plugin airplay_emulation to MyMusic Plugins May 21 01:05:34 volumio volumio[3005]: info: Adding plugin upnp_browser to MyMusic Plugins May 21 01:05:34 volumio volumio[3005]: info: Loading plugins from folder /data/plugins/ May 21 01:05:34 volumio volumio[3005]: info: Loading plugin "system"... May 21 01:05:34 volumio volumio[3005]: info: Loading plugin "appearance"... May 21 01:05:35 volumio volumio[3005]: info: Loading plugin "network"... May 21 01:05:35 volumio volumio[3005]: info: Refreshing Cached IP Addresses May 21 01:05:35 volumio sudo[3030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 01:05:35 volumio sudo[3030]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:35 volumio sudo[3030]: pam_unix(sudo:session): session closed for user root May 21 01:05:35 volumio sudo[3032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:35 volumio sudo[3032]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:35 volumio sudo[3032]: pam_unix(sudo:session): session closed for user root May 21 01:05:35 volumio volumio[3005]: info: Loading plugin "services"... May 21 01:05:35 volumio volumio[3005]: info: Loading plugin "alsa_controller"... May 21 01:05:35 volumio sudo[3043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 21 01:05:35 volumio sudo[3043]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:35 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 01:05:35 volumio volumio[3005]: info: Loading plugin "wizard"... May 21 01:05:35 volumio volumio[3005]: info: Loading plugin "networkfs"... May 21 01:05:35 volumio volumio[3005]: info: Starting Udev Watcher for removable devices May 21 01:05:35 volumio volumio[3005]: info: Ignoring mount for partition: boot May 21 01:05:35 volumio volumio[3005]: info: Ignoring mount for partition: volumio May 21 01:05:35 volumio volumio[3005]: info: Ignoring mount for partition: volumio_data May 21 01:05:35 volumio volumio[3005]: info: Mounting Device 7df37471-0494-4086-b393-2d77bb0b2127 May 21 01:05:35 volumio sudo[3061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7df37471-0494-4086-b393-2d77bb0b2127 -o noatime May 21 01:05:35 volumio sudo[3061]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:36 volumio sudo[3043]: pam_unix(sudo:session): session closed for user root May 21 01:05:38 volumio volumio-remote-updater[627]: [2025-05-21 01:05:38] [connect] Successful connection May 21 01:05:43 volumio sudo[3061]: pam_unix(sudo:session): session closed for user root May 21 01:05:43 volumio volumio[3005]: mount: /media/7df37471-0494-4086-b393-2d77bb0b2127: /dev/sda1 already mounted on /media/7df37471-0494-4086-b393-2d77bb0b2127. May 21 01:05:43 volumio volumio[3005]: error: Failed to mount 7df37471-0494-4086-b393-2d77bb0b2127: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7df37471-0494-4086-b393-2d77bb0b2127" -o noatime May 21 01:05:43 volumio volumio[3005]: mount: /media/7df37471-0494-4086-b393-2d77bb0b2127: /dev/sda1 already mounted on /media/7df37471-0494-4086-b393-2d77bb0b2127. May 21 01:05:43 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 21 01:05:43 volumio volumio[3005]: info: Loading plugin "volumio_command_line_client"... May 21 01:05:43 volumio volumio[3005]: info: Loading plugin "upnp"... May 21 01:05:43 volumio volumio[3005]: info: [1747757143436] Starting Upmpd Daemon May 21 01:05:43 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 21 01:05:43 volumio volumio[3005]: info: Loading plugin "my_music"... May 21 01:05:43 volumio volumio[3005]: info: Loading plugin "mpd"... May 21 01:05:43 volumio volumio[3005]: info: Loading plugin "upnp_browser"... May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "alarm-clock"... May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "airplay_emulation"... May 21 01:05:44 volumio volumio[3005]: info: Starting Shairport Sync May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "last_100"... May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "webradio"... May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "i2s_dacs"... May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "volumiodiscovery"... May 21 01:05:44 volumio volumio[3005]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 21 01:05:44 volumio volumio[3005]: *** WARNING *** Please fix your application to use the native API of Avahi! May 21 01:05:44 volumio volumio[3005]: *** WARNING *** For more information see May 21 01:05:44 volumio volumio[3005]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 21 01:05:44 volumio volumio[3005]: *** WARNING *** Please fix your application to use the native API of Avahi! May 21 01:05:44 volumio volumio[3005]: *** WARNING *** For more information see May 21 01:05:44 volumio node[3005]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 21 01:05:44 volumio node[3005]: *** WARNING *** Please fix your application to use the native API of Avahi! May 21 01:05:44 volumio node[3005]: *** WARNING *** For more information see May 21 01:05:44 volumio node[3005]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 21 01:05:44 volumio node[3005]: *** WARNING *** Please fix your application to use the native API of Avahi! May 21 01:05:44 volumio node[3005]: *** WARNING *** For more information see May 21 01:05:44 volumio volumio[3005]: info: Applying required configuration parameters for plugin volumiodiscovery May 21 01:05:44 volumio volumio[3005]: info: Discovery: Started advertising with name: Volumio May 21 01:05:44 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 21 01:05:44 volumio volumio[3005]: info: Loading plugin "squeezelite_mc"... May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "outputs"... May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "albumart"... May 21 01:05:45 volumio volumio[3005]: info: Plugin example_plugin is not enabled May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "inputs"... May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "updater_comm"... May 21 01:05:45 volumio volumio[3005]: info: Plugin mpdemulation is not enabled May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "rest_api"... May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "websocket"... May 21 01:05:45 volumio volumio[3005]: info: Starting Socket.io Server version 2.3.0 May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "minidlna"... May 21 01:05:45 volumio volumio[3005]: info: Applying required configuration parameters for plugin minidlna May 21 01:05:45 volumio volumio[3005]: Forking 3 albumart workers May 21 01:05:45 volumio volumio[3005]: info: Loading plugin "lastfm"... May 21 01:05:46 volumio volumio[3005]: info: Loading i18n strings for locale ja May 21 01:05:46 volumio volumio[3005]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 21 01:05:46 volumio volumio[3005]: Updating browse sources language May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::initPlayerControls May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 01:05:46 volumio volumio[3005]: Express server listening on port 3000 May 21 01:05:46 volumio volumio[3005]: [Metrics] WebUI: 13s 616.99ms May 21 01:05:46 volumio volumio[3005]: Starting albumart workers May 21 01:05:46 volumio volumio[3005]: Starting albumart workers May 21 01:05:46 volumio volumio[3005]: info: CoreStateMachine::resetVolumioState May 21 01:05:46 volumio volumio[3005]: info: CoreStateMachine::getcurrentVolume May 21 01:05:46 volumio volumio[3005]: info: CoreCommandRouter::volumioRetrievevolume May 21 01:05:46 volumio volumio[3005]: Starting albumart workers May 21 01:05:46 volumio volumio[3005]: info: Volumio Network Manager: Network status updated: 2 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::pushState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioPushState May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::updateTrackBlock May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrackBlock May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioRetrievevolume May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:47 volumio volumio[3005]: info: Reloading queue from file May 21 01:05:47 volumio volumio[3005]: info: Setting Device type: Raspberry PI May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::setRepeat null single undefined May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::pushState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioPushState May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::setRandom null May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::pushState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioPushState May 21 01:05:47 volumio volumio[3005]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb May 21 01:05:47 volumio volumio[3005]: info: USB Boot Capable - System SBC Revision found in cpuinfo: a32082 May 21 01:05:47 volumio volumio[3005]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI May 21 01:05:47 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 May 21 01:05:47 volumio volumio[3005]: info: Completed loading Core Plugins May 21 01:05:47 volumio volumio[3005]: info: Preparing to generate the ALSA configuration file May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::pushState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioPushState May 21 01:05:47 volumio volumio[3005]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf May 21 01:05:47 volumio volumio[3005]: info: Reading ALSA contributions from plugins. May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetVisibleSources May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetQueue May 21 01:05:47 volumio volumio[3005]: info: CoreStateMachine::getQueue May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getQueue May 21 01:05:47 volumio volumio[3005]: info: Listing playlists May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 01:05:47 volumio volumio[3005]: info: Received Get System Info May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:47 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: Discovery: adding e7b64a29-a130-44f8-bbc0-c3b4f002a7dc May 21 01:05:47 volumio volumio[3005]: info: Discovery: Found device Volumio May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:47 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:47 volumio volumio[3005]: info: Asound.conf file unchanged, so no further update is needed May 21 01:05:47 volumio volumio[3005]: info: Output device has changed, restarting MPD May 21 01:05:47 volumio volumio[3005]: info: Output device has changed, restarting Shairport Sync May 21 01:05:47 volumio sudo[3153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 01:05:47 volumio sudo[3153]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:47 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:47 volumio sudo[3153]: pam_unix(sudo:session): session closed for user root May 21 01:05:47 volumio sudo[3155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 01:05:47 volumio sudo[3155]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:48 volumio systemd[1]: Stopping Music Player Daemon... May 21 01:05:48 volumio volumio[3005]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 01:05:48 volumio volumio[3005]: info: ___________ START PLUGINS ___________ May 21 01:05:48 volumio systemd[1]: mpd.service: Succeeded. May 21 01:05:48 volumio systemd[1]: Stopped Music Player Daemon. May 21 01:05:48 volumio volumio[3005]: info: ControllerMpd::onStart: Initializing MPD May 21 01:05:48 volumio volumio[3005]: info: Creating MPD Configuration file May 21 01:05:48 volumio sudo[3163]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 21 01:05:48 volumio volumio[3005]: info: [1747757148081] CoreMusicLibrary::Adding element DLNAサーバー May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:48 volumio sudo[3163]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:48 volumio systemd[1]: Starting Music Player Daemon... May 21 01:05:48 volumio sudo[3163]: pam_unix(sudo:session): session closed for user root May 21 01:05:48 volumio sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 21 01:05:48 volumio sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:48 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM May 21 01:05:48 volumio systemd[1]: mpd.service: Succeeded. May 21 01:05:48 volumio systemd[1]: Stopped Music Player Daemon. May 21 01:05:48 volumio systemd[1]: Starting Music Player Daemon... May 21 01:05:48 volumio volumio[3005]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 21 01:05:48 volumio volumio[3005]: info: [1747757148212] CoreMusicLibrary::Adding element Last_100 May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 21 01:05:48 volumio volumio[3005]: info: [1747757148216] CoreMusicLibrary::Adding element Webradio May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 01:05:48 volumio volumio[3005]: info: Initializing BBC Radios May 21 01:05:48 volumio sudo[3171]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 21 01:05:48 volumio sudo[3171]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:48 volumio sudo[3171]: pam_unix(sudo:session): session closed for user root May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Starting proxy server... May 21 01:05:48 volumio volumio[3005]: info: Loading i18n strings for locale ja May 21 01:05:48 volumio volumio[3005]: error: minidlna: Fetching language file: Error: i18n file complementing the system language not found. May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 21 01:05:48 volumio volumio[3005]: info: [1747757148363] CoreMusicLibrary::Adding element LastFM May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:48 volumio volumio[3005]: Cannot find translation for source LastFM May 21 01:05:48 volumio volumio[3005]: info: [LastFM] scrobbler initiated! May 21 01:05:48 volumio volumio[3005]: info: [LastFM] extended logging: false May 21 01:05:48 volumio volumio[3005]: info: [LastFM] try scrobble stream/radio plays: true May 21 01:05:48 volumio volumio[3005]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" May 21 01:05:48 volumio volumio[3005]: info: [LastFM] Left init routine May 21 01:05:48 volumio volumio[3005]: info: [LastFM] Socket already connected: true May 21 01:05:48 volumio volumio[3005]: info: Volumio Calling Home May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Proxy server started on port 42699 May 21 01:05:48 volumio volumio[3005]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioRetrievevolume May 21 01:05:48 volumio volumio[3005]: info: minidlna: Found miniDLNA version 1.2.1 May 21 01:05:48 volumio volumio[3005]: info: minidlna: Starting minidlna.service May 21 01:05:48 volumio volumio[3005]: info: MPD Permissions set May 21 01:05:48 volumio volumio[3005]: info: CoreStateMachine::pushState May 21 01:05:48 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::volumioPushState May 21 01:05:48 volumio sudo[3199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service May 21 01:05:48 volumio sudo[3199]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Server discovery started May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Player finder started May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Executing aplay -D hw:2 --nonblock -f MPEG /dev/zero 2>&1 || true May 21 01:05:48 volumio volumio[3005]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. May 21 01:05:48 volumio sudo[3199]: pam_unix(sudo:session): session closed for user root May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 21 01:05:48 volumio volumio[3005]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"} May 21 01:05:49 volumio volumio[3005]: info: minidlna: systemctl start minidlna.service succeeded. May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Card 2 supports the following ALSA formats: ["S16_LE","S24_LE","S32_LE"] May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio","dsdFormat":null,"card":"2","mixerType":"Software","mixer":"SoftMaster"} May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 21 01:05:49 volumio volumio[3005]: info: Starting Shairport Sync May 21 01:05:49 volumio volumio[3005]: info: Starting Shairport Sync May 21 01:05:49 volumio sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf May 21 01:05:49 volumio sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 01:05:49 volumio sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio volumio[3005]: info: Starting Shairport Sync May 21 01:05:49 volumio sudo[3214]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 01:05:49 volumio sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 01:05:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 01:05:49 volumio sudo[3227]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 21 01:05:49 volumio sudo[3227]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 01:05:49 volumio volumio[3005]: info: Volumio called home May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Executing alsactl -L -R nrestore May 21 01:05:49 volumio sudo[3216]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 21 01:05:49 volumio systemd[1]: shairport-sync.service: Succeeded. May 21 01:05:49 volumio volumio[3005]: info: Shairport-Sync Started May 21 01:05:49 volumio sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore May 21 01:05:49 volumio sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio volumio[3005]: Error adding Membership: Error: addMembership EINVAL May 21 01:05:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 21 01:05:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 21 01:05:49 volumio sudo[3227]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio sudo[3219]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio sudo[3235]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 21 01:05:49 volumio volumio[3005]: info: Shairport-Sync Started May 21 01:05:49 volumio volumio[3005]: info: Shairport-Sync Started May 21 01:05:49 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 May 21 01:05:49 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 14 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 01:05:49 volumio sudo[3243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service May 21 01:05:49 volumio sudo[3243]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio sudo[3243]: pam_unix(sudo:session): session closed for user root May 21 01:05:49 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true May 21 01:05:49 volumio sudo[3246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload May 21 01:05:49 volumio sudo[3246]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:49 volumio systemd[1]: Reloading. May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetVisibleSources May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetQueue May 21 01:05:49 volumio volumio[3005]: info: CoreStateMachine::getQueue May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getQueue May 21 01:05:49 volumio volumio[3005]: info: Listing playlists May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 01:05:49 volumio volumio[3005]: info: Received Get System Info May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:49 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetVisibleSources May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetQueue May 21 01:05:49 volumio volumio[3005]: info: CoreStateMachine::getQueue May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getQueue May 21 01:05:49 volumio volumio[3005]: info: Listing playlists May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 01:05:49 volumio volumio[3005]: info: Received Get System Info May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:49 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:49 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:49 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:50 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. May 21 01:05:50 volumio mpd[3183]: May 21 01:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Notification listener started May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Getting players connected to pCP (192.168.10.127) May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Players connected to pCP (192.168.10.127): [{"id":"b8:27:eb:5c:f9:64","uuid":null,"ip":"192.168.10.124","name":"piCorePlayer","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:fb:dd:df","uuid":null,"ip":"192.168.10.128","name":"volumio","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}}] May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:fb:dd:df","uuid":null,"ip":"192.168.10.128","name":"volumio","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}} May 21 01:05:50 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 15 May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::volumioGetVisibleSources May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:50 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::volumioGetQueue May 21 01:05:50 volumio volumio[3005]: info: CoreStateMachine::getQueue May 21 01:05:50 volumio volumio[3005]: info: CorePlayQueue::getQueue May 21 01:05:50 volumio volumio[3005]: info: Listing playlists May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 01:05:50 volumio volumio[3005]: info: Received Get System Info May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:50 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:50 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:50 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:50 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:50 volumio systemd[1]: Started Music Player Daemon. May 21 01:05:50 volumio sudo[3246]: pam_unix(sudo:session): session closed for user root May 21 01:05:50 volumio sudo[3165]: pam_unix(sudo:session): session closed for user root May 21 01:05:50 volumio sudo[3155]: pam_unix(sudo:session): session closed for user root May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:50 volumio sudo[3267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:50 volumio sudo[3267]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:50 volumio sudo[3267]: pam_unix(sudo:session): session closed for user root May 21 01:05:50 volumio volumio[3005]: error: MPD error: The expression evaluated to a falsy value: May 21 01:05:50 volumio volumio[3005]: assert.ok(self.idling) May 21 01:05:50 volumio volumio[3005]: error: The expression evaluated to a falsy value: May 21 01:05:50 volumio volumio[3005]: assert.ok(self.idling) May 21 01:05:50 volumio volumio[3005]: info: MPD running with PID3183 May 21 01:05:50 volumio volumio[3005]: ,establishing connection May 21 01:05:50 volumio volumio[3005]: error: MPD error: The expression evaluated to a falsy value: May 21 01:05:50 volumio volumio[3005]: assert.ok(self.idling) May 21 01:05:50 volumio volumio[3005]: error: The expression evaluated to a falsy value: May 21 01:05:50 volumio volumio[3005]: assert.ok(self.idling) May 21 01:05:50 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true May 21 01:05:50 volumio volumio[3005]: error: updateQueue error: null May 21 01:05:50 volumio sudo[3270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite May 21 01:05:50 volumio sudo[3270]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:50 volumio systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... May 21 01:05:51 volumio volumio[3005]: info: [squeezelite_mc] 'client' notification received from pCP (192.168.10.127); type is 'disconnect' May 21 01:05:51 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:51 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:51 volumio systemd[1]: squeezelite.service: Succeeded. May 21 01:05:51 volumio systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. May 21 01:05:51 volumio sudo[3270]: pam_unix(sudo:session): session closed for user root May 21 01:05:51 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:51 volumio sudo[3275]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:51 volumio sudo[3275]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:51 volumio sudo[3275]: pam_unix(sudo:session): session closed for user root May 21 01:05:51 volumio volumio[3005]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log May 21 01:05:51 volumio sudo[3279]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log May 21 01:05:51 volumio sudo[3279]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:51 volumio sudo[3279]: pam_unix(sudo:session): session closed for user root May 21 01:05:51 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true May 21 01:05:51 volumio sudo[3282]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite May 21 01:05:51 volumio sudo[3282]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:51 volumio systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... May 21 01:05:51 volumio systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. May 21 01:05:51 volumio sudo[3282]: pam_unix(sudo:session): session closed for user root May 21 01:05:52 volumio volumio[3005]: info: [squeezelite_mc] 'client' notification received from pCP (192.168.10.127); type is 'connect' May 21 01:05:52 volumio volumio[3005]: info: [squeezelite_mc] Getting players connected to pCP (192.168.10.127) May 21 01:05:52 volumio volumio[3005]: info: [squeezelite_mc] Players connected to pCP (192.168.10.127): [{"id":"b8:27:eb:5c:f9:64","uuid":null,"ip":"192.168.10.124","name":"piCorePlayer","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}},{"id":"b8:27:eb:fb:dd:df","uuid":null,"ip":"192.168.10.128","name":"volumio","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}}] May 21 01:05:52 volumio volumio[3005]: info: [squeezelite_mc] Player found: {"id":"b8:27:eb:fb:dd:df","uuid":null,"ip":"192.168.10.128","name":"volumio","server":{"ip":"192.168.10.127","name":"pCP","ver":"9.0.2","uuid":"0df90e67-bd7c-43ac-a757-b4cc46c93cd8","jsonPort":"9000","cliPort":"9090"}} May 21 01:05:52 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:52 volumio sudo[3289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:52 volumio sudo[3289]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:52 volumio sudo[3289]: pam_unix(sudo:session): session closed for user root May 21 01:05:52 volumio sudo[3292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 01:05:52 volumio sudo[3292]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:52 volumio sudo[3292]: pam_unix(sudo:session): session closed for user root May 21 01:05:52 volumio sudo[3294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:52 volumio sudo[3294]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:52 volumio sudo[3294]: pam_unix(sudo:session): session closed for user root May 21 01:05:52 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 16 May 21 01:05:53 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:53 volumio sudo[3298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:53 volumio sudo[3298]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3298]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio sudo[3301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 01:05:53 volumio sudo[3301]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3301]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio sudo[3303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:53 volumio sudo[3303]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3303]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio volumio[3005]: verbose: New Socket.io Connection to 192.168.10.128 from 192.168.10.103 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/136.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::volumioGetVisibleSources May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:53 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 21 01:05:53 volumio volumio[3005]: info: Received Get System Info May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:53 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:53 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:53 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:53 volumio volumio[3005]: info: Listing playlists May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 01:05:53 volumio sudo[3307]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 21 01:05:53 volumio sudo[3307]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3307]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio volumio-remote-updater[627]: [2025-05-21 01:05:53] [connect] Successful connection May 21 01:05:53 volumio sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 21 01:05:53 volumio sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3309]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio volumio-remote-updater[627]: [2025-05-21 01:05:53] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1747757153 101 May 21 01:05:53 volumio volumio[3005]: 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: 18 May 21 01:05:53 volumio sudo[3313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 21 01:05:53 volumio sudo[3313]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3313]: pam_unix(sudo:session): session closed for user root May 21 01:05:53 volumio volumio[3005]: info: Upmpdcli Daemon Started May 21 01:05:53 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:53 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:53 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:53 volumio sudo[3319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:53 volumio sudo[3319]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:53 volumio sudo[3319]: pam_unix(sudo:session): session closed for user root May 21 01:05:54 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:54 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:54 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:54 volumio sudo[3322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:54 volumio sudo[3322]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:54 volumio sudo[3322]: pam_unix(sudo:session): session closed for user root May 21 01:05:54 volumio volumio[3005]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true May 21 01:05:54 volumio sudo[3325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite May 21 01:05:54 volumio sudo[3325]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:05:54 volumio sudo[3325]: pam_unix(sudo:session): session closed for user root May 21 01:05:54 volumio volumio[3005]: info: Completed starting Core Plugins May 21 01:05:54 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:54 volumio volumio[3005]: info: ----- MyVolumio plugins startup ---- May 21 01:05:54 volumio volumio[3005]: info: ------------------------------------------- May 21 01:05:54 volumio volumio[3005]: info: [MyVolumio PluginManager] Fetching plans data.... May 21 01:05:54 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard May 21 01:05:55 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 01:05:55 volumio volumio[3005]: info: Received Get System Info May 21 01:05:55 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:55 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:55 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:55 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:55 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:55 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus May 21 01:05:56 volumio volumio[3005]: info: Received Get System Info May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:05:56 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:05:56 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:05:56 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:05:57 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken May 21 01:06:03 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 21 01:06:03 volumio volumio[3005]: info: CoreCommandRouter::volumioGetQueue May 21 01:06:03 volumio volumio[3005]: info: CoreStateMachine::getQueue May 21 01:06:03 volumio volumio[3005]: info: CorePlayQueue::getQueue May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 21 01:06:03 volumio volumio[3005]: info: Adding plugin bluetooth to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin multiroom to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin metavolumio to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin cd_controller to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin qobuzconnect to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin smart_inputs to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: Adding plugin tidalconnect to MyMusic Plugins May 21 01:06:03 volumio volumio[3005]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 21 01:06:04 volumio volumio[3005]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 21 01:06:04 volumio volumio[3005]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 21 01:06:04 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 21 01:06:04 volumio volumio[3005]: info: Starting MyVolumio Remote Streaming Endpoints May 21 01:06:04 volumio volumio[3005]: info: MyVolumio login type: Token May 21 01:06:04 volumio volumio[3005]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 21 01:06:04 volumio volumio[3005]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 21 01:06:05 volumio volumio[3005]: info: Starting Streaming Service Transparent Proxy May 21 01:06:05 volumio volumio[3005]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 21 01:06:05 volumio volumio[3005]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 21 01:06:05 volumio volumio[3005]: info: Streaming services startup May 21 01:06:05 volumio volumio[3005]: info: Starting Streaming Daemon May 21 01:06:05 volumio sudo[3362]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 01:06:05 volumio sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:06:06 volumio sudo[3362]: pam_unix(sudo:session): session closed for user root May 21 01:06:06 volumio volumio[3005]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 21 01:06:06 volumio volumio[3005]: error: Cannot start Volumio Streaming Daemon May 21 01:06:06 volumio volumio[3005]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 01:06:06 volumio volumio[3005]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 01:06:06 volumio volumio[3005]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 21 01:06:07 volumio volumio[3005]: STREAMING PROXY: Starting server on port 3245 May 21 01:06:07 volumio volumio[3005]: Node JS runtime: 14 May 21 01:06:07 volumio volumio[3005]: info: MyVolumio token set successfully May 21 01:06:07 volumio volumio[3005]: info: MYVOLUMIO: Adding device May 21 01:06:07 volumio volumio[3005]: info: MYVOLUMIO: Evaluating Server May 21 01:06:08 volumio volumio[3005]: info: MyVolumio status changed May 21 01:06:08 volumio volumio[3005]: info: Streaming services startup May 21 01:06:08 volumio volumio[3005]: info: Starting Streaming Daemon May 21 01:06:08 volumio volumio[3005]: info: Removing browser output: myVolumio user plan is not superstar May 21 01:06:08 volumio volumio[3005]: info: Removing audio output: May 21 01:06:08 volumio volumio[3005]: info: Stoppping Tunnel 1 May 21 01:06:08 volumio sudo[3387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 21 01:06:08 volumio sudo[3387]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:06:08 volumio sudo[3390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 21 01:06:08 volumio sudo[3390]: pam_unix(sudo:session): session opened for user root by (uid=0) May 21 01:06:08 volumio sudo[3387]: pam_unix(sudo:session): session closed for user root May 21 01:06:08 volumio volumio[3005]: error: Cannot start Volumio Streaming Daemon May 21 01:06:08 volumio volumio[3005]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 21 01:06:08 volumio volumio[3005]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 21 01:06:08 volumio sudo[3390]: pam_unix(sudo:session): session closed for user root May 21 01:06:08 volumio volumio[3005]: info: Remote SSH Stopped May 21 01:06:08 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 01:06:09 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 01:06:09 volumio volumio[3005]: info: Preload queue cleared May 21 01:06:09 volumio volumio[3005]: info: Preload queue cleared May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 21 01:06:11 volumio volumio[3005]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 21 01:06:11 volumio volumio[3005]: info: Received Get System Version May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 21 01:06:11 volumio volumio[3005]: info: Received Get System Info May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 21 01:06:11 volumio volumio[3005]: info: Discovery: Getting this device information May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::volumioGetState May 21 01:06:11 volumio volumio[3005]: info: CorePlayQueue::getTrack 0 May 21 01:06:11 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 21 01:06:12 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 01:06:12 volumio volumio[3005]: info: Preload queue cleared May 21 01:06:12 volumio volumio[3005]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri May 21 01:06:12 volumio volumio[3005]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 01:06:12 volumio volumio[3005]: TypeError: Cannot read property 'length' of undefined May 21 01:06:12 volumio volumio[3005]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 May 21 01:06:12 volumio volumio[3005]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 May 21 01:06:12 volumio volumio[3005]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) May 21 01:06:12 volumio volumio[3005]: at Parser.emit (events.js:315:20) May 21 01:06:12 volumio volumio[3005]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) May 21 01:06:12 volumio volumio[3005]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) May 21 01:06:12 volumio volumio[3005]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) May 21 01:06:12 volumio volumio[3005]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) May 21 01:06:12 volumio volumio[3005]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) May 21 01:06:12 volumio volumio[3005]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) May 21 01:06:12 volumio volumio[3005]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) May 21 01:06:12 volumio volumio[3005]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) May 21 01:06:12 volumio volumio[3005]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) May 21 01:06:12 volumio volumio[3005]: at IncomingMessage.emit (events.js:327:22) May 21 01:06:12 volumio volumio[3005]: at endReadableNT (internal/streams/readable.js:1327:12) May 21 01:06:12 volumio volumio[3005]: at processTicksAndRejections (internal/process/task_queues.js:80:21) May 21 01:06:12 volumio volumio[3005]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 21 01:06:13 volumio sudo[3401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-21 01:05 May 21 01:06:13 volumio sudo[3401]: 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="9ab87de061b3649c303da550e73c92d340b0f989" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 30 Apr 2025 11:47:57 AM CEST" VOLUMIO_VERSION="3.807" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fddd10196af80fdc784c726185a6abe4"