home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.86k stars 965 forks source link

Supervisor stuck in startup on 8.0.rc2 #1852

Closed bschatzow closed 2 years ago

bschatzow commented 2 years ago

Describe the issue you are experiencing

Installed RC.2 and system does not come back on line. 2-04-15 18:48:01 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/config:

Can't restore from CLI. Error: 'OSManager.update' blocked from execution, system is not running - CoreState.STARTUP

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?

8.0 RC2

Did you upgrade the Operating System.

Yes

Steps to reproduce the issue

  1. Installed the update
  2. System never came back on line
  3. ...

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

22-04-15 18:48:01 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/config:

Error: 'OSManager.update' blocked from execution, system is not running - CoreState.STARTUP

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

# ha host logs
[    0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083]
[    0.000000] Linux version 5.15.32-v8 (builder@72088471553b) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -gc62532023) 9.4.0, GNU ld (GNU Binutils) 2.36.1) #1 SMP PREEMPT Fri Apr 15 20:11:05 UTC 2022
[    0.000000] random: fast init done
[    0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.2
[    0.000000] efi: UEFI not found.
[    0.000000] Reserved memory: created CMA memory pool at 0x000000001ec00000, size 256 MiB
[    0.000000] OF: reserved mem: initialized node linux,cma, compatible id shared-dma-pool
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000000000-0x000000003fffffff]
[    0.000000]   DMA32    [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000000000-0x000000003b2fffff]
[    0.000000]   node   0: [mem 0x0000000040000000-0x00000000fbffffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff]
[    0.000000] On node 0, zone DMA32: 256 pages in unavailable ranges
[    0.000000] percpu: Embedded 28 pages/cpu s77272 r8192 d29224 u114688
[    0.000000] pcpu-alloc: s77272 r8192 d29224 u114688 alloc=28*4096
[    0.000000] pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3
[    0.000000] Detected PIPT I-cache on CPU0
[    0.000000] CPU features: detected: Spectre-v2
[    0.000000] CPU features: detected: Spectre-v4
[    0.000000] CPU features: detected: Spectre-BHB
[    0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923
[    0.000000] Built 1 zonelists, mobility grouping on.  Total pages: 996660
[    0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=511fe888ff364f9e8755d41305ab32fe cgroup_enable=memory fsck.repair=yes coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 video=HDMI-A-1:1360x768M@60 smsc95xx.macaddr=DC:A6:32:75:DC:C6 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000  dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20 rootfstype=squashfs ro rauc.slot=B
[    0.000000] cgroup: Enabling memory control group subsystem
[    0.000000] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
[    0.000000] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
[    0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off
[    0.000000] software IO TLB: mapped [mem 0x0000000037300000-0x000000003b300000] (64MB)
[    0.000000] Memory: 3617008K/4049920K available (12608K kernel code, 2008K rwdata, 4388K rodata, 3968K init, 985K bss, 170768K reserved, 262144K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.000000] ftrace: allocating 40869 entries in 160 pages
[    0.000000] ftrace: allocated 160 pages with 2 groups
[    0.000000] trace event string verifier disabled
[    0.000000] rcu: Preemptible hierarchical RCU implementation.
[    0.000000] rcu:     RCU event tracing is enabled.
[    0.000000] rcu:     RCU restricting CPUs from NR_CPUS=256 to nr_cpu_ids=4.
[    0.000000]  Trampoline variant of Tasks RCU enabled.
[    0.000000]  Rude variant of Tasks RCU enabled.
[    0.000000]  Tracing variant of Tasks RCU enabled.
[    0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 25 jiffies.
[    0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4
[    0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
[    0.000000] Root IRQ handler: gic_handle_irq
[    0.000000] GIC: Using split EOI/Deactivate mode
[    0.000000] random: get_random_bytes called from start_kernel+0x4fc/0x6e8 with crng_init=1
[    0.000000] arch_timer: cp15 timer(s) running at 54.00MHz (phys).
[    0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0xc743ce346, max_idle_ns: 440795203123 ns
[    0.000000] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns
[    0.000261] Console: colour dummy device 80x25
[    0.000853] printk: console [tty1] enabled
[    0.000928] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000)
[    0.000969] pid_max: default: 32768 minimum: 301
[    0.001108] LSM: Security Framework initializing
[    0.001251] AppArmor: AppArmor initialized
[    0.001441] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.001524] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
[    0.004896] rcu: Hierarchical SRCU implementation.
[    0.006032] EFI services will not be available.
[    0.006597] smp: Bringing up secondary CPUs ...
[    0.007649] Detected PIPT I-cache on CPU1
[    0.007731] CPU1: Booted secondary processor 0x0000000001 [0x410fd083]
[    0.008950] Detected PIPT I-cache on CPU2
[    0.009000] CPU2: Booted secondary processor 0x0000000002 [0x410fd083]
[    0.010152] Detected PIPT I-cache on CPU3
[    0.010204] CPU3: Booted secondary processor 0x0000000003 [0x410fd083]
[    0.010349] smp: Brought up 1 node, 4 CPUs
[    0.010440] SMP: Total of 4 processors activated.
[    0.010462] CPU features: detected: 32-bit EL0 Support
[    0.010481] CPU features: detected: 32-bit EL1 Support
[    0.010502] CPU features: detected: CRC32 instructions
[    0.046534] CPU: All CPU(s) started at EL2
[    0.046637] alternatives: patching kernel code
[    0.048401] devtmpfs: initialized
[    0.059638] Enabled cp15_barrier support
[    0.059706] Enabled setend support
[    0.059912] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns
[    0.059960] futex hash table entries: 1024 (order: 4, 65536 bytes, linear)
[    0.068912] pinctrl core: initialized pinctrl subsystem
[    0.069750] DMI not present or invalid.
[    0.070276] NET: Registered PF_NETLINK/PF_ROUTE protocol family
[    0.074069] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations
[    0.074387] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
[    0.075317] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
[    0.075417] audit: initializing netlink subsys (disabled)
[    0.075725] audit: type=2000 audit(0.072:1): state=initialized audit_enabled=0 res=1
[    0.076273] thermal_sys: Registered thermal governor 'step_wise'
[    0.076498] cpuidle: using governor menu
[    0.076977] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
[    0.077173] ASID allocator initialised with 65536 entries
[    0.077353] Serial: AMBA PL011 UART driver
[    0.087554] bcm2835-mbox fe00b880.mailbox: mailbox enabled
[    0.104351] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-03-24T13:19:26, variant start
[    0.108359] raspberrypi-firmware soc:firmware: Firmware hash is e5a963efa66a1974127860b42e913d2374139ff5
[    0.161204] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1
[    0.165412] vgaarb: loaded
[    0.165829] SCSI subsystem initialized
[    0.166059] usbcore: registered new interface driver usbfs
[    0.166146] usbcore: registered new interface driver hub
[    0.166225] usbcore: registered new device driver usb
[    0.166547] usb_phy_generic phy: supply vcc not found, using dummy regulator
[    0.166991] mc: Linux media interface: v0.10
[    0.167113] pps_core: LinuxPPS API ver. 1 registered
[    0.167136] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    0.167173] PTP clock support registered
[    0.168907] clocksource: Switched to clocksource arch_sys_counter
[    0.261452] VFS: Disk quotas dquot_6.6.0
[    0.261579] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.261764] FS-Cache: Loaded
[    0.262027] CacheFiles: Loaded
[    0.262826] AppArmor: AppArmor Filesystem Enabled
[    0.263924] simple-framebuffer 3e6c7000.framebuffer: framebuffer at 0x3e6c7000, 0x3fc000 bytes
[    0.263961] simple-framebuffer 3e6c7000.framebuffer: format=a8r8g8b8, mode=1360x768x32, linelength=5440
[    0.270227] Console: switching to colour frame buffer device 170x48
[    0.276054] simple-framebuffer 3e6c7000.framebuffer: fb0: simplefb registered!
[    0.286478] NET: Registered PF_INET protocol family
[    0.287102] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
[    0.290676] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
[    0.290830] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
[    0.291146] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
[    0.291767] TCP: Hash tables configured (established 32768 bind 32768)
[    0.292093] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.292247] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
[    0.292617] NET: Registered PF_UNIX/PF_LOCAL protocol family
[    0.293755] RPC: Registered named UNIX socket transport module.
[    0.293839] RPC: Registered udp transport module.
[    0.293898] RPC: Registered tcp transport module.
[    0.293957] RPC: Registered tcp NFSv4.1 backchannel transport module.
[    0.294042] PCI: CLS 0 bytes, default 64
[    0.296337] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available
[    0.296792] kvm [1]: IPA Size Limit: 44 bits
[    0.298105] kvm [1]: vgic interrupt IRQ9
[    0.298421] kvm [1]: Hyp mode initialized successfully
[    1.184869] Initialise system trusted keyrings
[    1.185360] workingset: timestamp_bits=46 max_order=20 bucket_order=0
[    1.192330] zbud: loaded
[    1.194211] squashfs: version 4.0 (2009/01/31) Phillip Lougher
[    1.196647] FS-Cache: Netfs 'nfs' registered for caching
[    1.199467] NFS: Registering the id_resolver key type
[    1.201672] Key type id_resolver registered
[    1.203767] Key type id_legacy registered
[    1.205984] nfs4filelayout_init: NFSv4 File Layout Driver Registering...
[    1.208113] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering...
[    1.211556] Key type asymmetric registered
[    1.213775] Asymmetric key parser 'x509' registered
[    1.216018] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246)
[    1.218489] io scheduler mq-deadline registered
[    1.220683] io scheduler kyber registered
[    1.230973] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges:
[    1.233244] brcm-pcie fd500000.pcie:   No bus range found for /scb/pcie@7d500000, using [bus 00-ff]
[    1.235539] brcm-pcie fd500000.pcie:      MEM 0x0600000000..0x063fffffff -> 0x00c0000000
[    1.237848] brcm-pcie fd500000.pcie:   IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000
[    1.286995] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC)
[    1.289582] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00
[    1.291732] pci_bus 0000:00: root bus resource [bus 00-ff]
[    1.293910] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff])
[    1.296212] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400
[    1.298696] pci 0000:00:00.0: PME# supported from D0 D3hot
[    1.305058] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330
[    1.307339] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit]
[    1.309919] pci 0000:01:00.0: PME# supported from D0 D3cold
[    1.315923] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff]
[    1.318069] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit]
[    1.320171] pci 0000:00:00.0: PCI bridge to [bus 01]
[    1.322248] pci 0000:00:00.0:   bridge window [mem 0x600000000-0x6000fffff]
[    1.332709] iproc-rng200 fe104000.rng: hwrng registered
[    1.335312] vc-mem: phys_addr:0x00000000 mem_base=0x3eb00000 mem_size:0x3ff00000(1023 MiB)
[    1.338722] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000
[    1.341230] cacheinfo: Unable to detect cache hierarchy for CPU 0
[    1.354510] brd: module loaded
[    1.366287] loop: module loaded
[    1.368949] zram: Added device: zram0
[    1.371551] zram: Added device: zram1
[    1.374070] zram: Added device: zram2
[    1.376754] Loading iSCSI transport class v2.0-870.
[    1.383573] tun: Universal TUN/TAP device driver, 1.6
[    1.386509] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000
[    1.449065] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus
[    1.453679] pci 0000:00:00.0: enabling device (0000 -> 0002)
[    1.455685] xhci_hcd 0000:01:00.0: enabling device (0000 -> 0002)
[    1.457804] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.459783] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1
[    1.462525] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00003c0000000890
[    1.465909] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.15
[    1.467934] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.469975] usb usb1: Product: xHCI Host Controller
[    1.471965] usb usb1: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.473972] usb usb1: SerialNumber: 0000:01:00.0
[    1.476675] hub 1-0:1.0: USB hub found
[    1.478752] hub 1-0:1.0: 1 port detected
[    1.481350] xhci_hcd 0000:01:00.0: xHCI Host Controller
[    1.483295] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2
[    1.485331] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed
[    1.487732] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.15
[    1.489711] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[    1.491677] usb usb2: Product: xHCI Host Controller
[    1.493621] usb usb2: Manufacturer: Linux 5.15.32-v8 xhci-hcd
[    1.495557] usb usb2: SerialNumber: 0000:01:00.0
[    1.498155] hub 2-0:1.0: USB hub found
[    1.500132] hub 2-0:1.0: 4 ports detected
[    1.503642] dwc_otg: version 3.00a 10-AUG-2012 (platform bus)
[    1.506052] dwc_otg: FIQ enabled
[    1.506065] dwc_otg: NAK holdoff enabled
[    1.506075] dwc_otg: FIQ split-transaction FSM enabled
[    1.506088] Module dwc_common_port init
[    1.506651] usbcore: registered new interface driver uas
[    1.508679] usbcore: registered new interface driver usb-storage
[    1.510719] usbcore: registered new interface driver usbserial_generic
[    1.512584] usbserial: USB Serial support registered for generic
[    1.514701] mousedev: PS/2 mouse device common for all mice
[    1.516668] Driver for 1-wire Dallas network protocol.
[    1.523286] sdhci: Secure Digital Host Controller Interface driver
[    1.525223] sdhci: Copyright(c) Pierre Ossman
[    1.527621] sdhci-pltfm: SDHCI platform and OF driver helper
[    1.530948] ledtrig-cpu: registered to indicate activity on CPUs
[    1.532097] hid: raw HID events driver (C) Jiri Kosina
[    1.533132] usbcore: registered new interface driver usbhid
[    1.534038] usbhid: USB HID core driver
[    1.535083] ashmem: initialized
[    1.541033] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP)
[    1.542084] IPVS: Connection hash table configured (size=4096, memory=64Kbytes)
[    1.543227] IPVS: ipvs loaded.
[    1.544249] IPVS: [rr] scheduler registered.
[    1.545743] NET: Registered PF_INET6 protocol family
[    1.547658] Segment Routing with IPv6
[    1.548610] In-situ OAM (IOAM) with IPv6
[    1.549627] NET: Registered PF_PACKET protocol family
[    1.550600] Bridge firewalling registered
[    1.551579] Key type dns_resolver registered
[    1.553191] registered taskstats version 1
[    1.554134] Loading compiled-in X.509 certificates
[    1.555318] Key type ._fscrypt registered
[    1.556240] Key type .fscrypt registered
[    1.557232] Key type fscrypt-provisioning registered
[    1.558178] AppArmor: AppArmor sha1 policy hashing enabled
[    1.564158] uart-pl011 fe201000.serial: there is not valid maps for state default
[    1.565241] uart-pl011 fe201000.serial: cts_event_workaround enabled
[    1.566175] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2
[    1.574955] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer
[    1.576192] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver
[    1.577816] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0
[    1.578825] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated
[    1.611216] printk: console [netcon0] enabled
[    1.613195] netconsole: network logging started
[    1.615138] of_cfs_init
[    1.617108] of_cfs_init: OK
[    1.641043] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA
[    1.643173] Waiting for root device PARTUUID=a3ec664e-32ce-4665-95ea-7ae90ce9aa20...
[    1.703127] mmc1: new high speed SDIO card at address 0001
[    1.736927] usb 1-1: new high-speed USB device number 2 using xhci_hcd
[    1.887689] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.21
[    1.889732] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0
[    1.891670] usb 1-1: Product: USB2.0 Hub
[    1.895710] hub 1-1:1.0: USB hub found
[    1.897501] hub 1-1:1.0: 4 ports detected
[    2.013766] usb 2-2: new SuperSpeed USB device number 2 using xhci_hcd
[    2.050113] usb 2-2: New USB device found, idVendor=0bda, idProduct=0411, bcdDevice= 0.05
[    2.052025] usb 2-2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.053736] usb 2-2: Product: 2-Port USB 3.0 Hub
[    2.055401] usb 2-2: Manufacturer: Generic
[    2.065989] hub 2-2:1.0: USB hub found
[    2.068894] hub 2-2:1.0: 4 ports detected
[    2.192959] usb 1-1.2: new high-speed USB device number 3 using xhci_hcd
[    2.315691] usb 1-1.2: New USB device found, idVendor=0bda, idProduct=5411, bcdDevice= 0.05
[    2.317407] usb 1-1.2: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.319077] usb 1-1.2: Product: 4-Port USB 2.0 Hub
[    2.320736] usb 1-1.2: Manufacturer: Generic
[    2.324371] hub 1-1.2:1.0: USB hub found
[    2.327056] hub 1-1.2:1.0: 4 ports detected
[    2.393355] usb 2-2.1: new SuperSpeed USB device number 3 using xhci_hcd
[    2.414485] usb 2-2.1: New USB device found, idVendor=174c, idProduct=55aa, bcdDevice= 1.00
[    2.416156] usb 2-2.1: New USB device strings: Mfr=2, Product=3, SerialNumber=1
[    2.417843] usb 2-2.1: Product: ASMT1051
[    2.419495] usb 2-2.1: Manufacturer: asmedia
[    2.421170] usb 2-2.1: SerialNumber: 12345679A6EE
[    2.437364] scsi host0: uas
[    2.439829] scsi 0:0:0:0: Direct-Access     KINGSTON  SA400S37120G    0    PQ: 0 ANSI: 6
[    2.443078] sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
[    2.444426] sd 0:0:0:0: [sda] Write Protect is off
[    2.445479] sd 0:0:0:0: [sda] Mode Sense: 43 00 00 00
[    2.445713] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    2.447407] sd 0:0:0:0: [sda] Optimal transfer size 33553920 bytes
[    2.453365]  sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8
[    2.456596] sd 0:0:0:0: [sda] Attached SCSI disk
[    2.468190] VFS: Mounted root (squashfs filesystem) readonly on device 8:5.
[    2.470805] devtmpfs: mounted
[    2.479531] Freeing unused kernel memory: 3968K
[    2.481005] Run /sbin/init as init process
[    2.482030]   with arguments:
[    2.482034]     /sbin/init
[    2.482039]   with environment:
[    2.482043]     HOME=/
[    2.482048]     TERM=linux
[    2.492950] usb 1-1.3: new full-speed USB device number 4 using xhci_hcd
[    2.602742] usb 1-1.3: New USB device found, idVendor=10c4, idProduct=ea60, bcdDevice= 1.00
[    2.603930] usb 1-1.3: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[    2.605192] usb 1-1.3: Product: CP2102N USB to UART Bridge Controller
[    2.606359] usb 1-1.3: Manufacturer: Silicon Labs
[    2.607565] usb 1-1.3: SerialNumber: 00da33577e93eb119d5a194f3d98b6d1
[    2.644522] systemd[1]: System time before build time, advancing clock.
[    2.672936] usb 1-1.2.4: new low-speed USB device number 5 using xhci_hcd
[    2.683397] systemd[1]: systemd 250 running in system mode (+PAM -AUDIT -SELINUX +APPARMOR -IMA -SMACK -SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 -IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -BZIP2 -LZ4 -XZ +ZLIB -ZSTD -BPF_FRAMEWORK -XKBCOMMON -UTMP -SYSVINIT default-hierarchy=hybrid)
[    2.687614] systemd[1]: Detected architecture arm64.
[    2.726248] systemd[1]: Hostname set to <homeassistant>.
[    2.728226] systemd[1]: Installed transient /etc/machine-id file.
[    2.785850] usb 1-1.2.4: New USB device found, idVendor=046d, idProduct=c31c, bcdDevice=49.00
[    2.787239] usb 1-1.2.4: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[    2.788610] usb 1-1.2.4: Product: USB Keyboard
[    2.790143] usb 1-1.2.4: Manufacturer: Logitech
[    2.803848] input: Logitech USB Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2.4/1-1.2.4:1.0/0003:046D:C31C.0001/input/input0
[    2.852929] usb 1-1.4: new full-speed USB device number 6 using xhci_hcd
[    2.865377] hid-generic 0003:046D:C31C.0001: input,hidraw0: USB HID v1.10 Keyboard [Logitech USB Keyboard] on usb-0000:01:00.0-1.2.4/input0
[    2.873273] input: Logitech USB Keyboard as /devices/platform/scb/fd500000.pcie/pci0000:00/0000:00:00.0/0000:01:00.0/usb1/1-1/1-1.2/1-1.2.4/1-1.2.4:1.1/0003:046D:C31C.0002/input/input1
[    2.933183] hid-generic 0003:046D:C31C.0002: input,hidraw1: USB HID v1.10 Device [Logitech USB Keyboard] on usb-0000:01:00.0-1.2.4/input1
[    2.955192] usb 1-1.4: New USB device found, idVendor=1a86, idProduct=e024, bcdDevice= 1.0c
[    2.956729] usb 1-1.4: New USB device strings: Mfr=0, Product=0, SerialNumber=0
[    2.961867] hid-generic 0003:1A86:E024.0003: hiddev96,hidraw2: USB HID v1.00 Device [HID 1a86:e024] on usb-0000:01:00.0-1.4/input0
[    3.138776] systemd[1]: Queued start job for default target Multi-User System.
[    3.140983] random: systemd: uninitialized urandom read (16 bytes read)
[    3.172538] systemd[1]: Created slice Slice /system/ha-cli.
[    3.175814] random: systemd: uninitialized urandom read (16 bytes read)
[    3.179240] systemd[1]: Created slice Slice /system/modprobe.
[    3.182388] random: systemd: uninitialized urandom read (16 bytes read)
[    3.185799] systemd[1]: Created slice Slice /system/systemd-fsck.
[    3.191065] systemd[1]: Created slice Slice /system/systemd-growfs.
[    3.195868] systemd[1]: Created slice User and Session Slice.
[    3.199103] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[    3.202278] systemd[1]: Started Forward Password Requests to Wall Directory Watch.
[    3.205580] systemd[1]: Reached target Local Encrypted Volumes.
[    3.208599] systemd[1]: Reached target HassOS zram targets.
[    3.211634] systemd[1]: Reached target Local Integrity Protected Volumes.
[    3.214746] systemd[1]: Reached target Path Units.
[    3.217714] systemd[1]: Reached target Remote Encrypted Volumes.
[    3.220549] systemd[1]: Reached target Remote File Systems.
[    3.223395] systemd[1]: Reached target Remote Verity Protected Volumes.
[    3.226299] systemd[1]: Reached target Slice Units.
[    3.229248] systemd[1]: Reached target Local Verity Protected Volumes.
[    3.233180] systemd[1]: Listening on D-Bus System Message Bus Socket.
[    3.239697] systemd[1]: Listening on Process Core Dump Socket.
[    3.243889] systemd[1]: Listening on Journal Audit Socket.
[    3.247182] systemd[1]: Listening on Journal Socket (/dev/log).
[    3.250356] systemd[1]: Listening on Journal Socket.
[    3.254384] systemd[1]: Listening on udev Control Socket.
[    3.257350] systemd[1]: Listening on udev Kernel Socket.
[    3.260313] systemd[1]: Huge Pages File System was skipped because of a failed condition check (ConditionPathExists=/sys/kernel/mm/hugepages).
[    3.265232] systemd[1]: Mounting POSIX Message Queue File System...
[    3.272129] systemd[1]: Mounting Kernel Debug File System...
[    3.278850] systemd[1]: Mounting Kernel Trace File System...
[    3.286177] systemd[1]: Started D-Bus System Message Bus.
[    3.298898] systemd[1]: Starting Home Assistant OS Agent...
[    3.309929] systemd[1]: Starting Create List of Static Device Nodes...
[    3.317493] systemd[1]: Starting Load Kernel Module configfs...
[    3.325210] systemd[1]: Starting Load Kernel Module drm...
[    3.332239] systemd[1]: Starting Load Kernel Module fuse...
[    3.342256] systemd[1]: Starting Journal Service...
[    3.350362] systemd[1]: Starting Generate network units from Kernel command line...
[    3.358414] systemd[1]: Starting Remount Root and Kernel File Systems...
[    3.367059] systemd[1]: Starting Wait Until Kernel Time Synchronized...
[    3.374852] systemd[1]: Starting Coldplug All udev Devices...
[    3.382454] systemd[1]: Starting Disk Manager...
[    3.390484] systemd[1]: Starting HassOS ZRAM swap...
[    3.391229] fuse: init (API version 7.34)
[    3.400149] systemd[1]: Starting HassOS ZRAM tmp...
[    3.407801] systemd[1]: Starting HassOS ZRAM var...
[    3.417755] systemd[1]: Mounted POSIX Message Queue File System.
[    3.421294] systemd[1]: Mounted Kernel Debug File System.
[    3.424648] systemd[1]: Mounted Kernel Trace File System.
[    3.431178] systemd[1]: Finished Create List of Static Device Nodes.
[    3.436161] systemd[1]: modprobe@configfs.service: Deactivated successfully.
[    3.439057] systemd[1]: Finished Load Kernel Module configfs.
[    3.443260] systemd[1]: modprobe@drm.service: Deactivated successfully.
[    3.445829] systemd[1]: Finished Load Kernel Module drm.
[    3.451003] systemd[1]: modprobe@fuse.service: Deactivated successfully.
[    3.454635] systemd[1]: Finished Load Kernel Module fuse.
[    3.459890] systemd[1]: Finished Generate network units from Kernel command line.
[    3.466301] systemd[1]: Finished Remount Root and Kernel File Systems.
[    3.470746] systemd[1]: Reached target Preparation for Network.
[    3.481701] zram2: detected capacity change from 0 to 32768
[    3.482494] zram1: detected capacity change from 0 to 65536
[    3.486063] zram0: detected capacity change from 0 to 1941560
[    3.488353] systemd[1]: Mounting FUSE Control File System...
[    3.500318] systemd[1]: Mounting Kernel Configuration File System...
[    3.511004] systemd[1]: Starting Create Static Device Nodes in /dev...
[    3.517123] systemd[1]: Mounted FUSE Control File System.
[    3.520476] systemd[1]: Mounted Kernel Configuration File System.
[    3.561063] systemd[1]: Started Journal Service.
[    3.578170] audit: type=1334 audit(1642505743.931:2): prog-id=5 op=LOAD
[    3.580071] audit: type=1334 audit(1642505743.931:3): prog-id=6 op=LOAD
[    3.910307] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000
[    3.911939] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000
[    3.913044] usbcore: registered new interface driver cp210x
[    3.915090] usbserial: USB Serial support registered for cp210x
[    3.916389] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000
[    3.917833] cp210x 1-1.3:1.0: cp210x converter detected
[    3.925981] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000
[    3.927784] usb 1-1.3: cp210x converter now attached to ttyUSB0
[    3.957398] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned.
[    3.957567] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned.
[    3.961564] bcm2835_vc_sm_cma_probe: Videocore shared memory driver
[    3.963264] [vc_sm_connected_init]: start
[    3.988577] [vc_sm_connected_init]: installed successfully
[    4.038172] bcm2835_audio bcm2835_audio: card created with 4 channels
[    4.038623] videodev: Linux video capture interface: v2.00
[    4.063497] bcm2835_audio bcm2835_audio: card created with 4 channels
[    4.077378] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.081428] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.086033] checking generic (3e6c7000 3fc000) vs hw (0 ffffffffffffffff)
[    4.086053] fb0: switching to vc4 from simple
[    4.095764] Console: switching to colour dummy device 80x25
[    4.096754] vc4-drm gpu: bound fe600000.firmwarekms (ops vc4_fkms_ops [vc4])
[    4.097819] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned.
[    4.099861] [drm] Initialized vc4 0.0.0 20140616 for gpu on minor 0
[    4.135534] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned.
[    4.135531] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned.
[    4.138146] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned.
[    4.146843] bcm2835-codec bcm2835-codec: Device registered as /dev/video10
[    4.146899] bcm2835-codec bcm2835-codec: Loaded V4L2 decode
[    4.165616] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13
[    4.173574] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14
[    4.181335] bcm2835-codec bcm2835-codec: Device registered as /dev/video11
[    4.181368] bcm2835-codec bcm2835-codec: Loaded V4L2 encode
[    4.186289] bcm2835-codec bcm2835-codec: Device registered as /dev/video12
[    4.186327] bcm2835-codec bcm2835-codec: Loaded V4L2 isp
[    4.188286] bcm2835-codec bcm2835-codec: Device registered as /dev/video18
[    4.188316] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx
[    4.191813] bcm2835-codec bcm2835-codec: Device registered as /dev/video31
[    4.191846] bcm2835-codec bcm2835-codec: Loaded V4L2 encode_image
[    4.192035] [drm] Initialized v3d 1.0.0 20180419 for fec00000.v3d on minor 1
[    4.238216] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15
[    4.238706] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16
[    4.238734] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    4.238751] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    4.238765] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    4.238773] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    4.286373] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20
[    4.299069] cfg80211: Loading compiled-in X.509 certificates for regulatory database
[    4.310302] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7'
[    4.329448] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21
[    4.347219] Console: switching to colour frame buffer device 170x48
[    4.357736] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22
[    4.368314] vc4-drm gpu: [drm] fb0: vc4drmfb frame buffer device
[    4.386984] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23
[    4.388833] bcm2835-isp bcm2835-isp: Register output node 0 with media controller
[    4.389951] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller
[    4.390930] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller
[    4.391874] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller
[    4.407856] brcmfmac: F1 signature read @0x18000000=0x15264345
[    4.411041] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp
[    4.421077] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.422655] usbcore: registered new interface driver brcmfmac
[    4.423668] brcmfmac mmc1:0001:1: Direct firmware load for brcm/brcmfmac43455-sdio.raspberrypi,4-model-b.bin failed with error -2
[    4.655378] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.657438] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6
[    4.665662] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Nov  1 2021 00:37:25 version 7.45.241 (1a2f2fa CY) FWID 01-703fd60
[    6.129952] EXT4-fs (sda7): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    6.347732] EXT4-fs (sda8): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
[    6.382947] EXT4-fs (sda8): resizing filesystem from 29126769 to 29126769 blocks
[    6.634836] Adding 970776k swap on /dev/zram0.  Priority:-2 extents:1 across:970776k SSFS
[    7.625736] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier. Quota mode: none.
[    7.631639] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier. Quota mode: none.
[    7.761043] systemd-journald[124]: Received client request to flush runtime journal.
[    7.786179] random: crng init done
[    7.786194] random: 7 urandom warning(s) missed due to ratelimiting
[    7.792839] systemd-journald[124]: File /var/log/journal/511fe888ff364f9e8755d41305ab32fe/system.journal corrupted or uncleanly shut down, renaming and replacing.
[    8.248497] systemd-journald[124]: Oldest entry in /var/log/journal/511fe888ff364f9e8755d41305ab32fe/system.journal is older than the configured file retention duration (1month), suggesting rotation.
[    8.248523] systemd-journald[124]: /var/log/journal/511fe888ff364f9e8755d41305ab32fe/system.journal: Journal header limits reached or header out-of-date, rotating.
[    8.324014] audit: type=1334 audit(1650062620.754:4): prog-id=7 op=LOAD
[    8.324160] audit: type=1334 audit(1650062620.754:5): prog-id=8 op=LOAD
[    8.431625] uart-pl011 fe201000.serial: no DMA platform data
[    8.587690] audit: type=1334 audit(1650062621.018:6): prog-id=9 op=LOAD
[    8.587784] audit: type=1334 audit(1650062621.018:7): prog-id=10 op=LOAD
[    8.901377] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay)
[    8.901616] bcmgenet fd580000.ethernet eth0: Link is Down
[    8.945860] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled
[    9.015343] Bluetooth: Core ver 2.22
[    9.015457] NET: Registered PF_BLUETOOTH protocol family
[    9.015463] Bluetooth: HCI device and connection manager initialized
[    9.015480] Bluetooth: HCI socket layer initialized
[    9.015488] Bluetooth: L2CAP socket layer initialized
[    9.015506] Bluetooth: SCO socket layer initialized
[    9.021354] Bluetooth: HCI UART driver ver 2.3
[    9.021378] Bluetooth: HCI UART protocol H4 registered
[    9.021441] Bluetooth: HCI UART protocol Three-wire (H5) registered
[    9.021594] Bluetooth: HCI UART protocol Broadcom registered
[    9.201065] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    9.201087] Bluetooth: BNEP filters: protocol multicast
[    9.201104] Bluetooth: BNEP socket layer initialized
[    9.214424] NET: Registered PF_ALG protocol family
[   12.989037] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control rx/tx
[   12.989080] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
[   31.708915] cam-dummy-reg: disabling
[   38.640018] audit: type=1400 audit(1650062673.269:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=474 comm="apparmor_parser"
[   38.640051] audit: type=1400 audit(1650062673.269:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=474 comm="apparmor_parser"
[   38.640065] audit: type=1400 audit(1650062673.269:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=474 comm="apparmor_parser"
[   38.640081] audit: type=1300 audit(1650062673.269:8): arch=c00000b7 syscall=64 success=yes exit=37923 a0=6 a1=55b254cb70 a2=9423 a3=0 items=0 ppid=473 pid=474 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   38.640096] audit: type=1327 audit(1650062673.269:8): proctitle=61707061726D6F725F706172736572002D72002D57002D4C002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D73757065727669736F72
[   39.457114] audit: type=1334 audit(1650062674.097:9): prog-id=0 op=UNLOAD
[   39.457152] audit: type=1334 audit(1650062674.097:10): prog-id=0 op=UNLOAD
[   39.846259] audit: type=1400 audit(1650062674.481:11): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=500 comm="apparmor_parser"
[   39.846295] audit: type=1300 audit(1650062674.481:11): arch=c00000b7 syscall=64 success=yes exit=7945 a0=4 a1=55909d9d60 a2=1f09 a3=0 items=0 ppid=499 pid=500 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   39.846323] audit: type=1327 audit(1650062674.481:11): proctitle=61707061726D6F725F706172736572002D4B72002F6D6E742F646174612F646F636B65722F746D702F646F636B65722D64656661756C7433333330333032333234
[   40.576374] Initializing XFRM netlink socket
[   41.059454] hassio: port 1(vethbcb0000) entered blocking state
[   41.059519] hassio: port 1(vethbcb0000) entered disabled state
[   41.059994] device vethbcb0000 entered promiscuous mode
[   41.061297] hassio: port 1(vethbcb0000) entered blocking state
[   41.061314] hassio: port 1(vethbcb0000) entered forwarding state
[   41.061657] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready
[   41.061998] hassio: port 1(vethbcb0000) entered disabled state
[   41.903738] eth0: renamed from veth9f1cb3d
[   41.931393] IPv6: ADDRCONF(NETDEV_CHANGE): vethbcb0000: link becomes ready
[   41.931590] hassio: port 1(vethbcb0000) entered blocking state
[   41.931613] hassio: port 1(vethbcb0000) entered forwarding state
[   43.025113] docker0: port 1(vethd82f75b) entered blocking state
[   43.025166] docker0: port 1(vethd82f75b) entered disabled state
[   43.025454] device vethd82f75b entered promiscuous mode
[   43.068096] hassio: port 2(veth0d5df9e) entered blocking state
[   43.068119] hassio: port 2(veth0d5df9e) entered disabled state
[   43.069186] device veth0d5df9e entered promiscuous mode
[   43.070122] hassio: port 2(veth0d5df9e) entered blocking state
[   43.070141] hassio: port 2(veth0d5df9e) entered forwarding state
[   43.520481] eth0: renamed from vethf75c1d7
[   43.538500] hassio: port 2(veth0d5df9e) entered disabled state
[   43.538686] IPv6: ADDRCONF(NETDEV_CHANGE): vethd82f75b: link becomes ready
[   43.538826] docker0: port 1(vethd82f75b) entered blocking state
[   43.538839] docker0: port 1(vethd82f75b) entered forwarding state
[   43.539003] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready
[   43.651664] kauditd_printk_skb: 201 callbacks suppressed
[   43.651676] audit: type=1325 audit(1650062678.289:79): table=nat family=2 entries=0 op=xt_register pid=1084 subj==unconfined comm="iptables"
[   43.651763] audit: type=1300 audit(1650062678.289:79): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fc20b3420 items=0 ppid=1077 pid=1084 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   43.651810] audit: type=1327 audit(1650062678.289:79): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   43.669580] audit: type=1325 audit(1650062678.309:80): table=filter family=2 entries=0 op=xt_register pid=1086 subj==unconfined comm="iptables"
[   43.669670] audit: type=1300 audit(1650062678.309:80): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fc7957480 items=0 ppid=1077 pid=1086 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   43.669722] audit: type=1327 audit(1650062678.309:80): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   43.679556] audit: type=1325 audit(1650062678.317:81): table=nat family=2 entries=5 op=xt_replace pid=1089 subj==unconfined comm="iptables"
[   43.679593] audit: type=1300 audit(1650062678.317:81): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=55815e1a60 items=0 ppid=1077 pid=1089 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   43.679610] audit: type=1327 audit(1650062678.317:81): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B45525F4F5554505554
[   43.683258] audit: type=1325 audit(1650062678.321:82): table=nat family=2 entries=7 op=xt_replace pid=1090 subj==unconfined comm="iptables"
[   43.763101] eth1: renamed from veth8e890a3
[   43.786229] IPv6: ADDRCONF(NETDEV_CHANGE): veth0d5df9e: link becomes ready
[   43.786376] hassio: port 2(veth0d5df9e) entered blocking state
[   43.786389] hassio: port 2(veth0d5df9e) entered forwarding state
[   48.855635] kauditd_printk_skb: 20 callbacks suppressed
[   48.855648] audit: type=1334 audit(1650062683.493:89): prog-id=11 op=LOAD
[   48.855760] audit: type=1334 audit(1650062683.493:90): prog-id=12 op=LOAD
[   49.085232] audit: type=1334 audit(1650062683.725:91): prog-id=13 op=LOAD
[   49.085325] audit: type=1334 audit(1650062683.725:92): prog-id=14 op=LOAD
[   49.870317] audit: type=1400 audit(1650062684.501:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1437 comm="apparmor_parser"
[   49.870353] audit: type=1400 audit(1650062684.501:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1437 comm="apparmor_parser"
[   49.870372] audit: type=1400 audit(1650062684.501:93): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1437 comm="apparmor_parser"
[   49.870391] audit: type=1300 audit(1650062684.501:93): arch=c00000b7 syscall=64 success=yes exit=37923 a0=6 a1=55883e8b70 a2=9423 a3=0 items=0 ppid=1436 pid=1437 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="apparmor_parser" exe="/usr/sbin/apparmor_parser" subj==unconfined key=(null)
[   49.870410] audit: type=1327 audit(1650062684.501:93): proctitle=61707061726D6F725F706172736572002D2D7265706C616365002D2D77726974652D6361636865002D2D63616368652D6C6F63002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F6361636865002F6D6E742F646174612F73757065727669736F722F61707061726D6F722F68617373696F2D737570
[   50.083313] hassio: port 3(veth0c90fc4) entered blocking state
[   50.083340] hassio: port 3(veth0c90fc4) entered disabled state
[   50.083577] device veth0c90fc4 entered promiscuous mode
[   50.083661] audit: type=1700 audit(1650062684.721:94): dev=veth0c90fc4 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   50.690950] eth0: renamed from veth630af71
[   50.722884] IPv6: ADDRCONF(NETDEV_CHANGE): veth0c90fc4: link becomes ready
[   50.723111] hassio: port 3(veth0c90fc4) entered blocking state
[   50.723132] hassio: port 3(veth0c90fc4) entered forwarding state
[   51.061828] hassio: port 4(veth122da72) entered blocking state
[   51.061854] hassio: port 4(veth122da72) entered disabled state
[   51.062709] device veth122da72 entered promiscuous mode
[   51.063194] hassio: port 4(veth122da72) entered blocking state
[   51.063209] hassio: port 4(veth122da72) entered forwarding state
[   51.085867] hassio: port 4(veth122da72) entered disabled state
[   51.706669] eth0: renamed from veth163e08b
[   51.725974] IPv6: ADDRCONF(NETDEV_CHANGE): veth122da72: link becomes ready
[   51.726102] hassio: port 4(veth122da72) entered blocking state
[   51.726111] hassio: port 4(veth122da72) entered forwarding state
[   52.075586] hassio: port 5(veth05cad65) entered blocking state
[   52.075608] hassio: port 5(veth05cad65) entered disabled state
[   52.075824] device veth05cad65 entered promiscuous mode
[   52.078284] hassio: port 5(veth05cad65) entered blocking state
[   52.078305] hassio: port 5(veth05cad65) entered forwarding state
[   52.089156] hassio: port 5(veth05cad65) entered disabled state
[   52.739798] eth0: renamed from veth50de639
[   52.754097] IPv6: ADDRCONF(NETDEV_CHANGE): veth05cad65: link becomes ready
[   52.754235] hassio: port 5(veth05cad65) entered blocking state
[   52.754248] hassio: port 5(veth05cad65) entered forwarding state
[   55.612084] Bluetooth: RFCOMM TTY layer initialized
[   55.612120] Bluetooth: RFCOMM socket layer initialized
[   55.612140] Bluetooth: RFCOMM ver 1.11
[   56.680605] hassio: port 6(vethab7086f) entered blocking state
[   56.680628] hassio: port 6(vethab7086f) entered disabled state
[   56.680817] device vethab7086f entered promiscuous mode
[   56.681054] kauditd_printk_skb: 98 callbacks suppressed
[   56.681064] audit: type=1700 audit(1650062691.317:127): dev=vethab7086f prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   56.682351] audit: type=1300 audit(1650062691.317:127): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=4000beb560 a2=28 a3=0 items=0 ppid=1 pid=475 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)
[   56.682920] audit: type=1327 audit(1650062691.317:127): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   56.713527] audit: type=1325 audit(1650062691.353:128): table=nat family=2 entries=15 op=xt_replace pid=3016 subj==unconfined comm="iptables"
[   56.713560] audit: type=1300 audit(1650062691.353:128): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=5586d61c70 items=0 ppid=475 pid=3016 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   56.713579] audit: type=1327 audit(1650062691.353:128): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4100444F434B4552002D7000746370002D6400302F30002D2D64706F72740038383834002D6A00444E4154002D2D746F2D64657374696E6174696F6E003137322E33302E33332E303A383838340000002D690068617373696F
[   56.722681] audit: type=1325 audit(1650062691.361:129): table=filter family=2 entries=30 op=xt_replace pid=3018 subj==unconfined comm="iptables"
[   56.722720] audit: type=1300 audit(1650062691.361:129): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=55c8b5dff0 items=0 ppid=475 pid=3018 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   56.722738] audit: type=1327 audit(1650062691.361:129): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D740066696C746572002D4100444F434B45520000002D690068617373696F002D6F0068617373696F002D7000746370002D64003137322E33302E33332E30002D2D64706F72740038383834002D6A00414343455054
[   56.733279] audit: type=1325 audit(1650062691.373:130): table=nat family=2 entries=16 op=xt_replace pid=3020 subj==unconfined comm="iptables"
[   57.483396] eth0: renamed from veth0b74375
[   57.506728] IPv6: ADDRCONF(NETDEV_CHANGE): vethab7086f: link becomes ready
[   57.506912] hassio: port 6(vethab7086f) entered blocking state
[   57.506929] hassio: port 6(vethab7086f) entered forwarding state
[   57.835505] hassio: port 7(veth36b2035) entered blocking state
[   57.835526] hassio: port 7(veth36b2035) entered disabled state
[   57.841117] device veth36b2035 entered promiscuous mode
[   58.563000] eth0: renamed from vethbeb6ea3
[   58.585905] IPv6: ADDRCONF(NETDEV_CHANGE): veth36b2035: link becomes ready
[   58.586008] hassio: port 7(veth36b2035) entered blocking state
[   58.586019] hassio: port 7(veth36b2035) entered forwarding state
[   58.945458] hassio: port 8(vethd64dacd) entered blocking state
[   58.945478] hassio: port 8(vethd64dacd) entered disabled state
[   58.950086] device vethd64dacd entered promiscuous mode
[   59.787656] eth0: renamed from vethc8a7f31
[   59.808003] IPv6: ADDRCONF(NETDEV_CHANGE): vethd64dacd: link becomes ready
[   59.808186] hassio: port 8(vethd64dacd) entered blocking state
[   59.808203] hassio: port 8(vethd64dacd) entered forwarding state
[   65.192939] hassio: port 9(veth61dfdab) entered blocking state
[   65.192963] hassio: port 9(veth61dfdab) entered disabled state
[   65.193190] device veth61dfdab entered promiscuous mode
[   65.193251] kauditd_printk_skb: 134 callbacks suppressed
[   65.193256] audit: type=1700 audit(1650062699.829:175): dev=veth61dfdab prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295
[   65.193462] audit: type=1300 audit(1650062699.829:175): arch=c00000b7 syscall=206 success=yes exit=40 a0=f a1=400130a600 a2=28 a3=0 items=0 ppid=1 pid=475 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)
[   65.193507] audit: type=1327 audit(1650062699.829:175): proctitle=2F7573722F62696E2F646F636B657264002D480066643A2F2F002D2D6370752D72742D72756E74696D653D393530303030002D2D73746F726167652D6472697665723D6F7665726C617932002D2D6C6F672D6472697665723D6A6F75726E616C64002D2D6C6F672D6F7074007461673D7B7B2E496D6167654E616D657D7D2F7B
[   66.018412] audit: type=1325 audit(1650062700.657:176): table=nat family=2 entries=0 op=xt_register pid=7006 subj==unconfined comm="iptables"
[   66.018450] audit: type=1300 audit(1650062700.657:176): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fdbdae560 items=0 ppid=6990 pid=7006 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   66.018466] audit: type=1327 audit(1650062700.657:176): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   66.037715] audit: type=1325 audit(1650062700.677:177): table=filter family=2 entries=0 op=xt_register pid=7008 subj==unconfined comm="iptables"
[   66.037755] audit: type=1300 audit(1650062700.677:177): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fe1184360 items=0 ppid=6990 pid=7008 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   66.037770] audit: type=1327 audit(1650062700.677:177): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   66.053660] audit: type=1325 audit(1650062700.689:178): table=nat family=2 entries=5 op=xt_replace pid=7011 subj==unconfined comm="iptables"
[   66.134542] eth0: renamed from veth5ce0528
[   66.152005] IPv6: ADDRCONF(NETDEV_CHANGE): veth61dfdab: link becomes ready
[   66.152166] hassio: port 9(veth61dfdab) entered blocking state
[   66.152180] hassio: port 9(veth61dfdab) entered forwarding state
[   67.144598] hassio: port 10(vethf86e312) entered blocking state
[   67.144832] hassio: port 10(vethf86e312) entered disabled state
[   67.145729] device vethf86e312 entered promiscuous mode
[   68.354394] eth0: renamed from veth387a377
[   68.379610] IPv6: ADDRCONF(NETDEV_CHANGE): vethf86e312: link becomes ready
[   68.379772] hassio: port 10(vethf86e312) entered blocking state
[   68.379785] hassio: port 10(vethf86e312) entered forwarding state
[   69.702017] hassio: port 11(veth59e0ef0) entered blocking state
[   69.702045] hassio: port 11(veth59e0ef0) entered disabled state
[   69.703240] device veth59e0ef0 entered promiscuous mode
[   70.745367] kauditd_printk_skb: 59 callbacks suppressed
[   70.745388] audit: type=1325 audit(1650062705.385:198): table=nat family=2 entries=0 op=xt_register pid=8366 subj==unconfined comm="iptables"
[   70.745422] audit: type=1300 audit(1650062705.385:198): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7fddb87720 items=0 ppid=8342 pid=8366 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   70.745440] audit: type=1327 audit(1650062705.385:198): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4C002D6E
[   70.767158] audit: type=1325 audit(1650062705.401:199): table=filter family=2 entries=0 op=xt_register pid=8370 subj==unconfined comm="iptables"
[   70.767207] audit: type=1300 audit(1650062705.401:199): arch=c00000b7 syscall=209 success=yes exit=0 a0=4 a1=0 a2=40 a3=7feabda580 items=0 ppid=8342 pid=8370 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   70.767225] audit: type=1327 audit(1650062705.401:199): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D4C002D6E
[   70.789660] audit: type=1325 audit(1650062705.429:200): table=nat family=2 entries=5 op=xt_replace pid=8378 subj==unconfined comm="iptables"
[   70.789710] audit: type=1300 audit(1650062705.429:200): arch=c00000b7 syscall=208 success=yes exit=0 a0=4 a1=0 a2=40 a3=557856da60 items=0 ppid=8342 pid=8378 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="iptables" exe="/usr/sbin/xtables-legacy-multi" subj==unconfined key=(null)
[   70.789727] audit: type=1327 audit(1650062705.429:200): proctitle=2F7573722F7362696E2F69707461626C6573002D2D77616974002D74006E6174002D4E00444F434B45525F4F5554505554
[   70.797255] audit: type=1325 audit(1650062705.437:201): table=nat family=2 entries=7 op=xt_replace pid=8386 subj==unconfined comm="iptables"
[   70.939133] eth0: renamed from veth75d4c81
[   70.979860] IPv6: ADDRCONF(NETDEV_CHANGE): veth59e0ef0: link becomes ready
[   70.980048] hassio: port 11(veth59e0ef0) entered blocking state
[   70.980066] hassio: port 11(veth59e0ef0) entered forwarding state
[   79.443159] kauditd_printk_skb: 20 callbacks suppressed
[   79.443182] audit: type=1334 audit(1650062714.080:208): prog-id=0 op=UNLOAD
[   79.443215] audit: type=1334 audit(1650062714.080:209): prog-id=0 op=UNLOAD
[   79.517605] audit: type=1334 audit(1650062714.156:210): prog-id=0 op=UNLOAD
[   79.517645] audit: type=1334 audit(1650062714.156:211): prog-id=0 op=UNLOAD
#

System Health information

Can't get there

Additional information

No response

agners commented 2 years ago

My rpi4-64 test instance came up fine, I wonder what is exactly blocking on your end. Can you get the Supervisor logs (ha su logs)?

bschatzow commented 2 years ago

The only error is posted above. 22-04-15 18:48:01 ERROR (MainThread) [supervisor.homeassistant.api] Error on call https://172.30.32.1:8123/api/config:

I have restored so I can't get more information. If you want me to try again I can.

bschatzow commented 2 years ago

I just tried again and it work via CLI. Not sure why it failed before, but it did.