search

home-assistant / operating-system

:beginner: Home Assistant Operating System
Apache License 2.0
4.78k stars 957 forks source link

Upgrade from HAos 7.6 to 8.x not possible #1978

Closed muntoris closed 1 year ago

muntoris commented 2 years ago

Describe the issue you are experiencing

Upgrade from HAOS 7.6 to any 8.x version is not possible.

What operating system image do you use?

rpi3-64 (Raspberry Pi 3 64-bit OS)

What version of Home Assistant Operating System is installed?

7.6

Did you upgrade the Operating System.

No

Steps to reproduce the issue

  1. click on "install" button for OS update
  2. wait for reboot
  3. still old version installed

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

not as far as I see

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

not as far as I see

System Health information

No response

Additional information

No response

muntoris commented 2 years ago

I wonder, if there is a way to manual update the HA OS.

agners commented 2 years ago

Can you post the Supervisor logs after pressing the upgrade button?

wingedseed commented 2 years ago

Can you post the Supervisor logs after pressing the upgrade button?

22-06-27 20:32:04 ERROR (MainThread) [supervisor.os.manager] Home Assistant Operating System update failed with: Installation error: Failed updating slot boot.0: failed to run slot hook: Child process exited with code 1

muntoris commented 2 years ago

here we go .. supervisor log after an upgrade try:

22-06-27 14:49:56 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-audio with version 2022.05.0
22-06-27 14:49:56 INFO (MainThread) [supervisor.plugins.audio] Starting Audio plugin
22-06-27 14:49:58 INFO (SyncWorker_0) [supervisor.docker.audio] Starting Audio ghcr.io/home-assistant/aarch64-hassio-audio with version 2022.05.0 - 172.30.32.4
22-06-27 14:49:58 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-observer with version 2021.10.0
22-06-27 14:49:58 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/aarch64-hassio-multicast with version 2022.02.0
22-06-27 14:49:58 INFO (MainThread) [supervisor.plugins.multicast] Starting Multicast plugin
22-06-27 14:49:59 INFO (SyncWorker_1) [supervisor.docker.multicast] Starting Multicast ghcr.io/home-assistant/aarch64-hassio-multicast with version 2022.02.0 - Host
22-06-27 14:49:59 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
22-06-27 14:50:00 INFO (MainThread) [supervisor.homeassistant.secrets] Loaded 1 Home Assistant secrets
22-06-27 14:50:00 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to ghcr.io/home-assistant/raspberrypi4-64-homeassistant with version 2022.6.7
22-06-27 14:50:01 INFO (MainThread) [supervisor.os.manager] Detect Home Assistant Operating System 7.6 / BootSlot A
22-06-27 14:50:02 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/3a26b21d repository
22-06-27 14:50:02 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/core repository
22-06-27 14:50:02 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/a0d7b954 repository
22-06-27 14:50:02 INFO (MainThread) [supervisor.store.git] Loading add-on /data/addons/git/75a80a57 repository
22-06-27 14:50:04 INFO (MainThread) [supervisor.store] Loading add-ons from store: 64 all - 64 new - 0 remove
22-06-27 14:50:04 INFO (MainThread) [supervisor.addons] Found 9 installed add-ons
22-06-27 14:50:04 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-samba with version 10.0.0
22-06-27 14:50:04 INFO (SyncWorker_3) [supervisor.docker.interface] Attaching to bropat/eufy-security-ws with version 0.9.2
22-06-27 14:50:04 INFO (SyncWorker_2) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-letsencrypt with version 4.12.5
22-06-27 14:50:04 INFO (SyncWorker_5) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/node-red/aarch64 with version 12.0.2
22-06-27 14:50:04 INFO (SyncWorker_4) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-ssh with version 9.4.0
22-06-27 14:50:04 INFO (SyncWorker_6) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/influxdb/aarch64 with version 4.5.0
22-06-27 14:50:04 INFO (SyncWorker_1) [supervisor.docker.interface] Attaching to aler9/rtsp-simple-server with version v0.17.6
22-06-27 14:50:04 INFO (SyncWorker_7) [supervisor.docker.interface] Attaching to ghcr.io/hassio-addons/grafana/aarch64 with version 7.6.0
22-06-27 14:50:04 INFO (SyncWorker_0) [supervisor.docker.interface] Attaching to homeassistant/aarch64-addon-configurator with version 5.3.3
22-06-27 14:50:04 INFO (MainThread) [supervisor.backups.manager] Found 97 backup files
22-06-27 14:50:04 INFO (MainThread) [supervisor.discovery] Loaded 0 messages
22-06-27 14:50:04 INFO (MainThread) [supervisor.ingress] Loaded 0 ingress sessions
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.SETUP
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.check] System checks complete
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-06-27 14:50:04 INFO (MainThread) [supervisor.jobs] 'ResolutionFixup.run_autofix' blocked from execution, system is not running - CoreState.SETUP
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.SETUP
22-06-27 14:50:04 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-06-27 14:50:04 INFO (MainThread) [__main__] Running Supervisor
22-06-27 14:50:04 INFO (MainThread) [supervisor.os.manager] Rauc: A - marked slot kernel.0 as good
22-06-27 14:50:04 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.INITIALIZE' starting 0 add-ons
22-06-27 14:50:04 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SYSTEM' starting 0 add-ons
22-06-27 14:50:04 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.SERVICES' starting 3 add-ons
22-06-27 14:50:04 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
22-06-27 14:50:04 WARNING (SyncWorker_0) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
22-06-27 14:50:06 INFO (SyncWorker_0) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-samba with version 10.0.0
22-06-27 14:50:07 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
22-06-27 14:50:08 INFO (SyncWorker_5) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/influxdb/aarch64 with version 4.5.0
22-06-27 14:50:08 INFO (MainThread) [supervisor.api.middleware.security] /network/info access from core_samba
22-06-27 14:50:09 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/grafana/aarch64 with version 7.6.0
22-06-27 14:50:14 INFO (MainThread) [supervisor.core] Start Home Assistant Core
22-06-27 14:50:15 INFO (SyncWorker_3) [supervisor.docker.interface] Starting homeassistant
22-06-27 14:50:15 INFO (MainThread) [supervisor.homeassistant.core] Wait until Home Assistant is ready
22-06-27 14:50:15 INFO (MainThread) [supervisor.api.middleware.security] /dns/info access from a0d7b954_influxdb
22-06-27 14:50:31 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.STARTUP
22-06-27 14:50:31 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-06-27 14:50:32 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
22-06-27 14:50:32 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/home-assistant/addons repository
22-06-27 14:50:32 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/hassio-addons/repository repository
22-06-27 14:50:32 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/fuatakgun/eufy_security_addon repository
22-06-27 14:50:32 INFO (MainThread) [supervisor.store.git] Update add-on https://github.com/fuatakgun/rtsp_simple_server repository
22-06-27 14:50:33 INFO (MainThread) [supervisor.homeassistant.api] Updated Home Assistant API token
22-06-27 14:50:35 INFO (MainThread) [supervisor.store] Loading add-ons from store: 64 all - 0 new - 0 remove
22-06-27 14:50:35 INFO (MainThread) [supervisor.store] Loading add-ons from store: 64 all - 0 new - 0 remove
22-06-27 14:50:37 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
22-06-27 14:51:43 INFO (MainThread) [supervisor.homeassistant.core] Detect a running Home Assistant instance
22-06-27 14:51:43 INFO (MainThread) [supervisor.addons] Phase 'AddonStartup.APPLICATION' starting 4 add-ons
22-06-27 14:51:45 INFO (SyncWorker_6) [supervisor.docker.addon] Starting Docker add-on homeassistant/aarch64-addon-configurator with version 5.3.3
22-06-27 14:51:47 INFO (SyncWorker_3) [supervisor.docker.addon] Starting Docker add-on bropat/eufy-security-ws with version 0.9.2
22-06-27 14:51:49 INFO (SyncWorker_6) [supervisor.docker.addon] Starting Docker add-on aler9/rtsp-simple-server with version v0.17.6
22-06-27 14:51:51 INFO (SyncWorker_4) [supervisor.docker.addon] Starting Docker add-on ghcr.io/hassio-addons/node-red/aarch64 with version 12.0.2
22-06-27 14:51:56 INFO (MainThread) [supervisor.misc.tasks] All core tasks are scheduled
22-06-27 14:51:56 INFO (MainThread) [supervisor.core] Supervisor is up and running
22-06-27 14:51:56 INFO (MainThread) [supervisor.host.info] Updating local host information
22-06-27 14:51:56 INFO (MainThread) [supervisor.updater] Fetching update data from https://version.home-assistant.io/stable.json
22-06-27 14:51:56 INFO (MainThread) [supervisor.resolution.check] Starting system checks with state CoreState.RUNNING
22-06-27 14:51:56 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.DNS_SERVER_IPV6_ERROR/ContextType.DNS_SERVER
22-06-27 14:51:56 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.SECURITY/ContextType.CORE
22-06-27 14:51:56 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.TRUST/ContextType.SUPERVISOR
22-06-27 14:51:56 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.PWNED/ContextType.ADDON
22-06-27 14:51:56 WARNING (MainThread) [supervisor.addons.options] Option 'interface' does not exist in the schema for Samba share (core_samba)
22-06-27 14:51:57 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.FREE_SPACE/ContextType.SYSTEM
22-06-27 14:51:57 INFO (MainThread) [supervisor.resolution.checks.base] Run check for IssueType.DNS_SERVER_FAILED/ContextType.DNS_SERVER
22-06-27 14:51:57 INFO (MainThread) [supervisor.resolution.check] System checks complete
22-06-27 14:51:57 INFO (MainThread) [supervisor.resolution.evaluate] Starting system evaluation with state CoreState.RUNNING
22-06-27 14:51:57 INFO (MainThread) [supervisor.host.services] Updating service information
22-06-27 14:51:57 INFO (MainThread) [supervisor.host.network] Updating local network information
22-06-27 14:51:58 INFO (MainThread) [supervisor.resolution.evaluate] System evaluation complete
22-06-27 14:51:58 INFO (MainThread) [supervisor.resolution.fixup] Starting system autofix at state CoreState.RUNNING
22-06-27 14:51:59 INFO (MainThread) [supervisor.resolution.fixup] System autofix complete
22-06-27 14:51:59 INFO (MainThread) [supervisor.host.sound] Updating PulseAudio information
22-06-27 14:51:59 INFO (MainThread) [supervisor.host.manager] Host information reload completed
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request initialize
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] WebSocket access from a0d7b954_nodered
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
22-06-27 14:52:39 INFO (MainThread) [supervisor.api.proxy] Home Assistant WebSocket API request running
muntoris commented 2 years ago

also the host log:

