Oct 9 00:38:52.693104 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Oct 9 00:38:52.693120 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 9 00:38:52.693128 kernel: efi: EFI v2.70 by EDK II Oct 9 00:38:52.693133 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba6e018 ACPI 2.0=0xd9710018 RNG=0xdcfdd318 MEMRESERVE=0xd9b41c18 Oct 9 00:38:52.693138 kernel: efi: seeding entropy pool Oct 9 00:38:52.693143 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Oct 9 00:38:52.693149 kernel: ACPI: Early table checksum verification disabled Oct 9 00:38:52.693154 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Oct 9 00:38:52.693159 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Oct 9 00:38:52.693164 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693170 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693175 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693180 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693185 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693191 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693196 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693203 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693208 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 00:38:52.693213 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Oct 9 00:38:52.693219 kernel: ACPI: NUMA: Failed to initialise from firmware Oct 9 00:38:52.693224 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:38:52.693229 kernel: NUMA: NODE_DATA [mem 0xdc95a900-0xdc95ffff] Oct 9 00:38:52.693234 kernel: Zone ranges: Oct 9 00:38:52.693239 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:38:52.693244 kernel: DMA32 empty Oct 9 00:38:52.693249 kernel: Normal empty Oct 9 00:38:52.693255 kernel: Movable zone start for each node Oct 9 00:38:52.693261 kernel: Early memory node ranges Oct 9 00:38:52.693266 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Oct 9 00:38:52.693271 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Oct 9 00:38:52.693276 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Oct 9 00:38:52.693281 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Oct 9 00:38:52.693287 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Oct 9 00:38:52.693292 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Oct 9 00:38:52.693297 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Oct 9 00:38:52.693302 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Oct 9 00:38:52.693307 kernel: On node 0 totalpages: 643072 Oct 9 00:38:52.693316 kernel: DMA zone: 10048 pages used for memmap Oct 9 00:38:52.693321 kernel: DMA zone: 0 pages reserved Oct 9 00:38:52.693327 kernel: DMA zone: 643072 pages, LIFO batch:63 Oct 9 00:38:52.693335 kernel: psci: probing for conduit method from ACPI. Oct 9 00:38:52.693340 kernel: psci: PSCIv1.1 detected in firmware. Oct 9 00:38:52.693346 kernel: psci: Using standard PSCI v0.2 function IDs Oct 9 00:38:52.693351 kernel: psci: Trusted OS migration not required Oct 9 00:38:52.693357 kernel: psci: SMC Calling Convention v1.1 Oct 9 00:38:52.693362 kernel: ACPI: SRAT not present Oct 9 00:38:52.693368 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Oct 9 00:38:52.693373 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Oct 9 00:38:52.693379 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Oct 9 00:38:52.693385 kernel: Detected PIPT I-cache on CPU0 Oct 9 00:38:52.693391 kernel: CPU features: detected: GIC system register CPU interface Oct 9 00:38:52.693396 kernel: CPU features: kernel page table isolation forced ON by KASLR Oct 9 00:38:52.693402 kernel: CPU features: detected: Kernel page table isolation (KPTI) Oct 9 00:38:52.693407 kernel: CPU features: detected: Hardware dirty bit management Oct 9 00:38:52.693413 kernel: CPU features: detected: Spectre-v4 Oct 9 00:38:52.693418 kernel: CPU features: detected: ARM erratum 1418040 Oct 9 00:38:52.693424 kernel: CPU features: detected: Spectre-BHB Oct 9 00:38:52.693429 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Oct 9 00:38:52.693434 kernel: Policy zone: DMA Oct 9 00:38:52.693442 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 9 00:38:52.693448 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Oct 9 00:38:52.693454 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 9 00:38:52.693459 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 9 00:38:52.693465 kernel: Memory: 2452052K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120236K reserved, 0K cma-reserved) Oct 9 00:38:52.693471 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Oct 9 00:38:52.693476 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Oct 9 00:38:52.693482 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 9 00:38:52.693488 kernel: rcu: RCU event tracing is enabled. Oct 9 00:38:52.693494 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Oct 9 00:38:52.693499 kernel: Trampoline variant of Tasks RCU enabled. Oct 9 00:38:52.693506 kernel: Tracing variant of Tasks RCU enabled. Oct 9 00:38:52.693512 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 9 00:38:52.693517 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Oct 9 00:38:52.693523 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Oct 9 00:38:52.693528 kernel: GICv3: 256 SPIs implemented Oct 9 00:38:52.693534 kernel: GICv3: 0 Extended SPIs implemented Oct 9 00:38:52.693539 kernel: GICv3: Distributor has no Range Selector support Oct 9 00:38:52.693544 kernel: GICv3: 16 PPIs implemented Oct 9 00:38:52.693550 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Oct 9 00:38:52.693555 kernel: ACPI: SRAT not present Oct 9 00:38:52.693561 kernel: ITS [mem 0x08080000-0x0809ffff] Oct 9 00:38:52.693566 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Oct 9 00:38:52.693573 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Oct 9 00:38:52.693579 kernel: GICv3: using LPI property table @0x00000000400d0000 Oct 9 00:38:52.693584 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Oct 9 00:38:52.693590 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:38:52.693595 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Oct 9 00:38:52.693601 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Oct 9 00:38:52.693607 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Oct 9 00:38:52.693613 kernel: arm-pv: using stolen time PV Oct 9 00:38:52.693618 kernel: Console: colour dummy device 80x25 Oct 9 00:38:52.693624 kernel: ACPI: Core revision 20200925 Oct 9 00:38:52.693630 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Oct 9 00:38:52.693637 kernel: pid_max: default: 32768 minimum: 301 Oct 9 00:38:52.693642 kernel: LSM: Security Framework initializing Oct 9 00:38:52.693648 kernel: SELinux: Initializing. Oct 9 00:38:52.693654 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 9 00:38:52.693659 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Oct 9 00:38:52.693665 kernel: rcu: Hierarchical SRCU implementation. Oct 9 00:38:52.693671 kernel: Platform MSI: ITS@0x8080000 domain created Oct 9 00:38:52.693676 kernel: PCI/MSI: ITS@0x8080000 domain created Oct 9 00:38:52.693682 kernel: Remapping and enabling EFI services. Oct 9 00:38:52.693688 kernel: smp: Bringing up secondary CPUs ... Oct 9 00:38:52.693694 kernel: Detected PIPT I-cache on CPU1 Oct 9 00:38:52.693700 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Oct 9 00:38:52.693706 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Oct 9 00:38:52.693712 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:38:52.693717 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Oct 9 00:38:52.693723 kernel: Detected PIPT I-cache on CPU2 Oct 9 00:38:52.693729 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Oct 9 00:38:52.693735 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Oct 9 00:38:52.693741 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:38:52.693748 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Oct 9 00:38:52.693754 kernel: Detected PIPT I-cache on CPU3 Oct 9 00:38:52.693759 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Oct 9 00:38:52.693765 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Oct 9 00:38:52.693771 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Oct 9 00:38:52.693784 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Oct 9 00:38:52.693795 kernel: smp: Brought up 1 node, 4 CPUs Oct 9 00:38:52.693801 kernel: SMP: Total of 4 processors activated. Oct 9 00:38:52.693807 kernel: CPU features: detected: Privileged Access Never Oct 9 00:38:52.693814 kernel: CPU features: detected: LSE atomic instructions Oct 9 00:38:52.693819 kernel: CPU features: detected: User Access Override Oct 9 00:38:52.693825 kernel: CPU features: detected: 32-bit EL0 Support Oct 9 00:38:52.693831 kernel: CPU features: detected: Common not Private translations Oct 9 00:38:52.693837 kernel: CPU features: detected: RAS Extension Support Oct 9 00:38:52.693843 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Oct 9 00:38:52.693849 kernel: CPU features: detected: CRC32 instructions Oct 9 00:38:52.693855 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Oct 9 00:38:52.693862 kernel: CPU: All CPU(s) started at EL1 Oct 9 00:38:52.693868 kernel: alternatives: patching kernel code Oct 9 00:38:52.693874 kernel: devtmpfs: initialized Oct 9 00:38:52.693881 kernel: KASLR enabled Oct 9 00:38:52.693887 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 9 00:38:52.693894 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Oct 9 00:38:52.693900 kernel: pinctrl core: initialized pinctrl subsystem Oct 9 00:38:52.693906 kernel: SMBIOS 3.0.0 present. Oct 9 00:38:52.693912 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Oct 9 00:38:52.693918 kernel: NET: Registered protocol family 16 Oct 9 00:38:52.693924 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Oct 9 00:38:52.693930 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Oct 9 00:38:52.693936 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Oct 9 00:38:52.693942 kernel: audit: initializing netlink subsys (disabled) Oct 9 00:38:52.693948 kernel: audit: type=2000 audit(0.032:1): state=initialized audit_enabled=0 res=1 Oct 9 00:38:52.693955 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 9 00:38:52.693961 kernel: cpuidle: using governor menu Oct 9 00:38:52.693967 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Oct 9 00:38:52.693973 kernel: ASID allocator initialised with 32768 entries Oct 9 00:38:52.693979 kernel: ACPI: bus type PCI registered Oct 9 00:38:52.693985 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 9 00:38:52.693991 kernel: Serial: AMBA PL011 UART driver Oct 9 00:38:52.693997 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Oct 9 00:38:52.694003 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Oct 9 00:38:52.694010 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Oct 9 00:38:52.694016 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Oct 9 00:38:52.694022 kernel: cryptd: max_cpu_qlen set to 1000 Oct 9 00:38:52.694028 kernel: ACPI: Added _OSI(Module Device) Oct 9 00:38:52.694034 kernel: ACPI: Added _OSI(Processor Device) Oct 9 00:38:52.694040 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 9 00:38:52.694046 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 9 00:38:52.694052 kernel: ACPI: Added _OSI(Linux-Dell-Video) Oct 9 00:38:52.694058 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Oct 9 00:38:52.694107 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Oct 9 00:38:52.694113 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 9 00:38:52.694119 kernel: ACPI: Interpreter enabled Oct 9 00:38:52.694125 kernel: ACPI: Using GIC for interrupt routing Oct 9 00:38:52.694131 kernel: ACPI: MCFG table detected, 1 entries Oct 9 00:38:52.694137 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Oct 9 00:38:52.694143 kernel: printk: console [ttyAMA0] enabled Oct 9 00:38:52.694149 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 9 00:38:52.694254 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Oct 9 00:38:52.694313 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Oct 9 00:38:52.694366 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Oct 9 00:38:52.694418 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Oct 9 00:38:52.694478 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Oct 9 00:38:52.694487 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Oct 9 00:38:52.694493 kernel: PCI host bridge to bus 0000:00 Oct 9 00:38:52.694575 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Oct 9 00:38:52.694628 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Oct 9 00:38:52.694676 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Oct 9 00:38:52.694724 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 9 00:38:52.694799 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Oct 9 00:38:52.694863 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Oct 9 00:38:52.694921 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Oct 9 00:38:52.694975 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Oct 9 00:38:52.695031 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Oct 9 00:38:52.695095 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Oct 9 00:38:52.695151 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Oct 9 00:38:52.695206 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Oct 9 00:38:52.695256 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Oct 9 00:38:52.695304 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Oct 9 00:38:52.695351 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Oct 9 00:38:52.695361 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Oct 9 00:38:52.695367 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Oct 9 00:38:52.695374 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Oct 9 00:38:52.695380 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Oct 9 00:38:52.695386 kernel: vgaarb: loaded Oct 9 00:38:52.695392 kernel: Registered efivars operations Oct 9 00:38:52.695398 kernel: clocksource: Switched to clocksource arch_sys_counter Oct 9 00:38:52.695404 kernel: VFS: Disk quotas dquot_6.6.0 Oct 9 00:38:52.695410 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 9 00:38:52.695416 kernel: pnp: PnP ACPI init Oct 9 00:38:52.695479 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Oct 9 00:38:52.695529 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Oct 9 00:38:52.695537 kernel: pnp: PnP ACPI: found 1 devices Oct 9 00:38:52.695543 kernel: NET: Registered protocol family 2 Oct 9 00:38:52.695550 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Oct 9 00:38:52.695556 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Oct 9 00:38:52.695562 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 9 00:38:52.695568 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Oct 9 00:38:52.695576 kernel: TCP: Hash tables configured (established 32768 bind 32768) Oct 9 00:38:52.695582 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 9 00:38:52.695588 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Oct 9 00:38:52.695594 kernel: NET: Registered protocol family 1 Oct 9 00:38:52.695600 kernel: PCI: CLS 0 bytes, default 64 Oct 9 00:38:52.695606 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Oct 9 00:38:52.695612 kernel: kvm [1]: HYP mode not available Oct 9 00:38:52.695619 kernel: Initialise system trusted keyrings Oct 9 00:38:52.695625 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Oct 9 00:38:52.695632 kernel: Key type asymmetric registered Oct 9 00:38:52.695638 kernel: Asymmetric key parser 'x509' registered Oct 9 00:38:52.695644 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 9 00:38:52.695650 kernel: io scheduler mq-deadline registered Oct 9 00:38:52.695656 kernel: io scheduler kyber registered Oct 9 00:38:52.695662 kernel: io scheduler bfq registered Oct 9 00:38:52.695668 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Oct 9 00:38:52.695674 kernel: ACPI: Power Button [PWRB] Oct 9 00:38:52.695680 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Oct 9 00:38:52.695735 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Oct 9 00:38:52.695744 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 9 00:38:52.695750 kernel: msm_serial: driver initialized Oct 9 00:38:52.695756 kernel: random: fast init done Oct 9 00:38:52.695762 kernel: random: crng init done Oct 9 00:38:52.695768 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Oct 9 00:38:52.695780 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Oct 9 00:38:52.695787 kernel: thunder_xcv, ver 1.0 Oct 9 00:38:52.695793 kernel: thunder_bgx, ver 1.0 Oct 9 00:38:52.695799 kernel: nicpf, ver 1.0 Oct 9 00:38:52.695806 kernel: nicvf, ver 1.0 Oct 9 00:38:52.695868 kernel: rtc-efi rtc-efi.0: registered as rtc0 Oct 9 00:38:52.695920 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-10-09T00:38:52 UTC (1728434332) Oct 9 00:38:52.695928 kernel: hid: raw HID events driver (C) Jiri Kosina Oct 9 00:38:52.695934 kernel: NET: Registered protocol family 10 Oct 9 00:38:52.695940 kernel: Segment Routing with IPv6 Oct 9 00:38:52.695946 kernel: NET: Registered protocol family 17 Oct 9 00:38:52.695952 kernel: Key type dns_resolver registered Oct 9 00:38:52.695960 kernel: registered taskstats version 1 Oct 9 00:38:52.695966 kernel: Loading compiled-in X.509 certificates Oct 9 00:38:52.695972 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Oct 9 00:38:52.695978 kernel: Key type ._fscrypt registered Oct 9 00:38:52.695984 kernel: Key type .fscrypt registered Oct 9 00:38:52.695990 kernel: Key type fscrypt-provisioning registered Oct 9 00:38:52.695996 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 9 00:38:52.696002 kernel: ima: Allocated hash algorithm: sha1 Oct 9 00:38:52.696008 kernel: ima: No architecture policies found Oct 9 00:38:52.696015 kernel: Freeing unused kernel memory: 34240K Oct 9 00:38:52.696021 kernel: Run /init as init process Oct 9 00:38:52.696027 kernel: with arguments: Oct 9 00:38:52.696033 kernel: /init Oct 9 00:38:52.696039 kernel: with environment: Oct 9 00:38:52.696045 kernel: HOME=/ Oct 9 00:38:52.696051 kernel: TERM=linux Oct 9 00:38:52.696056 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 9 00:38:52.696075 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 9 00:38:52.696098 systemd[1]: Detected virtualization kvm. Oct 9 00:38:52.696105 systemd[1]: Detected architecture arm64. Oct 9 00:38:52.696111 systemd[1]: Running in initial RAM disk. Oct 9 00:38:52.696118 systemd[1]: No hostname configured, using default hostname. Oct 9 00:38:52.696124 systemd[1]: Hostname set to . Oct 9 00:38:52.696131 systemd[1]: Initializing machine ID from VM UUID. Oct 9 00:38:52.696137 systemd[1]: Queued start job for default target Initrd Default Target. Oct 9 00:38:52.696144 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 9 00:38:52.696152 systemd[1]: Reached target Local Encrypted Volumes. Oct 9 00:38:52.696158 systemd[1]: Reached target Path Units. Oct 9 00:38:52.696164 systemd[1]: Reached target Slice Units. Oct 9 00:38:52.696171 systemd[1]: Reached target Swaps. Oct 9 00:38:52.696177 systemd[1]: Reached target Timer Units. Oct 9 00:38:52.696184 systemd[1]: Listening on Open-iSCSI iscsid Socket. Oct 9 00:38:52.696190 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Oct 9 00:38:52.696196 systemd[1]: Listening on Journal Audit Socket. Oct 9 00:38:52.696204 systemd[1]: Listening on Journal Socket (/dev/log). Oct 9 00:38:52.696211 systemd[1]: Listening on Journal Socket. Oct 9 00:38:52.696217 systemd[1]: Listening on Network Service Netlink Socket. Oct 9 00:38:52.696223 systemd[1]: Listening on udev Control Socket. Oct 9 00:38:52.696230 systemd[1]: Listening on udev Kernel Socket. Oct 9 00:38:52.696236 systemd[1]: Reached target Socket Units. Oct 9 00:38:52.696243 systemd[1]: Starting Create List of Static Device Nodes... Oct 9 00:38:52.696249 systemd[1]: Finished Network Cleanup. Oct 9 00:38:52.696255 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Oct 9 00:38:52.696263 systemd[1]: Starting Journal Service... Oct 9 00:38:52.696269 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 9 00:38:52.696276 systemd[1]: Starting Apply Kernel Variables... Oct 9 00:38:52.696282 systemd[1]: Starting Setup Virtual Console... Oct 9 00:38:52.696289 systemd[1]: Finished Create List of Static Device Nodes. Oct 9 00:38:52.696295 systemd[1]: Finished Apply Kernel Variables. Oct 9 00:38:52.696302 systemd[1]: Finished Setup Virtual Console. Oct 9 00:38:52.696309 systemd[1]: Starting dracut ask for additional cmdline parameters... Oct 9 00:38:52.696315 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 9 00:38:52.696323 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 9 00:38:52.696332 systemd-journald[279]: Journal started Oct 9 00:38:52.696367 systemd-journald[279]: Runtime Journal (/run/log/journal/9cfe9b7ddee5481cb93d142d42d743c2) is 6.0M, max 48.5M, 42.4M free. Oct 9 00:38:52.697327 systemd[1]: Started Journal Service. Oct 9 00:38:52.704188 systemd[1]: Finished dracut ask for additional cmdline parameters. Oct 9 00:38:52.705478 systemd[1]: Starting dracut cmdline hook... Oct 9 00:38:52.714042 dracut-cmdline[297]: dracut-dracut-053 Oct 9 00:38:52.716612 dracut-cmdline[297]: 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 9 00:38:52.777089 kernel: SCSI subsystem initialized Oct 9 00:38:52.780075 kernel: Loading iSCSI transport class v2.0-870. Oct 9 00:38:52.789104 kernel: iscsi: registered transport (tcp) Oct 9 00:38:52.799088 kernel: iscsi: registered transport (qla4xxx) Oct 9 00:38:52.799102 kernel: QLogic iSCSI HBA Driver Oct 9 00:38:52.808297 systemd[1]: Finished dracut cmdline hook. Oct 9 00:38:52.809473 systemd[1]: Starting dracut pre-udev hook... Oct 9 00:38:52.822232 kernel: device-mapper: uevent: version 1.0.3 Oct 9 00:38:52.822259 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Oct 9 00:38:52.857086 kernel: raid6: neonx8 gen() 13794 MB/s Oct 9 00:38:52.874083 kernel: raid6: neonx8 xor() 10759 MB/s Oct 9 00:38:52.891074 kernel: raid6: neonx4 gen() 13606 MB/s Oct 9 00:38:52.908073 kernel: raid6: neonx4 xor() 11596 MB/s Oct 9 00:38:52.925073 kernel: raid6: neonx2 gen() 13006 MB/s Oct 9 00:38:52.942079 kernel: raid6: neonx2 xor() 9875 MB/s Oct 9 00:38:52.959074 kernel: raid6: neonx1 gen() 10916 MB/s Oct 9 00:38:52.976074 kernel: raid6: neonx1 xor() 8812 MB/s Oct 9 00:38:52.993083 kernel: raid6: int64x8 gen() 6821 MB/s Oct 9 00:38:53.010073 kernel: raid6: int64x8 xor() 3796 MB/s Oct 9 00:38:53.027074 kernel: raid6: int64x4 gen() 6902 MB/s Oct 9 00:38:53.044072 kernel: raid6: int64x4 xor() 3819 MB/s Oct 9 00:38:53.061080 kernel: raid6: int64x2 gen() 6172 MB/s Oct 9 00:38:53.078075 kernel: raid6: int64x2 xor() 3304 MB/s Oct 9 00:38:53.095080 kernel: raid6: int64x1 gen() 4999 MB/s Oct 9 00:38:53.112254 kernel: raid6: int64x1 xor() 2594 MB/s Oct 9 00:38:53.112297 kernel: raid6: using algorithm neonx8 gen() 13794 MB/s Oct 9 00:38:53.112306 kernel: raid6: .... xor() 10759 MB/s, rmw enabled Oct 9 00:38:53.112313 kernel: raid6: using neon recovery algorithm Oct 9 00:38:53.122082 kernel: xor: measuring software checksum speed Oct 9 00:38:53.123075 kernel: 8regs : 17152 MB/sec Oct 9 00:38:53.124269 kernel: 32regs : 20765 MB/sec Oct 9 00:38:53.124287 kernel: arm64_neon : 29173 MB/sec Oct 9 00:38:53.124301 kernel: xor: using function: arm64_neon (29173 MB/sec) Oct 9 00:38:53.173091 kernel: Btrfs loaded, crc32c=crc32c-generic Oct 9 00:38:53.182670 systemd[1]: Finished dracut pre-udev hook. Oct 9 00:38:53.183000 audit: BPF prog-id=6 op=LOAD Oct 9 00:38:53.184000 audit: BPF prog-id=7 op=LOAD Oct 9 00:38:53.184000 audit: BPF prog-id=8 op=LOAD Oct 9 00:38:53.185336 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 9 00:38:53.186816 kernel: audit: type=1334 audit(1728434333.183:2): prog-id=6 op=LOAD Oct 9 00:38:53.186834 kernel: audit: type=1334 audit(1728434333.184:3): prog-id=7 op=LOAD Oct 9 00:38:53.186842 kernel: audit: type=1334 audit(1728434333.184:4): prog-id=8 op=LOAD Oct 9 00:38:53.196131 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 9 00:38:53.197868 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 9 00:38:53.199280 systemd[1]: Starting dracut pre-trigger hook... Oct 9 00:38:53.204000 audit: BPF prog-id=9 op=LOAD Oct 9 00:38:53.205443 systemd[1]: Starting Network Configuration... Oct 9 00:38:53.209157 kernel: audit: type=1334 audit(1728434333.204:5): prog-id=9 op=LOAD Oct 9 00:38:53.213836 dracut-pre-trigger[418]: rd.md=0: removing MD RAID activation Oct 9 00:38:53.221849 systemd-networkd[435]: lo: Link UP Oct 9 00:38:53.221857 systemd-networkd[435]: lo: Gained carrier Oct 9 00:38:53.222168 systemd-networkd[435]: Enumeration completed Oct 9 00:38:53.222262 systemd[1]: Started Network Configuration. Oct 9 00:38:53.224353 systemd[1]: Starting Network Name Resolution... Oct 9 00:38:53.223000 audit: BPF prog-id=10 op=LOAD Oct 9 00:38:53.226106 kernel: audit: type=1334 audit(1728434333.223:6): prog-id=10 op=LOAD Oct 9 00:38:53.242125 systemd[1]: Finished dracut pre-trigger hook. Oct 9 00:38:53.243338 systemd[1]: Starting Coldplug All udev Devices... Oct 9 00:38:53.251969 systemd-resolved[449]: Positive Trust Anchors: Oct 9 00:38:53.251984 systemd-resolved[449]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 00:38:53.252012 systemd-resolved[449]: 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 9 00:38:53.252293 systemd-resolved[449]: Defaulting to hostname 'linux'. Oct 9 00:38:53.252931 systemd[1]: Started Network Name Resolution. Oct 9 00:38:53.253623 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Oct 9 00:38:53.254701 systemd[1]: Reached target Network. Oct 9 00:38:53.255221 systemd[1]: Reached target Host and Network Name Lookups. Oct 9 00:38:53.256422 systemd[1]: Starting iSCSI UserSpace I/O driver... Oct 9 00:38:53.268772 systemd[1]: Started iSCSI UserSpace I/O driver. Oct 9 00:38:53.270035 systemd[1]: Starting Open-iSCSI... Oct 9 00:38:53.272443 iscsid[489]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Oct 9 00:38:53.272443 iscsid[489]: 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 9 00:38:53.272443 iscsid[489]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Oct 9 00:38:53.272443 iscsid[489]: If using hardware iscsi like qla4xxx this message can be ignored. Oct 9 00:38:53.272443 iscsid[489]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Oct 9 00:38:53.272443 iscsid[489]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Oct 9 00:38:53.277016 systemd[1]: Started Open-iSCSI. Oct 9 00:38:53.278820 systemd[1]: Finished Coldplug All udev Devices. Oct 9 00:38:53.280080 systemd[1]: Starting dracut initqueue hook... Oct 9 00:38:53.301842 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Oct 9 00:38:53.301956 kernel: vda: detected capacity change from 0 to 4756340736 Oct 9 00:38:53.311302 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:38:53.316587 systemd-udevd[467]: Using default interface naming scheme 'v249'. Oct 9 00:38:53.326439 systemd-networkd[435]: eth0: Link UP Oct 9 00:38:53.331078 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by systemd-udevd (482) Oct 9 00:38:53.333338 systemd[1]: Found device /dev/disk/by-label/ROOT. Oct 9 00:38:53.339668 systemd[1]: Found device /dev/disk/by-label/OEM. Oct 9 00:38:53.341190 systemd[1]: Finished dracut initqueue hook. Oct 9 00:38:53.344132 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Oct 9 00:38:53.346803 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 9 00:38:53.348476 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Oct 9 00:38:53.349782 systemd[1]: Reached target Initrd Root Device. Oct 9 00:38:53.350356 systemd[1]: Reached target Preparation for Remote File Systems. Oct 9 00:38:53.351441 systemd[1]: Reached target Remote Encrypted Volumes. Oct 9 00:38:53.352653 systemd[1]: Reached target Remote File Systems. Oct 9 00:38:53.354255 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Oct 9 00:38:53.355485 systemd[1]: Starting dracut pre-mount hook... Oct 9 00:38:53.364299 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:38:53.363959 systemd[1]: Finished dracut pre-mount hook. Oct 9 00:38:54.346768 systemd-networkd[435]: eth0: Gained carrier Oct 9 00:38:54.347689 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Oct 9 00:38:54.358153 systemd-networkd[435]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Oct 9 00:38:54.376042 disk-uuid[511]: The operation has completed successfully. Oct 9 00:38:54.376848 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 00:38:54.398042 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 9 00:38:54.398186 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Oct 9 00:38:54.400250 systemd[1]: Starting Ignition (setup)... Oct 9 00:38:54.407376 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 9 00:38:54.407414 kernel: BTRFS info (device vda6): has skinny extents Oct 9 00:38:54.413136 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 9 00:38:54.417691 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 9 00:38:54.417824 systemd[1]: Finished Ignition (setup). Oct 9 00:38:54.419521 systemd[1]: Starting Ignition (disks)... Oct 9 00:38:54.432152 ignition[545]: Ignition v0.36.1-15-gde4e6cc9 Oct 9 00:38:54.432169 ignition[545]: Stage: disks Oct 9 00:38:54.432178 ignition[545]: reading system config file "/usr/lib/ignition/base.ign" Oct 9 00:38:54.432189 ignition[545]: no config at "/usr/lib/ignition/base.ign" Oct 9 00:38:54.432232 ignition[545]: parsed url from cmdline: "" Oct 9 00:38:54.432236 ignition[545]: no config URL provided Oct 9 00:38:54.432240 ignition[545]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 00:38:54.432248 ignition[545]: no config at "/usr/lib/ignition/user.ign" Oct 9 00:38:54.432268 ignition[545]: op(1): [started] loading QEMU firmware config module Oct 9 00:38:54.432272 ignition[545]: op(1): executing: "modprobe" "qemu_fw_cfg" Oct 9 00:38:54.433187 ignition[545]: op(1): [finished] loading QEMU firmware config module Oct 9 00:38:54.444441 ignition[545]: parsing config with SHA512: a97eb97f2c5a3128ba80949f872c1bb8501d56f8f2ecc6974f849b2265d2ea1ab8e938717956332461d8d93f9e0b43fff27301d1a62033f5d7ca5bbc09e9f896 Oct 9 00:38:54.446313 ignition[545]: disks: disks passed Oct 9 00:38:54.446322 ignition[545]: Ignition finished successfully Oct 9 00:38:54.446914 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 9 00:38:54.447032 systemd[1]: Finished Ignition (disks). Oct 9 00:38:54.447986 systemd[1]: Reached target Preparation for Local File Systems. Oct 9 00:38:54.449740 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Oct 9 00:38:54.451352 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Oct 9 00:38:54.458381 systemd-fsck[558]: ROOT: clean, 528/553792 files, 37752/553472 blocks Oct 9 00:38:54.461993 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Oct 9 00:38:54.463797 systemd[1]: Mounting /sysroot... Oct 9 00:38:54.469075 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Oct 9 00:38:54.486395 systemd[1]: Found device /dev/mapper/usr. Oct 9 00:38:54.488041 systemd[1]: Starting File System Check on /dev/mapper/usr... Oct 9 00:38:54.490261 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Oct 9 00:38:54.496812 systemd-fsck[574]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Oct 9 00:38:54.496812 systemd-fsck[574]: You must have r/w access to the filesystem or be root Oct 9 00:38:54.499610 systemd-fsck[569]: fsck failed with exit status 8. Oct 9 00:38:54.499617 systemd-fsck[569]: Ignoring error. Oct 9 00:38:54.500624 systemd[1]: Finished File System Check on /dev/mapper/usr. Oct 9 00:38:54.502132 systemd[1]: Mounting /sysusr/usr... Oct 9 00:38:54.510088 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Oct 9 00:38:54.510190 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Oct 9 00:38:54.511083 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Oct 9 00:38:54.511540 systemd[1]: Mounted /sysroot. Oct 9 00:38:54.512622 systemd[1]: Mounted /sysusr/usr. Oct 9 00:38:54.513612 systemd[1]: Reached target Initrd Root File System. Oct 9 00:38:54.514848 systemd[1]: Reached target Local File Systems. Oct 9 00:38:54.515982 systemd[1]: Reached target System Initialization. Oct 9 00:38:54.517164 systemd[1]: Reached target Basic System. Oct 9 00:38:54.519089 systemd[1]: Mounting /sysroot/usr... Oct 9 00:38:54.521123 systemd[1]: Mounted /sysroot/usr. Oct 9 00:38:54.522704 systemd[1]: Starting Root filesystem setup... Oct 9 00:38:54.547771 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 9 00:38:54.547884 systemd[1]: Finished Root filesystem setup. Oct 9 00:38:54.551282 systemd[1]: Starting Ignition (files)... Oct 9 00:38:54.552991 systemd[1]: Starting /sysroot/boot... Oct 9 00:38:54.553744 ignition[589]: Ignition v0.36.1-15-gde4e6cc9 Oct 9 00:38:54.553751 ignition[589]: Stage: files Oct 9 00:38:54.553761 ignition[589]: reading system config file "/usr/lib/ignition/base.ign" Oct 9 00:38:54.553770 ignition[589]: no config at "/usr/lib/ignition/base.ign" Oct 9 00:38:54.554265 ignition[589]: files: compiled without relabeling support, skipping Oct 9 00:38:54.580546 systemd[1]: Finished /sysroot/boot. Oct 9 00:38:54.595369 ignition[589]: files: createUsers: op(1): [started] creating or modifying user "core" Oct 9 00:38:54.596280 ignition[589]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Oct 9 00:38:54.597378 ignition[589]: files: createUsers: op(1): [finished] creating or modifying user "core" Oct 9 00:38:54.598296 ignition[589]: files: createUsers: op(2): [started] adding ssh keys to user "core" Oct 9 00:38:54.600210 ignition[589]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Oct 9 00:38:54.600238 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Oct 9 00:38:54.600515 ignition[589]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Oct 9 00:38:54.600522 ignition[589]: files: op(4): [started] processing unit "coreos-metadata.service" Oct 9 00:38:54.600612 ignition[589]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 9 00:38:54.603598 systemd[1]: ignition-files.service: Deactivated successfully. Oct 9 00:38:54.601007 ignition[589]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Oct 9 00:38:54.603705 systemd[1]: Finished Ignition (files). Oct 9 00:38:54.601013 ignition[589]: files: op(4): [finished] processing unit "coreos-metadata.service" Oct 9 00:38:54.604647 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Oct 9 00:38:54.601018 ignition[589]: files: files passed Oct 9 00:38:54.605266 systemd[1]: Starting Ignition (record completion)... Oct 9 00:38:54.601022 ignition[589]: Ignition finished successfully Oct 9 00:38:54.612000 audit: BPF prog-id=10 op=UNLOAD Oct 9 00:38:54.606735 systemd[1]: Starting Reload Configuration from the Real Root... Oct 9 00:38:54.614929 kernel: audit: type=1334 audit(1728434334.612:7): prog-id=10 op=UNLOAD Oct 9 00:38:54.614947 kernel: audit: type=1334 audit(1728434334.613:8): prog-id=3 op=UNLOAD Oct 9 00:38:54.613000 audit: BPF prog-id=3 op=UNLOAD Oct 9 00:38:54.607665 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 9 00:38:54.607784 systemd[1]: Finished Ignition (record completion). Oct 9 00:38:54.610913 systemd[1]: Reloading. Oct 9 00:38:54.618000 audit: BPF prog-id=9 op=UNLOAD Oct 9 00:38:54.618000 audit: BPF prog-id=6 op=UNLOAD Oct 9 00:38:54.621468 kernel: audit: type=1334 audit(1728434334.618:9): prog-id=9 op=UNLOAD Oct 9 00:38:54.621500 kernel: audit: type=1334 audit(1728434334.618:10): prog-id=6 op=UNLOAD Oct 9 00:38:54.693000 audit: BPF prog-id=11 op=LOAD Oct 9 00:38:54.694000 audit: BPF prog-id=12 op=LOAD Oct 9 00:38:54.694000 audit: BPF prog-id=13 op=LOAD Oct 9 00:38:54.694000 audit: BPF prog-id=14 op=LOAD Oct 9 00:38:54.694000 audit: BPF prog-id=4 op=UNLOAD Oct 9 00:38:54.694000 audit: BPF prog-id=5 op=UNLOAD Oct 9 00:38:54.694000 audit: BPF prog-id=15 op=LOAD Oct 9 00:38:54.695000 audit: BPF prog-id=16 op=LOAD Oct 9 00:38:54.695000 audit: BPF prog-id=17 op=LOAD Oct 9 00:38:54.695000 audit: BPF prog-id=18 op=LOAD Oct 9 00:38:54.695000 audit: BPF prog-id=7 op=UNLOAD Oct 9 00:38:54.695000 audit: BPF prog-id=8 op=UNLOAD Oct 9 00:38:54.709117 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 9 00:38:54.709229 systemd[1]: Finished Reload Configuration from the Real Root. Oct 9 00:38:54.710564 systemd[1]: Reached target Initrd File Systems. Oct 9 00:38:54.711607 systemd[1]: Reached target Initrd Default Target. Oct 9 00:38:54.712658 systemd[1]: Condition check resulted in dracut mount hook being skipped. Oct 9 00:38:54.713281 systemd[1]: Starting dracut pre-pivot and cleanup hook... Oct 9 00:38:54.723079 systemd[1]: Finished dracut pre-pivot and cleanup hook. Oct 9 00:38:54.724358 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Oct 9 00:38:54.730105 systemd[1]: Stopped target Host and Network Name Lookups. Oct 9 00:38:54.730822 systemd[1]: Stopped target Remote Encrypted Volumes. Oct 9 00:38:54.731930 systemd[1]: Stopped target Timer Units. Oct 9 00:38:54.732933 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 9 00:38:54.733037 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Oct 9 00:38:54.734009 systemd[1]: Stopped target Initrd Default Target. Oct 9 00:38:54.735074 systemd[1]: Stopped target Basic System. Oct 9 00:38:54.736071 systemd[1]: Stopped target Initrd Root Device. Oct 9 00:38:54.737204 systemd[1]: Stopped target Path Units. Oct 9 00:38:54.738083 systemd[1]: Stopped target Remote File Systems. Oct 9 00:38:54.739155 systemd[1]: Stopped target Preparation for Remote File Systems. Oct 9 00:38:54.740315 systemd[1]: Stopped target Slice Units. Oct 9 00:38:54.741245 systemd[1]: Stopped target Socket Units. Oct 9 00:38:54.742318 systemd[1]: Stopped target System Initialization. Oct 9 00:38:54.743276 systemd[1]: Stopped target Local File Systems. Oct 9 00:38:54.744196 systemd[1]: Stopped target Preparation for Local File Systems. Oct 9 00:38:54.745394 systemd[1]: Stopped target Swaps. Oct 9 00:38:54.746284 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 9 00:38:54.746379 systemd[1]: Stopped dracut pre-mount hook. Oct 9 00:38:54.747410 systemd[1]: Stopped target Local Encrypted Volumes. Oct 9 00:38:54.748264 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 9 00:38:54.750693 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Oct 9 00:38:54.751555 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 9 00:38:54.751651 systemd[1]: Stopped dracut initqueue hook. Oct 9 00:38:54.753888 iscsid[489]: iscsid shutting down. Oct 9 00:38:54.754752 systemd[1]: Stopping Open-iSCSI... Oct 9 00:38:54.755781 systemd[1]: Stopping /sysroot/boot... Oct 9 00:38:54.756256 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 9 00:38:54.756369 systemd[1]: Stopped Coldplug All udev Devices. Oct 9 00:38:54.757245 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 9 00:38:54.757319 systemd[1]: Stopped dracut pre-trigger hook. Oct 9 00:38:54.759288 systemd[1]: iscsid.service: Deactivated successfully. Oct 9 00:38:54.759388 systemd[1]: Stopped Open-iSCSI. Oct 9 00:38:54.760363 systemd[1]: iscsid.socket: Deactivated successfully. Oct 9 00:38:54.760407 systemd[1]: Closed Open-iSCSI iscsid Socket. Oct 9 00:38:54.761221 systemd[1]: Stopping iSCSI UserSpace I/O driver... Oct 9 00:38:54.764365 systemd[1]: iscsiuio.service: Deactivated successfully. Oct 9 00:38:54.764470 systemd[1]: Stopped iSCSI UserSpace I/O driver. Oct 9 00:38:54.766216 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 9 00:38:54.766319 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Oct 9 00:38:54.767172 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 9 00:38:54.767267 systemd[1]: Stopped /sysroot/boot. Oct 9 00:38:54.768768 systemd[1]: Stopped target Network. Oct 9 00:38:54.769711 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 9 00:38:54.769775 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Oct 9 00:38:54.770878 systemd[1]: Stopping Network Name Resolution... Oct 9 00:38:54.774028 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 9 00:38:54.774243 systemd[1]: Stopped Network Name Resolution. Oct 9 00:38:54.775656 systemd[1]: Stopping Network Configuration... Oct 9 00:38:54.778172 systemd-networkd[435]: eth0: DHCP lease lost Oct 9 00:38:54.782000 audit: BPF prog-id=11 op=UNLOAD Oct 9 00:38:54.787141 systemd-networkd[435]: eth0: DHCPv6 lease lost Oct 9 00:38:54.787945 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 9 00:38:54.788053 systemd[1]: Stopped Network Configuration. Oct 9 00:38:54.789343 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 9 00:38:54.789373 systemd[1]: Closed Network Service Netlink Socket. Oct 9 00:38:54.790682 systemd[1]: Stopping Network Cleanup... Oct 9 00:38:54.791173 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 9 00:38:54.791234 systemd[1]: Stopped Apply Kernel Variables. Oct 9 00:38:54.793248 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Oct 9 00:38:54.797452 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 9 00:38:54.797568 systemd[1]: Stopped Network Cleanup. Oct 9 00:38:54.800266 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 9 00:38:54.800381 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Oct 9 00:38:54.801763 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 9 00:38:54.803000 audit: BPF prog-id=15 op=UNLOAD Oct 9 00:38:54.801801 systemd[1]: Closed udev Control Socket. Oct 9 00:38:54.802797 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 9 00:38:54.805000 audit: BPF prog-id=16 op=UNLOAD Oct 9 00:38:54.802829 systemd[1]: Closed udev Kernel Socket. Oct 9 00:38:54.803929 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 9 00:38:54.803973 systemd[1]: Stopped dracut pre-udev hook. Oct 9 00:38:54.804836 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 9 00:38:54.804873 systemd[1]: Stopped dracut cmdline hook. Oct 9 00:38:54.806088 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 00:38:54.806128 systemd[1]: Stopped dracut ask for additional cmdline parameters. Oct 9 00:38:54.807735 systemd[1]: Starting Cleanup udev Database... Oct 9 00:38:54.808814 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Oct 9 00:38:54.809766 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 9 00:38:54.809814 systemd[1]: Stopped Create Static Device Nodes in /dev. Oct 9 00:38:54.810777 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 9 00:38:54.810813 systemd[1]: Stopped Create List of Static Device Nodes. Oct 9 00:38:54.811955 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 00:38:54.811991 systemd[1]: Stopped Setup Virtual Console. Oct 9 00:38:54.813287 systemd[1]: rngd.service: Deactivated successfully. Oct 9 00:38:54.813397 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Oct 9 00:38:54.814334 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 9 00:38:54.814447 systemd[1]: Finished Cleanup udev Database. Oct 9 00:38:54.815335 systemd[1]: Reached target Switch Root. Oct 9 00:38:54.822000 audit: BPF prog-id=12 op=UNLOAD Oct 9 00:38:54.816972 systemd[1]: Starting Switch Root... Oct 9 00:38:54.821771 systemd[1]: Switching root. Oct 9 00:38:54.839350 systemd-journald[279]: Journal stopped Oct 9 00:38:56.965593 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Oct 9 00:38:56.965646 kernel: SELinux: policy capability network_peer_controls=1 Oct 9 00:38:56.965664 kernel: SELinux: policy capability open_perms=1 Oct 9 00:38:56.965680 kernel: SELinux: policy capability extended_socket_class=1 Oct 9 00:38:56.965690 kernel: SELinux: policy capability always_check_network=0 Oct 9 00:38:56.965699 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 9 00:38:56.965708 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 9 00:38:56.965717 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 9 00:38:56.965726 systemd[1]: Successfully loaded SELinux policy in 28.663ms. Oct 9 00:38:56.965742 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.270ms. Oct 9 00:38:56.965753 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 9 00:38:56.965764 systemd[1]: Detected virtualization kvm. Oct 9 00:38:56.965774 systemd[1]: Detected architecture arm64. Oct 9 00:38:56.965784 systemd[1]: Detected first boot. Oct 9 00:38:56.965794 systemd[1]: Initializing machine ID from VM UUID. Oct 9 00:38:56.965809 systemd[1]: Populated /etc with preset unit settings. Oct 9 00:38:56.965823 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 9 00:38:56.965834 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Oct 9 00:38:56.965844 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 9 00:38:56.965854 systemd[1]: Stopped Switch Root. Oct 9 00:38:56.965865 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 9 00:38:56.965876 systemd[1]: Created slice Slice /system/addon-config. Oct 9 00:38:56.965885 systemd[1]: Created slice Slice /system/addon-run. Oct 9 00:38:56.965895 systemd[1]: Created slice Slice /system/getty. Oct 9 00:38:56.965905 systemd[1]: Created slice Slice /system/modprobe. Oct 9 00:38:56.965915 systemd[1]: Created slice Slice /system/serial-getty. Oct 9 00:38:56.965929 systemd[1]: Created slice Slice /system/system-cloudinit. Oct 9 00:38:56.965939 systemd[1]: Created slice Slice /system/systemd-fsck. Oct 9 00:38:56.965949 systemd[1]: Created slice User and Session Slice. Oct 9 00:38:56.965961 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Oct 9 00:38:56.966033 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Oct 9 00:38:56.966045 systemd[1]: Set up automount Boot partition Automount Point. Oct 9 00:38:56.966055 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Oct 9 00:38:56.966083 systemd[1]: Stopped target Switch Root. Oct 9 00:38:56.966095 systemd[1]: Stopped target Initrd File Systems. Oct 9 00:38:56.966105 systemd[1]: Stopped target Initrd Root File System. Oct 9 00:38:56.966115 systemd[1]: Reached target Remote Encrypted Volumes. Oct 9 00:38:56.966126 systemd[1]: Reached target Remote File Systems. Oct 9 00:38:56.966136 systemd[1]: Reached target Slice Units. Oct 9 00:38:56.966148 systemd[1]: Reached target Swaps. Oct 9 00:38:56.966159 systemd[1]: Reached target Verify torcx succeeded. Oct 9 00:38:56.966169 systemd[1]: Reached target Local Verity Protected Volumes. Oct 9 00:38:56.966180 systemd[1]: Listening on Process Core Dump Socket. Oct 9 00:38:56.966190 systemd[1]: Listening on initctl Compatibility Named Pipe. Oct 9 00:38:56.966200 systemd[1]: Listening on Network Service Netlink Socket. Oct 9 00:38:56.966210 systemd[1]: Listening on udev Control Socket. Oct 9 00:38:56.966220 systemd[1]: Listening on udev Kernel Socket. Oct 9 00:38:56.966230 systemd[1]: Mounting Huge Pages File System... Oct 9 00:38:56.966240 systemd[1]: Mounting POSIX Message Queue File System... Oct 9 00:38:56.966251 systemd[1]: Mounting External Media Directory... Oct 9 00:38:56.966261 systemd[1]: Mounting Kernel Debug File System... Oct 9 00:38:56.966271 systemd[1]: Mounting Kernel Trace File System... Oct 9 00:38:56.966281 systemd[1]: Mounting Temporary Directory /tmp... Oct 9 00:38:56.966291 systemd[1]: Starting Create missing system files... Oct 9 00:38:56.966301 systemd[1]: Starting Create List of Static Device Nodes... Oct 9 00:38:56.966312 systemd[1]: Starting Load Kernel Module configfs... Oct 9 00:38:56.966322 systemd[1]: Starting Load Kernel Module drm... Oct 9 00:38:56.966332 systemd[1]: Starting Load Kernel Module fuse... Oct 9 00:38:56.966342 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Oct 9 00:38:56.966352 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 9 00:38:56.966362 systemd[1]: Stopped File System Check on Root Device. Oct 9 00:38:56.966373 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 9 00:38:56.966383 systemd[1]: Stopped systemd-fsck-usr.service. Oct 9 00:38:56.966393 systemd[1]: Stopped Journal Service. Oct 9 00:38:56.966403 kernel: kauditd_printk_skb: 36 callbacks suppressed Oct 9 00:38:56.966413 kernel: audit: type=1334 audit(1728434336.938:47): prog-id=27 op=LOAD Oct 9 00:38:56.966427 kernel: audit: type=1334 audit(1728434336.939:48): prog-id=28 op=LOAD Oct 9 00:38:56.966438 systemd[1]: Starting Journal Service... Oct 9 00:38:56.966448 kernel: audit: type=1334 audit(1728434336.940:49): prog-id=29 op=LOAD Oct 9 00:38:56.966457 kernel: audit: type=1334 audit(1728434336.940:50): prog-id=25 op=UNLOAD Oct 9 00:38:56.966467 kernel: audit: type=1334 audit(1728434336.940:51): prog-id=26 op=UNLOAD Oct 9 00:38:56.966476 kernel: fuse: init (API version 7.32) Oct 9 00:38:56.966485 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Oct 9 00:38:56.966496 systemd[1]: Starting Remount Root and Kernel File Systems... Oct 9 00:38:56.966506 systemd[1]: Starting Apply Kernel Variables... Oct 9 00:38:56.966516 systemd[1]: Starting Coldplug All udev Devices... Oct 9 00:38:56.966527 systemd[1]: verity-setup.service: Deactivated successfully. Oct 9 00:38:56.966538 systemd[1]: Stopped verity-setup.service. Oct 9 00:38:56.966548 systemd[1]: Mounted Huge Pages File System. Oct 9 00:38:56.966558 systemd[1]: Mounted POSIX Message Queue File System. Oct 9 00:38:56.966568 systemd[1]: Mounted External Media Directory. Oct 9 00:38:56.966578 systemd[1]: Mounted Kernel Debug File System. Oct 9 00:38:56.966588 systemd[1]: Mounted Kernel Trace File System. Oct 9 00:38:56.966597 systemd[1]: Mounted Temporary Directory /tmp. Oct 9 00:38:56.966607 kernel: audit: type=1305 audit(1728434336.962:52): op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Oct 9 00:38:56.966617 systemd[1]: Finished Create missing system files. Oct 9 00:38:56.966628 systemd[1]: Finished Create List of Static Device Nodes. Oct 9 00:38:56.966639 systemd-journald[805]: Journal started Oct 9 00:38:56.966680 systemd-journald[805]: Runtime Journal (/run/log/journal/9cfe9b7ddee5481cb93d142d42d743c2) is 6.0M, max 48.5M, 42.4M free. Oct 9 00:38:54.901000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 9 00:38:54.927000 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 9 00:38:54.927000 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 9 00:38:54.927000 audit: BPF prog-id=19 op=LOAD Oct 9 00:38:54.927000 audit: BPF prog-id=19 op=UNLOAD Oct 9 00:38:54.927000 audit: BPF prog-id=20 op=LOAD Oct 9 00:38:54.927000 audit: BPF prog-id=20 op=UNLOAD Oct 9 00:38:54.960000 audit[754]: AVC avc: denied { associate } for pid=754 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 9 00:38:56.839000 audit: BPF prog-id=21 op=LOAD Oct 9 00:38:56.839000 audit: BPF prog-id=22 op=LOAD Oct 9 00:38:56.839000 audit: BPF prog-id=23 op=LOAD Oct 9 00:38:56.839000 audit: BPF prog-id=13 op=UNLOAD Oct 9 00:38:56.839000 audit: BPF prog-id=14 op=UNLOAD Oct 9 00:38:56.849000 audit: BPF prog-id=24 op=LOAD Oct 9 00:38:56.849000 audit: BPF prog-id=21 op=UNLOAD Oct 9 00:38:56.849000 audit: BPF prog-id=25 op=LOAD Oct 9 00:38:56.849000 audit: BPF prog-id=26 op=LOAD Oct 9 00:38:56.849000 audit: BPF prog-id=22 op=UNLOAD Oct 9 00:38:56.849000 audit: BPF prog-id=23 op=UNLOAD Oct 9 00:38:56.862000 audit: BPF prog-id=24 op=UNLOAD Oct 9 00:38:56.938000 audit: BPF prog-id=27 op=LOAD Oct 9 00:38:56.939000 audit: BPF prog-id=28 op=LOAD Oct 9 00:38:56.940000 audit: BPF prog-id=29 op=LOAD Oct 9 00:38:56.940000 audit: BPF prog-id=25 op=UNLOAD Oct 9 00:38:56.940000 audit: BPF prog-id=26 op=UNLOAD Oct 9 00:38:56.962000 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 9 00:38:56.837219 systemd[1]: Queued start job for default target Multi-User System. Oct 9 00:38:54.958785 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" 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 9 00:38:56.851626 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 9 00:38:54.959036 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 9 00:38:54.959054 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 9 00:38:54.959377 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Oct 9 00:38:54.959389 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="skipped missing lower profile" missing profile=oem Oct 9 00:38:54.959419 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Oct 9 00:38:54.959431 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Oct 9 00:38:54.959620 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Oct 9 00:38:54.959654 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Oct 9 00:38:54.959668 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Oct 9 00:38:54.960013 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" 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 9 00:38:54.960042 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" 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 9 00:38:54.960073 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" 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 9 00:38:54.960089 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Oct 9 00:38:54.960107 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" 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 9 00:38:54.960121 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:54Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Oct 9 00:38:56.652156 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Oct 9 00:38:56.652430 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" 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 9 00:38:56.652534 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" 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 9 00:38:56.652657 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" 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 9 00:38:56.652723 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Oct 9 00:38:56.652782 /usr/lib/systemd/system-generators/torcx-generator[754]: time="2024-10-09T00:38:56Z" 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 9 00:38:56.969104 systemd[1]: Started Journal Service. Oct 9 00:38:56.969280 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 9 00:38:56.975340 systemd[1]: Finished Load Kernel Module configfs. Oct 9 00:38:56.976217 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 00:38:56.976394 systemd[1]: Finished Load Kernel Module drm. Oct 9 00:38:56.977222 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 9 00:38:56.977396 systemd[1]: Finished Load Kernel Module fuse. Oct 9 00:38:56.978274 systemd[1]: Finished Remount Root and Kernel File Systems. Oct 9 00:38:56.979250 systemd[1]: Finished Apply Kernel Variables. Oct 9 00:38:56.982108 systemd[1]: Mounting FUSE Control File System... Oct 9 00:38:56.983697 systemd[1]: Mounting Kernel Configuration File System... Oct 9 00:38:56.984592 systemd[1]: Condition check resulted in Remount Root File System being skipped. Oct 9 00:38:56.986170 systemd[1]: Starting Rebuild Hardware Database... Oct 9 00:38:56.987852 systemd[1]: Starting Flush Journal to Persistent Storage... Oct 9 00:38:57.004152 systemd-journald[805]: Time spent on flushing to /var/log/journal/9cfe9b7ddee5481cb93d142d42d743c2 is 7.904ms for 767 entries. Oct 9 00:38:57.004152 systemd-journald[805]: System Journal (/var/log/journal/9cfe9b7ddee5481cb93d142d42d743c2) is 8.0M, max 203.0M, 195.0M free. Oct 9 00:38:56.988692 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Oct 9 00:38:56.989622 systemd[1]: Starting Load/Save Random Seed... Oct 9 00:38:56.991472 systemd[1]: Starting Create System Users... Oct 9 00:38:56.993005 systemd[1]: Mounted FUSE Control File System. Oct 9 00:38:56.995959 systemd[1]: Mounted Kernel Configuration File System. Oct 9 00:38:57.007992 systemd[1]: Finished Load/Save Random Seed. Oct 9 00:38:57.008792 systemd[1]: Reached target First Boot Complete. Oct 9 00:38:57.010306 systemd-sysusers[817]: Creating group sgx with gid 999. Oct 9 00:38:57.011815 systemd-sysusers[817]: Creating group systemd-oom with gid 998. Oct 9 00:38:57.012697 systemd-sysusers[817]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Oct 9 00:38:57.013860 systemd-sysusers[817]: Creating group systemd-timesync with gid 997. Oct 9 00:38:57.014873 systemd-sysusers[817]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Oct 9 00:38:57.021601 systemd[1]: Finished Coldplug All udev Devices. Oct 9 00:38:57.022534 systemd[1]: Finished Flush Journal to Persistent Storage. Oct 9 00:38:57.023537 systemd-sysusers[817]: Creating group systemd-coredump with gid 996. Oct 9 00:38:57.024360 systemd[1]: Starting Wait for udev To Complete Device Initialization... Oct 9 00:38:57.026620 systemd-sysusers[817]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Oct 9 00:38:57.036446 systemd[1]: Finished Create System Users. Oct 9 00:38:57.038041 systemd[1]: Starting Create Static Device Nodes in /dev... Oct 9 00:38:57.055743 systemd[1]: Finished Create Static Device Nodes in /dev. Oct 9 00:38:57.241050 systemd[1]: Finished Rebuild Hardware Database. Oct 9 00:38:57.253000 audit: BPF prog-id=30 op=LOAD Oct 9 00:38:57.254000 audit: BPF prog-id=31 op=LOAD Oct 9 00:38:57.254000 audit: BPF prog-id=32 op=LOAD Oct 9 00:38:57.255578 kernel: audit: type=1334 audit(1728434337.253:53): prog-id=30 op=LOAD Oct 9 00:38:57.255607 kernel: audit: type=1334 audit(1728434337.254:54): prog-id=31 op=LOAD Oct 9 00:38:57.255620 kernel: audit: type=1334 audit(1728434337.254:55): prog-id=32 op=LOAD Oct 9 00:38:57.255632 kernel: audit: type=1334 audit(1728434337.254:56): prog-id=17 op=UNLOAD Oct 9 00:38:57.254000 audit: BPF prog-id=17 op=UNLOAD Oct 9 00:38:57.255638 systemd[1]: Starting Rule-based Manager for Device Events and Files... Oct 9 00:38:57.254000 audit: BPF prog-id=18 op=UNLOAD Oct 9 00:38:57.278626 systemd[1]: Started Rule-based Manager for Device Events and Files. Oct 9 00:38:57.287000 audit: BPF prog-id=33 op=LOAD Oct 9 00:38:57.288256 systemd[1]: Starting Network Configuration... Oct 9 00:38:57.332241 udevadm[820]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 9 00:38:57.334267 systemd-networkd[842]: lo: Link UP Oct 9 00:38:57.334276 systemd-networkd[842]: lo: Gained carrier Oct 9 00:38:57.336448 systemd-networkd[842]: Enumeration completed Oct 9 00:38:57.336536 systemd[1]: Started Network Configuration. Oct 9 00:38:57.339423 systemd-udevd[834]: Using default interface naming scheme 'v249'. Oct 9 00:38:57.361982 systemd-networkd[842]: eth0: Link UP Oct 9 00:38:57.372192 systemd[1]: Finished Wait for udev To Complete Device Initialization. Oct 9 00:38:57.374120 systemd-networkd[842]: eth0: Gained carrier Oct 9 00:38:57.383926 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 9 00:38:57.387166 systemd-networkd[842]: eth0: DHCPv4 address 10.0.0.4/16 via 10.0.0.1 Oct 9 00:38:57.396641 lvm[850]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 00:38:57.423894 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 9 00:38:57.424743 systemd[1]: Reached target Local Encrypted Volumes. Oct 9 00:38:57.439902 systemd[1]: Starting Activation of LVM2 logical volumes... Oct 9 00:38:57.442898 lvm[851]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 00:38:57.471934 systemd[1]: Finished Activation of LVM2 logical volumes. Oct 9 00:38:57.472792 systemd[1]: Reached target Preparation for Local File Systems. Oct 9 00:38:57.473616 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Oct 9 00:38:57.473636 systemd[1]: Reached target Containers. Oct 9 00:38:57.485251 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Oct 9 00:38:57.498146 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Oct 9 00:38:57.499949 systemd[1]: Mounting /usr/share/oem... Oct 9 00:38:57.505312 kernel: BTRFS info (device vda6): disk space caching is enabled Oct 9 00:38:57.505423 kernel: BTRFS info (device vda6): has skinny extents Oct 9 00:38:57.507460 systemd[1]: Mounted /usr/share/oem. Oct 9 00:38:57.508049 systemd[1]: Reached target Local File Systems. Oct 9 00:38:57.509591 systemd[1]: Starting Rebuild Dynamic Linker Cache... Oct 9 00:38:57.510569 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Oct 9 00:38:57.510609 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Oct 9 00:38:57.511490 systemd[1]: Starting Commit a transient machine-id on disk... Oct 9 00:38:57.513115 systemd[1]: Starting Create Volatile Files and Directories... Oct 9 00:38:57.520267 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Oct 9 00:38:57.521330 systemd-tmpfiles[876]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Oct 9 00:38:57.531331 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 9 00:38:57.531893 systemd[1]: Finished Commit a transient machine-id on disk. Oct 9 00:38:57.556679 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 9 00:38:57.556690 systemd-tmpfiles[876]: Skipping /boot Oct 9 00:38:57.561511 systemd-tmpfiles[876]: Detected autofs mount point /boot during canonicalization of /boot. Oct 9 00:38:57.561528 systemd-tmpfiles[876]: Skipping /boot Oct 9 00:38:57.578664 systemd[1]: Finished Rebuild Dynamic Linker Cache. Oct 9 00:38:57.595609 systemd[1]: Finished Create Volatile Files and Directories. Oct 9 00:38:57.597417 systemd[1]: Starting Load Security Auditing Rules... Oct 9 00:38:57.598955 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Oct 9 00:38:57.600626 systemd[1]: Starting Rebuild Journal Catalog... Oct 9 00:38:57.604000 audit: BPF prog-id=34 op=LOAD Oct 9 00:38:57.605905 systemd[1]: Starting Network Name Resolution... Oct 9 00:38:57.607000 audit: BPF prog-id=35 op=LOAD Oct 9 00:38:57.608342 systemd[1]: Starting Network Time Synchronization... Oct 9 00:38:57.610118 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Oct 9 00:38:57.611907 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Oct 9 00:38:57.612961 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Oct 9 00:38:57.617285 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Oct 9 00:38:57.621483 systemd[1]: Finished Rebuild Journal Catalog. Oct 9 00:38:57.623005 systemd[1]: Starting Update is Completed... Oct 9 00:38:57.627735 systemd[1]: Finished Update is Completed. Oct 9 00:38:57.638038 augenrules[898]: No rules Oct 9 00:38:57.637000 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 9 00:38:57.645452 systemd[1]: Finished Load Security Auditing Rules. Oct 9 00:38:57.648475 systemd[1]: Started Network Time Synchronization. Oct 9 00:38:57.193099 systemd-timesyncd[887]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Oct 9 00:38:57.193261 systemd[1]: Reached target System Initialization. Oct 9 00:38:57.194057 systemd[1]: Started Watch for update engine configuration changes. Oct 9 00:38:57.194987 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 9 00:38:57.195919 systemd[1]: Started Daily Cleanup of Temporary Directories. Oct 9 00:38:57.196757 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Oct 9 00:38:57.196782 systemd[1]: Reached target Path Units. Oct 9 00:38:57.198216 systemd[1]: Reached target System Time Set. Oct 9 00:38:57.198577 systemd-resolved[881]: Positive Trust Anchors: Oct 9 00:38:57.199002 systemd[1]: Started Daily Log Rotation. Oct 9 00:38:57.199667 systemd[1]: Started Weekly check for MD array's redundancy information.. Oct 9 00:38:57.200640 systemd[1]: Reached target Timer Units. Oct 9 00:38:57.201508 systemd[1]: Listening on D-Bus System Message Bus Socket. Oct 9 00:38:57.202038 systemd-resolved[881]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 00:38:57.202069 systemd-resolved[881]: 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 9 00:38:57.202769 systemd-resolved[881]: Defaulting to hostname 'linux'. Oct 9 00:38:57.208632 systemd[1]: Starting Docker Socket for the API... Oct 9 00:38:57.211365 systemd[1]: Listening on OpenSSH Server Socket. Oct 9 00:38:57.212383 systemd[1]: Started Network Name Resolution. Oct 9 00:38:57.213061 systemd[1]: Listening on Docker Socket for the API. Oct 9 00:38:57.213712 systemd[1]: Reached target Network. Oct 9 00:38:57.214218 systemd[1]: Reached target Host and Network Name Lookups. Oct 9 00:38:57.214844 systemd[1]: Reached target Socket Units. Oct 9 00:38:57.215379 systemd[1]: Reached target Basic System. Oct 9 00:38:57.215943 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Oct 9 00:38:57.215968 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Oct 9 00:38:57.216974 systemd[1]: Started D-Bus System Message Bus. Oct 9 00:38:57.219761 systemd[1]: Starting Extend Filesystems... Oct 9 00:38:57.220397 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Oct 9 00:38:57.221361 systemd[1]: Starting Generate /run/flatcar/motd... Oct 9 00:38:57.222870 systemd[1]: Starting Install an ssh key from /proc/cmdline... Oct 9 00:38:57.227341 systemd[1]: Starting Generate sshd host keys... Oct 9 00:38:57.228055 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Oct 9 00:38:57.228092 systemd[1]: Reached target Load system-provided cloud configs. Oct 9 00:38:57.230506 systemd[1]: Starting User Login Management... Oct 9 00:38:57.230797 extend-filesystems[908]: Found vda Oct 9 00:38:57.230797 extend-filesystems[908]: Found vda1 Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda2 Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda3 Oct 9 00:38:57.232115 extend-filesystems[908]: Found usr Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda4 Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda6 Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda7 Oct 9 00:38:57.232115 extend-filesystems[908]: Found vda9 Oct 9 00:38:57.232115 extend-filesystems[908]: Checking size of /dev/vda9 Oct 9 00:38:57.231350 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 9 00:38:57.232137 systemd[1]: Starting Update Engine... Oct 9 00:38:57.233170 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Oct 9 00:38:57.233200 systemd[1]: Reached target Load user-provided cloud configs. Oct 9 00:38:57.235480 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 9 00:38:57.235692 systemd[1]: Finished Install an ssh key from /proc/cmdline. Oct 9 00:38:57.244220 systemd[1]: motdgen.service: Deactivated successfully. Oct 9 00:38:57.244449 systemd[1]: Finished Generate /run/flatcar/motd. Oct 9 00:38:57.253205 extend-filesystems[908]: Old size kept for /dev/vda9 Oct 9 00:38:57.258344 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 9 00:38:57.258576 systemd[1]: Finished Extend Filesystems. Oct 9 00:38:57.275419 update_engine[921]: I1009 00:38:57.275102 921 main.cc:89] Flatcar Update Engine starting Oct 9 00:38:57.281660 systemd[1]: Started Update Engine. Oct 9 00:38:57.281883 update_engine[921]: I1009 00:38:57.281844 921 update_check_scheduler.cc:74] Next update check in 8m17s Oct 9 00:38:57.282988 systemd-logind[919]: Watching system buttons on /dev/input/event0 (Power Button) Oct 9 00:38:57.283256 systemd-logind[919]: New seat seat0. Oct 9 00:38:57.290133 systemd[1]: Started Cluster reboot manager. Oct 9 00:38:57.291125 systemd[1]: Started User Login Management. Oct 9 00:38:57.320163 locksmithd[931]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 9 00:38:57.862907 systemd[1]: Created slice Slice /system/sshd. Oct 9 00:38:58.434179 systemd-networkd[842]: eth0: Gained IPv6LL Oct 9 00:38:59.153895 sshd_keygen[926]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Oct 9 00:38:59.171705 systemd[1]: Finished Generate sshd host keys. Oct 9 00:38:59.181948 systemd[1]: Starting Generate /run/issue... Oct 9 00:38:59.183321 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56036). Oct 9 00:38:59.186269 systemd[1]: issuegen.service: Deactivated successfully. Oct 9 00:38:59.186501 systemd[1]: Finished Generate /run/issue. Oct 9 00:38:59.188209 systemd[1]: Starting Permit User Sessions... Oct 9 00:38:59.192601 systemd[1]: Finished Permit User Sessions. Oct 9 00:38:59.194232 systemd[1]: Started Getty on tty1. Oct 9 00:38:59.195909 systemd[1]: Started Serial Getty on ttyAMA0. Oct 9 00:38:59.196866 systemd[1]: Reached target Login Prompts. Oct 9 00:38:59.197551 systemd[1]: Reached target Multi-User System. Oct 9 00:38:59.199302 systemd[1]: Starting Record Runlevel Change in UTMP... Oct 9 00:38:59.203722 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Oct 9 00:38:59.203913 systemd[1]: Finished Record Runlevel Change in UTMP. Oct 9 00:38:59.204833 systemd[1]: Startup finished in 692ms (kernel) + 2.295s (initrd) + 4.790s (userspace) = 7.777s. Oct 9 00:38:59.228231 sshd[946]: Accepted publickey for core from 10.0.0.1 port 56036 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.229743 sshd[946]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.242350 systemd[1]: Created slice User Slice of UID 500. Oct 9 00:38:59.243191 systemd[1]: Starting User Runtime Directory /run/user/500... Oct 9 00:38:59.246634 systemd-logind[919]: New session 1 of user core. Oct 9 00:38:59.249168 systemd[1]: Finished User Runtime Directory /run/user/500. Oct 9 00:38:59.250734 systemd[1]: Starting User Manager for UID 500... Oct 9 00:38:59.252732 systemd[955]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.299890 systemd[955]: Queued start job for default target Main User Target. Oct 9 00:38:59.300045 systemd[955]: Reached target Paths. Oct 9 00:38:59.300058 systemd[955]: Reached target Sockets. Oct 9 00:38:59.300069 systemd[955]: Reached target Timers. Oct 9 00:38:59.300079 systemd[955]: Reached target Basic System. Oct 9 00:38:59.300118 systemd[955]: Reached target Main User Target. Oct 9 00:38:59.300132 systemd[955]: Startup finished in 43ms. Oct 9 00:38:59.300325 systemd[1]: Started User Manager for UID 500. Oct 9 00:38:59.307247 systemd[1]: Started Session 1 of User core. Oct 9 00:38:59.366249 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56044). Oct 9 00:38:59.404495 sshd[964]: Accepted publickey for core from 10.0.0.1 port 56044 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.405427 sshd[964]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.408448 systemd-logind[919]: New session 2 of user core. Oct 9 00:38:59.421099 systemd[1]: Started Session 2 of User core. Oct 9 00:38:59.475490 sshd[964]: pam_unix(sshd:session): session closed for user core Oct 9 00:38:59.487641 systemd[1]: sshd@1-10.0.0.4:22-10.0.0.1:56044.service: Deactivated successfully. Oct 9 00:38:59.488266 systemd[1]: session-2.scope: Deactivated successfully. Oct 9 00:38:59.488891 systemd-logind[919]: Session 2 logged out. Waiting for processes to exit. Oct 9 00:38:59.490038 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56046). Oct 9 00:38:59.491254 systemd-logind[919]: Removed session 2. Oct 9 00:38:59.529017 sshd[970]: Accepted publickey for core from 10.0.0.1 port 56046 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.530368 sshd[970]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.533645 systemd-logind[919]: New session 3 of user core. Oct 9 00:38:59.548114 systemd[1]: Started Session 3 of User core. Oct 9 00:38:59.596803 sshd[970]: pam_unix(sshd:session): session closed for user core Oct 9 00:38:59.605949 systemd[1]: sshd@2-10.0.0.4:22-10.0.0.1:56046.service: Deactivated successfully. Oct 9 00:38:59.606581 systemd[1]: session-3.scope: Deactivated successfully. Oct 9 00:38:59.607249 systemd-logind[919]: Session 3 logged out. Waiting for processes to exit. Oct 9 00:38:59.608294 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56054). Oct 9 00:38:59.609172 systemd-logind[919]: Removed session 3. Oct 9 00:38:59.646604 sshd[977]: Accepted publickey for core from 10.0.0.1 port 56054 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.648047 sshd[977]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.653271 systemd-logind[919]: New session 4 of user core. Oct 9 00:38:59.667105 systemd[1]: Started Session 4 of User core. Oct 9 00:38:59.723520 sshd[977]: pam_unix(sshd:session): session closed for user core Oct 9 00:38:59.736540 systemd[1]: sshd@3-10.0.0.4:22-10.0.0.1:56054.service: Deactivated successfully. Oct 9 00:38:59.737143 systemd[1]: session-4.scope: Deactivated successfully. Oct 9 00:38:59.739685 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56068). Oct 9 00:38:59.739979 systemd-logind[919]: Session 4 logged out. Waiting for processes to exit. Oct 9 00:38:59.740718 systemd-logind[919]: Removed session 4. Oct 9 00:38:59.779608 sshd[983]: Accepted publickey for core from 10.0.0.1 port 56068 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.780962 sshd[983]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.784817 systemd-logind[919]: New session 5 of user core. Oct 9 00:38:59.790094 systemd[1]: Started Session 5 of User core. Oct 9 00:38:59.852244 sudo[986]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Oct 9 00:38:59.852704 sudo[986]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:38:59.867341 sudo[986]: pam_unix(sudo:session): session closed for user root Oct 9 00:38:59.871415 sshd[983]: pam_unix(sshd:session): session closed for user core Oct 9 00:38:59.882108 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56076). Oct 9 00:38:59.885301 dbus-daemon[907]: [system] Reloaded configuration Oct 9 00:38:59.888285 systemd[1]: sshd@4-10.0.0.4:22-10.0.0.1:56068.service: Deactivated successfully. Oct 9 00:38:59.888928 systemd[1]: session-5.scope: Deactivated successfully. Oct 9 00:38:59.889674 systemd-logind[919]: Session 5 logged out. Waiting for processes to exit. Oct 9 00:38:59.890676 systemd-logind[919]: Removed session 5. Oct 9 00:38:59.924190 sshd[989]: Accepted publickey for core from 10.0.0.1 port 56076 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:38:59.926915 sshd[989]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:38:59.931325 systemd-logind[919]: New session 6 of user core. Oct 9 00:38:59.942076 systemd[1]: Started Session 6 of User core. Oct 9 00:38:59.993698 sudo[995]: 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 9 00:38:59.993902 sudo[995]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:38:59.996968 sudo[995]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:00.000832 sudo[994]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Oct 9 00:39:00.001085 sudo[994]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:00.022012 systemd[1]: Stopping Load Security Auditing Rules... Oct 9 00:39:00.021000 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 9 00:39:00.024131 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 00:39:00.024494 auditctl[998]: No rules Oct 9 00:39:00.024360 systemd[1]: Stopped Load Security Auditing Rules. Oct 9 00:39:00.025889 systemd[1]: Starting Load Security Auditing Rules... Oct 9 00:39:00.040396 augenrules[1015]: No rules Oct 9 00:39:00.041757 systemd[1]: Finished Load Security Auditing Rules. Oct 9 00:39:00.042890 sudo[994]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:00.044120 sshd[989]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:00.047487 systemd[1]: sshd@5-10.0.0.4:22-10.0.0.1:56076.service: Deactivated successfully. Oct 9 00:39:00.048045 systemd[1]: session-6.scope: Deactivated successfully. Oct 9 00:39:00.048665 systemd-logind[919]: Session 6 logged out. Waiting for processes to exit. Oct 9 00:39:00.049691 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56088). Oct 9 00:39:00.050423 systemd-logind[919]: Removed session 6. Oct 9 00:39:00.088608 sshd[1021]: Accepted publickey for core from 10.0.0.1 port 56088 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:00.089885 sshd[1021]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:00.092443 systemd-logind[919]: New session 7 of user core. Oct 9 00:39:00.103088 systemd[1]: Started Session 7 of User core. Oct 9 00:39:00.157983 sudo[1024]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Oct 9 00:39:00.158187 sudo[1024]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:00.161305 sudo[1024]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:00.162532 sshd[1021]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:00.179131 systemd[1]: sshd@6-10.0.0.4:22-10.0.0.1:56088.service: Deactivated successfully. Oct 9 00:39:00.179964 systemd[1]: session-7.scope: Deactivated successfully. Oct 9 00:39:00.181229 systemd-logind[919]: Session 7 logged out. Waiting for processes to exit. Oct 9 00:39:00.182305 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56102). Oct 9 00:39:00.183355 systemd-logind[919]: Removed session 7. Oct 9 00:39:00.222964 sshd[1028]: Accepted publickey for core from 10.0.0.1 port 56102 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:00.224302 sshd[1028]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:00.227206 systemd-logind[919]: New session 8 of user core. Oct 9 00:39:00.233326 systemd[1]: Started Session 8 of User core. Oct 9 00:39:00.286817 sudo[1031]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Oct 9 00:39:00.287050 sudo[1031]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:00.499156 sudo[1031]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:00.500627 sshd[1028]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:00.516639 systemd[1]: sshd@7-10.0.0.4:22-10.0.0.1:56102.service: Deactivated successfully. Oct 9 00:39:00.517704 systemd[1]: session-8.scope: Deactivated successfully. Oct 9 00:39:00.518537 systemd-logind[919]: Session 8 logged out. Waiting for processes to exit. Oct 9 00:39:00.520012 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56118). Oct 9 00:39:00.521055 systemd-logind[919]: Removed session 8. Oct 9 00:39:00.561000 sshd[1035]: Accepted publickey for core from 10.0.0.1 port 56118 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:00.562299 sshd[1035]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:00.565346 systemd-logind[919]: New session 9 of user core. Oct 9 00:39:00.575287 systemd[1]: Started Session 9 of User core. Oct 9 00:39:00.626573 sudo[1038]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Oct 9 00:39:00.626781 sudo[1038]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:00.629824 sudo[1038]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:00.631414 sshd[1035]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:00.645926 systemd[1]: sshd@8-10.0.0.4:22-10.0.0.1:56118.service: Deactivated successfully. Oct 9 00:39:00.646822 systemd[1]: session-9.scope: Deactivated successfully. Oct 9 00:39:00.647678 systemd-logind[919]: Session 9 logged out. Waiting for processes to exit. Oct 9 00:39:00.649240 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:56120). Oct 9 00:39:00.650335 systemd-logind[919]: Removed session 9. Oct 9 00:39:00.687379 sshd[1042]: Accepted publickey for core from 10.0.0.1 port 56120 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:00.688396 sshd[1042]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:00.691335 systemd-logind[919]: New session 10 of user core. Oct 9 00:39:00.698087 systemd[1]: Started Session 10 of User core. Oct 9 00:39:00.748640 sudo[1045]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Oct 9 00:39:00.749130 sudo[1045]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:02.580842 sudo[1045]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:02.582266 sshd[1042]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:02.594625 systemd[1]: sshd@9-10.0.0.4:22-10.0.0.1:56120.service: Deactivated successfully. Oct 9 00:39:02.595206 systemd[1]: session-10.scope: Deactivated successfully. Oct 9 00:39:02.595451 systemd[1]: session-10.scope: Consumed 1.222s CPU time. Oct 9 00:39:02.596623 systemd-logind[919]: Session 10 logged out. Waiting for processes to exit. Oct 9 00:39:02.597202 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52120). Oct 9 00:39:02.598331 systemd-logind[919]: Removed session 10. Oct 9 00:39:02.636445 sshd[1049]: Accepted publickey for core from 10.0.0.1 port 52120 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:02.637463 sshd[1049]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:02.639034 kernel: kauditd_printk_skb: 10 callbacks suppressed Oct 9 00:39:02.639107 kernel: audit: type=1006 audit(1728434342.635:67): pid=1049 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=11 res=1 Oct 9 00:39:02.640866 systemd-logind[919]: New session 11 of user core. Oct 9 00:39:02.655090 systemd[1]: Started Session 11 of User core. Oct 9 00:39:02.708313 sudo[1052]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.payload Omaha Oct 9 00:39:02.708541 sudo[1052]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Oct 9 00:39:02.729143 systemd[1]: Started /home/core/./kolet run cl.update.payload Omaha. Oct 9 00:39:02.730179 sudo[1052]: pam_unix(sudo:session): session closed for user root Oct 9 00:39:02.732125 sshd[1049]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:02.734857 systemd[1]: sshd@10-10.0.0.4:22-10.0.0.1:52120.service: Deactivated successfully. Oct 9 00:39:02.735579 systemd[1]: session-11.scope: Deactivated successfully. Oct 9 00:39:02.736232 systemd-logind[919]: Session 11 logged out. Waiting for processes to exit. Oct 9 00:39:02.736866 systemd-logind[919]: Removed session 11. Oct 9 00:39:07.741491 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:52136). Oct 9 00:39:07.780203 sshd[1063]: Accepted publickey for core from 10.0.0.1 port 52136 ssh2: RSA SHA256:60k/UnDs2xCDM+iEzqxKwQzs737wmpdF/6B9zSTG41g Oct 9 00:39:07.781510 sshd[1063]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Oct 9 00:39:07.782952 kernel: audit: type=1006 audit(1728434347.779:68): pid=1063 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=12 res=1 Oct 9 00:39:07.784407 systemd-logind[919]: New session 12 of user core. Oct 9 00:39:07.795068 systemd[1]: Started Session 12 of User core. Oct 9 00:39:07.853312 sshd[1063]: pam_unix(sshd:session): session closed for user core Oct 9 00:39:07.855555 systemd-logind[919]: Session 12 logged out. Waiting for processes to exit. Oct 9 00:39:07.855901 systemd[1]: sshd@11-10.0.0.4:22-10.0.0.1:52136.service: Deactivated successfully. Oct 9 00:39:07.856669 systemd[1]: session-12.scope: Deactivated successfully. Oct 9 00:39:07.857376 systemd-logind[919]: Removed session 12. Oct 9 00:39:42.462949 update_engine[921]: I1009 00:39:42.462878 921 update_attempter.cc:505] Updating boot flags...