Oct 30 05:28:59.679905 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 30 05:28:59.679921 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 30 05:28:59.679929 kernel: efi: EFI v2.70 by EDK II Oct 30 05:28:59.679947 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 30 05:28:59.679952 kernel: efi: seeding entropy pool Oct 30 05:28:59.679957 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 30 05:28:59.679963 kernel: ACPI: Early table checksum verification disabled Oct 30 05:28:59.679968 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 30 05:28:59.679973 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 30 05:28:59.679978 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.679985 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.679990 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.679995 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680000 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680006 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680012 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680018 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680024 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:28:59.680035 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 30 05:28:59.680040 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 30 05:28:59.680046 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:28:59.680051 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Oct 30 05:28:59.680056 kernel: Zone ranges: Oct 30 05:28:59.680062 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:28:59.680067 kernel: DMA32 empty Oct 30 05:28:59.680072 kernel: Normal empty Oct 30 05:28:59.680077 kernel: Movable zone start for each node Oct 30 05:28:59.680083 kernel: Early memory node ranges Oct 30 05:28:59.680089 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 30 05:28:59.680094 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 30 05:28:59.680099 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 30 05:28:59.680105 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 30 05:28:59.680110 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 30 05:28:59.680115 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 30 05:28:59.680120 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 30 05:28:59.680126 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 30 05:28:59.680131 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 30 05:28:59.680136 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 30 05:28:59.680141 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 30 05:28:59.680147 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:28:59.680155 kernel: On node 0 totalpages: 643072 Oct 30 05:28:59.680161 kernel: DMA zone: 10048 pages used for memmap Oct 30 05:28:59.680167 kernel: DMA zone: 0 pages reserved Oct 30 05:28:59.680172 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 30 05:28:59.680178 kernel: psci: probing for conduit method from ACPI. Oct 30 05:28:59.680183 kernel: psci: PSCIv1.1 detected in firmware. Oct 30 05:28:59.680189 kernel: psci: Using standard PSCI v0.2 function IDs Oct 30 05:28:59.680195 kernel: psci: Trusted OS migration not required Oct 30 05:28:59.680201 kernel: psci: SMC Calling Convention v1.1 Oct 30 05:28:59.680207 kernel: ACPI: SRAT not present Oct 30 05:28:59.680213 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 30 05:28:59.680218 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 30 05:28:59.680224 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 30 05:28:59.680230 kernel: Detected PIPT I-cache on CPU0 Oct 30 05:28:59.680235 kernel: CPU features: detected: GIC system register CPU interface Oct 30 05:28:59.680241 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 30 05:28:59.680246 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 30 05:28:59.680252 kernel: CPU features: detected: Hardware dirty bit management Oct 30 05:28:59.680257 kernel: CPU features: detected: Spectre-v4 Oct 30 05:28:59.680264 kernel: CPU features: detected: ARM erratum 1418040 Oct 30 05:28:59.680269 kernel: CPU features: detected: Spectre-BHB Oct 30 05:28:59.680275 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 30 05:28:59.680280 kernel: Policy zone: DMA Oct 30 05:28:59.680287 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 30 05:28:59.680293 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 05:28:59.680299 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 05:28:59.680305 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 05:28:59.680311 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Oct 30 05:28:59.680316 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 30 05:28:59.680322 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 30 05:28:59.680328 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 30 05:28:59.680335 kernel: rcu: RCU event tracing is enabled. Oct 30 05:28:59.680340 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 30 05:28:59.680346 kernel: Trampoline variant of Tasks RCU enabled. Oct 30 05:28:59.680352 kernel: Tracing variant of Tasks RCU enabled. Oct 30 05:28:59.680358 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 30 05:28:59.680363 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 30 05:28:59.680369 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 30 05:28:59.680374 kernel: GICv3: 256 SPIs implemented Oct 30 05:28:59.680380 kernel: GICv3: 0 Extended SPIs implemented Oct 30 05:28:59.680386 kernel: GICv3: Distributor has no Range Selector support Oct 30 05:28:59.680391 kernel: GICv3: 16 PPIs implemented Oct 30 05:28:59.680398 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 30 05:28:59.680403 kernel: ACPI: SRAT not present Oct 30 05:28:59.680409 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 30 05:28:59.680414 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 30 05:28:59.680420 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 30 05:28:59.680426 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 30 05:28:59.680432 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 30 05:28:59.680437 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:28:59.680443 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 30 05:28:59.680449 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 30 05:28:59.680455 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 30 05:28:59.680460 kernel: arm-pv: using stolen time PV Oct 30 05:28:59.680467 kernel: Console: colour dummy device 80x25 Oct 30 05:28:59.680473 kernel: ACPI: Core revision 20200925 Oct 30 05:28:59.680479 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 30 05:28:59.680485 kernel: pid_max: default: 32768 minimum: 301 Oct 30 05:28:59.680491 kernel: LSM: Security Framework initializing Oct 30 05:28:59.680496 kernel: SELinux: Initializing. Oct 30 05:28:59.680502 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 05:28:59.680508 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 05:28:59.680514 kernel: rcu: Hierarchical SRCU implementation. Oct 30 05:28:59.680520 kernel: Platform MSI: ITS@0x8080000 domain created Oct 30 05:28:59.680526 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 30 05:28:59.680532 kernel: Remapping and enabling EFI services. Oct 30 05:28:59.680538 kernel: smp: Bringing up secondary CPUs ... Oct 30 05:28:59.680543 kernel: Detected PIPT I-cache on CPU1 Oct 30 05:28:59.680549 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 30 05:28:59.680564 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 30 05:28:59.680570 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:28:59.680576 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 30 05:28:59.680582 kernel: Detected PIPT I-cache on CPU2 Oct 30 05:28:59.680589 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 30 05:28:59.680596 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 30 05:28:59.680601 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:28:59.680607 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 30 05:28:59.680613 kernel: Detected PIPT I-cache on CPU3 Oct 30 05:28:59.680622 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 30 05:28:59.680629 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 30 05:28:59.680635 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:28:59.680641 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 30 05:28:59.680648 kernel: smp: Brought up 1 node, 4 CPUs Oct 30 05:28:59.680654 kernel: SMP: Total of 4 processors activated. Oct 30 05:28:59.680660 kernel: CPU features: detected: Privileged Access Never Oct 30 05:28:59.680666 kernel: CPU features: detected: LSE atomic instructions Oct 30 05:28:59.680672 kernel: CPU features: detected: User Access Override Oct 30 05:28:59.680678 kernel: CPU features: detected: 32-bit EL0 Support Oct 30 05:28:59.680684 kernel: CPU features: detected: Common not Private translations Oct 30 05:28:59.680690 kernel: CPU features: detected: RAS Extension Support Oct 30 05:28:59.680697 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 30 05:28:59.680703 kernel: CPU features: detected: CRC32 instructions Oct 30 05:28:59.680709 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 30 05:28:59.680715 kernel: CPU: All CPU(s) started at EL1 Oct 30 05:28:59.680721 kernel: alternatives: patching kernel code Oct 30 05:28:59.680729 kernel: devtmpfs: initialized Oct 30 05:28:59.680735 kernel: KASLR enabled Oct 30 05:28:59.680741 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 30 05:28:59.680747 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 30 05:28:59.680753 kernel: pinctrl core: initialized pinctrl subsystem Oct 30 05:28:59.680759 kernel: SMBIOS 3.0.0 present. Oct 30 05:28:59.680765 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 30 05:28:59.680771 kernel: NET: Registered protocol family 16 Oct 30 05:28:59.680778 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 30 05:28:59.680784 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 30 05:28:59.680791 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 30 05:28:59.680797 kernel: audit: initializing netlink subsys (disabled) Oct 30 05:28:59.680803 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 30 05:28:59.680809 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 05:28:59.680815 kernel: cpuidle: using governor menu Oct 30 05:28:59.680822 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 30 05:28:59.680828 kernel: ASID allocator initialised with 32768 entries Oct 30 05:28:59.680833 kernel: ACPI: bus type PCI registered Oct 30 05:28:59.680840 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 30 05:28:59.680847 kernel: Serial: AMBA PL011 UART driver Oct 30 05:28:59.680853 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 05:28:59.680859 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 30 05:28:59.680865 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 05:28:59.680871 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 30 05:28:59.680877 kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 05:28:59.680883 kernel: ACPI: Added _OSI(Module Device) Oct 30 05:28:59.680889 kernel: ACPI: Added _OSI(Processor Device) Oct 30 05:28:59.680895 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 30 05:28:59.680902 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 30 05:28:59.680908 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 30 05:28:59.680914 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 30 05:28:59.680920 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 30 05:28:59.680926 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 30 05:28:59.680932 kernel: ACPI: Interpreter enabled Oct 30 05:28:59.680938 kernel: ACPI: Using GIC for interrupt routing Oct 30 05:28:59.680944 kernel: ACPI: MCFG table detected, 1 entries Oct 30 05:28:59.680950 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 30 05:28:59.680957 kernel: printk: console [ttyAMA0] enabled Oct 30 05:28:59.680964 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 30 05:28:59.681070 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 30 05:28:59.681128 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 30 05:28:59.681181 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 30 05:28:59.681234 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 30 05:28:59.681285 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 30 05:28:59.681293 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 30 05:28:59.681301 kernel: PCI host bridge to bus 0000:00 Oct 30 05:28:59.681358 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 30 05:28:59.681406 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 30 05:28:59.681454 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 30 05:28:59.681503 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 30 05:28:59.681582 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 30 05:28:59.681645 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 30 05:28:59.681712 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 30 05:28:59.681772 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 30 05:28:59.681827 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 30 05:28:59.681890 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 30 05:28:59.681948 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 30 05:28:59.682005 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 30 05:28:59.682062 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 30 05:28:59.682116 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 30 05:28:59.682164 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 30 05:28:59.682172 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 30 05:28:59.682179 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 30 05:28:59.682185 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 30 05:28:59.682191 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 30 05:28:59.682197 kernel: vgaarb: loaded Oct 30 05:28:59.682203 kernel: Registered efivars operations Oct 30 05:28:59.682209 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 30 05:28:59.682217 kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 05:28:59.682223 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 30 05:28:59.682229 kernel: pnp: PnP ACPI init Oct 30 05:28:59.682291 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 30 05:28:59.682341 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 30 05:28:59.682350 kernel: pnp: PnP ACPI: found 1 devices Oct 30 05:28:59.682356 kernel: NET: Registered protocol family 2 Oct 30 05:28:59.682363 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 30 05:28:59.682370 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 30 05:28:59.682377 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 30 05:28:59.682383 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 30 05:28:59.682389 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 30 05:28:59.682395 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 05:28:59.682401 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 05:28:59.682407 kernel: NET: Registered protocol family 1 Oct 30 05:28:59.682414 kernel: PCI: CLS 0 bytes, default 64 Oct 30 05:28:59.682420 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 30 05:28:59.682427 kernel: kvm [1]: HYP mode not available Oct 30 05:28:59.682433 kernel: Initialise system trusted keyrings Oct 30 05:28:59.682439 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 30 05:28:59.682445 kernel: Key type asymmetric registered Oct 30 05:28:59.682451 kernel: Asymmetric key parser 'x509' registered Oct 30 05:28:59.682457 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 30 05:28:59.682463 kernel: io scheduler mq-deadline registered Oct 30 05:28:59.682469 kernel: io scheduler kyber registered Oct 30 05:28:59.682475 kernel: io scheduler bfq registered Oct 30 05:28:59.682482 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 30 05:28:59.682488 kernel: ACPI: Power Button [PWRB] Oct 30 05:28:59.682495 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 30 05:28:59.682549 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 30 05:28:59.682565 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 30 05:28:59.682571 kernel: msm_serial: driver initialized Oct 30 05:28:59.682577 kernel: random: fast init done Oct 30 05:28:59.682583 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 30 05:28:59.682589 kernel: random: crng init done Oct 30 05:28:59.682596 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 30 05:28:59.682603 kernel: thunder_xcv, ver 1.0 Oct 30 05:28:59.682609 kernel: thunder_bgx, ver 1.0 Oct 30 05:28:59.682615 kernel: nicpf, ver 1.0 Oct 30 05:28:59.682621 kernel: nicvf, ver 1.0 Oct 30 05:28:59.682682 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 30 05:28:59.682734 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-30T05:28:59 UTC (1761802139) Oct 30 05:28:59.682742 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 30 05:28:59.682749 kernel: NET: Registered protocol family 10 Oct 30 05:28:59.682756 kernel: Segment Routing with IPv6 Oct 30 05:28:59.682762 kernel: NET: Registered protocol family 17 Oct 30 05:28:59.682768 kernel: Key type dns_resolver registered Oct 30 05:28:59.682774 kernel: registered taskstats version 1 Oct 30 05:28:59.682780 kernel: Loading compiled-in X.509 certificates Oct 30 05:28:59.682786 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 30 05:28:59.682793 kernel: Key type ._fscrypt registered Oct 30 05:28:59.682799 kernel: Key type .fscrypt registered Oct 30 05:28:59.682805 kernel: Key type fscrypt-provisioning registered Oct 30 05:28:59.682812 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 30 05:28:59.682818 kernel: ima: Allocated hash algorithm: sha1 Oct 30 05:28:59.682824 kernel: ima: No architecture policies found Oct 30 05:28:59.682830 kernel: Freeing unused kernel memory: 34240K Oct 30 05:28:59.682837 kernel: Run /init as init process Oct 30 05:28:59.682842 kernel: with arguments: Oct 30 05:28:59.682848 kernel: /init Oct 30 05:28:59.682854 kernel: with environment: Oct 30 05:28:59.682860 kernel: HOME=/ Oct 30 05:28:59.682866 kernel: TERM=linux Oct 30 05:28:59.682872 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 30 05:28:59.682881 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=unified) Oct 30 05:28:59.682889 systemd[1]: Detected virtualization kvm. Oct 30 05:28:59.682896 systemd[1]: Detected architecture arm64. Oct 30 05:28:59.682902 systemd[1]: Running in initial RAM disk. Oct 30 05:28:59.682909 systemd[1]: No hostname configured, using default hostname. Oct 30 05:28:59.682915 systemd[1]: Hostname set to . Oct 30 05:28:59.682924 systemd[1]: Initializing machine ID from VM UUID. Oct 30 05:28:59.682930 systemd[1]: Queued start job for default target Initrd Default Target. Oct 30 05:28:59.682937 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 30 05:28:59.682943 systemd[1]: Reached target Local Encrypted Volumes. Oct 30 05:28:59.682950 systemd[1]: Reached target Path Units. Oct 30 05:28:59.682956 systemd[1]: Reached target Slice Units. Oct 30 05:28:59.682963 systemd[1]: Reached target Swaps. Oct 30 05:28:59.682969 systemd[1]: Reached target Timer Units. Oct 30 05:28:59.682977 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 30 05:28:59.682984 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 30 05:28:59.682990 systemd[1]: Listening on Journal Audit Socket. Oct 30 05:28:59.682997 systemd[1]: Listening on Journal Socket (/dev/log). Oct 30 05:28:59.683003 systemd[1]: Listening on Journal Socket. Oct 30 05:28:59.683010 systemd[1]: Listening on Network Service Netlink Socket. Oct 30 05:28:59.683017 systemd[1]: Listening on udev Control Socket. Oct 30 05:28:59.683023 systemd[1]: Listening on udev Kernel Socket. Oct 30 05:28:59.683035 systemd[1]: Reached target Socket Units. Oct 30 05:28:59.683043 systemd[1]: Starting Create List of Static Device Nodes... Oct 30 05:28:59.683050 systemd[1]: Finished Network Cleanup. Oct 30 05:28:59.683056 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 30 05:28:59.683063 systemd[1]: Starting Journal Service... Oct 30 05:28:59.683069 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 30 05:28:59.683076 systemd[1]: Starting Apply Kernel Variables... Oct 30 05:28:59.683082 systemd[1]: Starting Setup Virtual Console... Oct 30 05:28:59.683089 systemd[1]: Finished Create List of Static Device Nodes. Oct 30 05:28:59.683096 systemd[1]: Finished Apply Kernel Variables. Oct 30 05:28:59.683103 systemd[1]: Finished Setup Virtual Console. Oct 30 05:28:59.683110 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 30 05:28:59.683118 systemd-journald[280]: Journal started Oct 30 05:28:59.683152 systemd-journald[280]: Runtime Journal (/run/log/journal/17691bb7b7564595812e4271af311cda) is 6.0M, max 48.5M, 42.4M free. Oct 30 05:28:59.685815 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 30 05:28:59.687150 systemd[1]: Started Journal Service. Oct 30 05:28:59.687524 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 30 05:28:59.699137 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 30 05:28:59.700700 systemd[1]: Starting dracut cmdline hook... Oct 30 05:28:59.709064 dracut-cmdline[296]: dracut-dracut-053 Oct 30 05:28:59.711240 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 30 05:28:59.766577 kernel: SCSI subsystem initialized Oct 30 05:28:59.769574 kernel: Loading iSCSI transport class v2.0-870. Oct 30 05:28:59.775586 kernel: iscsi: registered transport (tcp) Oct 30 05:28:59.786580 kernel: iscsi: registered transport (qla4xxx) Oct 30 05:28:59.787575 kernel: QLogic iSCSI HBA Driver Oct 30 05:28:59.796318 systemd[1]: Finished dracut cmdline hook. Oct 30 05:28:59.797702 systemd[1]: Starting dracut pre-udev hook... Oct 30 05:28:59.810576 kernel: device-mapper: uevent: version 1.0.3 Oct 30 05:28:59.810605 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 30 05:28:59.845582 kernel: raid6: neonx8 gen() 13768 MB/s Oct 30 05:28:59.862570 kernel: raid6: neonx8 xor() 10727 MB/s Oct 30 05:28:59.879571 kernel: raid6: neonx4 gen() 13602 MB/s Oct 30 05:28:59.896566 kernel: raid6: neonx4 xor() 11563 MB/s Oct 30 05:28:59.913567 kernel: raid6: neonx2 gen() 12979 MB/s Oct 30 05:28:59.930578 kernel: raid6: neonx2 xor() 9856 MB/s Oct 30 05:28:59.947576 kernel: raid6: neonx1 gen() 10998 MB/s Oct 30 05:28:59.964573 kernel: raid6: neonx1 xor() 8839 MB/s Oct 30 05:28:59.981582 kernel: raid6: int64x8 gen() 6834 MB/s Oct 30 05:28:59.998580 kernel: raid6: int64x8 xor() 3798 MB/s Oct 30 05:29:00.015580 kernel: raid6: int64x4 gen() 6912 MB/s Oct 30 05:29:00.032581 kernel: raid6: int64x4 xor() 3837 MB/s Oct 30 05:29:00.049584 kernel: raid6: int64x2 gen() 6153 MB/s Oct 30 05:29:00.066573 kernel: raid6: int64x2 xor() 3311 MB/s Oct 30 05:29:00.083580 kernel: raid6: int64x1 gen() 5012 MB/s Oct 30 05:29:00.100644 kernel: raid6: int64x1 xor() 2598 MB/s Oct 30 05:29:00.100665 kernel: raid6: using algorithm neonx8 gen() 13768 MB/s Oct 30 05:29:00.100680 kernel: raid6: .... xor() 10727 MB/s, rmw enabled Oct 30 05:29:00.101711 kernel: raid6: using neon recovery algorithm Oct 30 05:29:00.111578 kernel: xor: measuring software checksum speed Oct 30 05:29:00.112572 kernel: 8regs : 17152 MB/sec Oct 30 05:29:00.113962 kernel: 32regs : 20760 MB/sec Oct 30 05:29:00.113973 kernel: arm64_neon : 29059 MB/sec Oct 30 05:29:00.113981 kernel: xor: using function: arm64_neon (29059 MB/sec) Oct 30 05:29:00.159578 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 30 05:29:00.169051 systemd[1]: Finished dracut pre-udev hook. Oct 30 05:29:00.172663 kernel: audit: type=1334 audit(1761802140.169:2): prog-id=6 op=LOAD Oct 30 05:29:00.172702 kernel: audit: type=1334 audit(1761802140.169:3): prog-id=7 op=LOAD Oct 30 05:29:00.172712 kernel: audit: type=1334 audit(1761802140.170:4): prog-id=8 op=LOAD Oct 30 05:29:00.169000 audit: BPF prog-id=6 op=LOAD Oct 30 05:29:00.169000 audit: BPF prog-id=7 op=LOAD Oct 30 05:29:00.170000 audit: BPF prog-id=8 op=LOAD Oct 30 05:29:00.171548 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 30 05:29:00.183463 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 30 05:29:00.185185 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 30 05:29:00.186843 systemd[1]: Starting dracut pre-trigger hook... Oct 30 05:29:00.187000 audit: BPF prog-id=9 op=LOAD Oct 30 05:29:00.188588 systemd[1]: Starting Network Configuration... Oct 30 05:29:00.191941 kernel: audit: type=1334 audit(1761802140.187:5): prog-id=9 op=LOAD Oct 30 05:29:00.199863 dracut-pre-trigger[424]: rd.md=0: removing MD RAID activation Oct 30 05:29:00.206269 systemd-networkd[430]: lo: Link UP Oct 30 05:29:00.209163 kernel: audit: type=1334 audit(1761802140.207:6): prog-id=10 op=LOAD Oct 30 05:29:00.207000 audit: BPF prog-id=10 op=LOAD Oct 30 05:29:00.206275 systemd-networkd[430]: lo: Gained carrier Oct 30 05:29:00.206524 systemd-networkd[430]: Enumeration completed Oct 30 05:29:00.206602 systemd[1]: Started Network Configuration. Oct 30 05:29:00.208131 systemd[1]: Starting Network Name Resolution... Oct 30 05:29:00.233615 systemd[1]: Finished dracut pre-trigger hook. Oct 30 05:29:00.234904 systemd[1]: Starting Coldplug All udev Devices... Oct 30 05:29:00.235039 systemd-resolved[446]: Positive Trust Anchors: Oct 30 05:29:00.235045 systemd-resolved[446]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 05:29:00.235073 systemd-resolved[446]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 30 05:29:00.235316 systemd-resolved[446]: Defaulting to hostname 'linux'. Oct 30 05:29:00.235915 systemd[1]: Started Network Name Resolution. Oct 30 05:29:00.236975 systemd[1]: Reached target Network. Oct 30 05:29:00.238133 systemd[1]: Reached target Host and Network Name Lookups. Oct 30 05:29:00.240634 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 30 05:29:00.246219 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 30 05:29:00.253279 iscsid[474]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 30 05:29:00.253279 iscsid[474]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log Oct 30 05:29:00.253279 iscsid[474]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 30 05:29:00.253279 iscsid[474]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 30 05:29:00.253279 iscsid[474]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 30 05:29:00.253279 iscsid[474]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 30 05:29:00.253279 iscsid[474]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 30 05:29:00.247448 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 30 05:29:00.250279 systemd[1]: Starting Open-iSCSI... Oct 30 05:29:00.256032 systemd[1]: Started Open-iSCSI. Oct 30 05:29:00.268794 systemd[1]: Finished Coldplug All udev Devices. Oct 30 05:29:00.270216 systemd[1]: Starting dracut initqueue hook... Oct 30 05:29:00.301162 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 30 05:29:00.301293 kernel: vda: detected capacity change from 0 to 4756340736 Oct 30 05:29:00.311719 systemd-udevd[482]: Using default interface naming scheme 'v249'. Oct 30 05:29:00.314578 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:00.317311 systemd-networkd[430]: eth0: Link UP Oct 30 05:29:00.330213 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (476) Oct 30 05:29:00.328993 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 30 05:29:00.335413 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 30 05:29:00.337121 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 30 05:29:00.342613 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 30 05:29:00.344362 systemd[1]: Finished dracut initqueue hook. Oct 30 05:29:00.347650 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 30 05:29:00.349356 systemd[1]: Reached target Initrd Root Device. Oct 30 05:29:00.350884 systemd[1]: Reached target Preparation for Remote File Systems. Oct 30 05:29:00.352640 systemd[1]: Reached target Remote Encrypted Volumes. Oct 30 05:29:00.354248 systemd[1]: Reached target Remote File Systems. Oct 30 05:29:00.356317 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 30 05:29:00.357800 systemd[1]: Starting dracut pre-mount hook... Oct 30 05:29:00.365580 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:00.365979 systemd[1]: Finished dracut pre-mount hook. Oct 30 05:29:00.369571 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:00.372569 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:01.328593 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 30 05:29:01.328661 systemd-networkd[430]: eth0: Gained carrier Oct 30 05:29:01.339627 systemd-networkd[430]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 30 05:29:01.376601 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:01.376865 disk-uuid[505]: The operation has completed successfully. Oct 30 05:29:01.398438 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 30 05:29:01.398584 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 30 05:29:01.400914 systemd[1]: Starting Ignition (setup)... Oct 30 05:29:01.408823 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 30 05:29:01.408859 kernel: BTRFS info (device vda6): has skinny extents Oct 30 05:29:01.414545 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 30 05:29:01.419884 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 30 05:29:01.419990 systemd[1]: Finished Ignition (setup). Oct 30 05:29:01.421926 systemd[1]: Starting Ignition (disks)... Oct 30 05:29:01.431367 ignition[540]: Ignition v0.36.1-15-gde4e6cc9 Oct 30 05:29:01.431384 ignition[540]: Stage: disks Oct 30 05:29:01.431393 ignition[540]: reading system config file "/usr/lib/ignition/base.ign" Oct 30 05:29:01.431403 ignition[540]: no config at "/usr/lib/ignition/base.ign" Oct 30 05:29:01.431444 ignition[540]: parsed url from cmdline: "" Oct 30 05:29:01.431448 ignition[540]: no config URL provided Oct 30 05:29:01.431452 ignition[540]: reading system config file "/usr/lib/ignition/user.ign" Oct 30 05:29:01.431459 ignition[540]: no config at "/usr/lib/ignition/user.ign" Oct 30 05:29:01.431476 ignition[540]: op(1): [started] loading QEMU firmware config module Oct 30 05:29:01.431482 ignition[540]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 30 05:29:01.432968 ignition[540]: op(1): [finished] loading QEMU firmware config module Oct 30 05:29:01.445705 ignition[540]: parsing config with SHA512: 7eefdfe5420fa4089ca0eb587f0b71126ba7bea3b22bf5ca6561acb28af6ce88250898e16bc101d435788ed830ef043326289e5233f32fa60793024494a6bf0b Oct 30 05:29:01.447951 ignition[540]: disks: disks passed Oct 30 05:29:01.447960 ignition[540]: Ignition finished successfully Oct 30 05:29:01.448536 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 30 05:29:01.448661 systemd[1]: Finished Ignition (disks). Oct 30 05:29:01.449965 systemd[1]: Reached target Preparation for Local File Systems. Oct 30 05:29:01.452048 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 30 05:29:01.454193 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 30 05:29:01.461834 systemd-fsck[553]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 30 05:29:01.464581 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 30 05:29:01.464901 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 30 05:29:01.467233 systemd[1]: Mounting /sysroot... Oct 30 05:29:01.484071 systemd[1]: Found device /dev/mapper/usr. Oct 30 05:29:01.485667 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 30 05:29:01.487894 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 30 05:29:01.497509 systemd-fsck[572]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 30 05:29:01.497509 systemd-fsck[572]: You must have r/w access to the filesystem or be root Oct 30 05:29:01.499632 systemd-fsck[568]: fsck failed with exit status 8. Oct 30 05:29:01.499638 systemd-fsck[568]: Ignoring error. Oct 30 05:29:01.500251 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 30 05:29:01.502149 systemd[1]: Mounting /sysusr/usr... Oct 30 05:29:01.508969 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 30 05:29:01.508997 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 30 05:29:01.509438 systemd[1]: Mounted /sysroot. Oct 30 05:29:01.511264 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 30 05:29:01.511267 systemd[1]: Mounted /sysusr/usr. Oct 30 05:29:01.511980 systemd[1]: Reached target Initrd Root File System. Oct 30 05:29:01.513102 systemd[1]: Reached target Local File Systems. Oct 30 05:29:01.514370 systemd[1]: Reached target System Initialization. Oct 30 05:29:01.515676 systemd[1]: Reached target Basic System. Oct 30 05:29:01.517826 systemd[1]: Mounting /sysroot/usr... Oct 30 05:29:01.519784 systemd[1]: Mounted /sysroot/usr. Oct 30 05:29:01.521003 systemd[1]: Starting Root filesystem setup... Oct 30 05:29:01.544538 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 30 05:29:01.544665 systemd[1]: Finished Root filesystem setup. Oct 30 05:29:01.546740 systemd[1]: Starting Ignition (files)... Oct 30 05:29:01.547927 systemd[1]: Starting /sysroot/boot... Oct 30 05:29:01.550575 ignition[587]: Ignition v0.36.1-15-gde4e6cc9 Oct 30 05:29:01.550589 ignition[587]: Stage: files Oct 30 05:29:01.550599 ignition[587]: reading system config file "/usr/lib/ignition/base.ign" Oct 30 05:29:01.550610 ignition[587]: no config at "/usr/lib/ignition/base.ign" Oct 30 05:29:01.551220 ignition[587]: files: compiled without relabeling support, skipping Oct 30 05:29:01.562882 systemd[1]: Finished /sysroot/boot. Oct 30 05:29:01.566184 ignition[587]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 30 05:29:01.566197 ignition[587]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 30 05:29:01.567413 ignition[587]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 30 05:29:01.567419 ignition[587]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 30 05:29:01.568604 ignition[587]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 30 05:29:01.568628 ignition[587]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 30 05:29:01.570142 systemd[1]: ignition-files.service: Deactivated successfully. Oct 30 05:29:01.568908 ignition[587]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 30 05:29:01.570242 systemd[1]: Finished Ignition (files). Oct 30 05:29:01.568915 ignition[587]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 30 05:29:01.571589 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 30 05:29:01.569003 ignition[587]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 30 05:29:01.580000 audit: BPF prog-id=9 op=UNLOAD Oct 30 05:29:01.572201 systemd[1]: Starting Ignition (record completion)... Oct 30 05:29:01.583153 kernel: audit: type=1334 audit(1761802141.580:7): prog-id=9 op=UNLOAD Oct 30 05:29:01.569501 ignition[587]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 30 05:29:01.574220 systemd[1]: Starting Reload Configuration from the Real Root... Oct 30 05:29:01.587077 kernel: audit: type=1334 audit(1761802141.584:8): prog-id=3 op=UNLOAD Oct 30 05:29:01.587094 kernel: audit: type=1334 audit(1761802141.584:9): prog-id=6 op=UNLOAD Oct 30 05:29:01.587103 kernel: audit: type=1334 audit(1761802141.585:10): prog-id=10 op=UNLOAD Oct 30 05:29:01.584000 audit: BPF prog-id=3 op=UNLOAD Oct 30 05:29:01.584000 audit: BPF prog-id=6 op=UNLOAD Oct 30 05:29:01.585000 audit: BPF prog-id=10 op=UNLOAD Oct 30 05:29:01.569507 ignition[587]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 30 05:29:01.575282 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 30 05:29:01.569511 ignition[587]: files: files passed Oct 30 05:29:01.575390 systemd[1]: Finished Ignition (record completion). Oct 30 05:29:01.569515 ignition[587]: Ignition finished successfully Oct 30 05:29:01.578437 systemd[1]: Reloading. Oct 30 05:29:01.664000 audit: BPF prog-id=11 op=LOAD Oct 30 05:29:01.664000 audit: BPF prog-id=12 op=LOAD Oct 30 05:29:01.664000 audit: BPF prog-id=13 op=LOAD Oct 30 05:29:01.664000 audit: BPF prog-id=14 op=LOAD Oct 30 05:29:01.664000 audit: BPF prog-id=4 op=UNLOAD Oct 30 05:29:01.664000 audit: BPF prog-id=5 op=UNLOAD Oct 30 05:29:01.665000 audit: BPF prog-id=15 op=LOAD Oct 30 05:29:01.665000 audit: BPF prog-id=16 op=LOAD Oct 30 05:29:01.665000 audit: BPF prog-id=17 op=LOAD Oct 30 05:29:01.665000 audit: BPF prog-id=7 op=UNLOAD Oct 30 05:29:01.665000 audit: BPF prog-id=8 op=UNLOAD Oct 30 05:29:01.665000 audit: BPF prog-id=18 op=LOAD Oct 30 05:29:01.679058 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 30 05:29:01.679164 systemd[1]: Finished Reload Configuration from the Real Root. Oct 30 05:29:01.680854 systemd[1]: Reached target Initrd File Systems. Oct 30 05:29:01.682212 systemd[1]: Reached target Initrd Default Target. Oct 30 05:29:01.683505 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 30 05:29:01.684155 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 30 05:29:01.693947 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 30 05:29:01.695390 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 30 05:29:01.700992 systemd[1]: Stopped target Host and Network Name Lookups. Oct 30 05:29:01.701908 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 30 05:29:01.703458 systemd[1]: Stopped target Timer Units. Oct 30 05:29:01.704747 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 30 05:29:01.704855 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 30 05:29:01.706074 systemd[1]: Stopped target Initrd Default Target. Oct 30 05:29:01.707402 systemd[1]: Stopped target Basic System. Oct 30 05:29:01.708679 systemd[1]: Stopped target Initrd Root Device. Oct 30 05:29:01.709956 systemd[1]: Stopped target Path Units. Oct 30 05:29:01.711141 systemd[1]: Stopped target Remote File Systems. Oct 30 05:29:01.712402 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 30 05:29:01.713738 systemd[1]: Stopped target Slice Units. Oct 30 05:29:01.714992 systemd[1]: Stopped target Socket Units. Oct 30 05:29:01.716326 systemd[1]: Stopped target System Initialization. Oct 30 05:29:01.717520 systemd[1]: Stopped target Local File Systems. Oct 30 05:29:01.718745 systemd[1]: Stopped target Preparation for Local File Systems. Oct 30 05:29:01.720190 systemd[1]: Stopped target Swaps. Oct 30 05:29:01.721294 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 30 05:29:01.721392 systemd[1]: Stopped dracut pre-mount hook. Oct 30 05:29:01.722715 systemd[1]: Stopped target Local Encrypted Volumes. Oct 30 05:29:01.723866 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 05:29:01.727718 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:01.728756 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 30 05:29:01.728851 systemd[1]: Stopped dracut initqueue hook. Oct 30 05:29:01.732210 iscsid[474]: iscsid shutting down. Oct 30 05:29:01.730495 systemd[1]: Stopping Open-iSCSI... Oct 30 05:29:01.732188 systemd[1]: Stopping /sysroot/boot... Oct 30 05:29:01.732776 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 30 05:29:01.732902 systemd[1]: Stopped Coldplug All udev Devices. Oct 30 05:29:01.734059 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 30 05:29:01.734150 systemd[1]: Stopped dracut pre-trigger hook. Oct 30 05:29:01.736123 systemd[1]: iscsid.service: Deactivated successfully. Oct 30 05:29:01.736240 systemd[1]: Stopped Open-iSCSI. Oct 30 05:29:01.737632 systemd[1]: iscsid.socket: Deactivated successfully. Oct 30 05:29:01.737684 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 30 05:29:01.738646 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 30 05:29:01.741621 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 30 05:29:01.741725 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 30 05:29:01.743035 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 30 05:29:01.743145 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 30 05:29:01.744456 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 30 05:29:01.744573 systemd[1]: Stopped /sysroot/boot. Oct 30 05:29:01.745924 systemd[1]: Stopped target Network. Oct 30 05:29:01.746613 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 30 05:29:01.746676 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 30 05:29:01.747908 systemd[1]: Stopping Network Name Resolution... Oct 30 05:29:01.754731 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 30 05:29:01.754845 systemd[1]: Stopped Network Name Resolution. Oct 30 05:29:01.755827 systemd[1]: Stopping Network Configuration... Oct 30 05:29:01.759643 systemd-networkd[430]: eth0: DHCP lease lost Oct 30 05:29:01.761000 audit: BPF prog-id=18 op=UNLOAD Oct 30 05:29:01.767721 systemd-networkd[430]: eth0: DHCPv6 lease lost Oct 30 05:29:01.768654 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 30 05:29:01.768769 systemd[1]: Stopped Network Configuration. Oct 30 05:29:01.770293 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 30 05:29:01.770324 systemd[1]: Closed Network Service Netlink Socket. Oct 30 05:29:01.771963 systemd[1]: Stopping Network Cleanup... Oct 30 05:29:01.772756 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 05:29:01.776000 audit: BPF prog-id=11 op=UNLOAD Oct 30 05:29:01.772824 systemd[1]: Stopped Apply Kernel Variables. Oct 30 05:29:01.774149 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 30 05:29:01.779384 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 30 05:29:01.779500 systemd[1]: Stopped Network Cleanup. Oct 30 05:29:01.781983 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 30 05:29:01.782109 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 30 05:29:01.783598 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 30 05:29:01.783639 systemd[1]: Closed udev Control Socket. Oct 30 05:29:01.784857 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 30 05:29:01.789000 audit: BPF prog-id=15 op=UNLOAD Oct 30 05:29:01.784890 systemd[1]: Closed udev Kernel Socket. Oct 30 05:29:01.786293 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 30 05:29:01.786338 systemd[1]: Stopped dracut pre-udev hook. Oct 30 05:29:01.787904 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 30 05:29:01.787943 systemd[1]: Stopped dracut cmdline hook. Oct 30 05:29:01.789045 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 30 05:29:01.789088 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 30 05:29:01.791260 systemd[1]: Starting Cleanup udev Database... Oct 30 05:29:01.792788 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 30 05:29:01.793927 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 05:29:01.793985 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 30 05:29:01.795389 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 30 05:29:01.795430 systemd[1]: Stopped Create List of Static Device Nodes. Oct 30 05:29:01.796678 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 05:29:01.796722 systemd[1]: Stopped Setup Virtual Console. Oct 30 05:29:01.798329 systemd[1]: rngd.service: Deactivated successfully. Oct 30 05:29:01.798432 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 30 05:29:01.799822 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 30 05:29:01.809000 audit: BPF prog-id=12 op=UNLOAD Oct 30 05:29:01.799917 systemd[1]: Finished Cleanup udev Database. Oct 30 05:29:01.800972 systemd[1]: Reached target Switch Root. Oct 30 05:29:01.802834 systemd[1]: Starting Switch Root... Oct 30 05:29:01.807448 systemd[1]: Switching root. Oct 30 05:29:01.828457 systemd-journald[280]: Journal stopped Oct 30 05:29:03.944754 systemd-journald[280]: Received SIGTERM from PID 1 (systemd). Oct 30 05:29:03.944810 kernel: SELinux: policy capability network_peer_controls=1 Oct 30 05:29:03.944825 kernel: SELinux: policy capability open_perms=1 Oct 30 05:29:03.944834 kernel: SELinux: policy capability extended_socket_class=1 Oct 30 05:29:03.944843 kernel: SELinux: policy capability always_check_network=0 Oct 30 05:29:03.944853 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 30 05:29:03.944864 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 30 05:29:03.944873 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 30 05:29:03.944883 systemd[1]: Successfully loaded SELinux policy in 29.872ms. Oct 30 05:29:03.944895 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.186ms. Oct 30 05:29:03.944906 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=unified) Oct 30 05:29:03.944916 systemd[1]: Detected virtualization kvm. Oct 30 05:29:03.944925 systemd[1]: Detected architecture arm64. Oct 30 05:29:03.944935 systemd[1]: Detected first boot. Oct 30 05:29:03.944946 systemd[1]: Initializing machine ID from VM UUID. Oct 30 05:29:03.944955 systemd[1]: Populated /etc with preset unit settings. Oct 30 05:29:03.944966 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 30 05:29:03.944977 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 30 05:29:03.944990 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 30 05:29:03.945000 systemd[1]: Stopped Switch Root. Oct 30 05:29:03.945017 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 30 05:29:03.945054 systemd[1]: Created slice Slice /system/addon-config. Oct 30 05:29:03.945066 systemd[1]: Created slice Slice /system/addon-run. Oct 30 05:29:03.945075 systemd[1]: Created slice Slice /system/getty. Oct 30 05:29:03.945085 systemd[1]: Created slice Slice /system/modprobe. Oct 30 05:29:03.945096 systemd[1]: Created slice Slice /system/serial-getty. Oct 30 05:29:03.945105 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 30 05:29:03.945115 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 30 05:29:03.945125 systemd[1]: Created slice User and Session Slice. Oct 30 05:29:03.945134 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:03.945144 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 30 05:29:03.945154 systemd[1]: Set up automount Boot partition Automount Point. Oct 30 05:29:03.945253 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 30 05:29:03.945265 systemd[1]: Stopped target Switch Root. Oct 30 05:29:03.945275 systemd[1]: Stopped target Initrd File Systems. Oct 30 05:29:03.945285 systemd[1]: Stopped target Initrd Root File System. Oct 30 05:29:03.945295 systemd[1]: Reached target Remote Encrypted Volumes. Oct 30 05:29:03.945304 systemd[1]: Reached target Remote File Systems. Oct 30 05:29:03.945314 systemd[1]: Reached target Slice Units. Oct 30 05:29:03.945335 systemd[1]: Reached target Swaps. Oct 30 05:29:03.945346 systemd[1]: Reached target Verify torcx succeeded. Oct 30 05:29:03.945357 systemd[1]: Reached target Local Verity Protected Volumes. Oct 30 05:29:03.945367 systemd[1]: Listening on Process Core Dump Socket. Oct 30 05:29:03.945377 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 30 05:29:03.945386 systemd[1]: Listening on Network Service Netlink Socket. Oct 30 05:29:03.945396 systemd[1]: Listening on udev Control Socket. Oct 30 05:29:03.945406 systemd[1]: Listening on udev Kernel Socket. Oct 30 05:29:03.945415 systemd[1]: Mounting Huge Pages File System... Oct 30 05:29:03.945425 systemd[1]: Mounting POSIX Message Queue File System... Oct 30 05:29:03.945435 systemd[1]: Mounting External Media Directory... Oct 30 05:29:03.945444 systemd[1]: Mounting Kernel Debug File System... Oct 30 05:29:03.945560 systemd[1]: Mounting Kernel Trace File System... Oct 30 05:29:03.945572 systemd[1]: Mounting Temporary Directory /tmp... Oct 30 05:29:03.945582 systemd[1]: Starting Create missing system files... Oct 30 05:29:03.945591 systemd[1]: Starting Create List of Static Device Nodes... Oct 30 05:29:03.945601 systemd[1]: Starting Load Kernel Module configfs... Oct 30 05:29:03.945611 systemd[1]: Starting Load Kernel Module drm... Oct 30 05:29:03.945620 systemd[1]: Starting Load Kernel Module fuse... Oct 30 05:29:03.945630 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 30 05:29:03.945640 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 30 05:29:03.945651 systemd[1]: Stopped File System Check on Root Device. Oct 30 05:29:03.945661 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 30 05:29:03.945670 systemd[1]: Stopped systemd-fsck-usr.service. Oct 30 05:29:03.945681 systemd[1]: Stopped Journal Service. Oct 30 05:29:03.945691 kernel: fuse: init (API version 7.32) Oct 30 05:29:03.945700 kernel: kauditd_printk_skb: 36 callbacks suppressed Oct 30 05:29:03.945709 kernel: audit: type=1334 audit(1761802143.928:47): prog-id=27 op=LOAD Oct 30 05:29:03.945718 kernel: audit: type=1334 audit(1761802143.929:48): prog-id=28 op=LOAD Oct 30 05:29:03.945728 systemd[1]: Starting Journal Service... Oct 30 05:29:03.945738 kernel: audit: type=1334 audit(1761802143.929:49): prog-id=29 op=LOAD Oct 30 05:29:03.945748 kernel: audit: type=1334 audit(1761802143.929:50): prog-id=25 op=UNLOAD Oct 30 05:29:03.945757 kernel: audit: type=1334 audit(1761802143.929:51): prog-id=26 op=UNLOAD Oct 30 05:29:03.945766 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 30 05:29:03.945776 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 30 05:29:03.945785 systemd[1]: Starting Apply Kernel Variables... Oct 30 05:29:03.945795 systemd[1]: Starting Coldplug All udev Devices... Oct 30 05:29:03.945805 systemd[1]: verity-setup.service: Deactivated successfully. Oct 30 05:29:03.945814 kernel: audit: type=1305 audit(1761802143.943:52): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 30 05:29:03.945823 systemd[1]: Stopped verity-setup.service. Oct 30 05:29:03.945835 systemd-journald[803]: Journal started Oct 30 05:29:03.945873 systemd-journald[803]: Runtime Journal (/run/log/journal/17691bb7b7564595812e4271af311cda) is 6.0M, max 48.5M, 42.4M free. Oct 30 05:29:01.889000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 30 05:29:01.917000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 30 05:29:01.917000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 30 05:29:01.917000 audit: BPF prog-id=19 op=LOAD Oct 30 05:29:01.917000 audit: BPF prog-id=19 op=UNLOAD Oct 30 05:29:01.917000 audit: BPF prog-id=20 op=LOAD Oct 30 05:29:01.917000 audit: BPF prog-id=20 op=UNLOAD Oct 30 05:29:01.951000 audit[752]: AVC avc: denied { associate } for pid=752 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 30 05:29:03.832000 audit: BPF prog-id=21 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=22 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=23 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=13 op=UNLOAD Oct 30 05:29:03.832000 audit: BPF prog-id=14 op=UNLOAD Oct 30 05:29:03.832000 audit: BPF prog-id=24 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=21 op=UNLOAD Oct 30 05:29:03.832000 audit: BPF prog-id=25 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=26 op=LOAD Oct 30 05:29:03.832000 audit: BPF prog-id=22 op=UNLOAD Oct 30 05:29:03.832000 audit: BPF prog-id=23 op=UNLOAD Oct 30 05:29:03.840000 audit: BPF prog-id=24 op=UNLOAD Oct 30 05:29:03.928000 audit: BPF prog-id=27 op=LOAD Oct 30 05:29:03.929000 audit: BPF prog-id=28 op=LOAD Oct 30 05:29:03.929000 audit: BPF prog-id=29 op=LOAD Oct 30 05:29:03.929000 audit: BPF prog-id=25 op=UNLOAD Oct 30 05:29:03.929000 audit: BPF prog-id=26 op=UNLOAD Oct 30 05:29:03.943000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 30 05:29:03.821033 systemd[1]: Queued start job for default target Multi-User System. Oct 30 05:29:01.950378 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 30 05:29:03.833809 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 30 05:29:01.950691 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 30 05:29:01.950711 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 30 05:29:01.950846 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 30 05:29:01.950859 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 30 05:29:01.950890 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 30 05:29:01.950902 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 30 05:29:01.951103 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 30 05:29:01.951137 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 30 05:29:01.951151 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 30 05:29:01.951531 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 30 05:29:01.951589 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 30 05:29:01.951610 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 30 05:29:01.951625 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 30 05:29:01.951643 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 30 05:29:01.951657 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:01Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 30 05:29:03.656036 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:03.656296 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:03.656396 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:03.656523 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:03.656594 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 30 05:29:03.656653 /usr/lib/systemd/system-generators/torcx-generator[752]: time="2025-10-30T05:29:03Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 30 05:29:03.949875 systemd[1]: Started Journal Service. Oct 30 05:29:03.950358 systemd[1]: Mounted Huge Pages File System. Oct 30 05:29:03.951239 systemd[1]: Mounted POSIX Message Queue File System. Oct 30 05:29:03.952180 systemd[1]: Mounted External Media Directory. Oct 30 05:29:03.953039 systemd[1]: Mounted Kernel Debug File System. Oct 30 05:29:03.953864 systemd[1]: Mounted Kernel Trace File System. Oct 30 05:29:03.954691 systemd[1]: Mounted Temporary Directory /tmp. Oct 30 05:29:03.955766 systemd[1]: Finished Create missing system files. Oct 30 05:29:03.956853 systemd[1]: Finished Create List of Static Device Nodes. Oct 30 05:29:03.957904 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 30 05:29:03.958085 systemd[1]: Finished Load Kernel Module configfs. Oct 30 05:29:03.959064 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 05:29:03.959233 systemd[1]: Finished Load Kernel Module drm. Oct 30 05:29:03.960187 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 30 05:29:03.960366 systemd[1]: Finished Load Kernel Module fuse. Oct 30 05:29:03.961423 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 30 05:29:03.962648 systemd[1]: Finished Apply Kernel Variables. Oct 30 05:29:03.979459 systemd[1]: Mounting FUSE Control File System... Oct 30 05:29:03.981544 systemd[1]: Mounting Kernel Configuration File System... Oct 30 05:29:03.982471 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 30 05:29:03.983958 systemd[1]: Starting Rebuild Hardware Database... Oct 30 05:29:03.985731 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 30 05:29:03.986619 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 30 05:29:03.988932 systemd-journald[803]: Time spent on flushing to /var/log/journal/17691bb7b7564595812e4271af311cda is 9.300ms for 775 entries. Oct 30 05:29:03.988932 systemd-journald[803]: System Journal (/var/log/journal/17691bb7b7564595812e4271af311cda) is 8.0M, max 203.0M, 195.0M free. Oct 30 05:29:03.987758 systemd[1]: Starting Load/Save Random Seed... Oct 30 05:29:03.989570 systemd[1]: Starting Create System Users... Oct 30 05:29:03.991872 systemd[1]: Mounted FUSE Control File System. Oct 30 05:29:03.993184 systemd[1]: Mounted Kernel Configuration File System. Oct 30 05:29:03.994974 systemd[1]: Finished Load/Save Random Seed. Oct 30 05:29:03.995838 systemd[1]: Reached target First Boot Complete. Oct 30 05:29:04.002388 systemd-sysusers[815]: Creating group sgx with gid 999. Oct 30 05:29:04.003177 systemd-sysusers[815]: Creating group systemd-oom with gid 998. Oct 30 05:29:04.007901 systemd[1]: Finished Coldplug All udev Devices. Oct 30 05:29:04.009685 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 30 05:29:04.011055 systemd-sysusers[815]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 30 05:29:04.011878 systemd-sysusers[815]: Creating group systemd-timesync with gid 997. Oct 30 05:29:04.012756 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 30 05:29:04.014166 systemd-sysusers[815]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 30 05:29:04.014813 systemd-sysusers[815]: Creating group systemd-coredump with gid 996. Oct 30 05:29:04.015284 systemd-sysusers[815]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 30 05:29:04.023864 systemd[1]: Finished Create System Users. Oct 30 05:29:04.025522 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 30 05:29:04.038786 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 30 05:29:04.224263 systemd[1]: Finished Rebuild Hardware Database. Oct 30 05:29:04.231000 audit: BPF prog-id=30 op=LOAD Oct 30 05:29:04.232000 audit: BPF prog-id=31 op=LOAD Oct 30 05:29:04.234014 kernel: audit: type=1334 audit(1761802144.231:53): prog-id=30 op=LOAD Oct 30 05:29:04.234049 kernel: audit: type=1334 audit(1761802144.232:54): prog-id=31 op=LOAD Oct 30 05:29:04.234068 kernel: audit: type=1334 audit(1761802144.233:55): prog-id=32 op=LOAD Oct 30 05:29:04.233000 audit: BPF prog-id=32 op=LOAD Oct 30 05:29:04.234607 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 30 05:29:04.233000 audit: BPF prog-id=16 op=UNLOAD Oct 30 05:29:04.236496 kernel: audit: type=1334 audit(1761802144.233:56): prog-id=16 op=UNLOAD Oct 30 05:29:04.233000 audit: BPF prog-id=17 op=UNLOAD Oct 30 05:29:04.256561 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 30 05:29:04.262000 audit: BPF prog-id=33 op=LOAD Oct 30 05:29:04.263655 systemd[1]: Starting Network Configuration... Oct 30 05:29:04.286746 systemd-udevd[825]: Using default interface naming scheme 'v249'. Oct 30 05:29:04.297541 systemd-networkd[836]: lo: Link UP Oct 30 05:29:04.297567 systemd-networkd[836]: lo: Gained carrier Oct 30 05:29:04.298122 systemd-networkd[836]: Enumeration completed Oct 30 05:29:04.298230 systemd[1]: Started Network Configuration. Oct 30 05:29:04.299424 systemd-networkd[836]: eth0: Link UP Oct 30 05:29:04.302620 systemd-networkd[836]: eth0: Gained carrier Oct 30 05:29:04.307679 systemd-networkd[836]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 30 05:29:04.315836 udevadm[818]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Oct 30 05:29:04.348918 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 30 05:29:04.357721 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 30 05:29:04.366764 lvm[847]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 30 05:29:04.394026 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 30 05:29:04.394999 systemd[1]: Reached target Local Encrypted Volumes. Oct 30 05:29:04.412724 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 30 05:29:04.416021 lvm[848]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 30 05:29:04.449584 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 30 05:29:04.450849 systemd[1]: Reached target Preparation for Local File Systems. Oct 30 05:29:04.452030 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 30 05:29:04.452059 systemd[1]: Reached target Containers. Oct 30 05:29:04.470193 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 30 05:29:04.482066 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 30 05:29:04.484077 systemd[1]: Mounting /usr/share/oem... Oct 30 05:29:04.490042 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 30 05:29:04.490094 kernel: BTRFS info (device vda6): has skinny extents Oct 30 05:29:04.492257 systemd[1]: Mounted /usr/share/oem. Oct 30 05:29:04.493032 systemd[1]: Reached target Local File Systems. Oct 30 05:29:04.515599 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 30 05:29:04.517197 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 30 05:29:04.517246 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 30 05:29:04.518307 systemd[1]: Starting Commit a transient machine-id on disk... Oct 30 05:29:04.520413 systemd[1]: Starting Create Volatile Files and Directories... Oct 30 05:29:04.526294 systemd-tmpfiles[873]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 30 05:29:04.527346 systemd-tmpfiles[873]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 30 05:29:04.536886 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 30 05:29:04.537502 systemd[1]: Finished Commit a transient machine-id on disk. Oct 30 05:29:04.554157 systemd-tmpfiles[873]: Detected autofs mount point /boot during canonicalization of /boot. Oct 30 05:29:04.554167 systemd-tmpfiles[873]: Skipping /boot Oct 30 05:29:04.559251 systemd-tmpfiles[873]: Detected autofs mount point /boot during canonicalization of /boot. Oct 30 05:29:04.559269 systemd-tmpfiles[873]: Skipping /boot Oct 30 05:29:04.594087 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 30 05:29:04.595249 systemd[1]: Finished Create Volatile Files and Directories. Oct 30 05:29:04.602787 systemd[1]: Starting Load Security Auditing Rules... Oct 30 05:29:04.604739 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 30 05:29:04.606718 systemd[1]: Starting Rebuild Journal Catalog... Oct 30 05:29:04.607000 audit: BPF prog-id=34 op=LOAD Oct 30 05:29:04.608834 systemd[1]: Starting Network Name Resolution... Oct 30 05:29:04.609000 audit: BPF prog-id=35 op=LOAD Oct 30 05:29:04.610918 systemd[1]: Starting Network Time Synchronization... Oct 30 05:29:04.613351 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 30 05:29:04.615254 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 30 05:29:04.617981 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 30 05:29:04.618877 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 30 05:29:04.623949 systemd[1]: Finished Rebuild Journal Catalog. Oct 30 05:29:04.625811 systemd[1]: Starting Update is Completed... Oct 30 05:29:04.630363 systemd[1]: Finished Update is Completed. Oct 30 05:29:04.640000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 30 05:29:04.641515 augenrules[896]: No rules Oct 30 05:29:04.642447 systemd[1]: Finished Load Security Auditing Rules. Oct 30 05:29:04.653327 systemd-resolved[879]: Positive Trust Anchors: Oct 30 05:29:04.653346 systemd-resolved[879]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 05:29:04.653375 systemd-resolved[879]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 30 05:29:04.653429 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 30 05:29:04.654226 systemd-resolved[879]: Defaulting to hostname 'linux'. Oct 30 05:29:04.655734 systemd[1]: Started Network Time Synchronization. Oct 30 05:29:04.656687 systemd[1]: Started Network Name Resolution. Oct 30 05:29:04.657431 systemd[1]: Reached target Network. Oct 30 05:29:04.658123 systemd[1]: Reached target Host and Network Name Lookups. Oct 30 05:29:04.659002 systemd[1]: Reached target System Initialization. Oct 30 05:29:04.659866 systemd[1]: Started Watch for update engine configuration changes. Oct 30 05:29:04.660848 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 30 05:29:04.661853 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 30 05:29:04.662740 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 30 05:29:04.662768 systemd[1]: Reached target Path Units. Oct 30 05:29:04.663379 systemd[1]: Reached target System Time Set. Oct 30 05:29:04.664316 systemd[1]: Started Daily Log Rotation. Oct 30 05:29:04.665165 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 30 05:29:04.666187 systemd[1]: Reached target Timer Units. Oct 30 05:29:04.667229 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 30 05:29:04.676798 systemd[1]: Starting Docker Socket for the API... Oct 30 05:29:04.680545 systemd[1]: Listening on OpenSSH Server Socket. Oct 30 05:29:04.681738 systemd[1]: Listening on Docker Socket for the API. Oct 30 05:29:04.682617 systemd[1]: Reached target Socket Units. Oct 30 05:29:04.683306 systemd[1]: Reached target Basic System. Oct 30 05:29:04.684083 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 30 05:29:04.684112 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 30 05:29:04.685112 systemd[1]: Started D-Bus System Message Bus. Oct 30 05:29:04.688497 systemd[1]: Starting Extend Filesystems... Oct 30 05:29:04.689338 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 30 05:29:04.690371 systemd[1]: Starting Generate /run/flatcar/motd... Oct 30 05:29:04.692234 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 30 05:29:04.695532 systemd[1]: Starting Generate sshd host keys... Oct 30 05:29:04.696248 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 30 05:29:04.696282 systemd[1]: Reached target Load system-provided cloud configs. Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda1 Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda2 Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda3 Oct 30 05:29:04.697788 extend-filesystems[905]: Found usr Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda4 Oct 30 05:29:04.697788 extend-filesystems[905]: Found vda6 Oct 30 05:29:04.710266 extend-filesystems[905]: Found vda7 Oct 30 05:29:04.710266 extend-filesystems[905]: Found vda9 Oct 30 05:29:04.710266 extend-filesystems[905]: Checking size of /dev/vda9 Oct 30 05:29:04.699351 systemd[1]: Starting User Login Management... Oct 30 05:29:04.715481 extend-filesystems[905]: Old size kept for /dev/vda9 Oct 30 05:29:04.700587 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 30 05:29:04.701233 systemd[1]: Starting Update Engine... Oct 30 05:29:04.704929 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 30 05:29:04.704955 systemd[1]: Reached target Load user-provided cloud configs. Oct 30 05:29:04.706507 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 30 05:29:04.706791 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 30 05:29:04.711083 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 30 05:29:04.711313 systemd[1]: Finished Extend Filesystems. Oct 30 05:29:04.718934 systemd[1]: motdgen.service: Deactivated successfully. Oct 30 05:29:04.719208 systemd[1]: Finished Generate /run/flatcar/motd. Oct 30 05:29:04.729254 systemd-logind[916]: Watching system buttons on /dev/input/event0 (Power Button) Oct 30 05:29:04.729668 systemd-logind[916]: New seat seat0. Oct 30 05:29:04.734487 systemd[1]: Started User Login Management. Oct 30 05:29:04.741534 update_engine[918]: I1030 05:29:04.741159 918 main.cc:89] Flatcar Update Engine starting Oct 30 05:29:04.745037 systemd[1]: Started Update Engine. Oct 30 05:29:04.745578 update_engine[918]: I1030 05:29:04.745249 918 update_check_scheduler.cc:74] Next update check in 11m57s Oct 30 05:29:04.760883 systemd[1]: Started Cluster reboot manager. Oct 30 05:29:04.786710 locksmithd[929]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 30 05:29:05.162267 sshd_keygen[923]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 30 05:29:05.180960 systemd[1]: Finished Generate sshd host keys. Oct 30 05:29:05.197981 systemd[1]: Starting Generate /run/issue... Oct 30 05:29:05.202753 systemd[1]: issuegen.service: Deactivated successfully. Oct 30 05:29:05.202984 systemd[1]: Finished Generate /run/issue. Oct 30 05:29:05.205093 systemd[1]: Starting Permit User Sessions... Oct 30 05:29:05.209342 systemd[1]: Finished Permit User Sessions. Oct 30 05:29:05.211398 systemd[1]: Started Getty on tty1. Oct 30 05:29:05.213428 systemd[1]: Started Serial Getty on ttyAMA0. Oct 30 05:29:05.214473 systemd[1]: Reached target Login Prompts. Oct 30 05:29:05.215422 systemd[1]: Reached target Multi-User System. Oct 30 05:29:05.217444 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 30 05:29:05.222270 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 30 05:29:05.222492 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 30 05:29:05.223599 systemd[1]: Startup finished in 698ms (kernel) + 2.295s (initrd) + 3.366s (userspace) = 6.360s. Oct 30 05:29:05.761626 systemd-networkd[836]: eth0: Gained IPv6LL Oct 30 05:29:11.634811 systemd[1]: Created slice Slice /system/sshd. Oct 30 05:29:11.636012 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42414). Oct 30 05:29:11.670805 sshd[952]: Accepted publickey for core from 10.0.0.1 port 42414 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:11.672522 sshd[952]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:11.690245 systemd[1]: Created slice User Slice of UID 500. Oct 30 05:29:11.691162 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 30 05:29:11.692841 systemd-logind[916]: New session 1 of user core. Oct 30 05:29:11.696814 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 30 05:29:11.697909 systemd[1]: Starting User Manager for UID 500... Oct 30 05:29:11.699917 systemd[955]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:11.745821 systemd[955]: Queued start job for default target Main User Target. Oct 30 05:29:11.745961 systemd[955]: Reached target Paths. Oct 30 05:29:11.745975 systemd[955]: Reached target Sockets. Oct 30 05:29:11.745985 systemd[955]: Reached target Timers. Oct 30 05:29:11.745996 systemd[955]: Reached target Basic System. Oct 30 05:29:11.746034 systemd[955]: Reached target Main User Target. Oct 30 05:29:11.746045 systemd[955]: Startup finished in 42ms. Oct 30 05:29:11.746283 systemd[1]: Started User Manager for UID 500. Oct 30 05:29:11.762341 systemd[1]: Started Session 1 of User core. Oct 30 05:29:11.826911 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42426). Oct 30 05:29:11.862287 sshd[964]: Accepted publickey for core from 10.0.0.1 port 42426 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:11.863792 sshd[964]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:11.866375 systemd-logind[916]: New session 2 of user core. Oct 30 05:29:11.881939 systemd[1]: Started Session 2 of User core. Oct 30 05:29:11.935994 sshd[964]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:11.944250 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:42426.service: Deactivated successfully. Oct 30 05:29:11.944897 systemd[1]: session-2.scope: Deactivated successfully. Oct 30 05:29:11.945477 systemd-logind[916]: Session 2 logged out. Waiting for processes to exit. Oct 30 05:29:11.946406 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42438). Oct 30 05:29:11.947092 systemd-logind[916]: Removed session 2. Oct 30 05:29:11.976483 sshd[970]: Accepted publickey for core from 10.0.0.1 port 42438 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:11.977542 sshd[970]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:11.979911 systemd-logind[916]: New session 3 of user core. Oct 30 05:29:11.995278 systemd[1]: Started Session 3 of User core. Oct 30 05:29:12.045315 sshd[970]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:12.057141 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:42438.service: Deactivated successfully. Oct 30 05:29:12.057723 systemd[1]: session-3.scope: Deactivated successfully. Oct 30 05:29:12.058288 systemd-logind[916]: Session 3 logged out. Waiting for processes to exit. Oct 30 05:29:12.059157 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42452). Oct 30 05:29:12.059856 systemd-logind[916]: Removed session 3. Oct 30 05:29:12.089813 sshd[976]: Accepted publickey for core from 10.0.0.1 port 42452 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:12.090706 sshd[976]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:12.093179 systemd-logind[916]: New session 4 of user core. Oct 30 05:29:12.108843 systemd[1]: Started Session 4 of User core. Oct 30 05:29:12.162229 sshd[976]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:12.178348 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:42452.service: Deactivated successfully. Oct 30 05:29:12.178956 systemd[1]: session-4.scope: Deactivated successfully. Oct 30 05:29:12.179520 systemd-logind[916]: Session 4 logged out. Waiting for processes to exit. Oct 30 05:29:12.180433 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42456). Oct 30 05:29:12.181124 systemd-logind[916]: Removed session 4. Oct 30 05:29:12.211671 sshd[982]: Accepted publickey for core from 10.0.0.1 port 42456 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:12.213025 sshd[982]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:12.215455 systemd-logind[916]: New session 5 of user core. Oct 30 05:29:12.237436 systemd[1]: Started Session 5 of User core. Oct 30 05:29:12.295662 sudo[985]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 30 05:29:12.295863 sudo[985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:12.314913 sudo[985]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:12.317033 sshd[982]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:12.324935 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42458). Oct 30 05:29:12.326127 dbus-daemon[904]: [system] Reloaded configuration Oct 30 05:29:12.328809 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:42456.service: Deactivated successfully. Oct 30 05:29:12.329527 systemd[1]: session-5.scope: Deactivated successfully. Oct 30 05:29:12.330245 systemd-logind[916]: Session 5 logged out. Waiting for processes to exit. Oct 30 05:29:12.331030 systemd-logind[916]: Removed session 5. Oct 30 05:29:12.355297 sshd[988]: Accepted publickey for core from 10.0.0.1 port 42458 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:12.356137 sshd[988]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:12.358514 systemd-logind[916]: New session 6 of user core. Oct 30 05:29:12.373843 systemd[1]: Started Session 6 of User core. Oct 30 05:29:12.427652 sudo[993]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 30 05:29:12.427880 sudo[993]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:12.430086 sudo[993]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:12.433496 sudo[992]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 30 05:29:12.433928 sudo[992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:12.454856 systemd[1]: Stopping Load Security Auditing Rules... Oct 30 05:29:12.455000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 30 05:29:12.456282 auditctl[996]: No rules Oct 30 05:29:12.456550 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 05:29:12.456822 kernel: kauditd_printk_skb: 5 callbacks suppressed Oct 30 05:29:12.456859 kernel: audit: type=1305 audit(1761802152.455:62): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 30 05:29:12.456803 systemd[1]: Stopped Load Security Auditing Rules. Oct 30 05:29:12.458035 systemd[1]: Starting Load Security Auditing Rules... Oct 30 05:29:12.472055 augenrules[1013]: No rules Oct 30 05:29:12.472912 systemd[1]: Finished Load Security Auditing Rules. Oct 30 05:29:12.473661 sudo[992]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:12.474718 sshd[988]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:12.488140 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:42458.service: Deactivated successfully. Oct 30 05:29:12.488734 systemd[1]: session-6.scope: Deactivated successfully. Oct 30 05:29:12.489332 systemd-logind[916]: Session 6 logged out. Waiting for processes to exit. Oct 30 05:29:12.490234 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:42466). Oct 30 05:29:12.490897 systemd-logind[916]: Removed session 6. Oct 30 05:29:12.521223 sshd[1019]: Accepted publickey for core from 10.0.0.1 port 42466 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:12.522201 sshd[1019]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:12.524595 kernel: audit: type=1006 audit(1761802152.521:63): pid=1019 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 30 05:29:12.526188 systemd-logind[916]: New session 7 of user core. Oct 30 05:29:12.542817 systemd[1]: Started Session 7 of User core. Oct 30 05:29:12.594557 sudo[1022]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/bash -c cat >/etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem </etc/coreos/update.conf.new </etc/coreos/update-payload-key.pub.pem < Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: Oct 30 05:29:15.347378 update_engine[1270]: I1030 05:29:15.347375 1270 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 30 05:29:15.349618 update_engine[1270]: I1030 05:29:15.349586 1270 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 30 05:29:15.349838 update_engine[1270]: I1030 05:29:15.349814 1270 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 30 05:29:15.351394 systemd-logind[916]: Removed session 24. Oct 30 05:29:15.353544 update_engine[1270]: I1030 05:29:15.353513 1270 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 30 05:29:15.353658 update_engine[1270]: I1030 05:29:15.353626 1270 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 30 05:29:15.353658 update_engine[1270]: I1030 05:29:15.353636 1270 omaha_request_action.cc:619] Omaha request response: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.353658 update_engine[1270]: Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358685 1270 omaha_request_action.cc:447] Omaha Response manifest version = Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358748 1270 omaha_request_action.cc:470] Found 1 url(s) Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358868 1270 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358896 1270 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358922 1270 omaha_request_action.cc:529] Url0: http://10.0.0.2:34567/packages/update.gz Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358930 1270 omaha_request_action.cc:541] Payload size = 439617737 bytes Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358943 1270 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358954 1270 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.358958 1270 payload_state.cc:360] Current Response Signature = Oct 30 05:29:15.360305 update_engine[1270]: NumURLs = 1 Oct 30 05:29:15.360305 update_engine[1270]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 30 05:29:15.360305 update_engine[1270]: Payload Size = 439617737 Oct 30 05:29:15.360305 update_engine[1270]: Payload Sha256 Hash = hKuDazTd3yRvZZ+1GzVZ5iDGEt/oXMhd+zKPhlhnEaA= Oct 30 05:29:15.360305 update_engine[1270]: Is Delta Payload = 0 Oct 30 05:29:15.360305 update_engine[1270]: Max Failure Count Per Url = 10 Oct 30 05:29:15.360305 update_engine[1270]: Disable Payload Backoff = 1 Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359155 1270 payload_state.cc:381] Payload Attempt Number = 0 Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359321 1270 payload_state.cc:404] Current URL Index = 0 Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359400 1270 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359503 1270 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359509 1270 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359699 1270 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359722 1270 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359727 1270 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 30 05:29:15.360305 update_engine[1270]: I1030 05:29:15.359778 1270 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 30 05:29:15.360813 update_engine[1270]: E1030 05:29:15.359829 1270 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 30 05:29:15.360813 update_engine[1270]: I1030 05:29:15.360733 1270 omaha_response_handler_action.cc:85] Using this install plan: Oct 30 05:29:15.360813 update_engine[1270]: I1030 05:29:15.360746 1270 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.2:34567/packages/update.gz, payload size: 439617737, payload hash: hKuDazTd3yRvZZ+1GzVZ5iDGEt/oXMhd+zKPhlhnEaA=, partition_path: /dev/vda4, kernel_path: /boot/flatcar/vmlinuz-b, pcr_policy_path: /var/lib/update_engine/pcrs-b.zip, old_partition_path: /dev/vda3, old_kernel_path: /boot/flatcar/vmlinuz-a Oct 30 05:29:15.360879 update_engine[1270]: I1030 05:29:15.360826 1270 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 30 05:29:15.362857 update_engine[1270]: I1030 05:29:15.362818 1270 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 30 05:29:15.375043 sshd[1291]: Accepted publickey for core from 10.0.0.1 port 42592 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:15.376111 sshd[1291]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:15.379886 systemd-logind[916]: New session 25 of user core. Oct 30 05:29:15.386727 systemd[1]: Started Session 25 of User core. Oct 30 05:29:15.456726 sshd[1291]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:15.459453 systemd[1]: sshd@24-10.0.0.3:22-10.0.0.1:42592.service: Deactivated successfully. Oct 30 05:29:15.460114 systemd[1]: session-25.scope: Deactivated successfully. Oct 30 05:29:15.460736 systemd-logind[916]: Session 25 logged out. Waiting for processes to exit. Oct 30 05:29:15.461406 systemd-logind[916]: Removed session 25. Oct 30 05:29:17.450744 update_engine[1270]: I1030 05:29:17.450709 1270 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 30 05:29:17.451171 update_engine[1270]: I1030 05:29:17.451155 1270 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 30 05:29:17.522660 update_engine[1270]: E1030 05:29:17.522599 1270 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 30 05:29:17.522660 update_engine[1270]: I1030 05:29:17.522643 1270 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 30 05:29:17.522660 update_engine[1270]: I1030 05:29:17.522650 1270 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 30 05:29:17.522660 update_engine[1270]: I1030 05:29:17.522653 1270 update_attempter.cc:302] Processing Done. Oct 30 05:29:17.522929 update_engine[1270]: E1030 05:29:17.522706 1270 update_attempter.cc:615] Update failed. Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522711 1270 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522715 1270 payload_state.cc:276] Incrementing the URL failure count Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522718 1270 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522892 1270 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522913 1270 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.2:34567/v1/update Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522917 1270 omaha_request_action.cc:269] Request: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: Oct 30 05:29:17.522929 update_engine[1270]: I1030 05:29:17.522934 1270 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 30 05:29:17.523230 update_engine[1270]: I1030 05:29:17.522990 1270 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 30 05:29:17.523230 update_engine[1270]: I1030 05:29:17.523089 1270 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 30 05:29:17.656182 update_engine[1270]: I1030 05:29:17.656137 1270 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656222 1270 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656229 1270 omaha_request_action.cc:619] Omaha request response: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: Oct 30 05:29:17.656324 update_engine[1270]: E1030 05:29:17.656243 1270 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656248 1270 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656251 1270 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656255 1270 update_attempter.cc:302] Processing Done. Oct 30 05:29:17.656324 update_engine[1270]: I1030 05:29:17.656260 1270 update_attempter.cc:306] Error event sent. Oct 30 05:29:25.524772 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45578). Oct 30 05:29:25.560373 sshd[1308]: Accepted publickey for core from 10.0.0.1 port 45578 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:25.562711 sshd[1308]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:25.564376 kernel: kauditd_printk_skb: 287 callbacks suppressed Oct 30 05:29:25.564439 kernel: audit: type=1006 audit(1761802165.560:359): pid=1308 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=26 res=1 Oct 30 05:29:25.565612 systemd-logind[916]: New session 26 of user core. Oct 30 05:29:25.578279 systemd[1]: Started Session 26 of User core. Oct 30 05:29:25.636250 sshd[1308]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:25.648677 systemd[1]: sshd@25-10.0.0.3:22-10.0.0.1:45578.service: Deactivated successfully. Oct 30 05:29:25.649202 systemd[1]: session-26.scope: Deactivated successfully. Oct 30 05:29:25.649787 systemd-logind[916]: Session 26 logged out. Waiting for processes to exit. Oct 30 05:29:25.650763 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45582). Oct 30 05:29:25.651425 systemd-logind[916]: Removed session 26. Oct 30 05:29:25.680731 sshd[1315]: Accepted publickey for core from 10.0.0.1 port 45582 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:25.681834 sshd[1315]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:25.683595 kernel: audit: type=1006 audit(1761802165.680:360): pid=1315 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=27 res=1 Oct 30 05:29:25.684256 systemd-logind[916]: New session 27 of user core. Oct 30 05:29:25.694257 systemd[1]: Started Session 27 of User core. Oct 30 05:29:25.750742 sshd[1315]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:25.758918 systemd[1]: sshd@26-10.0.0.3:22-10.0.0.1:45582.service: Deactivated successfully. Oct 30 05:29:25.759404 systemd[1]: session-27.scope: Deactivated successfully. Oct 30 05:29:25.759976 systemd-logind[916]: Session 27 logged out. Waiting for processes to exit. Oct 30 05:29:25.760895 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45584). Oct 30 05:29:25.761548 systemd-logind[916]: Removed session 27. Oct 30 05:29:25.791367 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 45584 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:25.792148 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:25.794581 kernel: audit: type=1006 audit(1761802165.791:361): pid=1323 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=28 res=1 Oct 30 05:29:25.794623 systemd-logind[916]: New session 28 of user core. Oct 30 05:29:25.812212 systemd[1]: Started Session 28 of User core. Oct 30 05:29:25.862789 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:25.871972 systemd[1]: sshd@27-10.0.0.3:22-10.0.0.1:45584.service: Deactivated successfully. Oct 30 05:29:25.872453 systemd[1]: session-28.scope: Deactivated successfully. Oct 30 05:29:25.873018 systemd-logind[916]: Session 28 logged out. Waiting for processes to exit. Oct 30 05:29:25.873931 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45592). Oct 30 05:29:25.874597 systemd-logind[916]: Removed session 28. Oct 30 05:29:25.904098 sshd[1329]: Accepted publickey for core from 10.0.0.1 port 45592 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:25.905062 sshd[1329]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:25.906587 kernel: audit: type=1006 audit(1761802165.904:362): pid=1329 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=29 res=1 Oct 30 05:29:25.907478 systemd-logind[916]: New session 29 of user core. Oct 30 05:29:25.919841 systemd[1]: Started Session 29 of User core. Oct 30 05:29:25.971910 sshd[1329]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:25.980359 systemd[1]: sshd@28-10.0.0.3:22-10.0.0.1:45592.service: Deactivated successfully. Oct 30 05:29:25.980982 systemd[1]: session-29.scope: Deactivated successfully. Oct 30 05:29:25.981656 systemd-logind[916]: Session 29 logged out. Waiting for processes to exit. Oct 30 05:29:25.982852 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:45600). Oct 30 05:29:25.983582 systemd-logind[916]: Removed session 29. Oct 30 05:29:26.014726 sshd[1335]: Accepted publickey for core from 10.0.0.1 port 45600 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:26.017479 sshd[1335]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:26.017642 kernel: audit: type=1006 audit(1761802166.013:363): pid=1335 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=30 res=1 Oct 30 05:29:26.019872 systemd-logind[916]: New session 30 of user core. Oct 30 05:29:26.034088 systemd[1]: Started Session 30 of User core. Oct 30 05:29:26.087364 sudo[1339]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 30 05:29:26.088134 sudo[1339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:26.093683 systemd[1]: sshd.socket: Deactivated successfully. Oct 30 05:29:26.094018 systemd[1]: Closed OpenSSH Server Socket. Oct 30 05:29:26.095058 sudo[1339]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:26.098501 sudo[1338]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 30 05:29:26.098719 sudo[1338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:26.122966 systemd[1]: Stopping Session 1 of User core... Oct 30 05:29:26.123668 sshd[952]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:26.123893 systemd[1]: Stopping Session 30 of User core... Oct 30 05:29:26.124454 sshd[1335]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:26.124887 systemd[1]: Removed slice Slice /system/addon-config. Oct 30 05:29:26.124530 sudo[1338]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:26.126005 systemd[1]: Removed slice Slice /system/addon-run. Oct 30 05:29:26.127182 systemd[1]: Removed slice Slice /system/modprobe. Oct 30 05:29:26.128296 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 30 05:29:26.129308 systemd[1]: Stopped target Multi-User System. Oct 30 05:29:26.130140 systemd[1]: Stopped target Login Prompts. Oct 30 05:29:26.130888 systemd[1]: Stopped target Containers. Oct 30 05:29:26.131605 systemd[1]: Stopped target Host and Network Name Lookups. Oct 30 05:29:26.132471 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 30 05:29:26.133408 systemd[1]: Stopped target Timer Units. Oct 30 05:29:26.134143 systemd[1]: logrotate.timer: Deactivated successfully. Oct 30 05:29:26.134196 systemd[1]: Stopped Daily Log Rotation. Oct 30 05:29:26.134909 systemd[1]: mdadm.timer: Deactivated successfully. Oct 30 05:29:26.134948 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 30 05:29:26.135948 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 30 05:29:26.135990 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 30 05:29:26.136913 systemd[1]: Stopped target System Time Set. Oct 30 05:29:26.137669 systemd[1]: Stopped target Load user-provided cloud configs. Oct 30 05:29:26.138587 systemd[1]: Stopped target Load system-provided cloud configs. Oct 30 05:29:26.139511 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 30 05:29:26.139624 systemd[1]: Closed Process Core Dump Socket. Oct 30 05:29:26.152132 systemd[1]: Unmounting Boot partition... Oct 30 05:29:26.153391 systemd[1]: Stopping Getty on tty1... Oct 30 05:29:26.154259 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 30 05:29:26.155191 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 30 05:29:26.156111 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:45600)... Oct 30 05:29:26.156986 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 30 05:29:26.157126 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 30 05:29:26.158206 systemd[1]: Stopped target First Boot Complete. Oct 30 05:29:26.159968 systemd[1]: Stopping Load/Save Random Seed... Oct 30 05:29:26.160919 systemd[1]: Stopping Update Engine... Oct 30 05:29:26.164002 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 30 05:29:26.164212 systemd[1]: Stopped Getty on tty1. Oct 30 05:29:26.165383 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 30 05:29:26.165589 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 30 05:29:26.166814 systemd[1]: sshd@0-10.0.0.3:22-10.0.0.1:42414.service: Deactivated successfully. Oct 30 05:29:26.167006 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 30 05:29:26.168350 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 30 05:29:26.168420 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 30 05:29:26.168688 systemd[1]: Stopped Update Engine. Oct 30 05:29:26.169380 systemd[1]: update-engine.service: Consumed 2.419s CPU time. Oct 30 05:29:26.169775 systemd[1]: sshd@29-10.0.0.3:22-10.0.0.1:45600.service: Deactivated successfully. Oct 30 05:29:26.169953 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:45600). Oct 30 05:29:26.181327 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 30 05:29:26.181538 systemd[1]: Stopped Load/Save Random Seed. Oct 30 05:29:26.182524 systemd[1]: session-1.scope: Deactivated successfully. Oct 30 05:29:26.182774 systemd[1]: Stopped Session 1 of User core. Oct 30 05:29:26.183705 systemd[1]: session-30.scope: Deactivated successfully. Oct 30 05:29:26.183930 systemd[1]: Stopped Session 30 of User core. Oct 30 05:29:26.185853 systemd-logind[916]: Removed session 1. Oct 30 05:29:26.186074 systemd[1]: Removed slice Slice /system/getty. Oct 30 05:29:26.187196 systemd[1]: Removed slice Slice /system/serial-getty. Oct 30 05:29:26.188371 systemd[1]: Removed slice Slice /system/sshd. Oct 30 05:29:26.189263 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 30 05:29:26.189371 systemd[1]: Stopped Generate sshd host keys. Oct 30 05:29:26.190388 systemd[1]: Stopping User Login Management... Oct 30 05:29:26.191162 systemd[1]: Stopping User Manager for UID 500... Oct 30 05:29:26.192052 systemd[955]: Stopped target Main User Target. Oct 30 05:29:26.192066 systemd[955]: Stopped target Basic System. Oct 30 05:29:26.192075 systemd[955]: Stopped target Paths. Oct 30 05:29:26.192083 systemd[955]: Stopped target Sockets. Oct 30 05:29:26.192099 systemd[955]: Reached target Shutdown. Oct 30 05:29:26.192106 systemd[955]: Stopped target Timers. Oct 30 05:29:26.192236 systemd[955]: Finished Exit the Session. Oct 30 05:29:26.192262 systemd[955]: Reached target Exit the Session. Oct 30 05:29:26.192485 systemd[1]: boot.mount: Deactivated successfully. Oct 30 05:29:26.192757 systemd[1]: Unmounted Boot partition. Oct 30 05:29:26.193924 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 30 05:29:26.194021 systemd-logind[916]: Session 30 logged out. Waiting for processes to exit. Oct 30 05:29:26.194058 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 30 05:29:26.196138 systemd[1]: user@500.service: Deactivated successfully. Oct 30 05:29:26.196432 systemd[1]: Stopped User Manager for UID 500. Oct 30 05:29:26.198356 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 30 05:29:26.203513 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 30 05:29:26.203598 systemd[1]: Unmounted /run/user/500. Oct 30 05:29:26.204871 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 30 05:29:26.205083 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 30 05:29:26.206506 systemd[1]: Removed slice User Slice of UID 500. Oct 30 05:29:26.208156 systemd[1]: Stopping D-Bus System Message Bus... Oct 30 05:29:26.209751 systemd[1]: Stopping Permit User Sessions... Oct 30 05:29:26.210984 systemd[1]: dbus.service: Deactivated successfully. Oct 30 05:29:26.211251 systemd[1]: Stopped D-Bus System Message Bus. Oct 30 05:29:26.212364 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 30 05:29:26.212622 systemd[1]: Stopped User Login Management. Oct 30 05:29:26.213983 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 30 05:29:26.214191 systemd[1]: Stopped Permit User Sessions. Oct 30 05:29:26.215115 systemd[1]: Stopped target Basic System. Oct 30 05:29:26.215861 systemd[1]: Stopped target Network. Oct 30 05:29:26.216483 systemd[1]: Stopped target Path Units. Oct 30 05:29:26.217189 systemd[1]: motdgen.path: Deactivated successfully. Oct 30 05:29:26.224000 audit: BPF prog-id=54 op=UNLOAD Oct 30 05:29:26.224982 systemd[1]: Stopped Watch for update engine configuration changes. Oct 30 05:29:26.226575 kernel: audit: type=1334 audit(1761802166.224:364): prog-id=54 op=UNLOAD Oct 30 05:29:26.226916 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 30 05:29:26.231065 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 30 05:29:26.232550 systemd[1]: Stopped target Remote File Systems. Oct 30 05:29:26.233350 systemd[1]: Stopped target Slice Units. Oct 30 05:29:26.234365 systemd[1]: Removed slice User and Session Slice. Oct 30 05:29:26.235252 systemd[1]: Stopped target Socket Units. Oct 30 05:29:26.236013 systemd[1]: dbus.socket: Deactivated successfully. Oct 30 05:29:26.236076 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 30 05:29:26.236997 systemd[1]: docker.socket: Deactivated successfully. Oct 30 05:29:26.237228 systemd[1]: Closed Docker Socket for the API. Oct 30 05:29:26.238030 systemd[1]: Stopped target System Initialization. Oct 30 05:29:26.238823 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 30 05:29:26.238948 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 30 05:29:26.239983 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 30 05:29:26.254300 systemd[1]: Stopping Load Security Auditing Rules... Oct 30 05:29:26.255300 systemd[1]: Stopping Network Name Resolution... Oct 30 05:29:26.256169 systemd[1]: Stopping Network Time Synchronization... Oct 30 05:29:26.256941 auditctl[1353]: No rules Oct 30 05:29:26.256998 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 30 05:29:26.257073 systemd[1]: Stopped Update is Completed. Oct 30 05:29:26.257809 systemd[1]: ldconfig.service: Deactivated successfully. Oct 30 05:29:26.257869 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 30 05:29:26.258720 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 30 05:29:26.258776 systemd[1]: Stopped Rebuild Hardware Database. Oct 30 05:29:26.259518 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 30 05:29:26.259596 systemd[1]: Stopped Rebuild Journal Catalog. Oct 30 05:29:26.261119 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 30 05:29:26.263467 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 30 05:29:26.263714 systemd[1]: Stopped Network Name Resolution. Oct 30 05:29:26.264716 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 30 05:29:26.264943 systemd[1]: Stopped Network Time Synchronization. Oct 30 05:29:26.266055 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 05:29:26.266256 systemd[1]: Stopped Load Security Auditing Rules. Oct 30 05:29:26.268532 systemd[1]: Stopping Network Configuration... Oct 30 05:29:26.269454 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 30 05:29:26.269000 audit: BPF prog-id=64 op=UNLOAD Oct 30 05:29:26.269905 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 30 05:29:26.269000 audit: BPF prog-id=57 op=UNLOAD Oct 30 05:29:26.270579 kernel: audit: type=1334 audit(1761802166.269:365): prog-id=64 op=UNLOAD Oct 30 05:29:26.270612 kernel: audit: type=1334 audit(1761802166.269:366): prog-id=57 op=UNLOAD Oct 30 05:29:26.272740 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 30 05:29:26.272842 systemd[1]: Stopped Create Volatile Files and Directories. Oct 30 05:29:26.273736 systemd[1]: Stopped target Local File Systems. Oct 30 05:29:26.275276 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 30 05:29:26.276738 systemd[1]: Unmounting External Media Directory... Oct 30 05:29:26.276878 systemd-networkd[836]: eth0: DHCPv6 lease lost Oct 30 05:29:26.278355 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 30 05:29:26.280188 systemd[1]: Unmounting /run/torcx/unpack... Oct 30 05:29:26.281482 systemd[1]: Unmounting Temporary Directory /tmp... Oct 30 05:29:26.282825 systemd[1]: Unmounting /usr/share/oem... Oct 30 05:29:26.284291 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 30 05:29:26.287044 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 30 05:29:26.287291 systemd[1]: Stopped Network Configuration. Oct 30 05:29:26.288285 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 30 05:29:26.288519 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 30 05:29:26.289828 systemd[1]: media.mount: Deactivated successfully. Oct 30 05:29:26.290077 systemd[1]: Unmounted External Media Directory. Oct 30 05:29:26.291085 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 30 05:29:26.291306 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 30 05:29:26.292646 systemd[1]: tmp.mount: Deactivated successfully. Oct 30 05:29:26.292885 systemd[1]: Unmounted Temporary Directory /tmp. Oct 30 05:29:26.293799 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 30 05:29:26.294040 systemd[1]: Unmounted /usr/share/oem. Oct 30 05:29:26.294976 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 30 05:29:26.295194 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 30 05:29:26.295000 audit: BPF prog-id=53 op=UNLOAD Oct 30 05:29:26.296631 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 30 05:29:26.296673 systemd[1]: Closed Network Service Netlink Socket. Oct 30 05:29:26.297589 kernel: audit: type=1334 audit(1761802166.295:367): prog-id=53 op=UNLOAD Oct 30 05:29:26.298381 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 30 05:29:26.298459 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 30 05:29:26.299727 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 30 05:29:26.300546 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 05:29:26.300626 systemd[1]: Stopped Apply Kernel Variables. Oct 30 05:29:26.301508 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 30 05:29:26.301763 systemd[1]: Unmounted /run/torcx/unpack. Oct 30 05:29:26.302821 systemd[1]: Stopped target Preparation for Local File Systems. Oct 30 05:29:26.303780 systemd[1]: Stopped target Swaps. Oct 30 05:29:26.304391 systemd[1]: Reached target Unmount All Filesystems. Oct 30 05:29:26.305250 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 30 05:29:26.305325 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 30 05:29:26.306232 systemd[1]: Stopped target Local Encrypted Volumes. Oct 30 05:29:26.307222 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 05:29:26.307294 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:26.308338 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 30 05:29:26.316201 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 30 05:29:26.317541 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 30 05:29:26.317632 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 30 05:29:26.318506 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 05:29:26.318583 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 30 05:29:26.319441 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 30 05:29:26.319512 systemd[1]: Stopped Create System Users. Oct 30 05:29:26.320305 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 30 05:29:26.320359 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 30 05:29:26.321367 systemd[1]: Reached target System Shutdown. Oct 30 05:29:26.322147 systemd[1]: Reached target Late Shutdown Services. Oct 30 05:29:26.323018 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 30 05:29:26.323054 systemd[1]: Finished System Reboot. Oct 30 05:29:26.323736 systemd[1]: Reached target System Reboot. Oct 30 05:29:26.324402 systemd[1]: Shutting down. Oct 30 05:29:26.326000 audit: BPF prog-id=56 op=UNLOAD Oct 30 05:29:26.326000 audit: BPF prog-id=55 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=60 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=59 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=58 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=63 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=62 op=UNLOAD Oct 30 05:29:26.327000 audit: BPF prog-id=61 op=UNLOAD Oct 30 05:29:26.328573 kernel: audit: type=1334 audit(1761802166.326:368): prog-id=56 op=UNLOAD Oct 30 05:29:26.372597 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 30 05:29:26.378605 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 30 05:29:26.378684 systemd-journald[803]: Journal stopped -- Reboot -- Oct 30 05:29:33.671164 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 30 05:29:33.671180 kernel: Linux version 5.10.107-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo 9.3.0-r1 p3) 9.3.0, GNU ld (Gentoo 2.36.1 p5) 2.36.1) #1 SMP PREEMPT Tue Mar 22 19:39:09 -00 2022 Oct 30 05:29:33.671189 kernel: efi: EFI v2.70 by EDK II Oct 30 05:29:33.671195 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 30 05:29:33.671200 kernel: efi: seeding entropy pool Oct 30 05:29:33.671206 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 30 05:29:33.671213 kernel: ACPI: Early table checksum verification disabled Oct 30 05:29:33.671218 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 30 05:29:33.671223 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 30 05:29:33.671228 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671234 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671240 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671245 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671250 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671256 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671262 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671268 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671274 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 30 05:29:33.671279 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 30 05:29:33.671284 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 30 05:29:33.671290 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:29:33.671295 kernel: NUMA: NODE_DATA [mem 0xdc959900-0xdc95efff] Oct 30 05:29:33.671300 kernel: Zone ranges: Oct 30 05:29:33.671306 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:29:33.671311 kernel: DMA32 empty Oct 30 05:29:33.671316 kernel: Normal empty Oct 30 05:29:33.671321 kernel: Movable zone start for each node Oct 30 05:29:33.671328 kernel: Early memory node ranges Oct 30 05:29:33.671333 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 30 05:29:33.671339 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 30 05:29:33.671344 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 30 05:29:33.671349 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 30 05:29:33.671354 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 30 05:29:33.671360 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 30 05:29:33.671365 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 30 05:29:33.671370 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 30 05:29:33.671376 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 30 05:29:33.671381 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 30 05:29:33.671386 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 30 05:29:33.671392 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 30 05:29:33.671400 kernel: On node 0 totalpages: 643072 Oct 30 05:29:33.671406 kernel: DMA zone: 10048 pages used for memmap Oct 30 05:29:33.671412 kernel: DMA zone: 0 pages reserved Oct 30 05:29:33.671417 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 30 05:29:33.671423 kernel: psci: probing for conduit method from ACPI. Oct 30 05:29:33.671429 kernel: psci: PSCIv1.1 detected in firmware. Oct 30 05:29:33.671434 kernel: psci: Using standard PSCI v0.2 function IDs Oct 30 05:29:33.671440 kernel: psci: Trusted OS migration not required Oct 30 05:29:33.671446 kernel: psci: SMC Calling Convention v1.1 Oct 30 05:29:33.671452 kernel: ACPI: SRAT not present Oct 30 05:29:33.671458 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 30 05:29:33.671463 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 30 05:29:33.671469 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 30 05:29:33.671474 kernel: Detected PIPT I-cache on CPU0 Oct 30 05:29:33.671480 kernel: CPU features: detected: GIC system register CPU interface Oct 30 05:29:33.671486 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 30 05:29:33.671491 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 30 05:29:33.671497 kernel: CPU features: detected: Hardware dirty bit management Oct 30 05:29:33.671503 kernel: CPU features: detected: Spectre-v4 Oct 30 05:29:33.671509 kernel: CPU features: detected: ARM erratum 1418040 Oct 30 05:29:33.671515 kernel: CPU features: detected: Spectre-BHB Oct 30 05:29:33.671521 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 30 05:29:33.671526 kernel: Policy zone: DMA Oct 30 05:29:33.671533 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 30 05:29:33.671539 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 30 05:29:33.671545 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 30 05:29:33.671551 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 30 05:29:33.671556 kernel: Memory: 2452096K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120192K reserved, 0K cma-reserved) Oct 30 05:29:33.671562 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 30 05:29:33.671568 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 30 05:29:33.671575 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 30 05:29:33.671581 kernel: rcu: RCU event tracing is enabled. Oct 30 05:29:33.671587 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 30 05:29:33.671593 kernel: Trampoline variant of Tasks RCU enabled. Oct 30 05:29:33.671598 kernel: Tracing variant of Tasks RCU enabled. Oct 30 05:29:33.671604 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 30 05:29:33.671610 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 30 05:29:33.671615 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 30 05:29:33.671621 kernel: GICv3: 256 SPIs implemented Oct 30 05:29:33.671627 kernel: GICv3: 0 Extended SPIs implemented Oct 30 05:29:33.671632 kernel: GICv3: Distributor has no Range Selector support Oct 30 05:29:33.671638 kernel: GICv3: 16 PPIs implemented Oct 30 05:29:33.671645 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 30 05:29:33.671650 kernel: ACPI: SRAT not present Oct 30 05:29:33.671656 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 30 05:29:33.671661 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 30 05:29:33.671667 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 30 05:29:33.671673 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 30 05:29:33.671679 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 30 05:29:33.671685 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:29:33.671690 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 30 05:29:33.671696 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 30 05:29:33.671702 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 30 05:29:33.671708 kernel: arm-pv: using stolen time PV Oct 30 05:29:33.671715 kernel: Console: colour dummy device 80x25 Oct 30 05:29:33.671721 kernel: ACPI: Core revision 20200925 Oct 30 05:29:33.671727 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 30 05:29:33.671733 kernel: pid_max: default: 32768 minimum: 301 Oct 30 05:29:33.671739 kernel: LSM: Security Framework initializing Oct 30 05:29:33.671745 kernel: SELinux: Initializing. Oct 30 05:29:33.671751 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 05:29:33.671757 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 30 05:29:33.671763 kernel: rcu: Hierarchical SRCU implementation. Oct 30 05:29:33.671770 kernel: Platform MSI: ITS@0x8080000 domain created Oct 30 05:29:33.671775 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 30 05:29:33.671781 kernel: Remapping and enabling EFI services. Oct 30 05:29:33.671787 kernel: smp: Bringing up secondary CPUs ... Oct 30 05:29:33.671793 kernel: Detected PIPT I-cache on CPU1 Oct 30 05:29:33.671799 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 30 05:29:33.671805 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 30 05:29:33.671811 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:29:33.671817 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 30 05:29:33.671823 kernel: Detected PIPT I-cache on CPU2 Oct 30 05:29:33.671830 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 30 05:29:33.671836 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 30 05:29:33.671842 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:29:33.671847 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 30 05:29:33.671855 kernel: Detected PIPT I-cache on CPU3 Oct 30 05:29:33.671865 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 30 05:29:33.671871 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 30 05:29:33.671879 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 30 05:29:33.671885 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 30 05:29:33.671928 kernel: smp: Brought up 1 node, 4 CPUs Oct 30 05:29:33.671937 kernel: SMP: Total of 4 processors activated. Oct 30 05:29:33.671944 kernel: CPU features: detected: Privileged Access Never Oct 30 05:29:33.671950 kernel: CPU features: detected: LSE atomic instructions Oct 30 05:29:33.671956 kernel: CPU features: detected: User Access Override Oct 30 05:29:33.671963 kernel: CPU features: detected: 32-bit EL0 Support Oct 30 05:29:33.671985 kernel: CPU features: detected: Common not Private translations Oct 30 05:29:33.671993 kernel: CPU features: detected: RAS Extension Support Oct 30 05:29:33.672001 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 30 05:29:33.672007 kernel: CPU features: detected: CRC32 instructions Oct 30 05:29:33.672026 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 30 05:29:33.672033 kernel: CPU: All CPU(s) started at EL1 Oct 30 05:29:33.672039 kernel: alternatives: patching kernel code Oct 30 05:29:33.672050 kernel: devtmpfs: initialized Oct 30 05:29:33.672056 kernel: KASLR enabled Oct 30 05:29:33.672063 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 30 05:29:33.672070 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 30 05:29:33.672076 kernel: pinctrl core: initialized pinctrl subsystem Oct 30 05:29:33.672082 kernel: SMBIOS 3.0.0 present. Oct 30 05:29:33.672103 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 30 05:29:33.672109 kernel: NET: Registered protocol family 16 Oct 30 05:29:33.672116 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 30 05:29:33.672125 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 30 05:29:33.672135 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 30 05:29:33.672142 kernel: audit: initializing netlink subsys (disabled) Oct 30 05:29:33.672148 kernel: audit: type=2000 audit(0.029:1): state=initialized audit_enabled=0 res=1 Oct 30 05:29:33.672155 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 30 05:29:33.672161 kernel: cpuidle: using governor menu Oct 30 05:29:33.672169 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 30 05:29:33.672175 kernel: ASID allocator initialised with 32768 entries Oct 30 05:29:33.672186 kernel: ACPI: bus type PCI registered Oct 30 05:29:33.672192 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 30 05:29:33.672203 kernel: Serial: AMBA PL011 UART driver Oct 30 05:29:33.672209 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 30 05:29:33.672215 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 30 05:29:33.672221 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 30 05:29:33.672228 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 30 05:29:33.672234 kernel: cryptd: max_cpu_qlen set to 1000 Oct 30 05:29:33.672240 kernel: ACPI: Added _OSI(Module Device) Oct 30 05:29:33.672246 kernel: ACPI: Added _OSI(Processor Device) Oct 30 05:29:33.672252 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 30 05:29:33.672260 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 30 05:29:33.672266 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 30 05:29:33.672272 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 30 05:29:33.672279 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 30 05:29:33.672285 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 30 05:29:33.672291 kernel: ACPI: Interpreter enabled Oct 30 05:29:33.672297 kernel: ACPI: Using GIC for interrupt routing Oct 30 05:29:33.672303 kernel: ACPI: MCFG table detected, 1 entries Oct 30 05:29:33.672310 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 30 05:29:33.672316 kernel: printk: console [ttyAMA0] enabled Oct 30 05:29:33.672327 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 30 05:29:33.672428 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 30 05:29:33.672484 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 30 05:29:33.672536 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 30 05:29:33.672587 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 30 05:29:33.672639 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 30 05:29:33.672647 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 30 05:29:33.672656 kernel: PCI host bridge to bus 0000:00 Oct 30 05:29:33.672713 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 30 05:29:33.672761 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 30 05:29:33.672808 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 30 05:29:33.672854 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 30 05:29:33.672929 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 30 05:29:33.672998 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 30 05:29:33.673080 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 30 05:29:33.673137 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 30 05:29:33.673192 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 30 05:29:33.673246 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 30 05:29:33.673300 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 30 05:29:33.673354 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 30 05:29:33.673402 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 30 05:29:33.673453 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 30 05:29:33.673501 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 30 05:29:33.673509 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 30 05:29:33.673515 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 30 05:29:33.673522 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 30 05:29:33.673528 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 30 05:29:33.673534 kernel: vgaarb: loaded Oct 30 05:29:33.673541 kernel: Registered efivars operations Oct 30 05:29:33.673547 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 30 05:29:33.673554 kernel: VFS: Disk quotas dquot_6.6.0 Oct 30 05:29:33.673561 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 30 05:29:33.673567 kernel: pnp: PnP ACPI init Oct 30 05:29:33.673647 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 30 05:29:33.673697 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 30 05:29:33.673705 kernel: pnp: PnP ACPI: found 1 devices Oct 30 05:29:33.673712 kernel: NET: Registered protocol family 2 Oct 30 05:29:33.673718 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 30 05:29:33.673726 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 30 05:29:33.673733 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 30 05:29:33.673739 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 30 05:29:33.673746 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 30 05:29:33.673752 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 05:29:33.673759 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 30 05:29:33.673765 kernel: NET: Registered protocol family 1 Oct 30 05:29:33.673771 kernel: PCI: CLS 0 bytes, default 64 Oct 30 05:29:33.673777 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 30 05:29:33.673784 kernel: kvm [1]: HYP mode not available Oct 30 05:29:33.673791 kernel: Initialise system trusted keyrings Oct 30 05:29:33.673797 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 30 05:29:33.673803 kernel: Key type asymmetric registered Oct 30 05:29:33.673809 kernel: Asymmetric key parser 'x509' registered Oct 30 05:29:33.673815 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 30 05:29:33.673822 kernel: io scheduler mq-deadline registered Oct 30 05:29:33.673828 kernel: io scheduler kyber registered Oct 30 05:29:33.673834 kernel: io scheduler bfq registered Oct 30 05:29:33.673841 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 30 05:29:33.673848 kernel: ACPI: Power Button [PWRB] Oct 30 05:29:33.673854 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 30 05:29:33.673907 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 30 05:29:33.673924 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 30 05:29:33.673930 kernel: msm_serial: driver initialized Oct 30 05:29:33.673936 kernel: random: fast init done Oct 30 05:29:33.673943 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 30 05:29:33.673949 kernel: random: crng init done Oct 30 05:29:33.673955 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 30 05:29:33.673962 kernel: thunder_xcv, ver 1.0 Oct 30 05:29:33.673969 kernel: thunder_bgx, ver 1.0 Oct 30 05:29:33.673975 kernel: nicpf, ver 1.0 Oct 30 05:29:33.673981 kernel: nicvf, ver 1.0 Oct 30 05:29:33.674050 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 30 05:29:33.674101 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-30T05:29:33 UTC (1761802173) Oct 30 05:29:33.674110 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 30 05:29:33.674116 kernel: NET: Registered protocol family 10 Oct 30 05:29:33.674124 kernel: Segment Routing with IPv6 Oct 30 05:29:33.674130 kernel: NET: Registered protocol family 17 Oct 30 05:29:33.674136 kernel: Key type dns_resolver registered Oct 30 05:29:33.674142 kernel: registered taskstats version 1 Oct 30 05:29:33.674148 kernel: Loading compiled-in X.509 certificates Oct 30 05:29:33.674155 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 30 05:29:33.674161 kernel: Key type ._fscrypt registered Oct 30 05:29:33.674167 kernel: Key type .fscrypt registered Oct 30 05:29:33.674173 kernel: Key type fscrypt-provisioning registered Oct 30 05:29:33.674181 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 30 05:29:33.674187 kernel: ima: Allocated hash algorithm: sha1 Oct 30 05:29:33.674193 kernel: ima: No architecture policies found Oct 30 05:29:33.674199 kernel: Freeing unused kernel memory: 34240K Oct 30 05:29:33.674205 kernel: Run /init as init process Oct 30 05:29:33.674211 kernel: with arguments: Oct 30 05:29:33.674217 kernel: /init Oct 30 05:29:33.674223 kernel: with environment: Oct 30 05:29:33.674229 kernel: HOME=/ Oct 30 05:29:33.674235 kernel: TERM=linux Oct 30 05:29:33.674242 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 30 05:29:33.674250 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=unified) Oct 30 05:29:33.674259 systemd[1]: Detected virtualization kvm. Oct 30 05:29:33.674266 systemd[1]: Detected architecture arm64. Oct 30 05:29:33.674272 systemd[1]: Running in initial RAM disk. Oct 30 05:29:33.674306 systemd[1]: No hostname configured, using default hostname. Oct 30 05:29:33.674316 systemd[1]: Hostname set to . Oct 30 05:29:33.674325 systemd[1]: Initializing machine ID from VM UUID. Oct 30 05:29:33.674332 systemd[1]: Queued start job for default target Initrd Default Target. Oct 30 05:29:33.674338 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:33.674345 systemd[1]: Reached target Local Encrypted Volumes. Oct 30 05:29:33.674351 systemd[1]: Reached target Path Units. Oct 30 05:29:33.674357 systemd[1]: Reached target Slice Units. Oct 30 05:29:33.674364 systemd[1]: Reached target Swaps. Oct 30 05:29:33.674370 systemd[1]: Reached target Timer Units. Oct 30 05:29:33.674378 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 30 05:29:33.674385 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 30 05:29:33.674391 systemd[1]: Listening on Journal Audit Socket. Oct 30 05:29:33.674398 systemd[1]: Listening on Journal Socket (/dev/log). Oct 30 05:29:33.674405 systemd[1]: Listening on Journal Socket. Oct 30 05:29:33.674411 systemd[1]: Listening on udev Control Socket. Oct 30 05:29:33.674418 systemd[1]: Listening on udev Kernel Socket. Oct 30 05:29:33.674424 systemd[1]: Reached target Socket Units. Oct 30 05:29:33.674431 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 30 05:29:33.674438 systemd[1]: Starting Create List of Static Device Nodes... Oct 30 05:29:33.674445 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 30 05:29:33.674451 systemd[1]: Starting Journal Service... Oct 30 05:29:33.674458 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 30 05:29:33.674465 systemd[1]: Starting Apply Kernel Variables... Oct 30 05:29:33.674471 systemd[1]: Starting Setup Virtual Console... Oct 30 05:29:33.674478 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 30 05:29:33.674484 kernel: SCSI subsystem initialized Oct 30 05:29:33.674490 kernel: Loading iSCSI transport class v2.0-870. Oct 30 05:29:33.674498 systemd[1]: Finished Create List of Static Device Nodes. Oct 30 05:29:33.674504 systemd[1]: Finished Apply Kernel Variables. Oct 30 05:29:33.674513 systemd-journald[270]: Journal started Oct 30 05:29:33.674554 systemd-journald[270]: Runtime Journal (/run/log/journal/17691bb7b7564595812e4271af311cda) is 6.0M, max 48.5M, 42.4M free. Oct 30 05:29:33.676150 systemd[1]: Started Journal Service. Oct 30 05:29:33.676496 systemd[1]: Finished Setup Virtual Console. Oct 30 05:29:33.677756 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 30 05:29:33.679265 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 30 05:29:33.682393 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 30 05:29:33.689361 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 30 05:29:33.690883 systemd[1]: Starting dracut cmdline hook... Oct 30 05:29:33.699274 dracut-cmdline[290]: dracut-dracut-053 Oct 30 05:29:33.701465 dracut-cmdline[290]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Oct 30 05:29:33.755037 kernel: iscsi: registered transport (tcp) Oct 30 05:29:33.766028 kernel: iscsi: registered transport (qla4xxx) Oct 30 05:29:33.766043 kernel: QLogic iSCSI HBA Driver Oct 30 05:29:33.775281 systemd[1]: Finished dracut cmdline hook. Oct 30 05:29:33.776561 systemd[1]: Starting dracut pre-udev hook... Oct 30 05:29:33.777683 systemd[1]: Starting Open-iSCSI... Oct 30 05:29:33.779919 iscsid[375]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 30 05:29:33.779919 iscsid[375]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Oct 30 05:29:33.779919 iscsid[375]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 30 05:29:33.779919 iscsid[375]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 30 05:29:33.788071 iscsid[375]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 30 05:29:33.788071 iscsid[375]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 30 05:29:33.791903 kernel: device-mapper: uevent: version 1.0.3 Oct 30 05:29:33.791928 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 30 05:29:33.784104 systemd[1]: Started Open-iSCSI. Oct 30 05:29:33.825042 kernel: raid6: neonx8 gen() 13728 MB/s Oct 30 05:29:33.842033 kernel: raid6: neonx8 xor() 10721 MB/s Oct 30 05:29:33.859033 kernel: raid6: neonx4 gen() 13557 MB/s Oct 30 05:29:33.876034 kernel: raid6: neonx4 xor() 11545 MB/s Oct 30 05:29:33.893036 kernel: raid6: neonx2 gen() 12984 MB/s Oct 30 05:29:33.910030 kernel: raid6: neonx2 xor() 9852 MB/s Oct 30 05:29:33.927042 kernel: raid6: neonx1 gen() 10870 MB/s Oct 30 05:29:33.944042 kernel: raid6: neonx1 xor() 8761 MB/s Oct 30 05:29:33.961033 kernel: raid6: int64x8 gen() 6817 MB/s Oct 30 05:29:33.978042 kernel: raid6: int64x8 xor() 3789 MB/s Oct 30 05:29:33.995034 kernel: raid6: int64x4 gen() 6900 MB/s Oct 30 05:29:34.012037 kernel: raid6: int64x4 xor() 3821 MB/s Oct 30 05:29:34.029030 kernel: raid6: int64x2 gen() 6177 MB/s Oct 30 05:29:34.046041 kernel: raid6: int64x2 xor() 3309 MB/s Oct 30 05:29:34.063036 kernel: raid6: int64x1 gen() 5003 MB/s Oct 30 05:29:34.080586 kernel: raid6: int64x1 xor() 2599 MB/s Oct 30 05:29:34.080608 kernel: raid6: using algorithm neonx8 gen() 13728 MB/s Oct 30 05:29:34.080625 kernel: raid6: .... xor() 10721 MB/s, rmw enabled Oct 30 05:29:34.080640 kernel: raid6: using neon recovery algorithm Oct 30 05:29:34.091242 kernel: xor: measuring software checksum speed Oct 30 05:29:34.091268 kernel: 8regs : 17156 MB/sec Oct 30 05:29:34.092177 kernel: 32regs : 20765 MB/sec Oct 30 05:29:34.093589 kernel: arm64_neon : 29059 MB/sec Oct 30 05:29:34.093617 kernel: xor: using function: arm64_neon (29059 MB/sec) Oct 30 05:29:34.138037 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 30 05:29:34.147285 systemd[1]: Finished dracut pre-udev hook. Oct 30 05:29:34.148000 audit: BPF prog-id=6 op=LOAD Oct 30 05:29:34.148000 audit: BPF prog-id=7 op=LOAD Oct 30 05:29:34.148000 audit: BPF prog-id=8 op=LOAD Oct 30 05:29:34.148952 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 30 05:29:34.151764 kernel: audit: type=1334 audit(1761802174.148:2): prog-id=6 op=LOAD Oct 30 05:29:34.151780 kernel: audit: type=1334 audit(1761802174.148:3): prog-id=7 op=LOAD Oct 30 05:29:34.151788 kernel: audit: type=1334 audit(1761802174.148:4): prog-id=8 op=LOAD Oct 30 05:29:34.161091 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 30 05:29:34.163479 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 30 05:29:34.165901 systemd[1]: Starting dracut pre-trigger hook... Oct 30 05:29:34.177041 dracut-pre-trigger[420]: rd.md=0: removing MD RAID activation Oct 30 05:29:34.197961 systemd[1]: Finished dracut pre-trigger hook. Oct 30 05:29:34.199322 systemd[1]: Starting Coldplug All udev Devices... Oct 30 05:29:34.208365 systemd-udevd[407]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 30 05:29:34.225730 systemd[1]: Finished Coldplug All udev Devices. Oct 30 05:29:34.227151 systemd[1]: Starting dracut initqueue hook... Oct 30 05:29:34.242276 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 30 05:29:34.242422 kernel: vda: detected capacity change from 0 to 4756340736 Oct 30 05:29:34.253553 systemd-udevd[467]: Using default interface naming scheme 'v249'. Oct 30 05:29:34.263223 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 30 05:29:34.284891 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 30 05:29:34.287112 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (455) Oct 30 05:29:34.286026 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 30 05:29:34.291074 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 30 05:29:34.295210 systemd[1]: Reached target Initrd Root Device. Oct 30 05:29:34.296578 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 30 05:29:34.298365 systemd[1]: Finished dracut initqueue hook. Oct 30 05:29:34.299277 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 30 05:29:34.299374 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 30 05:29:34.300648 systemd[1]: Reached target Preparation for Local File Systems. Oct 30 05:29:34.302061 systemd[1]: Reached target Preparation for Remote File Systems. Oct 30 05:29:34.303808 systemd[1]: Reached target Remote Encrypted Volumes. Oct 30 05:29:34.305372 systemd[1]: Reached target Remote File Systems. Oct 30 05:29:34.307341 systemd[1]: Starting dracut pre-mount hook... Oct 30 05:29:34.308544 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 30 05:29:34.315113 systemd[1]: Finished dracut pre-mount hook. Oct 30 05:29:34.316688 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 30 05:29:34.320862 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 30 05:29:34.326360 systemd-fsck[501]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 30 05:29:34.328969 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 30 05:29:34.330872 systemd[1]: Mounting /sysroot... Oct 30 05:29:34.346663 systemd[1]: Found device /dev/mapper/usr. Oct 30 05:29:34.348495 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 30 05:29:34.350596 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 30 05:29:34.357739 systemd-fsck[514]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 30 05:29:34.357739 systemd-fsck[514]: You must have r/w access to the filesystem or be root Oct 30 05:29:34.360778 systemd-fsck[509]: fsck failed with exit status 8. Oct 30 05:29:34.360791 systemd-fsck[509]: Ignoring error. Oct 30 05:29:34.369414 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 30 05:29:34.370864 systemd[1]: Mounting /sysusr/usr... Oct 30 05:29:34.375763 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 30 05:29:34.375802 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 30 05:29:34.376143 systemd[1]: Mounted /sysroot. Oct 30 05:29:34.376838 systemd[1]: Reached target Initrd Root File System. Oct 30 05:29:34.379546 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 30 05:29:34.379361 systemd[1]: Starting Reload Configuration from the Real Root... Oct 30 05:29:34.380774 systemd[1]: Mounted /sysusr/usr. Oct 30 05:29:34.381855 systemd[1]: Reached target Local File Systems. Oct 30 05:29:34.382761 systemd[1]: Reached target System Initialization. Oct 30 05:29:34.384052 systemd[1]: Reached target Basic System. Oct 30 05:29:34.386444 systemd[1]: Mounting /sysroot/usr... Oct 30 05:29:34.388702 systemd[1]: Mounted /sysroot/usr. Oct 30 05:29:34.389441 systemd[1]: Reloading. Oct 30 05:29:34.390000 audit: BPF prog-id=6 op=UNLOAD Oct 30 05:29:34.392042 kernel: audit: type=1334 audit(1761802174.390:5): prog-id=6 op=UNLOAD Oct 30 05:29:34.397000 audit: BPF prog-id=3 op=UNLOAD Oct 30 05:29:34.402047 kernel: audit: type=1334 audit(1761802174.397:6): prog-id=3 op=UNLOAD Oct 30 05:29:34.469000 audit: BPF prog-id=9 op=LOAD Oct 30 05:29:34.470000 audit: BPF prog-id=10 op=LOAD Oct 30 05:29:34.470000 audit: BPF prog-id=11 op=LOAD Oct 30 05:29:34.471774 kernel: audit: type=1334 audit(1761802174.469:7): prog-id=9 op=LOAD Oct 30 05:29:34.471812 kernel: audit: type=1334 audit(1761802174.470:8): prog-id=10 op=LOAD Oct 30 05:29:34.471834 kernel: audit: type=1334 audit(1761802174.470:9): prog-id=11 op=LOAD Oct 30 05:29:34.471851 kernel: audit: type=1334 audit(1761802174.470:10): prog-id=7 op=UNLOAD Oct 30 05:29:34.470000 audit: BPF prog-id=7 op=UNLOAD Oct 30 05:29:34.470000 audit: BPF prog-id=8 op=UNLOAD Oct 30 05:29:34.471000 audit: BPF prog-id=12 op=LOAD Oct 30 05:29:34.472000 audit: BPF prog-id=13 op=LOAD Oct 30 05:29:34.472000 audit: BPF prog-id=14 op=LOAD Oct 30 05:29:34.472000 audit: BPF prog-id=4 op=UNLOAD Oct 30 05:29:34.472000 audit: BPF prog-id=5 op=UNLOAD Oct 30 05:29:34.485198 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 30 05:29:34.485314 systemd[1]: Finished Reload Configuration from the Real Root. Oct 30 05:29:34.486993 systemd[1]: Reached target Initrd File Systems. Oct 30 05:29:34.488433 systemd[1]: Reached target Initrd Default Target. Oct 30 05:29:34.489788 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 30 05:29:34.490402 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 30 05:29:34.500130 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 30 05:29:34.501530 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 30 05:29:34.506728 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 30 05:29:34.507645 systemd[1]: Stopped target Timer Units. Oct 30 05:29:34.508858 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 30 05:29:34.508969 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 30 05:29:34.510302 systemd[1]: Stopped target Initrd Default Target. Oct 30 05:29:34.511730 systemd[1]: Stopped target Basic System. Oct 30 05:29:34.513002 systemd[1]: Stopped target Initrd Root Device. Oct 30 05:29:34.514396 systemd[1]: Stopped target Path Units. Oct 30 05:29:34.515566 systemd[1]: Stopped target Remote File Systems. Oct 30 05:29:34.516787 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 30 05:29:34.518171 systemd[1]: Stopped target Slice Units. Oct 30 05:29:34.519387 systemd[1]: Stopped target Socket Units. Oct 30 05:29:34.520696 systemd[1]: Stopped target System Initialization. Oct 30 05:29:34.521921 systemd[1]: Stopped target Local File Systems. Oct 30 05:29:34.523257 systemd[1]: Stopped target Preparation for Local File Systems. Oct 30 05:29:34.524688 systemd[1]: Stopped target Swaps. Oct 30 05:29:34.525813 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 30 05:29:34.525919 systemd[1]: Stopped dracut pre-mount hook. Oct 30 05:29:34.527288 systemd[1]: Stopped target Local Encrypted Volumes. Oct 30 05:29:34.528391 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 30 05:29:34.534655 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:34.535830 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 30 05:29:34.535933 systemd[1]: Stopped dracut initqueue hook. Oct 30 05:29:34.540750 iscsid[375]: iscsid shutting down. Oct 30 05:29:34.537619 systemd[1]: Stopping Open-iSCSI... Oct 30 05:29:34.540193 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 30 05:29:34.540305 systemd[1]: Stopped Apply Kernel Variables. Oct 30 05:29:34.541472 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 30 05:29:34.541555 systemd[1]: Stopped Coldplug All udev Devices. Oct 30 05:29:34.542647 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 30 05:29:34.542729 systemd[1]: Stopped dracut pre-trigger hook. Oct 30 05:29:34.543855 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 30 05:29:34.549138 systemd[1]: iscsid.service: Deactivated successfully. Oct 30 05:29:34.549250 systemd[1]: Stopped Open-iSCSI. Oct 30 05:29:34.550520 systemd[1]: iscsid.socket: Deactivated successfully. Oct 30 05:29:34.550563 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 30 05:29:34.551721 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 30 05:29:34.553096 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 30 05:29:34.553202 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 30 05:29:34.563000 audit: BPF prog-id=9 op=UNLOAD Oct 30 05:29:34.555857 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 30 05:29:34.555966 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 30 05:29:34.557169 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 30 05:29:34.557282 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 30 05:29:34.559240 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 30 05:29:34.559289 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 30 05:29:34.560502 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 30 05:29:34.560540 systemd[1]: Closed udev Control Socket. Oct 30 05:29:34.561689 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 30 05:29:34.561721 systemd[1]: Closed udev Kernel Socket. Oct 30 05:29:34.563134 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 30 05:29:34.563183 systemd[1]: Stopped dracut pre-udev hook. Oct 30 05:29:34.564530 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 30 05:29:34.564569 systemd[1]: Stopped dracut cmdline hook. Oct 30 05:29:34.565782 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 30 05:29:34.565819 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 30 05:29:34.567982 systemd[1]: Starting Cleanup udev Database... Oct 30 05:29:34.568802 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 30 05:29:34.570293 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 30 05:29:34.585000 audit: BPF prog-id=12 op=UNLOAD Oct 30 05:29:34.570349 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 30 05:29:34.571781 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 30 05:29:34.571823 systemd[1]: Stopped Create List of Static Device Nodes. Oct 30 05:29:34.573165 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 30 05:29:34.573206 systemd[1]: Stopped Setup Virtual Console. Oct 30 05:29:34.574799 systemd[1]: rngd.service: Deactivated successfully. Oct 30 05:29:34.574901 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 30 05:29:34.576067 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 30 05:29:34.576162 systemd[1]: Finished Cleanup udev Database. Oct 30 05:29:34.577602 systemd[1]: Reached target Switch Root. Oct 30 05:29:34.579278 systemd[1]: Starting Switch Root... Oct 30 05:29:34.584368 systemd[1]: Switching root. Oct 30 05:29:34.602236 systemd-journald[270]: Journal stopped Oct 30 05:29:36.636843 systemd-journald[270]: Received SIGTERM from PID 1 (systemd). Oct 30 05:29:36.636880 kernel: SELinux: policy capability network_peer_controls=1 Oct 30 05:29:36.636891 kernel: SELinux: policy capability open_perms=1 Oct 30 05:29:36.636911 kernel: SELinux: policy capability extended_socket_class=1 Oct 30 05:29:36.636926 kernel: SELinux: policy capability always_check_network=0 Oct 30 05:29:36.636935 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 30 05:29:36.636944 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 30 05:29:36.636953 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 30 05:29:36.636963 systemd[1]: Successfully loaded SELinux policy in 30.171ms. Oct 30 05:29:36.636978 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 5.954ms. Oct 30 05:29:36.636989 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=unified) Oct 30 05:29:36.636999 systemd[1]: Detected virtualization kvm. Oct 30 05:29:36.637009 systemd[1]: Detected architecture arm64. Oct 30 05:29:36.637031 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 30 05:29:36.637042 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 30 05:29:36.637052 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 30 05:29:36.637062 systemd[1]: Stopped Switch Root. Oct 30 05:29:36.637073 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 30 05:29:36.637083 systemd[1]: Created slice Slice /system/addon-config. Oct 30 05:29:36.637093 systemd[1]: Created slice Slice /system/addon-run. Oct 30 05:29:36.637106 systemd[1]: Created slice Slice /system/getty. Oct 30 05:29:36.637116 systemd[1]: Created slice Slice /system/modprobe. Oct 30 05:29:36.637125 systemd[1]: Created slice Slice /system/serial-getty. Oct 30 05:29:36.637135 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 30 05:29:36.637145 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 30 05:29:36.637156 systemd[1]: Created slice User and Session Slice. Oct 30 05:29:36.637165 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 30 05:29:36.637175 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 30 05:29:36.637185 systemd[1]: Set up automount Boot partition Automount Point. Oct 30 05:29:36.637195 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 30 05:29:36.637204 systemd[1]: Stopped target Switch Root. Oct 30 05:29:36.637214 systemd[1]: Stopped target Initrd File Systems. Oct 30 05:29:36.637223 systemd[1]: Stopped target Initrd Root File System. Oct 30 05:29:36.637233 systemd[1]: Reached target Remote Encrypted Volumes. Oct 30 05:29:36.637242 systemd[1]: Reached target Remote File Systems. Oct 30 05:29:36.637251 systemd[1]: Reached target Slice Units. Oct 30 05:29:36.637261 systemd[1]: Reached target Swaps. Oct 30 05:29:36.637270 systemd[1]: Reached target Verify torcx succeeded. Oct 30 05:29:36.637294 systemd[1]: Reached target Local Verity Protected Volumes. Oct 30 05:29:36.637314 systemd[1]: Listening on Process Core Dump Socket. Oct 30 05:29:36.637324 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 30 05:29:36.637333 systemd[1]: Listening on Network Service Netlink Socket. Oct 30 05:29:36.637343 systemd[1]: Listening on udev Control Socket. Oct 30 05:29:36.637352 systemd[1]: Listening on udev Kernel Socket. Oct 30 05:29:36.637362 systemd[1]: Mounting Huge Pages File System... Oct 30 05:29:36.637371 systemd[1]: Mounting POSIX Message Queue File System... Oct 30 05:29:36.637381 systemd[1]: Mounting External Media Directory... Oct 30 05:29:36.637390 systemd[1]: Mounting Kernel Debug File System... Oct 30 05:29:36.637401 systemd[1]: Mounting Kernel Trace File System... Oct 30 05:29:36.637411 systemd[1]: Mounting Temporary Directory /tmp... Oct 30 05:29:36.637420 systemd[1]: Starting Create missing system files... Oct 30 05:29:36.637429 systemd[1]: Starting Create List of Static Device Nodes... Oct 30 05:29:36.637439 systemd[1]: Starting Load Kernel Module configfs... Oct 30 05:29:36.637448 systemd[1]: Starting Load Kernel Module drm... Oct 30 05:29:36.637457 systemd[1]: Starting Load Kernel Module fuse... Oct 30 05:29:36.637467 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 30 05:29:36.637476 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 30 05:29:36.637487 systemd[1]: Stopped File System Check on Root Device. Oct 30 05:29:36.637497 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 30 05:29:36.637507 systemd[1]: Stopped systemd-fsck-usr.service. Oct 30 05:29:36.637517 systemd[1]: Stopped Journal Service. Oct 30 05:29:36.637526 kernel: fuse: init (API version 7.32) Oct 30 05:29:36.637536 systemd[1]: Starting Journal Service... Oct 30 05:29:36.637546 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 30 05:29:36.637555 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 30 05:29:36.637565 systemd[1]: Starting Apply Kernel Variables... Oct 30 05:29:36.637574 systemd[1]: Starting Coldplug All udev Devices... Oct 30 05:29:36.637584 systemd[1]: verity-setup.service: Deactivated successfully. Oct 30 05:29:36.637594 systemd[1]: Stopped verity-setup.service. Oct 30 05:29:36.637604 systemd-journald[690]: Journal started Oct 30 05:29:36.637639 systemd-journald[690]: Runtime Journal (/run/log/journal/17691bb7b7564595812e4271af311cda) is 6.0M, max 48.5M, 42.4M free. Oct 30 05:29:34.673000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 30 05:29:34.698000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 30 05:29:34.698000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Oct 30 05:29:34.698000 audit: BPF prog-id=15 op=LOAD Oct 30 05:29:34.698000 audit: BPF prog-id=15 op=UNLOAD Oct 30 05:29:34.698000 audit: BPF prog-id=16 op=LOAD Oct 30 05:29:34.698000 audit: BPF prog-id=16 op=UNLOAD Oct 30 05:29:34.730000 audit[642]: AVC avc: denied { associate } for pid=642 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Oct 30 05:29:36.513000 audit: BPF prog-id=17 op=LOAD Oct 30 05:29:36.513000 audit: BPF prog-id=18 op=LOAD Oct 30 05:29:36.513000 audit: BPF prog-id=19 op=LOAD Oct 30 05:29:36.513000 audit: BPF prog-id=13 op=UNLOAD Oct 30 05:29:36.513000 audit: BPF prog-id=14 op=UNLOAD Oct 30 05:29:36.526000 audit: BPF prog-id=20 op=LOAD Oct 30 05:29:36.526000 audit: BPF prog-id=17 op=UNLOAD Oct 30 05:29:36.526000 audit: BPF prog-id=21 op=LOAD Oct 30 05:29:36.526000 audit: BPF prog-id=22 op=LOAD Oct 30 05:29:36.526000 audit: BPF prog-id=18 op=UNLOAD Oct 30 05:29:36.526000 audit: BPF prog-id=19 op=UNLOAD Oct 30 05:29:36.527000 audit: BPF prog-id=23 op=LOAD Oct 30 05:29:36.527000 audit: BPF prog-id=20 op=UNLOAD Oct 30 05:29:36.527000 audit: BPF prog-id=24 op=LOAD Oct 30 05:29:36.527000 audit: BPF prog-id=25 op=LOAD Oct 30 05:29:36.527000 audit: BPF prog-id=21 op=UNLOAD Oct 30 05:29:36.527000 audit: BPF prog-id=22 op=UNLOAD Oct 30 05:29:36.537000 audit: BPF prog-id=23 op=UNLOAD Oct 30 05:29:36.625000 audit: BPF prog-id=26 op=LOAD Oct 30 05:29:36.625000 audit: BPF prog-id=27 op=LOAD Oct 30 05:29:36.625000 audit: BPF prog-id=28 op=LOAD Oct 30 05:29:36.625000 audit: BPF prog-id=24 op=UNLOAD Oct 30 05:29:36.625000 audit: BPF prog-id=25 op=UNLOAD Oct 30 05:29:36.635000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 30 05:29:36.512604 systemd[1]: Queued start job for default target Multi-User System. Oct 30 05:29:34.729003 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3033.2.4 /usr/share/oem/torcx/store /var/lib/torcx/store/3033.2.4 /var/lib/torcx/store]" Oct 30 05:29:36.527711 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 30 05:29:34.729275 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 30 05:29:34.729292 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 30 05:29:34.729572 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 30 05:29:34.729583 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 30 05:29:34.729617 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 30 05:29:34.729629 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 30 05:29:34.729917 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 30 05:29:34.729954 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 30 05:29:34.729969 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 30 05:29:34.730331 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Oct 30 05:29:34.730361 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Oct 30 05:29:34.730381 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3033.2.4: no such file or directory" path=/usr/share/oem/torcx/store/3033.2.4 Oct 30 05:29:34.730395 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 30 05:29:34.730516 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3033.2.4: no such file or directory" path=/var/lib/torcx/store/3033.2.4 Oct 30 05:29:34.730533 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:34Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 30 05:29:36.427461 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:36.427728 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:36.427829 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:36.427958 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 30 05:29:36.428069 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 30 05:29:36.428131 /usr/lib/systemd/system-generators/torcx-generator[642]: time="2025-10-30T05:29:36Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Oct 30 05:29:36.641032 systemd[1]: Started Journal Service. Oct 30 05:29:36.641141 systemd[1]: Mounted Huge Pages File System. Oct 30 05:29:36.641937 systemd[1]: Mounted POSIX Message Queue File System. Oct 30 05:29:36.642881 systemd[1]: Mounted External Media Directory. Oct 30 05:29:36.643792 systemd[1]: Mounted Kernel Debug File System. Oct 30 05:29:36.644681 systemd[1]: Mounted Kernel Trace File System. Oct 30 05:29:36.645544 systemd[1]: Mounted Temporary Directory /tmp. Oct 30 05:29:36.646608 systemd[1]: Finished Create missing system files. Oct 30 05:29:36.647750 systemd[1]: Finished Create List of Static Device Nodes. Oct 30 05:29:36.648844 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 30 05:29:36.649038 systemd[1]: Finished Load Kernel Module configfs. Oct 30 05:29:36.649979 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 30 05:29:36.650218 systemd[1]: Finished Load Kernel Module drm. Oct 30 05:29:36.651130 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 30 05:29:36.651291 systemd[1]: Finished Load Kernel Module fuse. Oct 30 05:29:36.652308 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 30 05:29:36.653427 systemd[1]: Finished Apply Kernel Variables. Oct 30 05:29:36.666587 systemd[1]: Mounting FUSE Control File System... Oct 30 05:29:36.668418 systemd[1]: Mounting Kernel Configuration File System... Oct 30 05:29:36.669229 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 30 05:29:36.670057 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 30 05:29:36.670947 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 30 05:29:36.671940 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 30 05:29:36.672812 systemd[1]: Starting Load/Save Random Seed... Oct 30 05:29:36.674091 systemd-journald[690]: Time spent on flushing to /var/log/journal/17691bb7b7564595812e4271af311cda is 11.238ms for 666 entries. Oct 30 05:29:36.674091 systemd-journald[690]: System Journal (/var/log/journal/17691bb7b7564595812e4271af311cda) is 8.0M, max 203.0M, 195.0M free. Oct 30 05:29:36.673778 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 30 05:29:36.674689 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 30 05:29:36.676980 systemd[1]: Mounted FUSE Control File System. Oct 30 05:29:36.678304 systemd[1]: Mounted Kernel Configuration File System. Oct 30 05:29:36.682702 systemd[1]: Finished Load/Save Random Seed. Oct 30 05:29:36.683826 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 30 05:29:36.703312 systemd[1]: Finished Coldplug All udev Devices. Oct 30 05:29:36.704463 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 30 05:29:36.705639 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 30 05:29:36.707544 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 30 05:29:36.709000 audit: BPF prog-id=29 op=LOAD Oct 30 05:29:36.709000 audit: BPF prog-id=30 op=LOAD Oct 30 05:29:36.709000 audit: BPF prog-id=31 op=LOAD Oct 30 05:29:36.709000 audit: BPF prog-id=10 op=UNLOAD Oct 30 05:29:36.709000 audit: BPF prog-id=11 op=UNLOAD Oct 30 05:29:36.709801 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 30 05:29:36.732892 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 30 05:29:36.742000 audit: BPF prog-id=32 op=LOAD Oct 30 05:29:36.743095 systemd[1]: Starting Network Configuration... Oct 30 05:29:36.763229 systemd-udevd[721]: Using default interface naming scheme 'v249'. Oct 30 05:29:36.772869 systemd-networkd[723]: lo: Link UP Oct 30 05:29:36.772880 systemd-networkd[723]: lo: Gained carrier Oct 30 05:29:36.773272 systemd-networkd[723]: Enumeration completed Oct 30 05:29:36.773369 systemd[1]: Started Network Configuration. Oct 30 05:29:36.774959 systemd-networkd[723]: eth0: Link UP Oct 30 05:29:36.781070 systemd-networkd[723]: eth0: Gained carrier Oct 30 05:29:36.795123 systemd-networkd[723]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Oct 30 05:29:36.796071 udevadm[703]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 30 05:29:36.818205 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 30 05:29:36.832784 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 30 05:29:36.840896 lvm[731]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 30 05:29:36.872804 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 30 05:29:36.874245 systemd[1]: Reached target Local Encrypted Volumes. Oct 30 05:29:36.886793 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 30 05:29:36.890617 lvm[732]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 30 05:29:36.921796 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 30 05:29:36.923308 systemd[1]: Reached target Preparation for Local File Systems. Oct 30 05:29:36.924439 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 30 05:29:36.924467 systemd[1]: Reached target Containers. Oct 30 05:29:36.945114 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 30 05:29:36.956768 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 30 05:29:36.958762 systemd[1]: Mounting /usr/share/oem... Oct 30 05:29:36.964467 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 30 05:29:36.964522 kernel: BTRFS info (device vda6): has skinny extents Oct 30 05:29:36.966525 systemd[1]: Mounted /usr/share/oem. Oct 30 05:29:36.967316 systemd[1]: Reached target Local File Systems. Oct 30 05:29:36.968144 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 30 05:29:36.968441 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 30 05:29:36.968474 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 30 05:29:36.968501 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 30 05:29:36.979743 systemd[1]: Starting Create Volatile Files and Directories... Oct 30 05:29:36.985154 systemd-tmpfiles[755]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 30 05:29:36.986152 systemd-tmpfiles[755]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 30 05:29:37.012336 systemd-tmpfiles[755]: Detected autofs mount point /boot during canonicalization of /boot. Oct 30 05:29:37.012346 systemd-tmpfiles[755]: Skipping /boot Oct 30 05:29:37.017785 systemd-tmpfiles[755]: Detected autofs mount point /boot during canonicalization of /boot. Oct 30 05:29:37.017802 systemd-tmpfiles[755]: Skipping /boot Oct 30 05:29:37.045363 systemd[1]: Finished Create Volatile Files and Directories. Oct 30 05:29:37.055831 systemd[1]: Starting Load Security Auditing Rules... Oct 30 05:29:37.057542 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 30 05:29:37.058575 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 30 05:29:37.059000 audit: BPF prog-id=33 op=LOAD Oct 30 05:29:37.059893 systemd[1]: Starting Network Name Resolution... Oct 30 05:29:37.062000 audit: BPF prog-id=34 op=LOAD Oct 30 05:29:37.063122 systemd[1]: Starting Network Time Synchronization... Oct 30 05:29:37.063924 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 30 05:29:37.064835 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 30 05:29:37.066209 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 30 05:29:37.067394 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 30 05:29:37.072076 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 30 05:29:37.085000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Oct 30 05:29:37.085425 augenrules[776]: No rules Oct 30 05:29:37.086203 systemd[1]: Finished Load Security Auditing Rules. Oct 30 05:29:37.096852 systemd-timesyncd[765]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 30 05:29:37.101614 systemd-resolved[759]: Positive Trust Anchors: Oct 30 05:29:37.101621 systemd-resolved[759]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 30 05:29:37.101647 systemd-resolved[759]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Oct 30 05:29:37.102146 systemd[1]: Started Network Time Synchronization. Oct 30 05:29:37.103078 systemd[1]: Reached target System Initialization. Oct 30 05:29:37.103596 systemd-resolved[759]: Defaulting to hostname 'linux'. Oct 30 05:29:37.104049 systemd[1]: Started Watch for update engine configuration changes. Oct 30 05:29:37.105066 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 30 05:29:37.106082 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 30 05:29:37.106941 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 30 05:29:37.106972 systemd[1]: Reached target Path Units. Oct 30 05:29:37.107701 systemd[1]: Reached target System Time Set. Oct 30 05:29:37.108640 systemd[1]: Started Daily Log Rotation. Oct 30 05:29:37.109439 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 30 05:29:37.110495 systemd[1]: Reached target Timer Units. Oct 30 05:29:37.111483 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 30 05:29:37.113144 systemd[1]: Starting Docker Socket for the API... Oct 30 05:29:37.115960 systemd[1]: Listening on OpenSSH Server Socket. Oct 30 05:29:37.117180 systemd[1]: Started Network Name Resolution. Oct 30 05:29:37.118040 systemd[1]: Listening on Docker Socket for the API. Oct 30 05:29:37.118858 systemd[1]: Reached target Network. Oct 30 05:29:37.119578 systemd[1]: Reached target Host and Network Name Lookups. Oct 30 05:29:37.120463 systemd[1]: Reached target Socket Units. Oct 30 05:29:37.121181 systemd[1]: Reached target Basic System. Oct 30 05:29:37.121891 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 30 05:29:37.121933 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 30 05:29:37.122794 systemd[1]: Started D-Bus System Message Bus. Oct 30 05:29:37.125806 systemd[1]: Starting Extend Filesystems... Oct 30 05:29:37.126671 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 30 05:29:37.127610 systemd[1]: Starting Generate /run/flatcar/motd... Oct 30 05:29:37.129412 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 30 05:29:37.131314 systemd[1]: Starting Generate sshd host keys... Oct 30 05:29:37.132156 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 30 05:29:37.132198 systemd[1]: Reached target Load system-provided cloud configs. Oct 30 05:29:37.134668 extend-filesystems[785]: Found vda Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda1 Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda2 Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda3 Oct 30 05:29:37.135976 extend-filesystems[785]: Found usr Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda4 Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda6 Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda7 Oct 30 05:29:37.135976 extend-filesystems[785]: Found vda9 Oct 30 05:29:37.135976 extend-filesystems[785]: Checking size of /dev/vda9 Oct 30 05:29:37.159447 extend-filesystems[785]: Old size kept for /dev/vda9 Oct 30 05:29:37.137279 systemd[1]: Starting User Login Management... Oct 30 05:29:37.138479 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 30 05:29:37.139058 systemd[1]: Starting Update Engine... Oct 30 05:29:37.140179 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 30 05:29:37.140211 systemd[1]: Reached target Load user-provided cloud configs. Oct 30 05:29:37.143136 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 30 05:29:37.143343 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 30 05:29:37.146324 systemd[1]: motdgen.service: Deactivated successfully. Oct 30 05:29:37.146546 systemd[1]: Finished Generate /run/flatcar/motd. Oct 30 05:29:37.161322 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 30 05:29:37.161543 systemd[1]: Finished Extend Filesystems. Oct 30 05:29:37.175334 systemd-logind[796]: Watching system buttons on /dev/input/event0 (Power Button) Oct 30 05:29:37.175485 systemd-logind[796]: New seat seat0. Oct 30 05:29:37.179739 systemd[1]: Started User Login Management. Oct 30 05:29:37.187480 update_engine[799]: I1030 05:29:37.187237 799 main.cc:89] Flatcar Update Engine starting Oct 30 05:29:37.188054 update_engine[799]: I1030 05:29:37.187999 799 payload_state.cc:360] Current Response Signature = Oct 30 05:29:37.188054 update_engine[799]: NumURLs = 1 Oct 30 05:29:37.188054 update_engine[799]: Url0 = http://10.0.0.2:34567/packages/update.gz Oct 30 05:29:37.188054 update_engine[799]: Payload Size = 439617737 Oct 30 05:29:37.188054 update_engine[799]: Payload Sha256 Hash = hKuDazTd3yRvZZ+1GzVZ5iDGEt/oXMhd+zKPhlhnEaA= Oct 30 05:29:37.188054 update_engine[799]: Is Delta Payload = 0 Oct 30 05:29:37.188054 update_engine[799]: Max Failure Count Per Url = 10 Oct 30 05:29:37.188054 update_engine[799]: Disable Payload Backoff = 1 Oct 30 05:29:37.188253 update_engine[799]: I1030 05:29:37.188200 799 payload_state.cc:381] Payload Attempt Number = 0 Oct 30 05:29:37.188354 update_engine[799]: I1030 05:29:37.188337 799 payload_state.cc:404] Current URL Index = 0 Oct 30 05:29:37.188495 update_engine[799]: I1030 05:29:37.188480 799 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 30 05:29:37.188648 update_engine[799]: I1030 05:29:37.188620 799 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 30 05:29:37.190215 systemd[1]: Started Update Engine. Oct 30 05:29:37.190462 update_engine[799]: I1030 05:29:37.190438 799 update_check_scheduler.cc:74] Next update check in 8m7s Oct 30 05:29:37.191664 systemd[1]: Finished Generate sshd host keys. Oct 30 05:29:37.206868 systemd[1]: Starting Generate /run/issue... Oct 30 05:29:37.210918 systemd[1]: issuegen.service: Deactivated successfully. Oct 30 05:29:37.211135 systemd[1]: Finished Generate /run/issue. Oct 30 05:29:37.212956 systemd[1]: Starting Permit User Sessions... Oct 30 05:29:37.217005 systemd[1]: Finished Permit User Sessions. Oct 30 05:29:37.218819 systemd[1]: Started Getty on tty1. Oct 30 05:29:37.220610 systemd[1]: Started Serial Getty on ttyAMA0. Oct 30 05:29:37.221546 systemd[1]: Reached target Login Prompts. Oct 30 05:29:37.222354 systemd[1]: Reached target Multi-User System. Oct 30 05:29:37.224251 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 30 05:29:37.228794 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 30 05:29:37.229004 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 30 05:29:37.229926 systemd[1]: Startup finished in 683ms (kernel) + 1.082s (initrd) + 2.588s (userspace) = 4.354s. Oct 30 05:29:37.962641 systemd-networkd[723]: eth0: Gained IPv6LL Oct 30 05:29:47.157088 systemd[1]: Created slice Slice /system/sshd. Oct 30 05:29:47.158133 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53022). Oct 30 05:29:47.192395 sshd[826]: Accepted publickey for core from 10.0.0.1 port 53022 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.194485 sshd[826]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.216197 systemd[1]: Created slice User Slice of UID 500. Oct 30 05:29:47.217593 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 30 05:29:47.219158 systemd-logind[796]: New session 1 of user core. Oct 30 05:29:47.223571 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 30 05:29:47.224639 systemd[1]: Starting User Manager for UID 500... Oct 30 05:29:47.226619 systemd[829]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.271135 systemd[829]: Queued start job for default target Main User Target. Oct 30 05:29:47.271261 systemd[829]: Reached target Paths. Oct 30 05:29:47.271274 systemd[829]: Reached target Sockets. Oct 30 05:29:47.271285 systemd[829]: Reached target Timers. Oct 30 05:29:47.271295 systemd[829]: Reached target Basic System. Oct 30 05:29:47.271332 systemd[829]: Reached target Main User Target. Oct 30 05:29:47.271343 systemd[829]: Startup finished in 41ms. Oct 30 05:29:47.271398 systemd[1]: Started User Manager for UID 500. Oct 30 05:29:47.287701 systemd[1]: Started Session 1 of User core. Oct 30 05:29:47.353064 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53024). Oct 30 05:29:47.388468 sshd[838]: Accepted publickey for core from 10.0.0.1 port 53024 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.389946 sshd[838]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.393064 systemd-logind[796]: New session 2 of user core. Oct 30 05:29:47.407536 systemd[1]: Started Session 2 of User core. Oct 30 05:29:47.462225 sshd[838]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:47.470482 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:53024.service: Deactivated successfully. Oct 30 05:29:47.471093 systemd[1]: session-2.scope: Deactivated successfully. Oct 30 05:29:47.471629 systemd-logind[796]: Session 2 logged out. Waiting for processes to exit. Oct 30 05:29:47.472507 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53028). Oct 30 05:29:47.473004 systemd-logind[796]: Removed session 2. Oct 30 05:29:47.505045 sshd[844]: Accepted publickey for core from 10.0.0.1 port 53028 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.506107 sshd[844]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.508271 systemd-logind[796]: New session 3 of user core. Oct 30 05:29:47.527985 systemd[1]: Started Session 3 of User core. Oct 30 05:29:47.577794 sshd[844]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:47.585324 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:53028.service: Deactivated successfully. Oct 30 05:29:47.585854 systemd[1]: session-3.scope: Deactivated successfully. Oct 30 05:29:47.586372 systemd-logind[796]: Session 3 logged out. Waiting for processes to exit. Oct 30 05:29:47.587209 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53038). Oct 30 05:29:47.587737 systemd-logind[796]: Removed session 3. Oct 30 05:29:47.617543 sshd[850]: Accepted publickey for core from 10.0.0.1 port 53038 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.618350 sshd[850]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.620696 systemd-logind[796]: New session 4 of user core. Oct 30 05:29:47.636642 systemd[1]: Started Session 4 of User core. Oct 30 05:29:47.688816 sshd[850]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:47.698303 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:53038.service: Deactivated successfully. Oct 30 05:29:47.699052 systemd[1]: session-4.scope: Deactivated successfully. Oct 30 05:29:47.699755 systemd-logind[796]: Session 4 logged out. Waiting for processes to exit. Oct 30 05:29:47.701215 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53046). Oct 30 05:29:47.701907 systemd-logind[796]: Removed session 4. Oct 30 05:29:47.730805 sshd[856]: Accepted publickey for core from 10.0.0.1 port 53046 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.731582 sshd[856]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.733948 systemd-logind[796]: New session 5 of user core. Oct 30 05:29:47.746192 systemd[1]: Started Session 5 of User core. Oct 30 05:29:47.804973 sudo[859]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 30 05:29:47.805423 sudo[859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:47.823637 sudo[859]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:47.825541 sshd[856]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:47.841528 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53054). Oct 30 05:29:47.844267 dbus-daemon[784]: [system] Reloaded configuration Oct 30 05:29:47.846602 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:53046.service: Deactivated successfully. Oct 30 05:29:47.847216 systemd[1]: session-5.scope: Deactivated successfully. Oct 30 05:29:47.847763 systemd-logind[796]: Session 5 logged out. Waiting for processes to exit. Oct 30 05:29:47.848347 systemd-logind[796]: Removed session 5. Oct 30 05:29:47.872074 sshd[862]: Accepted publickey for core from 10.0.0.1 port 53054 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:47.873203 sshd[862]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:47.875442 systemd-logind[796]: New session 6 of user core. Oct 30 05:29:47.897121 systemd[1]: Started Session 6 of User core. Oct 30 05:29:47.949222 sudo[867]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 30 05:29:47.949431 sudo[867]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:47.952161 sudo[867]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:47.955543 sudo[866]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 30 05:29:47.955747 sudo[866]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 30 05:29:47.971568 systemd[1]: Stopping Load Security Auditing Rules... Oct 30 05:29:47.971000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 30 05:29:47.973097 auditctl[870]: No rules Oct 30 05:29:47.973385 systemd[1]: audit-rules.service: Deactivated successfully. Oct 30 05:29:47.973640 systemd[1]: Stopped Load Security Auditing Rules. Oct 30 05:29:47.973720 kernel: kauditd_printk_skb: 49 callbacks suppressed Oct 30 05:29:47.973748 kernel: audit: type=1305 audit(1761802187.971:60): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 30 05:29:47.974950 systemd[1]: Starting Load Security Auditing Rules... Oct 30 05:29:47.989028 augenrules[887]: No rules Oct 30 05:29:47.995430 systemd[1]: Finished Load Security Auditing Rules. Oct 30 05:29:47.996157 sudo[866]: pam_unix(sudo:session): session closed for user root Oct 30 05:29:47.997280 sshd[862]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:48.000274 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:53054.service: Deactivated successfully. Oct 30 05:29:48.000777 systemd[1]: session-6.scope: Deactivated successfully. Oct 30 05:29:48.001342 systemd-logind[796]: Session 6 logged out. Waiting for processes to exit. Oct 30 05:29:48.002264 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53068). Oct 30 05:29:48.002876 systemd-logind[796]: Removed session 6. Oct 30 05:29:48.032811 sshd[893]: Accepted publickey for core from 10.0.0.1 port 53068 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:48.033758 sshd[893]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:48.036039 kernel: audit: type=1006 audit(1761802188.032:61): pid=893 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=7 res=1 Oct 30 05:29:48.037296 systemd-logind[796]: New session 7 of user core. Oct 30 05:29:48.047459 systemd[1]: Started Session 7 of User core. Oct 30 05:29:48.098325 sshd[893]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:48.113149 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:53068.service: Deactivated successfully. Oct 30 05:29:48.113774 systemd[1]: session-7.scope: Deactivated successfully. Oct 30 05:29:48.114498 systemd-logind[796]: Session 7 logged out. Waiting for processes to exit. Oct 30 05:29:48.115702 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:53076). Oct 30 05:29:48.116536 systemd-logind[796]: Removed session 7. Oct 30 05:29:48.146355 sshd[899]: Accepted publickey for core from 10.0.0.1 port 53076 ssh2: RSA SHA256:+etdwQGrTzRqATPNeE8jpFjiO/LBjojzA4FLIEyIQnY Oct 30 05:29:48.147226 sshd[899]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 30 05:29:48.149038 kernel: audit: type=1006 audit(1761802188.146:62): pid=899 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=8 res=1 Oct 30 05:29:48.150838 systemd-logind[796]: New session 8 of user core. Oct 30 05:29:48.161446 systemd[1]: Started Session 8 of User core. Oct 30 05:29:48.213075 sshd[899]: pam_unix(sshd:session): session closed for user core Oct 30 05:29:48.216069 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:53076.service: Deactivated successfully. Oct 30 05:29:48.216668 systemd[1]: session-8.scope: Deactivated successfully. Oct 30 05:29:48.217281 systemd-logind[796]: Session 8 logged out. Waiting for processes to exit. Oct 30 05:29:48.217996 systemd-logind[796]: Removed session 8.