``` [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x410fd083] [ 0.000000] Linux version 5.10.103-v8 (builder@f0f12117096a) (aarch64-buildroot-linux-gnu-gcc.br_real (Buildroot -g8d88bd9a3) 9.4.0, GNU ld (GNU Binutils) 2.36.1) #1 SMP PREEMPT Mon Mar 28 11:44:31 UTC 2022 [ 0.000000] random: fast init done [ 0.000000] Machine model: Raspberry Pi 4 Model B Rev 1.1 [ 0.000000] efi: UEFI not found. [ 0.000000] Reserved memory: created CMA memory pool at 0x000000002ac00000, size 64 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-0x000000003b3fffff] [ 0.000000] node 0: [mem 0x0000000040000000-0x00000000fbffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000000000000-0x00000000fbffffff] [ 0.000000] On node 0 totalpages: 1012736 [ 0.000000] DMA zone: 3792 pages used for memmap [ 0.000000] DMA zone: 0 pages reserved [ 0.000000] DMA zone: 242688 pages, LIFO batch:63 [ 0.000000] DMA32 zone: 12032 pages used for memmap [ 0.000000] DMA32 zone: 770048 pages, LIFO batch:63 [ 0.000000] percpu: Embedded 32 pages/cpu s91544 r8192 d31336 u131072 [ 0.000000] pcpu-alloc: s91544 r8192 d31336 u131072 alloc=32*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: ARM errata 1165522, 1319367, or 1530923 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 996912 [ 0.000000] Kernel command line: zram.enabled=1 zram.num_devices=3 apparmor=1 security=apparmor rootwait systemd.machine_id=0d5c515dcae14dfe8f83a3c9bee22af7 cgroup_enable=memory fsck.repair=yes coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_compat_alsa=0 snd_bcm2835.enable_hdmi=1 bcm2708_fb.fbwidth=0 bcm2708_fb.fbheight=0 bcm2708_fb.fbswap=1 smsc95xx.macaddr=DC:A6:32:42:26:CE vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 dwc_otg.lpm_enable=0 console=tty1 root=PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd rootfstype=squashfs ro rauc.slot=A [ 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 0x0000000037400000-0x000000003b400000] (64MB) [ 0.000000] Memory: 3815184K/4050944K available (12288K kernel code, 1994K rwdata, 4228K rodata, 3904K init, 1274K bss, 170224K reserved, 65536K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 [ 0.000000] ftrace: allocating 39661 entries in 155 pages [ 0.000000] ftrace: allocated 155 pages with 5 groups [ 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] GIC: Using split EOI/Deactivate mode [ 0.000000] random: get_random_bytes called from start_kernel+0x3b0/0x574 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.000005] sched_clock: 56 bits at 54MHz, resolution 18ns, wraps every 4398046511102ns [ 0.000272] Console: colour dummy device 80x25 [ 0.000843] printk: console [tty1] enabled [ 0.000901] Calibrating delay loop (skipped), value calculated using timer frequency.. 108.00 BogoMIPS (lpj=216000) [ 0.000943] pid_max: default: 32768 minimum: 301 [ 0.001095] LSM: Security Framework initializing [ 0.001217] AppArmor: AppArmor initialized [ 0.001435] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.001514] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) [ 0.004668] rcu: Hierarchical SRCU implementation. [ 0.005726] EFI services will not be available. [ 0.006259] smp: Bringing up secondary CPUs ... [ 0.007235] Detected PIPT I-cache on CPU1 [ 0.007309] CPU1: Booted secondary processor 0x0000000001 [0x410fd083] [ 0.008473] Detected PIPT I-cache on CPU2 [ 0.008520] CPU2: Booted secondary processor 0x0000000002 [0x410fd083] [ 0.009606] Detected PIPT I-cache on CPU3 [ 0.009652] CPU3: Booted secondary processor 0x0000000003 [0x410fd083] [ 0.009806] smp: Brought up 1 node, 4 CPUs [ 0.009894] SMP: Total of 4 processors activated. [ 0.009918] CPU features: detected: 32-bit EL0 Support [ 0.009941] CPU features: detected: CRC32 instructions [ 0.009965] CPU features: detected: 32-bit EL1 Support [ 0.048263] CPU: All CPU(s) started at EL2 [ 0.048349] alternatives: patching kernel code [ 0.049903] devtmpfs: initialized [ 0.061014] Enabled cp15_barrier support [ 0.061078] Enabled setend support [ 0.061290] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 7645041785100000 ns [ 0.061334] futex hash table entries: 1024 (order: 4, 65536 bytes, linear) [ 0.063287] pinctrl core: initialized pinctrl subsystem [ 0.064134] DMI not present or invalid. [ 0.064535] NET: Registered protocol family 16 [ 0.067807] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations [ 0.068096] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.069061] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.069143] audit: initializing netlink subsys (disabled) [ 0.069510] audit: type=2000 audit(0.068:1): state=initialized audit_enabled=0 res=1 [ 0.069986] thermal_sys: Registered thermal governor 'step_wise' [ 0.070432] cpuidle: using governor menu [ 0.070979] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.071168] ASID allocator initialised with 65536 entries [ 0.071313] Serial: AMBA PL011 UART driver [ 0.099277] bcm2835-mbox fe00b880.mailbox: mailbox enabled [ 0.104413] raspberrypi-firmware soc:firmware: Attached to firmware from 2022-03-24T13:19:26, variant start [ 0.108431] raspberrypi-firmware soc:firmware: Firmware hash is e5a963efa66a1974127860b42e913d2374139ff5 [ 0.144280] bcm2835-dma fe007000.dma: DMA legacy API manager, dmachans=0x1 [ 0.148937] vgaarb: loaded [ 0.149365] SCSI subsystem initialized [ 0.149590] usbcore: registered new interface driver usbfs [ 0.149656] usbcore: registered new interface driver hub [ 0.149734] usbcore: registered new device driver usb [ 0.150066] usb_phy_generic phy: supply vcc not found, using dummy regulator [ 0.150453] mc: Linux media interface: v0.10 [ 0.152106] clocksource: Switched to clocksource arch_sys_counter [ 1.171479] VFS: Disk quotas dquot_6.6.0 [ 1.171599] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 1.171767] FS-Cache: Loaded [ 1.172003] CacheFiles: Loaded [ 1.172780] AppArmor: AppArmor Filesystem Enabled [ 1.183366] NET: Registered protocol family 2 [ 1.183912] IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 1.187434] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) [ 1.187519] TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) [ 1.187760] TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) [ 1.188326] TCP: Hash tables configured (established 32768 bind 32768) [ 1.188594] UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) [ 1.188687] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) [ 1.188982] NET: Registered protocol family 1 [ 1.189869] RPC: Registered named UNIX socket transport module. [ 1.189897] RPC: Registered udp transport module. [ 1.189917] RPC: Registered tcp transport module. [ 1.189937] RPC: Registered tcp NFSv4.1 backchannel transport module. [ 1.189969] PCI: CLS 0 bytes, default 64 [ 1.192499] hw perfevents: enabled with armv8_cortex_a72 PMU driver, 7 counters available [ 1.192822] kvm [1]: IPA Size Limit: 44 bits [ 1.194059] kvm [1]: vgic interrupt IRQ9 [ 1.194355] kvm [1]: Hyp mode initialized successfully [ 1.196479] Initialise system trusted keyrings [ 1.196832] workingset: timestamp_bits=46 max_order=20 bucket_order=0 [ 1.203087] zbud: loaded [ 1.204797] squashfs: version 4.0 (2009/01/31) Phillip Lougher [ 1.205078] FS-Cache: Netfs 'nfs' registered for caching [ 1.205724] NFS: Registering the id_resolver key type [ 1.205780] Key type id_resolver registered [ 1.205802] Key type id_legacy registered [ 1.205926] nfs4filelayout_init: NFSv4 File Layout Driver Registering... [ 1.205953] nfs4flexfilelayout_init: NFSv4 Flexfile Layout Driver Registering... [ 1.207165] Key type asymmetric registered [ 1.207193] Asymmetric key parser 'x509' registered [ 1.207250] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248) [ 1.207504] io scheduler mq-deadline registered [ 1.207530] io scheduler kyber registered [ 1.211620] brcm-pcie fd500000.pcie: host bridge /scb/pcie@7d500000 ranges: [ 1.211666] brcm-pcie fd500000.pcie: No bus range found for /scb/pcie@7d500000, using [bus 00-ff] [ 1.211753] brcm-pcie fd500000.pcie: MEM 0x0600000000..0x063fffffff -> 0x00c0000000 [ 1.211856] brcm-pcie fd500000.pcie: IB MEM 0x0000000000..0x00bfffffff -> 0x0400000000 [ 1.262202] brcm-pcie fd500000.pcie: link up, 5.0 GT/s PCIe x1 (SSC) [ 1.262579] brcm-pcie fd500000.pcie: PCI host bridge to bus 0000:00 [ 1.262610] pci_bus 0000:00: root bus resource [bus 00-ff] [ 1.262639] pci_bus 0000:00: root bus resource [mem 0x600000000-0x63fffffff] (bus address [0xc0000000-0xffffffff]) [ 1.262740] pci 0000:00:00.0: [14e4:2711] type 01 class 0x060400 [ 1.262995] pci 0000:00:00.0: PME# supported from D0 D3hot [ 1.267213] pci 0000:01:00.0: [1106:3483] type 00 class 0x0c0330 [ 1.267293] pci 0000:01:00.0: reg 0x10: [mem 0x00000000-0x00000fff 64bit] [ 1.267548] pci 0000:01:00.0: PME# supported from D0 D3cold [ 1.271419] pci 0000:00:00.0: BAR 8: assigned [mem 0x600000000-0x6000fffff] [ 1.271454] pci 0000:01:00.0: BAR 0: assigned [mem 0x600000000-0x600000fff 64bit] [ 1.271506] pci 0000:00:00.0: PCI bridge to [bus 01] [ 1.271538] pci 0000:00:00.0: bridge window [mem 0x600000000-0x6000fffff] [ 1.272328] bcm2708_fb soc:fb: Unable to determine number of FBs. Disabling driver. [ 1.272376] bcm2708_fb: probe of soc:fb failed with error -2 [ 1.279731] iproc-rng200 fe104000.rng: hwrng registered [ 1.280182] vc-mem: phys_addr:0x00000000 mem_base=0x3ec00000 mem_size:0x40000000(1024 MiB) [ 1.281484] gpiomem-bcm2835 fe200000.gpiomem: Initialised: Registers at 0xfe200000 [ 1.281905] cacheinfo: Unable to detect cache hierarchy for CPU 0 [ 1.293364] brd: module loaded [ 1.305629] loop: module loaded [ 1.306228] zram: Added device: zram0 [ 1.306797] zram: Added device: zram1 [ 1.307312] zram: Added device: zram2 [ 1.309136] Loading iSCSI transport class v2.0-870. [ 1.313787] tun: Universal TUN/TAP device driver, 1.6 [ 1.314658] bcmgenet fd580000.ethernet: GENET 5.0 EPHY: 0x0000 [ 1.372245] unimac-mdio unimac-mdio.-19: Broadcom UniMAC MDIO bus [ 1.374829] pci 0000:00:00.0: enabling device (0000 -> 0002) [ 1.374879] xhci_hcd 0000:01:00.0: enabling device (0000 -> 0002) [ 1.374990] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 1.375042] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 [ 1.375820] xhci_hcd 0000:01:00.0: hcc params 0x002841eb hci version 0x100 quirks 0x00000e0000000890 [ 1.376774] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.10 [ 1.376809] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.376835] usb usb1: Product: xHCI Host Controller [ 1.376858] usb usb1: Manufacturer: Linux 5.10.103-v8 xhci-hcd [ 1.376881] usb usb1: SerialNumber: 0000:01:00.0 [ 1.377574] hub 1-0:1.0: USB hub found [ 1.377652] hub 1-0:1.0: 1 port detected [ 1.378231] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 1.378266] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 [ 1.378302] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed [ 1.378667] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.10 [ 1.378698] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 1.378724] usb usb2: Product: xHCI Host Controller [ 1.378746] usb usb2: Manufacturer: Linux 5.10.103-v8 xhci-hcd [ 1.378768] usb usb2: SerialNumber: 0000:01:00.0 [ 1.379401] hub 2-0:1.0: USB hub found [ 1.379488] hub 2-0:1.0: 4 ports detected [ 1.380984] dwc_otg: version 3.00a 10-AUG-2012 (platform bus) [ 1.381253] dwc_otg: FIQ enabled [ 1.381263] dwc_otg: NAK holdoff enabled [ 1.381273] dwc_otg: FIQ split-transaction FSM enabled [ 1.381287] Module dwc_common_port init [ 1.381818] usbcore: registered new interface driver uas [ 1.381936] usbcore: registered new interface driver usb-storage [ 1.382065] usbcore: registered new interface driver usbserial_generic [ 1.382112] usbserial: USB Serial support registered for generic [ 1.382341] mousedev: PS/2 mouse device common for all mice [ 1.382471] Driver for 1-wire Dallas network protocol. [ 1.384321] bcm2835-wdt bcm2835-wdt: Broadcom BCM2835 watchdog timer [ 1.387325] sdhci: Secure Digital Host Controller Interface driver [ 1.387352] sdhci: Copyright(c) Pierre Ossman [ 1.387989] mmc-bcm2835 fe300000.mmcnr: could not get clk, deferring probe [ 1.388705] sdhci-pltfm: SDHCI platform and OF driver helper [ 1.392907] ledtrig-cpu: registered to indicate activity on CPUs [ 1.393055] hid: raw HID events driver (C) Jiri Kosina [ 1.393137] usbcore: registered new interface driver usbhid [ 1.393146] usbhid: USB HID core driver [ 1.393307] ashmem: initialized [ 1.397439] IPVS: Registered protocols (TCP, UDP, SCTP, AH, ESP) [ 1.397523] IPVS: Connection hash table configured (size=4096, memory=64Kbytes) [ 1.397634] IPVS: ipvs loaded. [ 1.397645] IPVS: [rr] scheduler registered. [ 1.398101] NET: Registered protocol family 10 [ 1.398807] Segment Routing with IPv6 [ 1.398877] NET: Registered protocol family 17 [ 1.398922] Bridge firewalling registered [ 1.398977] Key type dns_resolver registered [ 1.399384] registered taskstats version 1 [ 1.399408] Loading compiled-in X.509 certificates [ 1.399618] Key type ._fscrypt registered [ 1.399629] Key type .fscrypt registered [ 1.399637] Key type fscrypt-provisioning registered [ 1.399705] AppArmor: AppArmor sha1 policy hashing enabled [ 1.405718] uart-pl011 fe201000.serial: there is not valid maps for state default [ 1.405895] uart-pl011 fe201000.serial: cts_event_workaround enabled [ 1.405970] fe201000.serial: ttyAMA0 at MMIO 0xfe201000 (irq = 17, base_baud = 0) is a PL011 rev2 [ 1.414074] bcm2835-power bcm2835-power: Broadcom BCM2835 power domains driver [ 1.414540] mmc-bcm2835 fe300000.mmcnr: mmc_debug:0 mmc_debug2:0 [ 1.414553] mmc-bcm2835 fe300000.mmcnr: DMA channel allocated [ 1.443075] printk: console [netcon0] enabled [ 1.443109] netconsole: network logging started [ 1.443176] of_cfs_init [ 1.443303] of_cfs_init: OK [ 1.463779] mmc1: queuing unknown CIS tuple 0x80 (2 bytes) [ 1.465403] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.467011] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.469889] mmc1: queuing unknown CIS tuple 0x80 (7 bytes) [ 1.471501] mmc1: queuing unknown CIS tuple 0x80 (3 bytes) [ 1.482004] mmc0: SDHCI controller on fe340000.mmc [fe340000.mmc] using ADMA [ 1.482777] Waiting for root device PARTUUID=8d3d53e3-6d49-4c38-8349-aff6859e82fd... [ 1.534898] mmc1: new high speed SDIO card at address 0001 [ 1.583484] mmc0: new ultra high speed DDR50 SDHC card at address aaaa [ 1.583996] mmcblk0: mmc0:aaaa SC32G 29.7 GiB [ 1.590359] mmcblk0: p1 p2 p3 p4 p5 p6 p7 p8 [ 1.595836] VFS: Mounted root (squashfs filesystem) readonly on device 179:3. [ 1.597293] devtmpfs: mounted [ 1.602143] Freeing unused kernel memory: 3904K [ 1.620217] Run /sbin/init as init process [ 1.620235] with arguments: [ 1.620243] /sbin/init [ 1.620250] with environment: [ 1.620257] HOME=/ [ 1.620265] TERM=linux [ 1.632151] usb 1-1: new high-speed USB device number 2 using xhci_hcd [ 1.781488] usb 1-1: New USB device found, idVendor=2109, idProduct=3431, bcdDevice= 4.20 [ 1.781526] usb 1-1: New USB device strings: Mfr=0, Product=1, SerialNumber=0 [ 1.781541] usb 1-1: Product: USB2.0 Hub [ 1.783136] hub 1-1:1.0: USB hub found [ 1.783386] hub 1-1:1.0: 4 ports detected [ 1.865354] systemd[1]: System time before build time, advancing clock. [ 1.916190] systemd[1]: systemd 249 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 -XKBCOMMON -UTMP -SYSVINIT default-hierarchy=hybrid) [ 1.916923] systemd[1]: Detected architecture arm64. [ 1.948491] systemd[1]: Hostname set to . [ 1.948798] systemd[1]: Installed transient /etc/machine-id file. [ 2.377473] systemd[1]: Queued start job for default target Multi-User System. [ 2.378219] random: systemd: uninitialized urandom read (16 bytes read) [ 2.408476] systemd[1]: Created slice Slice /system/ha-cli. [ 2.408859] random: systemd: uninitialized urandom read (16 bytes read) [ 2.410723] systemd[1]: Created slice Slice /system/modprobe. [ 2.410956] random: systemd: uninitialized urandom read (16 bytes read) [ 2.412822] systemd[1]: Created slice Slice /system/systemd-fsck. [ 2.415064] systemd[1]: Created slice Slice /system/systemd-growfs. [ 2.416944] systemd[1]: Created slice User and Session Slice. [ 2.417382] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ 2.417733] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 2.418062] systemd[1]: Reached target Local Encrypted Volumes. [ 2.418269] systemd[1]: Reached target HassOS zram targets. [ 2.418481] systemd[1]: Reached target Path Units. [ 2.418653] systemd[1]: Reached target Remote Encrypted Volumes. [ 2.418818] systemd[1]: Reached target Remote File Systems. [ 2.418997] systemd[1]: Reached target Remote Verity Integrity Protected Volumes. [ 2.419162] systemd[1]: Reached target Slice Units. [ 2.419375] systemd[1]: Reached target Local Verity Integrity Protected Volumes. [ 2.422751] systemd[1]: Listening on D-Bus System Message Bus Socket. [ 2.426248] systemd[1]: Listening on Process Core Dump Socket. [ 2.426995] systemd[1]: Listening on Journal Audit Socket. [ 2.427486] systemd[1]: Listening on Journal Socket (/dev/log). [ 2.428137] systemd[1]: Listening on Journal Socket. [ 2.431667] systemd[1]: Listening on udev Control Socket. [ 2.432537] systemd[1]: Listening on udev Kernel Socket. [ 2.433184] systemd[1]: Condition check resulted in Huge Pages File System being skipped. [ 2.437233] systemd[1]: Mounting POSIX Message Queue File System... [ 2.442000] systemd[1]: Mounting Kernel Debug File System... [ 2.446644] systemd[1]: Mounting Kernel Trace File System... [ 2.451252] systemd[1]: Started D-Bus System Message Bus. [ 2.461101] systemd[1]: Starting Home Assistant OS Agent... [ 2.474933] systemd[1]: Starting Create List of Static Device Nodes... [ 2.480177] systemd[1]: Starting Load Kernel Module configfs... [ 2.484978] systemd[1]: Starting Load Kernel Module drm... [ 2.489087] systemd[1]: Starting Load Kernel Module fuse... [ 2.495718] systemd[1]: Starting Journal Service... [ 2.499983] systemd[1]: Starting Remount Root and Kernel File Systems... [ 2.504538] systemd[1]: Starting Wait Until Kernel Time Synchronized... [ 2.508959] systemd[1]: Starting Coldplug All udev Devices... [ 2.513246] systemd[1]: Starting Disk Manager... [ 2.517587] systemd[1]: Starting HassOS ZRAM swap... [ 2.522344] systemd[1]: Starting HassOS ZRAM tmp... [ 2.528578] systemd[1]: Starting HassOS ZRAM var... [ 2.535725] systemd[1]: Mounted POSIX Message Queue File System. [ 2.536420] systemd[1]: Mounted Kernel Debug File System. [ 2.536910] systemd[1]: Mounted Kernel Trace File System. [ 2.539705] systemd[1]: Finished Create List of Static Device Nodes. [ 2.540888] systemd[1]: modprobe@configfs.service: Deactivated successfully. [ 2.542319] systemd[1]: Finished Load Kernel Module configfs. [ 2.545412] systemd[1]: Finished Remount Root and Kernel File Systems. [ 2.550056] systemd[1]: Mounting Kernel Configuration File System... [ 2.555492] systemd[1]: Starting Create Static Device Nodes in /dev... [ 2.561408] systemd[1]: Mounted Kernel Configuration File System. [ 2.663796] systemd[1]: Started Journal Service. [ 2.756496] zram2: detected capacity change from 0 to 16777216 [ 2.760199] zram0: detected capacity change from 0 to 994463744 [ 2.761646] zram1: detected capacity change from 0 to 33554432 [ 2.791597] fuse: init (API version 7.32) [ 2.936829] audit: type=1334 audit(1634052245.067:2): prog-id=5 op=LOAD [ 2.936944] audit: type=1334 audit(1634052245.067:3): prog-id=6 op=LOAD [ 3.254910] rpivid-mem feb00000.hevc-decoder: rpivid-hevcmem initialised: Registers at 0xfeb00000 length 0x00010000 [ 3.255419] rpivid-mem feb10000.rpivid-local-intc: rpivid-intcmem initialised: Registers at 0xfeb10000 length 0x00001000 [ 3.255720] rpivid-mem feb20000.h264-decoder: rpivid-h264mem initialised: Registers at 0xfeb20000 length 0x00010000 [ 3.256181] rpivid-mem feb30000.vp9-decoder: rpivid-vp9mem initialised: Registers at 0xfeb30000 length 0x00010000 [ 3.302254] vc_sm_cma: module is from the staging directory, the quality is unknown, you have been warned. [ 3.303210] bcm2835_vc_sm_cma_probe: Videocore shared memory driver [ 3.303253] [vc_sm_connected_init]: start [ 3.303861] [vc_sm_connected_init]: installed successfully [ 3.369097] videodev: Linux video capture interface: v2.00 [ 3.402998] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.403283] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.403755] bcm2835_mmal_vchiq: module is from the staging directory, the quality is unknown, you have been warned. [ 3.418708] snd_bcm2835: module is from the staging directory, the quality is unknown, you have been warned. [ 3.424022] bcm2835_audio bcm2835_audio: card created with 8 channels [ 3.558767] bcm2835_v4l2: module is from the staging directory, the quality is unknown, you have been warned. [ 3.559957] bcm2835_isp: module is from the staging directory, the quality is unknown, you have been warned. [ 3.560735] bcm2835_codec: module is from the staging directory, the quality is unknown, you have been warned. [ 3.573744] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video13 [ 3.575297] bcm2835-codec bcm2835-codec: Device registered as /dev/video10 [ 3.575343] bcm2835-codec bcm2835-codec: Loaded V4L2 decode [ 3.575616] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video14 [ 3.575916] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video15 [ 3.576178] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video16 [ 3.576214] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 3.576230] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 3.576243] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 3.576256] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 3.582575] bcm2835-codec bcm2835-codec: Device registered as /dev/video11 [ 3.582617] bcm2835-codec bcm2835-codec: Loaded V4L2 encode [ 3.583003] bcm2835-isp bcm2835-isp: Device node output[0] registered as /dev/video20 [ 3.584146] bcm2835-isp bcm2835-isp: Device node capture[0] registered as /dev/video21 [ 3.585290] bcm2835-isp bcm2835-isp: Device node capture[1] registered as /dev/video22 [ 3.585499] bcm2835-isp bcm2835-isp: Device node stats[2] registered as /dev/video23 [ 3.585522] bcm2835-isp bcm2835-isp: Register output node 0 with media controller [ 3.585538] bcm2835-isp bcm2835-isp: Register capture node 1 with media controller [ 3.585551] bcm2835-isp bcm2835-isp: Register capture node 2 with media controller [ 3.585564] bcm2835-isp bcm2835-isp: Register capture node 3 with media controller [ 3.585701] bcm2835-isp bcm2835-isp: Loaded V4L2 bcm2835-isp [ 3.588030] bcm2835-codec bcm2835-codec: Device registered as /dev/video12 [ 3.588055] bcm2835-codec bcm2835-codec: Loaded V4L2 isp [ 3.599452] bcm2835-codec bcm2835-codec: Device registered as /dev/video18 [ 3.599501] bcm2835-codec bcm2835-codec: Loaded V4L2 image_fx [ 3.736232] FAT-fs (mmcblk0p1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. [ 3.807439] cfg80211: Loading compiled-in X.509 certificates for regulatory database [ 3.828919] cfg80211: Loaded X.509 cert 'sforshee: 00b28ddf47aef9cea7' [ 3.894318] EXT4-fs (mmcblk0p7): mounted filesystem with ordered data mode. Opts: (null) [ 3.894363] ext4 filesystem being mounted at /mnt/overlay supports timestamps until 2038 (0x7fffffff) [ 3.908224] Adding 971152k swap on /dev/zram0. Priority:-2 extents:1 across:971152k SSFS [ 3.930114] brcmfmac: F1 signature read @0x18000000=0x15264345 [ 3.935900] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 3.937276] usbcore: registered new interface driver brcmfmac [ 4.203195] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 4.204230] brcmfmac: brcmf_fw_alloc_request: using brcm/brcmfmac43455-sdio for chip BCM4345/6 [ 4.216736] brcmfmac: brcmf_c_preinit_dcmds: Firmware: BCM4345/6 wl0: Jan 4 2021 19:56:29 version 7.45.229 (617f1f5 CY) FWID 01-2dbd9d2e [ 4.502749] EXT4-fs (mmcblk0p8): mounted filesystem with ordered data mode. Opts: (null) [ 4.550410] EXT4-fs (mmcblk0p8): resizing filesystem from 7613307 to 7613307 blocks [ 4.936575] EXT4-fs (zram2): mounted filesystem without journal. Opts: nobarrier [ 4.941071] EXT4-fs (zram1): mounted filesystem without journal. Opts: nobarrier [ 5.053958] systemd-journald[126]: Received client request to flush runtime journal. [ 5.068573] systemd-journald[126]: File /var/log/journal/0d5c515dcae14dfe8f83a3c9bee22af7/system.journal corrupted or uncleanly shut down, renaming and replacing. [ 5.871100] ext4 filesystem being remounted at /run/systemd/unit-root/var/lib/systemd/timesync supports timestamps until 2038 (0x7fffffff) [ 5.951325] ext4 filesystem being remounted at /run/systemd/unit-root/var/lib/systemd/timesync supports timestamps until 2038 (0x7fffffff) [ 6.035800] audit: type=1334 audit(1656334075.063:4): prog-id=7 op=LOAD [ 6.036002] audit: type=1334 audit(1656334075.063:5): prog-id=8 op=LOAD [ 6.176117] random: crng init done [ 6.176130] random: 7 urandom warning(s) missed due to ratelimiting [ 6.339780] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 6.339858] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 6.340059] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 6.340159] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 6.340563] ext4 filesystem being remounted at /run/systemd/unit-root/var/lib/systemd/linger supports timestamps until 2038 (0x7fffffff) [ 6.341204] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 6.341278] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 6.341345] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 6.341471] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 6.341595] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 6.350670] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 6.350717] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 6.350760] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 6.350799] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 6.350847] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 6.350917] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 6.350957] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 6.350999] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 6.351067] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 6.409627] ext4 filesystem being remounted at /run/systemd/unit-root/var/lib/systemd/linger supports timestamps until 2038 (0x7fffffff) [ 7.270750] uart-pl011 fe201000.serial: no DMA platform data [ 7.474771] audit: type=1334 audit(1656334076.503:6): prog-id=9 op=LOAD [ 7.474905] audit: type=1334 audit(1656334076.503:7): prog-id=10 op=LOAD [ 7.683125] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 7.683562] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 7.683905] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 7.684238] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 7.684465] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 7.684540] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 7.684822] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 7.685085] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 7.685252] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 7.695167] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 7.695246] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 7.695287] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 7.695334] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 7.695376] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 7.695446] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 7.695485] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 7.695526] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 7.695569] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 7.886899] bcmgenet fd580000.ethernet: configuring instance for external RGMII (RX delay) [ 7.887129] bcmgenet fd580000.ethernet eth0: Link is Down [ 7.924642] Bluetooth: Core ver 2.22 [ 7.924733] NET: Registered protocol family 31 [ 7.924739] Bluetooth: HCI device and connection manager initialized [ 7.924754] Bluetooth: HCI socket layer initialized [ 7.924763] Bluetooth: L2CAP socket layer initialized [ 7.924778] Bluetooth: SCO socket layer initialized [ 7.931275] Bluetooth: HCI UART driver ver 2.3 [ 7.931288] Bluetooth: HCI UART protocol H4 registered [ 7.931343] Bluetooth: HCI UART protocol Three-wire (H5) registered [ 7.931596] Bluetooth: HCI UART protocol Broadcom registered [ 7.932994] brcmfmac: brcmf_cfg80211_set_power_mgmt: power save enabled [ 8.084457] Bluetooth: BNEP (Ethernet Emulation) ver 1.3 [ 8.084468] Bluetooth: BNEP filters: protocol multicast [ 8.084482] Bluetooth: BNEP socket layer initialized [ 8.102549] NET: Registered protocol family 38 [ 11.972231] bcmgenet fd580000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off [ 11.972263] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ 31.716168] cam-dummy-reg: disabling [ 31.716197] cam1-reg: disabling [ 36.283872] audit: type=1400 audit(1656334178.165:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor" pid=441 comm="apparmor_parser" [ 36.283903] audit: type=1400 audit(1656334178.165:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=441 comm="apparmor_parser" [ 36.283922] audit: type=1400 audit(1656334178.165:8): apparmor="STATUS" operation="profile_load" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=441 comm="apparmor_parser" [ 38.093090] audit: type=1400 audit(1656334179.977:9): apparmor="STATUS" operation="profile_load" profile="unconfined" name="docker-default" pid=465 comm="apparmor_parser" [ 38.376324] audit: type=1334 audit(1656334180.265:10): prog-id=10 op=UNLOAD [ 38.376362] audit: type=1334 audit(1656334180.265:11): prog-id=9 op=UNLOAD [ 39.399232] audit: type=1325 audit(1656334181.285:12): table=nat family=2 entries=5 op=xt_replace pid=489 subj==unconfined comm="iptables" [ 39.408627] audit: type=1325 audit(1656334181.297:13): table=filter family=2 entries=4 op=xt_replace pid=491 subj==unconfined comm="iptables" [ 39.418655] audit: type=1325 audit(1656334181.305:14): table=filter family=2 entries=6 op=xt_replace pid=493 subj==unconfined comm="iptables" [ 39.426705] audit: type=1325 audit(1656334181.313:15): table=filter family=2 entries=8 op=xt_replace pid=495 subj==unconfined comm="iptables" [ 39.465839] Initializing XFRM netlink socket [ 40.045947] hassio: port 1(vethf776d27) entered blocking state [ 40.045964] hassio: port 1(vethf776d27) entered disabled state [ 40.046393] device vethf776d27 entered promiscuous mode [ 40.046822] hassio: port 1(vethf776d27) entered blocking state [ 40.046836] hassio: port 1(vethf776d27) entered forwarding state [ 40.049677] IPv6: ADDRCONF(NETDEV_CHANGE): hassio: link becomes ready [ 40.050003] hassio: port 1(vethf776d27) entered disabled state [ 40.697686] cgroup: cgroup: disabling cgroup2 socket matching due to net_prio or net_cls activation [ 41.207365] eth0: renamed from vethcd5679d [ 41.229456] IPv6: ADDRCONF(NETDEV_CHANGE): vethf776d27: link becomes ready [ 41.229582] hassio: port 1(vethf776d27) entered blocking state [ 41.229591] hassio: port 1(vethf776d27) entered forwarding state [ 43.230116] docker0: port 1(veth6f8b6c2) entered blocking state [ 43.230132] docker0: port 1(veth6f8b6c2) entered disabled state [ 43.230647] device veth6f8b6c2 entered promiscuous mode [ 43.230721] kauditd_printk_skb: 59 callbacks suppressed [ 43.230725] audit: type=1700 audit(1656334185.117:75): dev=veth6f8b6c2 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 43.285872] hassio: port 2(veth105f76e) entered blocking state [ 43.285891] hassio: port 2(veth105f76e) entered disabled state [ 43.286401] device veth105f76e entered promiscuous mode [ 43.286474] audit: type=1700 audit(1656334185.173:76): dev=veth105f76e prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 43.287387] hassio: port 2(veth105f76e) entered blocking state [ 43.287402] hassio: port 2(veth105f76e) entered forwarding state [ 43.845818] eth0: renamed from vethe4e7708 [ 43.875650] hassio: port 2(veth105f76e) entered disabled state [ 43.875933] IPv6: ADDRCONF(NETDEV_CHANGE): veth6f8b6c2: link becomes ready [ 43.876241] docker0: port 1(veth6f8b6c2) entered blocking state [ 43.876257] docker0: port 1(veth6f8b6c2) entered forwarding state [ 43.876513] IPv6: ADDRCONF(NETDEV_CHANGE): docker0: link becomes ready [ 43.990731] audit: type=1325 audit(1656334185.877:77): table=nat family=2 entries=0 op=xt_register pid=1031 subj==unconfined comm="iptables" [ 44.002980] audit: type=1325 audit(1656334185.889:78): table=filter family=2 entries=0 op=xt_register pid=1032 subj==unconfined comm="iptables" [ 44.017742] audit: type=1325 audit(1656334185.905:79): table=nat family=2 entries=5 op=xt_replace pid=1035 subj==unconfined comm="iptables" [ 44.024189] audit: type=1325 audit(1656334185.909:80): table=nat family=2 entries=7 op=xt_replace pid=1037 subj==unconfined comm="iptables" [ 44.032183] audit: type=1325 audit(1656334185.917:81): table=nat family=2 entries=8 op=xt_replace pid=1044 subj==unconfined comm="iptables" [ 44.036277] audit: type=1325 audit(1656334185.921:82): table=nat family=2 entries=10 op=xt_replace pid=1045 subj==unconfined comm="iptables" [ 44.042594] audit: type=1325 audit(1656334185.929:83): table=nat family=2 entries=11 op=xt_replace pid=1046 subj==unconfined comm="iptables" [ 44.047622] audit: type=1325 audit(1656334185.933:84): table=nat family=2 entries=12 op=xt_replace pid=1047 subj==unconfined comm="iptables" [ 44.097603] eth1: renamed from veth7d17ab3 [ 44.117960] IPv6: ADDRCONF(NETDEV_CHANGE): veth105f76e: link becomes ready [ 44.118137] hassio: port 2(veth105f76e) entered blocking state [ 44.118150] hassio: port 2(veth105f76e) entered forwarding state [ 50.461651] kauditd_printk_skb: 2 callbacks suppressed [ 50.461657] audit: type=1334 audit(1656334192.349:87): prog-id=11 op=LOAD [ 50.461734] audit: type=1334 audit(1656334192.349:88): prog-id=12 op=LOAD [ 50.666614] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 50.666939] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 50.666981] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 50.667399] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 50.667439] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 50.668238] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 50.668282] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 50.668453] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 50.668962] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 50.678796] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 50.678847] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 50.678887] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 50.678936] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 50.679006] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 50.679049] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 50.679090] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 50.679133] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 50.679172] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 50.817608] audit: type=1334 audit(1656334192.705:89): prog-id=13 op=LOAD [ 50.817775] audit: type=1334 audit(1656334192.705:90): prog-id=14 op=LOAD [ 50.992881] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 50.992973] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 50.993217] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 50.993450] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 50.993652] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 50.994457] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 50.994587] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 50.994965] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 50.995073] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 51.006833] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 51.006881] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 51.006923] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 51.007007] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 51.007077] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 51.007118] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 51.007161] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 51.007204] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 51.007243] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 51.792654] audit: type=1400 audit(1656334193.673:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor" pid=1243 comm="apparmor_parser" [ 51.792728] audit: type=1400 audit(1656334193.673:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/gdbus" pid=1243 comm="apparmor_parser" [ 51.792783] audit: type=1400 audit(1656334193.673:91): apparmor="STATUS" operation="profile_replace" info="same as current profile, skipping" profile="unconfined" name="hassio-supervisor///usr/bin/git" pid=1243 comm="apparmor_parser" [ 52.667402] hassio: port 3(vethab128e2) entered blocking state [ 52.667420] hassio: port 3(vethab128e2) entered disabled state [ 52.667854] device vethab128e2 entered promiscuous mode [ 52.667937] audit: type=1700 audit(1656334194.553:92): dev=vethab128e2 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 53.252055] audit: type=1325 audit(1656334195.137:93): table=nat family=2 entries=0 op=xt_register pid=1315 subj==unconfined comm="iptables" [ 53.264687] audit: type=1325 audit(1656334195.153:94): table=filter family=2 entries=0 op=xt_register pid=1316 subj==unconfined comm="iptables" [ 53.363084] eth0: renamed from veth2f4c97b [ 53.390124] IPv6: ADDRCONF(NETDEV_CHANGE): vethab128e2: link becomes ready [ 53.390312] hassio: port 3(vethab128e2) entered blocking state [ 53.390324] hassio: port 3(vethab128e2) entered forwarding state [ 54.148867] hassio: port 4(vethc3a31fb) entered blocking state [ 54.148881] hassio: port 4(vethc3a31fb) entered disabled state [ 54.149090] device vethc3a31fb entered promiscuous mode [ 54.875194] eth0: renamed from vethf4a6f8b [ 54.910310] IPv6: ADDRCONF(NETDEV_CHANGE): vethc3a31fb: link becomes ready [ 54.910530] hassio: port 4(vethc3a31fb) entered blocking state [ 54.910545] hassio: port 4(vethc3a31fb) entered forwarding state [ 55.668416] hassio: port 5(vethf1499b3) entered blocking state [ 55.668431] hassio: port 5(vethf1499b3) entered disabled state [ 55.668670] device vethf1499b3 entered promiscuous mode [ 55.668742] kauditd_printk_skb: 19 callbacks suppressed [ 55.668746] audit: type=1700 audit(1656334197.557:114): dev=vethf1499b3 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 56.253722] audit: type=1325 audit(1656334198.141:115): table=nat family=2 entries=0 op=xt_register pid=1925 subj==unconfined comm="iptables" [ 56.262310] audit: type=1325 audit(1656334198.149:116): table=filter family=2 entries=0 op=xt_register pid=1926 subj==unconfined comm="iptables" [ 56.273817] audit: type=1325 audit(1656334198.161:117): table=nat family=2 entries=5 op=xt_replace pid=1929 subj==unconfined comm="iptables" [ 56.277890] audit: type=1325 audit(1656334198.165:118): table=nat family=2 entries=7 op=xt_replace pid=1930 subj==unconfined comm="iptables" [ 56.286504] audit: type=1325 audit(1656334198.173:119): table=nat family=2 entries=8 op=xt_replace pid=1932 subj==unconfined comm="iptables" [ 56.291639] audit: type=1325 audit(1656334198.177:120): table=nat family=2 entries=10 op=xt_replace pid=1933 subj==unconfined comm="iptables" [ 56.299395] audit: type=1325 audit(1656334198.185:121): table=nat family=2 entries=11 op=xt_replace pid=1934 subj==unconfined comm="iptables" [ 56.305370] audit: type=1325 audit(1656334198.193:122): table=nat family=2 entries=12 op=xt_replace pid=1935 subj==unconfined comm="iptables" [ 56.311355] audit: type=1325 audit(1656334198.197:123): table=nat family=2 entries=13 op=xt_replace pid=1936 subj==unconfined comm="iptables" [ 56.369059] eth0: renamed from veth3cccb18 [ 56.397622] IPv6: ADDRCONF(NETDEV_CHANGE): vethf1499b3: link becomes ready [ 56.397799] hassio: port 5(vethf1499b3) entered blocking state [ 56.397810] hassio: port 5(vethf1499b3) entered forwarding state [ 59.744458] Bluetooth: RFCOMM TTY layer initialized [ 59.744480] Bluetooth: RFCOMM socket layer initialized [ 59.744502] Bluetooth: RFCOMM ver 1.11 [ 65.197370] hassio: port 6(veth7bdd198) entered blocking state [ 65.197384] hassio: port 6(veth7bdd198) entered disabled state [ 65.197604] device veth7bdd198 entered promiscuous mode [ 65.197674] kauditd_printk_skb: 1 callbacks suppressed [ 65.197677] audit: type=1700 audit(1656334207.085:125): dev=veth7bdd198 prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 65.286166] audit: type=1325 audit(1656334207.173:126): table=nat family=2 entries=15 op=xt_replace pid=2732 subj==unconfined comm="iptables" [ 65.298546] audit: type=1325 audit(1656334207.185:127): table=filter family=2 entries=30 op=xt_replace pid=2740 subj==unconfined comm="iptables" [ 65.309883] audit: type=1325 audit(1656334207.197:128): table=nat family=2 entries=16 op=xt_replace pid=2747 subj==unconfined comm="iptables" [ 65.896503] audit: type=1325 audit(1656334207.785:129): table=nat family=2 entries=0 op=xt_register pid=2832 subj==unconfined comm="iptables" [ 65.907721] audit: type=1325 audit(1656334207.793:130): table=filter family=2 entries=0 op=xt_register pid=2833 subj==unconfined comm="iptables" [ 65.920534] audit: type=1325 audit(1656334207.809:131): table=nat family=2 entries=5 op=xt_replace pid=2836 subj==unconfined comm="iptables" [ 65.925217] audit: type=1325 audit(1656334207.813:132): table=nat family=2 entries=7 op=xt_replace pid=2837 subj==unconfined comm="iptables" [ 65.933634] audit: type=1325 audit(1656334207.821:133): table=nat family=2 entries=8 op=xt_replace pid=2839 subj==unconfined comm="iptables" [ 65.937927] audit: type=1325 audit(1656334207.825:134): table=nat family=2 entries=10 op=xt_replace pid=2840 subj==unconfined comm="iptables" [ 65.999875] eth0: renamed from veth705061f [ 66.013606] IPv6: ADDRCONF(NETDEV_CHANGE): veth7bdd198: link becomes ready [ 66.013755] hassio: port 6(veth7bdd198) entered blocking state [ 66.013764] hassio: port 6(veth7bdd198) entered forwarding state [ 66.998317] hassio: port 7(vethc28266e) entered blocking state [ 66.998331] hassio: port 7(vethc28266e) entered disabled state [ 66.998925] device vethc28266e entered promiscuous mode [ 67.860449] eth0: renamed from veth8625881 [ 67.885304] IPv6: ADDRCONF(NETDEV_CHANGE): vethc28266e: link becomes ready [ 67.885438] hassio: port 7(vethc28266e) entered blocking state [ 67.885445] hassio: port 7(vethc28266e) entered forwarding state [ 81.264949] kauditd_printk_skb: 15 callbacks suppressed [ 81.264956] audit: type=1334 audit(1656334223.157:150): prog-id=12 op=UNLOAD [ 81.264988] audit: type=1334 audit(1656334223.157:151): prog-id=11 op=UNLOAD [ 81.292987] audit: type=1334 audit(1656334223.185:152): prog-id=14 op=UNLOAD [ 81.293012] audit: type=1334 audit(1656334223.185:153): prog-id=13 op=UNLOAD [ 162.409434] hassio: port 8(veth14588ed) entered blocking state [ 162.409509] hassio: port 8(veth14588ed) entered disabled state [ 162.409727] device veth14588ed entered promiscuous mode [ 162.409807] audit: type=1700 audit(1656334304.296:154): dev=veth14588ed prom=256 old_prom=0 auid=4294967295 uid=0 gid=0 ses=4294967295 [ 163.089362] audit: type=1325 audit(1656334304.976:155): table=nat family=2 entries=0 op=xt_register pid=4349 subj==unconfined comm="iptables" [ 163.105075] audit: type=1325 audit(1656334304.992:156): table=filter family=2 entries=0 op=xt_register pid=4350 subj==unconfined comm="iptables" [ 163.119711] audit: type=1325 audit(1656334305.008:157): table=nat family=2 entries=5 op=xt_replace pid=4353 subj==unconfined comm="iptables" [ 163.124285] audit: type=1325 audit(1656334305.012:158): table=nat family=2 entries=7 op=xt_replace pid=4354 subj==unconfined comm="iptables" [ 163.133297] audit: type=1325 audit(1656334305.020:159): table=nat family=2 entries=8 op=xt_replace pid=4356 subj==unconfined comm="iptables" [ 163.137133] audit: type=1325 audit(1656334305.024:160): table=nat family=2 entries=10 op=xt_replace pid=4357 subj==unconfined comm="iptables" [ 163.142697] audit: type=1325 audit(1656334305.032:161): table=nat family=2 entries=11 op=xt_replace pid=4358 subj==unconfined comm="iptables" [ 163.150276] audit: type=1325 audit(1656334305.040:162): table=nat family=2 entries=12 op=xt_replace pid=4359 subj==unconfined comm="iptables" [ 163.156535] audit: type=1325 audit(1656334305.044:163): table=nat family=2 entries=13 op=xt_replace pid=4360 subj==unconfined comm="iptables" [ 163.202630] eth0: renamed from vethbbf4ae9 [ 163.222796] IPv6: ADDRCONF(NETDEV_CHANGE): veth14588ed: link becomes ready [ 163.222914] hassio: port 8(veth14588ed) entered blocking state [ 163.222921] hassio: port 8(veth14588ed) entered forwarding state [ 164.136483] hassio: port 9(veth3ecee6e) entered blocking state [ 164.136496] hassio: port 9(veth3ecee6e) entered disabled state [ 164.136865] device veth3ecee6e entered promiscuous mode [ 165.095163] eth0: renamed from vethfb1be66 [ 165.111604] IPv6: ADDRCONF(NETDEV_CHANGE): veth3ecee6e: link becomes ready [ 165.111734] hassio: port 9(veth3ecee6e) entered blocking state [ 165.111741] hassio: port 9(veth3ecee6e) entered forwarding state [ 166.226344] hassio: port 10(vethd68b0a3) entered blocking state [ 166.226357] hassio: port 10(vethd68b0a3) entered disabled state [ 166.226591] device vethd68b0a3 entered promiscuous mode [ 167.338689] eth0: renamed from veth92162cd [ 167.360249] IPv6: ADDRCONF(NETDEV_CHANGE): vethd68b0a3: link becomes ready [ 167.360407] hassio: port 10(vethd68b0a3) entered blocking state [ 167.360416] hassio: port 10(vethd68b0a3) entered forwarding state [ 174.193926] kauditd_printk_skb: 32 callbacks suppressed [ 174.193932] audit: type=1334 audit(1656334316.084:196): prog-id=15 op=LOAD [ 174.194128] audit: type=1334 audit(1656334316.084:197): prog-id=16 op=LOAD [ 174.718760] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 174.718982] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 174.719799] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 174.720267] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 174.720577] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 174.720957] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 174.721053] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 174.721746] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 174.721801] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 174.749949] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 174.750021] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 174.750068] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 174.750112] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 174.750168] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 174.750211] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 174.750256] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 174.750306] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 174.750348] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 175.083724] audit: type=1334 audit(1656334316.972:198): prog-id=17 op=LOAD [ 175.083860] audit: type=1334 audit(1656334316.972:199): prog-id=18 op=LOAD [ 175.588533] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 175.589141] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 175.589637] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 175.589742] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 175.590636] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 175.590995] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 175.591455] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 175.592137] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 175.592361] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 175.623912] ext4 filesystem being remounted at /run/systemd/unit-root/etc/docker supports timestamps until 2038 (0x7fffffff) [ 175.623997] ext4 filesystem being remounted at /run/systemd/unit-root/etc/dropbear supports timestamps until 2038 (0x7fffffff) [ 175.624051] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hosts supports timestamps until 2038 (0x7fffffff) [ 175.624172] ext4 filesystem being remounted at /run/systemd/unit-root/etc/udev/rules.d supports timestamps until 2038 (0x7fffffff) [ 175.624219] ext4 filesystem being remounted at /run/systemd/unit-root/etc/NetworkManager/system-connections supports timestamps until 2038 (0x7fffffff) [ 175.624267] ext4 filesystem being remounted at /run/systemd/unit-root/etc/hostname supports timestamps until 2038 (0x7fffffff) [ 175.624310] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modprobe.d supports timestamps until 2038 (0x7fffffff) [ 175.624356] ext4 filesystem being remounted at /run/systemd/unit-root/etc/systemd/timesyncd.conf supports timestamps until 2038 (0x7fffffff) [ 175.624405] ext4 filesystem being remounted at /run/systemd/unit-root/etc/modules-load.d supports timestamps until 2038 (0x7fffffff) [ 205.125993] audit: type=1334 audit(1656334347.016:200): prog-id=16 op=UNLOAD [ 205.126019] audit: type=1334 audit(1656334347.016:201): prog-id=15 op=UNLOAD [ 206.007250] audit: type=1334 audit(1656334347.896:202): prog-id=18 op=UNLOAD [ 206.007274] audit: type=1334 audit(1656334347.896:203): prog-id=17 op=UNLOAD ```
muntoris commented 2 years ago

