Mar 17 18:07:50.736493 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 17 18:07:50.736509 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 Mar 17 18:07:50.736517 kernel: efi: EFI v2.70 by EDK II Mar 17 18:07:50.736523 kernel: efi: SMBIOS 3.0=0xd9260000 ACPI 2.0=0xd9240000 MEMATTR=0xda313018 RNG=0xdc9bd418 MEMRESERVE=0xd9521c18 Mar 17 18:07:50.736527 kernel: efi: seeding entropy pool Mar 17 18:07:50.736532 kernel: ACPI: Early table checksum verification disabled Mar 17 18:07:50.736538 kernel: ACPI: RSDP 0x00000000D9240000 000024 (v02 BOCHS ) Mar 17 18:07:50.736544 kernel: ACPI: XSDT 0x00000000D9230000 000064 (v01 BOCHS BXPC 00000001 01000013) Mar 17 18:07:50.736549 kernel: ACPI: FACP 0x00000000D91E0000 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736555 kernel: ACPI: DSDT 0x00000000D91F0000 0014A2 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736560 kernel: ACPI: APIC 0x00000000D91D0000 0001A8 (v04 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736565 kernel: ACPI: PPTT 0x00000000D91C0000 00009C (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736570 kernel: ACPI: GTDT 0x00000000D91B0000 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736575 kernel: ACPI: MCFG 0x00000000D91A0000 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736582 kernel: ACPI: SPCR 0x00000000D9190000 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736587 kernel: ACPI: DBG2 0x00000000D9180000 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736594 kernel: ACPI: IORT 0x00000000D9170000 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) Mar 17 18:07:50.736599 kernel: ACPI: SPCR: console: pl011,mmio,0x9000000,9600 Mar 17 18:07:50.736604 kernel: ACPI: NUMA: Failed to initialise from firmware Mar 17 18:07:50.736609 kernel: NUMA: Faking a node at [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:07:50.736615 kernel: NUMA: NODE_DATA [mem 0xdcb0a900-0xdcb0ffff] Mar 17 18:07:50.736620 kernel: Zone ranges: Mar 17 18:07:50.736625 kernel: DMA [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:07:50.736630 kernel: DMA32 empty Mar 17 18:07:50.736635 kernel: Normal empty Mar 17 18:07:50.736640 kernel: Movable zone start for each node Mar 17 18:07:50.736645 kernel: Early memory node ranges Mar 17 18:07:50.736652 kernel: node 0: [mem 0x0000000040000000-0x00000000d924ffff] Mar 17 18:07:50.736658 kernel: node 0: [mem 0x00000000d9250000-0x00000000d951ffff] Mar 17 18:07:50.736663 kernel: node 0: [mem 0x00000000d9520000-0x00000000dc7fffff] Mar 17 18:07:50.736668 kernel: node 0: [mem 0x00000000dc800000-0x00000000dc88ffff] Mar 17 18:07:50.736674 kernel: node 0: [mem 0x00000000dc890000-0x00000000dc89ffff] Mar 17 18:07:50.736679 kernel: node 0: [mem 0x00000000dc8a0000-0x00000000dc9bffff] Mar 17 18:07:50.736684 kernel: node 0: [mem 0x00000000dc9c0000-0x00000000dcffffff] Mar 17 18:07:50.736689 kernel: Initmem setup node 0 [mem 0x0000000040000000-0x00000000dcffffff] Mar 17 18:07:50.736694 kernel: On node 0 totalpages: 643072 Mar 17 18:07:50.736700 kernel: DMA zone: 10048 pages used for memmap Mar 17 18:07:50.736705 kernel: DMA zone: 0 pages reserved Mar 17 18:07:50.736710 kernel: DMA zone: 643072 pages, LIFO batch:63 Mar 17 18:07:50.736717 kernel: psci: probing for conduit method from ACPI. Mar 17 18:07:50.736725 kernel: psci: PSCIv1.1 detected in firmware. Mar 17 18:07:50.736730 kernel: psci: Using standard PSCI v0.2 function IDs Mar 17 18:07:50.736735 kernel: psci: Trusted OS migration not required Mar 17 18:07:50.736741 kernel: psci: SMC Calling Convention v1.1 Mar 17 18:07:50.736746 kernel: ACPI: SRAT not present Mar 17 18:07:50.736752 kernel: percpu: Embedded 32 pages/cpu s93720 r8192 d29160 u131072 Mar 17 18:07:50.736765 kernel: pcpu-alloc: s93720 r8192 d29160 u131072 alloc=32*4096 Mar 17 18:07:50.736770 kernel: pcpu-alloc: [0] 0 [0] 1 [0] 2 [0] 3 Mar 17 18:07:50.736778 kernel: Detected PIPT I-cache on CPU0 Mar 17 18:07:50.736783 kernel: CPU features: detected: GIC system register CPU interface Mar 17 18:07:50.736789 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 17 18:07:50.736827 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 17 18:07:50.736835 kernel: CPU features: detected: Hardware dirty bit management Mar 17 18:07:50.736840 kernel: CPU features: detected: Spectre-v4 Mar 17 18:07:50.736846 kernel: CPU features: detected: ARM erratum 1418040 Mar 17 18:07:50.736851 kernel: CPU features: detected: Spectre-BHB Mar 17 18:07:50.736857 kernel: Built 1 zonelists, mobility grouping on. Total pages: 633024 Mar 17 18:07:50.736862 kernel: Policy zone: DMA Mar 17 18:07:50.736869 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 Mar 17 18:07:50.736877 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 18:07:50.736883 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 18:07:50.736888 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:07:50.736894 kernel: Memory: 2452432K/2572288K available (9344K kernel code, 2274K rwdata, 6844K rodata, 34240K init, 769K bss, 119856K reserved, 0K cma-reserved) Mar 17 18:07:50.736906 kernel: random: get_random_u64 called from __kmem_cache_create+0x28/0x4a0 with crng_init=0 Mar 17 18:07:50.736916 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1 Mar 17 18:07:50.736921 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 18:07:50.736928 kernel: rcu: RCU event tracing is enabled. Mar 17 18:07:50.736934 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=4. Mar 17 18:07:50.736940 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 18:07:50.736945 kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:07:50.736952 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 18:07:50.736958 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=4 Mar 17 18:07:50.736963 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 17 18:07:50.736969 kernel: GICv3: 256 SPIs implemented Mar 17 18:07:50.736974 kernel: GICv3: 0 Extended SPIs implemented Mar 17 18:07:50.736980 kernel: GICv3: Distributor has no Range Selector support Mar 17 18:07:50.736985 kernel: GICv3: 16 PPIs implemented Mar 17 18:07:50.736991 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000080a0000 Mar 17 18:07:50.736996 kernel: ACPI: SRAT not present Mar 17 18:07:50.737001 kernel: ITS [mem 0x08080000-0x0809ffff] Mar 17 18:07:50.737007 kernel: ITS@0x0000000008080000: allocated 8192 Devices @400b0000 (indirect, esz 8, psz 64K, shr 1) Mar 17 18:07:50.737013 kernel: ITS@0x0000000008080000: allocated 8192 Interrupt Collections @400c0000 (flat, esz 8, psz 64K, shr 1) Mar 17 18:07:50.737020 kernel: GICv3: using LPI property table @0x00000000400d0000 Mar 17 18:07:50.737025 kernel: GICv3: CPU0: using allocated LPI pending table @0x00000000400e0000 Mar 17 18:07:50.737031 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:07:50.737036 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 17 18:07:50.737042 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 17 18:07:50.737048 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 17 18:07:50.737054 kernel: arm-pv: using stolen time PV Mar 17 18:07:50.737060 kernel: Console: colour dummy device 80x25 Mar 17 18:07:50.737065 kernel: ACPI: Core revision 20200925 Mar 17 18:07:50.737071 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 17 18:07:50.737078 kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:07:50.737084 kernel: LSM: Security Framework initializing Mar 17 18:07:50.737090 kernel: SELinux: Initializing. Mar 17 18:07:50.737096 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:07:50.737102 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:07:50.737108 kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:07:50.737113 kernel: Platform MSI: ITS@0x8080000 domain created Mar 17 18:07:50.737119 kernel: PCI/MSI: ITS@0x8080000 domain created Mar 17 18:07:50.737125 kernel: Remapping and enabling EFI services. Mar 17 18:07:50.737130 kernel: smp: Bringing up secondary CPUs ... Mar 17 18:07:50.737137 kernel: Detected PIPT I-cache on CPU1 Mar 17 18:07:50.737143 kernel: GICv3: CPU1: found redistributor 1 region 0:0x00000000080c0000 Mar 17 18:07:50.737149 kernel: GICv3: CPU1: using allocated LPI pending table @0x00000000400f0000 Mar 17 18:07:50.737155 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:07:50.737161 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 17 18:07:50.737167 kernel: Detected PIPT I-cache on CPU2 Mar 17 18:07:50.737173 kernel: GICv3: CPU2: found redistributor 2 region 0:0x00000000080e0000 Mar 17 18:07:50.737178 kernel: GICv3: CPU2: using allocated LPI pending table @0x0000000040100000 Mar 17 18:07:50.737184 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:07:50.737190 kernel: CPU2: Booted secondary processor 0x0000000002 [0x413fd0c1] Mar 17 18:07:50.737197 kernel: Detected PIPT I-cache on CPU3 Mar 17 18:07:50.737203 kernel: GICv3: CPU3: found redistributor 3 region 0:0x0000000008100000 Mar 17 18:07:50.737210 kernel: GICv3: CPU3: using allocated LPI pending table @0x0000000040110000 Mar 17 18:07:50.737216 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:07:50.737222 kernel: CPU3: Booted secondary processor 0x0000000003 [0x413fd0c1] Mar 17 18:07:50.737228 kernel: smp: Brought up 1 node, 4 CPUs Mar 17 18:07:50.737238 kernel: SMP: Total of 4 processors activated. Mar 17 18:07:50.737245 kernel: CPU features: detected: Privileged Access Never Mar 17 18:07:50.737251 kernel: CPU features: detected: LSE atomic instructions Mar 17 18:07:50.737257 kernel: CPU features: detected: User Access Override Mar 17 18:07:50.737263 kernel: CPU features: detected: 32-bit EL0 Support Mar 17 18:07:50.737269 kernel: CPU features: detected: Common not Private translations Mar 17 18:07:50.737275 kernel: CPU features: detected: RAS Extension Support Mar 17 18:07:50.737281 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 17 18:07:50.737287 kernel: CPU features: detected: CRC32 instructions Mar 17 18:07:50.737294 kernel: CPU features: detected: Speculative Store Bypassing Safe (SSBS) Mar 17 18:07:50.737300 kernel: CPU: All CPU(s) started at EL1 Mar 17 18:07:50.737307 kernel: alternatives: patching kernel code Mar 17 18:07:50.737313 kernel: devtmpfs: initialized Mar 17 18:07:50.737319 kernel: KASLR enabled Mar 17 18:07:50.737325 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 18:07:50.737331 kernel: futex hash table entries: 1024 (order: 4, 65536 bytes, linear) Mar 17 18:07:50.737339 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:07:50.737345 kernel: SMBIOS 3.0.0 present. Mar 17 18:07:50.737378 kernel: DMI: QEMU KVM Virtual Machine, BIOS 0.0.0 02/06/2015 Mar 17 18:07:50.737384 kernel: NET: Registered protocol family 16 Mar 17 18:07:50.737391 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 17 18:07:50.737397 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 17 18:07:50.737403 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 17 18:07:50.737410 kernel: audit: initializing netlink subsys (disabled) Mar 17 18:07:50.737416 kernel: audit: type=2000 audit(0.031:1): state=initialized audit_enabled=0 res=1 Mar 17 18:07:50.737425 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:07:50.737431 kernel: cpuidle: using governor menu Mar 17 18:07:50.737437 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 17 18:07:50.737444 kernel: ASID allocator initialised with 32768 entries Mar 17 18:07:50.737450 kernel: ACPI: bus type PCI registered Mar 17 18:07:50.737457 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:07:50.737463 kernel: Serial: AMBA PL011 UART driver Mar 17 18:07:50.737469 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 18:07:50.737475 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Mar 17 18:07:50.737481 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:07:50.737488 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Mar 17 18:07:50.737495 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:07:50.737501 kernel: ACPI: Added _OSI(Module Device) Mar 17 18:07:50.737507 kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:07:50.737514 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:07:50.737520 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:07:50.737526 kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:07:50.737532 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:07:50.737538 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:07:50.737545 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 18:07:50.737551 kernel: ACPI: Interpreter enabled Mar 17 18:07:50.737558 kernel: ACPI: Using GIC for interrupt routing Mar 17 18:07:50.737563 kernel: ACPI: MCFG table detected, 1 entries Mar 17 18:07:50.737570 kernel: ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA Mar 17 18:07:50.737576 kernel: printk: console [ttyAMA0] enabled Mar 17 18:07:50.737582 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Mar 17 18:07:50.737687 kernel: acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI HPX-Type3] Mar 17 18:07:50.737747 kernel: acpi PNP0A08:00: _OSC: platform does not support [LTR] Mar 17 18:07:50.737850 kernel: acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] Mar 17 18:07:50.737911 kernel: acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 Mar 17 18:07:50.737967 kernel: acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] Mar 17 18:07:50.737975 kernel: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] Mar 17 18:07:50.737982 kernel: PCI host bridge to bus 0000:00 Mar 17 18:07:50.738045 kernel: pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] Mar 17 18:07:50.738094 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] Mar 17 18:07:50.738148 kernel: pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] Mar 17 18:07:50.738197 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Mar 17 18:07:50.738266 kernel: pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 Mar 17 18:07:50.738329 kernel: pci 0000:00:01.0: [1af4:1005] type 00 class 0x00ff00 Mar 17 18:07:50.738401 kernel: pci 0000:00:01.0: reg 0x10: [io 0x0000-0x001f] Mar 17 18:07:50.738458 kernel: pci 0000:00:01.0: reg 0x14: [mem 0x10000000-0x10000fff] Mar 17 18:07:50.738515 kernel: pci 0000:00:01.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] Mar 17 18:07:50.738573 kernel: pci 0000:00:01.0: BAR 4: assigned [mem 0x8000000000-0x8000003fff 64bit pref] Mar 17 18:07:50.738632 kernel: pci 0000:00:01.0: BAR 1: assigned [mem 0x10000000-0x10000fff] Mar 17 18:07:50.738687 kernel: pci 0000:00:01.0: BAR 0: assigned [io 0x1000-0x101f] Mar 17 18:07:50.738739 kernel: pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] Mar 17 18:07:50.738800 kernel: pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] Mar 17 18:07:50.738852 kernel: pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] Mar 17 18:07:50.738860 kernel: ACPI: PCI Interrupt Link [GSI0] (IRQs *35) Mar 17 18:07:50.738869 kernel: ACPI: PCI Interrupt Link [GSI1] (IRQs *36) Mar 17 18:07:50.738875 kernel: ACPI: PCI Interrupt Link [GSI2] (IRQs *37) Mar 17 18:07:50.738881 kernel: ACPI: PCI Interrupt Link [GSI3] (IRQs *38) Mar 17 18:07:50.738888 kernel: vgaarb: loaded Mar 17 18:07:50.738894 kernel: Registered efivars operations Mar 17 18:07:50.738900 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 17 18:07:50.738906 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:07:50.738913 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:07:50.738919 kernel: pnp: PnP ACPI init Mar 17 18:07:50.738985 kernel: system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved Mar 17 18:07:50.739042 kernel: system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active) Mar 17 18:07:50.739051 kernel: pnp: PnP ACPI: found 1 devices Mar 17 18:07:50.739057 kernel: NET: Registered protocol family 2 Mar 17 18:07:50.739064 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 18:07:50.739070 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 18:07:50.739077 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 18:07:50.739083 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Mar 17 18:07:50.739091 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 18:07:50.739098 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:07:50.739104 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:07:50.739110 kernel: NET: Registered protocol family 1 Mar 17 18:07:50.739116 kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:07:50.739122 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available Mar 17 18:07:50.739129 kernel: kvm [1]: HYP mode not available Mar 17 18:07:50.739135 kernel: Initialise system trusted keyrings Mar 17 18:07:50.739141 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 18:07:50.739149 kernel: Key type asymmetric registered Mar 17 18:07:50.739155 kernel: Asymmetric key parser 'x509' registered Mar 17 18:07:50.739161 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Mar 17 18:07:50.739167 kernel: io scheduler mq-deadline registered Mar 17 18:07:50.739173 kernel: io scheduler kyber registered Mar 17 18:07:50.739180 kernel: io scheduler bfq registered Mar 17 18:07:50.739186 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 Mar 17 18:07:50.739192 kernel: ACPI: Power Button [PWRB] Mar 17 18:07:50.739198 kernel: PCI Interrupt Link [GSI1] enabled at IRQ 36 Mar 17 18:07:50.739255 kernel: virtio-pci 0000:00:01.0: enabling device (0005 -> 0007) Mar 17 18:07:50.739266 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 18:07:50.739272 kernel: msm_serial: driver initialized Mar 17 18:07:50.739278 kernel: random: fast init done Mar 17 18:07:50.739284 kernel: random: crng init done Mar 17 18:07:50.739290 kernel: wireguard: WireGuard 1.0.0 loaded. See www.wireguard.com for information. Mar 17 18:07:50.739297 kernel: wireguard: Copyright (C) 2015-2019 Jason A. Donenfeld . All Rights Reserved. Mar 17 18:07:50.739303 kernel: thunder_xcv, ver 1.0 Mar 17 18:07:50.739309 kernel: thunder_bgx, ver 1.0 Mar 17 18:07:50.739315 kernel: nicpf, ver 1.0 Mar 17 18:07:50.739323 kernel: nicvf, ver 1.0 Mar 17 18:07:50.739405 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 17 18:07:50.739462 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-17T18:07:50 UTC (1742234870) Mar 17 18:07:50.739470 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:07:50.739476 kernel: NET: Registered protocol family 10 Mar 17 18:07:50.739482 kernel: Segment Routing with IPv6 Mar 17 18:07:50.739489 kernel: NET: Registered protocol family 17 Mar 17 18:07:50.739495 kernel: Key type dns_resolver registered Mar 17 18:07:50.739501 kernel: registered taskstats version 1 Mar 17 18:07:50.739509 kernel: Loading compiled-in X.509 certificates Mar 17 18:07:50.739515 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.10.107-flatcar: acb58ed5bddf18757940d1f794ad1dcb34e85378' Mar 17 18:07:50.739522 kernel: Key type ._fscrypt registered Mar 17 18:07:50.739528 kernel: Key type .fscrypt registered Mar 17 18:07:50.739534 kernel: Key type fscrypt-provisioning registered Mar 17 18:07:50.739540 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 18:07:50.739547 kernel: ima: Allocated hash algorithm: sha1 Mar 17 18:07:50.739553 kernel: ima: No architecture policies found Mar 17 18:07:50.739559 kernel: Freeing unused kernel memory: 34240K Mar 17 18:07:50.739566 kernel: Run /init as init process Mar 17 18:07:50.739572 kernel: with arguments: Mar 17 18:07:50.739578 kernel: /init Mar 17 18:07:50.739584 kernel: with environment: Mar 17 18:07:50.739590 kernel: HOME=/ Mar 17 18:07:50.739596 kernel: TERM=linux Mar 17 18:07:50.739602 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 18:07:50.739610 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) Mar 17 18:07:50.739620 systemd[1]: Detected virtualization kvm. Mar 17 18:07:50.739627 systemd[1]: Detected architecture arm64. Mar 17 18:07:50.739633 systemd[1]: Running in initial RAM disk. Mar 17 18:07:50.739640 systemd[1]: No hostname configured, using default hostname. Mar 17 18:07:50.739646 systemd[1]: Hostname set to . Mar 17 18:07:50.739653 systemd[1]: Initializing machine ID from VM UUID. Mar 17 18:07:50.739660 systemd[1]: Queued start job for default target Initrd Default Target. Mar 17 18:07:50.739666 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Mar 17 18:07:50.739674 systemd[1]: Reached target Local Encrypted Volumes. Mar 17 18:07:50.739681 systemd[1]: Reached target Path Units. Mar 17 18:07:50.739687 systemd[1]: Reached target Slice Units. Mar 17 18:07:50.739694 systemd[1]: Reached target Swaps. Mar 17 18:07:50.739700 systemd[1]: Reached target Timer Units. Mar 17 18:07:50.739707 systemd[1]: Listening on Open-iSCSI iscsid Socket. Mar 17 18:07:50.739714 systemd[1]: Listening on Open-iSCSI iscsiuio Socket. Mar 17 18:07:50.739720 systemd[1]: Listening on Journal Audit Socket. Mar 17 18:07:50.739728 systemd[1]: Listening on Journal Socket (/dev/log). Mar 17 18:07:50.739735 systemd[1]: Listening on Journal Socket. Mar 17 18:07:50.739741 systemd[1]: Listening on Network Service Netlink Socket. Mar 17 18:07:50.739748 systemd[1]: Listening on udev Control Socket. Mar 17 18:07:50.739754 systemd[1]: Listening on udev Kernel Socket. Mar 17 18:07:50.739768 systemd[1]: Reached target Socket Units. Mar 17 18:07:50.739775 systemd[1]: Starting Create List of Static Device Nodes... Mar 17 18:07:50.739782 systemd[1]: Finished Network Cleanup. Mar 17 18:07:50.739789 systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. Mar 17 18:07:50.739795 systemd[1]: Starting Journal Service... Mar 17 18:07:50.739804 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Mar 17 18:07:50.739811 systemd[1]: Starting Apply Kernel Variables... Mar 17 18:07:50.739817 systemd[1]: Starting Setup Virtual Console... Mar 17 18:07:50.739824 systemd[1]: Finished Create List of Static Device Nodes. Mar 17 18:07:50.739830 systemd[1]: Starting Create Static Device Nodes in /dev... Mar 17 18:07:50.739837 systemd[1]: Finished Apply Kernel Variables. Mar 17 18:07:50.739844 systemd[1]: Finished Setup Virtual Console. Mar 17 18:07:50.739850 systemd[1]: Finished Create Static Device Nodes in /dev. Mar 17 18:07:50.739857 systemd[1]: Starting dracut ask for additional cmdline parameters... Mar 17 18:07:50.739870 systemd-journald[278]: Journal started Mar 17 18:07:50.739908 systemd-journald[278]: Runtime Journal (/run/log/journal/705e0bdd38cf417898167a5252cfd3fb) is 6.0M, max 48.5M, 42.4M free. Mar 17 18:07:50.741382 systemd[1]: Started Journal Service. Mar 17 18:07:50.751420 systemd[1]: Finished dracut ask for additional cmdline parameters. Mar 17 18:07:50.753028 systemd[1]: Starting dracut cmdline hook... Mar 17 18:07:50.763093 dracut-cmdline[296]: dracut-dracut-053 Mar 17 18:07:50.765603 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 Mar 17 18:07:50.851380 kernel: SCSI subsystem initialized Mar 17 18:07:50.855383 kernel: Loading iSCSI transport class v2.0-870. Mar 17 18:07:50.862378 kernel: iscsi: registered transport (tcp) Mar 17 18:07:50.875592 kernel: iscsi: registered transport (qla4xxx) Mar 17 18:07:50.875607 kernel: QLogic iSCSI HBA Driver Mar 17 18:07:50.886381 systemd[1]: Finished dracut cmdline hook. Mar 17 18:07:50.887814 systemd[1]: Starting dracut pre-udev hook... Mar 17 18:07:50.903266 kernel: device-mapper: uevent: version 1.0.3 Mar 17 18:07:50.903321 kernel: device-mapper: ioctl: 4.43.0-ioctl (2020-10-01) initialised: dm-devel@redhat.com Mar 17 18:07:50.940412 kernel: raid6: neonx8 gen() 12257 MB/s Mar 17 18:07:50.961408 kernel: raid6: neonx8 xor() 9922 MB/s Mar 17 18:07:50.978401 kernel: raid6: neonx4 gen() 13500 MB/s Mar 17 18:07:50.995416 kernel: raid6: neonx4 xor() 11282 MB/s Mar 17 18:07:51.012401 kernel: raid6: neonx2 gen() 12748 MB/s Mar 17 18:07:51.029402 kernel: raid6: neonx2 xor() 9516 MB/s Mar 17 18:07:51.046398 kernel: raid6: neonx1 gen() 10996 MB/s Mar 17 18:07:51.063401 kernel: raid6: neonx1 xor() 8824 MB/s Mar 17 18:07:51.080411 kernel: raid6: int64x8 gen() 6807 MB/s Mar 17 18:07:51.097399 kernel: raid6: int64x8 xor() 3799 MB/s Mar 17 18:07:51.114399 kernel: raid6: int64x4 gen() 6881 MB/s Mar 17 18:07:51.131391 kernel: raid6: int64x4 xor() 3829 MB/s Mar 17 18:07:51.148397 kernel: raid6: int64x2 gen() 6169 MB/s Mar 17 18:07:51.165400 kernel: raid6: int64x2 xor() 3313 MB/s Mar 17 18:07:51.182402 kernel: raid6: int64x1 gen() 5005 MB/s Mar 17 18:07:51.199809 kernel: raid6: int64x1 xor() 2592 MB/s Mar 17 18:07:51.199879 kernel: raid6: using algorithm neonx4 gen() 13500 MB/s Mar 17 18:07:51.199888 kernel: raid6: .... xor() 11282 MB/s, rmw enabled Mar 17 18:07:51.199897 kernel: raid6: using neon recovery algorithm Mar 17 18:07:51.210793 kernel: xor: measuring software checksum speed Mar 17 18:07:51.210845 kernel: 8regs : 17159 MB/sec Mar 17 18:07:51.211691 kernel: 32regs : 20749 MB/sec Mar 17 18:07:51.212446 kernel: arm64_neon : 29132 MB/sec Mar 17 18:07:51.212468 kernel: xor: using function: arm64_neon (29132 MB/sec) Mar 17 18:07:51.263389 kernel: Btrfs loaded, crc32c=crc32c-generic Mar 17 18:07:51.276134 systemd[1]: Finished dracut pre-udev hook. Mar 17 18:07:51.277000 audit: BPF prog-id=6 op=LOAD Mar 17 18:07:51.277000 audit: BPF prog-id=7 op=LOAD Mar 17 18:07:51.278819 kernel: audit: type=1334 audit(1742234871.277:2): prog-id=6 op=LOAD Mar 17 18:07:51.278850 kernel: audit: type=1334 audit(1742234871.277:3): prog-id=7 op=LOAD Mar 17 18:07:51.278859 kernel: audit: type=1334 audit(1742234871.278:4): prog-id=8 op=LOAD Mar 17 18:07:51.278000 audit: BPF prog-id=8 op=LOAD Mar 17 18:07:51.279256 systemd[1]: Starting Rule-based Manager for Device Events and Files... Mar 17 18:07:51.294063 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Mar 17 18:07:51.296223 systemd[1]: Started Rule-based Manager for Device Events and Files. Mar 17 18:07:51.299000 audit: BPF prog-id=9 op=LOAD Mar 17 18:07:51.298026 systemd[1]: Starting dracut pre-trigger hook... Mar 17 18:07:51.301093 kernel: audit: type=1334 audit(1742234871.299:5): prog-id=9 op=LOAD Mar 17 18:07:51.300457 systemd[1]: Starting Network Configuration... Mar 17 18:07:51.314097 dracut-pre-trigger[422]: rd.md=0: removing MD RAID activation Mar 17 18:07:51.322368 systemd-networkd[428]: lo: Link UP Mar 17 18:07:51.322439 systemd-networkd[428]: lo: Gained carrier Mar 17 18:07:51.324000 audit: BPF prog-id=10 op=LOAD Mar 17 18:07:51.322778 systemd-networkd[428]: Enumeration completed Mar 17 18:07:51.325959 kernel: audit: type=1334 audit(1742234871.324:6): prog-id=10 op=LOAD Mar 17 18:07:51.322875 systemd[1]: Started Network Configuration. Mar 17 18:07:51.325702 systemd[1]: Starting Network Name Resolution... Mar 17 18:07:51.344506 systemd[1]: Finished dracut pre-trigger hook. Mar 17 18:07:51.345969 systemd[1]: Starting Coldplug All udev Devices... Mar 17 18:07:51.356278 systemd-udevd[417]: /usr/lib/udev/rules.d/50-udev-default.rules:42 Unknown group 'sgx', ignoring Mar 17 18:07:51.356346 systemd-resolved[441]: Positive Trust Anchors: Mar 17 18:07:51.356368 systemd-resolved[441]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:07:51.356397 systemd-resolved[441]: 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 Mar 17 18:07:51.356696 systemd-resolved[441]: Defaulting to hostname 'linux'. Mar 17 18:07:51.357462 systemd[1]: Started Network Name Resolution. Mar 17 18:07:51.358849 systemd[1]: Reached target Network. Mar 17 18:07:51.363126 systemd[1]: Reached target Host and Network Name Lookups. Mar 17 18:07:51.372462 systemd[1]: Starting iSCSI UserSpace I/O driver... Mar 17 18:07:51.380475 systemd[1]: Started iSCSI UserSpace I/O driver. Mar 17 18:07:51.382341 systemd[1]: Starting Open-iSCSI... Mar 17 18:07:51.383609 systemd[1]: Finished Coldplug All udev Devices. Mar 17 18:07:51.385006 iscsid[481]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:07:51.385006 iscsid[481]: 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 Mar 17 18:07:51.385006 iscsid[481]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Mar 17 18:07:51.385006 iscsid[481]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Mar 17 18:07:51.385006 iscsid[481]: If using hardware iscsi like qla4xxx this message can be ignored. Mar 17 18:07:51.385006 iscsid[481]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:07:51.385006 iscsid[481]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Mar 17 18:07:51.388215 systemd[1]: Started Open-iSCSI. Mar 17 18:07:51.390863 systemd[1]: Starting dracut initqueue hook... Mar 17 18:07:51.403851 kernel: virtio_blk virtio1: [vda] 9289728 512-byte logical blocks (4.76 GB/4.43 GiB) Mar 17 18:07:51.403985 kernel: vda: detected capacity change from 0 to 4756340736 Mar 17 18:07:51.415638 systemd-udevd[466]: Using default interface naming scheme 'v249'. Mar 17 18:07:51.418379 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:07:51.421700 systemd-networkd[428]: eth0: Link UP Mar 17 18:07:51.441387 kernel: BTRFS: device label OEM devid 1 transid 11 /dev/vda6 scanned by systemd-udevd (476) Mar 17 18:07:51.442168 systemd[1]: Found device /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Mar 17 18:07:51.444437 systemd[1]: Found device /dev/disk/by-partlabel/USR-A. Mar 17 18:07:51.449438 systemd[1]: Found device /dev/disk/by-label/EFI-SYSTEM. Mar 17 18:07:51.454261 systemd[1]: Found device /dev/disk/by-label/OEM. Mar 17 18:07:51.456086 systemd[1]: Finished dracut initqueue hook. Mar 17 18:07:51.458615 systemd[1]: Found device /dev/disk/by-label/ROOT. Mar 17 18:07:51.459335 systemd[1]: Reached target Initrd Root Device. Mar 17 18:07:51.460465 systemd[1]: Reached target Preparation for Remote File Systems. Mar 17 18:07:51.461730 systemd[1]: Reached target Remote Encrypted Volumes. Mar 17 18:07:51.463036 systemd[1]: Reached target Remote File Systems. Mar 17 18:07:51.464984 systemd[1]: Starting Generate new UUID for disk GPT if necessary... Mar 17 18:07:51.466594 systemd[1]: Starting dracut pre-mount hook... Mar 17 18:07:51.472389 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:07:51.475556 systemd[1]: Finished dracut pre-mount hook. Mar 17 18:07:52.448213 systemd-networkd[428]: eth0: Gained carrier Mar 17 18:07:52.449275 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:07:52.458420 systemd-networkd[428]: eth0: DHCPv4 address 10.0.0.2/16 via 10.0.0.1 Mar 17 18:07:52.483296 disk-uuid[503]: The operation has completed successfully. Mar 17 18:07:52.484179 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Mar 17 18:07:52.507287 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 18:07:52.507416 systemd[1]: Finished Generate new UUID for disk GPT if necessary. Mar 17 18:07:52.509147 systemd[1]: Starting Ignition (setup)... Mar 17 18:07:52.517365 kernel: BTRFS info (device vda6): disk space caching is enabled Mar 17 18:07:52.517415 kernel: BTRFS info (device vda6): has skinny extents Mar 17 18:07:52.524439 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 18:07:52.534145 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 18:07:52.534269 systemd[1]: Finished Ignition (setup). Mar 17 18:07:52.536597 systemd[1]: Starting Ignition (disks)... Mar 17 18:07:52.553171 ignition[538]: Ignition v0.36.1-15-gde4e6cc9 Mar 17 18:07:52.553189 ignition[538]: Stage: disks Mar 17 18:07:52.553199 ignition[538]: reading system config file "/usr/lib/ignition/base.ign" Mar 17 18:07:52.553211 ignition[538]: no config at "/usr/lib/ignition/base.ign" Mar 17 18:07:52.553260 ignition[538]: parsed url from cmdline: "" Mar 17 18:07:52.553263 ignition[538]: no config URL provided Mar 17 18:07:52.553268 ignition[538]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:07:52.553275 ignition[538]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:07:52.553295 ignition[538]: op(1): [started] loading QEMU firmware config module Mar 17 18:07:52.553300 ignition[538]: op(1): executing: "modprobe" "qemu_fw_cfg" Mar 17 18:07:52.554456 ignition[538]: op(1): [finished] loading QEMU firmware config module Mar 17 18:07:52.566608 ignition[538]: parsing config with SHA512: 1a54f7a345914dafe23d592e425900fdade38dfc3f4500a0ab784247088ce65d254709664589f8f1fa58bf94b848b67afc53e6ac0551bfcb3bf586d4ba613352 Mar 17 18:07:52.569168 ignition[538]: disks: disks passed Mar 17 18:07:52.569177 ignition[538]: Ignition finished successfully Mar 17 18:07:52.569790 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 18:07:52.569911 systemd[1]: Finished Ignition (disks). Mar 17 18:07:52.571100 systemd[1]: Reached target Preparation for Local File Systems. Mar 17 18:07:52.573191 systemd[1]: Starting File System Check on /dev/disk/by-label/ROOT... Mar 17 18:07:52.575484 systemd[1]: Starting Verity Setup for /dev/mapper/usr... Mar 17 18:07:52.589992 systemd-fsck[551]: ROOT: clean, 528/553792 files, 37752/553472 blocks Mar 17 18:07:52.595379 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 17 18:07:52.599325 systemd[1]: Finished File System Check on /dev/disk/by-label/ROOT. Mar 17 18:07:52.601431 systemd[1]: Mounting /sysroot... Mar 17 18:07:52.622516 systemd[1]: Found device /dev/mapper/usr. Mar 17 18:07:52.624506 systemd[1]: Starting File System Check on /dev/mapper/usr... Mar 17 18:07:52.626701 systemd[1]: Finished Verity Setup for /dev/mapper/usr. Mar 17 18:07:52.634712 systemd-fsck[566]: fsck.ext4: Operation not permitted while trying to open /dev/mapper/usr Mar 17 18:07:52.634712 systemd-fsck[566]: You must have r/w access to the filesystem or be root Mar 17 18:07:52.637534 systemd-fsck[563]: fsck failed with exit status 8. Mar 17 18:07:52.637541 systemd-fsck[563]: Ignoring error. Mar 17 18:07:52.638619 systemd[1]: Finished File System Check on /dev/mapper/usr. Mar 17 18:07:52.640816 systemd[1]: Mounting /sysusr/usr... Mar 17 18:07:52.644993 kernel: EXT4-fs (vda9): mounted filesystem with ordered data mode. Opts: (null) Mar 17 18:07:52.645055 kernel: ext4 filesystem being mounted at /sysroot supports timestamps until 2038 (0x7fffffff) Mar 17 18:07:52.645665 systemd[1]: Mounted /sysroot. Mar 17 18:07:52.646342 systemd[1]: Reached target Initrd Root File System. Mar 17 18:07:52.651384 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: (null) Mar 17 18:07:52.651744 systemd[1]: Mounted /sysusr/usr. Mar 17 18:07:52.652556 systemd[1]: Reached target Local File Systems. Mar 17 18:07:52.653559 systemd[1]: Reached target System Initialization. Mar 17 18:07:52.654796 systemd[1]: Reached target Basic System. Mar 17 18:07:52.657636 systemd[1]: Mounting /sysroot/usr... Mar 17 18:07:52.659847 systemd[1]: Mounted /sysroot/usr. Mar 17 18:07:52.661169 systemd[1]: Starting Root filesystem setup... Mar 17 18:07:52.697239 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 18:07:52.697410 systemd[1]: Finished Root filesystem setup. Mar 17 18:07:52.699741 systemd[1]: Starting Ignition (files)... Mar 17 18:07:52.701137 systemd[1]: Starting /sysroot/boot... Mar 17 18:07:52.702744 ignition[582]: Ignition v0.36.1-15-gde4e6cc9 Mar 17 18:07:52.702759 ignition[582]: Stage: files Mar 17 18:07:52.702768 ignition[582]: reading system config file "/usr/lib/ignition/base.ign" Mar 17 18:07:52.702777 ignition[582]: no config at "/usr/lib/ignition/base.ign" Mar 17 18:07:52.703273 ignition[582]: files: compiled without relabeling support, skipping Mar 17 18:07:52.728563 systemd[1]: Finished /sysroot/boot. Mar 17 18:07:52.778138 ignition[582]: files: createUsers: op(1): [started] creating or modifying user "core" Mar 17 18:07:52.778153 ignition[582]: files: createUsers: op(1): executing: "/usr/sbin/usermod" "--root" "/sysroot" "core" Mar 17 18:07:52.780376 ignition[582]: files: createUsers: op(1): [finished] creating or modifying user "core" Mar 17 18:07:52.780387 ignition[582]: files: createUsers: op(2): [started] adding ssh keys to user "core" Mar 17 18:07:52.782976 ignition[582]: files: createUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 18:07:52.783001 ignition[582]: files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/etc/flatcar/update.conf" Mar 17 18:07:52.784192 ignition[582]: files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/etc/flatcar/update.conf" Mar 17 18:07:52.784202 ignition[582]: files: op(4): [started] processing unit "coreos-metadata.service" Mar 17 18:07:52.784378 ignition[582]: files: op(4): op(5): [started] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Mar 17 18:07:52.787959 ignition[582]: files: op(4): op(5): [finished] writing unit "coreos-metadata.service" at "etc/systemd/system/coreos-metadata.service" Mar 17 18:07:52.789990 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 18:07:52.787966 ignition[582]: files: op(4): [finished] processing unit "coreos-metadata.service" Mar 17 18:07:52.790114 systemd[1]: Finished Ignition (files). Mar 17 18:07:52.787972 ignition[582]: files: files passed Mar 17 18:07:52.792292 systemd[1]: Condition check resulted in Populate torcx store to satisfy profile being skipped. Mar 17 18:07:52.787976 ignition[582]: Ignition finished successfully Mar 17 18:07:52.793093 systemd[1]: Starting Ignition (record completion)... Mar 17 18:07:52.794435 systemd[1]: Starting Reload Configuration from the Real Root... Mar 17 18:07:52.799189 systemd[1]: Reloading. Mar 17 18:07:52.801000 audit: BPF prog-id=9 op=UNLOAD Mar 17 18:07:52.803368 kernel: audit: type=1334 audit(1742234872.801:7): prog-id=9 op=UNLOAD Mar 17 18:07:52.803000 audit: BPF prog-id=10 op=UNLOAD Mar 17 18:07:52.805370 kernel: audit: type=1334 audit(1742234872.803:8): prog-id=10 op=UNLOAD Mar 17 18:07:52.808000 audit: BPF prog-id=3 op=UNLOAD Mar 17 18:07:52.809000 audit: BPF prog-id=6 op=UNLOAD Mar 17 18:07:52.810391 kernel: audit: type=1334 audit(1742234872.808:9): prog-id=3 op=UNLOAD Mar 17 18:07:52.810411 kernel: audit: type=1334 audit(1742234872.809:10): prog-id=6 op=UNLOAD Mar 17 18:07:52.890000 audit: BPF prog-id=11 op=LOAD Mar 17 18:07:52.891000 audit: BPF prog-id=12 op=LOAD Mar 17 18:07:52.891000 audit: BPF prog-id=13 op=LOAD Mar 17 18:07:52.891000 audit: BPF prog-id=14 op=LOAD Mar 17 18:07:52.891000 audit: BPF prog-id=15 op=LOAD Mar 17 18:07:52.891000 audit: BPF prog-id=4 op=UNLOAD Mar 17 18:07:52.891000 audit: BPF prog-id=5 op=UNLOAD Mar 17 18:07:52.892000 audit: BPF prog-id=16 op=LOAD Mar 17 18:07:52.892000 audit: BPF prog-id=17 op=LOAD Mar 17 18:07:52.892000 audit: BPF prog-id=18 op=LOAD Mar 17 18:07:52.892000 audit: BPF prog-id=7 op=UNLOAD Mar 17 18:07:52.892000 audit: BPF prog-id=8 op=UNLOAD Mar 17 18:07:52.893551 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 18:07:52.893683 systemd[1]: Finished Ignition (record completion). Mar 17 18:07:52.908597 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 18:07:52.908736 systemd[1]: Finished Reload Configuration from the Real Root. Mar 17 18:07:52.910379 systemd[1]: Reached target Initrd File Systems. Mar 17 18:07:52.911750 systemd[1]: Reached target Initrd Default Target. Mar 17 18:07:52.913020 systemd[1]: Condition check resulted in dracut mount hook being skipped. Mar 17 18:07:52.913806 systemd[1]: Starting dracut pre-pivot and cleanup hook... Mar 17 18:07:52.924664 systemd[1]: Finished dracut pre-pivot and cleanup hook. Mar 17 18:07:52.926236 systemd[1]: Starting Cleaning Up and Shutting Down Daemons... Mar 17 18:07:52.932793 systemd[1]: Stopped target Host and Network Name Lookups. Mar 17 18:07:52.933661 systemd[1]: Stopped target Remote Encrypted Volumes. Mar 17 18:07:52.934966 systemd[1]: Stopped target Timer Units. Mar 17 18:07:52.936075 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 18:07:52.936203 systemd[1]: Stopped dracut pre-pivot and cleanup hook. Mar 17 18:07:52.937507 systemd[1]: Stopped target Initrd Default Target. Mar 17 18:07:52.938721 systemd[1]: Stopped target Basic System. Mar 17 18:07:52.939859 systemd[1]: Stopped target Initrd Root Device. Mar 17 18:07:52.941025 systemd[1]: Stopped target Path Units. Mar 17 18:07:52.942140 systemd[1]: Stopped target Remote File Systems. Mar 17 18:07:52.943312 systemd[1]: Stopped target Preparation for Remote File Systems. Mar 17 18:07:52.944633 systemd[1]: Stopped target Slice Units. Mar 17 18:07:52.945742 systemd[1]: Stopped target Socket Units. Mar 17 18:07:52.947009 systemd[1]: Stopped target System Initialization. Mar 17 18:07:52.948093 systemd[1]: Stopped target Local File Systems. Mar 17 18:07:52.949168 systemd[1]: Stopped target Preparation for Local File Systems. Mar 17 18:07:52.950529 systemd[1]: Stopped target Swaps. Mar 17 18:07:52.951594 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 18:07:52.951720 systemd[1]: Stopped dracut pre-mount hook. Mar 17 18:07:52.952995 systemd[1]: Stopped target Local Encrypted Volumes. Mar 17 18:07:52.954010 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 18:07:52.958400 systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. Mar 17 18:07:52.959298 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 18:07:52.959430 systemd[1]: Stopped dracut initqueue hook. Mar 17 18:07:52.962325 iscsid[481]: iscsid shutting down. Mar 17 18:07:52.960904 systemd[1]: Stopping Open-iSCSI... Mar 17 18:07:52.962585 systemd[1]: Stopping /sysroot/boot... Mar 17 18:07:52.963446 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 18:07:52.963576 systemd[1]: Stopped Coldplug All udev Devices. Mar 17 18:07:52.964562 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 18:07:52.964643 systemd[1]: Stopped dracut pre-trigger hook. Mar 17 18:07:52.966585 systemd[1]: iscsid.service: Deactivated successfully. Mar 17 18:07:52.966724 systemd[1]: Stopped Open-iSCSI. Mar 17 18:07:52.967938 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 18:07:52.968000 systemd[1]: Closed Open-iSCSI iscsid Socket. Mar 17 18:07:52.969262 systemd[1]: Stopping iSCSI UserSpace I/O driver... Mar 17 18:07:52.970538 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 18:07:52.970669 systemd[1]: Finished Cleaning Up and Shutting Down Daemons. Mar 17 18:07:52.972095 systemd[1]: iscsiuio.service: Deactivated successfully. Mar 17 18:07:52.972204 systemd[1]: Stopped iSCSI UserSpace I/O driver. Mar 17 18:07:52.973964 systemd[1]: Stopped target Network. Mar 17 18:07:52.975028 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 18:07:52.975092 systemd[1]: Closed Open-iSCSI iscsiuio Socket. Mar 17 18:07:52.976271 systemd[1]: Stopping Network Name Resolution... Mar 17 18:07:52.977698 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 18:07:52.977820 systemd[1]: Stopped /sysroot/boot. Mar 17 18:07:52.982317 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 18:07:52.982449 systemd[1]: Stopped Network Name Resolution. Mar 17 18:07:52.983599 systemd[1]: Stopping Network Configuration... Mar 17 18:07:52.988517 systemd-networkd[428]: eth0: DHCP lease lost Mar 17 18:07:52.989000 audit: BPF prog-id=12 op=UNLOAD Mar 17 18:07:52.999424 systemd-networkd[428]: eth0: DHCPv6 lease lost Mar 17 18:07:53.001142 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:07:53.001269 systemd[1]: Stopped Network Configuration. Mar 17 18:07:53.003024 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 18:07:53.003058 systemd[1]: Closed Network Service Netlink Socket. Mar 17 18:07:53.004849 systemd[1]: Stopping Network Cleanup... Mar 17 18:07:53.005425 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 18:07:53.005491 systemd[1]: Stopped Apply Kernel Variables. Mar 17 18:07:53.006849 systemd[1]: Stopping Rule-based Manager for Device Events and Files... Mar 17 18:07:53.012000 audit: BPF prog-id=11 op=UNLOAD Mar 17 18:07:53.012862 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 18:07:53.013019 systemd[1]: Stopped Rule-based Manager for Device Events and Files. Mar 17 18:07:53.014902 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 18:07:53.015021 systemd[1]: Stopped Network Cleanup. Mar 17 18:07:53.016053 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 18:07:53.016102 systemd[1]: Closed udev Control Socket. Mar 17 18:07:53.017337 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 18:07:53.017457 systemd[1]: Closed udev Kernel Socket. Mar 17 18:07:53.018504 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 18:07:53.018552 systemd[1]: Stopped dracut pre-udev hook. Mar 17 18:07:53.019986 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 18:07:53.026000 audit: BPF prog-id=16 op=UNLOAD Mar 17 18:07:53.020025 systemd[1]: Stopped dracut cmdline hook. Mar 17 18:07:53.021084 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 18:07:53.021124 systemd[1]: Stopped dracut ask for additional cmdline parameters. Mar 17 18:07:53.023405 systemd[1]: Starting Cleanup udev Database... Mar 17 18:07:53.024841 systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... Mar 17 18:07:53.025935 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Mar 17 18:07:53.025999 systemd[1]: Stopped Create Static Device Nodes in /dev. Mar 17 18:07:53.027614 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 18:07:53.027668 systemd[1]: Stopped Create List of Static Device Nodes. Mar 17 18:07:53.028922 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 18:07:53.028968 systemd[1]: Stopped Setup Virtual Console. Mar 17 18:07:53.030464 systemd[1]: rngd.service: Deactivated successfully. Mar 17 18:07:53.030590 systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. Mar 17 18:07:53.031912 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 18:07:53.032023 systemd[1]: Finished Cleanup udev Database. Mar 17 18:07:53.033112 systemd[1]: Reached target Switch Root. Mar 17 18:07:53.042000 audit: BPF prog-id=13 op=UNLOAD Mar 17 18:07:53.035139 systemd[1]: Starting Switch Root... Mar 17 18:07:53.040509 systemd[1]: Switching root. Mar 17 18:07:53.055676 systemd-journald[278]: Journal stopped Mar 17 18:07:55.478111 systemd-journald[278]: Received SIGTERM from PID 1 (systemd). Mar 17 18:07:55.478165 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 18:07:55.478179 kernel: SELinux: policy capability open_perms=1 Mar 17 18:07:55.478190 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 18:07:55.478203 kernel: SELinux: policy capability always_check_network=0 Mar 17 18:07:55.478213 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 18:07:55.478222 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 18:07:55.478232 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 18:07:55.478242 systemd[1]: Successfully loaded SELinux policy in 34.450ms. Mar 17 18:07:55.478255 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 6.602ms. Mar 17 18:07:55.478267 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) Mar 17 18:07:55.478280 systemd[1]: Detected virtualization kvm. Mar 17 18:07:55.478290 systemd[1]: Detected architecture arm64. Mar 17 18:07:55.478300 systemd[1]: Detected first boot. Mar 17 18:07:55.478310 systemd[1]: Initializing machine ID from VM UUID. Mar 17 18:07:55.478320 systemd[1]: Populated /etc with preset unit settings. Mar 17 18:07:55.478331 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. Mar 17 18:07:55.478342 systemd[1]: initrd-switch-root.service: Current command vanished from the unit file, execution of the command list won't be resumed. Mar 17 18:07:55.478372 kernel: kauditd_printk_skb: 24 callbacks suppressed Mar 17 18:07:55.478385 kernel: audit: type=1334 audit(1742234875.322:35): prog-id=21 op=LOAD Mar 17 18:07:55.478394 kernel: audit: type=1334 audit(1742234875.323:36): prog-id=22 op=LOAD Mar 17 18:07:55.478404 kernel: audit: type=1334 audit(1742234875.323:37): prog-id=23 op=LOAD Mar 17 18:07:55.478412 kernel: audit: type=1334 audit(1742234875.323:38): prog-id=14 op=UNLOAD Mar 17 18:07:55.478422 kernel: audit: type=1334 audit(1742234875.323:39): prog-id=15 op=UNLOAD Mar 17 18:07:55.478431 kernel: audit: type=1334 audit(1742234875.330:40): prog-id=24 op=LOAD Mar 17 18:07:55.478441 kernel: audit: type=1334 audit(1742234875.330:41): prog-id=21 op=UNLOAD Mar 17 18:07:55.478450 kernel: audit: type=1334 audit(1742234875.331:42): prog-id=25 op=LOAD Mar 17 18:07:55.478459 kernel: audit: type=1334 audit(1742234875.331:43): prog-id=26 op=LOAD Mar 17 18:07:55.478470 kernel: audit: type=1334 audit(1742234875.331:44): prog-id=22 op=UNLOAD Mar 17 18:07:55.478480 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 18:07:55.478490 systemd[1]: Stopped Switch Root. Mar 17 18:07:55.478500 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 18:07:55.478510 systemd[1]: Created slice Slice /system/addon-config. Mar 17 18:07:55.478520 systemd[1]: Created slice Slice /system/addon-run. Mar 17 18:07:55.478530 systemd[1]: Created slice Slice /system/getty. Mar 17 18:07:55.478547 systemd[1]: Created slice Slice /system/modprobe. Mar 17 18:07:55.478558 systemd[1]: Created slice Slice /system/serial-getty. Mar 17 18:07:55.478569 systemd[1]: Created slice Slice /system/system-cloudinit. Mar 17 18:07:55.478579 systemd[1]: Created slice Slice /system/systemd-fsck. Mar 17 18:07:55.478589 systemd[1]: Created slice User and Session Slice. Mar 17 18:07:55.478598 systemd[1]: Started Dispatch Password Requests to Console Directory Watch. Mar 17 18:07:55.478608 systemd[1]: Started Forward Password Requests to Wall Directory Watch. Mar 17 18:07:55.478618 systemd[1]: Set up automount Boot partition Automount Point. Mar 17 18:07:55.478629 systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. Mar 17 18:07:55.478639 systemd[1]: Stopped target Switch Root. Mar 17 18:07:55.478649 systemd[1]: Stopped target Initrd File Systems. Mar 17 18:07:55.478659 systemd[1]: Stopped target Initrd Root File System. Mar 17 18:07:55.478669 systemd[1]: Reached target Remote Encrypted Volumes. Mar 17 18:07:55.478679 systemd[1]: Reached target Remote File Systems. Mar 17 18:07:55.478694 systemd[1]: Reached target Slice Units. Mar 17 18:07:55.478705 systemd[1]: Reached target Swaps. Mar 17 18:07:55.478715 systemd[1]: Reached target Verify torcx succeeded. Mar 17 18:07:55.478725 systemd[1]: Reached target Local Verity Protected Volumes. Mar 17 18:07:55.478736 systemd[1]: Listening on Process Core Dump Socket. Mar 17 18:07:55.478747 systemd[1]: Listening on initctl Compatibility Named Pipe. Mar 17 18:07:55.478757 systemd[1]: Listening on Network Service Netlink Socket. Mar 17 18:07:55.478767 systemd[1]: Listening on udev Control Socket. Mar 17 18:07:55.478778 systemd[1]: Listening on udev Kernel Socket. Mar 17 18:07:55.478787 systemd[1]: Mounting Huge Pages File System... Mar 17 18:07:55.478797 systemd[1]: Mounting POSIX Message Queue File System... Mar 17 18:07:55.478807 systemd[1]: Mounting External Media Directory... Mar 17 18:07:55.478820 systemd[1]: Mounting Kernel Debug File System... Mar 17 18:07:55.478830 systemd[1]: Mounting Kernel Trace File System... Mar 17 18:07:55.478839 systemd[1]: Mounting Temporary Directory /tmp... Mar 17 18:07:55.478850 systemd[1]: Starting Create missing system files... Mar 17 18:07:55.478860 systemd[1]: Starting Create List of Static Device Nodes... Mar 17 18:07:55.478870 systemd[1]: Starting Load Kernel Module configfs... Mar 17 18:07:55.478881 systemd[1]: Starting Load Kernel Module drm... Mar 17 18:07:55.478891 systemd[1]: Starting Load Kernel Module fuse... Mar 17 18:07:55.478902 systemd[1]: Condition check resulted in Create /etc/nsswitch.conf being skipped. Mar 17 18:07:55.478912 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 18:07:55.478923 systemd[1]: Stopped File System Check on Root Device. Mar 17 18:07:55.478933 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 18:07:55.478944 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 18:07:55.478954 systemd[1]: Stopped Journal Service. Mar 17 18:07:55.478965 systemd[1]: Starting Journal Service... Mar 17 18:07:55.478975 kernel: fuse: init (API version 7.32) Mar 17 18:07:55.478985 systemd[1]: Condition check resulted in Load Kernel Modules being skipped. Mar 17 18:07:55.478995 systemd[1]: Starting Remount Root and Kernel File Systems... Mar 17 18:07:55.479005 systemd[1]: Starting Apply Kernel Variables... Mar 17 18:07:55.479015 systemd[1]: Starting Coldplug All udev Devices... Mar 17 18:07:55.479025 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 18:07:55.479035 systemd[1]: Stopped verity-setup.service. Mar 17 18:07:55.479046 systemd[1]: Mounted Huge Pages File System. Mar 17 18:07:55.479056 systemd[1]: Mounted POSIX Message Queue File System. Mar 17 18:07:55.479066 systemd[1]: Mounted External Media Directory. Mar 17 18:07:55.479076 systemd[1]: Mounted Kernel Debug File System. Mar 17 18:07:55.479086 systemd[1]: Mounted Kernel Trace File System. Mar 17 18:07:55.479096 systemd[1]: Mounted Temporary Directory /tmp. Mar 17 18:07:55.479106 systemd[1]: Finished Create missing system files. Mar 17 18:07:55.479116 systemd[1]: Finished Create List of Static Device Nodes. Mar 17 18:07:55.479128 systemd-journald[797]: Journal started Mar 17 18:07:55.479167 systemd-journald[797]: Runtime Journal (/run/log/journal/705e0bdd38cf417898167a5252cfd3fb) is 6.0M, max 48.5M, 42.4M free. Mar 17 18:07:53.162000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:07:53.202000 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 Mar 17 18:07:53.202000 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 Mar 17 18:07:53.202000 audit: BPF prog-id=19 op=LOAD Mar 17 18:07:53.202000 audit: BPF prog-id=19 op=UNLOAD Mar 17 18:07:53.202000 audit: BPF prog-id=20 op=LOAD Mar 17 18:07:53.202000 audit: BPF prog-id=20 op=UNLOAD Mar 17 18:07:53.252000 audit[746]: AVC avc: denied { associate } for pid=746 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 Mar 17 18:07:55.322000 audit: BPF prog-id=21 op=LOAD Mar 17 18:07:55.323000 audit: BPF prog-id=22 op=LOAD Mar 17 18:07:55.323000 audit: BPF prog-id=23 op=LOAD Mar 17 18:07:55.323000 audit: BPF prog-id=14 op=UNLOAD Mar 17 18:07:55.323000 audit: BPF prog-id=15 op=UNLOAD Mar 17 18:07:55.330000 audit: BPF prog-id=24 op=LOAD Mar 17 18:07:55.330000 audit: BPF prog-id=21 op=UNLOAD Mar 17 18:07:55.331000 audit: BPF prog-id=25 op=LOAD Mar 17 18:07:55.331000 audit: BPF prog-id=26 op=LOAD Mar 17 18:07:55.331000 audit: BPF prog-id=22 op=UNLOAD Mar 17 18:07:55.331000 audit: BPF prog-id=23 op=UNLOAD Mar 17 18:07:55.332000 audit: BPF prog-id=27 op=LOAD Mar 17 18:07:55.332000 audit: BPF prog-id=24 op=UNLOAD Mar 17 18:07:55.333000 audit: BPF prog-id=28 op=LOAD Mar 17 18:07:55.333000 audit: BPF prog-id=29 op=LOAD Mar 17 18:07:55.333000 audit: BPF prog-id=25 op=UNLOAD Mar 17 18:07:55.333000 audit: BPF prog-id=26 op=UNLOAD Mar 17 18:07:55.347000 audit: BPF prog-id=27 op=UNLOAD Mar 17 18:07:55.448000 audit: BPF prog-id=30 op=LOAD Mar 17 18:07:55.448000 audit: BPF prog-id=31 op=LOAD Mar 17 18:07:55.448000 audit: BPF prog-id=32 op=LOAD Mar 17 18:07:55.448000 audit: BPF prog-id=28 op=UNLOAD Mar 17 18:07:55.448000 audit: BPF prog-id=29 op=UNLOAD Mar 17 18:07:55.475000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Mar 17 18:07:55.317850 systemd[1]: Queued start job for default target Multi-User System. Mar 17 18:07:53.249818 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" 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]" Mar 17 18:07:55.338672 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 18:07:53.250500 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:07:53.250531 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:07:53.250822 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Mar 17 18:07:53.250835 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="skipped missing lower profile" missing profile=oem Mar 17 18:07:53.250871 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Mar 17 18:07:53.250884 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Mar 17 18:07:53.251091 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Mar 17 18:07:53.251127 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:07:53.251143 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:07:53.251721 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" 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 Mar 17 18:07:53.251753 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" 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 Mar 17 18:07:53.251774 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" 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 Mar 17 18:07:53.251790 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Mar 17 18:07:53.251808 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" 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 Mar 17 18:07:53.251822 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:53Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Mar 17 18:07:54.981329 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:07:54.981630 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" 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 Mar 17 18:07:54.981735 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" 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 Mar 17 18:07:54.981855 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" 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 Mar 17 18:07:54.981911 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Mar 17 18:07:54.981967 /usr/lib/systemd/system-generators/torcx-generator[746]: time="2025-03-17T18:07:54Z" 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 Mar 17 18:07:55.500708 systemd[1]: Started Journal Service. Mar 17 18:07:55.501311 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 18:07:55.501523 systemd[1]: Finished Load Kernel Module configfs. Mar 17 18:07:55.503923 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:07:55.504325 systemd[1]: Finished Load Kernel Module drm. Mar 17 18:07:55.505272 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 18:07:55.505466 systemd[1]: Finished Load Kernel Module fuse. Mar 17 18:07:55.506481 systemd[1]: Finished Remount Root and Kernel File Systems. Mar 17 18:07:55.507830 systemd[1]: Finished Apply Kernel Variables. Mar 17 18:07:55.510857 systemd[1]: Mounting FUSE Control File System... Mar 17 18:07:55.512551 systemd[1]: Mounting Kernel Configuration File System... Mar 17 18:07:55.513323 systemd[1]: Condition check resulted in Remount Root File System being skipped. Mar 17 18:07:55.515423 systemd[1]: Starting Rebuild Hardware Database... Mar 17 18:07:55.517407 systemd[1]: Starting Flush Journal to Persistent Storage... Mar 17 18:07:55.518288 systemd[1]: Condition check resulted in Platform Persistent Storage Archival being skipped. Mar 17 18:07:55.520124 systemd[1]: Starting Load/Save Random Seed... Mar 17 18:07:55.521625 systemd-journald[797]: Time spent on flushing to /var/log/journal/705e0bdd38cf417898167a5252cfd3fb is 10.898ms for 779 entries. Mar 17 18:07:55.521625 systemd-journald[797]: System Journal (/var/log/journal/705e0bdd38cf417898167a5252cfd3fb) is 8.0M, max 203.0M, 195.0M free. Mar 17 18:07:55.522054 systemd[1]: Starting Create System Users... Mar 17 18:07:55.526623 systemd[1]: Mounted FUSE Control File System. Mar 17 18:07:55.527713 systemd[1]: Mounted Kernel Configuration File System. Mar 17 18:07:55.529400 systemd[1]: Finished Load/Save Random Seed. Mar 17 18:07:55.530327 systemd[1]: Reached target First Boot Complete. Mar 17 18:07:55.531734 systemd[1]: Finished Coldplug All udev Devices. Mar 17 18:07:55.533925 systemd[1]: Starting Wait for udev To Complete Device Initialization... Mar 17 18:07:55.540887 systemd-sysusers[810]: Creating group sgx with gid 999. Mar 17 18:07:55.541814 systemd-sysusers[810]: Creating group systemd-oom with gid 998. Mar 17 18:07:55.542269 systemd-sysusers[810]: Creating user systemd-oom (systemd Userspace OOM Killer) with uid 998 and gid 998. Mar 17 18:07:55.542864 systemd-sysusers[810]: Creating group systemd-timesync with gid 997. Mar 17 18:07:55.543283 systemd-sysusers[810]: Creating user systemd-timesync (systemd Time Synchronization) with uid 997 and gid 997. Mar 17 18:07:55.543848 systemd-sysusers[810]: Creating group systemd-coredump with gid 996. Mar 17 18:07:55.544273 systemd-sysusers[810]: Creating user systemd-coredump (systemd Core Dumper) with uid 996 and gid 996. Mar 17 18:07:55.580289 systemd[1]: Finished Flush Journal to Persistent Storage. Mar 17 18:07:55.591200 systemd[1]: Finished Create System Users. Mar 17 18:07:55.601435 systemd[1]: Starting Create Static Device Nodes in /dev... Mar 17 18:07:55.626421 systemd[1]: Finished Create Static Device Nodes in /dev. Mar 17 18:07:55.787410 systemd[1]: Finished Rebuild Hardware Database. Mar 17 18:07:55.803000 audit: BPF prog-id=33 op=LOAD Mar 17 18:07:55.803000 audit: BPF prog-id=34 op=LOAD Mar 17 18:07:55.803000 audit: BPF prog-id=35 op=LOAD Mar 17 18:07:55.803000 audit: BPF prog-id=17 op=UNLOAD Mar 17 18:07:55.803000 audit: BPF prog-id=18 op=UNLOAD Mar 17 18:07:55.804896 systemd[1]: Starting Rule-based Manager for Device Events and Files... Mar 17 18:07:55.839132 systemd[1]: Started Rule-based Manager for Device Events and Files. Mar 17 18:07:55.861000 audit: BPF prog-id=36 op=LOAD Mar 17 18:07:55.862806 systemd[1]: Starting Network Configuration... Mar 17 18:07:55.881876 systemd-udevd[819]: Using default interface naming scheme 'v249'. Mar 17 18:07:55.913124 systemd-networkd[835]: lo: Link UP Mar 17 18:07:55.913133 systemd-networkd[835]: lo: Gained carrier Mar 17 18:07:55.913626 systemd-networkd[835]: Enumeration completed Mar 17 18:07:55.913752 systemd[1]: Started Network Configuration. Mar 17 18:07:55.915552 systemd-networkd[835]: eth0: Link UP Mar 17 18:07:55.921794 udevadm[812]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 18:07:55.923396 systemd-networkd[835]: eth0: Gained carrier Mar 17 18:07:55.935489 systemd-networkd[835]: eth0: DHCPv4 address 10.0.0.2/16 via 10.0.0.1 Mar 17 18:07:55.937509 systemd[1]: Finished Wait for udev To Complete Device Initialization. Mar 17 18:07:55.947437 systemd[1]: Starting Activation of LVM2 logical volumes... Mar 17 18:07:55.970759 lvm[843]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:07:55.996718 systemd[1]: Finished Activation of LVM2 logical volumes. Mar 17 18:07:55.997713 systemd[1]: Reached target Local Encrypted Volumes. Mar 17 18:07:56.022629 systemd[1]: Starting Activation of LVM2 logical volumes... Mar 17 18:07:56.026859 lvm[844]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:07:56.054553 systemd[1]: Finished Activation of LVM2 logical volumes. Mar 17 18:07:56.055561 systemd[1]: Reached target Preparation for Local File Systems. Mar 17 18:07:56.056518 systemd[1]: Condition check resulted in Virtual Machine and Container Storage (Compatibility) being skipped. Mar 17 18:07:56.056546 systemd[1]: Reached target Containers. Mar 17 18:07:56.066928 systemd[1]: Starting File System Check on /dev/disk/by-label/OEM... Mar 17 18:07:56.085613 systemd[1]: Finished File System Check on /dev/disk/by-label/OEM. Mar 17 18:07:56.096905 systemd[1]: Mounting /usr/share/oem... Mar 17 18:07:56.104855 kernel: BTRFS info (device vda6): disk space caching is enabled Mar 17 18:07:56.104957 kernel: BTRFS info (device vda6): has skinny extents Mar 17 18:07:56.108792 systemd[1]: Mounted /usr/share/oem. Mar 17 18:07:56.109581 systemd[1]: Reached target Local File Systems. Mar 17 18:07:56.111714 systemd[1]: Starting Rebuild Dynamic Linker Cache... Mar 17 18:07:56.113266 systemd[1]: Condition check resulted in Set Up Additional Binary Formats being skipped. Mar 17 18:07:56.113317 systemd[1]: Condition check resulted in Store a System Token in an EFI Variable being skipped. Mar 17 18:07:56.114486 systemd[1]: Starting Commit a transient machine-id on disk... Mar 17 18:07:56.116666 systemd[1]: Starting Create Volatile Files and Directories... Mar 17 18:07:56.125044 systemd-tmpfiles[869]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Mar 17 18:07:56.126796 systemd-tmpfiles[869]: /usr/lib/tmpfiles.d/systemd.conf:33: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 18:07:56.134131 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 18:07:56.135013 systemd[1]: Finished Commit a transient machine-id on disk. Mar 17 18:07:56.162492 systemd-tmpfiles[869]: Detected autofs mount point /boot during canonicalization of /boot. Mar 17 18:07:56.162674 systemd-tmpfiles[869]: Skipping /boot Mar 17 18:07:56.168029 systemd-tmpfiles[869]: Detected autofs mount point /boot during canonicalization of /boot. Mar 17 18:07:56.168223 systemd-tmpfiles[869]: Skipping /boot Mar 17 18:07:56.202595 systemd[1]: Finished Create Volatile Files and Directories. Mar 17 18:07:56.212965 systemd[1]: Starting Load Security Auditing Rules... Mar 17 18:07:56.216201 systemd[1]: Starting Clean up broken links in /etc/ssl/certs... Mar 17 18:07:56.218967 systemd[1]: Starting Rebuild Journal Catalog... Mar 17 18:07:56.222000 audit: BPF prog-id=37 op=LOAD Mar 17 18:07:56.223328 systemd[1]: Starting Network Name Resolution... Mar 17 18:07:56.228000 audit: BPF prog-id=38 op=LOAD Mar 17 18:07:56.232076 systemd[1]: Starting Network Time Synchronization... Mar 17 18:07:56.238273 systemd[1]: Starting Record System Boot/Shutdown in UTMP... Mar 17 18:07:56.241867 systemd[1]: Finished Clean up broken links in /etc/ssl/certs. Mar 17 18:07:56.244198 systemd[1]: Condition check resulted in Update CA bundle at /etc/ssl/certs/ca-certificates.crt being skipped. Mar 17 18:07:56.248446 systemd[1]: Finished Rebuild Journal Catalog. Mar 17 18:07:56.259293 systemd[1]: Finished Record System Boot/Shutdown in UTMP. Mar 17 18:07:56.287000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Mar 17 18:07:56.288121 augenrules[890]: No rules Mar 17 18:07:56.289094 systemd[1]: Finished Load Security Auditing Rules. Mar 17 18:07:56.303911 systemd-resolved[875]: Positive Trust Anchors: Mar 17 18:07:56.304213 systemd-resolved[875]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:07:56.304293 systemd-resolved[875]: 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 Mar 17 18:07:56.305620 systemd-resolved[875]: Defaulting to hostname 'linux'. Mar 17 18:07:56.305946 systemd[1]: Started Network Time Synchronization. Mar 17 18:07:56.307041 systemd[1]: Reached target System Time Set. Mar 17 18:07:56.747661 systemd-timesyncd[880]: Initial synchronization to time server 10.0.0.1:123 (10.0.0.1). Mar 17 18:07:56.749034 systemd[1]: Started Network Name Resolution. Mar 17 18:07:56.749928 systemd[1]: Reached target Network. Mar 17 18:07:56.750605 systemd[1]: Reached target Host and Network Name Lookups. Mar 17 18:07:56.767023 systemd[1]: Finished Rebuild Dynamic Linker Cache. Mar 17 18:07:56.779491 systemd[1]: Starting Update is Completed... Mar 17 18:07:56.784596 systemd[1]: Finished Update is Completed. Mar 17 18:07:56.785393 systemd[1]: Reached target System Initialization. Mar 17 18:07:56.786224 systemd[1]: Started Watch for update engine configuration changes. Mar 17 18:07:56.787216 systemd[1]: Started Watch for a cloud-config at /var/lib/flatcar-install/user_data. Mar 17 18:07:56.788362 systemd[1]: Started Daily Log Rotation. Mar 17 18:07:56.789117 systemd[1]: Started Weekly check for MD array's redundancy information.. Mar 17 18:07:56.790084 systemd[1]: Started Daily Cleanup of Temporary Directories. Mar 17 18:07:56.790892 systemd[1]: Condition check resulted in Update Engine Stub Timer being skipped. Mar 17 18:07:56.790919 systemd[1]: Reached target Path Units. Mar 17 18:07:56.791801 systemd[1]: Reached target Timer Units. Mar 17 18:07:56.793116 systemd[1]: Listening on D-Bus System Message Bus Socket. Mar 17 18:07:56.795133 systemd[1]: Starting Docker Socket for the API... Mar 17 18:07:56.799050 systemd[1]: Listening on OpenSSH Server Socket. Mar 17 18:07:56.800750 systemd[1]: Listening on Docker Socket for the API. Mar 17 18:07:56.801613 systemd[1]: Reached target Socket Units. Mar 17 18:07:56.802247 systemd[1]: Reached target Basic System. Mar 17 18:07:56.802986 systemd[1]: Condition check resulted in Configure Addon /usr/share/oem being skipped. Mar 17 18:07:56.803018 systemd[1]: Condition check resulted in Run Addon /usr/share/oem being skipped. Mar 17 18:07:56.804112 systemd[1]: Started D-Bus System Message Bus. Mar 17 18:07:56.807697 systemd[1]: Starting Extend Filesystems... Mar 17 18:07:56.808470 systemd[1]: Condition check resulted in Modifies /etc/environment for CoreOS being skipped. Mar 17 18:07:56.809654 systemd[1]: Starting Generate /run/flatcar/motd... Mar 17 18:07:56.812011 systemd[1]: Starting Install an ssh key from /proc/cmdline... Mar 17 18:07:56.814314 systemd[1]: Starting Generate sshd host keys... Mar 17 18:07:56.816783 systemd[1]: Condition check resulted in Load cloud-config from /usr/share/oem/cloud-config.yml being skipped. Mar 17 18:07:56.816815 systemd[1]: Reached target Load system-provided cloud configs. Mar 17 18:07:56.827046 extend-filesystems[900]: Found vda Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda1 Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda2 Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda3 Mar 17 18:07:56.828036 extend-filesystems[900]: Found usr Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda4 Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda6 Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda7 Mar 17 18:07:56.828036 extend-filesystems[900]: Found vda9 Mar 17 18:07:56.828036 extend-filesystems[900]: Checking size of /dev/vda9 Mar 17 18:07:56.830103 systemd[1]: Starting User Login Management... Mar 17 18:07:56.832364 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 18:07:56.833119 systemd[1]: Starting Update Engine... Mar 17 18:07:56.834280 systemd[1]: Condition check resulted in Load cloud-config from url defined in /proc/cmdline being skipped. Mar 17 18:07:56.834321 systemd[1]: Reached target Load user-provided cloud configs. Mar 17 18:07:56.838929 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 18:07:56.839162 systemd[1]: Finished Generate /run/flatcar/motd. Mar 17 18:07:56.842835 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 18:07:56.843085 systemd[1]: Finished Install an ssh key from /proc/cmdline. Mar 17 18:07:56.845456 extend-filesystems[900]: Old size kept for /dev/vda9 Mar 17 18:07:56.856736 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 18:07:56.856985 systemd[1]: Finished Extend Filesystems. Mar 17 18:07:56.878693 systemd-logind[916]: Watching system buttons on /dev/input/event0 (Power Button) Mar 17 18:07:56.878859 systemd-logind[916]: New seat seat0. Mar 17 18:07:56.880135 systemd[1]: Started User Login Management. Mar 17 18:07:56.898730 update_engine[918]: I0317 18:07:56.898437 918 main.cc:89] Flatcar Update Engine starting Mar 17 18:07:56.902035 systemd[1]: Started Update Engine. Mar 17 18:07:56.902395 update_engine[918]: I0317 18:07:56.902364 918 update_check_scheduler.cc:74] Next update check in 8m3s Mar 17 18:07:56.919811 systemd[1]: Started Cluster reboot manager. Mar 17 18:07:56.970865 locksmithd[923]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 18:07:57.009060 systemd[1]: Created slice Slice /system/sshd. Mar 17 18:07:57.156638 sshd_keygen[914]: ssh-keygen: generating new host keys: RSA DSA ECDSA ED25519 Mar 17 18:07:57.174629 systemd[1]: Finished Generate sshd host keys. Mar 17 18:07:57.185365 systemd[1]: Starting Generate /run/issue... Mar 17 18:07:57.186939 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34246). Mar 17 18:07:57.189709 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 18:07:57.189923 systemd[1]: Finished Generate /run/issue. Mar 17 18:07:57.191948 systemd[1]: Starting Permit User Sessions... Mar 17 18:07:57.197654 systemd[1]: Finished Permit User Sessions. Mar 17 18:07:57.199512 systemd[1]: Started Getty on tty1. Mar 17 18:07:57.201223 systemd[1]: Started Serial Getty on ttyAMA0. Mar 17 18:07:57.202100 systemd[1]: Reached target Login Prompts. Mar 17 18:07:57.202957 systemd[1]: Reached target Multi-User System. Mar 17 18:07:57.205108 systemd[1]: Starting Record Runlevel Change in UTMP... Mar 17 18:07:57.210256 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 17 18:07:57.210567 systemd[1]: Finished Record Runlevel Change in UTMP. Mar 17 18:07:57.211758 systemd[1]: Startup finished in 716ms (kernel) + 2.550s (initrd) + 3.650s (userspace) = 6.917s. Mar 17 18:07:57.240702 sshd[938]: Accepted publickey for core from 10.0.0.1 port 34246 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.242669 sshd[938]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.257875 systemd[1]: Created slice User Slice of UID 500. Mar 17 18:07:57.258992 systemd[1]: Starting User Runtime Directory /run/user/500... Mar 17 18:07:57.262637 systemd-logind[916]: New session 1 of user core. Mar 17 18:07:57.265903 systemd[1]: Finished User Runtime Directory /run/user/500. Mar 17 18:07:57.267924 systemd[1]: Starting User Manager for UID 500... Mar 17 18:07:57.270416 systemd[947]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.321415 systemd[947]: Queued start job for default target Main User Target. Mar 17 18:07:57.321600 systemd[947]: Reached target Paths. Mar 17 18:07:57.321616 systemd[947]: Reached target Sockets. Mar 17 18:07:57.321627 systemd[947]: Reached target Timers. Mar 17 18:07:57.321638 systemd[947]: Reached target Basic System. Mar 17 18:07:57.321678 systemd[947]: Reached target Main User Target. Mar 17 18:07:57.321691 systemd[947]: Startup finished in 47ms. Mar 17 18:07:57.321900 systemd[1]: Started User Manager for UID 500. Mar 17 18:07:57.331544 systemd[1]: Started Session 1 of User core. Mar 17 18:07:57.392102 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34260). Mar 17 18:07:57.435818 sshd[956]: Accepted publickey for core from 10.0.0.1 port 34260 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.437290 sshd[956]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.440472 systemd-logind[916]: New session 2 of user core. Mar 17 18:07:57.450592 systemd[1]: Started Session 2 of User core. Mar 17 18:07:57.508943 sshd[956]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:57.528098 systemd[1]: sshd@1-10.0.0.2:22-10.0.0.1:34260.service: Deactivated successfully. Mar 17 18:07:57.528746 systemd[1]: session-2.scope: Deactivated successfully. Mar 17 18:07:57.529332 systemd-logind[916]: Session 2 logged out. Waiting for processes to exit. Mar 17 18:07:57.530436 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34264). Mar 17 18:07:57.531129 systemd-logind[916]: Removed session 2. Mar 17 18:07:57.558496 systemd-networkd[835]: eth0: Gained IPv6LL Mar 17 18:07:57.571532 sshd[962]: Accepted publickey for core from 10.0.0.1 port 34264 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.572778 sshd[962]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.575559 systemd-logind[916]: New session 3 of user core. Mar 17 18:07:57.588603 systemd[1]: Started Session 3 of User core. Mar 17 18:07:57.639258 sshd[962]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:57.654730 systemd[1]: sshd@2-10.0.0.2:22-10.0.0.1:34264.service: Deactivated successfully. Mar 17 18:07:57.655337 systemd[1]: session-3.scope: Deactivated successfully. Mar 17 18:07:57.655988 systemd-logind[916]: Session 3 logged out. Waiting for processes to exit. Mar 17 18:07:57.657105 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34278). Mar 17 18:07:57.657799 systemd-logind[916]: Removed session 3. Mar 17 18:07:57.698561 sshd[968]: Accepted publickey for core from 10.0.0.1 port 34278 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.699649 sshd[968]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.702526 systemd-logind[916]: New session 4 of user core. Mar 17 18:07:57.719563 systemd[1]: Started Session 4 of User core. Mar 17 18:07:57.770663 sshd[968]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:57.783045 systemd[1]: sshd@3-10.0.0.2:22-10.0.0.1:34278.service: Deactivated successfully. Mar 17 18:07:57.783635 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 18:07:57.784241 systemd-logind[916]: Session 4 logged out. Waiting for processes to exit. Mar 17 18:07:57.785198 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34292). Mar 17 18:07:57.785866 systemd-logind[916]: Removed session 4. Mar 17 18:07:57.825906 sshd[974]: Accepted publickey for core from 10.0.0.1 port 34292 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.827179 sshd[974]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.829706 systemd-logind[916]: New session 5 of user core. Mar 17 18:07:57.840529 systemd[1]: Started Session 5 of User core. Mar 17 18:07:57.904692 sudo[977]: core : PWD=/home/core ; USER=root ; COMMAND=/sbin/setenforce 1 Mar 17 18:07:57.904908 sudo[977]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:57.915871 sudo[977]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:57.919556 sshd[974]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:57.919864 dbus-daemon[899]: [system] Reloaded configuration Mar 17 18:07:57.934982 systemd[1]: sshd@4-10.0.0.2:22-10.0.0.1:34292.service: Deactivated successfully. Mar 17 18:07:57.935638 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 18:07:57.936315 systemd-logind[916]: Session 5 logged out. Waiting for processes to exit. Mar 17 18:07:57.937417 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34294). Mar 17 18:07:57.938085 systemd-logind[916]: Removed session 5. Mar 17 18:07:57.978691 sshd[981]: Accepted publickey for core from 10.0.0.1 port 34294 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:57.980128 sshd[981]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:57.982884 systemd-logind[916]: New session 6 of user core. Mar 17 18:07:57.991585 systemd[1]: Started Session 6 of User core. Mar 17 18:07:58.042712 sudo[985]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Mar 17 18:07:58.042917 sudo[985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:58.045338 sudo[985]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:58.048869 sudo[984]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemctl restart audit-rules Mar 17 18:07:58.049069 sudo[984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:58.064250 systemd[1]: Stopping Load Security Auditing Rules... Mar 17 18:07:58.064000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Mar 17 18:07:58.065667 auditctl[988]: No rules Mar 17 18:07:58.066033 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 18:07:58.066263 systemd[1]: Stopped Load Security Auditing Rules. Mar 17 18:07:58.067561 systemd[1]: Starting Load Security Auditing Rules... Mar 17 18:07:58.082224 augenrules[1005]: No rules Mar 17 18:07:58.082931 systemd[1]: Finished Load Security Auditing Rules. Mar 17 18:07:58.083764 sudo[984]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:58.084918 sshd[981]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:58.102429 systemd[1]: sshd@5-10.0.0.2:22-10.0.0.1:34294.service: Deactivated successfully. Mar 17 18:07:58.102975 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 18:07:58.103603 systemd-logind[916]: Session 6 logged out. Waiting for processes to exit. Mar 17 18:07:58.104643 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34310). Mar 17 18:07:58.105199 systemd-logind[916]: Removed session 6. Mar 17 18:07:58.145009 sshd[1012]: Accepted publickey for core from 10.0.0.1 port 34310 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:58.146053 sshd[1012]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:58.148498 systemd-logind[916]: New session 7 of user core. Mar 17 18:07:58.167523 systemd[1]: Started Session 7 of User core. Mar 17 18:07:58.217700 sudo[1015]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p . Mar 17 18:07:58.217908 sudo[1015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:58.220839 sudo[1015]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:58.221963 sshd[1012]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:58.231014 systemd[1]: sshd@6-10.0.0.2:22-10.0.0.1:34310.service: Deactivated successfully. Mar 17 18:07:58.231601 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 18:07:58.232176 systemd-logind[916]: Session 7 logged out. Waiting for processes to exit. Mar 17 18:07:58.233203 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34324). Mar 17 18:07:58.233806 systemd-logind[916]: Removed session 7. Mar 17 18:07:58.273777 sshd[1019]: Accepted publickey for core from 10.0.0.1 port 34324 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:58.275039 sshd[1019]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:58.277478 systemd-logind[916]: New session 8 of user core. Mar 17 18:07:58.285563 systemd[1]: Started Session 8 of User core. Mar 17 18:07:58.336527 sudo[1022]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin kolet Mar 17 18:07:58.336726 sudo[1022]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:58.475601 sudo[1022]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:58.476968 sshd[1019]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:58.494111 systemd[1]: sshd@7-10.0.0.2:22-10.0.0.1:34324.service: Deactivated successfully. Mar 17 18:07:58.494688 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 18:07:58.495304 systemd-logind[916]: Session 8 logged out. Waiting for processes to exit. Mar 17 18:07:58.496411 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34336). Mar 17 18:07:58.497075 systemd-logind[916]: Removed session 8. Mar 17 18:07:58.537384 sshd[1026]: Accepted publickey for core from 10.0.0.1 port 34336 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:58.538539 sshd[1026]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:58.541367 systemd-logind[916]: New session 9 of user core. Mar 17 18:07:58.556537 systemd[1]: Started Session 9 of User core. Mar 17 18:07:58.606803 sudo[1029]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/mkdir -p /updates Mar 17 18:07:58.607304 sudo[1029]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:58.609912 sudo[1029]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:58.611263 sshd[1026]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:58.624221 systemd[1]: sshd@8-10.0.0.2:22-10.0.0.1:34336.service: Deactivated successfully. Mar 17 18:07:58.624808 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 18:07:58.625470 systemd-logind[916]: Session 9 logged out. Waiting for processes to exit. Mar 17 18:07:58.626876 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34350). Mar 17 18:07:58.627515 systemd-logind[916]: Removed session 9. Mar 17 18:07:58.669303 sshd[1033]: Accepted publickey for core from 10.0.0.1 port 34350 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:58.670544 sshd[1033]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:58.674076 systemd-logind[916]: New session 10 of user core. Mar 17 18:07:58.687583 systemd[1]: Started Session 10 of User core. Mar 17 18:07:58.739625 sudo[1036]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/install -m 0755 /dev/stdin /updates/update.gz Mar 17 18:07:58.739847 sudo[1036]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:59.400085 sudo[1036]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:59.401271 sshd[1033]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:59.419337 systemd[1]: sshd@9-10.0.0.2:22-10.0.0.1:34350.service: Deactivated successfully. Mar 17 18:07:59.419932 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 18:07:59.420620 systemd-logind[916]: Session 10 logged out. Waiting for processes to exit. Mar 17 18:07:59.421564 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:34362). Mar 17 18:07:59.422192 systemd-logind[916]: Removed session 10. Mar 17 18:07:59.463734 sshd[1040]: Accepted publickey for core from 10.0.0.1 port 34362 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:07:59.464678 sshd[1040]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:07:59.467309 systemd-logind[916]: New session 11 of user core. Mar 17 18:07:59.476507 systemd[1]: Started Session 11 of User core. Mar 17 18:07:59.527565 sudo[1043]: core : PWD=/home/core ; USER=root ; COMMAND=/bin/systemd-run --quiet ./kolet run cl.update.payload Omaha Mar 17 18:07:59.528024 sudo[1043]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:07:59.547455 systemd[1]: Started /home/core/./kolet run cl.update.payload Omaha. Mar 17 18:07:59.548436 sudo[1043]: pam_unix(sudo:session): session closed for user root Mar 17 18:07:59.549578 sshd[1040]: pam_unix(sshd:session): session closed for user core Mar 17 18:07:59.553009 systemd[1]: sshd@10-10.0.0.2:22-10.0.0.1:34362.service: Deactivated successfully. Mar 17 18:07:59.553725 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 18:07:59.554327 systemd-logind[916]: Session 11 logged out. Waiting for processes to exit. Mar 17 18:07:59.555035 systemd-logind[916]: Removed session 11. Mar 17 18:08:04.558839 systemd[1]: Started OpenSSH per-connection server daemon (10.0.0.1:58926). Mar 17 18:08:04.599736 sshd[1055]: Accepted publickey for core from 10.0.0.1 port 58926 ssh2: RSA SHA256:po7eHhAJ4DuZVB0QUQF294SGsZ7Clqjrp5aH2ugRpB4 Mar 17 18:08:04.600915 sshd[1055]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:08:04.603114 kernel: kauditd_printk_skb: 29 callbacks suppressed Mar 17 18:08:04.603196 kernel: audit: type=1006 audit(1742234884.599:74): pid=1055 uid=0 subj=system_u:system_r:kernel_t:s0 old-auid=4294967295 auid=500 tty=(none) old-ses=4294967295 ses=12 res=1 Mar 17 18:08:04.605271 systemd-logind[916]: New session 12 of user core. Mar 17 18:08:04.619585 systemd[1]: Started Session 12 of User core. Mar 17 18:08:04.678207 sshd[1055]: pam_unix(sshd:session): session closed for user core Mar 17 18:08:04.680256 systemd[1]: sshd@11-10.0.0.2:22-10.0.0.1:58926.service: Deactivated successfully. Mar 17 18:08:04.680917 systemd[1]: session-12.scope: Deactivated successfully. Mar 17 18:08:04.681490 systemd-logind[916]: Session 12 logged out. Waiting for processes to exit. Mar 17 18:08:04.682052 systemd-logind[916]: Removed session 12. Mar 17 18:08:42.300513 update_engine[918]: I0317 18:08:42.300451 918 update_attempter.cc:505] Updating boot flags...