Dec 13 01:19:00.717249 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Dec 13 01:19:00.717266 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 Dec 13 01:19:00.717275 kernel: efi: EFI v2.70 by EDK II Dec 13 01:19:00.717281 kernel: efi: SMBIOS 3.0=0xdced0000 MEMATTR=0xdba6e018 ACPI 2.0=0xd9710018 RNG=0xdcfdd318 MEMRESERVE=0xd9b41c18 Dec 13 01:19:00.717286 kernel: efi: seeding entropy pool Dec 13 01:19:00.717291 kernel: efi: memattr: Unexpected EFI Memory Attributes table version 2 Dec 13 01:19:00.717297 kernel: ACPI: Early table checksum verification disabled Dec 13 01:19:00.717302 kernel: ACPI: RSDP 0x00000000D9710018 000024 (v02 BOCHS ) Dec 13 01:19:00.717307 kernel: ACPI: XSDT 0x00000000D971FE98 000064 (v01 BOCHS BXPC 00000001 01000013) Dec 13 01:19:00.717312 kernel: ACPI: FACP 0x00000000D971FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717319 kernel: ACPI: DSDT 0x00000000D9717518 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717324 kernel: ACPI: APIC 0x00000000D971FC18 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717329 kernel: ACPI: PPTT 0x00000000D971D898 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717334 kernel: ACPI: GTDT 0x00000000D971E818 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717340 kernel: ACPI: MCFG 0x00000000D971E918 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717346 kernel: ACPI: SPCR 0x00000000D971FF98 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717378 kernel: ACPI: DBG2 0x00000000D971E418 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717384 kernel: ACPI: IORT 0x00000000D971E718 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Dec 13 01:19:00.717389 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Dec 13 01:19:00.717395 kernel: ACPI: NUMA: Failed to initialise from firmware Dec 13 01:19:00.717400 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:19:00.717405 kernel: NUMA: NODE_DATA [mem 0xdc95b900-0xdc960fff] Dec 13 01:19:00.717410 kernel: Zone ranges: Dec 13 01:19:00.717416 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:19:00.717421 kernel: DMA32 empty Dec 13 01:19:00.717426 kernel: Normal empty Dec 13 01:19:00.717431 kernel: Movable zone start for each node Dec 13 01:19:00.717437 kernel: Early memory node ranges Dec 13 01:19:00.717443 kernel: node 0: [mem 0x0000000040000000-0x00000000d976ffff] Dec 13 01:19:00.717448 kernel: node 0: [mem 0x00000000d9770000-0x00000000d9b3ffff] Dec 13 01:19:00.717453 kernel: node 0: [mem 0x00000000d9b40000-0x00000000dce1ffff] Dec 13 01:19:00.717459 kernel: node 0: [mem 0x00000000dce20000-0x00000000dceaffff] Dec 13 01:19:00.717464 kernel: node 0: [mem 0x00000000dceb0000-0x00000000dcebffff] Dec 13 01:19:00.717469 kernel: node 0: [mem 0x00000000dcec0000-0x00000000dcfdffff] Dec 13 01:19:00.717474 kernel: node 0: [mem 0x00000000dcfe0000-0x00000000dcffffff] Dec 13 01:19:00.717480 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Dec 13 01:19:00.717485 kernel: On node 0 totalpages: 643072 Dec 13 01:19:00.717490 kernel: DMA zone: 10048 pages used for memmap Dec 13 01:19:00.717495 kernel: DMA zone: 0 pages reserved Dec 13 01:19:00.717502 kernel: DMA zone: 643072 pages, LIFO batch:63 Dec 13 01:19:00.717509 kernel: psci: probing for conduit method from ACPI. Dec 13 01:19:00.717515 kernel: psci: PSCIv1.1 detected in firmware. Dec 13 01:19:00.717521 kernel: psci: Using standard PSCI v0.2 function IDs Dec 13 01:19:00.717526 kernel: psci: Trusted OS migration not required Dec 13 01:19:00.717532 kernel: psci: SMC Calling Convention v1.1 Dec 13 01:19:00.717537 kernel: ACPI: SRAT not present Dec 13 01:19:00.717543 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Dec 13 01:19:00.717548 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Dec 13 01:19:00.717554 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Dec 13 01:19:00.717561 kernel: Detected PIPT I-cache on CPU0 Dec 13 01:19:00.717567 kernel: CPU features: detected: GIC system register CPU interface Dec 13 01:19:00.717572 kernel: CPU features: kernel page table isolation forced ON by KASLR Dec 13 01:19:00.717578 kernel: CPU features: detected: Kernel page table isolation (KPTI) Dec 13 01:19:00.717584 kernel: CPU features: detected: Hardware dirty bit management Dec 13 01:19:00.717589 kernel: CPU features: detected: Spectre-v4 Dec 13 01:19:00.717594 kernel: CPU features: detected: ARM erratum 1418040 Dec 13 01:19:00.717600 kernel: CPU features: detected: Spectre-BHB Dec 13 01:19:00.717606 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Dec 13 01:19:00.717611 kernel: Policy zone: DMA Dec 13 01:19:00.717619 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 Dec 13 01:19:00.717625 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Dec 13 01:19:00.717631 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Dec 13 01:19:00.717637 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Dec 13 01:19:00.717643 kernel: Memory: 2452056K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 120232K reserved, 0K cma-reserved) Dec 13 01:19:00.717648 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Dec 13 01:19:00.717654 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Dec 13 01:19:00.717660 kernel: rcu: Preemptible hierarchical RCU implementation. Dec 13 01:19:00.717666 kernel: rcu: RCU event tracing is enabled. Dec 13 01:19:00.717672 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Dec 13 01:19:00.717677 kernel: Trampoline variant of Tasks RCU enabled. Dec 13 01:19:00.717684 kernel: Tracing variant of Tasks RCU enabled. Dec 13 01:19:00.717690 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Dec 13 01:19:00.717696 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Dec 13 01:19:00.717701 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Dec 13 01:19:00.717707 kernel: GICv3: 256 SPIs implemented Dec 13 01:19:00.717713 kernel: GICv3: 0 Extended SPIs implemented Dec 13 01:19:00.717718 kernel: GICv3: Distributor has no Range Selector support Dec 13 01:19:00.717730 kernel: GICv3: 16 PPIs implemented Dec 13 01:19:00.717736 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Dec 13 01:19:00.717742 kernel: ACPI: SRAT not present Dec 13 01:19:00.717747 kernel: ITS [mem 0x08080000-0x0809ffff] Dec 13 01:19:00.717753 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Dec 13 01:19:00.717761 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Dec 13 01:19:00.717766 kernel: GICv3: using LPI property table @0x00000000400d0000 Dec 13 01:19:00.717772 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Dec 13 01:19:00.717778 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:19:00.717784 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Dec 13 01:19:00.717789 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Dec 13 01:19:00.717795 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Dec 13 01:19:00.717801 kernel: arm-pv: using stolen time PV Dec 13 01:19:00.717807 kernel: Console: colour dummy device 80x25 Dec 13 01:19:00.717813 kernel: ACPI: Core revision 20200925 Dec 13 01:19:00.717819 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Dec 13 01:19:00.717826 kernel: pid_max: default: 32768 minimum: 301 Dec 13 01:19:00.717832 kernel: LSM: Security Framework initializing Dec 13 01:19:00.717838 kernel: SELinux: Initializing. Dec 13 01:19:00.717844 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:19:00.717850 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Dec 13 01:19:00.717855 kernel: rcu: Hierarchical SRCU implementation. Dec 13 01:19:00.717861 kernel: Platform MSI: ITS@0x8080000 domain created Dec 13 01:19:00.717867 kernel: PCI/MSI: ITS@0x8080000 domain created Dec 13 01:19:00.717873 kernel: Remapping and enabling EFI services. Dec 13 01:19:00.717880 kernel: smp: Bringing up secondary CPUs ... Dec 13 01:19:00.717886 kernel: Detected PIPT I-cache on CPU1 Dec 13 01:19:00.717891 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Dec 13 01:19:00.717897 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Dec 13 01:19:00.717903 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:19:00.717909 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Dec 13 01:19:00.717915 kernel: Detected PIPT I-cache on CPU2 Dec 13 01:19:00.717921 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Dec 13 01:19:00.717927 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Dec 13 01:19:00.717933 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:19:00.717940 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Dec 13 01:19:00.717946 kernel: Detected PIPT I-cache on CPU3 Dec 13 01:19:00.717952 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Dec 13 01:19:00.717957 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Dec 13 01:19:00.717963 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Dec 13 01:19:00.717969 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Dec 13 01:19:00.717979 kernel: smp: Brought up 1 node, 4 CPUs Dec 13 01:19:00.717985 kernel: SMP: Total of 4 processors activated. Dec 13 01:19:00.717992 kernel: CPU features: detected: Privileged Access Never Dec 13 01:19:00.717998 kernel: CPU features: detected: LSE atomic instructions Dec 13 01:19:00.718004 kernel: CPU features: detected: User Access Override Dec 13 01:19:00.718010 kernel: CPU features: detected: 32-bit EL0 Support Dec 13 01:19:00.718016 kernel: CPU features: detected: Common not Private translations Dec 13 01:19:00.718023 kernel: CPU features: detected: RAS Extension Support Dec 13 01:19:00.718029 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Dec 13 01:19:00.718035 kernel: CPU features: detected: CRC32 instructions Dec 13 01:19:00.718041 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Dec 13 01:19:00.718048 kernel: CPU: All CPU(s) started at EL1 Dec 13 01:19:00.718054 kernel: alternatives: patching kernel code Dec 13 01:19:00.718060 kernel: devtmpfs: initialized Dec 13 01:19:00.718067 kernel: KASLR enabled Dec 13 01:19:00.718073 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Dec 13 01:19:00.718080 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Dec 13 01:19:00.718087 kernel: pinctrl core: initialized pinctrl subsystem Dec 13 01:19:00.718093 kernel: SMBIOS 3.0.0 present. Dec 13 01:19:00.718099 kernel: DMI: QEMU KVM Virtual Machine, BIOS edk2-20230524-3.fc38 05/24/2023 Dec 13 01:19:00.718105 kernel: NET: Registered protocol family 16 Dec 13 01:19:00.718111 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Dec 13 01:19:00.718117 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Dec 13 01:19:00.718123 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Dec 13 01:19:00.718129 kernel: audit: initializing netlink subsys (disabled) Dec 13 01:19:00.718136 kernel: audit: type=2000 audit(0.035:1): state=initialized audit_enabled=0 res=1 Dec 13 01:19:00.718143 kernel: thermal_sys: Registered thermal governor 'step_wise' Dec 13 01:19:00.718149 kernel: cpuidle: using governor menu Dec 13 01:19:00.718155 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Dec 13 01:19:00.718161 kernel: ASID allocator initialised with 32768 entries Dec 13 01:19:00.718167 kernel: ACPI: bus type PCI registered Dec 13 01:19:00.718173 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Dec 13 01:19:00.718179 kernel: Serial: AMBA PL011 UART driver Dec 13 01:19:00.718186 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Dec 13 01:19:00.718192 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Dec 13 01:19:00.718199 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Dec 13 01:19:00.718205 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Dec 13 01:19:00.718211 kernel: cryptd: max_cpu_qlen set to 1000 Dec 13 01:19:00.718217 kernel: ACPI: Added _OSI(Module Device) Dec 13 01:19:00.718223 kernel: ACPI: Added _OSI(Processor Device) Dec 13 01:19:00.718230 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Dec 13 01:19:00.718236 kernel: ACPI: Added _OSI(Processor Aggregator Device) Dec 13 01:19:00.718242 kernel: ACPI: Added _OSI(Linux-Dell-Video) Dec 13 01:19:00.718248 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Dec 13 01:19:00.718256 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Dec 13 01:19:00.718262 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Dec 13 01:19:00.718268 kernel: ACPI: Interpreter enabled Dec 13 01:19:00.718274 kernel: ACPI: Using GIC for interrupt routing Dec 13 01:19:00.718280 kernel: ACPI: MCFG table detected, 1 entries Dec 13 01:19:00.718286 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Dec 13 01:19:00.718292 kernel: printk: console [ttyAMA0] enabled Dec 13 01:19:00.718299 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Dec 13 01:19:00.718434 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Dec 13 01:19:00.718498 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Dec 13 01:19:00.718552 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Dec 13 01:19:00.718605 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Dec 13 01:19:00.718658 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Dec 13 01:19:00.718667 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Dec 13 01:19:00.718673 kernel: PCI host bridge to bus 0000:00 Dec 13 01:19:00.718749 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Dec 13 01:19:00.718804 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Dec 13 01:19:00.718854 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Dec 13 01:19:00.718901 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Dec 13 01:19:00.718967 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Dec 13 01:19:00.719029 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Dec 13 01:19:00.719087 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Dec 13 01:19:00.719143 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Dec 13 01:19:00.719203 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 01:19:00.719261 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Dec 13 01:19:00.719320 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Dec 13 01:19:00.719400 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Dec 13 01:19:00.719454 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Dec 13 01:19:00.719504 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Dec 13 01:19:00.719554 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Dec 13 01:19:00.719565 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Dec 13 01:19:00.719571 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Dec 13 01:19:00.719578 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Dec 13 01:19:00.719594 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Dec 13 01:19:00.719600 kernel: vgaarb: loaded Dec 13 01:19:00.719607 kernel: Registered efivars operations Dec 13 01:19:00.719613 kernel: clocksource: Switched to clocksource arch_sys_counter Dec 13 01:19:00.719619 kernel: VFS: Disk quotas dquot_6.6.0 Dec 13 01:19:00.719626 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Dec 13 01:19:00.719632 kernel: pnp: PnP ACPI init Dec 13 01:19:00.719694 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Dec 13 01:19:00.719756 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Dec 13 01:19:00.719764 kernel: pnp: PnP ACPI: found 1 devices Dec 13 01:19:00.719771 kernel: NET: Registered protocol family 2 Dec 13 01:19:00.719777 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Dec 13 01:19:00.719783 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Dec 13 01:19:00.719790 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Dec 13 01:19:00.719796 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Dec 13 01:19:00.719804 kernel: TCP: Hash tables configured (established 32768 bind 32768) Dec 13 01:19:00.719811 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:19:00.719817 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Dec 13 01:19:00.719823 kernel: NET: Registered protocol family 1 Dec 13 01:19:00.719829 kernel: PCI: CLS 0 bytes, default 64 Dec 13 01:19:00.719835 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Dec 13 01:19:00.719841 kernel: kvm [1]: HYP mode not available Dec 13 01:19:00.719847 kernel: Initialise system trusted keyrings Dec 13 01:19:00.719854 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Dec 13 01:19:00.719861 kernel: Key type asymmetric registered Dec 13 01:19:00.719867 kernel: Asymmetric key parser 'x509' registered Dec 13 01:19:00.719873 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Dec 13 01:19:00.719880 kernel: io scheduler mq-deadline registered Dec 13 01:19:00.719886 kernel: io scheduler kyber registered Dec 13 01:19:00.719892 kernel: io scheduler bfq registered Dec 13 01:19:00.719899 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Dec 13 01:19:00.719905 kernel: ACPI: Power Button [PWRB] Dec 13 01:19:00.719911 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Dec 13 01:19:00.719971 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Dec 13 01:19:00.719979 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Dec 13 01:19:00.719985 kernel: msm_serial: driver initialized Dec 13 01:19:00.719992 kernel: random: fast init done Dec 13 01:19:00.719997 kernel: random: crng init done Dec 13 01:19:00.720004 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Dec 13 01:19:00.720010 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Dec 13 01:19:00.720016 kernel: thunder_xcv, ver 1.0 Dec 13 01:19:00.720022 kernel: thunder_bgx, ver 1.0 Dec 13 01:19:00.720028 kernel: nicpf, ver 1.0 Dec 13 01:19:00.720036 kernel: nicvf, ver 1.0 Dec 13 01:19:00.720105 kernel: rtc-efi rtc-efi.0: registered as rtc0 Dec 13 01:19:00.720158 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-12-13T01:19:00 UTC (1734052740) Dec 13 01:19:00.720166 kernel: hid: raw HID events driver (C) Jiri Kosina Dec 13 01:19:00.720173 kernel: NET: Registered protocol family 10 Dec 13 01:19:00.720179 kernel: Segment Routing with IPv6 Dec 13 01:19:00.720185 kernel: NET: Registered protocol family 17 Dec 13 01:19:00.720191 kernel: Key type dns_resolver registered Dec 13 01:19:00.720199 kernel: registered taskstats version 1 Dec 13 01:19:00.720205 kernel: Loading compiled-in X.509 certificates Dec 13 01:19:00.720212 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Dec 13 01:19:00.720218 kernel: Key type ._fscrypt registered Dec 13 01:19:00.720224 kernel: Key type .fscrypt registered Dec 13 01:19:00.720230 kernel: Key type fscrypt-provisioning registered Dec 13 01:19:00.720236 kernel: ima: No TPM chip found, activating TPM-bypass! Dec 13 01:19:00.720242 kernel: ima: Allocated hash algorithm: sha1 Dec 13 01:19:00.720248 kernel: ima: No architecture policies found Dec 13 01:19:00.720255 kernel: Freeing unused kernel memory: 34240K Dec 13 01:19:00.720261 kernel: Run /init as init process Dec 13 01:19:00.720267 kernel: with arguments: Dec 13 01:19:00.720273 kernel: /init Dec 13 01:19:00.720279 kernel: with environment: Dec 13 01:19:00.720285 kernel: HOME=/ Dec 13 01:19:00.720290 kernel: TERM=linux Dec 13 01:19:00.720296 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Dec 13 01:19:00.720305 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) Dec 13 01:19:00.720314 systemd[1]: Detected virtualization kvm. Dec 13 01:19:00.720321 systemd[1]: Detected architecture arm64. Dec 13 01:19:00.720328 systemd[1]: Running in initial RAM disk. Dec 13 01:19:00.720334 systemd[1]: No hostname configured, using default hostname. Dec 13 01:19:00.720341 systemd[1]: Hostname set to . Dec 13 01:19:00.720369 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:19:00.720376 systemd[1]: Queued start job for default target Initrd Default Target. Dec 13 01:19:00.720383 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Dec 13 01:19:00.720391 systemd[1]: Reached target Local Encrypted Volumes. Dec 13 01:19:00.720398 systemd[1]: Reached target Path Units. Dec 13 01:19:00.720405 systemd[1]: Reached target Slice Units. Dec 13 01:19:00.720411 systemd[1]: Reached target Swaps. Dec 13 01:19:00.720418 systemd[1]: Reached target Timer Units. Dec 13 01:19:00.720425 systemd[1]: Listening on Open-iSCSI iscsid Socket. Dec 13 01:19:00.720432 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Dec 13 01:19:00.720438 systemd[1]: Listening on Journal Audit Socket. Dec 13 01:19:00.720446 systemd[1]: Listening on Journal Socket (/dev/log). Dec 13 01:19:00.720453 systemd[1]: Listening on Journal Socket. Dec 13 01:19:00.720460 systemd[1]: Listening on Network Service Netlink Socket. Dec 13 01:19:00.720466 systemd[1]: Listening on udev Control Socket. Dec 13 01:19:00.720473 systemd[1]: Listening on udev Kernel Socket. Dec 13 01:19:00.720479 systemd[1]: Reached target Socket Units. Dec 13 01:19:00.720486 systemd[1]: Starting Create List of Static Device Nodes... Dec 13 01:19:00.720493 systemd[1]: Finished Network Cleanup. Dec 13 01:19:00.720500 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Dec 13 01:19:00.720508 systemd[1]: Starting Journal Service... Dec 13 01:19:00.720514 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Dec 13 01:19:00.720521 systemd[1]: Starting Apply Kernel Variables... Dec 13 01:19:00.720528 systemd[1]: Starting Setup Virtual Console... Dec 13 01:19:00.720534 systemd[1]: Finished Create List of Static Device Nodes. Dec 13 01:19:00.720541 systemd[1]: Finished Apply Kernel Variables. Dec 13 01:19:00.720548 systemd[1]: Finished Setup Virtual Console. Dec 13 01:19:00.720573 systemd[1]: Starting dracut ask for additional cmdline parameters... Dec 13 01:19:00.720619 systemd[1]: Starting Create Static Device Nodes in /dev... Dec 13 01:19:00.720640 systemd-journald[279]: Journal started Dec 13 01:19:00.720678 systemd-journald[279]: Runtime Journal (/run/log/journal/e6dcefccdb024aeaaedfe1af747340e5) is 6.0M, max 48.5M, 42.4M free. Dec 13 01:19:00.722400 systemd[1]: Started Journal Service. Dec 13 01:19:00.722529 systemd[1]: Finished Create Static Device Nodes in /dev. Dec 13 01:19:00.727853 systemd[1]: Finished dracut ask for additional cmdline parameters. Dec 13 01:19:00.729375 systemd[1]: Starting dracut cmdline hook... Dec 13 01:19:00.738773 dracut-cmdline[296]: dracut-dracut-053 Dec 13 01:19:00.741218 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=ttyS0,115200 flatcar.first_boot=detected acpi=force verity.usrhash=add5f6239860d533a5bdd88b1ecacfa095fc20febf51ab00ef758dd688c5dc20 Dec 13 01:19:00.806373 kernel: SCSI subsystem initialized Dec 13 01:19:00.810374 kernel: Loading iSCSI transport class v2.0-870. Dec 13 01:19:00.819370 kernel: iscsi: registered transport (tcp) Dec 13 01:19:00.831377 kernel: iscsi: registered transport (qla4xxx) Dec 13 01:19:00.831420 kernel: QLogic iSCSI HBA Driver Dec 13 01:19:00.841136 systemd[1]: Finished dracut cmdline hook. Dec 13 01:19:00.842511 systemd[1]: Starting dracut pre-udev hook... Dec 13 01:19:00.858365 kernel: device-mapper: uevent: version 1.0.3 Dec 13 01:19:00.858413 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Dec 13 01:19:00.895376 kernel: raid6: neonx8 gen() 11453 MB/s Dec 13 01:19:00.912364 kernel: raid6: neonx8 xor() 10721 MB/s Dec 13 01:19:00.929368 kernel: raid6: neonx4 gen() 13491 MB/s Dec 13 01:19:00.946366 kernel: raid6: neonx4 xor() 11493 MB/s Dec 13 01:19:00.963364 kernel: raid6: neonx2 gen() 12908 MB/s Dec 13 01:19:00.980365 kernel: raid6: neonx2 xor() 9837 MB/s Dec 13 01:19:00.997363 kernel: raid6: neonx1 gen() 10820 MB/s Dec 13 01:19:01.014364 kernel: raid6: neonx1 xor() 8821 MB/s Dec 13 01:19:01.031363 kernel: raid6: int64x8 gen() 6821 MB/s Dec 13 01:19:01.048373 kernel: raid6: int64x8 xor() 3786 MB/s Dec 13 01:19:01.065368 kernel: raid6: int64x4 gen() 6892 MB/s Dec 13 01:19:01.082363 kernel: raid6: int64x4 xor() 3824 MB/s Dec 13 01:19:01.099362 kernel: raid6: int64x2 gen() 6171 MB/s Dec 13 01:19:01.116364 kernel: raid6: int64x2 xor() 3301 MB/s Dec 13 01:19:01.133370 kernel: raid6: int64x1 gen() 4993 MB/s Dec 13 01:19:01.150576 kernel: raid6: int64x1 xor() 2588 MB/s Dec 13 01:19:01.150596 kernel: raid6: using algorithm neonx4 gen() 13491 MB/s Dec 13 01:19:01.150611 kernel: raid6: .... xor() 11493 MB/s, rmw enabled Dec 13 01:19:01.150627 kernel: raid6: using neon recovery algorithm Dec 13 01:19:01.160365 kernel: xor: measuring software checksum speed Dec 13 01:19:01.161361 kernel: 8regs : 16908 MB/sec Dec 13 01:19:01.162643 kernel: 32regs : 20749 MB/sec Dec 13 01:19:01.162653 kernel: arm64_neon : 29173 MB/sec Dec 13 01:19:01.162661 kernel: xor: using function: arm64_neon (29173 MB/sec) Dec 13 01:19:01.213371 kernel: Btrfs loaded, crc32c=crc32c-generic Dec 13 01:19:01.223077 systemd[1]: Finished dracut pre-udev hook. Dec 13 01:19:01.223000 audit: BPF prog-id=6 op=LOAD Dec 13 01:19:01.224000 audit: BPF prog-id=7 op=LOAD Dec 13 01:19:01.225406 kernel: audit: type=1334 audit(1734052741.223:2): prog-id=6 op=LOAD Dec 13 01:19:01.225426 kernel: audit: type=1334 audit(1734052741.224:3): prog-id=7 op=LOAD Dec 13 01:19:01.225435 kernel: audit: type=1334 audit(1734052741.225:4): prog-id=8 op=LOAD Dec 13 01:19:01.225000 audit: BPF prog-id=8 op=LOAD Dec 13 01:19:01.225768 systemd[1]: Starting Rule-based Manager for Device Events and Files... Dec 13 01:19:01.237265 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Dec 13 01:19:01.238944 systemd[1]: Started Rule-based Manager for Device Events and Files. Dec 13 01:19:01.241000 audit: BPF prog-id=9 op=LOAD Dec 13 01:19:01.240448 systemd[1]: Starting dracut pre-trigger hook... Dec 13 01:19:01.242246 systemd[1]: Starting Network Configuration... Dec 13 01:19:01.243866 kernel: audit: type=1334 audit(1734052741.241:5): prog-id=9 op=LOAD Dec 13 01:19:01.253260 dracut-pre-trigger[419]: rd.md=0: removing MD RAID activation Dec 13 01:19:01.259393 systemd-networkd[420]: lo: Link UP Dec 13 01:19:01.259400 systemd-networkd[420]: lo: Gained carrier Dec 13 01:19:01.259695 systemd-networkd[420]: Enumeration completed Dec 13 01:19:01.261000 audit: BPF prog-id=10 op=LOAD Dec 13 01:19:01.259789 systemd[1]: Started Network Configuration. Dec 13 01:19:01.262833 kernel: audit: type=1334 audit(1734052741.261:6): prog-id=10 op=LOAD Dec 13 01:19:01.262049 systemd[1]: Starting Network Name Resolution... Dec 13 01:19:01.280556 systemd[1]: Finished dracut pre-trigger hook. Dec 13 01:19:01.281873 systemd[1]: Starting Coldplug All udev Devices... Dec 13 01:19:01.290459 systemd-resolved[443]: Positive Trust Anchors: Dec 13 01:19:01.290473 systemd-resolved[443]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:19:01.290501 systemd-resolved[443]: 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 Dec 13 01:19:01.290784 systemd-resolved[443]: Defaulting to hostname 'linux'. Dec 13 01:19:01.291408 systemd[1]: Started Network Name Resolution. Dec 13 01:19:01.292766 systemd-udevd[416]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Dec 13 01:19:01.293397 systemd[1]: Reached target Network. Dec 13 01:19:01.299497 systemd[1]: Reached target Host and Network Name Lookups. Dec 13 01:19:01.301375 systemd[1]: Starting iSCSI UserSpace I/O driver... Dec 13 01:19:01.308863 systemd[1]: Started iSCSI UserSpace I/O driver. Dec 13 01:19:01.310160 systemd[1]: Starting Open-iSCSI... Dec 13 01:19:01.312728 iscsid[483]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Dec 13 01:19:01.312728 iscsid[483]: 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]. Dec 13 01:19:01.312728 iscsid[483]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Dec 13 01:19:01.312728 iscsid[483]: If using hardware iscsi like qla4xxx this message can be ignored. Dec 13 01:19:01.312728 iscsid[483]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Dec 13 01:19:01.321454 iscsid[483]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Dec 13 01:19:01.317468 systemd[1]: Started Open-iSCSI. Dec 13 01:19:01.318179 systemd[1]: Finished Coldplug All udev Devices. Dec 13 01:19:01.319499 systemd[1]: Starting dracut initqueue hook... Dec 13 01:19:01.342483 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Dec 13 01:19:01.342714 kernel: vda: detected capacity change from 0 to 4756340736 Dec 13 01:19:01.352369 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:19:01.363586 systemd-udevd[463]: Using default interface naming scheme 'v249'. Dec 13 01:19:01.369244 systemd-networkd[420]: eth0: Link UP Dec 13 01:19:01.377373 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by systemd-udevd (468) Dec 13 01:19:01.380834 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Dec 13 01:19:01.384935 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Dec 13 01:19:01.385803 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Dec 13 01:19:01.387488 systemd[1]: Finished dracut initqueue hook. Dec 13 01:19:01.390509 systemd[1]: Found device /dev/disk/by-label/ROOT. Dec 13 01:19:01.393254 systemd[1]: Found device /dev/disk/by-label/OEM. Dec 13 01:19:01.394055 systemd[1]: Reached target Initrd Root Device. Dec 13 01:19:01.394972 systemd[1]: Reached target Preparation for Remote File Systems. Dec 13 01:19:01.396066 systemd[1]: Reached target Remote Encrypted Volumes. Dec 13 01:19:01.397204 systemd[1]: Reached target Remote File Systems. Dec 13 01:19:01.399129 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Dec 13 01:19:01.400797 systemd[1]: Starting dracut pre-mount hook... Dec 13 01:19:01.410427 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:19:01.411030 systemd[1]: Finished dracut pre-mount hook. Dec 13 01:19:02.370384 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Dec 13 01:19:02.370095 systemd-networkd[420]: eth0: Gained carrier Dec 13 01:19:02.388420 systemd-networkd[420]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Dec 13 01:19:02.422012 disk-uuid[504]: The operation has completed successfully. Dec 13 01:19:02.425587 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Dec 13 01:19:02.446894 systemd[1]: disk-uuid.service: Deactivated successfully. Dec 13 01:19:02.447019 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Dec 13 01:19:02.450551 systemd[1]: Starting Ignition (setup)... Dec 13 01:19:02.461368 kernel: BTRFS info (device vda6): disk space caching is enabled Dec 13 01:19:02.461476 kernel: BTRFS info (device vda6): has skinny extents Dec 13 01:19:02.468299 systemd[1]: mnt-oem.mount: Deactivated successfully. Dec 13 01:19:02.474219 systemd[1]: ignition-setup.service: Deactivated successfully. Dec 13 01:19:02.474342 systemd[1]: Finished Ignition (setup). Dec 13 01:19:02.476192 systemd[1]: Starting Ignition (disks)... Dec 13 01:19:02.500339 ignition[540]: Ignition v0.36.1-15-gde4e6cc9 Dec 13 01:19:02.500374 ignition[540]: Stage: disks Dec 13 01:19:02.500384 ignition[540]: reading system config file "/usr/lib/ignition/base.ign" Dec 13 01:19:02.500396 ignition[540]: no config at "/usr/lib/ignition/base.ign" Dec 13 01:19:02.500442 ignition[540]: parsed url from cmdline: "" Dec 13 01:19:02.500445 ignition[540]: no config URL provided Dec 13 01:19:02.500450 ignition[540]: reading system config file "/usr/lib/ignition/user.ign" Dec 13 01:19:02.500457 ignition[540]: no config at "/usr/lib/ignition/user.ign" Dec 13 01:19:02.500477 ignition[540]: op(1): [started] loading QEMU firmware config module Dec 13 01:19:02.500482 ignition[540]: op(1): executing: "modprobe" "qemu_fw_cfg" Dec 13 01:19:02.501498 ignition[540]: op(1): [finished] loading QEMU firmware config module Dec 13 01:19:02.512734 ignition[540]: parsing config with SHA512: 705967c7b8b7ac3c01e2b496f5fae75e2b478512dcae3c1fabe9f205be07e3eddf47911df34cf160c2bd775775eb5a11c97544435ca73008fda47608c14bc670 Dec 13 01:19:02.514880 ignition[540]: disks: disks passed Dec 13 01:19:02.514889 ignition[540]: Ignition finished successfully Dec 13 01:19:02.515697 systemd[1]: ignition-disks.service: Deactivated successfully. Dec 13 01:19:02.515825 systemd[1]: Finished Ignition (disks). Dec 13 01:19:02.516702 systemd[1]: Reached target Preparation for Local File Systems. Dec 13 01:19:02.518493 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Dec 13 01:19:02.520189 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Dec 13 01:19:02.534429 systemd-fsck[555]: ROOT: clean, 528/553792 files, 37752/553472 blocks Dec 13 01:19:02.536372 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Dec 13 01:19:02.542906 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Dec 13 01:19:02.544457 systemd[1]: Mounting /sysroot... Dec 13 01:19:02.563601 systemd[1]: Found device /dev/mapper/usr. Dec 13 01:19:02.565454 systemd[1]: Starting File System Check on /dev/mapper/usr... Dec 13 01:19:02.567405 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Dec 13 01:19:02.574843 systemd-fsck[567]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Dec 13 01:19:02.574843 systemd-fsck[567]: You must have r/w access to the filesystem or be root Dec 13 01:19:02.575253 systemd-fsck[564]: fsck failed with exit status 8. Dec 13 01:19:02.575256 systemd-fsck[564]: Ignoring error. Dec 13 01:19:02.576304 systemd[1]: Finished File System Check on /dev/mapper/usr. Dec 13 01:19:02.578094 systemd[1]: Mounting /sysusr/usr... Dec 13 01:19:02.588395 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Dec 13 01:19:02.588437 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Dec 13 01:19:02.590019 systemd[1]: Mounted /sysroot. Dec 13 01:19:02.590936 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Dec 13 01:19:02.591062 systemd[1]: Mounted /sysusr/usr. Dec 13 01:19:02.591812 systemd[1]: Reached target Initrd Root File System. Dec 13 01:19:02.592515 systemd[1]: Reached target Local File Systems. Dec 13 01:19:02.593592 systemd[1]: Reached target System Initialization. Dec 13 01:19:02.594645 systemd[1]: Reached target Basic System. Dec 13 01:19:02.599920 systemd[1]: Mounting /sysroot/usr... Dec 13 01:19:02.601998 systemd[1]: Mounted /sysroot/usr. Dec 13 01:19:02.603133 systemd[1]: Starting Root filesystem setup... Dec 13 01:19:02.628294 systemd[1]: initrd-setup-root.service: Deactivated successfully. Dec 13 01:19:02.628436 systemd[1]: Finished Root filesystem setup. Dec 13 01:19:02.630314 systemd[1]: Starting Ignition (files)... Dec 13 01:19:02.631508 systemd[1]: Starting /sysroot/boot... Dec 13 01:19:02.635305 ignition[583]: Ignition v0.36.1-15-gde4e6cc9 Dec 13 01:19:02.636098 ignition[583]: Stage: files Dec 13 01:19:02.636540 ignition[583]: reading system config file "/usr/lib/ignition/base.ign" Dec 13 01:19:02.637258 ignition[583]: no config at "/usr/lib/ignition/base.ign" Dec 13 01:19:02.637790 ignition[583]: files: compiled without relabeling support, skipping Dec 13 01:19:02.657676 systemd[1]: Finished /sysroot/boot. Dec 13 01:19:02.674253 ignition[583]: files: createUsers: op(1): [started] creating or modifying user "core" Dec 13 01:19:02.674269 ignition[583]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Dec 13 01:19:02.675780 ignition[583]: files: createUsers: op(1): [finished] creating or modifying user "core" Dec 13 01:19:02.675792 ignition[583]: files: createUsers: op(2): [started] adding ssh keys to user "core" Dec 13 01:19:02.677000 ignition[583]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Dec 13 01:19:02.677018 ignition[583]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Dec 13 01:19:02.678471 systemd[1]: ignition-files.service: Deactivated successfully. Dec 13 01:19:02.677313 ignition[583]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Dec 13 01:19:02.678585 systemd[1]: Finished Ignition (files). Dec 13 01:19:02.677320 ignition[583]: files: op(4): [started] processing unit "coreos-metadata.service" Dec 13 01:19:02.679975 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Dec 13 01:19:02.677460 ignition[583]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Dec 13 01:19:02.680630 systemd[1]: Starting Ignition (record completion)... Dec 13 01:19:02.677861 ignition[583]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Dec 13 01:19:02.682560 systemd[1]: Starting Reload Configuration from the Real Root... Dec 13 01:19:02.677867 ignition[583]: files: op(4): [finished] processing unit "coreos-metadata.service" Dec 13 01:19:02.684031 systemd[1]: ignition-quench.service: Deactivated successfully. Dec 13 01:19:02.677872 ignition[583]: files: files passed Dec 13 01:19:02.684139 systemd[1]: Finished Ignition (record completion). Dec 13 01:19:02.690000 audit: BPF prog-id=10 op=UNLOAD Dec 13 01:19:02.677875 ignition[583]: Ignition finished successfully Dec 13 01:19:02.693139 kernel: audit: type=1334 audit(1734052742.690:7): prog-id=10 op=UNLOAD Dec 13 01:19:02.693158 kernel: audit: type=1334 audit(1734052742.692:8): prog-id=9 op=UNLOAD Dec 13 01:19:02.692000 audit: BPF prog-id=9 op=UNLOAD Dec 13 01:19:02.686818 systemd[1]: Reloading. Dec 13 01:19:02.694000 audit: BPF prog-id=6 op=UNLOAD Dec 13 01:19:02.695000 audit: BPF prog-id=3 op=UNLOAD Dec 13 01:19:02.696779 kernel: audit: type=1334 audit(1734052742.694:9): prog-id=6 op=UNLOAD Dec 13 01:19:02.696800 kernel: audit: type=1334 audit(1734052742.695:10): prog-id=3 op=UNLOAD Dec 13 01:19:02.780000 audit: BPF prog-id=11 op=LOAD Dec 13 01:19:02.780000 audit: BPF prog-id=12 op=LOAD Dec 13 01:19:02.780000 audit: BPF prog-id=13 op=LOAD Dec 13 01:19:02.780000 audit: BPF prog-id=14 op=LOAD Dec 13 01:19:02.780000 audit: BPF prog-id=15 op=LOAD Dec 13 01:19:02.780000 audit: BPF prog-id=7 op=UNLOAD Dec 13 01:19:02.780000 audit: BPF prog-id=8 op=UNLOAD Dec 13 01:19:02.781000 audit: BPF prog-id=16 op=LOAD Dec 13 01:19:02.781000 audit: BPF prog-id=17 op=LOAD Dec 13 01:19:02.781000 audit: BPF prog-id=18 op=LOAD Dec 13 01:19:02.781000 audit: BPF prog-id=4 op=UNLOAD Dec 13 01:19:02.781000 audit: BPF prog-id=5 op=UNLOAD Dec 13 01:19:02.798951 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Dec 13 01:19:02.799076 systemd[1]: Finished Reload Configuration from the Real Root. Dec 13 01:19:02.800015 systemd[1]: Reached target Initrd File Systems. Dec 13 01:19:02.800643 systemd[1]: Reached target Initrd Default Target. Dec 13 01:19:02.801244 systemd[1]: Condition check resulted in dracut mount hook being skipped. Dec 13 01:19:02.801913 systemd[1]: Starting dracut pre-pivot and cleanup hook... Dec 13 01:19:02.811955 systemd[1]: Finished dracut pre-pivot and cleanup hook. Dec 13 01:19:02.813288 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Dec 13 01:19:02.819449 systemd[1]: Stopped target Host and Network Name Lookups. Dec 13 01:19:02.820193 systemd[1]: Stopped target Remote Encrypted Volumes. Dec 13 01:19:02.821477 systemd[1]: Stopped target Timer Units. Dec 13 01:19:02.822426 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Dec 13 01:19:02.822533 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Dec 13 01:19:02.823481 systemd[1]: Stopped target Initrd Default Target. Dec 13 01:19:02.824582 systemd[1]: Stopped target Basic System. Dec 13 01:19:02.825597 systemd[1]: Stopped target Initrd Root Device. Dec 13 01:19:02.826600 systemd[1]: Stopped target Path Units. Dec 13 01:19:02.827477 systemd[1]: Stopped target Remote File Systems. Dec 13 01:19:02.828593 systemd[1]: Stopped target Preparation for Remote File Systems. Dec 13 01:19:02.829697 systemd[1]: Stopped target Slice Units. Dec 13 01:19:02.830676 systemd[1]: Stopped target Socket Units. Dec 13 01:19:02.831742 systemd[1]: Stopped target System Initialization. Dec 13 01:19:02.832687 systemd[1]: Stopped target Local File Systems. Dec 13 01:19:02.833628 systemd[1]: Stopped target Preparation for Local File Systems. Dec 13 01:19:02.834764 systemd[1]: Stopped target Swaps. Dec 13 01:19:02.835631 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Dec 13 01:19:02.835742 systemd[1]: Stopped dracut pre-mount hook. Dec 13 01:19:02.836772 systemd[1]: Stopped target Local Encrypted Volumes. Dec 13 01:19:02.837638 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Dec 13 01:19:02.840047 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Dec 13 01:19:02.840883 systemd[1]: dracut-initqueue.service: Deactivated successfully. Dec 13 01:19:02.840980 systemd[1]: Stopped dracut initqueue hook. Dec 13 01:19:02.845284 iscsid[483]: iscsid shutting down. Dec 13 01:19:02.842134 systemd[1]: Stopping Open-iSCSI... Dec 13 01:19:02.845509 systemd[1]: Stopping /sysroot/boot... Dec 13 01:19:02.846271 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Dec 13 01:19:02.846403 systemd[1]: Stopped Coldplug All udev Devices. Dec 13 01:19:02.847250 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Dec 13 01:19:02.847326 systemd[1]: Stopped dracut pre-trigger hook. Dec 13 01:19:02.849130 systemd[1]: iscsid.service: Deactivated successfully. Dec 13 01:19:02.849238 systemd[1]: Stopped Open-iSCSI. Dec 13 01:19:02.850313 systemd[1]: iscsid.socket: Deactivated successfully. Dec 13 01:19:02.850400 systemd[1]: Closed Open-iSCSI iscsid Socket. Dec 13 01:19:02.851430 systemd[1]: Stopping iSCSI UserSpace I/O driver... Dec 13 01:19:02.852660 systemd[1]: initrd-cleanup.service: Deactivated successfully. Dec 13 01:19:02.852787 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Dec 13 01:19:02.854329 systemd[1]: iscsiuio.service: Deactivated successfully. Dec 13 01:19:02.854453 systemd[1]: Stopped iSCSI UserSpace I/O driver. Dec 13 01:19:02.855168 systemd[1]: Stopped target Network. Dec 13 01:19:02.856059 systemd[1]: iscsiuio.socket: Deactivated successfully. Dec 13 01:19:02.856090 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Dec 13 01:19:02.857310 systemd[1]: Stopping Network Name Resolution... Dec 13 01:19:02.859246 systemd[1]: sysroot-boot.service: Deactivated successfully. Dec 13 01:19:02.859363 systemd[1]: Stopped /sysroot/boot. Dec 13 01:19:02.861047 systemd[1]: systemd-resolved.service: Deactivated successfully. Dec 13 01:19:02.866000 audit: BPF prog-id=11 op=UNLOAD Dec 13 01:19:02.861155 systemd[1]: Stopped Network Name Resolution. Dec 13 01:19:02.862222 systemd[1]: Stopping Network Configuration... Dec 13 01:19:02.867457 systemd-networkd[420]: eth0: DHCP lease lost Dec 13 01:19:02.876399 systemd-networkd[420]: eth0: DHCPv6 lease lost Dec 13 01:19:02.877853 systemd[1]: systemd-networkd.service: Deactivated successfully. Dec 13 01:19:02.877970 systemd[1]: Stopped Network Configuration. Dec 13 01:19:02.879192 systemd[1]: systemd-networkd.socket: Deactivated successfully. Dec 13 01:19:02.879220 systemd[1]: Closed Network Service Netlink Socket. Dec 13 01:19:02.880632 systemd[1]: Stopping Network Cleanup... Dec 13 01:19:02.881102 systemd[1]: systemd-sysctl.service: Deactivated successfully. Dec 13 01:19:02.881162 systemd[1]: Stopped Apply Kernel Variables. Dec 13 01:19:02.882181 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Dec 13 01:19:02.887000 systemd[1]: network-cleanup.service: Deactivated successfully. Dec 13 01:19:02.887000 audit: BPF prog-id=12 op=UNLOAD Dec 13 01:19:02.887115 systemd[1]: Stopped Network Cleanup. Dec 13 01:19:02.889614 systemd[1]: systemd-udevd.service: Deactivated successfully. Dec 13 01:19:02.889745 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Dec 13 01:19:02.890569 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Dec 13 01:19:02.890607 systemd[1]: Closed udev Control Socket. Dec 13 01:19:02.891545 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Dec 13 01:19:02.891573 systemd[1]: Closed udev Kernel Socket. Dec 13 01:19:02.892595 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Dec 13 01:19:02.892631 systemd[1]: Stopped dracut pre-udev hook. Dec 13 01:19:02.893681 systemd[1]: dracut-cmdline.service: Deactivated successfully. Dec 13 01:19:02.893719 systemd[1]: Stopped dracut cmdline hook. Dec 13 01:19:02.894555 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Dec 13 01:19:02.894587 systemd[1]: Stopped dracut ask for additional cmdline parameters. Dec 13 01:19:02.902000 audit: BPF prog-id=13 op=UNLOAD Dec 13 01:19:02.896382 systemd[1]: Starting Cleanup udev Database... Dec 13 01:19:02.897374 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Dec 13 01:19:02.898429 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Dec 13 01:19:02.898478 systemd[1]: Stopped Create Static Device Nodes in /dev. Dec 13 01:19:02.899610 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Dec 13 01:19:02.899646 systemd[1]: Stopped Create List of Static Device Nodes. Dec 13 01:19:02.900637 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Dec 13 01:19:02.900677 systemd[1]: Stopped Setup Virtual Console. Dec 13 01:19:02.901958 systemd[1]: rngd.service: Deactivated successfully. Dec 13 01:19:02.902069 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Dec 13 01:19:02.903278 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Dec 13 01:19:02.903388 systemd[1]: Finished Cleanup udev Database. Dec 13 01:19:02.904244 systemd[1]: Reached target Switch Root. Dec 13 01:19:02.905813 systemd[1]: Starting Switch Root... Dec 13 01:19:02.910709 systemd[1]: Switching root. Dec 13 01:19:02.913000 audit: BPF prog-id=16 op=UNLOAD Dec 13 01:19:02.930676 systemd-journald[279]: Journal stopped Dec 13 01:19:05.090943 systemd-journald[279]: Received SIGTERM from PID 1 (systemd). Dec 13 01:19:05.090997 kernel: SELinux: policy capability network_peer_controls=1 Dec 13 01:19:05.091009 kernel: SELinux: policy capability open_perms=1 Dec 13 01:19:05.091021 kernel: SELinux: policy capability extended_socket_class=1 Dec 13 01:19:05.091030 kernel: SELinux: policy capability always_check_network=0 Dec 13 01:19:05.091040 kernel: SELinux: policy capability cgroup_seclabel=1 Dec 13 01:19:05.091049 kernel: SELinux: policy capability nnp_nosuid_transition=1 Dec 13 01:19:05.091058 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Dec 13 01:19:05.091068 systemd[1]: Successfully loaded SELinux policy in 32.079ms. Dec 13 01:19:05.091083 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.649ms. Dec 13 01:19:05.091093 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) Dec 13 01:19:05.091103 systemd[1]: Detected virtualization kvm. Dec 13 01:19:05.091115 systemd[1]: Detected architecture arm64. Dec 13 01:19:05.091124 systemd[1]: Detected first boot. Dec 13 01:19:05.091134 systemd[1]: Initializing machine ID from VM UUID. Dec 13 01:19:05.091143 systemd[1]: Populated /etc with preset unit settings. Dec 13 01:19:05.091155 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. Dec 13 01:19:05.091166 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 13 01:19:05.091176 kernel: kauditd_printk_skb: 24 callbacks suppressed Dec 13 01:19:05.091185 kernel: audit: type=1334 audit(1734052744.969:35): prog-id=21 op=LOAD Dec 13 01:19:05.091195 kernel: audit: type=1334 audit(1734052744.969:36): prog-id=22 op=LOAD Dec 13 01:19:05.091204 kernel: audit: type=1334 audit(1734052744.970:37): prog-id=23 op=LOAD Dec 13 01:19:05.091213 systemd[1]: initrd-switch-root.service: Deactivated successfully. Dec 13 01:19:05.091224 kernel: audit: type=1334 audit(1734052744.970:38): prog-id=17 op=UNLOAD Dec 13 01:19:05.091233 systemd[1]: Stopped Switch Root. Dec 13 01:19:05.091243 kernel: audit: type=1334 audit(1734052744.970:39): prog-id=18 op=UNLOAD Dec 13 01:19:05.091252 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Dec 13 01:19:05.091262 systemd[1]: Created slice Slice /system/addon-config. Dec 13 01:19:05.091271 systemd[1]: Created slice Slice /system/addon-run. Dec 13 01:19:05.091282 systemd[1]: Created slice Slice /system/getty. Dec 13 01:19:05.091292 systemd[1]: Created slice Slice /system/modprobe. Dec 13 01:19:05.091302 systemd[1]: Created slice Slice /system/serial-getty. Dec 13 01:19:05.091311 kernel: audit: type=1334 audit(1734052744.981:40): prog-id=21 op=UNLOAD Dec 13 01:19:05.091320 systemd[1]: Created slice Slice /system/system-cloudinit. Dec 13 01:19:05.091330 systemd[1]: Created slice Slice /system/systemd-fsck. Dec 13 01:19:05.091339 systemd[1]: Created slice User and Session Slice. Dec 13 01:19:05.091362 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Dec 13 01:19:05.091374 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Dec 13 01:19:05.091385 systemd[1]: Set up automount Boot partition Automount Point. Dec 13 01:19:05.091395 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Dec 13 01:19:05.091405 systemd[1]: Stopped target Switch Root. Dec 13 01:19:05.091415 systemd[1]: Stopped target Initrd File Systems. Dec 13 01:19:05.091424 systemd[1]: Stopped target Initrd Root File System. Dec 13 01:19:05.091434 systemd[1]: Reached target Remote Encrypted Volumes. Dec 13 01:19:05.091444 systemd[1]: Reached target Remote File Systems. Dec 13 01:19:05.091455 systemd[1]: Reached target Slice Units. Dec 13 01:19:05.091465 systemd[1]: Reached target Swaps. Dec 13 01:19:05.091474 systemd[1]: Reached target Verify torcx succeeded. Dec 13 01:19:05.091485 systemd[1]: Reached target Local Verity Protected Volumes. Dec 13 01:19:05.091495 systemd[1]: Listening on Process Core Dump Socket. Dec 13 01:19:05.091504 systemd[1]: Listening on initctl Compatibility Named Pipe. Dec 13 01:19:05.091514 systemd[1]: Listening on Network Service Netlink Socket. Dec 13 01:19:05.091523 systemd[1]: Listening on udev Control Socket. Dec 13 01:19:05.091533 systemd[1]: Listening on udev Kernel Socket. Dec 13 01:19:05.091542 systemd[1]: Mounting Huge Pages File System... Dec 13 01:19:05.091552 systemd[1]: Mounting POSIX Message Queue File System... Dec 13 01:19:05.091562 systemd[1]: Mounting External Media Directory... Dec 13 01:19:05.091571 systemd[1]: Mounting Kernel Debug File System... Dec 13 01:19:05.091582 systemd[1]: Mounting Kernel Trace File System... Dec 13 01:19:05.091592 systemd[1]: Mounting Temporary Directory /tmp... Dec 13 01:19:05.091601 systemd[1]: Starting Create missing system files... Dec 13 01:19:05.091611 systemd[1]: Starting Create List of Static Device Nodes... Dec 13 01:19:05.091621 systemd[1]: Starting Load Kernel Module configfs... Dec 13 01:19:05.091631 systemd[1]: Starting Load Kernel Module drm... Dec 13 01:19:05.091641 systemd[1]: Starting Load Kernel Module fuse... Dec 13 01:19:05.091651 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Dec 13 01:19:05.091661 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Dec 13 01:19:05.091672 systemd[1]: Stopped File System Check on Root Device. Dec 13 01:19:05.091682 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Dec 13 01:19:05.091691 systemd[1]: Stopped systemd-fsck-usr.service. Dec 13 01:19:05.091702 systemd[1]: Stopped Journal Service. Dec 13 01:19:05.091717 kernel: audit: type=1334 audit(1734052745.062:41): prog-id=24 op=LOAD Dec 13 01:19:05.091729 kernel: audit: type=1334 audit(1734052745.063:42): prog-id=25 op=LOAD Dec 13 01:19:05.091739 kernel: audit: type=1334 audit(1734052745.064:43): prog-id=26 op=LOAD Dec 13 01:19:05.091748 systemd[1]: Starting Journal Service... Dec 13 01:19:05.091759 kernel: audit: type=1334 audit(1734052745.064:44): prog-id=22 op=UNLOAD Dec 13 01:19:05.091769 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Dec 13 01:19:05.091778 systemd[1]: Starting Remount Root and Kernel File Systems... Dec 13 01:19:05.091788 systemd[1]: Starting Apply Kernel Variables... Dec 13 01:19:05.091797 kernel: fuse: init (API version 7.32) Dec 13 01:19:05.091808 systemd[1]: Starting Coldplug All udev Devices... Dec 13 01:19:05.091818 systemd[1]: verity-setup.service: Deactivated successfully. Dec 13 01:19:05.091827 systemd[1]: Stopped verity-setup.service. Dec 13 01:19:05.091837 systemd[1]: Mounted Huge Pages File System. Dec 13 01:19:05.091846 systemd[1]: Mounted POSIX Message Queue File System. Dec 13 01:19:05.091859 systemd[1]: Mounted External Media Directory. Dec 13 01:19:05.091869 systemd[1]: Mounted Kernel Debug File System. Dec 13 01:19:05.091879 systemd[1]: Mounted Kernel Trace File System. Dec 13 01:19:05.091888 systemd[1]: Mounted Temporary Directory /tmp. Dec 13 01:19:05.091898 systemd[1]: Finished Create missing system files. Dec 13 01:19:05.091907 systemd[1]: Finished Create List of Static Device Nodes. Dec 13 01:19:05.091917 systemd[1]: modprobe@configfs.service: Deactivated successfully. Dec 13 01:19:05.091927 systemd[1]: Finished Load Kernel Module configfs. Dec 13 01:19:05.091938 systemd-journald[800]: Journal started Dec 13 01:19:05.091974 systemd-journald[800]: Runtime Journal (/run/log/journal/e6dcefccdb024aeaaedfe1af747340e5) is 6.0M, max 48.5M, 42.4M free. Dec 13 01:19:03.004000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Dec 13 01:19:03.037000 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 Dec 13 01:19:03.037000 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 Dec 13 01:19:03.037000 audit: BPF prog-id=19 op=LOAD Dec 13 01:19:03.037000 audit: BPF prog-id=19 op=UNLOAD Dec 13 01:19:03.037000 audit: BPF prog-id=20 op=LOAD Dec 13 01:19:03.037000 audit: BPF prog-id=20 op=UNLOAD Dec 13 01:19:03.077000 audit[749]: AVC avc: denied { associate } for pid=749 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 Dec 13 01:19:04.969000 audit: BPF prog-id=21 op=LOAD Dec 13 01:19:04.969000 audit: BPF prog-id=22 op=LOAD Dec 13 01:19:04.970000 audit: BPF prog-id=23 op=LOAD Dec 13 01:19:04.970000 audit: BPF prog-id=17 op=UNLOAD Dec 13 01:19:04.970000 audit: BPF prog-id=18 op=UNLOAD Dec 13 01:19:04.981000 audit: BPF prog-id=21 op=UNLOAD Dec 13 01:19:05.062000 audit: BPF prog-id=24 op=LOAD Dec 13 01:19:05.063000 audit: BPF prog-id=25 op=LOAD Dec 13 01:19:05.064000 audit: BPF prog-id=26 op=LOAD Dec 13 01:19:05.064000 audit: BPF prog-id=22 op=UNLOAD Dec 13 01:19:05.064000 audit: BPF prog-id=23 op=UNLOAD Dec 13 01:19:05.089000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Dec 13 01:19:04.959559 systemd[1]: Queued start job for default target Multi-User System. Dec 13 01:19:03.075760 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" 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]" Dec 13 01:19:04.971038 systemd[1]: systemd-journald.service: Deactivated successfully. Dec 13 01:19:03.076064 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 01:19:03.076084 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 01:19:03.076204 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Dec 13 01:19:03.076216 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="skipped missing lower profile" missing profile=oem Dec 13 01:19:03.076248 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Dec 13 01:19:03.076260 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Dec 13 01:19:03.076485 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Dec 13 01:19:03.076520 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Dec 13 01:19:03.076535 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Dec 13 01:19:03.076949 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" 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 Dec 13 01:19:03.076981 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" 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 Dec 13 01:19:03.077001 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" 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 Dec 13 01:19:03.077016 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Dec 13 01:19:03.077035 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" 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 Dec 13 01:19:03.077050 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:03Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Dec 13 01:19:04.785811 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Dec 13 01:19:04.786073 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" 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 Dec 13 01:19:04.786186 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" 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 Dec 13 01:19:04.786310 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" 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 Dec 13 01:19:04.786384 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Dec 13 01:19:04.786448 /usr/lib/systemd/system-generators/torcx-generator[749]: time="2024-12-13T01:19:04Z" 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 Dec 13 01:19:05.093736 systemd[1]: Started Journal Service. Dec 13 01:19:05.094258 systemd[1]: modprobe@drm.service: Deactivated successfully. Dec 13 01:19:05.094476 systemd[1]: Finished Load Kernel Module drm. Dec 13 01:19:05.095266 systemd[1]: modprobe@fuse.service: Deactivated successfully. Dec 13 01:19:05.095473 systemd[1]: Finished Load Kernel Module fuse. Dec 13 01:19:05.096386 systemd[1]: Finished Remount Root and Kernel File Systems. Dec 13 01:19:05.097563 systemd[1]: Finished Apply Kernel Variables. Dec 13 01:19:05.107509 systemd[1]: Mounting FUSE Control File System... Dec 13 01:19:05.109049 systemd[1]: Mounting Kernel Configuration File System... Dec 13 01:19:05.109692 systemd[1]: Condition check resulted in Remount Root File System being skipped. Dec 13 01:19:05.111096 systemd[1]: Starting Rebuild Hardware Database... Dec 13 01:19:05.112793 systemd[1]: Starting Flush Journal to Persistent Storage... Dec 13 01:19:05.113537 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Dec 13 01:19:05.114549 systemd[1]: Starting Load/Save Random Seed... Dec 13 01:19:05.123939 systemd-journald[800]: Time spent on flushing to /var/log/journal/e6dcefccdb024aeaaedfe1af747340e5 is 10.374ms for 767 entries. Dec 13 01:19:05.123939 systemd-journald[800]: System Journal (/var/log/journal/e6dcefccdb024aeaaedfe1af747340e5) is 8.0M, max 203.0M, 195.0M free. Dec 13 01:19:05.120671 systemd[1]: Starting Create System Users... Dec 13 01:19:05.122385 systemd[1]: Mounted FUSE Control File System. Dec 13 01:19:05.123090 systemd[1]: Mounted Kernel Configuration File System. Dec 13 01:19:05.126418 systemd[1]: Finished Coldplug All udev Devices. Dec 13 01:19:05.128033 systemd[1]: Starting Wait for udev To Complete Device Initialization... Dec 13 01:19:05.132573 systemd[1]: Finished Load/Save Random Seed. Dec 13 01:19:05.133470 systemd[1]: Reached target First Boot Complete. Dec 13 01:19:05.135292 systemd-sysusers[812]: Creating group sgx with gid 999. Dec 13 01:19:05.136056 systemd-sysusers[812]: Creating group systemd-oom with gid 998. Dec 13 01:19:05.136586 systemd-sysusers[812]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Dec 13 01:19:05.137211 systemd-sysusers[812]: Creating group systemd-timesync with gid 997. Dec 13 01:19:05.137677 systemd-sysusers[812]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Dec 13 01:19:05.138258 systemd-sysusers[812]: Creating group systemd-coredump with gid 996. Dec 13 01:19:05.138776 systemd-sysusers[812]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Dec 13 01:19:05.151237 systemd[1]: Finished Flush Journal to Persistent Storage. Dec 13 01:19:05.152525 systemd[1]: Finished Create System Users. Dec 13 01:19:05.162421 systemd[1]: Starting Create Static Device Nodes in /dev... Dec 13 01:19:05.175661 systemd[1]: Finished Create Static Device Nodes in /dev. Dec 13 01:19:05.352787 systemd[1]: Finished Rebuild Hardware Database. Dec 13 01:19:05.359000 audit: BPF prog-id=27 op=LOAD Dec 13 01:19:05.359000 audit: BPF prog-id=28 op=LOAD Dec 13 01:19:05.359000 audit: BPF prog-id=29 op=LOAD Dec 13 01:19:05.359000 audit: BPF prog-id=14 op=UNLOAD Dec 13 01:19:05.359000 audit: BPF prog-id=15 op=UNLOAD Dec 13 01:19:05.360689 systemd[1]: Starting Rule-based Manager for Device Events and Files... Dec 13 01:19:05.385006 systemd[1]: Started Rule-based Manager for Device Events and Files. Dec 13 01:19:05.393000 audit: BPF prog-id=30 op=LOAD Dec 13 01:19:05.394427 systemd[1]: Starting Network Configuration... Dec 13 01:19:05.419859 systemd-udevd[835]: Using default interface naming scheme 'v249'. Dec 13 01:19:05.446873 systemd-networkd[838]: lo: Link UP Dec 13 01:19:05.446883 systemd-networkd[838]: lo: Gained carrier Dec 13 01:19:05.450009 systemd-networkd[838]: Enumeration completed Dec 13 01:19:05.450086 systemd[1]: Started Network Configuration. Dec 13 01:19:05.452281 systemd-networkd[838]: eth0: Link UP Dec 13 01:19:05.459472 systemd-networkd[838]: eth0: Gained carrier Dec 13 01:19:05.459613 udevadm[814]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Dec 13 01:19:05.466495 systemd-networkd[838]: eth0: DHCPv4 address 10.0.0.3/16 via 10.0.0.1 Dec 13 01:19:05.488507 systemd[1]: Finished Wait for udev To Complete Device Initialization. Dec 13 01:19:05.500157 systemd[1]: Starting Activation of LVM2 logical volumes... Dec 13 01:19:05.508012 lvm[846]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:19:05.536236 systemd[1]: Finished Activation of LVM2 logical volumes. Dec 13 01:19:05.537032 systemd[1]: Reached target Local Encrypted Volumes. Dec 13 01:19:05.547273 systemd[1]: Starting Activation of LVM2 logical volumes... Dec 13 01:19:05.550341 lvm[847]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Dec 13 01:19:05.572149 systemd[1]: Finished Activation of LVM2 logical volumes. Dec 13 01:19:05.572925 systemd[1]: Reached target Preparation for Local File Systems. Dec 13 01:19:05.573649 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Dec 13 01:19:05.573670 systemd[1]: Reached target Containers. Dec 13 01:19:05.583623 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Dec 13 01:19:05.594673 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Dec 13 01:19:05.596454 systemd[1]: Mounting /usr/share/oem... Dec 13 01:19:05.601691 kernel: BTRFS info (device vda6): disk space caching is enabled Dec 13 01:19:05.601746 kernel: BTRFS info (device vda6): has skinny extents Dec 13 01:19:05.604122 systemd[1]: Mounted /usr/share/oem. Dec 13 01:19:05.604747 systemd[1]: Reached target Local File Systems. Dec 13 01:19:05.606194 systemd[1]: Starting Rebuild Dynamic Linker Cache... Dec 13 01:19:05.607204 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Dec 13 01:19:05.607245 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Dec 13 01:19:05.608162 systemd[1]: Starting Commit a transient machine-id on disk... Dec 13 01:19:05.609824 systemd[1]: Starting Create Volatile Files and Directories... Dec 13 01:19:05.616729 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Dec 13 01:19:05.617750 systemd-tmpfiles[872]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Dec 13 01:19:05.627205 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Dec 13 01:19:05.627848 systemd[1]: Finished Commit a transient machine-id on disk. Dec 13 01:19:05.657437 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Dec 13 01:19:05.657446 systemd-tmpfiles[872]: Skipping /boot Dec 13 01:19:05.662447 systemd-tmpfiles[872]: Detected autofs mount point /boot during canonicalization of /boot. Dec 13 01:19:05.662557 systemd-tmpfiles[872]: Skipping /boot Dec 13 01:19:05.685712 systemd[1]: Finished Rebuild Dynamic Linker Cache. Dec 13 01:19:05.704745 systemd[1]: Finished Create Volatile Files and Directories. Dec 13 01:19:05.706507 systemd[1]: Starting Load Security Auditing Rules... Dec 13 01:19:05.708046 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Dec 13 01:19:05.709716 systemd[1]: Starting Rebuild Journal Catalog... Dec 13 01:19:05.710000 audit: BPF prog-id=31 op=LOAD Dec 13 01:19:05.711758 systemd[1]: Starting Network Name Resolution... Dec 13 01:19:05.712000 audit: BPF prog-id=32 op=LOAD Dec 13 01:19:05.714152 systemd[1]: Starting Network Time Synchronization... Dec 13 01:19:05.717051 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Dec 13 01:19:05.718340 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Dec 13 01:19:05.719437 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Dec 13 01:19:05.724670 systemd[1]: Finished Rebuild Journal Catalog. Dec 13 01:19:05.726249 systemd[1]: Starting Update is Completed... Dec 13 01:19:05.728643 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Dec 13 01:19:05.730664 systemd[1]: Finished Update is Completed. Dec 13 01:19:05.741000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Dec 13 01:19:05.741572 augenrules[894]: No rules Dec 13 01:19:05.742243 systemd[1]: Finished Load Security Auditing Rules. Dec 13 01:19:05.756359 systemd-resolved[877]: Positive Trust Anchors: Dec 13 01:19:05.756369 systemd-resolved[877]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Dec 13 01:19:05.756396 systemd-resolved[877]: 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 Dec 13 01:19:05.757082 systemd-resolved[877]: Defaulting to hostname 'linux'. Dec 13 01:19:05.760477 systemd[1]: Started Network Name Resolution. Dec 13 01:19:05.761089 systemd[1]: Reached target Network. Dec 13 01:19:05.761604 systemd[1]: Reached target Host and Network Name Lookups. Dec 13 01:19:05.762834 systemd[1]: Started Network Time Synchronization. Dec 13 01:19:05.763476 systemd-timesyncd[883]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Dec 13 01:19:05.763700 systemd[1]: Reached target System Initialization. Dec 13 01:19:05.764368 systemd[1]: Started Watch for update engine configuration changes. Dec 13 01:19:05.765105 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Dec 13 01:19:05.765926 systemd[1]: Started Daily Cleanup of Temporary Directories. Dec 13 01:19:05.766604 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Dec 13 01:19:05.766630 systemd[1]: Reached target Path Units. Dec 13 01:19:05.767133 systemd[1]: Reached target System Time Set. Dec 13 01:19:05.767824 systemd[1]: Started Daily Log Rotation. Dec 13 01:19:05.768462 systemd[1]: Started Weekly check for MD array's redundancy information.. Dec 13 01:19:05.769227 systemd[1]: Reached target Timer Units. Dec 13 01:19:05.770064 systemd[1]: Listening on D-Bus System Message Bus Socket. Dec 13 01:19:05.780884 systemd[1]: Starting Docker Socket for the API... Dec 13 01:19:05.783682 systemd[1]: Listening on OpenSSH Server Socket. Dec 13 01:19:05.784758 systemd[1]: Listening on Docker Socket for the API. Dec 13 01:19:05.785492 systemd[1]: Reached target Socket Units. Dec 13 01:19:05.786087 systemd[1]: Reached target Basic System. Dec 13 01:19:05.786721 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Dec 13 01:19:05.786745 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Dec 13 01:19:05.787719 systemd[1]: Started D-Bus System Message Bus. Dec 13 01:19:05.790557 systemd[1]: Starting Extend Filesystems... Dec 13 01:19:05.791200 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Dec 13 01:19:05.792186 systemd[1]: Starting Generate /run/flatcar/motd... Dec 13 01:19:05.793783 systemd[1]: Starting Install an ssh key from /proc/cmdline... Dec 13 01:19:05.798544 systemd[1]: Starting Generate sshd host keys... Dec 13 01:19:05.799187 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Dec 13 01:19:05.799222 systemd[1]: Reached target Load system-provided cloud configs. Dec 13 01:19:05.801467 extend-filesystems[903]: Found vda Dec 13 01:19:05.801467 extend-filesystems[903]: Found vda1 Dec 13 01:19:05.801467 extend-filesystems[903]: Found vda2 Dec 13 01:19:05.801467 extend-filesystems[903]: Found vda3 Dec 13 01:19:05.805486 extend-filesystems[903]: Found usr Dec 13 01:19:05.805486 extend-filesystems[903]: Found vda4 Dec 13 01:19:05.805486 extend-filesystems[903]: Found vda6 Dec 13 01:19:05.805486 extend-filesystems[903]: Found vda7 Dec 13 01:19:05.805486 extend-filesystems[903]: Found vda9 Dec 13 01:19:05.805486 extend-filesystems[903]: Checking size of /dev/vda9 Dec 13 01:19:05.802043 systemd[1]: Starting User Login Management... Dec 13 01:19:05.802990 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Dec 13 01:19:05.803563 systemd[1]: Starting Update Engine... Dec 13 01:19:05.804153 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Dec 13 01:19:05.804177 systemd[1]: Reached target Load user-provided cloud configs. Dec 13 01:19:05.806689 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Dec 13 01:19:05.806920 systemd[1]: Finished Install an ssh key from /proc/cmdline. Dec 13 01:19:05.820264 systemd[1]: motdgen.service: Deactivated successfully. Dec 13 01:19:05.820525 systemd[1]: Finished Generate /run/flatcar/motd. Dec 13 01:19:05.826690 extend-filesystems[903]: Old size kept for /dev/vda9 Dec 13 01:19:05.828603 systemd[1]: extend-filesystems.service: Deactivated successfully. Dec 13 01:19:05.828848 systemd[1]: Finished Extend Filesystems. Dec 13 01:19:05.843860 systemd-logind[916]: Watching system buttons on /dev/input/event0 (Power Button) Dec 13 01:19:05.844016 systemd-logind[916]: New seat seat0. Dec 13 01:19:05.846658 systemd[1]: Started User Login Management. Dec 13 01:19:05.849463 update_engine[919]: I1213 01:19:05.849221 919 main.cc:89] Flatcar Update Engine starting Dec 13 01:19:05.852975 systemd[1]: Started Update Engine. Dec 13 01:19:05.853184 update_engine[919]: I1213 01:19:05.853156 919 update_check_scheduler.cc:74] Next update check in 6m0s Dec 13 01:19:05.858619 systemd[1]: Started Cluster reboot manager. Dec 13 01:19:05.888368 locksmithd[927]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Dec 13 01:19:06.290451 systemd[1]: Created slice Slice /system/sshd. Dec 13 01:19:06.752232 sshd_keygen[920]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Dec 13 01:19:06.770476 systemd[1]: Finished Generate sshd host keys. Dec 13 01:19:06.789287 systemd[1]: Starting Generate /run/issue... Dec 13 01:19:06.790831 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55802). Dec 13 01:19:06.793853 systemd[1]: issuegen.service: Deactivated successfully. Dec 13 01:19:06.794065 systemd[1]: Finished Generate /run/issue. Dec 13 01:19:06.795898 systemd[1]: Starting Permit User Sessions... Dec 13 01:19:06.800542 systemd[1]: Finished Permit User Sessions. Dec 13 01:19:06.802290 systemd[1]: Started Getty on tty1. Dec 13 01:19:06.803993 systemd[1]: Started Serial Getty on ttyAMA0. Dec 13 01:19:06.804766 systemd[1]: Reached target Login Prompts. Dec 13 01:19:06.805426 systemd[1]: Reached target Multi-User System. Dec 13 01:19:06.807219 systemd[1]: Starting Record Runlevel Change in UTMP... Dec 13 01:19:06.811976 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 13 01:19:06.812188 systemd[1]: Finished Record Runlevel Change in UTMP. Dec 13 01:19:06.813083 systemd[1]: Startup finished in 737ms (kernel) + 2.388s (initrd) + 3.845s (userspace) = 6.970s. Dec 13 01:19:06.833582 sshd[942]: Accepted publickey for core from 10.0.0.1 port 55802 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:06.835222 sshd[942]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:06.850816 systemd[1]: Created slice User Slice of UID 500. Dec 13 01:19:06.851705 systemd[1]: Starting User Runtime Directory /run/user/500... Dec 13 01:19:06.853155 systemd-logind[916]: New session 1 of user core. Dec 13 01:19:06.857271 systemd[1]: Finished User Runtime Directory /run/user/500. Dec 13 01:19:06.858379 systemd[1]: Starting User Manager for UID 500... Dec 13 01:19:06.860514 systemd[951]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:06.909286 systemd[951]: Queued start job for default target Main User Target. Dec 13 01:19:06.909476 systemd[951]: Reached target Paths. Dec 13 01:19:06.909491 systemd[951]: Reached target Sockets. Dec 13 01:19:06.909502 systemd[951]: Reached target Timers. Dec 13 01:19:06.909512 systemd[951]: Reached target Basic System. Dec 13 01:19:06.909557 systemd[951]: Reached target Main User Target. Dec 13 01:19:06.909571 systemd[951]: Startup finished in 45ms. Dec 13 01:19:06.909627 systemd[1]: Started User Manager for UID 500. Dec 13 01:19:06.916555 systemd[1]: Started Session 1 of User core. Dec 13 01:19:06.979004 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55810). Dec 13 01:19:07.010335 sshd[960]: Accepted publickey for core from 10.0.0.1 port 55810 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.011191 sshd[960]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.013755 systemd-logind[916]: New session 2 of user core. Dec 13 01:19:07.027535 systemd[1]: Started Session 2 of User core. Dec 13 01:19:07.083755 sshd[960]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.093452 systemd[1]: sshd@1-10.0.0.3:22-10.0.0.1:55810.service: Deactivated successfully. Dec 13 01:19:07.094021 systemd[1]: session-2.scope: Deactivated successfully. Dec 13 01:19:07.094645 systemd-logind[916]: Session 2 logged out. Waiting for processes to exit. Dec 13 01:19:07.095635 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55824). Dec 13 01:19:07.096295 systemd-logind[916]: Removed session 2. Dec 13 01:19:07.127110 sshd[966]: Accepted publickey for core from 10.0.0.1 port 55824 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.128144 sshd[966]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.131093 systemd-logind[916]: New session 3 of user core. Dec 13 01:19:07.139531 systemd[1]: Started Session 3 of User core. Dec 13 01:19:07.190407 sshd[966]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.203048 systemd[1]: sshd@2-10.0.0.3:22-10.0.0.1:55824.service: Deactivated successfully. Dec 13 01:19:07.203650 systemd[1]: session-3.scope: Deactivated successfully. Dec 13 01:19:07.204255 systemd-logind[916]: Session 3 logged out. Waiting for processes to exit. Dec 13 01:19:07.205203 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55832). Dec 13 01:19:07.205905 systemd-logind[916]: Removed session 3. Dec 13 01:19:07.236548 sshd[972]: Accepted publickey for core from 10.0.0.1 port 55832 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.237802 sshd[972]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.240471 systemd-logind[916]: New session 4 of user core. Dec 13 01:19:07.249545 systemd[1]: Started Session 4 of User core. Dec 13 01:19:07.302806 sshd[972]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.314398 systemd[1]: sshd@3-10.0.0.3:22-10.0.0.1:55832.service: Deactivated successfully. Dec 13 01:19:07.315182 systemd[1]: session-4.scope: Deactivated successfully. Dec 13 01:19:07.315909 systemd-logind[916]: Session 4 logged out. Waiting for processes to exit. Dec 13 01:19:07.317167 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55838). Dec 13 01:19:07.317922 systemd-logind[916]: Removed session 4. Dec 13 01:19:07.348420 sshd[978]: Accepted publickey for core from 10.0.0.1 port 55838 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.349292 sshd[978]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.351814 systemd-logind[916]: New session 5 of user core. Dec 13 01:19:07.365591 systemd[1]: Started Session 5 of User core. Dec 13 01:19:07.423802 sudo[981]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Dec 13 01:19:07.424009 sudo[981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:07.427032 sudo[981]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:07.429807 systemd-networkd[838]: eth0: Gained IPv6LL Dec 13 01:19:07.431682 sshd[978]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.442923 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55840). Dec 13 01:19:07.445624 dbus-daemon[902]: [system] Reloaded configuration Dec 13 01:19:07.451746 systemd[1]: sshd@4-10.0.0.3:22-10.0.0.1:55838.service: Deactivated successfully. Dec 13 01:19:07.452399 systemd[1]: session-5.scope: Deactivated successfully. Dec 13 01:19:07.453043 systemd-logind[916]: Session 5 logged out. Waiting for processes to exit. Dec 13 01:19:07.454835 systemd-logind[916]: Removed session 5. Dec 13 01:19:07.479226 sshd[984]: Accepted publickey for core from 10.0.0.1 port 55840 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.480237 sshd[984]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.483683 systemd-logind[916]: New session 6 of user core. Dec 13 01:19:07.494529 systemd[1]: Started Session 6 of User core. Dec 13 01:19:07.546453 sudo[989]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Dec 13 01:19:07.546671 sudo[989]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:07.548952 sudo[989]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:07.552533 sudo[988]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Dec 13 01:19:07.552734 sudo[988]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:07.566280 systemd[1]: Stopping Load Security Auditing Rules... Dec 13 01:19:07.565000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Dec 13 01:19:07.567462 auditctl[992]: No rules Dec 13 01:19:07.567730 systemd[1]: audit-rules.service: Deactivated successfully. Dec 13 01:19:07.567963 systemd[1]: Stopped Load Security Auditing Rules. Dec 13 01:19:07.569239 systemd[1]: Starting Load Security Auditing Rules... Dec 13 01:19:07.583960 augenrules[1009]: No rules Dec 13 01:19:07.584647 systemd[1]: Finished Load Security Auditing Rules. Dec 13 01:19:07.585461 sudo[988]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:07.586558 sshd[984]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.596925 systemd[1]: sshd@5-10.0.0.3:22-10.0.0.1:55840.service: Deactivated successfully. Dec 13 01:19:07.597460 systemd[1]: session-6.scope: Deactivated successfully. Dec 13 01:19:07.598084 systemd-logind[916]: Session 6 logged out. Waiting for processes to exit. Dec 13 01:19:07.599049 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55854). Dec 13 01:19:07.599949 systemd-logind[916]: Removed session 6. Dec 13 01:19:07.630364 sshd[1015]: Accepted publickey for core from 10.0.0.1 port 55854 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.631282 sshd[1015]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.633837 systemd-logind[916]: New session 7 of user core. Dec 13 01:19:07.646550 systemd[1]: Started Session 7 of User core. Dec 13 01:19:07.700227 sudo[1018]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Dec 13 01:19:07.700461 sudo[1018]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:07.702845 sudo[1018]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:07.703834 sshd[1015]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:07.711930 systemd[1]: sshd@6-10.0.0.3:22-10.0.0.1:55854.service: Deactivated successfully. Dec 13 01:19:07.712470 systemd[1]: session-7.scope: Deactivated successfully. Dec 13 01:19:07.713069 systemd-logind[916]: Session 7 logged out. Waiting for processes to exit. Dec 13 01:19:07.714007 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55856). Dec 13 01:19:07.714695 systemd-logind[916]: Removed session 7. Dec 13 01:19:07.745205 sshd[1022]: Accepted publickey for core from 10.0.0.1 port 55856 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:07.746276 sshd[1022]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:07.748668 systemd-logind[916]: New session 8 of user core. Dec 13 01:19:07.756568 systemd[1]: Started Session 8 of User core. Dec 13 01:19:07.807786 sudo[1025]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Dec 13 01:19:07.808011 sudo[1025]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:08.060078 sudo[1025]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:08.062961 sshd[1022]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:08.074455 systemd[1]: sshd@7-10.0.0.3:22-10.0.0.1:55856.service: Deactivated successfully. Dec 13 01:19:08.075020 systemd[1]: session-8.scope: Deactivated successfully. Dec 13 01:19:08.075683 systemd-logind[916]: Session 8 logged out. Waiting for processes to exit. Dec 13 01:19:08.076732 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55858). Dec 13 01:19:08.077718 systemd-logind[916]: Removed session 8. Dec 13 01:19:08.107976 sshd[1029]: Accepted publickey for core from 10.0.0.1 port 55858 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:08.108909 sshd[1029]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:08.111519 systemd-logind[916]: New session 9 of user core. Dec 13 01:19:08.123538 systemd[1]: Started Session 9 of User core. Dec 13 01:19:08.175803 sudo[1032]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Dec 13 01:19:08.176031 sudo[1032]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:08.178572 sudo[1032]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:08.179614 sshd[1029]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:08.189280 systemd[1]: sshd@8-10.0.0.3:22-10.0.0.1:55858.service: Deactivated successfully. Dec 13 01:19:08.189885 systemd[1]: session-9.scope: Deactivated successfully. Dec 13 01:19:08.190430 systemd-logind[916]: Session 9 logged out. Waiting for processes to exit. Dec 13 01:19:08.191452 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55868). Dec 13 01:19:08.192003 systemd-logind[916]: Removed session 9. Dec 13 01:19:08.223235 sshd[1036]: Accepted publickey for core from 10.0.0.1 port 55868 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:08.224167 sshd[1036]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:08.226629 systemd-logind[916]: New session 10 of user core. Dec 13 01:19:08.235543 systemd[1]: Started Session 10 of User core. Dec 13 01:19:08.288084 sudo[1039]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Dec 13 01:19:08.288333 sudo[1039]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:09.961565 sudo[1039]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:09.966939 sshd[1036]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:09.977040 systemd[1]: sshd@9-10.0.0.3:22-10.0.0.1:55868.service: Deactivated successfully. Dec 13 01:19:09.977719 systemd[1]: session-10.scope: Deactivated successfully. Dec 13 01:19:09.977959 systemd[1]: session-10.scope: Consumed 1.301s CPU time. Dec 13 01:19:09.978345 systemd-logind[916]: Session 10 logged out. Waiting for processes to exit. Dec 13 01:19:09.979497 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:55882). Dec 13 01:19:09.980090 systemd-logind[916]: Removed session 10. Dec 13 01:19:10.015708 sshd[1043]: Accepted publickey for core from 10.0.0.1 port 55882 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:10.016680 sshd[1043]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:10.018267 kernel: kauditd_printk_skb: 16 callbacks suppressed Dec 13 01:19:10.018386 kernel: audit: type=1006 audit(1734052750.014:61): pid=1043 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=11 res=1 Dec 13 01:19:10.023162 systemd-logind[916]: New session 11 of user core. Dec 13 01:19:10.033634 systemd[1]: Started Session 11 of User core. Dec 13 01:19:10.088455 sudo[1046]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.docker-btrfs-compat Omaha Dec 13 01:19:10.088931 sudo[1046]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Dec 13 01:19:10.112669 systemd[1]: Started /home/core/./kolet run cl.update.docker-btrfs-compat Omaha. Dec 13 01:19:10.113889 sudo[1046]: pam_unix(sudo:session): session closed for user root Dec 13 01:19:10.116885 sshd[1043]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:10.120635 systemd[1]: sshd@10-10.0.0.3:22-10.0.0.1:55882.service: Deactivated successfully. Dec 13 01:19:10.121444 systemd[1]: session-11.scope: Deactivated successfully. Dec 13 01:19:10.122020 systemd-logind[916]: Session 11 logged out. Waiting for processes to exit. Dec 13 01:19:10.122672 systemd-logind[916]: Removed session 11. Dec 13 01:19:15.210048 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:38424). Dec 13 01:19:15.241673 sshd[1058]: Accepted publickey for core from 10.0.0.1 port 38424 ssh2: RSA SHA256:9VyhdtG1PdxLjcv3gGiu0HhiErNGWXArtdLPGK/4nd4 Dec 13 01:19:15.243731 sshd[1058]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Dec 13 01:19:15.244544 kernel: audit: type=1006 audit(1734052755.240:62): pid=1058 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=12 res=1 Dec 13 01:19:15.246418 systemd-logind[916]: New session 12 of user core. Dec 13 01:19:15.256503 systemd[1]: Started Session 12 of User core. Dec 13 01:19:15.315699 sshd[1058]: pam_unix(sshd:session): session closed for user core Dec 13 01:19:15.317585 systemd[1]: sshd@11-10.0.0.3:22-10.0.0.1:38424.service: Deactivated successfully. Dec 13 01:19:15.318259 systemd[1]: session-12.scope: Deactivated successfully. Dec 13 01:19:15.320527 systemd-logind[916]: Session 12 logged out. Waiting for processes to exit. Dec 13 01:19:15.321204 systemd-logind[916]: Removed session 12. Dec 13 01:19:50.878117 update_engine[919]: I1213 01:19:50.878031 919 update_attempter.cc:505] Updating boot flags...