I have the feeling, that when I click on the "upgrade" button, just nothing happens in the backend. The system reboots and thats it. I also do not have the option to create an backup before upgrading. Is this normal? For every other upgrade/update I have that option.

muntoris commented 2 years ago

Nobody a suggestion or solution? I really do not want to setup the complete system from scratch ...

agners commented 2 years ago

here we go .. supervisor log after an upgrade try:

That log is from after the reboot I assume correct? Can you try capture one just before it starts rebooting? I understand might not work, but the log after reboot only contains the startup sequence of Supervisor, no logs of why the update failed, unfortunately.

The system reboots and thats it. I also do not have the option to create an backup before upgrading. Is this normal? For every other upgrade/update I have that option.

That is normal yes, as we don't have a backup of the OS itself (it is mostly stateless anyways).

Do you have SSH access to the underlying OS?

muntoris commented 2 years ago

Not so easy to grab the logs BEFORE reboot. I did now an update to version 8.0 (for trying) and the last lines before the reboot:

22-07-16 19:53:54 INFO (MainThread) [supervisor.api.middleware.security] /os/update access from core_ssh 22-07-16 19:53:54 INFO (MainThread) [supervisor.os.manager] Fetch OTA update from https://github.com/home-assistant/operating-system/releases/download/8.0/haos_rpi4-64-8.0.raucb 22-07-16 19:54:03 INFO (MainThread) [supervisor.os.manager] Completed download of OTA update file /data/tmp/hassos-8.0.raucb

