home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.79k stars 959 forks source link

HAOS shutting down after a while after upgrading to 6.0 #1409

Closed dewitpj closed 3 years ago

dewitpj commented 3 years ago

Hardware Environment

Home Assistant OS release:

agners commented 3 years ago

Hm, from the logs I can't see why the system would shut down. How much memory do you assign the VM?

Can you login on the terminal and try to get the journal from the last boot?

journalctl -b -1
dewitpj commented 3 years ago

Hey,

I have 2gig assigned (can bump to 4 if needed) - 566 meg used according to "free". I don't have journalcrl for some reason. Is it included in the base image ?

Thanks

agners commented 3 years ago

@dewitpj journalctl is only accessible from the OS shell, either via terminal or by using SSH to connect to the underlying OS (see https://developers.home-assistant.io/docs/operating-system/debugging).

piio commented 3 years ago

Hi,

I got similar problem - after updating OS to 6.0 after some time host stops responding for ping on wifi connection. I configured wired connection and it looks it is available after loss of wifi.

In journal I see something strange: Jun 16 17:21:32 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:4d:28:f9:af:97 reason=2 Jun 16 17:21:32 home-rpi wpa_supplicant[342]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0 Jun 16 17:21:32 home-rpi NetworkManager[334]: [1623864092.4304] sup-iface[0x3aa380,wlan0]: connection disconnected (reason 2)

Larger journal log: 16 17:21:27 home-rpi kernel: audit: type=1325 audit(1623864087.801:184): table=nat family=2 entries=13 op=xt_replace pid=4808 subj==unconfined comm="iptables" Jun 16 17:21:27 home-rpi audit[4809]: NETFILTER_CFG table=nat family=2 entries=14 op=xt_replace pid=4809 subj==unconfined comm="iptables" Jun 16 17:21:28 home-rpi kernel: eth0: renamed from veth4be4a5f Jun 16 17:21:28 home-rpi NetworkManager[334]: [1623864088.0876] device (vethf79915f): carrier: link connected Jun 16 17:21:28 home-rpi kernel: IPv6: ADDRCONF(NETDEV_CHANGE): vethf79915f: link becomes ready Jun 16 17:21:28 home-rpi kernel: hassio: port 8(vethf79915f) entered blocking state Jun 16 17:21:28 home-rpi kernel: hassio: port 8(vethf79915f) entered forwarding state Jun 16 17:21:28 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:28 INFO (SyncWorker_5) [supervisor.docker.addon] Starting Docker add-on homeassistant/armv7-addon-configurator with version 5.3.1 Jun 16 17:21:28 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:28 INFO (SyncWorker_5) [supervisor.docker.addon] Starting Docker add-on homeassistant/armv7-addon-configurator with version 5.3.1 Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [s6-init] making user provided files available at /var/run/s6/etc...exited 0. Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [s6-init] ensuring user provided files have correct perms...exited 0. Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [fix-attrs.d] applying ownership & permissions fixes... Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [fix-attrs.d] done. Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [cont-init.d] executing container initialization scripts... Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [cont-init.d] done. Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [services.d] starting services Jun 16 17:21:28 home-rpi 69d69b86ebfb[467]: [services.d] done. Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /core/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /supervisor/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 37e95124579a[467]: 06-16 19:21:30 INFO Starting server on port 8099 Jun 16 17:21:30 home-rpi 37e95124579a[467]: 06-16 19:21:30 INFO Server started Jun 16 17:21:30 home-rpi 37e95124579a[467]: 06-16 19:21:30 INFO Syncing Snapshots Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/3441155b/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/3441155b/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/c80bd594/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/c80bd594/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/8e2f7a51/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/8e2f7a51/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/583cc028/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/583cc028/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/8182a405/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/8182a405/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/7305bd8e/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/7305bd8e/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/1eaf4191/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:30 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:30 INFO (MainThread) [supervisor.api.middleware.security] /snapshots/1eaf4191/info access from cebe7a76_hassio_google_drive_backup Jun 16 17:21:32 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:4d:28:f9:af:97 reason=2 Jun 16 17:21:32 home-rpi wpa_supplicant[342]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0 Jun 16 17:21:32 home-rpi NetworkManager[334]: [1623864092.4304] sup-iface[0x3aa380,wlan0]: connection disconnected (reason 2) Jun 16 17:21:32 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 16 17:21:32 home-rpi NetworkManager[334]: [1623864092.4437] device (wlan0): supplicant interface state: completed -> disconnected Jun 16 17:21:32 home-rpi NetworkManager[334]: [1623864092.5349] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:21:32 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:21:32 home-rpi NetworkManager[334]: [1623864092.5992] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:21:33 home-rpi 69d69b86ebfb[467]: INFO:2021-06-16 19:21:33,042:hass_configurator.configurator:Starting server Jun 16 17:21:33 home-rpi 69d69b86ebfb[467]: INFO:2021-06-16 19:21:33,045:hass_configurator.configurator:Listening on: http://0.0.0.0:8099 Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.core] Supervisor is up and running Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.core] Supervisor is up and running Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.host.info] Updating local host information Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.host.info] Updating local host information Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON Jun 16 17:21:33 home-rpi dbus-daemon[104]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.111' (uid=0 pid=5039 comm="gdbus call --system --dest org.freedesktop.hostnam") Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.check] System checks complete Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.check] System checks complete Jun 16 17:21:33 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING Jun 16 17:21:33 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:33 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING Jun 16 17:21:33 home-rpi audit: BPF prog-id=15 op=LOAD Jun 16 17:21:33 home-rpi audit: BPF prog-id=16 op=LOAD Jun 16 17:21:33 home-rpi systemd[1]: Starting Hostname Service... ░░ Subject: A start job for unit systemd-hostnamed.service has begun execution ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit systemd-hostnamed.service has begun execution. ░░ ░░ The job identifier is 1103. Jun 16 17:21:33 home-rpi kernel: kauditd_printk_skb: 1 callbacks suppressed Jun 16 17:21:33 home-rpi kernel: audit: type=1334 audit(1623864093.531:186): prog-id=15 op=LOAD Jun 16 17:21:33 home-rpi kernel: audit: type=1334 audit(1623864093.531:187): prog-id=16 op=LOAD Jun 16 17:21:34 home-rpi dbus-daemon[104]: [system] Successfully activated service 'org.freedesktop.hostname1' Jun 16 17:21:34 home-rpi systemd[1]: Started Hostname Service. ░░ Subject: A start job for unit systemd-hostnamed.service has finished successfully ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit systemd-hostnamed.service has finished successfully. ░░ ░░ The job identifier is 1103. Jun 16 17:21:34 home-rpi dbus-daemon[104]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.113' (uid=0 pid=5052 comm="gdbus call --system --dest org.freedesktop.timedat") Jun 16 17:21:34 home-rpi audit: BPF prog-id=17 op=LOAD Jun 16 17:21:34 home-rpi audit: BPF prog-id=18 op=LOAD Jun 16 17:21:34 home-rpi kernel: audit: type=1334 audit(1623864094.161:188): prog-id=17 op=LOAD Jun 16 17:21:34 home-rpi kernel: audit: type=1334 audit(1623864094.161:189): prog-id=18 op=LOAD Jun 16 17:21:34 home-rpi systemd[1]: Starting Time & Date Service... ░░ Subject: A start job for unit systemd-timedated.service has begun execution ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit systemd-timedated.service has begun execution. ░░ ░░ The job identifier is 1203. Jun 16 17:21:34 home-rpi dbus-daemon[104]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 16 17:21:34 home-rpi systemd[1]: Started Time & Date Service. ░░ Subject: A start job for unit systemd-timedated.service has finished successfully ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit systemd-timedated.service has finished successfully. ░░ ░░ The job identifier is 1203. Jun 16 17:21:34 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:34 INFO (MainThread) [supervisor.host.services] Updating service information Jun 16 17:21:34 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:34 INFO (MainThread) [supervisor.host.services] Updating service information Jun 16 17:21:35 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:35 INFO (MainThread) [supervisor.host.network] Updating local network information Jun 16 17:21:35 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:35 INFO (MainThread) [supervisor.host.network] Updating local network information Jun 16 17:21:36 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 16 17:21:36 home-rpi NetworkManager[334]: [1623864096.1947] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:21:36 home-rpi NetworkManager[334]: [1623864096.2947] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:21:38 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:38 WARNING (MainThread) [supervisor.updater] Can't fetch versions from https://version.home-assistant.io/stable.json: Cannot connect to host version.home-assistant.io:443 ssl:default [Try again] Jun 16 17:21:38 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:38 WARNING (MainThread) [supervisor.updater] Can't fetch versions from https://version.home-assistant.io/stable.json: Cannot connect to host version.home-assistant.io:443 ssl:default [Try again] Jun 16 17:21:38 home-rpi NetworkManager[334]: [1623864098.7381] manager: NetworkManager state is now CONNECTED_SITE Jun 16 17:21:38 home-rpi dbus-daemon[104]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.7' (uid=0 pid=334 comm="/usr/sbin/NetworkManager --no-daemon ") Jun 16 17:21:38 home-rpi systemd[1]: Starting Network Manager Script Dispatcher Service... ░░ Subject: A start job for unit NetworkManager-dispatcher.service has begun execution ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit NetworkManager-dispatcher.service has begun execution. ░░ ░░ The job identifier is 1303. Jun 16 17:21:38 home-rpi dbus-daemon[104]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' Jun 16 17:21:38 home-rpi systemd[1]: Started Network Manager Script Dispatcher Service. ░░ Subject: A start job for unit NetworkManager-dispatcher.service has finished successfully ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ A start job for unit NetworkManager-dispatcher.service has finished successfully. ░░ ░░ The job identifier is 1303. Jun 16 17:21:38 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:43505 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 6.278541531s Jun 16 17:21:38 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: connect: no route to host Jun 16 17:21:39 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:21:39 home-rpi NetworkManager[334]: [1623864099.1332] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33975 - 13313 "NS IN . udp 17 false 512" NOERROR - 0 7.40075954s Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: connect: no route to host Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:45834 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 5.402359663s Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: connect: no route to host Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:47785 - 43707 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 5.861307701s Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.0.0.1:853: connect: no route to host Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:53353 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 8.832926653s Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: connect: no route to host Jun 16 17:21:42 home-rpi 4a745889fe82[467]: 2021-06-16 19:21:42 WARNING (MainThread) [homeassistant.components.zwave] Z-Wave node 1 not ready after 30 seconds, continuing anyway Jun 16 17:21:42 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 16 17:21:42 home-rpi NetworkManager[334]: [1623864102.4157] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:21:42 home-rpi NetworkManager[334]: [1623864102.5160] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:48374 - 55167 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 12.005725356s Jun 16 17:21:42 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:38010->127.0.0.1:5553: i/o timeout Jun 16 17:21:43 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:40823 - 45713 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 12.007933457s Jun 16 17:21:43 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:44639->127.0.0.1:5553: i/o timeout Jun 16 17:21:43 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:43 ERROR (MainThread) [supervisor.utils.codenotary] Timeout while processing CodeNotary Jun 16 17:21:43 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:43 ERROR (MainThread) [supervisor.utils.codenotary] Timeout while processing CodeNotary Jun 16 17:21:43 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete Jun 16 17:21:43 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING Jun 16 17:21:43 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete Jun 16 17:21:43 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING Jun 16 17:21:43 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete Jun 16 17:21:43 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:43 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete Jun 16 17:21:44 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:56358 - 45713 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 8.004720736s Jun 16 17:21:44 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:37668->127.0.0.1:5553: i/o timeout Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:56401 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 7.139035395s Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: connect: no route to host Jun 16 17:21:45 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:21:45 home-rpi NetworkManager[334]: [1623864105.3485] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:49027 - 17650 "A IN version.home-assistant.io. udp 43 false 512" NOERROR - 0 12.006244948s Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: read udp 127.0.0.1:37058->127.0.0.1:5553: i/o timeout Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:37297 - 55167 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 10.005060866s Jun 16 17:21:45 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:47789->127.0.0.1:5553: i/o timeout Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:49027 - 17650 "A IN version.home-assistant.io. udp 43 false 512" NOERROR - 0 10.005362525s Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: read udp 127.0.0.1:58976->127.0.0.1:5553: i/o timeout Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:52844 - 43707 "AAAA IN main.codenotary.io. udp 36 false 512" NOERROR - 0 12.005655455s Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: read udp 127.0.0.1:47022->127.0.0.1:5553: i/o timeout Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:43434 - 15481 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004206327s Jun 16 17:21:46 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:49616->127.0.0.1:5553: i/o timeout Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.2506] device (wlan0): link timed out. Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.2509] device (wlan0): state change: activated -> failed (reason 'supplicant-timeout', sys-iface-state: 'managed') Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.2537] manager: NetworkManager state is now CONNECTED_LOCAL Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.2629] device (wlan0): Activation: failed for connection 'hassos-network' Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.2711] device (wlan0): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.3205] dhcp4 (wlan0): canceled DHCP transaction Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.3207] dhcp4 (wlan0): state changed bound -> done Jun 16 17:21:47 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:4d:28:f9:af:97 reason=3 locally_generated=1 Jun 16 17:21:47 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.3813] sup-iface[0x3aa380,wlan0]: connection disconnected (reason -3) Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.3834] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:21:47 home-rpi NetworkManager[334]: [1623864107.3838] sup-iface[0x3aa380,wlan0]: connection disconnected (reason -3) Jun 16 17:21:48 home-rpi add7739d256d[467]: mdns-repeater (3): send() Jun 16 17:21:48 home-rpi 4a745889fe82[467]: 2021-06-16 19:21:48 WARNING (zeroconf-Engine-243) [zeroconf] Error sending through socket 21 Jun 16 17:21:48 home-rpi 4a745889fe82[467]: Traceback (most recent call last): Jun 16 17:21:48 home-rpi 4a745889fe82[467]: File "/usr/local/lib/python3.8/site-packages/zeroconf/init.py", line 2948, in send Jun 16 17:21:48 home-rpi 4a745889fe82[467]: bytes_sent = s.sendto(packet, 0, (real_addr, port)) Jun 16 17:21:48 home-rpi 4a745889fe82[467]: OSError: [Errno 101] Network unreachable Jun 16 17:21:48 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:48 WARNING (MainThread) [supervisor.host.network] Can't update connectivity information: Error: Timeout was reached Jun 16 17:21:48 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:48 WARNING (MainThread) [supervisor.host.network] Can't update connectivity information: Error: Timeout was reached Jun 16 17:21:48 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:48 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information Jun 16 17:21:48 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:48 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information Jun 16 17:21:48 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:43434 - 15481 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004741933s Jun 16 17:21:48 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:59992->127.0.0.1:5553: i/o timeout Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: I: [pulseaudio] client.c: Created 0 "Native client (UNIX socket client)" Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: I: [pulseaudio] protocol-native.c: Client authenticated anonymously. Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: D: [pulseaudio] protocol-native.c: Protocol version: remote 34, local 34 Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: D: [pulseaudio] protocol-native.c: SHM possible: no Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: D: [pulseaudio] protocol-native.c: Negotiated SHM: no Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: D: [pulseaudio] protocol-native.c: Disabling srbchannel, reason: No SHM support Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: I: [pulseaudio] client.c: Freed 0 "supervisor" Jun 16 17:21:48 home-rpi dc66ebe20bcd[467]: I: [pulseaudio] protocol-native.c: Connection died. Jun 16 17:21:48 home-rpi 3c8b9ad8ec07[467]: 21-06-16 17:21:48 INFO (MainThread) [supervisor.host] Host information reload completed Jun 16 17:21:48 home-rpi hassos-supervisor[1229]: 21-06-16 17:21:48 INFO (MainThread) [supervisor.host] Host information reload completed Jun 16 17:21:49 home-rpi systemd[1]: NetworkManager-dispatcher.service: Succeeded. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ The unit NetworkManager-dispatcher.service has successfully entered the 'dead' state. Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:48196 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 11.585466066s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:52094 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 10.591394469s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:45076 - 33627 "NS IN . udp 17 false 512" NOERROR - 0 6.092340692s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:47789 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 6.092737892s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:48794 - 43707 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 9.732362165s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:60288 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 10.399294642s Jun 16 17:21:49 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:58976 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 6.092486582s Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2203] policy: auto-activating connection 'hassos-network' (72111c67-4a5d-4d5c-925e-f8ee26efb3c3) Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2225] device (wlan0): Activation: starting connection 'hassos-network' (72111c67-4a5d-4d5c-925e-f8ee26efb3c3) Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2230] device (wlan0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2246] manager: NetworkManager state is now CONNECTING Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2259] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2276] device (wlan0): Activation: (wifi) access point 'hassos-network' has security, but secrets are required. Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2277] device (wlan0): state change: config -> need-auth (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2418] device (wlan0): state change: need-auth -> prepare (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2449] device (wlan0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2475] device (wlan0): Activation: (wifi) connection 'hassos-network' has security, and secrets exist. No new secrets needed. Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2478] Config: added 'ssid' value 'NET-SSID' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2480] Config: added 'scan_ssid' value '1' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2483] Config: added 'bgscan' value 'simple:30:-70:86400' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2485] Config: added 'key_mgmt' value 'WPA-PSK WPA-PSK-SHA256 FT-PSK' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2485] Config: added 'auth_alg' value 'OPEN' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2487] Config: added 'psk' value '' Jun 16 17:21:50 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:21:50 home-rpi NetworkManager[334]: [1623864110.2863] device (wlan0): supplicant interface state: disconnected -> associating Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:38010 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 9.931751009s Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:34364 - 43707 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 8.527242154s Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:57383 - 53434 "NS IN . udp 17 false 512" NOERROR - 0 6.092836877s Jun 16 17:21:50 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:49661 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 12.375114198s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:50964 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 11.806027777s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:37668 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 8.528016189s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:57478 - 15481 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 8.527563347s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36722 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 9.667462872s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:35941 - 16410 "NS IN . udp 17 false 512" NOERROR - 0 12.676972703s Jun 16 17:21:51 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:37058 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 8.527363704999999s Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:45234 - 46796 "AAAA IN main.codenotary.io. udp 36 false 512" NOERROR - 0 8.004287984s Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: read udp 127.0.0.1:33514->127.0.0.1:5553: i/o timeout Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:51299 - 12659 "NS IN . udp 17 false 512" NOERROR - 0 6.092528908s Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:56591 - 15481 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 12.070911658s Jun 16 17:21:52 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:53 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 16 17:21:53 home-rpi NetworkManager[334]: [1623864113.5257] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:47022 - 43707 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 9.295627466s Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33318 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 5.966850014s Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:40214 - 55167 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 8.005599754s Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:37306->127.0.0.1:5553: i/o timeout Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36391 - 24958 "NS IN . udp 17 false 512" NOERROR - 0 6.163778844s Jun 16 17:21:53 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:45143 - 46796 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 7.9549287159999995s Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:44639 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 13.173597451s Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:54 home-rpi NetworkManager[334]: [1623864114.5267] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:34431 - 45713 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 8.004567791s Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:33598->127.0.0.1:5553: i/o timeout Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:59992 - 15481 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 7.955046974s Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:43329 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 6.163599052s Jun 16 17:21:54 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33636 - 45403 "NS IN . udp 17 false 512" NOERROR - 0 5.966693034s Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:49616 - 15481 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 11.05976023s Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:34408 - 31675 "NS IN . udp 17 false 512" NOERROR - 0 13.750136957s Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:51271 - 13697 "NS IN . udp 17 false 512" NOERROR - 0 15.251424521s Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:49164 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 18.177770841s Jun 16 17:21:55 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:21:57 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:21:57 home-rpi NetworkManager[334]: [1623864117.3500] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:22:00 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36105 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 18.977052434s Jun 16 17:22:00 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:00 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36835 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 18.474736031s Jun 16 17:22:00 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:00 home-rpi wpa_supplicant[342]: wlan0: Associated with 76:4d:28:f9:af:97 Jun 16 17:22:00 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-CONNECTED - Connection to 76:4d:28:f9:af:97 completed [id=0 id_str=] Jun 16 17:22:00 home-rpi kernel: IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready Jun 16 17:22:00 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 17:22:00 home-rpi NetworkManager[334]: [1623864120.8358] device (wlan0): supplicant interface state: associating -> completed Jun 16 17:22:00 home-rpi NetworkManager[334]: [1623864120.8359] device (wlan0): Activation: (wifi) Stage 2 of 5 (Device Configure) successful. Connected to wireless network "NET-SSID" Jun 16 17:22:00 home-rpi NetworkManager[334]: [1623864120.8367] device (wlan0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') Jun 16 17:22:00 home-rpi NetworkManager[334]: [1623864120.8438] dhcp4 (wlan0): activation: beginning transaction (timeout in 45 seconds) Jun 16 17:22:03 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33514 - 46796 "AAAA IN main.codenotary.io. udp 47 true 2048" NOERROR - 0 13.008671814s Jun 16 17:22:03 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 main.codenotary.io. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:03 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:56110 - 25715 "NS IN . udp 17 false 512" NOERROR - 0 13.008694938s Jun 16 17:22:03 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:04 home-rpi systemd[1]: systemd-hostnamed.service: Succeeded. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ The unit systemd-hostnamed.service has successfully entered the 'dead' state. Jun 16 17:22:04 home-rpi audit: BPF prog-id=16 op=UNLOAD Jun 16 17:22:04 home-rpi audit: BPF prog-id=15 op=UNLOAD Jun 16 17:22:04 home-rpi kernel: audit: type=1334 audit(1623864124.181:190): prog-id=16 op=UNLOAD Jun 16 17:22:04 home-rpi kernel: audit: type=1334 audit(1623864124.181:191): prog-id=15 op=UNLOAD Jun 16 17:22:04 home-rpi systemd[1]: systemd-timedated.service: Succeeded. ░░ Subject: Unit succeeded ░░ Defined-By: systemd ░░ Support: https://lists.freedesktop.org/mailman/listinfo/systemd-devel ░░ ░░ The unit systemd-timedated.service has successfully entered the 'dead' state. Jun 16 17:22:04 home-rpi audit: BPF prog-id=18 op=UNLOAD Jun 16 17:22:04 home-rpi audit: BPF prog-id=17 op=UNLOAD Jun 16 17:22:04 home-rpi kernel: audit: type=1334 audit(1623864124.741:192): prog-id=18 op=UNLOAD Jun 16 17:22:04 home-rpi kernel: audit: type=1334 audit(1623864124.741:193): prog-id=17 op=UNLOAD Jun 16 17:22:04 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:4d:28:f9:af:97 reason=6 Jun 16 17:22:04 home-rpi wpa_supplicant[342]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0 Jun 16 17:22:04 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:22:04 home-rpi NetworkManager[334]: [1623864124.7956] sup-iface[0x3aa380,wlan0]: connection disconnected (reason 6) Jun 16 17:22:04 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 16 17:22:04 home-rpi NetworkManager[334]: [1623864124.8108] device (wlan0): supplicant interface state: completed -> associating Jun 16 17:22:05 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:32865 - 17650 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 30.000885153s Jun 16 17:22:05 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:06 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:53220 - 46034 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004677359s Jun 16 17:22:06 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:60273->127.0.0.1:5553: i/o timeout Jun 16 17:22:06 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:53220 - 44784 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.005265175s Jun 16 17:22:06 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:36777->127.0.0.1:5553: i/o timeout Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:37306 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 15.304719347s Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33598 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 14.535011837s Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:51334 - 35452 "NS IN . udp 17 false 512" NOERROR - 0 13.983992113s Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36300 - 30262 "NS IN . udp 17 false 512" NOERROR - 0 15.742504112s Jun 16 17:22:07 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:07 home-rpi wpa_supplicant[342]: wlan0: Associated with 4a:8f:5a:07:84:b4 Jun 16 17:22:07 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-CONNECTED - Connection to 4a:8f:5a:07:84:b4 completed [id=0 id_str=] Jun 16 17:22:07 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 17:22:08 home-rpi NetworkManager[334]: [1623864128.0026] device (wlan0): supplicant interface state: associating -> completed Jun 16 17:22:08 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:53220 - 46034 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004221796s Jun 16 17:22:08 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:59489->127.0.0.1:5553: i/o timeout Jun 16 17:22:08 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:53220 - 44784 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.00489258s Jun 16 17:22:08 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:59304->127.0.0.1:5553: i/o timeout Jun 16 17:22:10 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:37554 - 31714 "NS IN . udp 17 false 512" NOERROR - 0 15.34769299s Jun 16 17:22:10 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:11 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:45771 - 14319 "A IN version.home-assistant.io. udp 43 false 512" NOERROR - 0 8.004437092s Jun 16 17:22:11 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: read udp 127.0.0.1:59551->127.0.0.1:5553: i/o timeout Jun 16 17:22:11 home-rpi 4a745889fe82[467]: 2021-06-16 19:22:11 ERROR (Thread-3) [custom_components.ble_monitor.ble_parser.xiaomi] No encryption key found for device with MAC A4:C1:38:D9:78:0F Jun 16 17:22:12 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:34939 - 34642 "NS IN . udp 17 false 512" NOERROR - 0 15.347574999s Jun 16 17:22:12 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:13 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:51577 - 55167 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 8.004412391s Jun 16 17:22:13 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:46741->127.0.0.1:5553: i/o timeout Jun 16 17:22:13 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.2:45771 - 14319 "A IN version.home-assistant.io. udp 43 false 512" NOERROR - 0 8.005474692s Jun 16 17:22:13 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: read udp 127.0.0.1:45469->127.0.0.1:5553: i/o timeout Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.33.1:47650 - 45713 "A IN www.googleapis.com. udp 36 false 512" NOERROR - 0 8.004134707s Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: read udp 127.0.0.1:46317->127.0.0.1:5553: i/o timeout Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:46298 - 25490 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.00395272s Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:46107->127.0.0.1:5553: i/o timeout Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:46298 - 26845 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.003937616s Jun 16 17:22:14 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:48186->127.0.0.1:5553: i/o timeout Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:32778 - 9177 "NS IN . udp 17 false 512" NOERROR - 0 15.34779983s Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:48796 - 44784 "A IN api.github.com. udp 43 true 2048" NOERROR - 0 15.34759983s Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:46990 - 46034 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 15.347599308s Jun 16 17:22:15 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:16 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:49317 - 20616 "NS IN . udp 17 false 512" NOERROR - 0 15.3478702s Jun 16 17:22:16 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:17 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:46298 - 26845 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004199323s Jun 16 17:22:17 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:40325->127.0.0.1:5553: i/o timeout Jun 16 17:22:17 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:46298 - 25490 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.004199375s Jun 16 17:22:17 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:52546->127.0.0.1:5553: i/o timeout Jun 16 17:22:17 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=4a:8f:5a:07:84:b4 reason=6 Jun 16 17:22:17 home-rpi wpa_supplicant[342]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0 Jun 16 17:22:17 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:22:17 home-rpi NetworkManager[334]: [1623864137.7428] sup-iface[0x3aa380,wlan0]: connection disconnected (reason 6) Jun 16 17:22:17 home-rpi NetworkManager[334]: [1623864137.7562] device (wlan0): supplicant interface state: completed -> associating Jun 16 17:22:17 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 16 17:22:20 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:43659 - 53233 "NS IN . udp 17 false 512" NOERROR - 0 21.805951886s Jun 16 17:22:20 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:20 home-rpi wpa_supplicant[342]: wlan0: Associated with 76:4d:28:f9:af:97 Jun 16 17:22:20 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-CONNECTED - Connection to 76:4d:28:f9:af:97 completed [id=0 id_str=] Jun 16 17:22:20 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 16 17:22:20 home-rpi NetworkManager[334]: [1623864140.9082] device (wlan0): supplicant interface state: associating -> completed Jun 16 17:22:22 home-rpi 4a745889fe82[467]: 2021-06-16 19:22:22 ERROR (MainThread) [custom_components.hacs] Cannot connect to host api.github.com:443 ssl:default [Try again] Jun 16 17:22:22 home-rpi 4a745889fe82[467]: 2021-06-16 19:22:22 ERROR (MainThread) [custom_components.hacs] Cannot connect to host api.github.com:443 ssl:default [Try again] Jun 16 17:22:23 home-rpi 4a745889fe82[467]: 2021-06-16 19:22:23 ERROR (MainThread) [homeassistant] Error doing job: Task exception was never retrieved Jun 16 17:22:23 home-rpi 4a745889fe82[467]: Traceback (most recent call last): Jun 16 17:22:23 home-rpi 4a745889fe82[467]: File "/usr/src/homeassistant/homeassistant/components/ssdp/init.py", line 90, in async_scan Jun 16 17:22:23 home-rpi 4a745889fe82[467]: await async_search(async_callback=self._on_ssdp_response) Jun 16 17:22:23 home-rpi 4a745889fe82[467]: File "/usr/local/lib/python3.8/site-packages/async_upnp_client/search.py", line 137, in async_search Jun 16 17:22:23 home-rpi 4a745889fe82[467]: await listener.async_start() Jun 16 17:22:23 home-rpi 4a745889fe82[467]: File "/usr/local/lib/python3.8/site-packages/async_upnp_client/search.py", line 91, in async_start Jun 16 17:22:23 home-rpi 4a745889fe82[467]: sock, source, self._target = get_ssdp_socket(self.source_ip, self.target_ip) Jun 16 17:22:23 home-rpi 4a745889fe82[467]: File "/usr/local/lib/python3.8/site-packages/async_upnp_client/ssdp.py", line 228, in get_ssdp_socket Jun 16 17:22:23 home-rpi 4a745889fe82[467]: sock.setsockopt( Jun 16 17:22:23 home-rpi 4a745889fe82[467]: OSError: [Errno 19] No such device Jun 16 17:22:24 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:53581 - 41327 "NS IN . udp 17 false 512" NOERROR - 0 20.015869741s Jun 16 17:22:24 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:24 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-DISCONNECTED bssid=76:4d:28:f9:af:97 reason=2 Jun 16 17:22:24 home-rpi wpa_supplicant[342]: dbus: wpa_dbus_property_changed: no property SessionLength in object /fi/w1/wpa_supplicant1/Interfaces/0 Jun 16 17:22:24 home-rpi NetworkManager[334]: [1623864144.3038] sup-iface[0x3aa380,wlan0]: connection disconnected (reason 2) Jun 16 17:22:24 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 16 17:22:24 home-rpi NetworkManager[334]: [1623864144.3143] device (wlan0): supplicant interface state: completed -> disconnected Jun 16 17:22:24 home-rpi NetworkManager[334]: [1623864144.4084] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:22:24 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:22:24 home-rpi NetworkManager[334]: [1623864144.4748] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:50152 - 24850 "NS IN . udp 17 false 512" NOERROR - 0 20.015812845s Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:45407 - 3038 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.005298491s Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:39114->127.0.0.1:5553: i/o timeout Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:45407 - 4340 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.005452815s Jun 16 17:22:25 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:44998->127.0.0.1:5553: i/o timeout Jun 16 17:22:26 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:59304 - 44784 "A IN api.github.com. udp 43 true 2048" NOERROR - 0 20.015974168s Jun 16 17:22:26 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:26 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:59489 - 46034 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 20.015847761s Jun 16 17:22:26 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:27 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:33291 - 58909 "NS IN . udp 17 false 512" NOERROR - 0 20.015880557s Jun 16 17:22:27 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:27 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 16 17:22:27 home-rpi NetworkManager[334]: [1623864147.6783] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:22:27 home-rpi NetworkManager[334]: [1623864147.7776] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:22:28 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:45407 - 3038 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.004405895s Jun 16 17:22:28 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:55652->127.0.0.1:5553: i/o timeout Jun 16 17:22:28 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:45407 - 4340 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004408967s Jun 16 17:22:28 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:40613->127.0.0.1:5553: i/o timeout Jun 16 17:22:30 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:22:30 home-rpi NetworkManager[334]: [1623864150.6060] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:57656 - 44107 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004609612s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:57656 - 43326 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.004609664s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:57431->127.0.0.1:5553: i/o timeout Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:51351->127.0.0.1:5553: i/o timeout Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36913 - 14069 "NS IN . udp 17 false 512" NOERROR - 0 28.41455578s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:55509 - 44784 "A IN api.github.com. udp 43 true 2048" NOERROR - 0 28.41430089s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:32860 - 46034 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 28.414300682s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:57066 - 14319 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 23.701603504s Jun 16 17:22:31 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:44893 - 58413 "NS IN . udp 17 false 512" NOERROR - 0 23.701646601s Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:46864 - 26845 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 23.701601471s Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:36777 - 44784 "A IN api.github.com. udp 43 true 2048" NOERROR - 0 28.414463025s Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:60273 - 46034 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 28.414849069s Jun 16 17:22:32 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:59551 - 14319 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 23.701681663s Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:57656 - 44107 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.004640815s Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:44084->127.0.0.1:5553: i/o timeout Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:57656 - 43326 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.004640451s Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:58910->127.0.0.1:5553: i/o timeout Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:38542 - 14319 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 28.4144794s Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:41044 - 20937 "NS IN . udp 17 false 512" NOERROR - 0 23.701533312s Jun 16 17:22:33 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 . NS: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:34 home-rpi wpa_supplicant[342]: wlan0: CTRL-EVENT-ASSOC-REJECT bssid=00:00:00:00:00:00 status_code=16 Jun 16 17:22:34 home-rpi NetworkManager[334]: [1623864154.2472] device (wlan0): supplicant interface state: associating -> disconnected Jun 16 17:22:34 home-rpi NetworkManager[334]: [1623864154.3476] device (wlan0): supplicant interface state: disconnected -> scanning Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:48103 - 32864 "A IN api.github.com. udp 32 false 512" NOERROR - 0 8.004558378s Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: read udp 127.0.0.1:49802->127.0.0.1:5553: i/o timeout Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [INFO] 172.30.32.1:48103 - 33854 "AAAA IN api.github.com. udp 32 false 512" NOERROR - 0 8.00401447s Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: read udp 127.0.0.1:43496->127.0.0.1:5553: i/o timeout Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:57361 - 25490 "A IN api.github.com. udp 43 true 2048" NOERROR - 0 25.450059995s Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:49968 - 26845 "AAAA IN api.github.com. udp 43 true 2048" NOERROR - 0 25.450257652s Jun 16 17:22:36 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 api.github.com. AAAA: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:37 home-rpi wpa_supplicant[342]: wlan0: Trying to associate with SSID 'NET-SSID' Jun 16 17:22:37 home-rpi NetworkManager[334]: [1623864157.1712] device (wlan0): supplicant interface state: scanning -> associating Jun 16 17:22:37 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:45469 - 14319 "A IN version.home-assistant.io. udp 54 true 2048" NOERROR - 0 25.450254204s Jun 16 17:22:37 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 version.home-assistant.io. A: dial tcp 1.1.1.1:853: i/o timeout Jun 16 17:22:37 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:41423 - 55167 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 30.000916297s Jun 16 17:22:37 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout Jun 16 17:22:38 home-rpi f0609d1b5cd8[467]: [INFO] 127.0.0.1:41911 - 45713 "A IN www.googleapis.com. udp 47 true 2048" NOERROR - 0 30.000599214s Jun 16 17:22:38 home-rpi f0609d1b5cd8[467]: [ERROR] plugin/errors: 2 www.googleapis.com. A: dial tcp 1.0.0.1:853: i/o timeout

After downgrading os to 5.13 everything is working well as before.

agners commented 3 years ago

@piio

I got similar problem - after updating OS to 6.0 after some time host stops responding for ping on wifi connection.

Shutdown and disconnect from WiFi doesn't seem the same issue to me. This requires a separate issue.

sjukes69 commented 3 years ago

I have the same issue running on a VM. HA unavailable. Strange thing however, i find that if I just hit enter in the cli, it starts working again for a short while. HA been rock solid for me up until the latest update. I am running on Oracle MV VirtualBox. Core 2021-21.6.5, supervisior-2021.6.3. Upgraded from 5.* and issue started

System Health

version: core-2021.6.5 installation_type: Home Assistant OS dev: false hassio: true docker: true virtualenv: false python_version: 3.8.9 os_name: Linux os_version: 5.10.42 arch: x86_64 timezone: Europe/London

GitHub API: ok Github API Calls Remaining: 4949 Installed Version: 1.12.4 Stage: running Available Repositories: 842 Installed Repositories: 8

can_reach_server: ok remaining_requests: 25

logged_in: true subscription_expiration: 6 July 2021, 1:00 relayer_connected: true remote_enabled: true remote_connected: true alexa_enabled: false google_enabled: true can_reach_cert_server: ok can_reach_cloud_auth: ok can_reach_cloud: failed to load: timeout

host_os: Home Assistant OS 6.0 update_channel: stable supervisor_version: supervisor-2021.06.3 docker_version: 20.10.6 disk_total: 30.8 GB disk_used: 8.8 GB healthy: true supported: true board: ova supervisor_api: ok version_api: ok installed_addons: File editor (5.3.1), ESPHome (1.18.0), Samba share (9.5.1), Terminal & SSH (9.1.3), TasmoAdmin (0.15.0), texecom2mqtt (1.0.42), Home Assistant Google Drive Backup (0.104.3), Node-RED (9.1.4)

dashboards: 4 resources: 4 views: 30 mode: storage

Update: Still losing all access to HA (local web on VM, external web, Nabu Case, tried disabling integrations, still no joy. However, I can confirm that just hitting enter at the CLI works each time and HA starts responding again for a short while.

After downgrading to 5.13, the issue is resolved

dewitpj commented 3 years ago

I did a manual reboot of the box and since then it's been stable - been a few days so happy to close it for me