operating-system icon indicating copy to clipboard operation
operating-system copied to clipboard

HA OS 13.0 task kthreadd blocked for more than 120 seconds

Open rrooggiieerr opened this issue 1 year ago • 30 comments
trafficstars

Describe the issue you are experiencing

Since I upgraded my Raspberry Pi 4 to the latest HA OS 13.0 the system freezes regularly with the message

task kthreadd:2 blocked for more than 120 seconds.

IMG_20240815_135926

Once frozen I can still ping my HA server, but can't SSH into it anymore

What operating system image do you use?

rpi4-64 (Raspberry Pi 4/400 64-bit OS)

What version of Home Assistant Operating System is installed?

3.0

Did the problem occur after upgrading the Operating System?

Yes

Hardware details

Raspberry Pi 4 with 1 GB memory and WD Purple SC QD101 microSDXC 64 GB storage, original power supply and powered USB hub, Phoscon RaspBee II Zigbee module

Steps to reproduce the issue

  1. Upgrade to latest HA OS 13.0
  2. After 5 to 10 minutes the system freezes
  3. After about 20 minutes the system restarts and it starts over again

about 1 in 4 times the system boots up correctly and I can access HA, but then after some hours it crashes again

Anything in the Supervisor logs that might be useful for us?

Can't access my system

Anything in the Host logs that might be useful for us?

