-- Logs begin at Wed 2024-08-28 16:23:34 CST, end at Wed 2024-08-28 16:28:03 CST. -- Aug 28 16:27:08 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-BEACON-LOSS Aug 28 16:27:09 volumio dhcpcd[919]: wlan0: carrier lost Aug 28 16:27:09 volumio kernel: wlan0: Connection to AP f0:9f:c2:ae:91:a5 lost Aug 28 16:27:09 volumio avahi-daemon[797]: Withdrawing address record for 192.168.100.65 on wlan0. Aug 28 16:27:09 volumio avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.65. Aug 28 16:27:09 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:ae:91:a5 reason=4 locally_generated=1 Aug 28 16:27:09 volumio avahi-daemon[797]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 28 16:27:09 volumio volumio[1254]: info: Discovery: A device disappeared from network Aug 28 16:27:09 volumio volumio[1254]: info: Discovery: Device volumio disappeared from network Aug 28 16:27:09 volumio dhcpcd[919]: wlan0: deleting route to 192.168.100.0/24 Aug 28 16:27:09 volumio dhcpcd[919]: wlan0: deleting default route via 192.168.100.1 Aug 28 16:27:09 volumio nmbd[981]: [2024/08/28 16:27:09.957809, 0] ../source3/libsmb/nmblib.c:917(send_udp) Aug 28 16:27:09 volumio nmbd[981]: Packet send failed to 192.168.100.255(138) ERRNO=Network is unreachable Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: SME: Trying to authenticate with f0:9f:c2:ae:91:a5 (SSID='unifi' freq=5180 MHz) Aug 28 16:27:10 volumio kernel: wlan0: authenticate with f0:9f:c2:ae:91:a5 Aug 28 16:27:10 volumio kernel: wlan0: send auth to f0:9f:c2:ae:91:a5 (try 1/3) Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: Trying to associate with f0:9f:c2:ae:91:a5 (SSID='unifi' freq=5180 MHz) Aug 28 16:27:10 volumio kernel: wlan0: authenticated Aug 28 16:27:10 volumio kernel: wlan0: associate with f0:9f:c2:ae:91:a5 (try 1/3) Aug 28 16:27:10 volumio kernel: wlan0: RX AssocResp from f0:9f:c2:ae:91:a5 (capab=0x11 status=0 aid=1) Aug 28 16:27:10 volumio kernel: wlan0: associated Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: Associated with f0:9f:c2:ae:91:a5 Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: WPA: Key negotiation completed with f0:9f:c2:ae:91:a5 [PTK=CCMP GTK=CCMP] Aug 28 16:27:10 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:9f:c2:ae:91:a5 completed [id=0 id_str=] Aug 28 16:27:10 volumio dhcpcd[919]: wlan0: carrier acquired Aug 28 16:27:10 volumio dhcpcd[919]: wlan0: IAID 15:ac:11:15 Aug 28 16:27:10 volumio dhcpcd[919]: wlan0: rebinding lease of 192.168.100.65 Aug 28 16:27:10 volumio dhcpcd[919]: wlan0: soliciting an IPv6 router Aug 28 16:27:10 volumio dhcpcd[919]: wlan0: probing address 192.168.100.65/24 Aug 28 16:27:11 volumio ntpd[1301]: Deleting interface #3 wlan0, 192.168.100.65#123, interface stats: received=145, sent=153, dropped=0, active_time=200 secs Aug 28 16:27:11 volumio ntpd[1301]: 202.118.1.81 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 193.182.111.12 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 162.159.200.123 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 202.112.31.197 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 119.28.206.193 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 162.159.200.1 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 84.16.67.12 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 202.118.1.130 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 78.46.102.180 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 193.182.111.14 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 119.28.183.184 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 172.105.240.243 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 202.112.29.82 local addr 192.168.100.65 -> Aug 28 16:27:11 volumio ntpd[1301]: 45.76.221.157 local addr 192.168.100.65 -> Aug 28 16:27:15 volumio dhcpcd[919]: wlan0: leased 192.168.100.65 for 1800 seconds Aug 28 16:27:15 volumio avahi-daemon[797]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.65. Aug 28 16:27:15 volumio avahi-daemon[797]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 16:27:15 volumio dhcpcd[919]: wlan0: adding route to 192.168.100.0/24 Aug 28 16:27:15 volumio dhcpcd[919]: wlan0: adding default route via 192.168.100.1 Aug 28 16:27:15 volumio avahi-daemon[797]: Registering new address record for 192.168.100.65 on wlan0.IPv4. Aug 28 16:27:15 volumio ntpd[1301]: ntpd exiting on signal 15 (Terminated) Aug 28 16:27:15 volumio systemd[1]: Stopping Network Time Service... Aug 28 16:27:15 volumio systemd[1]: ntp.service: Succeeded. Aug 28 16:27:15 volumio systemd[1]: Stopped Network Time Service. Aug 28 16:27:15 volumio systemd[1]: Starting Network Time Service... Aug 28 16:27:15 volumio ntpd[2299]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 16:27:15 volumio ntpd[2299]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 28 16:27:15 volumio systemd[1]: Started Network Time Service. Aug 28 16:27:15 volumio ntpd[2305]: proto: precision = 0.056 usec (-24) Aug 28 16:27:15 volumio ntpd[2305]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 16:27:15 volumio ntpd[2305]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 16:27:15 volumio ntpd[2305]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 610 days ago Aug 28 16:27:15 volumio ntpd[2305]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 16:27:15 volumio ntpd[2305]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 16:27:15 volumio ntpd[2305]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 16:27:15 volumio ntpd[2305]: Listen normally on 3 wlan0 192.168.100.65:123 Aug 28 16:27:15 volumio ntpd[2305]: Listening on routing socket on fd #20 for interface updates Aug 28 16:27:15 volumio ntpd[2305]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 28 16:27:15 volumio ntpd[2305]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio shairport-sync[2057]: {"time":1724833545949,"response":"startAirplayPlayback Success"}Very large sync error: 174295 frames, with delay: 262495, td_in_frames: 283056, rt: 8027724938, nt: 8027834004, current_delay: 88505, seqno: 52013, given timestamp: 3732866708. Aug 28 16:27:15 volumio shairport-sync[2057]: Very large sync error: 174291 frames, with delay: 262491, td_in_frames: 283720, rt: 8027724938, nt: 8027834356, current_delay: 88189, seqno: 52014, given timestamp: 3732867060. Aug 28 16:27:15 volumio shairport-sync[2057]: Very large sync error: 174292 frames, with delay: 262492, td_in_frames: 283722, rt: 8027724938, nt: 8027834708, current_delay: 88540, seqno: 52015, given timestamp: 3732867412. Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio shairport-sync[2057]: Very large sync error: 174284 frames, with delay: 262484, td_in_frames: 284156, rt: 8027724938, nt: 8027835060, current_delay: 88450, seqno: 52016, given timestamp: 3732867764. Aug 28 16:27:15 volumio shairport-sync[2057]: Shome mhistake shurely: very large number of frames to drop: 174284 -- setting it to 132300. Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 28 16:27:15 volumio volumio[1254]: info: Retrying Login Due to Network Error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:15 volumio volumio[1254]: error: error Aug 28 16:27:16 volumio volumio[1254]: error: error Aug 28 16:27:16 volumio volumio[1254]: error: error Aug 28 16:27:16 volumio volumio[1254]: info: Discovery: adding 7e86a38c-64a4-4462-bc56-16631640bc6b Aug 28 16:27:16 volumio volumio[1254]: info: Discovery: Found device Volumio Aug 28 16:27:16 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 28 16:27:16 volumio ntpd[2305]: Soliciting pool server 211.68.71.118 Aug 28 16:27:17 volumio ntpd[2305]: Soliciting pool server 202.112.31.197 Aug 28 16:27:17 volumio ntpd[2305]: Soliciting pool server 202.112.29.82 Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:17 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:18 volumio ntpd[2305]: Soliciting pool server 202.118.1.130 Aug 28 16:27:18 volumio ntpd[2305]: Soliciting pool server 111.203.6.13 Aug 28 16:27:18 volumio dhcpcd[919]: wlan0: carrier lost Aug 28 16:27:18 volumio kernel: wlan0: Connection to AP f0:9f:c2:ae:91:a5 lost Aug 28 16:27:18 volumio avahi-daemon[797]: Withdrawing address record for 192.168.100.65 on wlan0. Aug 28 16:27:18 volumio avahi-daemon[797]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.65. Aug 28 16:27:18 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-DISCONNECTED bssid=f0:9f:c2:ae:91:a5 reason=4 locally_generated=1 Aug 28 16:27:18 volumio avahi-daemon[797]: Interface wlan0.IPv4 no longer relevant for mDNS. Aug 28 16:27:18 volumio volumio[1254]: info: Discovery: A device disappeared from network Aug 28 16:27:18 volumio volumio[1254]: info: Discovery: Device volumio disappeared from network Aug 28 16:27:18 volumio dhcpcd[919]: wlan0: deleting route to 192.168.100.0/24 Aug 28 16:27:18 volumio dhcpcd[919]: wlan0: deleting default route via 192.168.100.1 Aug 28 16:27:19 volumio ntpd[2305]: Soliciting pool server 193.182.111.14 Aug 28 16:27:19 volumio ntpd[2305]: Soliciting pool server 78.46.102.180 Aug 28 16:27:19 volumio wpa_supplicant[1170]: wlan0: SME: Trying to authenticate with f0:9f:c2:ad:91:a5 (SSID='unifi' freq=2437 MHz) Aug 28 16:27:19 volumio kernel: wlan0: authenticate with f0:9f:c2:ad:91:a5 Aug 28 16:27:19 volumio kernel: wlan0: 80 MHz not supported, disabling VHT Aug 28 16:27:19 volumio kernel: wlan0: send auth to f0:9f:c2:ad:91:a5 (try 1/3) Aug 28 16:27:19 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-AUTH-REJECT f0:9f:c2:ad:91:a5 auth_type=0 auth_transaction=2 status_code=82 ie=340df09fc2ae91a500000000802409 Aug 28 16:27:19 volumio kernel: wlan0: f0:9f:c2:ad:91:a5 denied authentication (status 82) Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: SME: Trying to authenticate with f0:9f:c2:ae:91:a5 (SSID='unifi' freq=5180 MHz) Aug 28 16:27:20 volumio kernel: wlan0: authenticate with f0:9f:c2:ae:91:a5 Aug 28 16:27:20 volumio kernel: wlan0: send auth to f0:9f:c2:ae:91:a5 (try 1/3) Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: Trying to associate with f0:9f:c2:ae:91:a5 (SSID='unifi' freq=5180 MHz) Aug 28 16:27:20 volumio kernel: wlan0: authenticated Aug 28 16:27:20 volumio kernel: wlan0: associate with f0:9f:c2:ae:91:a5 (try 1/3) Aug 28 16:27:20 volumio kernel: wlan0: RX AssocResp from f0:9f:c2:ae:91:a5 (capab=0x11 status=0 aid=1) Aug 28 16:27:20 volumio kernel: wlan0: associated Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: Associated with f0:9f:c2:ae:91:a5 Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: WPA: Key negotiation completed with f0:9f:c2:ae:91:a5 [PTK=CCMP GTK=CCMP] Aug 28 16:27:20 volumio wpa_supplicant[1170]: wlan0: CTRL-EVENT-CONNECTED - Connection to f0:9f:c2:ae:91:a5 completed [id=0 id_str=] Aug 28 16:27:20 volumio dhcpcd[919]: wlan0: carrier acquired Aug 28 16:27:20 volumio dhcpcd[919]: wlan0: IAID 15:ac:11:15 Aug 28 16:27:21 volumio dhcpcd[919]: wlan0: soliciting an IPv6 router Aug 28 16:27:21 volumio dhcpcd[919]: wlan0: rebinding lease of 192.168.100.65 Aug 28 16:27:21 volumio dhcpcd[919]: wlan0: probing address 192.168.100.65/24 Aug 28 16:27:22 volumio ntpd[2305]: Deleting interface #3 wlan0, 192.168.100.65#123, interface stats: received=5, sent=5, dropped=4, active_time=7 secs Aug 28 16:27:22 volumio ntpd[2305]: 202.118.1.130 local addr 192.168.100.65 -> Aug 28 16:27:22 volumio ntpd[2305]: 111.203.6.13 local addr 192.168.100.65 -> Aug 28 16:27:22 volumio ntpd[2305]: 202.112.29.82 local addr 192.168.100.65 -> Aug 28 16:27:22 volumio ntpd[2305]: 202.112.31.197 local addr 192.168.100.65 -> Aug 28 16:27:22 volumio ntpd[2305]: 211.68.71.118 local addr 192.168.100.65 -> Aug 28 16:27:23 volumio ntpd[2305]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Aug 28 16:27:23 volumio ntpd[2305]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Aug 28 16:27:25 volumio volumio[1254]: info: MyVolumio login type: Token Aug 28 16:27:25 volumio volumio[1254]: error: MyVolumio got a network error: A network error (such as timeout, interrupted connection or unreachable host) has occurred. Aug 28 16:27:25 volumio volumio[1254]: info: Retrying Login Due to Network Error Aug 28 16:27:26 volumio dhcpcd[919]: wlan0: leased 192.168.100.65 for 1800 seconds Aug 28 16:27:26 volumio avahi-daemon[797]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.100.65. Aug 28 16:27:26 volumio dhcpcd[919]: wlan0: adding route to 192.168.100.0/24 Aug 28 16:27:26 volumio avahi-daemon[797]: New relevant interface wlan0.IPv4 for mDNS. Aug 28 16:27:26 volumio dhcpcd[919]: wlan0: adding default route via 192.168.100.1 Aug 28 16:27:26 volumio avahi-daemon[797]: Registering new address record for 192.168.100.65 on wlan0.IPv4. Aug 28 16:27:26 volumio ntpd[2305]: ntpd exiting on signal 15 (Terminated) Aug 28 16:27:26 volumio systemd[1]: Stopping Network Time Service... Aug 28 16:27:26 volumio systemd[1]: ntp.service: Succeeded. Aug 28 16:27:26 volumio systemd[1]: Stopped Network Time Service. Aug 28 16:27:26 volumio systemd[1]: Starting Network Time Service... Aug 28 16:27:26 volumio ntpd[2379]: ntpd 4.2.8p12@1.3728-o (1): Starting Aug 28 16:27:26 volumio ntpd[2379]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Aug 28 16:27:26 volumio systemd[1]: Started Network Time Service. Aug 28 16:27:26 volumio ntpd[2385]: proto: precision = 0.058 usec (-24) Aug 28 16:27:26 volumio ntpd[2385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Aug 28 16:27:26 volumio ntpd[2385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Aug 28 16:27:26 volumio ntpd[2385]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 610 days ago Aug 28 16:27:26 volumio ntpd[2385]: Listen and drop on 0 v6wildcard [::]:123 Aug 28 16:27:26 volumio ntpd[2385]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Aug 28 16:27:26 volumio ntpd[2385]: Listen normally on 2 lo 127.0.0.1:123 Aug 28 16:27:26 volumio ntpd[2385]: Listen normally on 3 wlan0 192.168.100.65:123 Aug 28 16:27:26 volumio ntpd[2385]: Listening on routing socket on fd #20 for interface updates Aug 28 16:27:26 volumio ntpd[2385]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 28 16:27:26 volumio ntpd[2385]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::servicePushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreStateMachine::pushState Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioPushState Aug 28 16:27:26 volumio volumio[1254]: info: Discovery: adding 7e86a38c-64a4-4462-bc56-16631640bc6b Aug 28 16:27:26 volumio volumio[1254]: info: Discovery: Found device Volumio Aug 28 16:27:26 volumio volumio[1254]: info: CoreCommandRouter::volumioGetState Aug 28 16:27:27 volumio ntpd[2385]: Soliciting pool server 202.112.31.197 Aug 28 16:27:27 volumio shairport-sync[2057]: Very large sync error: 247905 frames, with delay: 336105, td_in_frames: 479841, rt: 8028077738, nt: 8028309908, current_delay: 88434, seqno: 53365, given timestamp: 3733342612. Aug 28 16:27:27 volumio shairport-sync[2057]: Very large sync error: 247902 frames, with delay: 336102, td_in_frames: 480059, rt: 8028077738, nt: 8028310260, current_delay: 88565, seqno: 53366, given timestamp: 3733342964. Aug 28 16:27:27 volumio shairport-sync[2057]: Very large sync error: 247902 frames, with delay: 336102, td_in_frames: 480501, rt: 8028077738, nt: 8028310612, current_delay: 88475, seqno: 53367, given timestamp: 3733343316. Aug 28 16:27:27 volumio shairport-sync[2057]: Very large sync error: 247899 frames, with delay: 336099, td_in_frames: 480940, rt: 8028077738, nt: 8028310964, current_delay: 88385, seqno: 53368, given timestamp: 3733343668. Aug 28 16:27:27 volumio shairport-sync[2057]: Shome mhistake shurely: very large number of frames to drop: 247899 -- setting it to 132300. Aug 28 16:27:28 volumio ntpd[2385]: Soliciting pool server 111.203.6.13 Aug 28 16:27:28 volumio ntpd[2385]: Soliciting pool server 202.118.1.130 Aug 28 16:27:29 volumio ntpd[2385]: Soliciting pool server 193.182.111.14 Aug 28 16:27:29 volumio ntpd[2385]: Soliciting pool server 78.46.102.180 Aug 28 16:27:29 volumio ntpd[2385]: Soliciting pool server 185.209.85.222 Aug 28 16:27:30 volumio ntpd[2385]: Soliciting pool server 162.159.200.1 Aug 28 16:27:30 volumio ntpd[2385]: Soliciting pool server 172.105.240.243 Aug 28 16:27:30 volumio ntpd[2385]: Soliciting pool server 211.68.71.118 Aug 28 16:27:30 volumio ntpd[2385]: Soliciting pool server 202.118.1.81 Aug 28 16:27:31 volumio ntpd[2385]: Soliciting pool server 45.76.221.157 Aug 28 16:27:31 volumio ntpd[2385]: Soliciting pool server 162.159.200.123 Aug 28 16:27:31 volumio ntpd[2385]: Soliciting pool server 202.112.29.82 Aug 28 16:27:32 volumio ntpd[2385]: Soliciting pool server 119.28.183.184 Aug 28 16:27:32 volumio ntpd[2385]: Soliciting pool server 119.28.206.193 Aug 28 16:27:33 volumio ntpd[2385]: Soliciting pool server 117.80.112.205 Aug 28 16:27:33 volumio ntpd[2385]: Soliciting pool server 2001:da8:9000::130 Aug 28 16:27:35 volumio volumio[1254]: info: MyVolumio login type: Token Aug 28 16:28:03 volumio volumio[1254]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:28:03 volumio volumio[1254]: Error: connect ETIMEDOUT 199.59.148.201:443 Aug 28 16:28:03 volumio volumio[1254]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Aug 28 16:28:03 volumio volumio[1254]: errno: -110, Aug 28 16:28:03 volumio volumio[1254]: code: 'ETIMEDOUT', Aug 28 16:28:03 volumio volumio[1254]: syscall: 'connect', Aug 28 16:28:03 volumio volumio[1254]: address: '199.59.148.201', Aug 28 16:28:03 volumio volumio[1254]: port: 443 Aug 28 16:28:03 volumio volumio[1254]: } Aug 28 16:28:03 volumio volumio[1254]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 16:28:03 volumio sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-28 16:27 Aug 28 16:28:03 volumio sudo[2446]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"