Are the log files somewhere stored or after the reboot overwritten?

Nafania commented 2 years ago

I have the same issue

Here is the logs

22-07-18 16:40:00 INFO (MainThread) [supervisor.os.manager] Completed download of OTA update file /data/tmp/hassos-8.2.raucb
22-07-18 16:40:24 ERROR (MainThread) [supervisor.os.manager] Home Assistant Operating System update failed with: Installation error: Failed updating slot boot.0: failed to run slot hook: Child process exited with code 1

Tried to reboot host / supervisor - no changes. Tried ha supervisor repair command - also didn't help.

agners commented 2 years ago

Are the log files somewhere stored or after the reboot overwritten?

The frontend only shows logs since the last boot (and a maximum of 100 lines for most log types).

However, underneath more logs are stored, but currently SSH access is required to access them.

If someone has SSH access to the underlying OS, please try to get the following with the following commands:

journalctl -u rauc.service
journalctl CONTAINER_NAME=hassio_supervisor --since yesterday
czrogers13 commented 2 years ago

I'm having the same issue, and have been since before 7.6. I'm on rpi3-64 as well and the only solution I've found to update is to wipe and reflash the SD card, which totally overwrites my current setup. Hoping to find a solution that doesn't involve upgrading hardware.

czrogers13 commented 2 years ago