2024-08-15 12:04:15.867 homeassistant kernel: audit: type=1325 audit(1723723455.862:191): table=filter:88 family=2 entries=1 op=nft_register_chain pid=2558 subj=docker-default comm="iptables-nft"
2024-08-15 12:04:15.868 homeassistant kernel: audit: type=1300 audit(1723723455.862:191): arch=c00000b7 syscall=211 success=yes exit=160 a0=3 a1=7febafef68 a2=0 a3=0 items=0 ppid=2405 pid=2558 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-nft" exe="/sbin/xtables-nft-multi" subj=docker-default key=(null)
2024-08-15 12:04:15.868 homeassistant kernel: audit: type=1327 audit(1723723455.862:191): proctitle=69707461626C65732D6E6674002D5000464F525741524400414343455054
2024-08-15 12:04:15.899 homeassistant kernel: audit: type=1325 audit(1723723455.894:192): table=filter:89 family=2 entries=10 op=nft_unregister_rule pid=2559 subj=docker-default comm="iptables-nft"
2024-08-15 12:04:15.900 homeassistant kernel: audit: type=1300 audit(1723723455.894:192): arch=c00000b7 syscall=211 success=yes exit=92 a0=3 a1=7ff3c1b308 a2=0 a3=0 items=0 ppid=2405 pid=2559 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables-nft" exe="/sbin/xtables-nft-multi" subj=docker-default key=(null)
2024-08-15 12:04:15.900 homeassistant kernel: audit: type=1327 audit(1723723455.894:192): proctitle=69707461626C65732D6E6674002D4600464F5257415244
2024-08-15 12:04:16.091 homeassistant kernel: 8021q: 802.1Q VLAN Support v1.8
2024-08-15 12:04:18.647 homeassistant systemd[1]: var-lib-docker-overlay2-4391ae713f43a23c228ec31a12c35b07791c34a1e1e367885619dd2869276147\x2dinit-merged.mount: Deactivated successfully.
2024-08-15 12:04:18.648 homeassistant systemd[1]: mnt-data-docker-overlay2-4391ae713f43a23c228ec31a12c35b07791c34a1e1e367885619dd2869276147\x2dinit-merged.mount: Deactivated successfully.
2024-08-15 12:04:19.139 homeassistant kernel: hassio: port 6(veth33aed7c) entered blocking state
2024-08-15 12:04:19.140 homeassistant kernel: hassio: port 6(veth33aed7c) entered disabled state
2024-08-15 12:04:19.140 homeassistant kernel: veth33aed7c: entered allmulticast mode
2024-08-15 12:04:19.140 homeassistant kernel: veth33aed7c: entered promiscuous mode
2024-08-15 12:04:19.140 homeassistant kernel: audit: type=1700 audit(1723723459.134:193): dev=veth33aed7c prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
2024-08-15 12:04:19.144 homeassistant NetworkManager[515]: <info>  [1723723459.1436] manager: (veth589f35d): new Veth device (/org/freedesktop/NetworkManager/Devices/19)
2024-08-15 12:04:19.150 homeassistant NetworkManager[515]: <info>  [1723723459.1485] manager: (veth33aed7c): new Veth device (/org/freedesktop/NetworkManager/Devices/20)
2024-08-15 12:04:19.452 homeassistant systemd[1]: Started libcontainer container 84ec30f4d55409589db1f07f2275b6f853ddf7aa4cb9801cd6b998f540b48932.
2024-08-15 12:04:19.711 homeassistant kernel: eth0: renamed from veth589f35d
2024-08-15 12:04:19.756 homeassistant kernel: hassio: port 6(veth33aed7c) entered blocking state
2024-08-15 12:04:19.756 homeassistant kernel: hassio: port 6(veth33aed7c) entered forwarding state
2024-08-15 12:04:19.758 homeassistant NetworkManager[515]: <info>  [1723723459.7583] device (veth33aed7c): carrier: link connected
2024-08-15 12:04:24.910 homeassistant systemd[1]: systemd-hostnamed.service: Deactivated successfully.
2024-08-15 12:04:25.011 homeassistant kernel: kauditd_printk_skb: 51 callbacks suppressed
2024-08-15 12:04:25.012 homeassistant kernel: audit: type=1334 audit(1723723465.006:211): prog-id=14 op=UNLOAD
2024-08-15 12:04:25.012 homeassistant kernel: audit: type=1334 audit(1723723465.006:212): prog-id=13 op=UNLOAD
2024-08-15 12:04:25.012 homeassistant kernel: audit: type=1334 audit(1723723465.006:213): prog-id=12 op=UNLOAD
2024-08-15 12:04:25.148 homeassistant systemd[1]: systemd-timedated.service: Deactivated successfully.
2024-08-15 12:04:25.179 homeassistant kernel: audit: type=1334 audit(1723723465.174:214): prog-id=25 op=UNLOAD
2024-08-15 12:04:25.180 homeassistant kernel: audit: type=1334 audit(1723723465.174:215): prog-id=24 op=UNLOAD
2024-08-15 12:04:25.180 homeassistant kernel: audit: type=1334 audit(1723723465.174:216): prog-id=23 op=UNLOAD
2024-08-15 12:04:25.236 homeassistant systemd[1]: Started libcontainer container 155206ea45e51114c2b733b399e916f16caafc36333c0a3b286bae800fb0dbcd.
2024-08-15 12:04:25.295 homeassistant kernel: audit: type=1334 audit(1723723465.290:217): prog-id=50 op=LOAD
2024-08-15 12:04:25.296 homeassistant kernel: audit: type=1300 audit(1723723465.290:217): arch=c00000b7 syscall=280 success=yes exit=15 a0=5 a1=4000195840 a2=78 a3=0 items=0 ppid=2867 pid=2880 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="runc" exe="/usr/bin/runc" subj=unconfined key=(null)
2024-08-15 12:04:25.296 homeassistant kernel: audit: type=1327 audit(1723723465.290:217): proctitle=72756E63002D2D726F6F74002F7661722F72756E2F646F636B65722F72756E74696D652D72756E632F6D6F6279002D2D6C6F67002F72756E2F636F6E7461696E6572642F696F2E636F6E7461696E6572642E72756E74696D652E76322E7461736B2F6D6F62792F31353532303665613435653531313134633262373333623339
2024-08-15 12:04:25.296 homeassistant kernel: audit: type=1334 audit(1723723465.290:218): prog-id=51 op=LOAD
2024-08-15 12:06:00.427 homeassistant kernel: uart-pl011 fe201000.serial: no DMA platform data
2024-08-15 12:06:15.381 homeassistant kernel: kauditd_printk_skb: 11 callbacks suppressed
2024-08-15 12:06:15.495 homeassistant kernel: audit: type=1334 audit(1723723575.215:222): prog-id=53 op=LOAD
2024-08-15 12:06:15.495 homeassistant kernel: audit: type=1334 audit(1723723575.215:223): prog-id=54 op=LOAD
2024-08-15 12:06:15.520 homeassistant kernel: audit: type=1334 audit(1723723575.215:224): prog-id=55 op=LOAD
2024-08-15 12:06:15.560 homeassistant systemd[1]: Starting Hostname Service...
2024-08-15 12:06:16.315 homeassistant systemd[1]: Started Hostname Service.
2024-08-15 12:06:16.591 homeassistant kernel: audit: type=1334 audit(1723723576.587:225): prog-id=56 op=LOAD
2024-08-15 12:06:16.612 homeassistant kernel: audit: type=1334 audit(1723723576.587:226): prog-id=57 op=LOAD
2024-08-15 12:06:16.612 homeassistant kernel: audit: type=1334 audit(1723723576.587:227): prog-id=58 op=LOAD
2024-08-15 12:06:16.621 homeassistant systemd[1]: Starting Time & Date Service...
2024-08-15 12:06:17.057 homeassistant systemd[1]: Started Time & Date Service.
2024-08-15 12:06:47.251 homeassistant systemd[1]: systemd-hostnamed.service: Deactivated successfully.
2024-08-15 12:06:47.287 homeassistant systemd[1]: systemd-timedated.service: Deactivated successfully.
2024-08-15 12:06:47.669 homeassistant kernel: audit: type=1334 audit(1723723607.279:228): prog-id=58 op=UNLOAD
2024-08-15 12:06:47.669 homeassistant kernel: audit: type=1334 audit(1723723607.279:229): prog-id=57 op=UNLOAD
2024-08-15 12:06:47.669 homeassistant kernel: audit: type=1334 audit(1723723607.279:230): prog-id=56 op=UNLOAD
2024-08-15 12:06:47.669 homeassistant kernel: audit: type=1334 audit(1723723607.379:231): prog-id=55 op=UNLOAD
2024-08-15 12:06:47.670 homeassistant kernel: audit: type=1334 audit(1723723607.379:232): prog-id=54 op=UNLOAD
2024-08-15 12:06:47.670 homeassistant kernel: audit: type=1334 audit(1723723607.379:233): prog-id=53 op=UNLOAD
2024-08-15 12:09:12.642 homeassistant systemd[1]: var-lib-docker-overlay2-e787cc7fceda8f103aebabf4570728705be15a59c036dfad2478ccaf5c243f8f\x2dinit-merged.mount: Deactivated successfully.
2024-08-15 12:09:12.714 homeassistant systemd[1]: mnt-data-docker-overlay2-e787cc7fceda8f103aebabf4570728705be15a59c036dfad2478ccaf5c243f8f\x2dinit-merged.mount: Deactivated successfully.
2024-08-15 12:09:13.080 homeassistant kernel: hassio: port 7(vethfba167f) entered blocking state
2024-08-15 12:09:13.080 homeassistant kernel: hassio: port 7(vethfba167f) entered disabled state
2024-08-15 12:09:13.080 homeassistant kernel: vethfba167f: entered allmulticast mode
2024-08-15 12:09:13.091 homeassistant kernel: vethfba167f: entered promiscuous mode
2024-08-15 12:09:13.091 homeassistant kernel: audit: type=1700 audit(1723723753.060:234): dev=vethfba167f prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
2024-08-15 12:09:13.091 homeassistant kernel: audit: type=1300 audit(1723723753.060:234): arch=c00000b7 syscall=206 success=yes exit=40 a0=d a1=4001e71830 a2=28 a3=0 items=0 ppid=1 pid=613 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj=unconfined key=(null)
2024-08-15 12:09:13.092 homeassistant kernel: audit: type=1327 audit(1723723753.060:234): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D636F6E7461696E6572643D2F72756E2F636F6E7461696E6572642F636F6E7461696E6572642E736F636B
2024-08-15 12:09:13.314 homeassistant NetworkManager[515]: <info>  [1723723753.2661] manager: (veth984781a): new Veth device (/org/freedesktop/NetworkManager/Devices/21)
2024-08-15 12:09:13.364 homeassistant NetworkManager[515]: <info>  [1723723753.3640] manager: (vethfba167f): new Veth device (/org/freedesktop/NetworkManager/Devices/22)
2024-08-15 12:09:13.453 homeassistant dockerd[613]: time="2024-08-15T12:09:13.452252945Z" level=warning msg="Failed to allocate and map port 443-443: Bind for 0.0.0.0:443 failed: port is already allocated"
2024-08-15 12:09:13.488 homeassistant kernel: hassio: port 7(vethfba167f) entered disabled state
2024-08-15 12:09:13.546 homeassistant kernel: vethfba167f (unregistering): left allmulticast mode
2024-08-15 12:09:13.551 homeassistant kernel: vethfba167f (unregistering): left promiscuous mode
2024-08-15 12:09:13.552 homeassistant kernel: hassio: port 7(vethfba167f) entered disabled state
2024-08-15 12:09:13.552 homeassistant kernel: audit: type=1700 audit(1723723753.480:235): dev=vethfba167f prom=0 old_prom=256 auid=4294967295 uid=0 gid=0 ses=4294967295
2024-08-15 12:09:13.583 homeassistant kernel: audit: type=1300 audit(1723723753.480:235): arch=c00000b7 syscall=206 success=yes exit=32 a0=d a1=4001ad2800 a2=20 a3=0 items=0 ppid=1 pid=613 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="dockerd" exe="/usr/bin/dockerd" subj=unconfined key=(null)
2024-08-15 12:09:13.584 homeassistant kernel: audit: type=1327 audit(1723723753.480:235): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D636F6E7461696E6572643D2F72756E2F636F6E7461696E6572642F636F6E7461696E6572642E736F636B
2024-08-15 12:09:13.641 homeassistant systemd[1]: var-lib-docker-overlay2-e787cc7fceda8f103aebabf4570728705be15a59c036dfad2478ccaf5c243f8f-merged.mount: Deactivated successfully.
2024-08-15 12:09:13.642 homeassistant systemd[1]: mnt-data-docker-overlay2-e787cc7fceda8f103aebabf4570728705be15a59c036dfad2478ccaf5c243f8f-merged.mount: Deactivated successfully.
2024-08-15 12:09:13.787 homeassistant dockerd[613]: time="2024-08-15T12:09:13.787270443Z" level=error msg="Handler for POST /v1.45/containers/77723e5f0e954941ff63a966cdf5ea19d0f2822af93f5a45296b50a9fd36dd25/start returned error: driver failed programming external connectivity on endpoint addon_core_nginx_proxy (7fed2bd72200cb354cc69be05973f0f46bf58c829c063888f32128da7ec8aa9f): Bind for 0.0.0.0:443 failed: port is already allocated" spanID=fec057372fd04fda traceID=e6acf3861d1db164c4a0c3f75b088501
2024-08-15 12:16:40.824 homeassistant dropbear[3315]: [3315] Aug 15 12:16:40 Child connection from fe80::cc1:d4ef:da1e:a2bf%end0:51348
2024-08-15 12:16:41.046 homeassistant dropbear[3315]: [3315] Aug 15 12:16:41 Pubkey auth succeeded for 'root' with ssh-rsa key SHA256:HdSjbsv1W4rF17b8pQxlXWLOxW/624uKFklM71lA7Eg from fe80::cc1:d4ef:da1e:a2bf%end0:51348

System information

No response

Additional information

No response

rrooggiieerr avatar Aug 15 '24 12:08 rrooggiieerr