-- Logs begin at Tue 2024-09-17 16:06:54 BST, end at Tue 2024-09-17 16:13:41 BST. -- Sep 17 16:12:00 volumio2024 volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 16:12:02 volumio2024 volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 16:12:03 volumio2024 volumio[909]: info: CALLMETHOD: system_controller system saveUpdateSettings [object Object] Sep 17 16:12:03 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , saveUpdateSettings Sep 17 16:12:03 volumio2024 volumio[909]: error: Failed callmethod call: TypeError: Cannot read property 'value' of undefined Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::Reload Ui Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Sep 17 16:12:04 volumio2024 volumio[909]: info: Wizard terminated Successfully Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::volumiosetStartupVolume Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 16:12:04 volumio2024 volumio[909]: info: Player Reboot required after I2S DAC has been enabled in wizard Sep 17 16:12:04 volumio2024 volumio[909]: info: Executing Deferred Wifi Connection Sep 17 16:12:04 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Sep 17 16:12:04 volumio2024 volumio[909]: info: Saving new wireless network Sep 17 16:12:04 volumio2024 sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Sep 17 16:12:04 volumio2024 sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:05 volumio2024 sudo[1936]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:06 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Sep 17 16:12:06 volumio2024 volumio[909]: info: Discovery: Restarting Advertising due to device name change Sep 17 16:12:06 volumio2024 sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Sep 17 16:12:06 volumio2024 sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:06 volumio2024 systemd[1]: Starting Wireless Services... Sep 17 16:12:06 volumio2024 kernel: hwmon hwmon1: Undervoltage detected! Sep 17 16:12:07 volumio2024 volumio[909]: info: Discovery: A device disappeared from network Sep 17 16:12:07 volumio2024 wireless.js[1943]: Cleaning previous... Sep 17 16:12:07 volumio2024 sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 16:12:07 volumio2024 sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:07 volumio2024 sudo[1956]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:07 volumio2024 sudo[1959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:07 volumio2024 sudo[1959]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:07 volumio2024 sudo[1959]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:07 volumio2024 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Sep 17 16:12:07 volumio2024 systemd[1]: hostapd.service: Succeeded. Sep 17 16:12:07 volumio2024 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Sep 17 16:12:08 volumio2024 sudo[1964]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 16:12:08 volumio2024 sudo[1964]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:08 volumio2024 avahi-daemon[672]: Withdrawing address record for 192.168.211.1 on wlan0. Sep 17 16:12:08 volumio2024 avahi-daemon[672]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Sep 17 16:12:08 volumio2024 avahi-daemon[672]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 17 16:12:08 volumio2024 sudo[1964]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:08 volumio2024 sudo[1966]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 16:12:08 volumio2024 sudo[1966]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:08 volumio2024 sudo[1966]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:08 volumio2024 wireless.js[1943]: Stopped aP Sep 17 16:12:08 volumio2024 sudo[1973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 16:12:08 volumio2024 sudo[1973]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:08 volumio2024 sudo[1973]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:08 volumio2024 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 16:12:08 volumio2024 sudo[1975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Sep 17 16:12:08 volumio2024 sudo[1975]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:08 volumio2024 sudo[1975]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:08 volumio2024 sudo[1982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 16:12:08 volumio2024 sudo[1982]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:08 volumio2024 sudo[1982]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:08 volumio2024 sudo[1984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Sep 17 16:12:08 volumio2024 sudo[1984]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:09 volumio2024 sudo[1984]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:09 volumio2024 wireless.js[1943]: SETTING APPROPRIATE REG DOMAIN: US Sep 17 16:12:09 volumio2024 sudo[1989]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Sep 17 16:12:09 volumio2024 sudo[1989]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:09 volumio2024 sudo[1989]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:09 volumio2024 sudo[1991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg set US Sep 17 16:12:09 volumio2024 sudo[1991]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:09 volumio2024 sudo[1991]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:09 volumio2024 wireless.js[1943]: SUCCESSFULLY SET NEW REGDOMAIN: US Sep 17 16:12:09 volumio2024 wireless.js[1943]: Start wireless flow Sep 17 16:12:09 volumio2024 wireless.js[1943]: Stopped hotspot (if there).. Sep 17 16:12:09 volumio2024 sudo[1996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Sep 17 16:12:09 volumio2024 sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:09 volumio2024 sudo[1996]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:09 volumio2024 sudo[1998]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Sep 17 16:12:09 volumio2024 sudo[1998]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:10 volumio2024 sudo[1998]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:10 volumio2024 wireless.js[1943]: DHCP IP Sep 17 16:12:10 volumio2024 wireless.js[1943]: Start ap Sep 17 16:12:10 volumio2024 wpa_supplicant[2000]: Successfully initialized wpa_supplicant Sep 17 16:12:10 volumio2024 sudo[2001]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Sep 17 16:12:10 volumio2024 sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:10 volumio2024 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 16:12:10 volumio2024 dhcpcd[2002]: dev: loaded udev Sep 17 16:12:10 volumio2024 dhcpcd-run-hooks[2020]: wlan0: starting wpa_supplicant Sep 17 16:12:10 volumio2024 dhcpcd-run-hooks[2025]: wlan0: failed to start wpa_supplicant Sep 17 16:12:10 volumio2024 dhcpcd-run-hooks[2026]: 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 Sep 17 16:12:10 volumio2024 dhcpcd[2002]: wlan0: connected to Access Point `' Sep 17 16:12:10 volumio2024 dhcpcd[2002]: no interfaces have a carrier Sep 17 16:12:10 volumio2024 dhcpcd[2002]: forked to background, child pid 2036 Sep 17 16:12:10 volumio2024 dhcpcd[2036]: eth0: waiting for carrier Sep 17 16:12:10 volumio2024 dhcpcd[2036]: wlan0: waiting for carrier Sep 17 16:12:10 volumio2024 sudo[2001]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:10 volumio2024 wpa_supplicant[2024]: wlan0: Trying to associate with SSID 'tpclubwifi' Sep 17 16:12:11 volumio2024 volumio[909]: info: Discovery: Started advertising with name: Volumio2024 Sep 17 16:12:11 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:11 volumio2024 sudo[2039]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:11 volumio2024 sudo[2039]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:11 volumio2024 sudo[2039]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:11 volumio2024 dhcpcd[2036]: wlan0: carrier acquired Sep 17 16:12:11 volumio2024 dhcpcd[2036]: wlan0: connected to Access Point `tpclubwifi' Sep 17 16:12:11 volumio2024 wpa_supplicant[2024]: wlan0: Associated with a8:5e:45:67:d7:88 Sep 17 16:12:11 volumio2024 wpa_supplicant[2024]: wlan0: CTRL-EVENT-CONNECTED - Connection to a8:5e:45:67:d7:88 completed [id=0 id_str=] Sep 17 16:12:11 volumio2024 wpa_supplicant[2024]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Sep 17 16:12:11 volumio2024 wpa_supplicant[2024]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=US Sep 17 16:12:12 volumio2024 dhcpcd[2036]: DUID 00:01:00:01:2e:7c:58:cc:b8:27:eb:ca:ba:c2 Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: IAID eb:ca:ba:c2 Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: adding address fe80::eb58:2bae:231c:6da0 Sep 17 16:12:12 volumio2024 dhcpcd[2036]: ipv6_addaddr1: Permission denied Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: carrier lost Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: carrier acquired Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: IAID eb:ca:ba:c2 Sep 17 16:12:12 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:12 volumio2024 sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:12 volumio2024 sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:12 volumio2024 sudo[2054]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:12 volumio2024 kernel: hwmon hwmon1: Voltage normalised Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: soliciting a DHCP lease Sep 17 16:12:12 volumio2024 dhcpcd[2036]: wlan0: soliciting an IPv6 router Sep 17 16:12:13 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:13 volumio2024 sudo[2058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:13 volumio2024 sudo[2058]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:13 volumio2024 sudo[2058]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:13 volumio2024 ntpd[813]: Deleting interface #3 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=306 secs Sep 17 16:12:14 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:14 volumio2024 sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:14 volumio2024 sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:14 volumio2024 sudo[2061]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:15 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:15 volumio2024 sudo[2064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:15 volumio2024 sudo[2064]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:15 volumio2024 sudo[2064]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:16 volumio2024 dhcpcd[2036]: wlan0: offered 192.168.11.155 from 192.168.11.1 Sep 17 16:12:16 volumio2024 dhcpcd[2036]: wlan0: probing address 192.168.11.155/24 Sep 17 16:12:16 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:16 volumio2024 sudo[2067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:16 volumio2024 sudo[2067]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:16 volumio2024 sudo[2067]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:17 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:17 volumio2024 sudo[2070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:17 volumio2024 sudo[2070]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:17 volumio2024 sudo[2070]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:18 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:18 volumio2024 sudo[2073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:18 volumio2024 sudo[2073]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:18 volumio2024 sudo[2073]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:19 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:19 volumio2024 sudo[2076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:19 volumio2024 sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:19 volumio2024 sudo[2076]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:20 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:20 volumio2024 sudo[2079]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:20 volumio2024 sudo[2079]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:20 volumio2024 sudo[2079]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:20 volumio2024 dhcpcd[2036]: wlan0: leased 192.168.11.155 for 86400 seconds Sep 17 16:12:20 volumio2024 avahi-daemon[672]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.11.155. Sep 17 16:12:20 volumio2024 avahi-daemon[672]: New relevant interface wlan0.IPv4 for mDNS. Sep 17 16:12:20 volumio2024 dhcpcd[2036]: wlan0: adding route to 192.168.11.0/24 Sep 17 16:12:20 volumio2024 avahi-daemon[672]: Registering new address record for 192.168.11.155 on wlan0.IPv4. Sep 17 16:12:20 volumio2024 dhcpcd[2036]: wlan0: adding default route via 192.168.11.1 Sep 17 16:12:20 volumio2024 dnsmasq[900]: reading /etc/resolv.conf Sep 17 16:12:20 volumio2024 dnsmasq[900]: using nameserver 192.168.11.1#53 Sep 17 16:12:20 volumio2024 dnsmasq[900]: using nameserver 208.67.222.222#53 Sep 17 16:12:20 volumio2024 dnsmasq[900]: using nameserver 208.67.220.220#53 Sep 17 16:12:21 volumio2024 wireless.js[1943]: trying... Sep 17 16:12:21 volumio2024 sudo[2104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Sep 17 16:12:21 volumio2024 sudo[2104]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:21 volumio2024 sudo[2104]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:21 volumio2024 wireless.js[1943]: Connected to: ----tpclubwifi Sep 17 16:12:21 volumio2024 wireless.js[1943]: ---- Sep 17 16:12:21 volumio2024 sudo[2107]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:21 volumio2024 sudo[2107]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:21 volumio2024 sudo[2107]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:21 volumio2024 wireless.js[1943]: ... joined AP, wlan0 IPv4 is 192.168.11.155, ipV6 is undefined Sep 17 16:12:21 volumio2024 wireless.js[1943]: It's done! AP Sep 17 16:12:21 volumio2024 systemd[1]: Started Wireless Services. Sep 17 16:12:21 volumio2024 kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Sep 17 16:12:21 volumio2024 sudo[1941]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:21 volumio2024 volumio[909]: info: Discovery: adding 3c1d6ca7-3269-433c-bf7d-0597c4a21243 Sep 17 16:12:21 volumio2024 volumio[909]: info: Discovery: Found device Volumio2024 Sep 17 16:12:21 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:21 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:21 volumio2024 ntpd[813]: Listen normally on 4 wlan0 192.168.11.155:123 Sep 17 16:12:21 volumio2024 ntpd[813]: new interface(s) found: waking up resolver Sep 17 16:12:25 volumio2024 ntpd[813]: Soliciting pool server 162.159.200.123 Sep 17 16:12:26 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Sep 17 16:12:26 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Sep 17 16:12:26 volumio2024 sudo[2114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:26 volumio2024 sudo[2114]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:26 volumio2024 sudo[2114]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:26 volumio2024 sudo[2117]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Sep 17 16:12:26 volumio2024 sudo[2117]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:26 volumio2024 sudo[2117]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:26 volumio2024 ntpd[813]: Soliciting pool server 178.62.68.79 Sep 17 16:12:31 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Sep 17 16:12:31 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Sep 17 16:12:31 volumio2024 volumio[909]: info: Refreshing Cached IP Addresses Sep 17 16:12:31 volumio2024 sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Sep 17 16:12:31 volumio2024 sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:31 volumio2024 sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 16:12:31 volumio2024 sudo[2122]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:31 volumio2024 sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:31 volumio2024 sudo[2124]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:31 volumio2024 volumio[909]: error: Upnp client error: Error: This socket has been ended by the other party Sep 17 16:12:31 volumio2024 sudo[2128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:31 volumio2024 sudo[2128]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:31 volumio2024 sudo[2128]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:36 volumio2024 ntpd[813]: Soliciting pool server 193.57.144.50 Sep 17 16:12:36 volumio2024 ntpd[813]: Soliciting pool server 195.171.43.12 Sep 17 16:12:37 volumio2024 kernel: ieee80211 phy0: brcmf_psm_watchdog_notify: PSM's watchdog has fired! Sep 17 16:12:41 volumio2024 sudo[2132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 16:12:41 volumio2024 sudo[2132]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:41 volumio2024 sudo[2132]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:41 volumio2024 sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:41 volumio2024 sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:41 volumio2024 sudo[2134]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:41 volumio2024 sudo[2137]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 17 16:12:41 volumio2024 sudo[2137]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:41 volumio2024 sudo[2137]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:41 volumio2024 volumio[909]: info: Upmpdcli Daemon Started Sep 17 16:12:42 volumio2024 ntpd[813]: Soliciting pool server 185.177.149.33 Sep 17 16:12:42 volumio2024 ntpd[813]: Soliciting pool server 131.111.8.63 Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:12:43 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:43 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:12:43 volumio2024 volumio[909]: verbose: New Socket.io Connection to 192.168.11.155:3000 from 192.168.11.224 UA: Dart/3.4 (dart:io) Engine version: 3 Transport: websocket Total Clients: 3 Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:43 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 17 16:12:43 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 17 16:12:49 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:12:49 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:12:49 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:12:49 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:49 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:49 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:12:54 volumio2024 kernel: hwmon hwmon1: Undervoltage detected! Sep 17 16:12:54 volumio2024 sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 16:12:54 volumio2024 sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:54 volumio2024 sudo[2142]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:54 volumio2024 sudo[2144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:54 volumio2024 sudo[2144]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:54 volumio2024 sudo[2144]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:55 volumio2024 volumio[909]: verbose: New Socket.io Connection to 192.168.11.155 from 192.168.11.224 UA: Mozilla/5.0 (Linux; Android 14; SM-S9280 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Sep 17 16:12:55 volumio2024 sudo[2149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 16:12:55 volumio2024 sudo[2149]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:55 volumio2024 sudo[2149]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:55 volumio2024 sudo[2151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 16:12:55 volumio2024 sudo[2151]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 16:12:55 volumio2024 sudo[2151]: pam_unix(sudo:session): session closed for user root Sep 17 16:12:55 volumio2024 volumio[909]: verbose: New Socket.io Connection to 192.168.11.155 from 192.168.11.224 UA: Mozilla/5.0 (Linux; Android 14; SM-S9280 Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.70 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 17 16:12:55 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetVisibleSources Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:56 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 17 16:12:56 volumio2024 volumio[909]: info: Received Get System Info Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:12:56 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:56 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:56 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:56 volumio2024 volumio[909]: info: Listing playlists Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 17 16:12:56 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 16:12:57 volumio2024 volumio[909]: info: Received Get System Info Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:12:57 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:12:57 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:12:57 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 17 16:12:58 volumio2024 kernel: hwmon hwmon1: Voltage normalised Sep 17 16:13:05 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 17 16:13:13 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:13:13 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:13:13 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:13:13 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:13:13 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:13:13 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:13:13 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: wizard , setOnboardingWizardFalse Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 16:13:14 volumio2024 volumio[909]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 17 16:13:14 volumio2024 volumio[909]: info: Received Get System Version Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 16:13:14 volumio2024 volumio[909]: info: Received Get System Info Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 16:13:14 volumio2024 volumio[909]: info: Discovery: Getting this device information Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetState Sep 17 16:13:14 volumio2024 volumio[909]: info: CorePlayQueue::getTrack 0 Sep 17 16:13:14 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 16:13:19 volumio2024 volumio[909]: info: CoreCommandRouter::volumioGetQueue Sep 17 16:13:19 volumio2024 volumio[909]: info: CoreStateMachine::getQueue Sep 17 16:13:19 volumio2024 volumio[909]: info: CorePlayQueue::getQueue Sep 17 16:13:24 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 16:13:24 volumio2024 volumio[909]: info: Preload queue cleared Sep 17 16:13:25 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 16:13:26 volumio2024 volumio[909]: info: Preload queue cleared Sep 17 16:13:27 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 16:13:27 volumio2024 volumio[909]: info: Preload queue cleared Sep 17 16:13:30 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 16:13:32 volumio2024 volumio[909]: info: Preload queue cleared Sep 17 16:13:32 volumio2024 ntpd[813]: Soliciting pool server 195.171.43.10 Sep 17 16:13:33 volumio2024 ntpd[813]: Soliciting pool server 162.159.200.1 Sep 17 16:13:37 volumio2024 ntpd[813]: Soliciting pool server 185.57.191.229 Sep 17 16:13:39 volumio2024 volumio[909]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Sep 17 16:13:40 volumio2024 volumio[909]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 16:13:40 volumio2024 volumio[909]: TypeError: Cannot read property 'length' of undefined Sep 17 16:13:40 volumio2024 volumio[909]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Sep 17 16:13:40 volumio2024 volumio[909]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Sep 17 16:13:40 volumio2024 volumio[909]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Sep 17 16:13:40 volumio2024 volumio[909]: at Parser.emit (events.js:315:20) Sep 17 16:13:40 volumio2024 volumio[909]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Sep 17 16:13:40 volumio2024 volumio[909]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Sep 17 16:13:40 volumio2024 volumio[909]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Sep 17 16:13:40 volumio2024 volumio[909]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Sep 17 16:13:40 volumio2024 volumio[909]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Sep 17 16:13:40 volumio2024 volumio[909]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Sep 17 16:13:40 volumio2024 volumio[909]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Sep 17 16:13:40 volumio2024 volumio[909]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Sep 17 16:13:40 volumio2024 volumio[909]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Sep 17 16:13:40 volumio2024 volumio[909]: at IncomingMessage.emit (events.js:327:22) Sep 17 16:13:40 volumio2024 volumio[909]: at endReadableNT (internal/streams/readable.js:1327:12) Sep 17 16:13:40 volumio2024 volumio[909]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Sep 17 16:13:40 volumio2024 volumio[909]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 16:13:41 volumio2024 sudo[2206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-17 16:12 Sep 17 16:13:41 volumio2024 sudo[2206]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:01:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="e0a6dc7e914e3fb93cbf2123f2f73eac"