-- Logs begin at Sat 2024-04-27 15:40:16 UTC, end at Sat 2024-04-27 15:46:28 UTC. -- Apr 27 15:45:02 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , connectWirelessNetwork Apr 27 15:45:02 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getWiredInfo Apr 27 15:45:02 volumio sudo[2528]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:45:02 volumio sudo[2528]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:02 volumio sudo[2528]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:02 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Apr 27 15:45:02 volumio volumio[852]: info: Saving new wireless network Apr 27 15:45:02 volumio sudo[2531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Apr 27 15:45:02 volumio sudo[2531]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:02 volumio sudo[2531]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:02 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 27 15:45:02 volumio volumio[852]: info: Discovery: Restarting Advertising due to device name change Apr 27 15:45:02 volumio sudo[2534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 27 15:45:02 volumio sudo[2534]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:02 volumio systemd[1]: Starting Wireless Services... Apr 27 15:45:02 volumio wireless.js[2536]: Cleaning previous... Apr 27 15:45:02 volumio systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Apr 27 15:45:02 volumio dnsmasq[2385]: exiting on receipt of SIGTERM Apr 27 15:45:02 volumio systemd[1]: dnsmasq.service: Succeeded. Apr 27 15:45:02 volumio systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Apr 27 15:45:02 volumio systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Apr 27 15:45:02 volumio dhcpcd[542]: wlan0: carrier lost Apr 27 15:45:02 volumio avahi-daemon[517]: Withdrawing address record for 169.254.23.73 on wlan0. Apr 27 15:45:02 volumio dhcpcd[542]: wlan0: deleting route to 169.254.0.0/16 Apr 27 15:45:02 volumio avahi-daemon[517]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 27 15:45:02 volumio avahi-daemon[517]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 27 15:45:02 volumio avahi-daemon[517]: Withdrawing address record for 192.168.211.1 on wlan0. Apr 27 15:45:02 volumio volumio[852]: info: Discovery: A device disappeared from network Apr 27 15:45:02 volumio volumio[852]: info: Discovery: Device volumio disappeared from network Apr 27 15:45:03 volumio avahi-daemon[517]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 27 15:45:03 volumio avahi-daemon[517]: New relevant interface wlan0.IPv4 for mDNS. Apr 27 15:45:03 volumio avahi-daemon[517]: Registering new address record for 192.168.211.1 on wlan0.IPv4. Apr 27 15:45:03 volumio systemd[1]: hostapd.service: Succeeded. Apr 27 15:45:03 volumio systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Apr 27 15:45:03 volumio sudo[2599]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 27 15:45:03 volumio sudo[2599]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio avahi-daemon[517]: Withdrawing address record for 192.168.211.1 on wlan0. Apr 27 15:45:03 volumio avahi-daemon[517]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Apr 27 15:45:03 volumio avahi-daemon[517]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 27 15:45:03 volumio sudo[2599]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:03 volumio sudo[2601]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 27 15:45:03 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:03 volumio wireless.js[2536]: Stopped aP Apr 27 15:45:03 volumio sudo[2608]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 27 15:45:03 volumio sudo[2608]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio sudo[2608]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:03 volumio sudo[2610]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 27 15:45:03 volumio sudo[2610]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio sudo[2610]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:03 volumio sudo[2617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 27 15:45:03 volumio sudo[2617]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio sudo[2617]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:03 volumio sudo[2619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 27 15:45:03 volumio sudo[2619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:03 volumio volumio[852]: info: Discovery: A device disappeared from network Apr 27 15:45:04 volumio sudo[2619]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:04 volumio wireless.js[2536]: Start wireless flow Apr 27 15:45:04 volumio wireless.js[2536]: Stopped hotspot (if there).. Apr 27 15:45:04 volumio sudo[2624]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 27 15:45:04 volumio sudo[2624]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:04 volumio sudo[2624]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:04 volumio sudo[2626]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 27 15:45:04 volumio sudo[2626]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:04 volumio sudo[2626]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:04 volumio wireless.js[2536]: DHCP IP Apr 27 15:45:04 volumio wireless.js[2536]: Start ap Apr 27 15:45:04 volumio wpa_supplicant[2628]: Successfully initialized wpa_supplicant Apr 27 15:45:04 volumio sudo[2629]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 27 15:45:04 volumio sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:04 volumio dhcpcd[2630]: sending commands to master dhcpcd process Apr 27 15:45:04 volumio dhcpcd[542]: control command: /sbin/dhcpcd Apr 27 15:45:04 volumio sudo[2629]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:05 volumio wireless.js[2536]: trying... Apr 27 15:45:05 volumio sudo[2633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:05 volumio sudo[2633]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:05 volumio sudo[2633]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:05 volumio ntpd[1639]: Deleting interface #6 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=56 secs Apr 27 15:45:05 volumio ntpd[1639]: Deleting interface #7 wlan0, 169.254.23.73#123, interface stats: received=0, sent=0, dropped=0, active_time=46 secs Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: SME: Trying to authenticate with 8c:dc:02:c9:d6:f4 (SSID='FASTWEB-PHG7X2' freq=2417 MHz) Apr 27 15:45:05 volumio kernel: wlan0: authenticate with 8c:dc:02:c9:d6:f4 Apr 27 15:45:05 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Apr 27 15:45:05 volumio kernel: wlan0: send auth to 8c:dc:02:c9:d6:f4 (try 1/3) Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: Trying to associate with 8c:dc:02:c9:d6:f4 (SSID='FASTWEB-PHG7X2' freq=2417 MHz) Apr 27 15:45:05 volumio kernel: wlan0: authenticated Apr 27 15:45:05 volumio kernel: wlan0: associate with 8c:dc:02:c9:d6:f4 (try 1/3) Apr 27 15:45:05 volumio kernel: wlan0: RX AssocResp from 8c:dc:02:c9:d6:f4 (capab=0x1831 status=0 aid=3) Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: Associated with 8c:dc:02:c9:d6:f4 Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=IT Apr 27 15:45:05 volumio kernel: wlan0: associated Apr 27 15:45:05 volumio kernel: wlan0: Limiting TX power to 27 (27 - 0) dBm as advertised by 8c:dc:02:c9:d6:f4 Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: WPA: Key negotiation completed with 8c:dc:02:c9:d6:f4 [PTK=CCMP GTK=CCMP] Apr 27 15:45:05 volumio wpa_supplicant[2631]: wlan0: CTRL-EVENT-CONNECTED - Connection to 8c:dc:02:c9:d6:f4 completed [id=0 id_str=] Apr 27 15:45:05 volumio dhcpcd[542]: wlan0: carrier acquired Apr 27 15:45:05 volumio dhcpcd[542]: wlan0: IAID 43:69:5b:5a Apr 27 15:45:06 volumio dhcpcd[542]: wlan0: soliciting a DHCP lease Apr 27 15:45:06 volumio dhcpcd[542]: wlan0: soliciting an IPv6 router Apr 27 15:45:06 volumio wireless.js[2536]: trying... Apr 27 15:45:06 volumio sudo[2657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:06 volumio sudo[2657]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:06 volumio sudo[2657]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:07 volumio wireless.js[2536]: trying... Apr 27 15:45:07 volumio sudo[2660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:07 volumio sudo[2660]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:07 volumio sudo[2660]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:07 volumio volumio[852]: info: Discovery: Started advertising with name: Volumio Apr 27 15:45:08 volumio dhcpcd[542]: wlan0: offered 192.168.1.75 from 192.168.1.254 Apr 27 15:45:08 volumio wireless.js[2536]: trying... Apr 27 15:45:08 volumio sudo[2664]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:08 volumio sudo[2664]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:08 volumio sudo[2664]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:08 volumio dhcpcd[542]: wlan0: probing address 192.168.1.75/24 Apr 27 15:45:08 volumio volumio[852]: info: Discovery: adding 810edcb4-2665-4d4c-ad51-a6847c6f85d4 Apr 27 15:45:08 volumio volumio[852]: info: Discovery: Found device Volumio Apr 27 15:45:08 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:08 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:09 volumio wireless.js[2536]: trying... Apr 27 15:45:09 volumio sudo[2669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:09 volumio sudo[2669]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:09 volumio sudo[2669]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:10 volumio wireless.js[2536]: trying... Apr 27 15:45:10 volumio sudo[2672]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:10 volumio sudo[2672]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:10 volumio sudo[2672]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:11 volumio wireless.js[2536]: trying... Apr 27 15:45:11 volumio sudo[2676]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:11 volumio sudo[2676]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:11 volumio sudo[2676]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:12 volumio wireless.js[2536]: trying... Apr 27 15:45:12 volumio sudo[2679]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:12 volumio sudo[2679]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:12 volumio sudo[2679]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:13 volumio dhcpcd[542]: wlan0: leased 192.168.1.75 for 86400 seconds Apr 27 15:45:13 volumio dhcpcd[542]: wlan0: adding route to 192.168.1.0/24 Apr 27 15:45:13 volumio wireless.js[2536]: trying... Apr 27 15:45:13 volumio dhcpcd[542]: wlan0: adding default route via 192.168.1.254 Apr 27 15:45:13 volumio avahi-daemon[517]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.75. Apr 27 15:45:13 volumio avahi-daemon[517]: New relevant interface wlan0.IPv4 for mDNS. Apr 27 15:45:13 volumio avahi-daemon[517]: Registering new address record for 192.168.1.75 on wlan0.IPv4. Apr 27 15:45:13 volumio sudo[2683]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 27 15:45:13 volumio sudo[2683]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:13 volumio sudo[2683]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:13 volumio wireless.js[2536]: Connected to: ----FASTWEB-PHG7X2 Apr 27 15:45:13 volumio wireless.js[2536]: ---- Apr 27 15:45:13 volumio sudo[2699]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:45:13 volumio sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:13 volumio sudo[2699]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:13 volumio wireless.js[2536]: ... joined AP, wlan0 IPv4 is 192.168.1.75, ipV6 is undefined Apr 27 15:45:13 volumio wireless.js[2536]: It's done! AP Apr 27 15:45:13 volumio systemd[1]: Started Wireless Services. Apr 27 15:45:13 volumio sudo[2534]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:13 volumio ntpd[1639]: ntpd exiting on signal 15 (Terminated) Apr 27 15:45:13 volumio ntpd[1639]: 185.19.184.35 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio systemd[1]: Stopping Network Time Service... Apr 27 15:45:13 volumio ntpd[1639]: 37.247.53.178 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 162.159.200.123 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 212.45.144.3 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 95.230.240.5 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 93.94.88.50 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 212.45.144.88 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 149.62.187.162 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 162.159.200.1 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 95.110.254.234 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 217.61.62.224 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio ntpd[1639]: 212.6.50.243 local addr 192.168.1.99 -> Apr 27 15:45:13 volumio systemd[1]: ntp.service: Succeeded. Apr 27 15:45:13 volumio systemd[1]: Stopped Network Time Service. Apr 27 15:45:13 volumio systemd[1]: Starting Network Time Service... Apr 27 15:45:13 volumio ntpd[2724]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 27 15:45:13 volumio ntpd[2724]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 27 15:45:13 volumio systemd[1]: Started Network Time Service. Apr 27 15:45:13 volumio ntpd[2730]: proto: precision = 0.050 usec (-24) Apr 27 15:45:13 volumio ntpd[2730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 27 15:45:13 volumio ntpd[2730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 27 15:45:13 volumio ntpd[2730]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 487 days ago Apr 27 15:45:13 volumio ntpd[2730]: Listen and drop on 0 v6wildcard [::]:123 Apr 27 15:45:13 volumio ntpd[2730]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 27 15:45:13 volumio ntpd[2730]: Listen normally on 2 lo 127.0.0.1:123 Apr 27 15:45:13 volumio ntpd[2730]: Listen normally on 3 eth0 192.168.1.99:123 Apr 27 15:45:13 volumio ntpd[2730]: Listen normally on 4 wlan0 192.168.1.75:123 Apr 27 15:45:13 volumio ntpd[2730]: Listening on routing socket on fd #21 for interface updates Apr 27 15:45:13 volumio ntpd[2730]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 15:45:13 volumio ntpd[2730]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Apr 27 15:45:14 volumio volumio[852]: info: Discovery: this is already registered, 810edcb4-2665-4d4c-ad51-a6847c6f85d4 Apr 27 15:45:14 volumio volumio[852]: info: Discovery: Found device Volumio Apr 27 15:45:14 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:14 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:14 volumio ntpd[2730]: Soliciting pool server 95.110.254.234 Apr 27 15:45:15 volumio ntpd[2730]: Soliciting pool server 212.45.144.206 Apr 27 15:45:15 volumio ntpd[2730]: Soliciting pool server 31.14.133.122 Apr 27 15:45:16 volumio ntpd[2730]: Soliciting pool server 85.199.214.99 Apr 27 15:45:16 volumio ntpd[2730]: Soliciting pool server 212.45.144.3 Apr 27 15:45:16 volumio ntpd[2730]: Soliciting pool server 188.213.165.209 Apr 27 15:45:17 volumio ntpd[2730]: Soliciting pool server 31.14.133.93 Apr 27 15:45:17 volumio ntpd[2730]: Soliciting pool server 149.62.187.162 Apr 27 15:45:17 volumio ntpd[2730]: Soliciting pool server 185.221.173.236 Apr 27 15:45:17 volumio ntpd[2730]: Soliciting pool server 80.211.152.245 Apr 27 15:45:18 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 27 15:45:18 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 27 15:45:18 volumio sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:45:18 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:18 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:18 volumio ntpd[2730]: Soliciting pool server 162.159.200.123 Apr 27 15:45:18 volumio ntpd[2730]: Soliciting pool server 162.159.200.1 Apr 27 15:45:18 volumio sudo[2780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 27 15:45:18 volumio sudo[2780]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:18 volumio sudo[2780]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:19 volumio ntpd[2730]: Soliciting pool server 185.157.229.254 Apr 27 15:45:20 volumio ntpd[2730]: Soliciting pool server 2a00:6d41:200:2::11 Apr 27 15:45:23 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 27 15:45:23 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 27 15:45:23 volumio volumio[852]: info: Refreshing Cached IP Addresses Apr 27 15:45:23 volumio sudo[2796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 27 15:45:23 volumio sudo[2796]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:23 volumio sudo[2796]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:23 volumio sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:45:23 volumio sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:23 volumio sudo[2799]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:23 volumio volumio[852]: error: Upnp client error: Error: This socket has been ended by the other party Apr 27 15:45:23 volumio sudo[2802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:45:23 volumio sudo[2802]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:23 volumio sudo[2802]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:24 volumio volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:45:24 volumio volumio[852]: info: Retrieving Cloud Streaming UI Apr 27 15:45:24 volumio volumio[852]: info: Getting Tidal Cloud Configuration Apr 27 15:45:24 volumio volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:45:24 volumio volumio[852]: info: Getting Qobuz Cloud Configuration Apr 27 15:45:24 volumio volumio[852]: info: Asking plugin for UI Config Apr 27 15:45:24 volumio volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:45:24 volumio volumio[852]: info: Getting Spotify Cloud Configuration Apr 27 15:45:24 volumio volumio[852]: info: Asking plugin for UI Config Apr 27 15:45:24 volumio volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:45:24 volumio volumio[852]: info: Saving Spotify Acccount Apr 27 15:45:24 volumio volumio[852]: info: Got Tidal Cloud Configuration Apr 27 15:45:24 volumio volumio[852]: info: Got it Apr 27 15:45:24 volumio volumio[852]: info: Got it Apr 27 15:45:24 volumio volumio[852]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 27 15:45:24 volumio volumio[852]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 27 15:45:24 volumio volumio[852]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Apr 27 15:45:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonation Apr 27 15:45:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 27 15:45:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , getDonationsArray Apr 27 15:45:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , getDoneMessage Apr 27 15:45:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , detectVolumioHardware Apr 27 15:45:31 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Apr 27 15:45:31 volumio volumio[852]: info: CoreCommandRouter::Reload Ui Apr 27 15:45:31 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , setShowWizard Apr 27 15:45:31 volumio volumio[852]: info: Wizard terminated Successfully Apr 27 15:45:31 volumio volumio[852]: info: CoreCommandRouter::volumiosetStartupVolume Apr 27 15:45:31 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 27 15:45:31 volumio volumio[852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 4 Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 15:45:32 volumio volumio[852]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Total Clients: 5 Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::volumioGetVisibleSources Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:32 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 27 15:45:32 volumio volumio[852]: info: Received Get System Info Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:45:32 volumio volumio[852]: info: Discovery: Getting this device information Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:32 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:32 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:32 volumio volumio[852]: info: Listing playlists Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 15:45:32 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 27 15:45:33 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:33 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:33 volumio sudo[2838]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 27 15:45:33 volumio sudo[2838]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:33 volumio sudo[2838]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:33 volumio sudo[2840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 27 15:45:33 volumio sudo[2840]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:33 volumio sudo[2840]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:33 volumio sudo[2844]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Apr 27 15:45:33 volumio sudo[2844]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 27 15:45:33 volumio sudo[2844]: pam_unix(sudo:session): session closed for user root Apr 27 15:45:33 volumio volumio[852]: info: Upmpdcli Daemon Started Apr 27 15:45:34 volumio nmbd[596]: [2024/04/27 15:45:34.000743, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 27 15:45:34 volumio nmbd[596]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 27 15:45:34 volumio nmbd[596]: [2024/04/27 15:45:34.000850, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 27 15:45:34 volumio nmbd[596]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Apr 27 15:45:34 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 27 15:45:34 volumio volumio[852]: info: Received Get System Info Apr 27 15:45:34 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:45:34 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:45:34 volumio volumio[852]: info: Discovery: Getting this device information Apr 27 15:45:34 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:34 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:34 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 27 15:45:50 volumio volumio[852]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 27 15:45:50 volumio volumio[852]: info: Received Get System Version Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 27 15:45:50 volumio volumio[852]: info: Received Get System Info Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 27 15:45:50 volumio volumio[852]: info: Discovery: Getting this device information Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::volumioGetState Apr 27 15:45:50 volumio volumio[852]: info: CorePlayQueue::getTrack 0 Apr 27 15:45:50 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 27 15:45:54 volumio nmbd[596]: [2024/04/27 15:45:54.021704, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 27 15:45:54 volumio nmbd[596]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Apr 27 15:45:59 volumio volumio[852]: info: CoreCommandRouter::volumioGetQueue Apr 27 15:45:59 volumio volumio[852]: info: CoreStateMachine::getQueue Apr 27 15:45:59 volumio volumio[852]: info: CorePlayQueue::getQueue Apr 27 15:46:00 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 15:46:00 volumio volumio[852]: info: Preload queue cleared Apr 27 15:46:08 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 15:46:08 volumio volumio[852]: info: Preload queue cleared Apr 27 15:46:12 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 15:46:12 volumio volumio[852]: info: Preload queue cleared Apr 27 15:46:27 volumio volumio[852]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 27 15:46:28 volumio volumio[852]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 15:46:28 volumio volumio[852]: TypeError: Cannot read property 'length' of undefined Apr 27 15:46:28 volumio volumio[852]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 27 15:46:28 volumio volumio[852]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 27 15:46:28 volumio volumio[852]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 27 15:46:28 volumio volumio[852]: at Parser.emit (events.js:400:28) Apr 27 15:46:28 volumio volumio[852]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 27 15:46:28 volumio volumio[852]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 27 15:46:28 volumio volumio[852]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 27 15:46:28 volumio volumio[852]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 27 15:46:28 volumio volumio[852]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 27 15:46:28 volumio volumio[852]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 27 15:46:28 volumio volumio[852]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 27 15:46:28 volumio volumio[852]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 27 15:46:28 volumio volumio[852]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 27 15:46:28 volumio volumio[852]: at IncomingMessage.emit (events.js:412:35) Apr 27 15:46:28 volumio volumio[852]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 27 15:46:28 volumio volumio[852]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 27 15:46:28 volumio volumio[852]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 27 15:46:28 volumio sudo[2921]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-27 15:45 Apr 27 15:46:28 volumio sudo[2921]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="7b9cb4a8b52fb7148a38d6ac651c1c29e09bb92c" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="b1dcc17f6d31bf91f0abd3c3fa57506d1e0d2b6b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 01 Mar 2024 08:52:33 PM CET" VOLUMIO_VERSION="3.634" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="93edfc70711724967af109c0a39fa069"