@agners sorry for the delay here, took me a couple days to get around to getting the SSH set up. I also made the mistake[?] of trying the update from the SSH CLI before I tested it front end again. The interesting news is that the update did actually work from CLI, so apparently it's not a hardware thing. I'm now on 8.2, which had failed from the front end.

I went ahead and pulled the two command responses you asked for. Command 1 is below, but command 2 was is too long to paste. Not sure if there's a better way around that. They were pulled after the CLI update went through, so they may or may not be helpful for troubleshooting. Unfortunately, I've ruled myself out of front-end update testing until another update drops, so let me know if I can help in the meantime.

Command 1:

May 10 14:37:43 homeassistant rauc[358]: rauc mark: marked slot kernel.0 as good
-- Boot 0ecab851763248ac8ac018c4fe53fcf4 --
May 16 11:47:53 homeassistant systemd[1]: Starting Rauc Update Service...
May 16 11:47:54 homeassistant rauc[339]: Using central status file /mnt/data/rauc.db
May 16 11:47:54 homeassistant systemd[1]: Started Rauc Update Service.
May 16 11:50:38 homeassistant rauc[339]: rauc mark: marked slot kernel.0 as good
-- Boot d5c2ba78ef3f47c18162f856dcea78d6 --
May 16 20:29:55 homeassistant rauc[334]: rauc mark: marked slot kernel.0 as good
May 23 22:18:29 homeassistant rauc[334]: rauc mark: marked slot kernel.0 as good
-- Boot 0ecab851763248ac8ac018c4fe53fcf4 --
Jun 08 01:43:48 homeassistant rauc[339]: Using deprecated 'Install' D-Bus Method (replaced by 'InstallBundle')
Jun 08 01:43:48 homeassistant rauc[339]: input bundle: /mnt/data/supervisor/tmp/hassos-8.1.raucb
Jun 08 01:43:48 homeassistant rauc[339]: Active slot bootname: A
Jun 08 01:43:48 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: started
Jun 08 01:43:48 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking and mounting bundle...
Jun 08 01:43:48 homeassistant rauc[339]: Reading bundle: /mnt/data/supervisor/tmp/hassos-8.1.raucb
Jun 08 01:43:48 homeassistant rauc[339]: Detected CRL but CRL checking is disabled!
Jun 08 01:43:48 homeassistant rauc[339]: Verifying bundle signature... 
Jun 08 01:43:52 homeassistant rauc[339]: Verified detached signature by 'O = HassOS, CN = HassOS GitHub-1'
Jun 08 01:43:52 homeassistant rauc[339]: Mounting bundle '/mnt/data/supervisor/tmp/hassos-8.1.raucb' to '/run/rauc/bundle'
Jun 08 01:43:52 homeassistant rauc[339]: Configured loop device '/dev/loop0' for 117989376 bytes
Jun 08 01:43:52 homeassistant rauc[339]: Running bundle hook install-check
Jun 08 01:43:52 homeassistant rauc[339]: Marking target slot kernel.1 as non-bootable...
Jun 08 01:43:52 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slots...
Jun 08 01:43:52 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot boot.0
Jun 08 01:43:53 homeassistant rauc[339]: Slot needs to be updated with /run/rauc/bundle/boot.vfat
Jun 08 01:43:53 homeassistant rauc[339]: Updating /dev/disk/by-partlabel/hassos-boot with /run/rauc/bundle/boot.vfat
Jun 08 01:43:53 homeassistant rauc[339]: Running custom slot install hook for boot.0
Jun 08 01:43:53 homeassistant rauc[339]: Running slot hook slot-install for boot.0
Jun 08 01:43:53 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0
Jun 08 01:44:25 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0 status
Jun 08 01:44:25 homeassistant rauc[339]: Checking image type for slot type: raw
Jun 08 01:44:25 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0 done
Jun 08 01:44:25 homeassistant rauc[339]: Image detected as type: *.img
Jun 08 01:44:25 homeassistant rauc[339]: Slot needs to be updated with /run/rauc/bundle/kernel.img
Jun 08 01:44:25 homeassistant rauc[339]: Updating /dev/disk/by-partlabel/hassos-kernel1 with /run/rauc/bundle/kernel.img
Jun 08 01:44:25 homeassistant rauc[339]: opening slot device /dev/disk/by-partlabel/hassos-kernel1
Jun 08 01:44:25 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot kernel.1
Jun 08 01:44:25 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1
Jun 08 01:44:25 homeassistant rauc[339]: writing data to device /dev/disk/by-partlabel/hassos-kernel1
Jun 08 01:44:30 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1 status
Jun 08 01:44:30 homeassistant rauc[339]: Checking image type for slot type: raw
Jun 08 01:44:30 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1 done
Jun 08 01:44:30 homeassistant rauc[339]: Image detected as type: *.img
Jun 08 01:44:30 homeassistant rauc[339]: Slot needs to be updated with /run/rauc/bundle/rootfs.img
Jun 08 01:44:30 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot rootfs.1
Jun 08 01:44:30 homeassistant rauc[339]: Updating /dev/disk/by-partlabel/hassos-system1 with /run/rauc/bundle/rootfs.img
Jun 08 01:44:30 homeassistant rauc[339]: opening slot device /dev/disk/by-partlabel/hassos-system1
Jun 08 01:44:30 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1
Jun 08 01:44:30 homeassistant rauc[339]: writing data to device /dev/disk/by-partlabel/hassos-system1
Jun 08 01:45:03 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1 status
Jun 08 01:45:03 homeassistant rauc[339]: Marking target slot kernel.1 as bootable...
Jun 08 01:45:03 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1 done
Jun 08 01:45:04 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: All slots updated
Jun 08 01:45:04 homeassistant rauc[339]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: finished
Jun 08 01:45:04 homeassistant rauc[339]: installing `/mnt/data/supervisor/tmp/hassos-8.1.raucb` succeeded
Jun 08 01:45:52 homeassistant rauc[339]: stopping service
Jun 08 01:45:52 homeassistant systemd[1]: Stopping Rauc Update Service...
Jun 08 01:45:52 homeassistant systemd[1]: rauc.service: Deactivated successfully.
Jun 08 01:45:52 homeassistant systemd[1]: Stopped Rauc Update Service.
-- Boot 4cc6e3bd4bde4b47a5f72f2e1d540f92 --
Jun 08 01:45:59 homeassistant systemd[1]: Starting Rauc Update Service...
Jun 08 01:46:00 homeassistant rauc[339]: Using central status file /mnt/data/rauc.db
Jun 08 01:46:00 homeassistant systemd[1]: Started Rauc Update Service.
-- Boot 4ab07c0ad8ab485a9a9f29b0b9f56426 --
Jun 08 09:36:51 homeassistant rauc[335]: rauc mark: marked slot kernel.0 as good
Jun 08 23:15:59 homeassistant rauc[335]: Using deprecated 'Install' D-Bus Method (replaced by 'InstallBundle')
Jun 08 23:15:59 homeassistant rauc[335]: input bundle: /mnt/data/supervisor/tmp/hassos-8.1.raucb
Jun 08 23:15:59 homeassistant rauc[335]: Active slot bootname: A
Jun 08 23:15:59 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: started
Jun 08 23:15:59 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking and mounting bundle...
Jun 08 23:15:59 homeassistant rauc[335]: Reading bundle: /mnt/data/supervisor/tmp/hassos-8.1.raucb
Jun 08 23:15:59 homeassistant rauc[335]: Detected CRL but CRL checking is disabled!
Jun 08 23:15:59 homeassistant rauc[335]: Verifying bundle signature... 
Jun 08 23:16:03 homeassistant rauc[335]: Verified detached signature by 'O = HassOS, CN = HassOS GitHub-1'
Jun 08 23:16:03 homeassistant rauc[335]: Mounting bundle '/mnt/data/supervisor/tmp/hassos-8.1.raucb' to '/run/rauc/bundle'
Jun 08 23:16:03 homeassistant rauc[335]: Configured loop device '/dev/loop0' for 117989376 bytes
Jun 08 23:16:03 homeassistant rauc[335]: Running bundle hook install-check
Jun 08 23:16:04 homeassistant rauc[335]: Marking target slot kernel.1 as non-bootable...
Jun 08 23:16:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slots...
Jun 08 23:16:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot boot.0
Jun 08 23:16:04 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/boot.vfat
Jun 08 23:16:04 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-boot with /run/rauc/bundle/boot.vfat
Jun 08 23:16:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0
Jun 08 23:16:04 homeassistant rauc[335]: Running custom slot install hook for boot.0
Jun 08 23:16:04 homeassistant rauc[335]: Running slot hook slot-install for boot.0
Jun 08 23:16:39 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0 status
Jun 08 23:16:39 homeassistant rauc[335]: Checking image type for slot type: raw
Jun 08 23:16:39 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot boot.0 done
Jun 08 23:16:39 homeassistant rauc[335]: Image detected as type: *.img
Jun 08 23:16:39 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot kernel.1
Jun 08 23:16:39 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/kernel.img
Jun 08 23:16:39 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-kernel1 with /run/rauc/bundle/kernel.img
Jun 08 23:16:39 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1
Jun 08 23:16:39 homeassistant rauc[335]: opening slot device /dev/disk/by-partlabel/hassos-kernel1
Jun 08 23:16:39 homeassistant rauc[335]: writing data to device /dev/disk/by-partlabel/hassos-kernel1
Jun 08 23:16:42 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1 status
Jun 08 23:16:42 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot kernel.1 done
Jun 08 23:16:42 homeassistant rauc[335]: Checking image type for slot type: raw
Jun 08 23:16:42 homeassistant rauc[335]: Image detected as type: *.img
Jun 08 23:16:42 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Checking slot rootfs.1
Jun 08 23:16:42 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/rootfs.img
Jun 08 23:16:42 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-system1 with /run/rauc/bundle/rootfs.img
Jun 08 23:16:42 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1
Jun 08 23:16:42 homeassistant rauc[335]: opening slot device /dev/disk/by-partlabel/hassos-system1
Jun 08 23:16:42 homeassistant rauc[335]: writing data to device /dev/disk/by-partlabel/hassos-system1
Jun 08 23:17:11 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1 status
Jun 08 23:17:11 homeassistant rauc[335]: Marking target slot kernel.1 as bootable...
Jun 08 23:17:11 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: Updating slot rootfs.1 done
Jun 08 23:17:12 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: All slots updated
Jun 08 23:17:12 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.1.raucb: finished
Jun 08 23:17:12 homeassistant rauc[335]: installing `/mnt/data/supervisor/tmp/hassos-8.1.raucb` succeeded
Jun 08 23:18:00 homeassistant systemd[1]: Stopping Rauc Update Service...
Jun 08 23:18:00 homeassistant rauc[335]: stopping service
Jun 08 23:18:01 homeassistant systemd[1]: rauc.service: Deactivated successfully.
Jun 08 23:18:01 homeassistant systemd[1]: Stopped Rauc Update Service.
-- Boot 4cc6e3bd4bde4b47a5f72f2e1d540f92 --
Jun 08 23:21:54 homeassistant rauc[339]: rauc mark: marked slot kernel.0 as good
-- Boot 50b64dd97fc84f6da50795f49eccac80 --
Jun 14 11:54:16 homeassistant systemd[1]: Starting Rauc Update Service...
Jun 14 11:54:19 homeassistant rauc[334]: Using central status file /mnt/data/rauc.db
Jun 14 11:54:19 homeassistant systemd[1]: Started Rauc Update Service.
Jun 14 11:57:00 homeassistant rauc[334]: rauc mark: marked slot kernel.0 as good
Jul 06 10:04:24 homeassistant rauc[334]: rauc mark: marked slot kernel.0 as good
Jul 07 10:20:58 homeassistant rauc[334]: rauc mark: marked slot kernel.0 as good
-- Boot d621c9f3b74a4cd99837779a5142aeb0 --
Jul 14 15:02:03 homeassistant systemd[1]: Starting Rauc Update Service...
Jul 14 15:02:04 homeassistant rauc[331]: Using central status file /mnt/data/rauc.db
Jul 14 15:02:04 homeassistant systemd[1]: Started Rauc Update Service.
Jul 14 15:04:58 homeassistant rauc[331]: rauc mark: marked slot kernel.0 as good
-- Boot e98abda81ecd4863a879d4e34dd04890 --
Jul 20 11:35:13 homeassistant systemd[1]: Starting Rauc Update Service...
Jul 20 11:35:14 homeassistant rauc[337]: Using central status file /mnt/data/rauc.db
Jul 20 11:35:14 homeassistant systemd[1]: Started Rauc Update Service.
Jul 22 04:11:15 homeassistant rauc[337]: rauc mark: marked slot kernel.0 as good
-- Boot 0ef4cdcf9e3245a1b5ae33d535fb750c --
Jul 22 04:43:29 homeassistant systemd[1]: Starting Rauc Update Service...
Jul 22 04:43:30 homeassistant rauc[335]: Using central status file /mnt/data/rauc.db
Jul 22 04:43:30 homeassistant systemd[1]: Started Rauc Update Service.
Jul 22 06:42:34 homeassistant rauc[2909]: Warning: Bad CRC, using default environment
Jul 22 06:42:34 homeassistant rauc[335]: rauc mark: marked slot kernel.0 as good
Jul 22 20:00:26 homeassistant rauc[335]: Using deprecated 'Install' D-Bus Method (replaced by 'InstallBundle')
Jul 22 20:00:26 homeassistant rauc[335]: input bundle: /mnt/data/supervisor/tmp/hassos-8.2.raucb
Jul 22 20:00:26 homeassistant rauc[335]: Active slot bootname: A
Jul 22 20:00:26 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: started
Jul 22 20:00:26 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Checking and mounting bundle...
Jul 22 20:00:26 homeassistant rauc[335]: Reading bundle: /mnt/data/supervisor/tmp/hassos-8.2.raucb
Jul 22 20:00:26 homeassistant rauc[335]: Detected CRL but CRL checking is disabled!
Jul 22 20:00:26 homeassistant rauc[335]: Verifying bundle signature... 
Jul 22 20:00:30 homeassistant rauc[335]: Verified detached signature by 'O = HassOS, CN = HassOS GitHub-1'
Jul 22 20:00:30 homeassistant rauc[335]: Mounting bundle '/mnt/data/supervisor/tmp/hassos-8.2.raucb' to '/run/rauc/bundle'
Jul 22 20:00:30 homeassistant rauc[335]: Configured loop device '/dev/loop0' for 119005184 bytes
Jul 22 20:00:30 homeassistant rauc[335]: Running bundle hook install-check
Jul 22 20:00:30 homeassistant rauc[335]: Marking target slot kernel.1 as non-bootable...
Jul 22 20:00:30 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slots...
Jul 22 20:00:30 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Checking slot boot.0
Jul 22 20:00:30 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/boot.vfat
Jul 22 20:00:30 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-boot with /run/rauc/bundle/boot.vfat
Jul 22 20:00:30 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot boot.0
Jul 22 20:00:30 homeassistant rauc[335]: Running custom slot install hook for boot.0
Jul 22 20:00:30 homeassistant rauc[335]: Running slot hook slot-install for boot.0
Jul 22 20:01:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot boot.0 status
Jul 22 20:01:04 homeassistant rauc[335]: Checking image type for slot type: raw
Jul 22 20:01:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot boot.0 done
Jul 22 20:01:04 homeassistant rauc[335]: Image detected as type: *.img
Jul 22 20:01:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Checking slot kernel.1
Jul 22 20:01:04 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/kernel.img
Jul 22 20:01:04 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-kernel1 with /run/rauc/bundle/kernel.img
Jul 22 20:01:04 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot kernel.1
Jul 22 20:01:04 homeassistant rauc[335]: opening slot device /dev/disk/by-partlabel/hassos-kernel1
Jul 22 20:01:04 homeassistant rauc[335]: writing data to device /dev/disk/by-partlabel/hassos-kernel1
Jul 22 20:01:06 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot kernel.1 status
Jul 22 20:01:06 homeassistant rauc[335]: Checking image type for slot type: raw
Jul 22 20:01:06 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot kernel.1 done
Jul 22 20:01:06 homeassistant rauc[335]: Image detected as type: *.img
Jul 22 20:01:06 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Checking slot rootfs.1
Jul 22 20:01:06 homeassistant rauc[335]: Slot needs to be updated with /run/rauc/bundle/rootfs.img
Jul 22 20:01:06 homeassistant rauc[335]: Updating /dev/disk/by-partlabel/hassos-system1 with /run/rauc/bundle/rootfs.img
Jul 22 20:01:06 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot rootfs.1
Jul 22 20:01:06 homeassistant rauc[335]: opening slot device /dev/disk/by-partlabel/hassos-system1
Jul 22 20:01:06 homeassistant rauc[335]: writing data to device /dev/disk/by-partlabel/hassos-system1
Jul 22 20:01:27 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot rootfs.1 status
Jul 22 20:01:27 homeassistant rauc[335]: Marking target slot kernel.1 as bootable...
Jul 22 20:01:27 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: Updating slot rootfs.1 done
Jul 22 20:01:27 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: All slots updated
Jul 22 20:01:27 homeassistant rauc[335]: installing /mnt/data/supervisor/tmp/hassos-8.2.raucb: finished
Jul 22 20:01:27 homeassistant rauc[335]: installing `/mnt/data/supervisor/tmp/hassos-8.2.raucb` succeeded
Jul 22 20:02:39 homeassistant systemd[1]: Stopping Rauc Update Service...
Jul 22 20:02:39 homeassistant rauc[335]: stopping service
Jul 22 20:02:39 homeassistant systemd[1]: rauc.service: Deactivated successfully.
Jul 22 20:02:39 homeassistant systemd[1]: Stopped Rauc Update Service.
-- Boot c8cbd20124af4717a1ed1f8e4c2e65e5 --
Jul 22 20:02:46 homeassistant systemd[1]: Starting Rauc Update Service...
Jul 22 20:02:47 homeassistant rauc[360]: Using central status file /mnt/data/rauc.db
Jul 22 20:02:47 homeassistant rauc[360]: Using central status file /mnt/data/rauc.db
Jul 22 20:02:47 homeassistant systemd[1]: Started Rauc Update Service.
Jul 22 20:06:09 homeassistant rauc[360]: rauc mark: marked slot kernel.1 as good
agners commented 2 years ago

