Oct 29 20:25:57.680974 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 20:25:57.680990 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 29 20:25:57.680998 kernel: efi: EFI v2.70 by EDK II Oct 29 20:25:57.681004 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 20:25:57.681008 kernel: efi: seeding entropy pool Oct 29 20:25:57.681014 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 20:25:57.681020 kernel: ACPI: Early table checksum verification disabled Oct 29 20:25:57.681024 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 20:25:57.681029 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 20:25:57.681034 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681041 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681046 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681051 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681056 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681062 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681067 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681074 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681079 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:25:57.681085 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 20:25:57.681090 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 20:25:57.681095 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:25:57.681100 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Oct 29 20:25:57.681105 kernel: Zone ranges: Oct 29 20:25:57.681111 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:25:57.681116 kernel: DMA32 empty Oct 29 20:25:57.681121 kernel: Normal empty Oct 29 20:25:57.681127 kernel: Movable zone start for each node Oct 29 20:25:57.681133 kernel: Early memory node ranges Oct 29 20:25:57.681138 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 20:25:57.681144 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 20:25:57.681149 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 20:25:57.681154 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 20:25:57.681159 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 20:25:57.681164 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 20:25:57.681169 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 20:25:57.681175 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 20:25:57.681180 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 20:25:57.681185 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 20:25:57.681190 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 20:25:57.681195 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:25:57.681204 kernel: On node 0 totalpages: 643072 Oct 29 20:25:57.681209 kernel: DMA zone: 10048 pages used for memmap Oct 29 20:25:57.681215 kernel: DMA zone: 0 pages reserved Oct 29 20:25:57.681220 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 20:25:57.681226 kernel: psci: probing for conduit method from ACPI. Oct 29 20:25:57.681231 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 20:25:57.681250 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 20:25:57.681255 kernel: psci: Trusted OS migration not required Oct 29 20:25:57.681262 kernel: psci: SMC Calling Convention v1.1 Oct 29 20:25:57.681268 kernel: ACPI: SRAT not present Oct 29 20:25:57.681273 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 20:25:57.681279 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 20:25:57.681284 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 20:25:57.681289 kernel: Detected PIPT I-cache on CPU0 Oct 29 20:25:57.681295 kernel: CPU features: detected: GIC system register CPU interface Oct 29 20:25:57.681300 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 20:25:57.681306 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 20:25:57.681311 kernel: CPU features: detected: Hardware dirty bit management Oct 29 20:25:57.681317 kernel: CPU features: detected: Spectre-v4 Oct 29 20:25:57.681323 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 20:25:57.681329 kernel: CPU features: detected: Spectre-BHB Oct 29 20:25:57.681334 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 20:25:57.681339 kernel: Policy zone: DMA Oct 29 20:25:57.681346 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 29 20:25:57.681352 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 20:25:57.681357 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 20:25:57.681363 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 20:25:57.681369 kernel: Memory: 2452104K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120184K reserved, 0K cma-reserved) Oct 29 20:25:57.681374 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 20:25:57.681380 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 20:25:57.681386 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 20:25:57.681392 kernel: rcu: RCU event tracing is enabled. Oct 29 20:25:57.681398 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 20:25:57.681404 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 20:25:57.681409 kernel: Tracing variant of Tasks RCU enabled. Oct 29 20:25:57.681415 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 20:25:57.681427 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 20:25:57.681433 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 20:25:57.681438 kernel: GICv3: 256 SPIs implemented Oct 29 20:25:57.681443 kernel: GICv3: 0 Extended SPIs implemented Oct 29 20:25:57.681449 kernel: GICv3: Distributor has no Range Selector support Oct 29 20:25:57.681454 kernel: GICv3: 16 PPIs implemented Oct 29 20:25:57.681461 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 20:25:57.681466 kernel: ACPI: SRAT not present Oct 29 20:25:57.681472 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 20:25:57.681477 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 20:25:57.681483 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 20:25:57.681488 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 20:25:57.681494 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 20:25:57.681500 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:25:57.681505 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 20:25:57.681511 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 20:25:57.681517 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 20:25:57.681523 kernel: arm-pv: using stolen time PV Oct 29 20:25:57.681531 kernel: Console: colour dummy device 80x25 Oct 29 20:25:57.681537 kernel: ACPI: Core revision 20200925 Oct 29 20:25:57.681542 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 20:25:57.681548 kernel: pid_max: default: 32768 minimum: 301 Oct 29 20:25:57.681554 kernel: LSM: Security Framework initializing Oct 29 20:25:57.681559 kernel: SELinux: Initializing. Oct 29 20:25:57.681565 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 20:25:57.681571 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 20:25:57.681577 kernel: rcu: Hierarchical SRCU implementation. Oct 29 20:25:57.681584 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 20:25:57.681589 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 20:25:57.681595 kernel: Remapping and enabling EFI services. Oct 29 20:25:57.681601 kernel: smp: Bringing up secondary CPUs ... Oct 29 20:25:57.681606 kernel: Detected PIPT I-cache on CPU1 Oct 29 20:25:57.681612 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 20:25:57.681618 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 20:25:57.681625 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:25:57.681631 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 20:25:57.681636 kernel: Detected PIPT I-cache on CPU2 Oct 29 20:25:57.681643 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 20:25:57.681650 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 20:25:57.681655 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:25:57.681661 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 20:25:57.681667 kernel: Detected PIPT I-cache on CPU3 Oct 29 20:25:57.681676 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 20:25:57.681682 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 20:25:57.681690 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:25:57.681696 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 20:25:57.681702 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 20:25:57.681707 kernel: SMP: Total of 4 processors activated. Oct 29 20:25:57.681713 kernel: CPU features: detected: Privileged Access Never Oct 29 20:25:57.681719 kernel: CPU features: detected: LSE atomic instructions Oct 29 20:25:57.681725 kernel: CPU features: detected: User Access Override Oct 29 20:25:57.681731 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 20:25:57.681737 kernel: CPU features: detected: Common not Private translations Oct 29 20:25:57.681743 kernel: CPU features: detected: RAS Extension Support Oct 29 20:25:57.681750 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 20:25:57.681756 kernel: CPU features: detected: CRC32 instructions Oct 29 20:25:57.681762 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 20:25:57.681768 kernel: CPU: All CPU(s) started at EL1 Oct 29 20:25:57.681774 kernel: alternatives: patching kernel code Oct 29 20:25:57.681781 kernel: devtmpfs: initialized Oct 29 20:25:57.681786 kernel: KASLR enabled Oct 29 20:25:57.681792 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 20:25:57.681799 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 20:25:57.681804 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 20:25:57.681810 kernel: SMBIOS 3.0.0 present. Oct 29 20:25:57.681816 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 20:25:57.681822 kernel: NET: Registered protocol family 16 Oct 29 20:25:57.681828 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 20:25:57.681834 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 20:25:57.681841 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 20:25:57.681847 kernel: audit: initializing netlink subsys (disabled) Oct 29 20:25:57.681854 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 29 20:25:57.681860 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 20:25:57.681865 kernel: cpuidle: using governor menu Oct 29 20:25:57.681871 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 20:25:57.681877 kernel: ASID allocator initialised with 32768 entries Oct 29 20:25:57.681883 kernel: ACPI: bus type PCI registered Oct 29 20:25:57.681889 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 20:25:57.681896 kernel: Serial: AMBA PL011 UART driver Oct 29 20:25:57.681902 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 20:25:57.681908 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 20:25:57.681914 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 20:25:57.681920 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 20:25:57.681926 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 20:25:57.681932 kernel: ACPI: Added _OSI(Module Device) Oct 29 20:25:57.681938 kernel: ACPI: Added _OSI(Processor Device) Oct 29 20:25:57.681944 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 20:25:57.681951 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 20:25:57.681957 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 20:25:57.681963 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 20:25:57.681968 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 20:25:57.681974 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 20:25:57.681980 kernel: ACPI: Interpreter enabled Oct 29 20:25:57.681986 kernel: ACPI: Using GIC for interrupt routing Oct 29 20:25:57.681992 kernel: ACPI: MCFG table detected, 1 entries Oct 29 20:25:57.681998 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 20:25:57.682004 kernel: printk: console [ttyAMA0] enabled Oct 29 20:25:57.682011 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 20:25:57.682112 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 20:25:57.682168 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 20:25:57.682220 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 20:25:57.682285 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 20:25:57.682337 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 20:25:57.682345 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 20:25:57.682353 kernel: PCI host bridge to bus 0000:00 Oct 29 20:25:57.682411 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 20:25:57.682470 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 20:25:57.682519 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 20:25:57.682566 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 20:25:57.682631 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 20:25:57.682690 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 20:25:57.682748 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 20:25:57.682801 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 20:25:57.682856 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 20:25:57.682910 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 20:25:57.682963 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 20:25:57.683017 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 20:25:57.683065 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 20:25:57.683113 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 20:25:57.683161 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 20:25:57.683169 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 20:25:57.683175 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 20:25:57.683181 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 20:25:57.683187 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 20:25:57.683193 kernel: vgaarb: loaded Oct 29 20:25:57.683199 kernel: Registered efivars operations Oct 29 20:25:57.683205 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 20:25:57.683212 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 20:25:57.683219 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 20:25:57.683225 kernel: pnp: PnP ACPI init Oct 29 20:25:57.683320 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 20:25:57.683370 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 20:25:57.683378 kernel: pnp: PnP ACPI: found 1 devices Oct 29 20:25:57.683384 kernel: NET: Registered protocol family 2 Oct 29 20:25:57.683390 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 20:25:57.683398 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 20:25:57.683405 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 20:25:57.683411 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 20:25:57.683422 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 20:25:57.683429 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 20:25:57.683435 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 20:25:57.683441 kernel: NET: Registered protocol family 1 Oct 29 20:25:57.683447 kernel: PCI: CLS 0 bytes, default 64 Oct 29 20:25:57.683453 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 20:25:57.683460 kernel: kvm [1]: HYP mode not available Oct 29 20:25:57.683466 kernel: Initialise system trusted keyrings Oct 29 20:25:57.683472 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 20:25:57.683478 kernel: Key type asymmetric registered Oct 29 20:25:57.683484 kernel: Asymmetric key parser 'x509' registered Oct 29 20:25:57.683490 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 20:25:57.683496 kernel: io scheduler mq-deadline registered Oct 29 20:25:57.683502 kernel: io scheduler kyber registered Oct 29 20:25:57.683508 kernel: io scheduler bfq registered Oct 29 20:25:57.683515 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 20:25:57.683521 kernel: ACPI: Power Button [PWRB] Oct 29 20:25:57.683527 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 20:25:57.683583 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 20:25:57.683591 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 20:25:57.683597 kernel: msm_serial: driver initialized Oct 29 20:25:57.683603 kernel: random: fast init done Oct 29 20:25:57.683609 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 20:25:57.683615 kernel: random: crng init done Oct 29 20:25:57.683621 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 20:25:57.683628 kernel: thunder_xcv, ver 1.0 Oct 29 20:25:57.683634 kernel: thunder_bgx, ver 1.0 Oct 29 20:25:57.683640 kernel: nicpf, ver 1.0 Oct 29 20:25:57.683646 kernel: nicvf, ver 1.0 Oct 29 20:25:57.683716 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 20:25:57.683768 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T20:25:57 UTC (1761769557) Oct 29 20:25:57.683776 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 20:25:57.683782 kernel: NET: Registered protocol family 10 Oct 29 20:25:57.683789 kernel: Segment Routing with IPv6 Oct 29 20:25:57.683795 kernel: NET: Registered protocol family 17 Oct 29 20:25:57.683802 kernel: Key type dns_resolver registered Oct 29 20:25:57.683807 kernel: registered taskstats version 1 Oct 29 20:25:57.683813 kernel: Loading compiled-in X.509 certificates Oct 29 20:25:57.683820 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 20:25:57.683826 kernel: Key type ._fscrypt registered Oct 29 20:25:57.683832 kernel: Key type .fscrypt registered Oct 29 20:25:57.683838 kernel: Key type fscrypt-provisioning registered Oct 29 20:25:57.683845 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 20:25:57.683851 kernel: ima: Allocated hash algorithm: sha1 Oct 29 20:25:57.683858 kernel: ima: No architecture policies found Oct 29 20:25:57.683864 kernel: Freeing unused kernel memory: 34240K Oct 29 20:25:57.683870 kernel: Run /init as init process Oct 29 20:25:57.683876 kernel: with arguments: Oct 29 20:25:57.683881 kernel: /init Oct 29 20:25:57.683887 kernel: with environment: Oct 29 20:25:57.683893 kernel: HOME=/ Oct 29 20:25:57.683899 kernel: TERM=linux Oct 29 20:25:57.683906 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 20:25:57.683914 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 29 20:25:57.683922 systemd[1]: Detected virtualization kvm. Oct 29 20:25:57.683929 systemd[1]: Detected architecture arm64. Oct 29 20:25:57.683935 systemd[1]: Running in initial RAM disk. Oct 29 20:25:57.683942 systemd[1]: No hostname configured, using default hostname. Oct 29 20:25:57.683948 systemd[1]: Hostname set to . Oct 29 20:25:57.683956 systemd[1]: Initializing machine ID from VM UUID. Oct 29 20:25:57.683963 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 20:25:57.683969 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 20:25:57.683975 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 20:25:57.683981 systemd[1]: Reached target Path Units. Oct 29 20:25:57.683987 systemd[1]: Reached target Slice Units. Oct 29 20:25:57.683994 systemd[1]: Reached target Swaps. Oct 29 20:25:57.684000 systemd[1]: Reached target Timer Units. Oct 29 20:25:57.684007 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 20:25:57.684014 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 20:25:57.684020 systemd[1]: Listening on Journal Audit Socket. Oct 29 20:25:57.684027 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 20:25:57.684033 systemd[1]: Listening on Journal Socket. Oct 29 20:25:57.684039 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 20:25:57.684046 systemd[1]: Listening on udev Control Socket. Oct 29 20:25:57.684052 systemd[1]: Listening on udev Kernel Socket. Oct 29 20:25:57.684058 systemd[1]: Reached target Socket Units. Oct 29 20:25:57.684066 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 20:25:57.684072 systemd[1]: Finished Network Cleanup. Oct 29 20:25:57.684078 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 20:25:57.684085 systemd[1]: Starting Journal Service... Oct 29 20:25:57.684091 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 20:25:57.684097 systemd[1]: Starting Apply Kernel Variables... Oct 29 20:25:57.684104 systemd[1]: Starting Setup Virtual Console... Oct 29 20:25:57.684110 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 20:25:57.684116 systemd[1]: Finished Apply Kernel Variables. Oct 29 20:25:57.684124 systemd[1]: Finished Setup Virtual Console. Oct 29 20:25:57.684130 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 20:25:57.684137 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 20:25:57.684145 systemd-journald[279]: Journal started Oct 29 20:25:57.684177 systemd-journald[279]: Runtime Journal (/run/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 6.0M, max 48.5M, 42.4M free. Oct 29 20:25:57.686401 systemd[1]: Started Journal Service. Oct 29 20:25:57.686893 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 20:25:57.696630 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 20:25:57.698125 systemd[1]: Starting dracut cmdline hook... Oct 29 20:25:57.706433 dracut-cmdline[296]: dracut-dracut-053 Oct 29 20:25:57.708667 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 29 20:25:57.764263 kernel: SCSI subsystem initialized Oct 29 20:25:57.767247 kernel: Loading iSCSI transport class v2.0-870. Oct 29 20:25:57.773255 kernel: iscsi: registered transport (tcp) Oct 29 20:25:57.784266 kernel: iscsi: registered transport (qla4xxx) Oct 29 20:25:57.784289 kernel: QLogic iSCSI HBA Driver Oct 29 20:25:57.793458 systemd[1]: Finished dracut cmdline hook. Oct 29 20:25:57.794746 systemd[1]: Starting dracut pre-udev hook... Oct 29 20:25:57.807527 kernel: device-mapper: uevent: version 1.0.3 Oct 29 20:25:57.807553 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 20:25:57.842256 kernel: raid6: neonx8 gen() 13753 MB/s Oct 29 20:25:57.859263 kernel: raid6: neonx8 xor() 10718 MB/s Oct 29 20:25:57.876262 kernel: raid6: neonx4 gen() 13514 MB/s Oct 29 20:25:57.893255 kernel: raid6: neonx4 xor() 11581 MB/s Oct 29 20:25:57.910254 kernel: raid6: neonx2 gen() 12991 MB/s Oct 29 20:25:57.927260 kernel: raid6: neonx2 xor() 9893 MB/s Oct 29 20:25:57.944263 kernel: raid6: neonx1 gen() 10895 MB/s Oct 29 20:25:57.961261 kernel: raid6: neonx1 xor() 8840 MB/s Oct 29 20:25:57.978263 kernel: raid6: int64x8 gen() 6830 MB/s Oct 29 20:25:57.995260 kernel: raid6: int64x8 xor() 3799 MB/s Oct 29 20:25:58.012261 kernel: raid6: int64x4 gen() 6916 MB/s Oct 29 20:25:58.029263 kernel: raid6: int64x4 xor() 3836 MB/s Oct 29 20:25:58.046264 kernel: raid6: int64x2 gen() 6184 MB/s Oct 29 20:25:58.063261 kernel: raid6: int64x2 xor() 3307 MB/s Oct 29 20:25:58.080262 kernel: raid6: int64x1 gen() 5009 MB/s Oct 29 20:25:58.097275 kernel: raid6: int64x1 xor() 2597 MB/s Oct 29 20:25:58.097294 kernel: raid6: using algorithm neonx8 gen() 13753 MB/s Oct 29 20:25:58.097309 kernel: raid6: .... xor() 10718 MB/s, rmw enabled Oct 29 20:25:58.098283 kernel: raid6: using neon recovery algorithm Oct 29 20:25:58.108485 kernel: xor: measuring software checksum speed Oct 29 20:25:58.108507 kernel: 8regs : 17170 MB/sec Oct 29 20:25:58.109447 kernel: 32regs : 20765 MB/sec Oct 29 20:25:58.110874 kernel: arm64_neon : 29173 MB/sec Oct 29 20:25:58.110884 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 29 20:25:58.155258 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 20:25:58.164358 systemd[1]: Finished dracut pre-udev hook. Oct 29 20:25:58.165000 audit: BPF prog-id=6 op=LOAD Oct 29 20:25:58.166012 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 20:25:58.168835 kernel: audit: type=1334 audit(1761769558.165:2): prog-id=6 op=LOAD Oct 29 20:25:58.168851 kernel: audit: type=1334 audit(1761769558.165:3): prog-id=7 op=LOAD Oct 29 20:25:58.168859 kernel: audit: type=1334 audit(1761769558.165:4): prog-id=8 op=LOAD Oct 29 20:25:58.165000 audit: BPF prog-id=7 op=LOAD Oct 29 20:25:58.165000 audit: BPF prog-id=8 op=LOAD Oct 29 20:25:58.179043 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 20:25:58.180736 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 20:25:58.184800 systemd[1]: Starting dracut pre-trigger hook... Oct 29 20:25:58.186000 audit: BPF prog-id=9 op=LOAD Oct 29 20:25:58.187118 systemd[1]: Starting Network Configuration... Oct 29 20:25:58.188310 kernel: audit: type=1334 audit(1761769558.186:5): prog-id=9 op=LOAD Oct 29 20:25:58.192397 dracut-pre-trigger[423]: rd.md=0: removing MD RAID activation Oct 29 20:25:58.204019 systemd-networkd[432]: lo: Link UP Oct 29 20:25:58.204025 systemd-networkd[432]: lo: Gained carrier Oct 29 20:25:58.204308 systemd-networkd[432]: Enumeration completed Oct 29 20:25:58.204779 systemd[1]: Started Network Configuration. Oct 29 20:25:58.205000 audit: BPF prog-id=10 op=LOAD Oct 29 20:25:58.206376 systemd[1]: Starting Network Name Resolution... Oct 29 20:25:58.209727 kernel: audit: type=1334 audit(1761769558.205:6): prog-id=10 op=LOAD Oct 29 20:25:58.225398 systemd[1]: Finished dracut pre-trigger hook. Oct 29 20:25:58.226714 systemd[1]: Starting Coldplug All udev Devices... Oct 29 20:25:58.235664 systemd-resolved[452]: Positive Trust Anchors: Oct 29 20:25:58.235676 systemd-resolved[452]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 20:25:58.235704 systemd-resolved[452]: 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 29 20:25:58.235959 systemd-resolved[452]: Defaulting to hostname 'linux'. Oct 29 20:25:58.236050 systemd-udevd[415]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 20:25:58.236572 systemd[1]: Started Network Name Resolution. Oct 29 20:25:58.238896 systemd[1]: Reached target Network. Oct 29 20:25:58.249474 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 20:25:58.254435 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 20:25:58.259875 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 20:25:58.261346 systemd[1]: Starting Open-iSCSI... Oct 29 20:25:58.263641 iscsid[484]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 20:25:58.263641 iscsid[484]: 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 29 20:25:58.263641 iscsid[484]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 20:25:58.263641 iscsid[484]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 20:25:58.263641 iscsid[484]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 20:25:58.263641 iscsid[484]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 20:25:58.286949 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 20:25:58.287056 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 20:25:58.266993 systemd[1]: Finished Coldplug All udev Devices. Oct 29 20:25:58.270433 systemd[1]: Started Open-iSCSI. Oct 29 20:25:58.272592 systemd[1]: Starting dracut initqueue hook... Oct 29 20:25:58.295261 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 20:25:58.301673 systemd-udevd[478]: Using default interface naming scheme 'v249'. Oct 29 20:25:58.306173 systemd-networkd[432]: eth0: Link UP Oct 29 20:25:58.315970 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 29 20:25:58.317265 kernel: BTRFS: device label OEM devid 1 transid 10 /dev/vda6 scanned by systemd-udevd (470) Oct 29 20:25:58.320411 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 20:25:58.326166 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 20:25:58.327221 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 20:25:58.331474 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 29 20:25:58.332290 systemd[1]: Reached target Initrd Root Device. Oct 29 20:25:58.334221 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 20:25:58.336600 systemd[1]: Finished dracut initqueue hook. Oct 29 20:25:58.337364 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 20:25:58.338956 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 20:25:58.340773 systemd[1]: Reached target Remote File Systems. Oct 29 20:25:58.342795 systemd[1]: Starting dracut pre-mount hook... Oct 29 20:25:58.347255 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 20:25:58.350898 systemd[1]: Finished dracut pre-mount hook. Oct 29 20:25:58.352230 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 20:25:59.339267 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 29 20:25:59.339224 systemd-networkd[432]: eth0: Gained carrier Oct 29 20:25:59.349298 systemd-networkd[432]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 29 20:25:59.355256 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 20:25:59.355521 disk-uuid[504]: The operation has completed successfully. Oct 29 20:25:59.377562 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 20:25:59.377684 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 20:25:59.379493 systemd[1]: Starting Ignition (setup)... Oct 29 20:25:59.387251 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 20:25:59.387278 kernel: BTRFS info (device vda6): has skinny extents Oct 29 20:25:59.392972 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 29 20:25:59.398793 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 29 20:25:59.398913 systemd[1]: Finished Ignition (setup). Oct 29 20:25:59.401455 systemd[1]: Starting Ignition (disks)... Oct 29 20:25:59.410791 ignition[540]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 20:25:59.410808 ignition[540]: Stage: disks Oct 29 20:25:59.410818 ignition[540]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 20:25:59.410829 ignition[540]: no config at "/usr/lib/ignition/base.ign" Oct 29 20:25:59.410873 ignition[540]: parsed url from cmdline: "" Oct 29 20:25:59.410877 ignition[540]: no config URL provided Oct 29 20:25:59.410881 ignition[540]: reading system config file "/usr/lib/ignition/user.ign" Oct 29 20:25:59.410888 ignition[540]: no config at "/usr/lib/ignition/user.ign" Oct 29 20:25:59.410907 ignition[540]: op(1): [started] loading QEMU firmware config module Oct 29 20:25:59.410912 ignition[540]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 29 20:25:59.411701 ignition[540]: op(1): [finished] loading QEMU firmware config module Oct 29 20:25:59.425091 ignition[540]: parsing config with SHA512: fef7bf5564e2ca3008acbcfd548ad7a251787d605769ea70175380446fd9a19d49bc3149bef359a9277d00f1fbc8eba6210af3eed70cc44e15d152bb9c9bf833 Oct 29 20:25:59.427205 ignition[540]: disks: disks passed Oct 29 20:25:59.427215 ignition[540]: Ignition finished successfully Oct 29 20:25:59.428004 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 29 20:25:59.428116 systemd[1]: Finished Ignition (disks). Oct 29 20:25:59.429226 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 20:25:59.431442 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 20:25:59.433582 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 20:25:59.444058 systemd-fsck[554]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 29 20:25:59.445694 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 20:25:59.448736 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 20:25:59.450666 systemd[1]: Mounting /sysroot... Oct 29 20:25:59.465105 systemd[1]: Found device /dev/mapper/usr. Oct 29 20:25:59.466564 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 20:25:59.467552 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 20:25:59.475653 systemd-fsck[568]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 20:25:59.475653 systemd-fsck[568]: You must have r/w access to the filesystem or be root Oct 29 20:25:59.476510 systemd-fsck[565]: fsck failed with exit status 8. Oct 29 20:25:59.476513 systemd-fsck[565]: Ignoring error. Oct 29 20:25:59.477336 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 20:25:59.479851 systemd[1]: Mounting /sysusr/usr... Oct 29 20:25:59.498084 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 20:25:59.498118 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 20:25:59.498613 systemd[1]: Mounted /sysroot. Oct 29 20:25:59.499295 systemd[1]: Reached target Initrd Root File System. Oct 29 20:25:59.509069 systemd[1]: Mounted /sysusr/usr. Oct 29 20:25:59.510256 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 20:25:59.509817 systemd[1]: Reached target Local File Systems. Oct 29 20:25:59.510983 systemd[1]: Reached target System Initialization. Oct 29 20:25:59.512294 systemd[1]: Reached target Basic System. Oct 29 20:25:59.514611 systemd[1]: Mounting /sysroot/usr... Oct 29 20:25:59.516623 systemd[1]: Mounted /sysroot/usr. Oct 29 20:25:59.517899 systemd[1]: Starting Root filesystem setup... Oct 29 20:25:59.540891 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 29 20:25:59.541008 systemd[1]: Finished Root filesystem setup. Oct 29 20:25:59.543216 systemd[1]: Starting Ignition (files)... Oct 29 20:25:59.544414 systemd[1]: Starting /sysroot/boot... Oct 29 20:25:59.546587 ignition[585]: Ignition v0.36.1-15-gde4e6cc9 Oct 29 20:25:59.546602 ignition[585]: Stage: files Oct 29 20:25:59.546612 ignition[585]: reading system config file "/usr/lib/ignition/base.ign" Oct 29 20:25:59.546621 ignition[585]: no config at "/usr/lib/ignition/base.ign" Oct 29 20:25:59.547091 ignition[585]: files: compiled without relabeling support, skipping Oct 29 20:25:59.559222 systemd[1]: Finished /sysroot/boot. Oct 29 20:25:59.563826 ignition[585]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 29 20:25:59.563840 ignition[585]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 29 20:25:59.565131 ignition[585]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 29 20:25:59.565138 ignition[585]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 29 20:25:59.566207 ignition[585]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 29 20:25:59.568094 systemd[1]: ignition-files.service: Deactivated successfully. Oct 29 20:25:59.566225 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 29 20:25:59.568197 systemd[1]: Finished Ignition (files). Oct 29 20:25:59.566520 ignition[585]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 29 20:25:59.569742 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 29 20:25:59.566527 ignition[585]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 29 20:25:59.570331 systemd[1]: Starting Ignition (record completion)... Oct 29 20:25:59.580675 kernel: audit: type=1334 audit(1761769559.578:7): prog-id=9 op=UNLOAD Oct 29 20:25:59.580695 kernel: audit: type=1334 audit(1761769559.579:8): prog-id=3 op=UNLOAD Oct 29 20:25:59.578000 audit: BPF prog-id=9 op=UNLOAD Oct 29 20:25:59.579000 audit: BPF prog-id=3 op=UNLOAD Oct 29 20:25:59.566611 ignition[585]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 20:25:59.583061 kernel: audit: type=1334 audit(1761769559.580:9): prog-id=10 op=UNLOAD Oct 29 20:25:59.580000 audit: BPF prog-id=10 op=UNLOAD Oct 29 20:25:59.571627 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 20:25:59.567004 ignition[585]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 29 20:25:59.589151 kernel: audit: type=1334 audit(1761769559.584:10): prog-id=6 op=UNLOAD Oct 29 20:25:59.584000 audit: BPF prog-id=6 op=UNLOAD Oct 29 20:25:59.573477 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 29 20:25:59.567010 ignition[585]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 29 20:25:59.573569 systemd[1]: Finished Ignition (record completion). Oct 29 20:25:59.567014 ignition[585]: files: files passed Oct 29 20:25:59.575880 systemd[1]: Reloading. Oct 29 20:25:59.567018 ignition[585]: Ignition finished successfully Oct 29 20:25:59.668000 audit: BPF prog-id=11 op=LOAD Oct 29 20:25:59.669000 audit: BPF prog-id=12 op=LOAD Oct 29 20:25:59.669000 audit: BPF prog-id=13 op=LOAD Oct 29 20:25:59.669000 audit: BPF prog-id=14 op=LOAD Oct 29 20:25:59.669000 audit: BPF prog-id=4 op=UNLOAD Oct 29 20:25:59.669000 audit: BPF prog-id=5 op=UNLOAD Oct 29 20:25:59.669000 audit: BPF prog-id=15 op=LOAD Oct 29 20:25:59.670000 audit: BPF prog-id=16 op=LOAD Oct 29 20:25:59.670000 audit: BPF prog-id=17 op=LOAD Oct 29 20:25:59.670000 audit: BPF prog-id=18 op=LOAD Oct 29 20:25:59.670000 audit: BPF prog-id=7 op=UNLOAD Oct 29 20:25:59.670000 audit: BPF prog-id=8 op=UNLOAD Oct 29 20:25:59.684085 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 20:25:59.684202 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 20:25:59.686034 systemd[1]: Reached target Initrd File Systems. Oct 29 20:25:59.687441 systemd[1]: Reached target Initrd Default Target. Oct 29 20:25:59.688803 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 20:25:59.689491 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 20:25:59.699532 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 20:25:59.701016 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 20:25:59.706886 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 20:25:59.707873 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 20:25:59.709333 systemd[1]: Stopped target Timer Units. Oct 29 20:25:59.710658 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 20:25:59.710761 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 20:25:59.712061 systemd[1]: Stopped target Initrd Default Target. Oct 29 20:25:59.713495 systemd[1]: Stopped target Basic System. Oct 29 20:25:59.714865 systemd[1]: Stopped target Initrd Root Device. Oct 29 20:25:59.716185 systemd[1]: Stopped target Path Units. Oct 29 20:25:59.717362 systemd[1]: Stopped target Remote File Systems. Oct 29 20:25:59.718699 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 20:25:59.720131 systemd[1]: Stopped target Slice Units. Oct 29 20:25:59.721387 systemd[1]: Stopped target Socket Units. Oct 29 20:25:59.722827 systemd[1]: Stopped target System Initialization. Oct 29 20:25:59.724158 systemd[1]: Stopped target Local File Systems. Oct 29 20:25:59.725449 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 20:25:59.726937 systemd[1]: Stopped target Swaps. Oct 29 20:25:59.728062 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 20:25:59.728163 systemd[1]: Stopped dracut pre-mount hook. Oct 29 20:25:59.729567 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 20:25:59.730726 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 20:25:59.734286 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 20:25:59.735314 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 20:25:59.735412 systemd[1]: Stopped dracut initqueue hook. Oct 29 20:25:59.738768 iscsid[484]: iscsid shutting down. Oct 29 20:25:59.737059 systemd[1]: Stopping Open-iSCSI... Oct 29 20:25:59.738794 systemd[1]: Stopping /sysroot/boot... Oct 29 20:25:59.739385 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 20:25:59.739520 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 20:25:59.740656 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 20:25:59.740744 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 20:25:59.742798 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 20:25:59.742906 systemd[1]: Stopped Open-iSCSI. Oct 29 20:25:59.744323 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 20:25:59.744387 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 20:25:59.745541 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 20:25:59.747717 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 20:25:59.747817 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 20:25:59.749249 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 20:25:59.749349 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 20:25:59.750696 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 29 20:25:59.750798 systemd[1]: Stopped /sysroot/boot. Oct 29 20:25:59.752098 systemd[1]: Stopped target Network. Oct 29 20:25:59.753805 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 20:25:59.753870 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 20:25:59.755122 systemd[1]: Stopping Network Name Resolution... Oct 29 20:25:59.759771 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 20:25:59.759886 systemd[1]: Stopped Network Name Resolution. Oct 29 20:25:59.766000 audit: BPF prog-id=15 op=UNLOAD Oct 29 20:25:59.761284 systemd[1]: Stopping Network Configuration... Oct 29 20:25:59.766344 systemd-networkd[432]: eth0: DHCP lease lost Oct 29 20:25:59.772268 systemd-networkd[432]: eth0: DHCPv6 lease lost Oct 29 20:25:59.773252 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 20:25:59.773367 systemd[1]: Stopped Network Configuration. Oct 29 20:25:59.774981 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 20:25:59.775010 systemd[1]: Closed Network Service Netlink Socket. Oct 29 20:25:59.776715 systemd[1]: Stopping Network Cleanup... Oct 29 20:25:59.780000 audit: BPF prog-id=11 op=UNLOAD Oct 29 20:25:59.777498 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 20:25:59.777565 systemd[1]: Stopped Apply Kernel Variables. Oct 29 20:25:59.778928 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 20:25:59.784997 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 29 20:25:59.785113 systemd[1]: Stopped Network Cleanup. Oct 29 20:25:59.787643 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 20:25:59.787779 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 20:25:59.789487 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 20:25:59.789526 systemd[1]: Closed udev Control Socket. Oct 29 20:25:59.790808 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 20:25:59.790840 systemd[1]: Closed udev Kernel Socket. Oct 29 20:25:59.792205 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 20:25:59.792265 systemd[1]: Stopped dracut pre-udev hook. Oct 29 20:25:59.793411 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 20:25:59.793459 systemd[1]: Stopped dracut cmdline hook. Oct 29 20:25:59.794824 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 20:25:59.801000 audit: BPF prog-id=16 op=UNLOAD Oct 29 20:25:59.794863 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 20:25:59.796768 systemd[1]: Starting Cleanup udev Database... Oct 29 20:25:59.797639 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 20:25:59.798865 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 20:25:59.798921 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 20:25:59.800411 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 20:25:59.800461 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 20:25:59.801808 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 20:25:59.801849 systemd[1]: Stopped Setup Virtual Console. Oct 29 20:25:59.803446 systemd[1]: rngd.service: Deactivated successfully. Oct 29 20:25:59.803562 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 20:25:59.813000 audit: BPF prog-id=12 op=UNLOAD Oct 29 20:25:59.805051 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 20:25:59.805155 systemd[1]: Finished Cleanup udev Database. Oct 29 20:25:59.806220 systemd[1]: Reached target Switch Root. Oct 29 20:25:59.808119 systemd[1]: Starting Switch Root... Oct 29 20:25:59.812916 systemd[1]: Switching root. Oct 29 20:25:59.832041 systemd-journald[279]: Journal stopped Oct 29 20:26:01.979871 systemd-journald[279]: Received SIGTERM from PID 1 (n/a). Oct 29 20:26:01.979922 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 20:26:01.979934 kernel: SELinux: policy capability open_perms=1 Oct 29 20:26:01.979944 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 20:26:01.979953 kernel: SELinux: policy capability always_check_network=0 Oct 29 20:26:01.979962 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 20:26:01.979971 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 20:26:01.979981 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 20:26:01.979995 systemd[1]: Successfully loaded SELinux policy in 30.285ms. Oct 29 20:26:01.980013 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.276ms. Oct 29 20:26:01.980024 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 29 20:26:01.980034 systemd[1]: Detected virtualization kvm. Oct 29 20:26:01.980044 systemd[1]: Detected architecture arm64. Oct 29 20:26:01.980054 systemd[1]: Detected first boot. Oct 29 20:26:01.980064 systemd[1]: Initializing machine ID from VM UUID. Oct 29 20:26:01.980074 systemd[1]: Populated /etc with preset unit settings. Oct 29 20:26:01.980084 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 29 20:26:01.980097 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 20:26:01.980107 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 20:26:01.980117 systemd[1]: Stopped Switch Root. Oct 29 20:26:01.980127 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 20:26:01.980137 systemd[1]: Created slice Slice /system/addon-config. Oct 29 20:26:01.980147 systemd[1]: Created slice Slice /system/addon-run. Oct 29 20:26:01.980156 systemd[1]: Created slice Slice /system/getty. Oct 29 20:26:01.980166 systemd[1]: Created slice Slice /system/modprobe. Oct 29 20:26:01.980177 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 20:26:01.980187 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 20:26:01.980197 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 20:26:01.980207 systemd[1]: Created slice User and Session Slice. Oct 29 20:26:01.980217 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 20:26:01.980227 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 20:26:01.980249 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 20:26:01.980260 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 20:26:01.980269 systemd[1]: Stopped target Switch Root. Oct 29 20:26:01.980281 systemd[1]: Stopped target Initrd File Systems. Oct 29 20:26:01.980290 systemd[1]: Stopped target Initrd Root File System. Oct 29 20:26:01.980300 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 20:26:01.980310 systemd[1]: Reached target Remote File Systems. Oct 29 20:26:01.980320 systemd[1]: Reached target Slice Units. Oct 29 20:26:01.980330 systemd[1]: Reached target Swaps. Oct 29 20:26:01.980339 systemd[1]: Reached target Verify torcx succeeded. Oct 29 20:26:01.980349 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 20:26:01.980359 systemd[1]: Listening on Process Core Dump Socket. Oct 29 20:26:01.980369 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 20:26:01.980380 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 20:26:01.980390 systemd[1]: Listening on udev Control Socket. Oct 29 20:26:01.980400 systemd[1]: Listening on udev Kernel Socket. Oct 29 20:26:01.980409 systemd[1]: Mounting Huge Pages File System... Oct 29 20:26:01.980419 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 20:26:01.980430 systemd[1]: Mounting External Media Directory... Oct 29 20:26:01.980440 systemd[1]: Mounting Kernel Debug File System... Oct 29 20:26:01.980459 systemd[1]: Mounting Kernel Trace File System... Oct 29 20:26:01.980469 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 20:26:01.980478 systemd[1]: Starting Create missing system files... Oct 29 20:26:01.980490 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 20:26:01.980500 systemd[1]: Starting Load Kernel Module configfs... Oct 29 20:26:01.980509 systemd[1]: Starting Load Kernel Module drm... Oct 29 20:26:01.980518 systemd[1]: Starting Load Kernel Module fuse... Oct 29 20:26:01.980528 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 20:26:01.980537 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 20:26:01.980548 systemd[1]: Stopped File System Check on Root Device. Oct 29 20:26:01.980558 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 20:26:01.980568 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 20:26:01.980579 systemd[1]: Stopped Journal Service. Oct 29 20:26:01.980588 kernel: fuse: init (API version 7.32) Oct 29 20:26:01.980597 kernel: kauditd_printk_skb: 36 callbacks suppressed Oct 29 20:26:01.980607 kernel: audit: type=1334 audit(1761769561.961:47): prog-id=27 op=LOAD Oct 29 20:26:01.980616 kernel: audit: type=1334 audit(1761769561.962:48): prog-id=28 op=LOAD Oct 29 20:26:01.980626 systemd[1]: Starting Journal Service... Oct 29 20:26:01.980635 kernel: audit: type=1334 audit(1761769561.963:49): prog-id=29 op=LOAD Oct 29 20:26:01.980644 kernel: audit: type=1334 audit(1761769561.963:50): prog-id=25 op=UNLOAD Oct 29 20:26:01.980653 kernel: audit: type=1334 audit(1761769561.963:51): prog-id=26 op=UNLOAD Oct 29 20:26:01.980663 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 20:26:01.980673 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 20:26:01.980683 systemd[1]: Starting Apply Kernel Variables... Oct 29 20:26:01.980693 systemd[1]: Starting Coldplug All udev Devices... Oct 29 20:26:01.980702 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 20:26:01.980712 systemd[1]: Stopped verity-setup.service. Oct 29 20:26:01.980722 kernel: audit: type=1305 audit(1761769561.978:52): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 29 20:26:01.980732 systemd-journald[801]: Journal started Oct 29 20:26:01.980765 systemd-journald[801]: Runtime Journal (/run/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 6.0M, max 48.5M, 42.4M free. Oct 29 20:25:59.893000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 20:25:59.922000 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 29 20:25:59.922000 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 29 20:25:59.922000 audit: BPF prog-id=19 op=LOAD Oct 29 20:25:59.922000 audit: BPF prog-id=19 op=UNLOAD Oct 29 20:25:59.922000 audit: BPF prog-id=20 op=LOAD Oct 29 20:25:59.922000 audit: BPF prog-id=20 op=UNLOAD Oct 29 20:25:59.963000 audit[750]: AVC avc: denied { associate } for pid=750 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 29 20:26:01.857000 audit: BPF prog-id=21 op=LOAD Oct 29 20:26:01.857000 audit: BPF prog-id=22 op=LOAD Oct 29 20:26:01.857000 audit: BPF prog-id=23 op=LOAD Oct 29 20:26:01.857000 audit: BPF prog-id=13 op=UNLOAD Oct 29 20:26:01.857000 audit: BPF prog-id=14 op=UNLOAD Oct 29 20:26:01.864000 audit: BPF prog-id=24 op=LOAD Oct 29 20:26:01.864000 audit: BPF prog-id=21 op=UNLOAD Oct 29 20:26:01.864000 audit: BPF prog-id=25 op=LOAD Oct 29 20:26:01.864000 audit: BPF prog-id=26 op=LOAD Oct 29 20:26:01.864000 audit: BPF prog-id=22 op=UNLOAD Oct 29 20:26:01.864000 audit: BPF prog-id=23 op=UNLOAD Oct 29 20:26:01.872000 audit: BPF prog-id=24 op=UNLOAD Oct 29 20:26:01.961000 audit: BPF prog-id=27 op=LOAD Oct 29 20:26:01.962000 audit: BPF prog-id=28 op=LOAD Oct 29 20:26:01.963000 audit: BPF prog-id=29 op=LOAD Oct 29 20:26:01.963000 audit: BPF prog-id=25 op=UNLOAD Oct 29 20:26:01.963000 audit: BPF prog-id=26 op=UNLOAD Oct 29 20:26:01.978000 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 29 20:26:01.856737 systemd[1]: Queued start job for default target Multi-User System. Oct 29 20:25:59.962596 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" 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 29 20:26:01.865059 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 20:25:59.962854 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 20:25:59.962872 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 20:25:59.962972 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 20:25:59.962983 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 20:25:59.963010 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 20:25:59.963023 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 20:25:59.963211 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 20:25:59.963263 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 20:25:59.963277 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 20:25:59.963623 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" 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 29 20:25:59.963653 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" 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 29 20:25:59.963673 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" 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 29 20:25:59.963688 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 20:25:59.963707 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" 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 29 20:25:59.963721 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:25:59Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 20:26:01.683334 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 20:26:01.683618 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" 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 29 20:26:01.683723 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" 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 29 20:26:01.683849 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" 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 29 20:26:01.683901 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 20:26:01.683959 /usr/lib/systemd/system-generators/torcx-generator[750]: time="2025-10-29T20:26:01Z" 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 29 20:26:01.984253 systemd[1]: Started Journal Service. Oct 29 20:26:01.984553 systemd[1]: Mounted Huge Pages File System. Oct 29 20:26:01.985391 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 20:26:01.986357 systemd[1]: Mounted External Media Directory. Oct 29 20:26:01.987209 systemd[1]: Mounted Kernel Debug File System. Oct 29 20:26:01.988070 systemd[1]: Mounted Kernel Trace File System. Oct 29 20:26:01.988976 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 20:26:01.990086 systemd[1]: Finished Create missing system files. Oct 29 20:26:01.991321 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 20:26:01.992399 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 20:26:01.992587 systemd[1]: Finished Load Kernel Module configfs. Oct 29 20:26:01.993615 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 20:26:01.993787 systemd[1]: Finished Load Kernel Module drm. Oct 29 20:26:01.994797 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 20:26:01.994965 systemd[1]: Finished Load Kernel Module fuse. Oct 29 20:26:01.996076 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 20:26:02.012752 systemd[1]: Finished Apply Kernel Variables. Oct 29 20:26:02.015603 systemd[1]: Mounting FUSE Control File System... Oct 29 20:26:02.017285 systemd[1]: Mounting Kernel Configuration File System... Oct 29 20:26:02.018075 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 20:26:02.019622 systemd[1]: Starting Rebuild Hardware Database... Oct 29 20:26:02.021620 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 20:26:02.024950 systemd-journald[801]: Time spent on flushing to /var/log/journal/35cb98b33e1f44799c33d8bd2b636957 is 7.920ms for 772 entries. Oct 29 20:26:02.024950 systemd-journald[801]: System Journal (/var/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 8.0M, max 203.0M, 195.0M free. Oct 29 20:26:02.022902 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 20:26:02.024188 systemd[1]: Starting Load/Save Random Seed... Oct 29 20:26:02.028754 systemd[1]: Starting Create System Users... Oct 29 20:26:02.031344 systemd[1]: Mounted FUSE Control File System. Oct 29 20:26:02.032407 systemd[1]: Mounted Kernel Configuration File System. Oct 29 20:26:02.033654 systemd[1]: Finished Load/Save Random Seed. Oct 29 20:26:02.035006 systemd[1]: Reached target First Boot Complete. Oct 29 20:26:02.038224 systemd[1]: Finished Coldplug All udev Devices. Oct 29 20:26:02.045188 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 20:26:02.048126 systemd-sysusers[813]: Creating group sgx with gid 999. Oct 29 20:26:02.048929 systemd-sysusers[813]: Creating group systemd-oom with gid 998. Oct 29 20:26:02.049385 systemd-sysusers[813]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 29 20:26:02.049676 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 20:26:02.051437 systemd-sysusers[813]: Creating group systemd-timesync with gid 997. Oct 29 20:26:02.051930 systemd-sysusers[813]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 29 20:26:02.052502 systemd-sysusers[813]: Creating group systemd-coredump with gid 996. Oct 29 20:26:02.052934 systemd-sysusers[813]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 29 20:26:02.063602 systemd[1]: Finished Create System Users. Oct 29 20:26:02.065433 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 20:26:02.077770 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 20:26:02.272469 systemd[1]: Finished Rebuild Hardware Database. Oct 29 20:26:02.285000 audit: BPF prog-id=30 op=LOAD Oct 29 20:26:02.286000 audit: BPF prog-id=31 op=LOAD Oct 29 20:26:02.287611 kernel: audit: type=1334 audit(1761769562.285:53): prog-id=30 op=LOAD Oct 29 20:26:02.287651 kernel: audit: type=1334 audit(1761769562.286:54): prog-id=31 op=LOAD Oct 29 20:26:02.287665 kernel: audit: type=1334 audit(1761769562.287:55): prog-id=32 op=LOAD Oct 29 20:26:02.287000 audit: BPF prog-id=32 op=LOAD Oct 29 20:26:02.288257 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 20:26:02.287000 audit: BPF prog-id=17 op=UNLOAD Oct 29 20:26:02.287000 audit: BPF prog-id=18 op=UNLOAD Oct 29 20:26:02.289265 kernel: audit: type=1334 audit(1761769562.287:56): prog-id=17 op=UNLOAD Oct 29 20:26:02.312661 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 20:26:02.319000 audit: BPF prog-id=33 op=LOAD Oct 29 20:26:02.320487 systemd[1]: Starting Network Configuration... Oct 29 20:26:02.347075 systemd-networkd[838]: lo: Link UP Oct 29 20:26:02.347083 systemd-networkd[838]: lo: Gained carrier Oct 29 20:26:02.347387 systemd-networkd[838]: Enumeration completed Oct 29 20:26:02.347483 systemd[1]: Started Network Configuration. Oct 29 20:26:02.348556 systemd-udevd[825]: Using default interface naming scheme 'v249'. Oct 29 20:26:02.373256 systemd-networkd[838]: eth0: Link UP Oct 29 20:26:02.375137 udevadm[817]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 20:26:02.377323 systemd-networkd[838]: eth0: Gained carrier Oct 29 20:26:02.386405 systemd-networkd[838]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 29 20:26:02.388463 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 20:26:02.395148 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 20:26:02.403872 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 20:26:02.431265 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 20:26:02.432331 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 20:26:02.441272 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 20:26:02.444971 lvm[847]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 20:26:02.479388 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 20:26:02.480394 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 20:26:02.481778 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 20:26:02.481806 systemd[1]: Reached target Containers. Oct 29 20:26:02.492521 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 20:26:02.506331 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 20:26:02.522150 systemd[1]: Mounting /usr/share/oem... Oct 29 20:26:02.528639 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 20:26:02.528695 kernel: BTRFS info (device vda6): has skinny extents Oct 29 20:26:02.531367 systemd[1]: Mounted /usr/share/oem. Oct 29 20:26:02.532132 systemd[1]: Reached target Local File Systems. Oct 29 20:26:02.533999 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 29 20:26:02.535215 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 20:26:02.535268 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 20:26:02.536201 systemd[1]: Starting Commit a transient machine-id on disk... Oct 29 20:26:02.538186 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 20:26:02.545559 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 20:26:02.547609 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 20:26:02.554105 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 29 20:26:02.554907 systemd[1]: Finished Commit a transient machine-id on disk. Oct 29 20:26:02.582748 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 20:26:02.582776 systemd-tmpfiles[872]: Skipping /boot Oct 29 20:26:02.588989 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 20:26:02.589000 systemd-tmpfiles[872]: Skipping /boot Oct 29 20:26:02.618745 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 29 20:26:02.634656 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 20:26:02.636801 systemd[1]: Starting Load Security Auditing Rules... Oct 29 20:26:02.638619 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 20:26:02.640880 systemd[1]: Starting Rebuild Journal Catalog... Oct 29 20:26:02.643000 audit: BPF prog-id=34 op=LOAD Oct 29 20:26:02.644705 systemd[1]: Starting Network Name Resolution... Oct 29 20:26:02.646000 audit: BPF prog-id=35 op=LOAD Oct 29 20:26:02.647211 systemd[1]: Starting Network Time Synchronization... Oct 29 20:26:02.649174 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 20:26:02.650842 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 20:26:02.652356 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 20:26:02.653029 systemd[1]: Finished Rebuild Journal Catalog. Oct 29 20:26:02.661176 systemd[1]: Starting Update is Completed... Oct 29 20:26:02.663365 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 20:26:02.666168 systemd[1]: Finished Update is Completed. Oct 29 20:26:02.672000 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 29 20:26:02.673194 augenrules[895]: No rules Oct 29 20:26:02.674099 systemd[1]: Finished Load Security Auditing Rules. Oct 29 20:26:02.683922 systemd[1]: Started Network Time Synchronization. Oct 29 20:26:02.684561 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 20:26:02.684845 systemd-resolved[882]: Positive Trust Anchors: Oct 29 20:26:02.684851 systemd-resolved[882]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 20:26:02.684879 systemd-resolved[882]: 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 29 20:26:02.685163 systemd[1]: Reached target System Initialization. Oct 29 20:26:02.685739 systemd-resolved[882]: Defaulting to hostname 'linux'. Oct 29 20:26:02.686289 systemd[1]: Started Watch for update engine configuration changes. Oct 29 20:26:02.687378 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 20:26:02.688432 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 20:26:02.689368 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 20:26:02.689400 systemd[1]: Reached target Path Units. Oct 29 20:26:02.690090 systemd[1]: Reached target System Time Set. Oct 29 20:26:02.691057 systemd[1]: Started Daily Log Rotation. Oct 29 20:26:02.691951 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 20:26:02.693055 systemd[1]: Reached target Timer Units. Oct 29 20:26:02.694180 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 20:26:02.703862 systemd[1]: Starting Docker Socket for the API... Oct 29 20:26:02.706930 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 20:26:02.708178 systemd[1]: Started Network Name Resolution. Oct 29 20:26:02.709141 systemd[1]: Listening on Docker Socket for the API. Oct 29 20:26:02.710101 systemd[1]: Reached target Network. Oct 29 20:26:02.710850 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 20:26:02.711772 systemd[1]: Reached target Socket Units. Oct 29 20:26:02.712544 systemd[1]: Reached target Basic System. Oct 29 20:26:02.713300 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 20:26:02.713329 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 20:26:02.714292 systemd[1]: Started D-Bus System Message Bus. Oct 29 20:26:02.717362 systemd[1]: Starting Extend Filesystems... Oct 29 20:26:02.718179 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 20:26:02.719178 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 20:26:02.721007 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 20:26:02.723782 systemd[1]: Starting Generate sshd host keys... Oct 29 20:26:02.724544 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 20:26:02.724577 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 20:26:02.726352 extend-filesystems[904]: Found vda Oct 29 20:26:02.726352 extend-filesystems[904]: Found vda1 Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda2 Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda3 Oct 29 20:26:02.729121 extend-filesystems[904]: Found usr Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda4 Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda6 Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda7 Oct 29 20:26:02.729121 extend-filesystems[904]: Found vda9 Oct 29 20:26:02.729121 extend-filesystems[904]: Checking size of /dev/vda9 Oct 29 20:26:02.728275 systemd[1]: Starting User Login Management... Oct 29 20:26:02.730863 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 20:26:02.731967 systemd[1]: Starting Update Engine... Oct 29 20:26:02.733753 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 20:26:02.733785 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 20:26:02.735372 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 20:26:02.735605 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 20:26:02.746335 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 20:26:02.750667 extend-filesystems[904]: Old size kept for /dev/vda9 Oct 29 20:26:02.746591 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 20:26:02.748568 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 20:26:02.748770 systemd[1]: Finished Extend Filesystems. Oct 29 20:26:02.767528 systemd-logind[917]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 20:26:02.767689 systemd-logind[917]: New seat seat0. Oct 29 20:26:02.771076 systemd[1]: Started User Login Management. Oct 29 20:26:02.771755 update_engine[922]: I1029 20:26:02.771483 922 main.cc:89] Flatcar Update Engine starting Oct 29 20:26:02.773763 systemd[1]: Started Update Engine. Oct 29 20:26:02.773993 update_engine[922]: I1029 20:26:02.773957 922 update_check_scheduler.cc:74] Next update check in 5m37s Oct 29 20:26:02.783534 systemd[1]: Started Cluster reboot manager. Oct 29 20:26:02.812055 locksmithd[927]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 29 20:26:03.299649 sshd_keygen[920]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 29 20:26:03.318721 systemd[1]: Finished Generate sshd host keys. Oct 29 20:26:03.329363 systemd[1]: Starting Generate /run/issue... Oct 29 20:26:03.333583 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 20:26:03.333820 systemd[1]: Finished Generate /run/issue. Oct 29 20:26:03.335850 systemd[1]: Starting Permit User Sessions... Oct 29 20:26:03.340374 systemd[1]: Finished Permit User Sessions. Oct 29 20:26:03.342329 systemd[1]: Started Getty on tty1. Oct 29 20:26:03.344117 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 20:26:03.345104 systemd[1]: Reached target Login Prompts. Oct 29 20:26:03.346066 systemd[1]: Reached target Multi-User System. Oct 29 20:26:03.348023 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 20:26:03.352957 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 20:26:03.353177 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 20:26:03.354226 systemd[1]: Startup finished in 688ms (kernel) + 2.297s (initrd) + 3.492s (userspace) = 6.478s. Oct 29 20:26:03.569823 systemd-networkd[838]: eth0: Gained IPv6LL Oct 29 20:26:09.604763 systemd[1]: Created slice Slice /system/sshd. Oct 29 20:26:09.606035 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40756). Oct 29 20:26:09.653211 sshd[949]: Accepted publickey for core from 10.0.0.1 port 40756 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:09.655122 sshd[949]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:09.671121 systemd[1]: Created slice User Slice of UID 500. Oct 29 20:26:09.672090 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 20:26:09.673552 systemd-logind[917]: New session 1 of user core. Oct 29 20:26:09.677993 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 20:26:09.679119 systemd[1]: Starting User Manager for UID 500... Oct 29 20:26:09.681215 systemd[952]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:09.727093 systemd[952]: Queued start job for default target Main User Target. Oct 29 20:26:09.727241 systemd[952]: Reached target Paths. Oct 29 20:26:09.727268 systemd[952]: Reached target Sockets. Oct 29 20:26:09.727280 systemd[952]: Reached target Timers. Oct 29 20:26:09.727290 systemd[952]: Reached target Basic System. Oct 29 20:26:09.727328 systemd[952]: Reached target Main User Target. Oct 29 20:26:09.727342 systemd[952]: Startup finished in 42ms. Oct 29 20:26:09.727614 systemd[1]: Started User Manager for UID 500. Oct 29 20:26:09.738448 systemd[1]: Started Session 1 of User core. Oct 29 20:26:09.797933 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40764). Oct 29 20:26:09.839127 sshd[961]: Accepted publickey for core from 10.0.0.1 port 40764 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:09.840344 sshd[961]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:09.842945 systemd-logind[917]: New session 2 of user core. Oct 29 20:26:09.851387 systemd[1]: Started Session 2 of User core. Oct 29 20:26:09.904096 sshd[961]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:09.912766 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:40764.service: Deactivated successfully. Oct 29 20:26:09.913329 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 20:26:09.913934 systemd-logind[917]: Session 2 logged out. Waiting for processes to exit. Oct 29 20:26:09.914911 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40766). Oct 29 20:26:09.915599 systemd-logind[917]: Removed session 2. Oct 29 20:26:09.956725 sshd[967]: Accepted publickey for core from 10.0.0.1 port 40766 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:09.957712 sshd[967]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:09.960264 systemd-logind[917]: New session 3 of user core. Oct 29 20:26:09.973455 systemd[1]: Started Session 3 of User core. Oct 29 20:26:10.023762 sshd[967]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:10.032887 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:40766.service: Deactivated successfully. Oct 29 20:26:10.033402 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 20:26:10.033989 systemd-logind[917]: Session 3 logged out. Waiting for processes to exit. Oct 29 20:26:10.034903 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40776). Oct 29 20:26:10.035565 systemd-logind[917]: Removed session 3. Oct 29 20:26:10.076794 sshd[974]: Accepted publickey for core from 10.0.0.1 port 40776 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:10.077696 sshd[974]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:10.080792 systemd-logind[917]: New session 4 of user core. Oct 29 20:26:10.091423 systemd[1]: Started Session 4 of User core. Oct 29 20:26:10.144766 sshd[974]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:10.154767 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:40776.service: Deactivated successfully. Oct 29 20:26:10.155384 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 20:26:10.156214 systemd-logind[917]: Session 4 logged out. Waiting for processes to exit. Oct 29 20:26:10.157110 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40778). Oct 29 20:26:10.157674 systemd-logind[917]: Removed session 4. Oct 29 20:26:10.199605 sshd[980]: Accepted publickey for core from 10.0.0.1 port 40778 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:10.200819 sshd[980]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:10.203212 systemd-logind[917]: New session 5 of user core. Oct 29 20:26:10.210428 systemd[1]: Started Session 5 of User core. Oct 29 20:26:10.266565 sudo[983]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 20:26:10.266767 sudo[983]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:10.279573 sudo[983]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:10.282421 sshd[980]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:10.303897 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40790). Oct 29 20:26:10.305099 dbus-daemon[903]: [system] Reloaded configuration Oct 29 20:26:10.307343 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:40778.service: Deactivated successfully. Oct 29 20:26:10.307923 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 20:26:10.308533 systemd-logind[917]: Session 5 logged out. Waiting for processes to exit. Oct 29 20:26:10.309163 systemd-logind[917]: Removed session 5. Oct 29 20:26:10.346011 sshd[986]: Accepted publickey for core from 10.0.0.1 port 40790 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:10.346890 sshd[986]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:10.349438 systemd-logind[917]: New session 6 of user core. Oct 29 20:26:10.355465 systemd[1]: Started Session 6 of User core. Oct 29 20:26:10.406973 sudo[991]: 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 29 20:26:10.407665 sudo[991]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:10.410270 sudo[991]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:10.414108 sudo[990]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 20:26:10.414323 sudo[990]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:10.433177 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 20:26:10.432000 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 29 20:26:10.436601 kernel: kauditd_printk_skb: 5 callbacks suppressed Oct 29 20:26:10.436704 kernel: audit: type=1305 audit(1761769570.432:62): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 20:26:10.436724 auditctl[994]: No rules Oct 29 20:26:10.436998 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 20:26:10.437273 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 20:26:10.438545 systemd[1]: Starting Load Security Auditing Rules... Oct 29 20:26:10.453052 augenrules[1011]: No rules Oct 29 20:26:10.453931 systemd[1]: Finished Load Security Auditing Rules. Oct 29 20:26:10.454941 sudo[990]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:10.456043 sshd[986]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:10.467336 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:40790.service: Deactivated successfully. Oct 29 20:26:10.467826 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 20:26:10.468466 systemd-logind[917]: Session 6 logged out. Waiting for processes to exit. Oct 29 20:26:10.469315 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:40796). Oct 29 20:26:10.470421 systemd-logind[917]: Removed session 6. Oct 29 20:26:10.511080 sshd[1017]: Accepted publickey for core from 10.0.0.1 port 40796 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:10.514104 sshd[1017]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:10.514313 kernel: audit: type=1006 audit(1761769570.510:63): pid=1017 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 29 20:26:10.516721 systemd-logind[917]: New session 7 of user core. Oct 29 20:26:10.530409 systemd[1]: Started Session 7 of User core. Oct 29 20:26:10.582314 sudo[1020]: 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 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: Oct 29 20:26:13.503553 update_engine[1267]: I1029 20:26:13.503422 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 20:26:13.506789 update_engine[1267]: I1029 20:26:13.506769 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 20:26:13.506967 sshd[1280]: Accepted publickey for core from 10.0.0.1 port 40958 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:13.508154 sshd[1280]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:13.512361 update_engine[1267]: I1029 20:26:13.512333 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 20:26:13.513513 update_engine[1267]: I1029 20:26:13.513485 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 20:26:13.513647 update_engine[1267]: I1029 20:26:13.513626 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 791 bytes downloaded Oct 29 20:26:13.513647 update_engine[1267]: I1029 20:26:13.513640 1267 omaha_request_action.cc:619] Omaha request response: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.513647 update_engine[1267]: Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515779 1267 omaha_request_action.cc:447] Omaha Response manifest version = Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515806 1267 omaha_request_action.cc:470] Found 1 url(s) Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515825 1267 omaha_request_action.cc:506] Processing first of 1 package(s) Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515828 1267 omaha_request_action.cc:513] Omaha Response package name = update.gz Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515831 1267 omaha_request_action.cc:529] Url0: http://10.0.0.7:34567/packages/update.gz Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515839 1267 omaha_request_action.cc:541] Payload size = 439328019 bytes Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515851 1267 omaha_request_action.cc:565] Found 1 action(s). Processing the postinstall action. Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515862 1267 payload_state.cc:51] Resetting all persisted state as this is a new response Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515866 1267 payload_state.cc:360] Current Response Signature = Oct 29 20:26:13.515994 update_engine[1267]: NumURLs = 1 Oct 29 20:26:13.515994 update_engine[1267]: Url0 = http://10.0.0.7:34567/packages/update.gz Oct 29 20:26:13.515994 update_engine[1267]: Payload Size = 439328019 Oct 29 20:26:13.515994 update_engine[1267]: Payload Sha256 Hash = JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8= Oct 29 20:26:13.515994 update_engine[1267]: Is Delta Payload = 0 Oct 29 20:26:13.515994 update_engine[1267]: Max Failure Count Per Url = 10 Oct 29 20:26:13.515994 update_engine[1267]: Disable Payload Backoff = 1 Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.515964 1267 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 20:26:13.515994 update_engine[1267]: I1029 20:26:13.516002 1267 payload_state.cc:404] Current URL Index = 0 Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516031 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 0 Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516057 1267 payload_state.cc:287] Resetting backoff expiry time as payload backoff is disabled Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516060 1267 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516091 1267 payload_state.cc:203] Payload backoff logic is disabled. Can proceed with the download Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516108 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaRequestAction, starting OmahaResponseHandlerAction Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516112 1267 omaha_response_handler_action.cc:43] Using Url0 as the download url this time Oct 29 20:26:13.516350 update_engine[1267]: I1029 20:26:13.516126 1267 prefs.cc:51] update-state-next-operation not present in /var/lib/update_engine/prefs Oct 29 20:26:13.516350 update_engine[1267]: E1029 20:26:13.516131 1267 payload_processor.cc:488] prefs->GetInt64(kPrefsUpdateStateNextOperation, &next_operation) && next_operation != kUpdateStateOperationInvalid && next_operation > 0 failed. Oct 29 20:26:13.516653 update_engine[1267]: I1029 20:26:13.516625 1267 omaha_response_handler_action.cc:85] Using this install plan: Oct 29 20:26:13.516653 update_engine[1267]: I1029 20:26:13.516640 1267 install_plan.cc:53] InstallPlan: , new_update, url: http://10.0.0.7:34567/packages/update.gz, payload size: 439328019, payload hash: JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8=, 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 29 20:26:13.516732 update_engine[1267]: I1029 20:26:13.516717 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished OmahaResponseHandlerAction, starting FilesystemCopierAction Oct 29 20:26:13.517899 update_engine[1267]: I1029 20:26:13.517861 1267 filesystem_copier_action.cc:296] Filesystem size: 1073741824 Oct 29 20:26:13.520557 systemd-logind[917]: New session 25 of user core. Oct 29 20:26:13.535444 systemd[1]: Started Session 25 of User core. Oct 29 20:26:13.606814 sshd[1280]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:13.609209 systemd[1]: sshd@24-10.0.0.8:22-10.0.0.1:40958.service: Deactivated successfully. Oct 29 20:26:13.609834 systemd[1]: session-25.scope: Deactivated successfully. Oct 29 20:26:13.610453 systemd-logind[917]: Session 25 logged out. Waiting for processes to exit. Oct 29 20:26:13.611207 systemd-logind[917]: Removed session 25. Oct 29 20:26:15.337748 update_engine[1267]: I1029 20:26:15.337711 1267 filesystem_copier_action.cc:274] Hash: MqVcpLuSy9a3pkT1U79KicfT+9MRnGj6QbL170vealU= Oct 29 20:26:15.338154 update_engine[1267]: I1029 20:26:15.338136 1267 action_processor.cc:82] ActionProcessor::ActionComplete: finished FilesystemCopierAction, starting KernelCopierAction Oct 29 20:26:15.406313 update_engine[1267]: E1029 20:26:15.400702 1267 kernel_copier_action.cc:60] Failed to copy kernel from /boot/flatcar/vmlinuz-a to /boot/flatcar/vmlinuz-b Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400748 1267 action_processor.cc:68] ActionProcessor::ActionComplete: KernelCopierAction action failed. Aborting processing. Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400753 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type KernelCopierAction Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400756 1267 update_attempter.cc:302] Processing Done. Oct 29 20:26:15.406313 update_engine[1267]: E1029 20:26:15.400803 1267 update_attempter.cc:615] Update failed. Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400806 1267 payload_state.cc:97] Updating payload state for error code: 1 (kActionCodeError) Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400810 1267 payload_state.cc:276] Incrementing the URL failure count Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.400813 1267 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401060 1267 action_processor.cc:36] ActionProcessor::StartProcessing: OmahaRequestAction Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401083 1267 omaha_request_action.cc:268] Posting an Omaha request to http://10.0.0.7:34567/v1/update Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401086 1267 omaha_request_action.cc:269] Request: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401091 1267 libcurl_http_fetcher.cc:48] Starting/Resuming transfer Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401149 1267 libcurl_http_fetcher.cc:152] Setting up curl options for HTTP Oct 29 20:26:15.406313 update_engine[1267]: I1029 20:26:15.401271 1267 libcurl_http_fetcher.cc:435] Setting up timeout source: 1 seconds. Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605216 1267 libcurl_http_fetcher.cc:248] HTTP response code: 200 Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605319 1267 libcurl_http_fetcher.cc:305] Transfer completed (200), 241 bytes downloaded Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605326 1267 omaha_request_action.cc:619] Omaha request response: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: Oct 29 20:26:15.605346 update_engine[1267]: E1029 20:26:15.605341 1267 omaha_request_action.cc:626] HTTP reported success but Omaha reports an error. Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605346 1267 action_processor.cc:65] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605350 1267 action_processor.cc:73] ActionProcessor::ActionComplete: finished last action of type OmahaRequestAction Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605352 1267 update_attempter.cc:302] Processing Done. Oct 29 20:26:15.605346 update_engine[1267]: I1029 20:26:15.605356 1267 update_attempter.cc:306] Error event sent. Oct 29 20:26:23.679200 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56590). Oct 29 20:26:23.724911 sshd[1302]: Accepted publickey for core from 10.0.0.1 port 56590 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:23.726404 sshd[1302]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:23.726663 kernel: kauditd_printk_skb: 287 callbacks suppressed Oct 29 20:26:23.726742 kernel: audit: type=1006 audit(1761769583.725:359): pid=1302 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 29 20:26:23.729306 systemd-logind[917]: New session 26 of user core. Oct 29 20:26:23.742411 systemd[1]: Started Session 26 of User core. Oct 29 20:26:23.801384 sshd[1302]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:23.814268 systemd[1]: sshd@25-10.0.0.8:22-10.0.0.1:56590.service: Deactivated successfully. Oct 29 20:26:23.814937 systemd[1]: session-26.scope: Deactivated successfully. Oct 29 20:26:23.815676 systemd-logind[917]: Session 26 logged out. Waiting for processes to exit. Oct 29 20:26:23.816915 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56594). Oct 29 20:26:23.817769 systemd-logind[917]: Removed session 26. Oct 29 20:26:23.859495 sshd[1309]: Accepted publickey for core from 10.0.0.1 port 56594 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:23.860435 sshd[1309]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:23.862257 kernel: audit: type=1006 audit(1761769583.858:360): pid=1309 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 29 20:26:23.862991 systemd-logind[917]: New session 27 of user core. Oct 29 20:26:23.876441 systemd[1]: Started Session 27 of User core. Oct 29 20:26:23.932218 sshd[1309]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:23.956099 systemd[1]: sshd@26-10.0.0.8:22-10.0.0.1:56594.service: Deactivated successfully. Oct 29 20:26:23.956882 systemd[1]: session-27.scope: Deactivated successfully. Oct 29 20:26:23.957645 systemd-logind[917]: Session 27 logged out. Waiting for processes to exit. Oct 29 20:26:23.958941 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56598). Oct 29 20:26:23.959737 systemd-logind[917]: Removed session 27. Oct 29 20:26:24.000933 sshd[1317]: Accepted publickey for core from 10.0.0.1 port 56598 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:24.002573 sshd[1317]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:24.004266 kernel: audit: type=1006 audit(1761769584.000:361): pid=1317 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 29 20:26:24.005089 systemd-logind[917]: New session 28 of user core. Oct 29 20:26:24.024601 systemd[1]: Started Session 28 of User core. Oct 29 20:26:24.075358 sshd[1317]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:24.084081 systemd[1]: sshd@27-10.0.0.8:22-10.0.0.1:56598.service: Deactivated successfully. Oct 29 20:26:24.084730 systemd[1]: session-28.scope: Deactivated successfully. Oct 29 20:26:24.085389 systemd-logind[917]: Session 28 logged out. Waiting for processes to exit. Oct 29 20:26:24.086601 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56602). Oct 29 20:26:24.087352 systemd-logind[917]: Removed session 28. Oct 29 20:26:24.129960 sshd[1323]: Accepted publickey for core from 10.0.0.1 port 56602 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:24.132615 sshd[1323]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:24.134275 kernel: audit: type=1006 audit(1761769584.130:362): pid=1323 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 29 20:26:24.135426 systemd-logind[917]: New session 29 of user core. Oct 29 20:26:24.148379 systemd[1]: Started Session 29 of User core. Oct 29 20:26:24.197169 sshd[1323]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:24.209758 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56618). Oct 29 20:26:24.210183 systemd[1]: sshd@28-10.0.0.8:22-10.0.0.1:56602.service: Deactivated successfully. Oct 29 20:26:24.210888 systemd[1]: session-29.scope: Deactivated successfully. Oct 29 20:26:24.211563 systemd-logind[917]: Session 29 logged out. Waiting for processes to exit. Oct 29 20:26:24.212478 systemd-logind[917]: Removed session 29. Oct 29 20:26:24.251793 sshd[1328]: Accepted publickey for core from 10.0.0.1 port 56618 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:24.253013 sshd[1328]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:24.255258 kernel: audit: type=1006 audit(1761769584.251:363): pid=1328 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 29 20:26:24.257060 systemd-logind[917]: New session 30 of user core. Oct 29 20:26:24.271449 systemd[1]: Started Session 30 of User core. Oct 29 20:26:24.324387 sudo[1333]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl stop sshd.socket Oct 29 20:26:24.324591 sudo[1333]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:24.330264 systemd[1]: sshd.socket: Deactivated successfully. Oct 29 20:26:24.330615 systemd[1]: Closed OpenSSH Server Socket. Oct 29 20:26:24.331708 sudo[1333]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:24.335304 sudo[1332]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/reboot Oct 29 20:26:24.335519 sudo[1332]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:24.349411 systemd[1]: Stopping Session 1 of User core... Oct 29 20:26:24.349816 sshd[949]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:24.350324 systemd[1]: Stopping Session 30 of User core... Oct 29 20:26:24.350721 sshd[1328]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:24.350929 sudo[1332]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:24.351340 systemd[1]: Removed slice Slice /system/addon-config. Oct 29 20:26:24.352468 systemd[1]: Removed slice Slice /system/addon-run. Oct 29 20:26:24.353604 systemd[1]: Removed slice Slice /system/modprobe. Oct 29 20:26:24.354707 systemd[1]: Removed slice Slice /system/system-cloudinit. Oct 29 20:26:24.355668 systemd[1]: Stopped target Multi-User System. Oct 29 20:26:24.356454 systemd[1]: Stopped target Login Prompts. Oct 29 20:26:24.357153 systemd[1]: Stopped target Containers. Oct 29 20:26:24.357919 systemd[1]: Stopped target Host and Network Name Lookups. Oct 29 20:26:24.358882 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 20:26:24.359816 systemd[1]: Stopped target Timer Units. Oct 29 20:26:24.360622 systemd[1]: logrotate.timer: Deactivated successfully. Oct 29 20:26:24.360672 systemd[1]: Stopped Daily Log Rotation. Oct 29 20:26:24.361439 systemd[1]: mdadm.timer: Deactivated successfully. Oct 29 20:26:24.361477 systemd[1]: Stopped Weekly check for MD array's redundancy information.. Oct 29 20:26:24.362515 systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 29 20:26:24.362554 systemd[1]: Stopped Daily Cleanup of Temporary Directories. Oct 29 20:26:24.363458 systemd[1]: Stopped target System Time Set. Oct 29 20:26:24.364164 systemd[1]: Stopped target Load user-provided cloud configs. Oct 29 20:26:24.365117 systemd[1]: Stopped target Load system-provided cloud configs. Oct 29 20:26:24.366105 systemd[1]: systemd-coredump.socket: Deactivated successfully. Oct 29 20:26:24.366195 systemd[1]: Closed Process Core Dump Socket. Oct 29 20:26:24.380776 systemd[1]: Unmounting Boot partition... Oct 29 20:26:24.381894 systemd[1]: Stopping Getty on tty1... Oct 29 20:26:24.382645 systemd[1]: Stopping Serial Getty on ttyAMA0... Oct 29 20:26:24.384769 systemd[1]: Stopping OpenSSH per-connection server daemon... Oct 29 20:26:24.385668 systemd[1]: Stopping OpenSSH per-connection server daemon (10.0.0.1:56618)... Oct 29 20:26:24.386630 systemd[1]: systemd-machine-id-commit.service: Deactivated successfully. Oct 29 20:26:24.386769 systemd[1]: Stopped Commit a transient machine-id on disk. Oct 29 20:26:24.387762 systemd[1]: Stopped target First Boot Complete. Oct 29 20:26:24.389569 systemd[1]: Stopping Load/Save Random Seed... Oct 29 20:26:24.390533 systemd[1]: Stopping Update Engine... Oct 29 20:26:24.393685 systemd[1]: getty@tty1.service: Deactivated successfully. Oct 29 20:26:24.393878 systemd[1]: Stopped Getty on tty1. Oct 29 20:26:24.395059 systemd[1]: serial-getty@ttyAMA0.service: Deactivated successfully. Oct 29 20:26:24.395262 systemd[1]: Stopped Serial Getty on ttyAMA0. Oct 29 20:26:24.396511 systemd[1]: sshd@0-10.0.0.8:22-10.0.0.1:40756.service: Deactivated successfully. Oct 29 20:26:24.396704 systemd[1]: Stopped OpenSSH per-connection server daemon. Oct 29 20:26:24.398087 systemd[1]: update-engine.service: Main process exited, code=exited, status=1/FAILURE Oct 29 20:26:24.398158 systemd[1]: update-engine.service: Failed with result 'exit-code'. Oct 29 20:26:24.398420 systemd[1]: Stopped Update Engine. Oct 29 20:26:24.399145 systemd[1]: update-engine.service: Consumed 2.357s CPU time. Oct 29 20:26:24.399526 systemd[1]: sshd@29-10.0.0.8:22-10.0.0.1:56618.service: Deactivated successfully. Oct 29 20:26:24.399705 systemd[1]: Stopped OpenSSH per-connection server daemon (10.0.0.1:56618). Oct 29 20:26:24.409038 systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 29 20:26:24.409271 systemd[1]: Stopped Load/Save Random Seed. Oct 29 20:26:24.410320 systemd[1]: session-30.scope: Deactivated successfully. Oct 29 20:26:24.410553 systemd[1]: Stopped Session 30 of User core. Oct 29 20:26:24.411661 systemd[1]: boot.mount: Deactivated successfully. Oct 29 20:26:24.411904 systemd[1]: Unmounted Boot partition. Oct 29 20:26:24.412848 systemd[1]: session-1.scope: Deactivated successfully. Oct 29 20:26:24.413082 systemd[1]: Stopped Session 1 of User core. Oct 29 20:26:24.414446 systemd-logind[917]: Session 1 logged out. Waiting for processes to exit. Oct 29 20:26:24.415366 systemd-logind[917]: Session 30 logged out. Waiting for processes to exit. Oct 29 20:26:24.415621 systemd[1]: Removed slice Slice /system/getty. Oct 29 20:26:24.416731 systemd[1]: Removed slice Slice /system/serial-getty. Oct 29 20:26:24.417895 systemd[1]: Removed slice Slice /system/sshd. Oct 29 20:26:24.418757 systemd[1]: sshd-keygen.service: Deactivated successfully. Oct 29 20:26:24.418857 systemd[1]: Stopped Generate sshd host keys. Oct 29 20:26:24.419740 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service: Deactivated successfully. Oct 29 20:26:24.419854 systemd[1]: Stopped File System Check on /dev/disk/by-label/EFI-SYSTEM. Oct 29 20:26:24.421148 systemd[1]: Stopping User Login Management... Oct 29 20:26:24.422429 systemd[1]: Stopping User Manager for UID 500... Oct 29 20:26:24.423644 systemd[952]: Stopped target Main User Target. Oct 29 20:26:24.423666 systemd[952]: Stopped target Basic System. Oct 29 20:26:24.423675 systemd[952]: Stopped target Paths. Oct 29 20:26:24.423682 systemd[952]: Stopped target Sockets. Oct 29 20:26:24.423698 systemd[952]: Reached target Shutdown. Oct 29 20:26:24.423705 systemd[952]: Stopped target Timers. Oct 29 20:26:24.423810 systemd[952]: Finished Exit the Session. Oct 29 20:26:24.423832 systemd[952]: Reached target Exit the Session. Oct 29 20:26:24.425329 systemd-logind[917]: Removed session 1. Oct 29 20:26:24.426466 systemd[1]: user@500.service: Deactivated successfully. Oct 29 20:26:24.426767 systemd[1]: Stopped User Manager for UID 500. Oct 29 20:26:24.428879 systemd[1]: Stopping User Runtime Directory /run/user/500... Oct 29 20:26:24.434070 systemd[1]: run-user-500.mount: Deactivated successfully. Oct 29 20:26:24.434130 systemd[1]: Unmounted /run/user/500. Oct 29 20:26:24.435303 systemd[1]: user-runtime-dir@500.service: Deactivated successfully. Oct 29 20:26:24.435499 systemd[1]: Stopped User Runtime Directory /run/user/500. Oct 29 20:26:24.436875 systemd[1]: systemd-logind.service: Deactivated successfully. Oct 29 20:26:24.437104 systemd[1]: Stopped User Login Management. Oct 29 20:26:24.438375 systemd[1]: Removed slice User Slice of UID 500. Oct 29 20:26:24.440090 systemd[1]: Stopping D-Bus System Message Bus... Oct 29 20:26:24.450000 audit: BPF prog-id=59 op=UNLOAD Oct 29 20:26:24.451990 systemd[1]: Stopping Permit User Sessions... Oct 29 20:26:24.452273 kernel: audit: type=1334 audit(1761769584.450:364): prog-id=59 op=UNLOAD Oct 29 20:26:24.453195 systemd[1]: dbus.service: Deactivated successfully. Oct 29 20:26:24.453460 systemd[1]: Stopped D-Bus System Message Bus. Oct 29 20:26:24.456274 systemd[1]: systemd-user-sessions.service: Deactivated successfully. Oct 29 20:26:24.456493 systemd[1]: Stopped Permit User Sessions. Oct 29 20:26:24.457334 systemd[1]: Stopped target Basic System. Oct 29 20:26:24.458069 systemd[1]: Stopped target Network. Oct 29 20:26:24.458944 systemd[1]: Stopped target Path Units. Oct 29 20:26:24.459708 systemd[1]: motdgen.path: Deactivated successfully. Oct 29 20:26:24.461283 systemd[1]: Stopped Watch for update engine configuration changes. Oct 29 20:26:24.462230 systemd[1]: user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path: Deactivated successfully. Oct 29 20:26:24.466288 systemd[1]: Stopped Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 20:26:24.467327 systemd[1]: Stopped target Remote File Systems. Oct 29 20:26:24.468106 systemd[1]: Stopped target Slice Units. Oct 29 20:26:24.469139 systemd[1]: Removed slice User and Session Slice. Oct 29 20:26:24.470027 systemd[1]: Stopped target Socket Units. Oct 29 20:26:24.470834 systemd[1]: dbus.socket: Deactivated successfully. Oct 29 20:26:24.470885 systemd[1]: Closed D-Bus System Message Bus Socket. Oct 29 20:26:24.471851 systemd[1]: docker.socket: Deactivated successfully. Oct 29 20:26:24.472082 systemd[1]: Closed Docker Socket for the API. Oct 29 20:26:24.472932 systemd[1]: Stopped target System Initialization. Oct 29 20:26:24.473783 systemd[1]: proc-sys-fs-binfmt_misc.automount: Deactivated successfully. Oct 29 20:26:24.473908 systemd[1]: Unset automount Arbitrary Executable File Formats File System Automount Point. Oct 29 20:26:24.475078 systemd[1]: Stopped target Local Verity Protected Volumes. Oct 29 20:26:24.476963 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 20:26:24.477991 systemd[1]: Stopping Network Name Resolution... Oct 29 20:26:24.478934 systemd[1]: Stopping Network Time Synchronization... Oct 29 20:26:24.479457 auditctl[1348]: No rules Oct 29 20:26:24.479811 systemd[1]: systemd-update-done.service: Deactivated successfully. Oct 29 20:26:24.479885 systemd[1]: Stopped Update is Completed. Oct 29 20:26:24.480732 systemd[1]: ldconfig.service: Deactivated successfully. Oct 29 20:26:24.480790 systemd[1]: Stopped Rebuild Dynamic Linker Cache. Oct 29 20:26:24.481625 systemd[1]: systemd-hwdb-update.service: Deactivated successfully. Oct 29 20:26:24.481689 systemd[1]: Stopped Rebuild Hardware Database. Oct 29 20:26:24.482490 systemd[1]: systemd-journal-catalog-update.service: Deactivated successfully. Oct 29 20:26:24.482550 systemd[1]: Stopped Rebuild Journal Catalog. Oct 29 20:26:24.484209 systemd[1]: Stopping Record System Boot/Shutdown in UTMP... Oct 29 20:26:24.486851 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 29 20:26:24.487105 systemd[1]: Stopped Network Name Resolution. Oct 29 20:26:24.488185 systemd[1]: systemd-timesyncd.service: Deactivated successfully. Oct 29 20:26:24.488452 systemd[1]: Stopped Network Time Synchronization. Oct 29 20:26:24.489632 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 20:26:24.489854 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 20:26:24.492150 systemd[1]: Stopping Network Configuration... Oct 29 20:26:24.493137 systemd[1]: systemd-update-utmp.service: Deactivated successfully. Oct 29 20:26:24.493372 systemd[1]: Stopped Record System Boot/Shutdown in UTMP. Oct 29 20:26:24.494871 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 29 20:26:24.494975 systemd[1]: Stopped Create Volatile Files and Directories. Oct 29 20:26:24.496118 systemd[1]: Stopped target Local File Systems. Oct 29 20:26:24.504322 systemd-networkd[838]: eth0: DHCPv6 lease lost Oct 29 20:26:24.508000 audit: BPF prog-id=63 op=UNLOAD Oct 29 20:26:24.508000 audit: BPF prog-id=62 op=UNLOAD Oct 29 20:26:24.509156 systemd[1]: Unmounting /etc/flatcar/update-payload-key.pub.pem... Oct 29 20:26:24.509946 kernel: audit: type=1334 audit(1761769584.508:365): prog-id=63 op=UNLOAD Oct 29 20:26:24.509984 kernel: audit: type=1334 audit(1761769584.508:366): prog-id=62 op=UNLOAD Oct 29 20:26:24.511420 systemd[1]: Unmounting External Media Directory... Oct 29 20:26:24.513009 systemd[1]: Unmounting /run/credentials/systemd-sysusers.service... Oct 29 20:26:24.514733 systemd[1]: Unmounting /run/torcx/unpack... Oct 29 20:26:24.516081 systemd[1]: Unmounting Temporary Directory /tmp... Oct 29 20:26:24.517401 systemd[1]: Unmounting /usr/share/oem... Oct 29 20:26:24.518889 systemd[1]: Unmounting /usr/share/update_engine/update-payload-key.pub.pem... Oct 29 20:26:24.521729 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 29 20:26:24.521978 systemd[1]: Stopped Network Configuration. Oct 29 20:26:24.522949 systemd[1]: etc-flatcar-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 20:26:24.523174 systemd[1]: Unmounted /etc/flatcar/update-payload-key.pub.pem. Oct 29 20:26:24.524513 systemd[1]: media.mount: Deactivated successfully. Oct 29 20:26:24.524750 systemd[1]: Unmounted External Media Directory. Oct 29 20:26:24.525674 systemd[1]: run-credentials-systemd\x2dsysusers.service.mount: Deactivated successfully. Oct 29 20:26:24.525907 systemd[1]: Unmounted /run/credentials/systemd-sysusers.service. Oct 29 20:26:24.536374 systemd[1]: tmp.mount: Deactivated successfully. Oct 29 20:26:24.536643 systemd[1]: Unmounted Temporary Directory /tmp. Oct 29 20:26:24.537615 systemd[1]: run-torcx-unpack.mount: Deactivated successfully. Oct 29 20:26:24.537847 systemd[1]: Unmounted /run/torcx/unpack. Oct 29 20:26:24.538780 systemd[1]: usr-share-oem.mount: Deactivated successfully. Oct 29 20:26:24.539047 systemd[1]: Unmounted /usr/share/oem. Oct 29 20:26:24.539907 systemd[1]: usr-share-update_engine-update\x2dpayload\x2dkey.pub.pem.mount: Deactivated successfully. Oct 29 20:26:24.540143 systemd[1]: Unmounted /usr/share/update_engine/update-payload-key.pub.pem. Oct 29 20:26:24.541529 systemd[1]: Stopped target Swaps. Oct 29 20:26:24.542213 systemd[1]: Reached target Unmount All Filesystems. Oct 29 20:26:24.543109 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 29 20:26:24.543168 systemd[1]: Closed Network Service Netlink Socket. Oct 29 20:26:24.542000 audit: BPF prog-id=64 op=UNLOAD Oct 29 20:26:24.544991 systemd[1]: systemd-fsck@dev-disk-by\x2dlabel-OEM.service: Deactivated successfully. Oct 29 20:26:24.545064 systemd[1]: Stopped File System Check on /dev/disk/by-label/OEM. Oct 29 20:26:24.545263 kernel: audit: type=1334 audit(1761769584.542:367): prog-id=64 op=UNLOAD Oct 29 20:26:24.546395 systemd[1]: Removed slice Slice /system/systemd-fsck. Oct 29 20:26:24.547288 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 20:26:24.548172 systemd[1]: lvm2-activation.service: Deactivated successfully. Oct 29 20:26:24.548265 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 20:26:24.549094 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 20:26:24.549944 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 20:26:24.550010 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 20:26:24.551082 systemd[1]: systemd-ask-password-wall.path: Deactivated successfully. Oct 29 20:26:24.554322 systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. Oct 29 20:26:24.555477 systemd[1]: lvm2-activation-early.service: Deactivated successfully. Oct 29 20:26:24.555557 systemd[1]: Stopped Activation of LVM2 logical volumes. Oct 29 20:26:24.556512 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 20:26:24.556569 systemd[1]: Stopped Apply Kernel Variables. Oct 29 20:26:24.557364 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 20:26:24.557425 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 20:26:24.558368 systemd[1]: systemd-sysusers.service: Deactivated successfully. Oct 29 20:26:24.558428 systemd[1]: Stopped Create System Users. Oct 29 20:26:24.559221 systemd[1]: systemd-remount-fs.service: Deactivated successfully. Oct 29 20:26:24.559295 systemd[1]: Stopped Remount Root and Kernel File Systems. Oct 29 20:26:24.560218 systemd[1]: Reached target System Shutdown. Oct 29 20:26:24.561008 systemd[1]: Reached target Late Shutdown Services. Oct 29 20:26:24.562051 systemd[1]: systemd-reboot.service: Deactivated successfully. Oct 29 20:26:24.562089 systemd[1]: Finished System Reboot. Oct 29 20:26:24.562835 systemd[1]: Reached target System Reboot. Oct 29 20:26:24.563623 systemd[1]: Shutting down. Oct 29 20:26:24.565000 audit: BPF prog-id=55 op=UNLOAD Oct 29 20:26:24.565000 audit: BPF prog-id=54 op=UNLOAD Oct 29 20:26:24.565000 audit: BPF prog-id=53 op=UNLOAD Oct 29 20:26:24.565000 audit: BPF prog-id=58 op=UNLOAD Oct 29 20:26:24.565000 audit: BPF prog-id=57 op=UNLOAD Oct 29 20:26:24.565000 audit: BPF prog-id=56 op=UNLOAD Oct 29 20:26:24.567255 kernel: audit: type=1334 audit(1761769584.565:368): prog-id=55 op=UNLOAD Oct 29 20:26:24.566000 audit: BPF prog-id=61 op=UNLOAD Oct 29 20:26:24.566000 audit: BPF prog-id=60 op=UNLOAD Oct 29 20:26:24.606255 systemd-shutdown[1]: Syncing filesystems and block devices. Oct 29 20:26:24.612687 systemd-shutdown[1]: Sending SIGTERM to remaining processes... Oct 29 20:26:24.612737 systemd-journald[801]: Journal stopped -- Reboot -- Oct 29 20:26:31.668956 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 29 20:26:31.668973 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 29 20:26:31.668980 kernel: efi: EFI v2.70 by EDK II Oct 29 20:26:31.668986 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdb82c018 ACPI 2.0=0xdbfd0018 RNG=0xdcfdd318 MEMRESERVE=0xdb834c18 Oct 29 20:26:31.668990 kernel: efi: seeding entropy pool Oct 29 20:26:31.668995 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 29 20:26:31.669001 kernel: ACPI: Early table checksum verification disabled Oct 29 20:26:31.669006 kernel: ACPI: RSDP 0x00000000DBFD0018 000024 (v02 BOCHS ) Oct 29 20:26:31.669011 kernel: ACPI: XSDT 0x00000000DBFD0F18 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 29 20:26:31.669016 kernel: ACPI: FACP 0x00000000DBFD0B18 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669022 kernel: ACPI: DSDT 0x00000000DBF0E018 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669026 kernel: ACPI: APIC 0x00000000DBFD0C98 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669031 kernel: ACPI: PPTT 0x00000000DBFD0098 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669036 kernel: ACPI: GTDT 0x00000000DBFD0818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669042 kernel: ACPI: MCFG 0x00000000DBFD0A98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669047 kernel: ACPI: SPCR 0x00000000DBFD0918 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669054 kernel: ACPI: DBG2 0x00000000DBFD0998 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669059 kernel: ACPI: IORT 0x00000000DBFD0198 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 29 20:26:31.669064 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 29 20:26:31.669078 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 29 20:26:31.669084 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:26:31.669089 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 29 20:26:31.669095 kernel: Zone ranges: Oct 29 20:26:31.669100 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:26:31.669105 kernel: DMA32 empty Oct 29 20:26:31.669109 kernel: Normal empty Oct 29 20:26:31.669115 kernel: Movable zone start for each node Oct 29 20:26:31.669121 kernel: Early memory node ranges Oct 29 20:26:31.669126 kernel: node 0: [mem 0x0000000040000000-0x00000000dc09ffff] Oct 29 20:26:31.669132 kernel: node 0: [mem 0x00000000dc0a0000-0x00000000dc2dffff] Oct 29 20:26:31.669137 kernel: node 0: [mem 0x00000000dc2e0000-0x00000000dc36ffff] Oct 29 20:26:31.669142 kernel: node 0: [mem 0x00000000dc370000-0x00000000dc45ffff] Oct 29 20:26:31.669147 kernel: node 0: [mem 0x00000000dc460000-0x00000000dc52ffff] Oct 29 20:26:31.669152 kernel: node 0: [mem 0x00000000dc530000-0x00000000dc5cffff] Oct 29 20:26:31.669157 kernel: node 0: [mem 0x00000000dc5d0000-0x00000000dce1ffff] Oct 29 20:26:31.669162 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 29 20:26:31.669167 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 29 20:26:31.669172 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 29 20:26:31.669177 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 29 20:26:31.669182 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 29 20:26:31.669190 kernel: On node 0 totalpages: 643072 Oct 29 20:26:31.669196 kernel: DMA zone: 10048 pages used for memmap Oct 29 20:26:31.669201 kernel: DMA zone: 0 pages reserved Oct 29 20:26:31.669207 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 29 20:26:31.669212 kernel: psci: probing for conduit method from ACPI. Oct 29 20:26:31.669217 kernel: psci: PSCIv1.1 detected in firmware. Oct 29 20:26:31.669223 kernel: psci: Using standard PSCI v0.2 function IDs Oct 29 20:26:31.669234 kernel: psci: Trusted OS migration not required Oct 29 20:26:31.669240 kernel: psci: SMC Calling Convention v1.1 Oct 29 20:26:31.669246 kernel: ACPI: SRAT not present Oct 29 20:26:31.669251 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 29 20:26:31.669257 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 29 20:26:31.669262 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 29 20:26:31.669268 kernel: Detected PIPT I-cache on CPU0 Oct 29 20:26:31.669273 kernel: CPU features: detected: GIC system register CPU interface Oct 29 20:26:31.669279 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 29 20:26:31.669284 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 29 20:26:31.669290 kernel: CPU features: detected: Hardware dirty bit management Oct 29 20:26:31.669295 kernel: CPU features: detected: Spectre-v4 Oct 29 20:26:31.669302 kernel: CPU features: detected: ARM erratum 1418040 Oct 29 20:26:31.669308 kernel: CPU features: detected: Spectre-BHB Oct 29 20:26:31.669313 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 29 20:26:31.669319 kernel: Policy zone: DMA Oct 29 20:26:31.669325 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 29 20:26:31.669331 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 29 20:26:31.669337 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 29 20:26:31.669342 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 29 20:26:31.669348 kernel: Memory: 2452100K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120188K reserved, 0K cma-reserved) Oct 29 20:26:31.669354 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 29 20:26:31.669359 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 29 20:26:31.669366 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 29 20:26:31.669372 kernel: rcu: RCU event tracing is enabled. Oct 29 20:26:31.669377 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 29 20:26:31.669383 kernel: Trampoline variant of Tasks RCU enabled. Oct 29 20:26:31.669388 kernel: Tracing variant of Tasks RCU enabled. Oct 29 20:26:31.669394 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 29 20:26:31.669400 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 29 20:26:31.669405 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 29 20:26:31.669410 kernel: GICv3: 256 SPIs implemented Oct 29 20:26:31.669416 kernel: GICv3: 0 Extended SPIs implemented Oct 29 20:26:31.669421 kernel: GICv3: Distributor has no Range Selector support Oct 29 20:26:31.669426 kernel: GICv3: 16 PPIs implemented Oct 29 20:26:31.669433 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 29 20:26:31.669438 kernel: ACPI: SRAT not present Oct 29 20:26:31.669444 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 29 20:26:31.669449 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 29 20:26:31.669455 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 29 20:26:31.669460 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 29 20:26:31.669466 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 29 20:26:31.669471 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:26:31.669477 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 29 20:26:31.669482 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 29 20:26:31.669488 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 29 20:26:31.669494 kernel: arm-pv: using stolen time PV Oct 29 20:26:31.669501 kernel: Console: colour dummy device 80x25 Oct 29 20:26:31.669506 kernel: ACPI: Core revision 20200925 Oct 29 20:26:31.669512 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 29 20:26:31.669518 kernel: pid_max: default: 32768 minimum: 301 Oct 29 20:26:31.669524 kernel: LSM: Security Framework initializing Oct 29 20:26:31.669529 kernel: SELinux: Initializing. Oct 29 20:26:31.669535 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 20:26:31.669541 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 29 20:26:31.669546 kernel: rcu: Hierarchical SRCU implementation. Oct 29 20:26:31.669553 kernel: Platform MSI: ITS@0x8080000 domain created Oct 29 20:26:31.669558 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 29 20:26:31.669564 kernel: Remapping and enabling EFI services. Oct 29 20:26:31.669570 kernel: smp: Bringing up secondary CPUs ... Oct 29 20:26:31.669575 kernel: Detected PIPT I-cache on CPU1 Oct 29 20:26:31.669581 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 29 20:26:31.669587 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 29 20:26:31.669593 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:26:31.669598 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 29 20:26:31.669604 kernel: Detected PIPT I-cache on CPU2 Oct 29 20:26:31.669611 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 29 20:26:31.669617 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 29 20:26:31.669623 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:26:31.669629 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 29 20:26:31.669635 kernel: Detected PIPT I-cache on CPU3 Oct 29 20:26:31.669644 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 29 20:26:31.669650 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 29 20:26:31.669657 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 29 20:26:31.669663 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 29 20:26:31.669669 kernel: smp: Brought up 1 node, 4 CPUs Oct 29 20:26:31.669675 kernel: SMP: Total of 4 processors activated. Oct 29 20:26:31.669681 kernel: CPU features: detected: Privileged Access Never Oct 29 20:26:31.669686 kernel: CPU features: detected: LSE atomic instructions Oct 29 20:26:31.669692 kernel: CPU features: detected: User Access Override Oct 29 20:26:31.669698 kernel: CPU features: detected: 32-bit EL0 Support Oct 29 20:26:31.669704 kernel: CPU features: detected: Common not Private translations Oct 29 20:26:31.669710 kernel: CPU features: detected: RAS Extension Support Oct 29 20:26:31.669717 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 29 20:26:31.669723 kernel: CPU features: detected: CRC32 instructions Oct 29 20:26:31.669729 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 29 20:26:31.669735 kernel: CPU: All CPU(s) started at EL1 Oct 29 20:26:31.669741 kernel: alternatives: patching kernel code Oct 29 20:26:31.669748 kernel: devtmpfs: initialized Oct 29 20:26:31.669754 kernel: KASLR enabled Oct 29 20:26:31.669760 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 29 20:26:31.669766 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 29 20:26:31.669772 kernel: pinctrl core: initialized pinctrl subsystem Oct 29 20:26:31.669778 kernel: SMBIOS 3.0.0 present. Oct 29 20:26:31.669784 kernel: DMI: QEMU KVM Virtual Machine, BIOS unknown 02/02/2022 Oct 29 20:26:31.669790 kernel: NET: Registered protocol family 16 Oct 29 20:26:31.669796 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 29 20:26:31.669802 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 29 20:26:31.669809 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 29 20:26:31.669815 kernel: audit: initializing netlink subsys (disabled) Oct 29 20:26:31.669821 kernel: audit: type=2000 audit(0.030:1): state=initialized audit_enabled=0 res=1 Oct 29 20:26:31.669827 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 29 20:26:31.669833 kernel: cpuidle: using governor menu Oct 29 20:26:31.669839 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 29 20:26:31.669845 kernel: ASID allocator initialised with 32768 entries Oct 29 20:26:31.669850 kernel: ACPI: bus type PCI registered Oct 29 20:26:31.669856 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 29 20:26:31.669863 kernel: Serial: AMBA PL011 UART driver Oct 29 20:26:31.669869 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 29 20:26:31.669876 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 29 20:26:31.669881 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 29 20:26:31.669887 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 29 20:26:31.669893 kernel: cryptd: max_cpu_qlen set to 1000 Oct 29 20:26:31.669899 kernel: ACPI: Added _OSI(Module Device) Oct 29 20:26:31.669905 kernel: ACPI: Added _OSI(Processor Device) Oct 29 20:26:31.669911 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 29 20:26:31.669917 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 29 20:26:31.669924 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 29 20:26:31.669930 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 29 20:26:31.669936 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 29 20:26:31.669942 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 29 20:26:31.669948 kernel: ACPI: Interpreter enabled Oct 29 20:26:31.669953 kernel: ACPI: Using GIC for interrupt routing Oct 29 20:26:31.669959 kernel: ACPI: MCFG table detected, 1 entries Oct 29 20:26:31.669965 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 29 20:26:31.669971 kernel: printk: console [ttyAMA0] enabled Oct 29 20:26:31.669978 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 29 20:26:31.670096 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 29 20:26:31.670159 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 29 20:26:31.670211 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 29 20:26:31.670270 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 29 20:26:31.670321 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 29 20:26:31.670329 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 29 20:26:31.670337 kernel: PCI host bridge to bus 0000:00 Oct 29 20:26:31.670395 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 29 20:26:31.670444 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 29 20:26:31.670492 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 29 20:26:31.670539 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 29 20:26:31.670604 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 29 20:26:31.670673 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 29 20:26:31.670734 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 29 20:26:31.670789 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 29 20:26:31.670843 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 20:26:31.670897 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 29 20:26:31.670950 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 29 20:26:31.671004 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 29 20:26:31.671051 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 29 20:26:31.671110 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 29 20:26:31.671157 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 29 20:26:31.671165 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 29 20:26:31.671172 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 29 20:26:31.671178 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 29 20:26:31.671184 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 29 20:26:31.671189 kernel: vgaarb: loaded Oct 29 20:26:31.671195 kernel: Registered efivars operations Oct 29 20:26:31.671202 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 29 20:26:31.671209 kernel: VFS: Disk quotas dquot_6.6.0 Oct 29 20:26:31.671216 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 29 20:26:31.671221 kernel: pnp: PnP ACPI init Oct 29 20:26:31.671290 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 29 20:26:31.671340 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 29 20:26:31.671348 kernel: pnp: PnP ACPI: found 1 devices Oct 29 20:26:31.671354 kernel: NET: Registered protocol family 2 Oct 29 20:26:31.671360 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 29 20:26:31.671367 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 29 20:26:31.671374 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 29 20:26:31.671380 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 29 20:26:31.671386 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 29 20:26:31.671392 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 20:26:31.671398 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 29 20:26:31.671404 kernel: NET: Registered protocol family 1 Oct 29 20:26:31.671410 kernel: PCI: CLS 0 bytes, default 64 Oct 29 20:26:31.671416 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 29 20:26:31.671423 kernel: kvm [1]: HYP mode not available Oct 29 20:26:31.671430 kernel: Initialise system trusted keyrings Oct 29 20:26:31.671435 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 29 20:26:31.671441 kernel: Key type asymmetric registered Oct 29 20:26:31.671447 kernel: Asymmetric key parser 'x509' registered Oct 29 20:26:31.671453 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 29 20:26:31.671459 kernel: io scheduler mq-deadline registered Oct 29 20:26:31.671465 kernel: io scheduler kyber registered Oct 29 20:26:31.671471 kernel: io scheduler bfq registered Oct 29 20:26:31.671478 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 29 20:26:31.671484 kernel: ACPI: Power Button [PWRB] Oct 29 20:26:31.671490 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 29 20:26:31.671545 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 29 20:26:31.671553 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 29 20:26:31.671559 kernel: msm_serial: driver initialized Oct 29 20:26:31.671565 kernel: random: fast init done Oct 29 20:26:31.671570 kernel: random: crng init done Oct 29 20:26:31.671576 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 29 20:26:31.671583 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 29 20:26:31.671590 kernel: thunder_xcv, ver 1.0 Oct 29 20:26:31.671595 kernel: thunder_bgx, ver 1.0 Oct 29 20:26:31.671601 kernel: nicpf, ver 1.0 Oct 29 20:26:31.671607 kernel: nicvf, ver 1.0 Oct 29 20:26:31.671666 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 29 20:26:31.671716 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-10-29T20:26:31 UTC (1761769591) Oct 29 20:26:31.671724 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 29 20:26:31.671730 kernel: NET: Registered protocol family 10 Oct 29 20:26:31.671737 kernel: Segment Routing with IPv6 Oct 29 20:26:31.671743 kernel: NET: Registered protocol family 17 Oct 29 20:26:31.671749 kernel: Key type dns_resolver registered Oct 29 20:26:31.671755 kernel: registered taskstats version 1 Oct 29 20:26:31.671761 kernel: Loading compiled-in X.509 certificates Oct 29 20:26:31.671767 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 29 20:26:31.671774 kernel: Key type ._fscrypt registered Oct 29 20:26:31.671780 kernel: Key type .fscrypt registered Oct 29 20:26:31.671786 kernel: Key type fscrypt-provisioning registered Oct 29 20:26:31.671793 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 29 20:26:31.671799 kernel: ima: Allocated hash algorithm: sha1 Oct 29 20:26:31.671805 kernel: ima: No architecture policies found Oct 29 20:26:31.671811 kernel: Freeing unused kernel memory: 34240K Oct 29 20:26:31.671817 kernel: Run /init as init process Oct 29 20:26:31.671823 kernel: with arguments: Oct 29 20:26:31.671828 kernel: /init Oct 29 20:26:31.671834 kernel: with environment: Oct 29 20:26:31.671840 kernel: HOME=/ Oct 29 20:26:31.671846 kernel: TERM=linux Oct 29 20:26:31.671853 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 29 20:26:31.671861 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 29 20:26:31.671869 systemd[1]: Detected virtualization kvm. Oct 29 20:26:31.671876 systemd[1]: Detected architecture arm64. Oct 29 20:26:31.671882 systemd[1]: Running in initial RAM disk. Oct 29 20:26:31.671888 systemd[1]: No hostname configured, using default hostname. Oct 29 20:26:31.671895 systemd[1]: Hostname set to . Oct 29 20:26:31.671902 systemd[1]: Initializing machine ID from VM UUID. Oct 29 20:26:31.671909 systemd[1]: Queued start job for default target Initrd Default Target. Oct 29 20:26:31.671915 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 20:26:31.671922 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 20:26:31.671928 systemd[1]: Reached target Path Units. Oct 29 20:26:31.671934 systemd[1]: Reached target Slice Units. Oct 29 20:26:31.671940 systemd[1]: Reached target Swaps. Oct 29 20:26:31.671947 systemd[1]: Reached target Timer Units. Oct 29 20:26:31.671954 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 29 20:26:31.671961 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 29 20:26:31.671967 systemd[1]: Listening on Journal Audit Socket. Oct 29 20:26:31.671974 systemd[1]: Listening on Journal Socket (/dev/log). Oct 29 20:26:31.671980 systemd[1]: Listening on Journal Socket. Oct 29 20:26:31.671986 systemd[1]: Listening on udev Control Socket. Oct 29 20:26:31.671992 systemd[1]: Listening on udev Kernel Socket. Oct 29 20:26:31.671999 systemd[1]: Reached target Socket Units. Oct 29 20:26:31.672005 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 29 20:26:31.672012 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 20:26:31.672019 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 29 20:26:31.672025 systemd[1]: Starting Journal Service... Oct 29 20:26:31.672032 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 20:26:31.672038 systemd[1]: Starting Apply Kernel Variables... Oct 29 20:26:31.672045 systemd[1]: Starting Setup Virtual Console... Oct 29 20:26:31.672051 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 29 20:26:31.672057 kernel: SCSI subsystem initialized Oct 29 20:26:31.672064 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 20:26:31.672077 systemd[1]: Finished Apply Kernel Variables. Oct 29 20:26:31.672086 kernel: Loading iSCSI transport class v2.0-870. Oct 29 20:26:31.672092 systemd[1]: Finished Setup Virtual Console. Oct 29 20:26:31.672101 systemd-journald[269]: Journal started Oct 29 20:26:31.672135 systemd-journald[269]: Runtime Journal (/run/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 6.0M, max 48.5M, 42.4M free. Oct 29 20:26:31.674342 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 29 20:26:31.677098 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 20:26:31.677140 systemd[1]: Started Journal Service. Oct 29 20:26:31.678198 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 20:26:31.689663 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 29 20:26:31.691236 systemd[1]: Starting dracut cmdline hook... Oct 29 20:26:31.699672 dracut-cmdline[290]: dracut-dracut-053 Oct 29 20:26:31.701850 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 29 20:26:31.756109 kernel: iscsi: registered transport (tcp) Oct 29 20:26:31.767215 kernel: iscsi: registered transport (qla4xxx) Oct 29 20:26:31.767279 kernel: QLogic iSCSI HBA Driver Oct 29 20:26:31.776657 systemd[1]: Finished dracut cmdline hook. Oct 29 20:26:31.778009 systemd[1]: Starting dracut pre-udev hook... Oct 29 20:26:31.779185 systemd[1]: Starting Open-iSCSI... Oct 29 20:26:31.781345 iscsid[376]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 29 20:26:31.781345 iscsid[376]: 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 29 20:26:31.781345 iscsid[376]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 29 20:26:31.781345 iscsid[376]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 29 20:26:31.781345 iscsid[376]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 29 20:26:31.781345 iscsid[376]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 29 20:26:31.784841 systemd[1]: Started Open-iSCSI. Oct 29 20:26:31.794265 kernel: device-mapper: uevent: version 1.0.3 Oct 29 20:26:31.794281 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 29 20:26:31.829098 kernel: raid6: neonx8 gen() 13773 MB/s Oct 29 20:26:31.846096 kernel: raid6: neonx8 xor() 10726 MB/s Oct 29 20:26:31.863090 kernel: raid6: neonx4 gen() 13583 MB/s Oct 29 20:26:31.880093 kernel: raid6: neonx4 xor() 11526 MB/s Oct 29 20:26:31.897092 kernel: raid6: neonx2 gen() 12984 MB/s Oct 29 20:26:31.914100 kernel: raid6: neonx2 xor() 9869 MB/s Oct 29 20:26:31.931090 kernel: raid6: neonx1 gen() 10870 MB/s Oct 29 20:26:31.948091 kernel: raid6: neonx1 xor() 8803 MB/s Oct 29 20:26:31.965091 kernel: raid6: int64x8 gen() 6830 MB/s Oct 29 20:26:31.982101 kernel: raid6: int64x8 xor() 3799 MB/s Oct 29 20:26:31.999090 kernel: raid6: int64x4 gen() 6895 MB/s Oct 29 20:26:32.016092 kernel: raid6: int64x4 xor() 3834 MB/s Oct 29 20:26:32.033092 kernel: raid6: int64x2 gen() 6184 MB/s Oct 29 20:26:32.050092 kernel: raid6: int64x2 xor() 3309 MB/s Oct 29 20:26:32.067092 kernel: raid6: int64x1 gen() 5002 MB/s Oct 29 20:26:32.084133 kernel: raid6: int64x1 xor() 2597 MB/s Oct 29 20:26:32.084150 kernel: raid6: using algorithm neonx8 gen() 13773 MB/s Oct 29 20:26:32.084164 kernel: raid6: .... xor() 10726 MB/s, rmw enabled Oct 29 20:26:32.085166 kernel: raid6: using neon recovery algorithm Oct 29 20:26:32.095091 kernel: xor: measuring software checksum speed Oct 29 20:26:32.096584 kernel: 8regs : 17148 MB/sec Oct 29 20:26:32.096596 kernel: 32regs : 20781 MB/sec Oct 29 20:26:32.097452 kernel: arm64_neon : 29173 MB/sec Oct 29 20:26:32.097474 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 29 20:26:32.143101 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 29 20:26:32.152840 systemd[1]: Finished dracut pre-udev hook. Oct 29 20:26:32.154641 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 20:26:32.158713 kernel: audit: type=1334 audit(1761769592.154:2): prog-id=6 op=LOAD Oct 29 20:26:32.158732 kernel: audit: type=1334 audit(1761769592.154:3): prog-id=7 op=LOAD Oct 29 20:26:32.158747 kernel: audit: type=1334 audit(1761769592.154:4): prog-id=8 op=LOAD Oct 29 20:26:32.154000 audit: BPF prog-id=6 op=LOAD Oct 29 20:26:32.154000 audit: BPF prog-id=7 op=LOAD Oct 29 20:26:32.154000 audit: BPF prog-id=8 op=LOAD Oct 29 20:26:32.167855 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 20:26:32.169513 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 20:26:32.171126 systemd[1]: Starting dracut pre-trigger hook... Oct 29 20:26:32.183271 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Oct 29 20:26:32.206583 systemd[1]: Finished dracut pre-trigger hook. Oct 29 20:26:32.208011 systemd[1]: Starting Coldplug All udev Devices... Oct 29 20:26:32.217631 systemd-udevd[409]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 29 20:26:32.237347 systemd[1]: Finished Coldplug All udev Devices. Oct 29 20:26:32.238784 systemd[1]: Starting dracut initqueue hook... Oct 29 20:26:32.256258 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 29 20:26:32.256380 kernel: vda: detected capacity change from 0 to 4756340736 Oct 29 20:26:32.263793 systemd-udevd[469]: Using default interface naming scheme 'v249'. Oct 29 20:26:32.268096 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 29 20:26:32.296338 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 29 20:26:32.298499 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by systemd-udevd (463) Oct 29 20:26:32.297419 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 29 20:26:32.303149 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 29 20:26:32.309042 systemd[1]: Reached target Initrd Root Device. Oct 29 20:26:32.310521 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 29 20:26:32.311639 systemd[1]: Finished dracut initqueue hook. Oct 29 20:26:32.312942 systemd[1]: Reached target Preparation for Remote File Systems. Oct 29 20:26:32.314310 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 20:26:32.315838 systemd[1]: Reached target Remote File Systems. Oct 29 20:26:32.317826 systemd[1]: Starting dracut pre-mount hook... Oct 29 20:26:32.318705 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 29 20:26:32.318818 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 29 20:26:32.320142 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 20:26:32.322250 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 29 20:26:32.326574 systemd[1]: Finished dracut pre-mount hook. Oct 29 20:26:32.328040 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 29 20:26:32.334106 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 29 20:26:32.338739 systemd-fsck[508]: ROOT: clean, 640/553792 files, 39961/553472 blocks Oct 29 20:26:32.340756 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 29 20:26:32.344589 systemd[1]: Mounting /sysroot... Oct 29 20:26:32.354826 systemd[1]: Found device /dev/mapper/usr. Oct 29 20:26:32.356767 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 29 20:26:32.360618 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 29 20:26:32.366859 systemd-fsck[519]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 29 20:26:32.366859 systemd-fsck[519]: You must have r/w access to the filesystem or be root Oct 29 20:26:32.369333 systemd-fsck[515]: fsck failed with exit status 8. Oct 29 20:26:32.369340 systemd-fsck[515]: Ignoring error. Oct 29 20:26:32.372062 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 29 20:26:32.376292 systemd[1]: Mounting /sysusr/usr... Oct 29 20:26:32.384132 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 29 20:26:32.384554 systemd[1]: Mounted /sysusr/usr. Oct 29 20:26:32.387543 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 29 20:26:32.387560 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 29 20:26:32.385243 systemd[1]: Reached target Local File Systems. Oct 29 20:26:32.388215 systemd[1]: Reached target System Initialization. Oct 29 20:26:32.389560 systemd[1]: Reached target Basic System. Oct 29 20:26:32.391399 systemd[1]: Mounted /sysroot. Oct 29 20:26:32.392040 systemd[1]: Reached target Initrd Root File System. Oct 29 20:26:32.394569 systemd[1]: Mounting /sysroot/usr... Oct 29 20:26:32.395731 systemd[1]: Starting Reload Configuration from the Real Root... Oct 29 20:26:32.397184 systemd[1]: Mounted /sysroot/usr. Oct 29 20:26:32.399837 systemd[1]: Reloading. Oct 29 20:26:32.400000 audit: BPF prog-id=6 op=UNLOAD Oct 29 20:26:32.402087 kernel: audit: type=1334 audit(1761769592.400:5): prog-id=6 op=UNLOAD Oct 29 20:26:32.408000 audit: BPF prog-id=3 op=UNLOAD Oct 29 20:26:32.414101 kernel: audit: type=1334 audit(1761769592.408:6): prog-id=3 op=UNLOAD Oct 29 20:26:32.482000 audit: BPF prog-id=9 op=LOAD Oct 29 20:26:32.483000 audit: BPF prog-id=10 op=LOAD Oct 29 20:26:32.484663 kernel: audit: type=1334 audit(1761769592.482:7): prog-id=9 op=LOAD Oct 29 20:26:32.484685 kernel: audit: type=1334 audit(1761769592.483:8): prog-id=10 op=LOAD Oct 29 20:26:32.484694 kernel: audit: type=1334 audit(1761769592.484:9): prog-id=11 op=LOAD Oct 29 20:26:32.484000 audit: BPF prog-id=11 op=LOAD Oct 29 20:26:32.485412 kernel: audit: type=1334 audit(1761769592.484:10): prog-id=7 op=UNLOAD Oct 29 20:26:32.484000 audit: BPF prog-id=7 op=UNLOAD Oct 29 20:26:32.484000 audit: BPF prog-id=8 op=UNLOAD Oct 29 20:26:32.485000 audit: BPF prog-id=12 op=LOAD Oct 29 20:26:32.486000 audit: BPF prog-id=13 op=LOAD Oct 29 20:26:32.486000 audit: BPF prog-id=14 op=LOAD Oct 29 20:26:32.486000 audit: BPF prog-id=4 op=UNLOAD Oct 29 20:26:32.486000 audit: BPF prog-id=5 op=UNLOAD Oct 29 20:26:32.499920 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 29 20:26:32.500051 systemd[1]: Finished Reload Configuration from the Real Root. Oct 29 20:26:32.501831 systemd[1]: Reached target Initrd File Systems. Oct 29 20:26:32.503282 systemd[1]: Reached target Initrd Default Target. Oct 29 20:26:32.504656 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 29 20:26:32.505325 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 29 20:26:32.515616 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 29 20:26:32.517141 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 29 20:26:32.522663 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 29 20:26:32.523591 systemd[1]: Stopped target Timer Units. Oct 29 20:26:32.524848 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 29 20:26:32.524957 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 29 20:26:32.526211 systemd[1]: Stopped target Initrd Default Target. Oct 29 20:26:32.527688 systemd[1]: Stopped target Basic System. Oct 29 20:26:32.528980 systemd[1]: Stopped target Initrd Root Device. Oct 29 20:26:32.530309 systemd[1]: Stopped target Path Units. Oct 29 20:26:32.531466 systemd[1]: Stopped target Remote File Systems. Oct 29 20:26:32.532765 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 29 20:26:32.534115 systemd[1]: Stopped target Slice Units. Oct 29 20:26:32.535350 systemd[1]: Stopped target Socket Units. Oct 29 20:26:32.536743 systemd[1]: Stopped target System Initialization. Oct 29 20:26:32.537971 systemd[1]: Stopped target Local File Systems. Oct 29 20:26:32.539180 systemd[1]: Stopped target Preparation for Local File Systems. Oct 29 20:26:32.540704 systemd[1]: Stopped target Swaps. Oct 29 20:26:32.541828 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 29 20:26:32.541931 systemd[1]: Stopped dracut pre-mount hook. Oct 29 20:26:32.543310 systemd[1]: Stopped target Local Encrypted Volumes. Oct 29 20:26:32.544434 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 29 20:26:32.548128 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 29 20:26:32.549166 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 29 20:26:32.549282 systemd[1]: Stopped dracut initqueue hook. Oct 29 20:26:32.552630 iscsid[376]: iscsid shutting down. Oct 29 20:26:32.550909 systemd[1]: Stopping Open-iSCSI... Oct 29 20:26:32.551998 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 29 20:26:32.552130 systemd[1]: Stopped Apply Kernel Variables. Oct 29 20:26:32.553356 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 29 20:26:32.553443 systemd[1]: Stopped Coldplug All udev Devices. Oct 29 20:26:32.554537 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 29 20:26:32.554624 systemd[1]: Stopped dracut pre-trigger hook. Oct 29 20:26:32.555796 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 29 20:26:32.560687 systemd[1]: iscsid.service: Deactivated successfully. Oct 29 20:26:32.560805 systemd[1]: Stopped Open-iSCSI. Oct 29 20:26:32.562268 systemd[1]: iscsid.socket: Deactivated successfully. Oct 29 20:26:32.562315 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 29 20:26:32.563217 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 29 20:26:32.565486 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 29 20:26:32.565599 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 29 20:26:32.573000 audit: BPF prog-id=9 op=UNLOAD Oct 29 20:26:32.567181 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 29 20:26:32.567298 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 29 20:26:32.568348 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 29 20:26:32.568445 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 29 20:26:32.570411 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 29 20:26:32.570456 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 29 20:26:32.571875 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 29 20:26:32.571914 systemd[1]: Closed udev Control Socket. Oct 29 20:26:32.573333 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 29 20:26:32.573364 systemd[1]: Closed udev Kernel Socket. Oct 29 20:26:32.574763 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 29 20:26:32.574803 systemd[1]: Stopped dracut pre-udev hook. Oct 29 20:26:32.576087 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 29 20:26:32.576128 systemd[1]: Stopped dracut cmdline hook. Oct 29 20:26:32.577479 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 29 20:26:32.577517 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 29 20:26:32.579622 systemd[1]: Starting Cleanup udev Database... Oct 29 20:26:32.580564 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 29 20:26:32.581882 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 29 20:26:32.581938 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 29 20:26:32.583305 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 29 20:26:32.598000 audit: BPF prog-id=12 op=UNLOAD Oct 29 20:26:32.583348 systemd[1]: Stopped Create List of Static Device Nodes. Oct 29 20:26:32.584940 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 29 20:26:32.584984 systemd[1]: Stopped Setup Virtual Console. Oct 29 20:26:32.586379 systemd[1]: rngd.service: Deactivated successfully. Oct 29 20:26:32.586495 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 29 20:26:32.587984 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 29 20:26:32.588094 systemd[1]: Finished Cleanup udev Database. Oct 29 20:26:32.589326 systemd[1]: Reached target Switch Root. Oct 29 20:26:32.591184 systemd[1]: Starting Switch Root... Oct 29 20:26:32.597278 systemd[1]: Switching root. Oct 29 20:26:32.612406 systemd-journald[269]: Journal stopped Oct 29 20:26:34.681329 systemd-journald[269]: Received SIGTERM from PID 1 (systemd). Oct 29 20:26:34.681378 kernel: SELinux: policy capability network_peer_controls=1 Oct 29 20:26:34.681390 kernel: SELinux: policy capability open_perms=1 Oct 29 20:26:34.681399 kernel: SELinux: policy capability extended_socket_class=1 Oct 29 20:26:34.681408 kernel: SELinux: policy capability always_check_network=0 Oct 29 20:26:34.681417 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 29 20:26:34.681432 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 29 20:26:34.681440 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 29 20:26:34.681450 systemd[1]: Successfully loaded SELinux policy in 29.799ms. Oct 29 20:26:34.681462 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.014ms. Oct 29 20:26:34.681472 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 29 20:26:34.681483 systemd[1]: Detected virtualization kvm. Oct 29 20:26:34.681493 systemd[1]: Detected architecture arm64. Oct 29 20:26:34.681504 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 29 20:26:34.681516 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 29 20:26:34.681526 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 29 20:26:34.681536 systemd[1]: Stopped Switch Root. Oct 29 20:26:34.681545 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 29 20:26:34.681555 systemd[1]: Created slice Slice /system/addon-config. Oct 29 20:26:34.681564 systemd[1]: Created slice Slice /system/addon-run. Oct 29 20:26:34.681574 systemd[1]: Created slice Slice /system/getty. Oct 29 20:26:34.681583 systemd[1]: Created slice Slice /system/modprobe. Oct 29 20:26:34.681593 systemd[1]: Created slice Slice /system/serial-getty. Oct 29 20:26:34.681604 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 29 20:26:34.681614 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 29 20:26:34.681623 systemd[1]: Created slice User and Session Slice. Oct 29 20:26:34.681633 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 29 20:26:34.681643 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 29 20:26:34.681652 systemd[1]: Set up automount Boot partition Automount Point. Oct 29 20:26:34.681662 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 29 20:26:34.681672 systemd[1]: Stopped target Switch Root. Oct 29 20:26:34.681681 systemd[1]: Stopped target Initrd File Systems. Oct 29 20:26:34.681691 systemd[1]: Stopped target Initrd Root File System. Oct 29 20:26:34.681701 systemd[1]: Reached target Remote Encrypted Volumes. Oct 29 20:26:34.681711 systemd[1]: Reached target Remote File Systems. Oct 29 20:26:34.681720 systemd[1]: Reached target Slice Units. Oct 29 20:26:34.681729 systemd[1]: Reached target Swaps. Oct 29 20:26:34.681741 systemd[1]: Reached target Verify torcx succeeded. Oct 29 20:26:34.681751 systemd[1]: Reached target Local Verity Protected Volumes. Oct 29 20:26:34.681760 systemd[1]: Listening on Process Core Dump Socket. Oct 29 20:26:34.681770 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 29 20:26:34.681779 systemd[1]: Listening on Network Service Netlink Socket. Oct 29 20:26:34.681789 systemd[1]: Listening on udev Control Socket. Oct 29 20:26:34.681803 systemd[1]: Listening on udev Kernel Socket. Oct 29 20:26:34.681813 systemd[1]: Mounting Huge Pages File System... Oct 29 20:26:34.681823 systemd[1]: Mounting POSIX Message Queue File System... Oct 29 20:26:34.681833 systemd[1]: Mounting External Media Directory... Oct 29 20:26:34.681843 systemd[1]: Mounting Kernel Debug File System... Oct 29 20:26:34.681852 systemd[1]: Mounting Kernel Trace File System... Oct 29 20:26:34.681862 systemd[1]: Mounting Temporary Directory /tmp... Oct 29 20:26:34.681871 systemd[1]: Starting Create missing system files... Oct 29 20:26:34.681882 systemd[1]: Starting Create List of Static Device Nodes... Oct 29 20:26:34.681892 systemd[1]: Starting Load Kernel Module configfs... Oct 29 20:26:34.681901 systemd[1]: Starting Load Kernel Module drm... Oct 29 20:26:34.681911 systemd[1]: Starting Load Kernel Module fuse... Oct 29 20:26:34.681920 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 29 20:26:34.681931 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 29 20:26:34.681941 systemd[1]: Stopped File System Check on Root Device. Oct 29 20:26:34.681952 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 29 20:26:34.681962 systemd[1]: Stopped systemd-fsck-usr.service. Oct 29 20:26:34.681974 systemd[1]: Stopped Journal Service. Oct 29 20:26:34.681984 kernel: fuse: init (API version 7.32) Oct 29 20:26:34.681993 systemd[1]: Starting Journal Service... Oct 29 20:26:34.682004 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 29 20:26:34.682014 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 29 20:26:34.682023 systemd[1]: Starting Apply Kernel Variables... Oct 29 20:26:34.682032 systemd[1]: Starting Coldplug All udev Devices... Oct 29 20:26:34.682042 systemd[1]: verity-setup.service: Deactivated successfully. Oct 29 20:26:34.682052 systemd[1]: Stopped verity-setup.service. Oct 29 20:26:34.682062 systemd-journald[695]: Journal started Oct 29 20:26:34.682115 systemd-journald[695]: Runtime Journal (/run/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 6.0M, max 48.5M, 42.4M free. Oct 29 20:26:32.681000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 29 20:26:32.706000 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 29 20:26:32.706000 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 29 20:26:32.706000 audit: BPF prog-id=15 op=LOAD Oct 29 20:26:32.706000 audit: BPF prog-id=15 op=UNLOAD Oct 29 20:26:32.707000 audit: BPF prog-id=16 op=LOAD Oct 29 20:26:32.707000 audit: BPF prog-id=16 op=UNLOAD Oct 29 20:26:32.741000 audit[647]: AVC avc: denied { associate } for pid=647 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 29 20:26:34.539000 audit: BPF prog-id=17 op=LOAD Oct 29 20:26:34.539000 audit: BPF prog-id=18 op=LOAD Oct 29 20:26:34.539000 audit: BPF prog-id=19 op=LOAD Oct 29 20:26:34.539000 audit: BPF prog-id=13 op=UNLOAD Oct 29 20:26:34.539000 audit: BPF prog-id=14 op=UNLOAD Oct 29 20:26:34.554000 audit: BPF prog-id=20 op=LOAD Oct 29 20:26:34.554000 audit: BPF prog-id=17 op=UNLOAD Oct 29 20:26:34.555000 audit: BPF prog-id=21 op=LOAD Oct 29 20:26:34.555000 audit: BPF prog-id=22 op=LOAD Oct 29 20:26:34.555000 audit: BPF prog-id=18 op=UNLOAD Oct 29 20:26:34.555000 audit: BPF prog-id=19 op=UNLOAD Oct 29 20:26:34.565000 audit: BPF prog-id=20 op=UNLOAD Oct 29 20:26:34.667000 audit: BPF prog-id=23 op=LOAD Oct 29 20:26:34.668000 audit: BPF prog-id=24 op=LOAD Oct 29 20:26:34.668000 audit: BPF prog-id=25 op=LOAD Oct 29 20:26:34.668000 audit: BPF prog-id=21 op=UNLOAD Oct 29 20:26:34.668000 audit: BPF prog-id=22 op=UNLOAD Oct 29 20:26:34.680000 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 29 20:26:34.538330 systemd[1]: Queued start job for default target Multi-User System. Oct 29 20:26:32.739890 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" 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 29 20:26:34.556108 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 29 20:26:32.740121 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 20:26:32.740139 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 20:26:32.740420 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 29 20:26:32.740432 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 29 20:26:32.740464 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 29 20:26:32.740477 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 29 20:26:32.740789 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 29 20:26:32.740826 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 29 20:26:32.740842 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 29 20:26:32.741198 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" 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 29 20:26:32.741228 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" 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 29 20:26:32.741259 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" 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 29 20:26:32.741274 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 29 20:26:32.741367 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" 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 29 20:26:32.741382 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:32Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 29 20:26:34.452662 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 29 20:26:34.452924 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" 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 29 20:26:34.453019 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" 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 29 20:26:34.453216 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" 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 29 20:26:34.453324 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 29 20:26:34.453384 /usr/lib/systemd/system-generators/torcx-generator[647]: time="2025-10-29T20:26:34Z" 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 29 20:26:34.684237 systemd[1]: Started Journal Service. Oct 29 20:26:34.684704 systemd[1]: Mounted Huge Pages File System. Oct 29 20:26:34.685543 systemd[1]: Mounted POSIX Message Queue File System. Oct 29 20:26:34.686474 systemd[1]: Mounted External Media Directory. Oct 29 20:26:34.687312 systemd[1]: Mounted Kernel Debug File System. Oct 29 20:26:34.688137 systemd[1]: Mounted Kernel Trace File System. Oct 29 20:26:34.688946 systemd[1]: Mounted Temporary Directory /tmp. Oct 29 20:26:34.690068 systemd[1]: Finished Create missing system files. Oct 29 20:26:34.691184 systemd[1]: Finished Create List of Static Device Nodes. Oct 29 20:26:34.692272 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 29 20:26:34.692443 systemd[1]: Finished Load Kernel Module configfs. Oct 29 20:26:34.693420 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 29 20:26:34.693600 systemd[1]: Finished Load Kernel Module drm. Oct 29 20:26:34.694534 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 29 20:26:34.694703 systemd[1]: Finished Load Kernel Module fuse. Oct 29 20:26:34.695738 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 29 20:26:34.715799 systemd[1]: Finished Apply Kernel Variables. Oct 29 20:26:34.718659 systemd[1]: Mounting FUSE Control File System... Oct 29 20:26:34.720435 systemd[1]: Mounting Kernel Configuration File System... Oct 29 20:26:34.721208 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 29 20:26:34.722184 systemd[1]: Condition check resulted in Rebuild Hardware Database being skipped. Oct 29 20:26:34.723055 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 29 20:26:34.723965 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 29 20:26:34.724855 systemd[1]: Starting Load/Save Random Seed... Oct 29 20:26:34.727209 systemd-journald[695]: Time spent on flushing to /var/log/journal/35cb98b33e1f44799c33d8bd2b636957 is 12.673ms for 660 entries. Oct 29 20:26:34.727209 systemd-journald[695]: System Journal (/var/log/journal/35cb98b33e1f44799c33d8bd2b636957) is 8.0M, max 203.0M, 195.0M free. Oct 29 20:26:34.752000 audit: BPF prog-id=26 op=LOAD Oct 29 20:26:34.752000 audit: BPF prog-id=27 op=LOAD Oct 29 20:26:34.752000 audit: BPF prog-id=28 op=LOAD Oct 29 20:26:34.752000 audit: BPF prog-id=10 op=UNLOAD Oct 29 20:26:34.752000 audit: BPF prog-id=11 op=UNLOAD Oct 29 20:26:34.725864 systemd[1]: Condition check resulted in Create System Users being skipped. Oct 29 20:26:34.726757 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 29 20:26:34.729672 systemd[1]: Mounted FUSE Control File System. Oct 29 20:26:34.730848 systemd[1]: Mounted Kernel Configuration File System. Oct 29 20:26:34.732104 systemd[1]: Finished Load/Save Random Seed. Oct 29 20:26:34.733392 systemd[1]: Condition check resulted in First Boot Complete being skipped. Oct 29 20:26:34.746420 systemd[1]: Finished Coldplug All udev Devices. Oct 29 20:26:34.748522 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 29 20:26:34.750730 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 29 20:26:34.753036 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 29 20:26:34.760127 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 29 20:26:34.780185 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 29 20:26:34.782924 systemd[1]: Starting Network Configuration... Oct 29 20:26:34.782000 audit: BPF prog-id=29 op=LOAD Oct 29 20:26:34.810452 systemd-networkd[726]: lo: Link UP Oct 29 20:26:34.810460 systemd-networkd[726]: lo: Gained carrier Oct 29 20:26:34.810760 systemd-networkd[726]: Enumeration completed Oct 29 20:26:34.810871 systemd[1]: Started Network Configuration. Oct 29 20:26:34.823438 systemd-udevd[714]: Using default interface naming scheme 'v249'. Oct 29 20:26:34.837980 systemd-networkd[726]: eth0: Link UP Oct 29 20:26:34.845123 systemd-networkd[726]: eth0: Gained carrier Oct 29 20:26:34.858213 systemd-networkd[726]: eth0: DHCPv4 address 10.0.0.8/16 via 10.0.0.1 Oct 29 20:26:34.861795 udevadm[707]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 29 20:26:34.876569 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 29 20:26:34.890193 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 20:26:34.898832 lvm[735]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 20:26:34.932729 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 20:26:34.934015 systemd[1]: Reached target Local Encrypted Volumes. Oct 29 20:26:34.948751 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 29 20:26:34.952557 lvm[736]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 29 20:26:34.987028 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 29 20:26:34.988210 systemd[1]: Reached target Preparation for Local File Systems. Oct 29 20:26:34.989168 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 29 20:26:34.989191 systemd[1]: Reached target Containers. Oct 29 20:26:35.002432 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 29 20:26:35.015411 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 29 20:26:35.017429 systemd[1]: Mounting /usr/share/oem... Oct 29 20:26:35.023210 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 29 20:26:35.023270 kernel: BTRFS info (device vda6): has skinny extents Oct 29 20:26:35.025264 systemd[1]: Mounted /usr/share/oem. Oct 29 20:26:35.025989 systemd[1]: Reached target Local File Systems. Oct 29 20:26:35.026874 systemd[1]: Condition check resulted in Rebuild Dynamic Linker Cache being skipped. Oct 29 20:26:35.027230 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 29 20:26:35.027277 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 29 20:26:35.027308 systemd[1]: Condition check resulted in Commit a transient machine-id on disk being skipped. Oct 29 20:26:35.028204 systemd[1]: Starting Create Volatile Files and Directories... Oct 29 20:26:35.033727 systemd-tmpfiles[759]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 29 20:26:35.034758 systemd-tmpfiles[759]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 29 20:26:35.061358 systemd-tmpfiles[759]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 20:26:35.061370 systemd-tmpfiles[759]: Skipping /boot Oct 29 20:26:35.066749 systemd-tmpfiles[759]: Detected autofs mount point /boot during canonicalization of /boot. Oct 29 20:26:35.066767 systemd-tmpfiles[759]: Skipping /boot Oct 29 20:26:35.094972 systemd[1]: Finished Create Volatile Files and Directories. Oct 29 20:26:35.119852 systemd[1]: Starting Load Security Auditing Rules... Oct 29 20:26:35.121651 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 29 20:26:35.122692 systemd[1]: Condition check resulted in Rebuild Journal Catalog being skipped. Oct 29 20:26:35.123000 audit: BPF prog-id=30 op=LOAD Oct 29 20:26:35.124377 systemd[1]: Starting Network Name Resolution... Oct 29 20:26:35.125000 audit: BPF prog-id=31 op=LOAD Oct 29 20:26:35.126815 systemd[1]: Starting Network Time Synchronization... Oct 29 20:26:35.128790 systemd[1]: Condition check resulted in Update is Completed being skipped. Oct 29 20:26:35.129814 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 29 20:26:35.131362 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 29 20:26:35.132544 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 29 20:26:35.137725 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 29 20:26:35.148669 augenrules[780]: No rules Oct 29 20:26:35.148000 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 29 20:26:35.149377 systemd[1]: Finished Load Security Auditing Rules. Oct 29 20:26:35.164515 systemd-resolved[763]: Positive Trust Anchors: Oct 29 20:26:35.164529 systemd-resolved[763]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 29 20:26:35.164556 systemd-resolved[763]: 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 29 20:26:35.165266 systemd-resolved[763]: Defaulting to hostname 'linux'. Oct 29 20:26:35.599296 systemd-timesyncd[769]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 29 20:26:35.599442 systemd[1]: Started Network Time Synchronization. Oct 29 20:26:35.600493 systemd[1]: Reached target System Initialization. Oct 29 20:26:35.600512 systemd-resolved[763]: Clock change detected. Flushing caches. Oct 29 20:26:35.601362 systemd[1]: Started Watch for update engine configuration changes. Oct 29 20:26:35.602338 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 29 20:26:35.603339 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 29 20:26:35.604196 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 29 20:26:35.604224 systemd[1]: Reached target Path Units. Oct 29 20:26:35.604958 systemd[1]: Reached target System Time Set. Oct 29 20:26:35.605887 systemd[1]: Started Daily Log Rotation. Oct 29 20:26:35.606690 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 29 20:26:35.607723 systemd[1]: Reached target Timer Units. Oct 29 20:26:35.608702 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 29 20:26:35.621941 systemd[1]: Starting Docker Socket for the API... Oct 29 20:26:35.625007 systemd[1]: Listening on OpenSSH Server Socket. Oct 29 20:26:35.626208 systemd[1]: Started Network Name Resolution. Oct 29 20:26:35.627150 systemd[1]: Listening on Docker Socket for the API. Oct 29 20:26:35.628064 systemd[1]: Reached target Network. Oct 29 20:26:35.628781 systemd[1]: Reached target Host and Network Name Lookups. Oct 29 20:26:35.629682 systemd[1]: Reached target Socket Units. Oct 29 20:26:35.630373 systemd[1]: Reached target Basic System. Oct 29 20:26:35.631085 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 29 20:26:35.631117 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 29 20:26:35.632032 systemd[1]: Started D-Bus System Message Bus. Oct 29 20:26:35.635517 systemd[1]: Starting Extend Filesystems... Oct 29 20:26:35.636303 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 29 20:26:35.637401 systemd[1]: Starting Generate /run/flatcar/motd... Oct 29 20:26:35.639252 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 29 20:26:35.641387 systemd[1]: Starting Generate sshd host keys... Oct 29 20:26:35.642334 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 29 20:26:35.642380 systemd[1]: Reached target Load system-provided cloud configs. Oct 29 20:26:35.647754 systemd[1]: Starting User Login Management... Oct 29 20:26:35.648924 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 29 20:26:35.649257 extend-filesystems[790]: Found vda Oct 29 20:26:35.649577 systemd[1]: Starting Update Engine... Oct 29 20:26:35.650221 extend-filesystems[790]: Found vda1 Oct 29 20:26:35.650627 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 29 20:26:35.650661 systemd[1]: Reached target Load user-provided cloud configs. Oct 29 20:26:35.651444 extend-filesystems[790]: Found vda2 Oct 29 20:26:35.652826 systemd[1]: motdgen.service: Deactivated successfully. Oct 29 20:26:35.653069 extend-filesystems[790]: Found vda3 Oct 29 20:26:35.653132 systemd[1]: Finished Generate /run/flatcar/motd. Oct 29 20:26:35.654037 extend-filesystems[790]: Found usr Oct 29 20:26:35.655636 extend-filesystems[790]: Found vda4 Oct 29 20:26:35.655636 extend-filesystems[790]: Found vda6 Oct 29 20:26:35.655636 extend-filesystems[790]: Found vda7 Oct 29 20:26:35.655231 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 29 20:26:35.661413 extend-filesystems[790]: Found vda9 Oct 29 20:26:35.661413 extend-filesystems[790]: Checking size of /dev/vda9 Oct 29 20:26:35.655557 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 29 20:26:35.669889 extend-filesystems[790]: Old size kept for /dev/vda9 Oct 29 20:26:35.667586 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 29 20:26:35.667853 systemd[1]: Finished Extend Filesystems. Oct 29 20:26:35.684197 systemd[1]: Finished Generate sshd host keys. Oct 29 20:26:35.691161 systemd[1]: Starting Generate /run/issue... Oct 29 20:26:35.696053 systemd[1]: issuegen.service: Deactivated successfully. Oct 29 20:26:35.696265 systemd[1]: Finished Generate /run/issue. Oct 29 20:26:35.698204 systemd[1]: Starting Permit User Sessions... Oct 29 20:26:35.700130 systemd-logind[802]: Watching system buttons on /dev/input/event0 (Power Button) Oct 29 20:26:35.700336 systemd-logind[802]: New seat seat0. Oct 29 20:26:35.701836 systemd[1]: Started User Login Management. Oct 29 20:26:35.704593 systemd[1]: Finished Permit User Sessions. Oct 29 20:26:35.706723 systemd[1]: Started Getty on tty1. Oct 29 20:26:35.708778 systemd[1]: Started Serial Getty on ttyAMA0. Oct 29 20:26:35.708933 update_engine[805]: I1029 20:26:35.708585 805 main.cc:89] Flatcar Update Engine starting Oct 29 20:26:35.709961 update_engine[805]: I1029 20:26:35.709942 805 payload_state.cc:360] Current Response Signature = Oct 29 20:26:35.709961 update_engine[805]: NumURLs = 1 Oct 29 20:26:35.709961 update_engine[805]: Url0 = http://10.0.0.7:34567/packages/update.gz Oct 29 20:26:35.709961 update_engine[805]: Payload Size = 439328019 Oct 29 20:26:35.709961 update_engine[805]: Payload Sha256 Hash = JprWN+czonR3c0MyWAyU2g4RVVt99zqV4E+IEkT3wn8= Oct 29 20:26:35.709961 update_engine[805]: Is Delta Payload = 0 Oct 29 20:26:35.709961 update_engine[805]: Max Failure Count Per Url = 10 Oct 29 20:26:35.709961 update_engine[805]: Disable Payload Backoff = 1 Oct 29 20:26:35.710007 systemd[1]: Reached target Login Prompts. Oct 29 20:26:35.710850 update_engine[805]: I1029 20:26:35.710829 805 payload_state.cc:381] Payload Attempt Number = 0 Oct 29 20:26:35.711146 update_engine[805]: I1029 20:26:35.711130 805 payload_state.cc:404] Current URL Index = 0 Oct 29 20:26:35.711387 update_engine[805]: I1029 20:26:35.711375 805 payload_state.cc:425] Current URL (Url0)'s Failure Count = 1 Oct 29 20:26:35.711637 update_engine[805]: I1029 20:26:35.711606 805 payload_state.cc:452] Backoff Expiry Time = 01/01/70 00:00:00 UTC Oct 29 20:26:35.713366 systemd[1]: Started Update Engine. Oct 29 20:26:35.714095 update_engine[805]: I1029 20:26:35.714071 805 update_check_scheduler.cc:74] Next update check in 3m30s Oct 29 20:26:35.714325 systemd[1]: Reached target Multi-User System. Oct 29 20:26:35.716165 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 29 20:26:35.720778 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 29 20:26:35.720992 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 29 20:26:35.721965 systemd[1]: Startup finished in 689ms (kernel) + 1.092s (initrd) + 2.639s (userspace) = 4.420s. Oct 29 20:26:37.284736 systemd-networkd[726]: eth0: Gained IPv6LL Oct 29 20:26:45.459319 systemd[1]: Created slice Slice /system/sshd. Oct 29 20:26:45.460484 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57344). Oct 29 20:26:45.507431 sshd[829]: Accepted publickey for core from 10.0.0.1 port 57344 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:45.509533 sshd[829]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:45.533563 systemd[1]: Created slice User Slice of UID 500. Oct 29 20:26:45.534576 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 29 20:26:45.536036 systemd-logind[802]: New session 1 of user core. Oct 29 20:26:45.540265 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 29 20:26:45.541366 systemd[1]: Starting User Manager for UID 500... Oct 29 20:26:45.543360 systemd[832]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:45.588034 systemd[832]: Queued start job for default target Main User Target. Oct 29 20:26:45.588164 systemd[832]: Reached target Paths. Oct 29 20:26:45.588178 systemd[832]: Reached target Sockets. Oct 29 20:26:45.588188 systemd[832]: Reached target Timers. Oct 29 20:26:45.588198 systemd[832]: Reached target Basic System. Oct 29 20:26:45.588236 systemd[832]: Reached target Main User Target. Oct 29 20:26:45.588248 systemd[832]: Startup finished in 41ms. Oct 29 20:26:45.588316 systemd[1]: Started User Manager for UID 500. Oct 29 20:26:45.594407 systemd[1]: Started Session 1 of User core. Oct 29 20:26:45.651448 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57352). Oct 29 20:26:45.694868 sshd[841]: Accepted publickey for core from 10.0.0.1 port 57352 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:45.696251 sshd[841]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:45.700029 systemd-logind[802]: New session 2 of user core. Oct 29 20:26:45.715998 systemd[1]: Started Session 2 of User core. Oct 29 20:26:45.770977 sshd[841]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:45.784963 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57364). Oct 29 20:26:45.785389 systemd[1]: sshd@1-10.0.0.8:22-10.0.0.1:57352.service: Deactivated successfully. Oct 29 20:26:45.786040 systemd[1]: session-2.scope: Deactivated successfully. Oct 29 20:26:45.786612 systemd-logind[802]: Session 2 logged out. Waiting for processes to exit. Oct 29 20:26:45.787351 systemd-logind[802]: Removed session 2. Oct 29 20:26:45.828964 sshd[846]: Accepted publickey for core from 10.0.0.1 port 57364 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:45.830074 sshd[846]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:45.832336 systemd-logind[802]: New session 3 of user core. Oct 29 20:26:45.853611 systemd[1]: Started Session 3 of User core. Oct 29 20:26:45.901715 sshd[846]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:45.917341 systemd[1]: sshd@2-10.0.0.8:22-10.0.0.1:57364.service: Deactivated successfully. Oct 29 20:26:45.917829 systemd[1]: session-3.scope: Deactivated successfully. Oct 29 20:26:45.918329 systemd-logind[802]: Session 3 logged out. Waiting for processes to exit. Oct 29 20:26:45.919253 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57374). Oct 29 20:26:45.919816 systemd-logind[802]: Removed session 3. Oct 29 20:26:45.960088 sshd[854]: Accepted publickey for core from 10.0.0.1 port 57374 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:45.961235 sshd[854]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:45.963499 systemd-logind[802]: New session 4 of user core. Oct 29 20:26:45.975990 systemd[1]: Started Session 4 of User core. Oct 29 20:26:46.029691 sshd[854]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:46.040690 systemd[1]: sshd@3-10.0.0.8:22-10.0.0.1:57374.service: Deactivated successfully. Oct 29 20:26:46.041168 systemd[1]: session-4.scope: Deactivated successfully. Oct 29 20:26:46.041742 systemd-logind[802]: Session 4 logged out. Waiting for processes to exit. Oct 29 20:26:46.042631 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57378). Oct 29 20:26:46.043305 systemd-logind[802]: Removed session 4. Oct 29 20:26:46.084660 sshd[860]: Accepted publickey for core from 10.0.0.1 port 57378 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:46.086434 sshd[860]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:46.089425 systemd-logind[802]: New session 5 of user core. Oct 29 20:26:46.098496 systemd[1]: Started Session 5 of User core. Oct 29 20:26:46.156352 sudo[863]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 29 20:26:46.156564 sudo[863]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:46.167803 sudo[863]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:46.169648 sshd[860]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:46.186764 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57386). Oct 29 20:26:46.188955 dbus-daemon[789]: [system] Reloaded configuration Oct 29 20:26:46.191469 systemd[1]: sshd@4-10.0.0.8:22-10.0.0.1:57378.service: Deactivated successfully. Oct 29 20:26:46.192131 systemd[1]: session-5.scope: Deactivated successfully. Oct 29 20:26:46.192891 systemd-logind[802]: Session 5 logged out. Waiting for processes to exit. Oct 29 20:26:46.193670 systemd-logind[802]: Removed session 5. Oct 29 20:26:46.230257 sshd[866]: Accepted publickey for core from 10.0.0.1 port 57386 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:46.232476 sshd[866]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:46.235144 systemd-logind[802]: New session 6 of user core. Oct 29 20:26:46.246180 systemd[1]: Started Session 6 of User core. Oct 29 20:26:46.299087 sudo[871]: 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 29 20:26:46.299330 sudo[871]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:46.301436 sudo[871]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:46.304819 sudo[870]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 29 20:26:46.305195 sudo[870]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 29 20:26:46.321044 systemd[1]: Stopping Load Security Auditing Rules... Oct 29 20:26:46.321000 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 29 20:26:46.324445 auditctl[874]: No rules Oct 29 20:26:46.324678 kernel: kauditd_printk_skb: 43 callbacks suppressed Oct 29 20:26:46.324701 kernel: audit: type=1305 audit(1761769606.321:54): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Oct 29 20:26:46.324809 systemd[1]: audit-rules.service: Deactivated successfully. Oct 29 20:26:46.325026 systemd[1]: Stopped Load Security Auditing Rules. Oct 29 20:26:46.326202 systemd[1]: Starting Load Security Auditing Rules... Oct 29 20:26:46.340065 augenrules[891]: No rules Oct 29 20:26:46.340674 systemd[1]: Finished Load Security Auditing Rules. Oct 29 20:26:46.341735 sudo[870]: pam_unix(sudo:session): session closed for user root Oct 29 20:26:46.342772 sshd[866]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:46.350795 systemd[1]: sshd@5-10.0.0.8:22-10.0.0.1:57386.service: Deactivated successfully. Oct 29 20:26:46.351266 systemd[1]: session-6.scope: Deactivated successfully. Oct 29 20:26:46.351779 systemd-logind[802]: Session 6 logged out. Waiting for processes to exit. Oct 29 20:26:46.352660 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57394). Oct 29 20:26:46.353256 systemd-logind[802]: Removed session 6. Oct 29 20:26:46.396159 sshd[898]: Accepted publickey for core from 10.0.0.1 port 57394 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:46.397183 sshd[898]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:46.399303 kernel: audit: type=1006 audit(1761769606.396:55): pid=898 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 29 20:26:46.400063 systemd-logind[802]: New session 7 of user core. Oct 29 20:26:46.418659 systemd[1]: Started Session 7 of User core. Oct 29 20:26:46.468200 sshd[898]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:46.480794 systemd[1]: sshd@6-10.0.0.8:22-10.0.0.1:57394.service: Deactivated successfully. Oct 29 20:26:46.481376 systemd[1]: session-7.scope: Deactivated successfully. Oct 29 20:26:46.481976 systemd-logind[802]: Session 7 logged out. Waiting for processes to exit. Oct 29 20:26:46.483224 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:57400). Oct 29 20:26:46.483977 systemd-logind[802]: Removed session 7. Oct 29 20:26:46.526685 sshd[904]: Accepted publickey for core from 10.0.0.1 port 57400 ssh2: RSA SHA256:wpN8sY2AdNlC8BALPZqGqoOkA8mT8kUAdz+IgZ187U8 Oct 29 20:26:46.528224 sshd[904]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 29 20:26:46.530311 kernel: audit: type=1006 audit(1761769606.527:56): pid=904 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 29 20:26:46.531047 systemd-logind[802]: New session 8 of user core. Oct 29 20:26:46.546455 systemd[1]: Started Session 8 of User core. Oct 29 20:26:46.596029 sshd[904]: pam_unix(sshd:session): session closed for user core Oct 29 20:26:46.598080 systemd[1]: session-8.scope: Deactivated successfully. Oct 29 20:26:46.598637 systemd-logind[802]: Session 8 logged out. Waiting for processes to exit. Oct 29 20:26:46.598767 systemd[1]: sshd@7-10.0.0.8:22-10.0.0.1:57400.service: Deactivated successfully. Oct 29 20:26:46.599530 systemd-logind[802]: Removed session 8.