@czrogers13 At least on your last reboot, it seems the system rebooted into the new OS. Are you sure that updating failed?

Can you paste output of ha os info before and after the update?

saeedesmaili commented 2 years ago

I have the same issue, and while running the aforementioned commands, it seems like I don't have systemd installed. How can I run those commands on my raspberry pi?

~ journalctl -u rauc.service
zsh: command not found: journalctl

~ apt-get install systemd
zsh: command not found: apt-get
ppohns commented 2 years ago

same issue here - trying to upgrade from 7.6 to 8.4.

Nafania commented 2 years ago

Here is my log

Aug 30 15:47:34 homeassistant rauc[376]: Using deprecated 'Install' D-Bus Method (replaced by 'InstallBundle')
Aug 30 15:47:34 homeassistant rauc[376]: Active slot bootname: A
Aug 30 15:47:34 homeassistant rauc[376]: input bundle: /mnt/data/supervisor/tmp/hassos-8.5.raucb
Aug 30 15:47:34 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: started
Aug 30 15:47:34 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: Checking and mounting bundle...
Aug 30 15:47:34 homeassistant rauc[376]: Reading bundle: /mnt/data/supervisor/tmp/hassos-8.5.raucb
Aug 30 15:47:34 homeassistant rauc[376]: Detected CRL but CRL checking is disabled!
Aug 30 15:47:34 homeassistant rauc[376]: Verifying bundle signature...
Aug 30 15:47:35 homeassistant rauc[376]: Verified detached signature by 'O = HassOS, CN = HassOS GitHub-1'
Aug 30 15:47:35 homeassistant rauc[376]: Mounting bundle '/mnt/data/supervisor/tmp/hassos-8.5.raucb' to '/run/rauc/bundle'
Aug 30 15:47:35 homeassistant rauc[376]: Configured loop device '/dev/loop9' for 121425920 bytes
Aug 30 15:47:35 homeassistant rauc[376]: Running bundle hook install-check
Aug 30 15:47:35 homeassistant rauc[376]: Marking target slot kernel.1 as non-bootable...
Aug 30 15:47:35 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: Updating slots...
Aug 30 15:47:35 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: Checking slot boot.0
Aug 30 15:47:35 homeassistant rauc[376]: Slot needs to be updated with /run/rauc/bundle/boot.vfat
Aug 30 15:47:35 homeassistant rauc[376]: Updating /dev/disk/by-partlabel/hassos-boot with /run/rauc/bundle/boot.vfat
Aug 30 15:47:35 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: Updating slot boot.0
Aug 30 15:47:35 homeassistant rauc[376]: Running custom slot install hook for boot.0
Aug 30 15:47:35 homeassistant rauc[376]: Running slot hook slot-install for boot.0
Aug 30 15:47:36 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:36 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:36 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:36 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:37 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:37 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:38 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:38 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:39 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:39 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:39 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:39 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:40 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:47 homeassistant rauc[200951]: cp: write error: No space left on device
Aug 30 15:47:54 homeassistant rauc[376]: Installation error: Failed updating slot boot.0: failed to run slot hook: Child process exited with code 1
Aug 30 15:47:54 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: Installation error: Failed updating slot boot.0: failed to run slot hook: Child process exited with code 1
Aug 30 15:47:54 homeassistant rauc[376]: installing /mnt/data/supervisor/tmp/hassos-8.5.raucb: finished
Aug 30 15:47:54 homeassistant rauc[376]: installing `/mnt/data/supervisor/tmp/hassos-8.5.raucb` failed: 1
# df -h /mnt/data/supervisor/tmp/
Filesystem                Size      Used Available Use% Mounted on
/dev/mmcblk0p8           58.7G      9.7G     46.7G  17% /mnt/data
Nafania commented 1 year ago

@agners Any ideas on how i can help you to investigate more?

paavo commented 1 year ago

did someone found a solution / workaround?

Nafania commented 1 year ago

did someone found a solution / workaround?

I've just decided to do full backup, install latest version of ha from scratch and restore backup on top of the latest version. Everything went smoothly for me.

ppohns commented 1 year ago

Same here - reinstall.

agners commented 1 year ago

It seems the boot partition run full, I wonder why that is. If someone observes it again, and has shell access, can you check df -h and check the contents of /mnt/boot?

github-actions[bot] commented 1 year ago

There hasn't been any activity on this issue recently. To keep our backlog manageable we have to clean old issues, as many of them have already been resolved with the latest updates. Please make sure to update to the latest Home Assistant OS version and check if that solves the issue. Let us know if that works for you by adding a comment 👍 This issue has now been marked as stale and will be closed if no further activity occurs. Thank you for your contributions.