Oct 9 01:29:08.926393 kernel: Linux version 6.6.54-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 13.3.1_p20240614 p17) 13.3.1 20240614, GNU ld (Gentoo 2.42 p3) 2.42.0) #1 SMP PREEMPT_DYNAMIC Tue Oct 8 23:33:43 -00 2024 Oct 9 01:29:08.926415 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:29:08.926427 kernel: BIOS-provided physical RAM map: Oct 9 01:29:08.926434 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable Oct 9 01:29:08.926441 kernel: BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved Oct 9 01:29:08.926448 kernel: BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved Oct 9 01:29:08.926456 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000007ffdcfff] usable Oct 9 01:29:08.926464 kernel: BIOS-e820: [mem 0x000000007ffdd000-0x000000007fffffff] reserved Oct 9 01:29:08.926471 kernel: BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved Oct 9 01:29:08.926480 kernel: BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved Oct 9 01:29:08.926487 kernel: NX (Execute Disable) protection: active Oct 9 01:29:08.926494 kernel: APIC: Static calls initialized Oct 9 01:29:08.926501 kernel: SMBIOS 2.8 present. Oct 9 01:29:08.926509 kernel: DMI: OpenStack Foundation OpenStack Nova, BIOS 1.15.0-1 04/01/2014 Oct 9 01:29:08.926518 kernel: Hypervisor detected: KVM Oct 9 01:29:08.926527 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Oct 9 01:29:08.926535 kernel: kvm-clock: using sched offset of 4466538580 cycles Oct 9 01:29:08.926543 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Oct 9 01:29:08.926551 kernel: tsc: Detected 1996.249 MHz processor Oct 9 01:29:08.926559 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Oct 9 01:29:08.926567 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Oct 9 01:29:08.926575 kernel: last_pfn = 0x7ffdd max_arch_pfn = 0x400000000 Oct 9 01:29:08.926583 kernel: MTRR map: 4 entries (3 fixed + 1 variable; max 19), built from 8 variable MTRRs Oct 9 01:29:08.926591 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Oct 9 01:29:08.926601 kernel: ACPI: Early table checksum verification disabled Oct 9 01:29:08.926608 kernel: ACPI: RSDP 0x00000000000F5930 000014 (v00 BOCHS ) Oct 9 01:29:08.926616 kernel: ACPI: RSDT 0x000000007FFE1848 000030 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:29:08.926624 kernel: ACPI: FACP 0x000000007FFE172C 000074 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:29:08.926632 kernel: ACPI: DSDT 0x000000007FFE0040 0016EC (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:29:08.926640 kernel: ACPI: FACS 0x000000007FFE0000 000040 Oct 9 01:29:08.926647 kernel: ACPI: APIC 0x000000007FFE17A0 000080 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:29:08.926655 kernel: ACPI: WAET 0x000000007FFE1820 000028 (v01 BOCHS BXPC 00000001 BXPC 00000001) Oct 9 01:29:08.926663 kernel: ACPI: Reserving FACP table memory at [mem 0x7ffe172c-0x7ffe179f] Oct 9 01:29:08.926690 kernel: ACPI: Reserving DSDT table memory at [mem 0x7ffe0040-0x7ffe172b] Oct 9 01:29:08.926698 kernel: ACPI: Reserving FACS table memory at [mem 0x7ffe0000-0x7ffe003f] Oct 9 01:29:08.926706 kernel: ACPI: Reserving APIC table memory at [mem 0x7ffe17a0-0x7ffe181f] Oct 9 01:29:08.926714 kernel: ACPI: Reserving WAET table memory at [mem 0x7ffe1820-0x7ffe1847] Oct 9 01:29:08.926721 kernel: No NUMA configuration found Oct 9 01:29:08.926729 kernel: Faking a node at [mem 0x0000000000000000-0x000000007ffdcfff] Oct 9 01:29:08.926737 kernel: NODE_DATA(0) allocated [mem 0x7ffd7000-0x7ffdcfff] Oct 9 01:29:08.926748 kernel: Zone ranges: Oct 9 01:29:08.926758 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Oct 9 01:29:08.926766 kernel: DMA32 [mem 0x0000000001000000-0x000000007ffdcfff] Oct 9 01:29:08.926774 kernel: Normal empty Oct 9 01:29:08.926782 kernel: Movable zone start for each node Oct 9 01:29:08.926790 kernel: Early memory node ranges Oct 9 01:29:08.926798 kernel: node 0: [mem 0x0000000000001000-0x000000000009efff] Oct 9 01:29:08.926809 kernel: node 0: [mem 0x0000000000100000-0x000000007ffdcfff] Oct 9 01:29:08.926817 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000007ffdcfff] Oct 9 01:29:08.926825 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Oct 9 01:29:08.926834 kernel: On node 0, zone DMA: 97 pages in unavailable ranges Oct 9 01:29:08.926842 kernel: On node 0, zone DMA32: 35 pages in unavailable ranges Oct 9 01:29:08.926850 kernel: ACPI: PM-Timer IO Port: 0x608 Oct 9 01:29:08.926858 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Oct 9 01:29:08.926866 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Oct 9 01:29:08.926874 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) Oct 9 01:29:08.926883 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Oct 9 01:29:08.926893 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Oct 9 01:29:08.926901 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Oct 9 01:29:08.926909 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Oct 9 01:29:08.926918 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Oct 9 01:29:08.926926 kernel: smpboot: Allowing 2 CPUs, 0 hotplug CPUs Oct 9 01:29:08.926934 kernel: kvm-guest: APIC: eoi() replaced with kvm_guest_apic_eoi_write() Oct 9 01:29:08.926942 kernel: [mem 0x80000000-0xfeffbfff] available for PCI devices Oct 9 01:29:08.926950 kernel: Booting paravirtualized kernel on KVM Oct 9 01:29:08.926959 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Oct 9 01:29:08.926969 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Oct 9 01:29:08.926978 kernel: percpu: Embedded 58 pages/cpu s196904 r8192 d32472 u1048576 Oct 9 01:29:08.926986 kernel: pcpu-alloc: s196904 r8192 d32472 u1048576 alloc=1*2097152 Oct 9 01:29:08.926994 kernel: pcpu-alloc: [0] 0 1 Oct 9 01:29:08.927002 kernel: kvm-guest: PV spinlocks disabled, no host support Oct 9 01:29:08.927012 kernel: Kernel command line: rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:29:08.927021 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Oct 9 01:29:08.927029 kernel: Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Oct 9 01:29:08.927039 kernel: Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Oct 9 01:29:08.927047 kernel: Fallback order for Node 0: 0 Oct 9 01:29:08.927055 kernel: Built 1 zonelists, mobility grouping on. Total pages: 515805 Oct 9 01:29:08.927063 kernel: Policy zone: DMA32 Oct 9 01:29:08.927071 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Oct 9 01:29:08.927080 kernel: Memory: 1971212K/2096620K available (12288K kernel code, 2305K rwdata, 22728K rodata, 42872K init, 2316K bss, 125148K reserved, 0K cma-reserved) Oct 9 01:29:08.927088 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Oct 9 01:29:08.927096 kernel: ftrace: allocating 37786 entries in 148 pages Oct 9 01:29:08.927107 kernel: ftrace: allocated 148 pages with 3 groups Oct 9 01:29:08.927115 kernel: Dynamic Preempt: voluntary Oct 9 01:29:08.927123 kernel: rcu: Preemptible hierarchical RCU implementation. Oct 9 01:29:08.927132 kernel: rcu: RCU event tracing is enabled. Oct 9 01:29:08.927140 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Oct 9 01:29:08.927149 kernel: Trampoline variant of Tasks RCU enabled. Oct 9 01:29:08.927157 kernel: Rude variant of Tasks RCU enabled. Oct 9 01:29:08.927165 kernel: Tracing variant of Tasks RCU enabled. Oct 9 01:29:08.927173 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Oct 9 01:29:08.927181 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Oct 9 01:29:08.927192 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Oct 9 01:29:08.927200 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Oct 9 01:29:08.927208 kernel: Console: colour VGA+ 80x25 Oct 9 01:29:08.927216 kernel: printk: console [tty0] enabled Oct 9 01:29:08.927224 kernel: printk: console [ttyS0] enabled Oct 9 01:29:08.927232 kernel: ACPI: Core revision 20230628 Oct 9 01:29:08.927240 kernel: APIC: Switch to symmetric I/O mode setup Oct 9 01:29:08.927248 kernel: x2apic enabled Oct 9 01:29:08.927257 kernel: APIC: Switched APIC routing to: physical x2apic Oct 9 01:29:08.927266 kernel: ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 Oct 9 01:29:08.927275 kernel: tsc: Marking TSC unstable due to TSCs unsynchronized Oct 9 01:29:08.927283 kernel: Calibrating delay loop (skipped) preset value.. 3992.49 BogoMIPS (lpj=1996249) Oct 9 01:29:08.927291 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 Oct 9 01:29:08.927299 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 Oct 9 01:29:08.927308 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Oct 9 01:29:08.927316 kernel: Spectre V2 : Mitigation: Retpolines Oct 9 01:29:08.927324 kernel: Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch Oct 9 01:29:08.927332 kernel: Spectre V2 : Spectre v2 / SpectreRSB : Filling RSB on VMEXIT Oct 9 01:29:08.927342 kernel: Speculative Store Bypass: Vulnerable Oct 9 01:29:08.927351 kernel: x86/fpu: x87 FPU will use FXSAVE Oct 9 01:29:08.927359 kernel: Freeing SMP alternatives memory: 32K Oct 9 01:29:08.927367 kernel: pid_max: default: 32768 minimum: 301 Oct 9 01:29:08.927375 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,integrity Oct 9 01:29:08.927383 kernel: landlock: Up and running. Oct 9 01:29:08.927391 kernel: SELinux: Initializing. Oct 9 01:29:08.927400 kernel: Mount-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:29:08.927415 kernel: Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes, linear) Oct 9 01:29:08.927424 kernel: smpboot: CPU0: AMD Intel Core i7 9xx (Nehalem Class Core i7) (family: 0x6, model: 0x1a, stepping: 0x3) Oct 9 01:29:08.927433 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:29:08.927441 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:29:08.927452 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1. Oct 9 01:29:08.927460 kernel: Performance Events: AMD PMU driver. Oct 9 01:29:08.927469 kernel: ... version: 0 Oct 9 01:29:08.927478 kernel: ... bit width: 48 Oct 9 01:29:08.927486 kernel: ... generic registers: 4 Oct 9 01:29:08.927496 kernel: ... value mask: 0000ffffffffffff Oct 9 01:29:08.927505 kernel: ... max period: 00007fffffffffff Oct 9 01:29:08.927514 kernel: ... fixed-purpose events: 0 Oct 9 01:29:08.927522 kernel: ... event mask: 000000000000000f Oct 9 01:29:08.927531 kernel: signal: max sigframe size: 1440 Oct 9 01:29:08.927539 kernel: rcu: Hierarchical SRCU implementation. Oct 9 01:29:08.927548 kernel: rcu: Max phase no-delay instances is 400. Oct 9 01:29:08.927556 kernel: smp: Bringing up secondary CPUs ... Oct 9 01:29:08.927576 kernel: smpboot: x86: Booting SMP configuration: Oct 9 01:29:08.927587 kernel: .... node #0, CPUs: #1 Oct 9 01:29:08.927596 kernel: smp: Brought up 1 node, 2 CPUs Oct 9 01:29:08.927604 kernel: smpboot: Max logical packages: 2 Oct 9 01:29:08.927613 kernel: smpboot: Total of 2 processors activated (7984.99 BogoMIPS) Oct 9 01:29:08.927622 kernel: devtmpfs: initialized Oct 9 01:29:08.927630 kernel: x86/mm: Memory block size: 128MB Oct 9 01:29:08.927639 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Oct 9 01:29:08.927648 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Oct 9 01:29:08.927658 kernel: pinctrl core: initialized pinctrl subsystem Oct 9 01:29:08.927682 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Oct 9 01:29:08.927692 kernel: audit: initializing netlink subsys (disabled) Oct 9 01:29:08.927702 kernel: audit: type=2000 audit(1728437347.982:1): state=initialized audit_enabled=0 res=1 Oct 9 01:29:08.927711 kernel: thermal_sys: Registered thermal governor 'step_wise' Oct 9 01:29:08.927720 kernel: thermal_sys: Registered thermal governor 'user_space' Oct 9 01:29:08.927729 kernel: cpuidle: using governor menu Oct 9 01:29:08.927738 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Oct 9 01:29:08.927748 kernel: dca service started, version 1.12.1 Oct 9 01:29:08.927757 kernel: PCI: Using configuration type 1 for base access Oct 9 01:29:08.927770 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Oct 9 01:29:08.927779 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Oct 9 01:29:08.927789 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Oct 9 01:29:08.927798 kernel: ACPI: Added _OSI(Module Device) Oct 9 01:29:08.927807 kernel: ACPI: Added _OSI(Processor Device) Oct 9 01:29:08.927816 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Oct 9 01:29:08.927825 kernel: ACPI: Added _OSI(Processor Aggregator Device) Oct 9 01:29:08.927835 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Oct 9 01:29:08.927844 kernel: ACPI: _OSC evaluation for CPUs failed, trying _PDC Oct 9 01:29:08.927855 kernel: ACPI: Interpreter enabled Oct 9 01:29:08.927864 kernel: ACPI: PM: (supports S0 S3 S5) Oct 9 01:29:08.927873 kernel: ACPI: Using IOAPIC for interrupt routing Oct 9 01:29:08.927883 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Oct 9 01:29:08.927892 kernel: PCI: Using E820 reservations for host bridge windows Oct 9 01:29:08.927901 kernel: ACPI: Enabled 2 GPEs in block 00 to 0F Oct 9 01:29:08.927911 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Oct 9 01:29:08.928053 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Oct 9 01:29:08.928163 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Oct 9 01:29:08.928260 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Oct 9 01:29:08.928274 kernel: acpiphp: Slot [3] registered Oct 9 01:29:08.928284 kernel: acpiphp: Slot [4] registered Oct 9 01:29:08.928293 kernel: acpiphp: Slot [5] registered Oct 9 01:29:08.928302 kernel: acpiphp: Slot [6] registered Oct 9 01:29:08.928312 kernel: acpiphp: Slot [7] registered Oct 9 01:29:08.928321 kernel: acpiphp: Slot [8] registered Oct 9 01:29:08.928330 kernel: acpiphp: Slot [9] registered Oct 9 01:29:08.928342 kernel: acpiphp: Slot [10] registered Oct 9 01:29:08.928352 kernel: acpiphp: Slot [11] registered Oct 9 01:29:08.928361 kernel: acpiphp: Slot [12] registered Oct 9 01:29:08.928370 kernel: acpiphp: Slot [13] registered Oct 9 01:29:08.928379 kernel: acpiphp: Slot [14] registered Oct 9 01:29:08.928388 kernel: acpiphp: Slot [15] registered Oct 9 01:29:08.928397 kernel: acpiphp: Slot [16] registered Oct 9 01:29:08.928406 kernel: acpiphp: Slot [17] registered Oct 9 01:29:08.928416 kernel: acpiphp: Slot [18] registered Oct 9 01:29:08.928427 kernel: acpiphp: Slot [19] registered Oct 9 01:29:08.928436 kernel: acpiphp: Slot [20] registered Oct 9 01:29:08.928445 kernel: acpiphp: Slot [21] registered Oct 9 01:29:08.928454 kernel: acpiphp: Slot [22] registered Oct 9 01:29:08.928463 kernel: acpiphp: Slot [23] registered Oct 9 01:29:08.928491 kernel: acpiphp: Slot [24] registered Oct 9 01:29:08.928501 kernel: acpiphp: Slot [25] registered Oct 9 01:29:08.928510 kernel: acpiphp: Slot [26] registered Oct 9 01:29:08.928519 kernel: acpiphp: Slot [27] registered Oct 9 01:29:08.928530 kernel: acpiphp: Slot [28] registered Oct 9 01:29:08.928539 kernel: acpiphp: Slot [29] registered Oct 9 01:29:08.928548 kernel: acpiphp: Slot [30] registered Oct 9 01:29:08.928558 kernel: acpiphp: Slot [31] registered Oct 9 01:29:08.928567 kernel: PCI host bridge to bus 0000:00 Oct 9 01:29:08.928715 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Oct 9 01:29:08.928810 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Oct 9 01:29:08.928896 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Oct 9 01:29:08.928988 kernel: pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff window] Oct 9 01:29:08.929072 kernel: pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window] Oct 9 01:29:08.929156 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Oct 9 01:29:08.929266 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 Oct 9 01:29:08.929372 kernel: pci 0000:00:01.0: [8086:7000] type 00 class 0x060100 Oct 9 01:29:08.929478 kernel: pci 0000:00:01.1: [8086:7010] type 00 class 0x010180 Oct 9 01:29:08.929591 kernel: pci 0000:00:01.1: reg 0x20: [io 0xc120-0xc12f] Oct 9 01:29:08.929729 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] Oct 9 01:29:08.929829 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] Oct 9 01:29:08.929924 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] Oct 9 01:29:08.930012 kernel: pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] Oct 9 01:29:08.930108 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 Oct 9 01:29:08.930197 kernel: pci 0000:00:01.3: quirk: [io 0x0600-0x063f] claimed by PIIX4 ACPI Oct 9 01:29:08.930293 kernel: pci 0000:00:01.3: quirk: [io 0x0700-0x070f] claimed by PIIX4 SMB Oct 9 01:29:08.930391 kernel: pci 0000:00:02.0: [1af4:1050] type 00 class 0x030000 Oct 9 01:29:08.930482 kernel: pci 0000:00:02.0: reg 0x10: [mem 0xfe000000-0xfe7fffff pref] Oct 9 01:29:08.930571 kernel: pci 0000:00:02.0: reg 0x18: [mem 0xfe800000-0xfe803fff 64bit pref] Oct 9 01:29:08.930695 kernel: pci 0000:00:02.0: reg 0x20: [mem 0xfeb90000-0xfeb90fff] Oct 9 01:29:08.930816 kernel: pci 0000:00:02.0: reg 0x30: [mem 0xfeb80000-0xfeb8ffff pref] Oct 9 01:29:08.930913 kernel: pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff] Oct 9 01:29:08.931023 kernel: pci 0000:00:03.0: [1af4:1000] type 00 class 0x020000 Oct 9 01:29:08.931121 kernel: pci 0000:00:03.0: reg 0x10: [io 0xc080-0xc0bf] Oct 9 01:29:08.931217 kernel: pci 0000:00:03.0: reg 0x14: [mem 0xfeb91000-0xfeb91fff] Oct 9 01:29:08.931314 kernel: pci 0000:00:03.0: reg 0x20: [mem 0xfe804000-0xfe807fff 64bit pref] Oct 9 01:29:08.931416 kernel: pci 0000:00:03.0: reg 0x30: [mem 0xfeb00000-0xfeb7ffff pref] Oct 9 01:29:08.931523 kernel: pci 0000:00:04.0: [1af4:1001] type 00 class 0x010000 Oct 9 01:29:08.931637 kernel: pci 0000:00:04.0: reg 0x10: [io 0xc000-0xc07f] Oct 9 01:29:08.931764 kernel: pci 0000:00:04.0: reg 0x14: [mem 0xfeb92000-0xfeb92fff] Oct 9 01:29:08.931862 kernel: pci 0000:00:04.0: reg 0x20: [mem 0xfe808000-0xfe80bfff 64bit pref] Oct 9 01:29:08.931966 kernel: pci 0000:00:05.0: [1af4:1002] type 00 class 0x00ff00 Oct 9 01:29:08.932064 kernel: pci 0000:00:05.0: reg 0x10: [io 0xc0c0-0xc0ff] Oct 9 01:29:08.932162 kernel: pci 0000:00:05.0: reg 0x20: [mem 0xfe80c000-0xfe80ffff 64bit pref] Oct 9 01:29:08.932267 kernel: pci 0000:00:06.0: [1af4:1005] type 00 class 0x00ff00 Oct 9 01:29:08.932366 kernel: pci 0000:00:06.0: reg 0x10: [io 0xc100-0xc11f] Oct 9 01:29:08.932472 kernel: pci 0000:00:06.0: reg 0x20: [mem 0xfe810000-0xfe813fff 64bit pref] Oct 9 01:29:08.932487 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Oct 9 01:29:08.932496 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Oct 9 01:29:08.932506 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Oct 9 01:29:08.932515 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Oct 9 01:29:08.932525 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Oct 9 01:29:08.932534 kernel: iommu: Default domain type: Translated Oct 9 01:29:08.932544 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Oct 9 01:29:08.932557 kernel: PCI: Using ACPI for IRQ routing Oct 9 01:29:08.932567 kernel: PCI: pci_cache_line_size set to 64 bytes Oct 9 01:29:08.932577 kernel: e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff] Oct 9 01:29:08.932587 kernel: e820: reserve RAM buffer [mem 0x7ffdd000-0x7fffffff] Oct 9 01:29:08.932716 kernel: pci 0000:00:02.0: vgaarb: setting as boot VGA device Oct 9 01:29:08.932821 kernel: pci 0000:00:02.0: vgaarb: bridge control possible Oct 9 01:29:08.932920 kernel: pci 0000:00:02.0: vgaarb: VGA device added: decodes=io+mem,owns=io+mem,locks=none Oct 9 01:29:08.932935 kernel: vgaarb: loaded Oct 9 01:29:08.932944 kernel: clocksource: Switched to clocksource kvm-clock Oct 9 01:29:08.932957 kernel: VFS: Disk quotas dquot_6.6.0 Oct 9 01:29:08.932965 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Oct 9 01:29:08.932974 kernel: pnp: PnP ACPI init Oct 9 01:29:08.933063 kernel: pnp 00:03: [dma 2] Oct 9 01:29:08.933077 kernel: pnp: PnP ACPI: found 5 devices Oct 9 01:29:08.933086 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Oct 9 01:29:08.933095 kernel: NET: Registered PF_INET protocol family Oct 9 01:29:08.933104 kernel: IP idents hash table entries: 32768 (order: 6, 262144 bytes, linear) Oct 9 01:29:08.933116 kernel: tcp_listen_portaddr_hash hash table entries: 1024 (order: 2, 16384 bytes, linear) Oct 9 01:29:08.933125 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Oct 9 01:29:08.933134 kernel: TCP established hash table entries: 16384 (order: 5, 131072 bytes, linear) Oct 9 01:29:08.933143 kernel: TCP bind hash table entries: 16384 (order: 7, 524288 bytes, linear) Oct 9 01:29:08.933151 kernel: TCP: Hash tables configured (established 16384 bind 16384) Oct 9 01:29:08.933160 kernel: UDP hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:29:08.933169 kernel: UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes, linear) Oct 9 01:29:08.933178 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Oct 9 01:29:08.933187 kernel: NET: Registered PF_XDP protocol family Oct 9 01:29:08.933269 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Oct 9 01:29:08.933350 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Oct 9 01:29:08.933430 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Oct 9 01:29:08.933511 kernel: pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff window] Oct 9 01:29:08.933590 kernel: pci_bus 0000:00: resource 8 [mem 0x100000000-0x17fffffff window] Oct 9 01:29:08.933731 kernel: pci 0000:00:01.0: PIIX3: Enabling Passive Release Oct 9 01:29:08.933843 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Oct 9 01:29:08.933858 kernel: PCI: CLS 0 bytes, default 64 Oct 9 01:29:08.933870 kernel: Initialise system trusted keyrings Oct 9 01:29:08.933880 kernel: workingset: timestamp_bits=39 max_order=19 bucket_order=0 Oct 9 01:29:08.933888 kernel: Key type asymmetric registered Oct 9 01:29:08.933897 kernel: Asymmetric key parser 'x509' registered Oct 9 01:29:08.933905 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 251) Oct 9 01:29:08.933914 kernel: io scheduler mq-deadline registered Oct 9 01:29:08.933923 kernel: io scheduler kyber registered Oct 9 01:29:08.933931 kernel: io scheduler bfq registered Oct 9 01:29:08.933940 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Oct 9 01:29:08.933951 kernel: ACPI: \_SB_.LNKB: Enabled at IRQ 10 Oct 9 01:29:08.933960 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Oct 9 01:29:08.933969 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 11 Oct 9 01:29:08.933978 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Oct 9 01:29:08.933987 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Oct 9 01:29:08.933995 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Oct 9 01:29:08.934004 kernel: random: crng init done Oct 9 01:29:08.934013 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Oct 9 01:29:08.934022 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Oct 9 01:29:08.934033 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Oct 9 01:29:08.934123 kernel: rtc_cmos 00:04: RTC can wake from S4 Oct 9 01:29:08.934138 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Oct 9 01:29:08.934216 kernel: rtc_cmos 00:04: registered as rtc0 Oct 9 01:29:08.934296 kernel: rtc_cmos 00:04: setting system clock to 2024-10-09T01:29:08 UTC (1728437348) Oct 9 01:29:08.934377 kernel: rtc_cmos 00:04: alarms up to one day, y3k, 242 bytes nvram Oct 9 01:29:08.934391 kernel: amd_pstate: the _CPC object is not present in SBIOS or ACPI disabled Oct 9 01:29:08.934399 kernel: NET: Registered PF_INET6 protocol family Oct 9 01:29:08.934411 kernel: Segment Routing with IPv6 Oct 9 01:29:08.934420 kernel: In-situ OAM (IOAM) with IPv6 Oct 9 01:29:08.934429 kernel: NET: Registered PF_PACKET protocol family Oct 9 01:29:08.934438 kernel: Key type dns_resolver registered Oct 9 01:29:08.934446 kernel: IPI shorthand broadcast: enabled Oct 9 01:29:08.934455 kernel: sched_clock: Marking stable (992008314, 128111481)->(1124495832, -4376037) Oct 9 01:29:08.934464 kernel: registered taskstats version 1 Oct 9 01:29:08.934473 kernel: Loading compiled-in X.509 certificates Oct 9 01:29:08.934482 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.6.54-flatcar: 03ae66f5ce294ce3ab718ee0d7c4a4a6e8c5aae6' Oct 9 01:29:08.934493 kernel: Key type .fscrypt registered Oct 9 01:29:08.934501 kernel: Key type fscrypt-provisioning registered Oct 9 01:29:08.934510 kernel: ima: No TPM chip found, activating TPM-bypass! Oct 9 01:29:08.934518 kernel: ima: Allocated hash algorithm: sha1 Oct 9 01:29:08.934527 kernel: ima: No architecture policies found Oct 9 01:29:08.934536 kernel: clk: Disabling unused clocks Oct 9 01:29:08.934544 kernel: Freeing unused kernel image (initmem) memory: 42872K Oct 9 01:29:08.934553 kernel: Write protecting the kernel read-only data: 36864k Oct 9 01:29:08.934562 kernel: Freeing unused kernel image (rodata/data gap) memory: 1848K Oct 9 01:29:08.934572 kernel: Run /init as init process Oct 9 01:29:08.934581 kernel: with arguments: Oct 9 01:29:08.934590 kernel: /init Oct 9 01:29:08.934598 kernel: with environment: Oct 9 01:29:08.934606 kernel: HOME=/ Oct 9 01:29:08.934615 kernel: TERM=linux Oct 9 01:29:08.934623 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Oct 9 01:29:08.934635 systemd[1]: systemd 255 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 9 01:29:08.934648 systemd[1]: Detected virtualization kvm. Oct 9 01:29:08.934658 systemd[1]: Detected architecture x86-64. Oct 9 01:29:08.934712 systemd[1]: Running in initrd. Oct 9 01:29:08.934722 systemd[1]: No hostname configured, using default hostname. Oct 9 01:29:08.934732 systemd[1]: Hostname set to . Oct 9 01:29:08.934742 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:29:08.934751 systemd[1]: Queued start job for default target initrd.target. Oct 9 01:29:08.934764 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:29:08.934774 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:29:08.934784 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Oct 9 01:29:08.934793 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:29:08.934803 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Oct 9 01:29:08.934812 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Oct 9 01:29:08.934823 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Oct 9 01:29:08.934835 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Oct 9 01:29:08.934844 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:29:08.934854 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:29:08.934863 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:29:08.934881 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:29:08.934893 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:29:08.934904 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:29:08.934913 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:29:08.934923 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:29:08.934933 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Oct 9 01:29:08.934943 systemd[1]: Listening on systemd-journald.socket - Journal Socket. Oct 9 01:29:08.934953 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:29:08.934963 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:29:08.934972 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:29:08.934982 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:29:08.934994 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Oct 9 01:29:08.935003 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:29:08.935013 systemd[1]: Finished network-cleanup.service - Network Cleanup. Oct 9 01:29:08.935023 systemd[1]: Starting systemd-fsck-usr.service... Oct 9 01:29:08.935032 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:29:08.935059 systemd-journald[182]: Collecting audit messages is disabled. Oct 9 01:29:08.935085 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:29:08.935096 systemd-journald[182]: Journal started Oct 9 01:29:08.935118 systemd-journald[182]: Runtime Journal (/run/log/journal/b3e46c96522940fbbd6871cf8f23294a) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:29:08.944715 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:08.947768 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:29:08.948412 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Oct 9 01:29:08.950034 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:29:08.951534 systemd[1]: Finished systemd-fsck-usr.service. Oct 9 01:29:08.959147 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:29:08.963819 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:29:08.977454 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:29:09.038535 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Oct 9 01:29:09.038561 kernel: Bridge firewalling registered Oct 9 01:29:08.985129 systemd-modules-load[184]: Inserted module 'overlay' Oct 9 01:29:08.987989 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:29:09.026216 systemd-modules-load[184]: Inserted module 'br_netfilter' Oct 9 01:29:09.050911 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:29:09.051724 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:09.054961 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:29:09.057166 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:29:09.062787 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:29:09.064707 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:29:09.075177 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:29:09.084371 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:29:09.086732 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:29:09.088791 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Oct 9 01:29:09.107297 dracut-cmdline[220]: dracut-dracut-053 Oct 9 01:29:09.111289 dracut-cmdline[220]: Using kernel command line parameters: rd.driver.pre=btrfs rootflags=rw mount.usrflags=ro 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,115200n8 console=tty0 flatcar.first_boot=detected flatcar.oem.id=openstack flatcar.autologin verity.usrhash=ecc53326196a1bacd9ba781ce772ef34cdd5fe5561cf830307501ec3d5ba168a Oct 9 01:29:09.111776 systemd-resolved[216]: Positive Trust Anchors: Oct 9 01:29:09.111784 systemd-resolved[216]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:29:09.111828 systemd-resolved[216]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 01:29:09.114442 systemd-resolved[216]: Defaulting to hostname 'linux'. Oct 9 01:29:09.115395 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:29:09.118831 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:29:09.186733 kernel: SCSI subsystem initialized Oct 9 01:29:09.198799 kernel: Loading iSCSI transport class v2.0-870. Oct 9 01:29:09.211183 kernel: iscsi: registered transport (tcp) Oct 9 01:29:09.233925 kernel: iscsi: registered transport (qla4xxx) Oct 9 01:29:09.233999 kernel: QLogic iSCSI HBA Driver Oct 9 01:29:09.288112 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Oct 9 01:29:09.301782 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Oct 9 01:29:09.352117 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Oct 9 01:29:09.352244 kernel: device-mapper: uevent: version 1.0.3 Oct 9 01:29:09.356279 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com Oct 9 01:29:09.403902 kernel: raid6: sse2x4 gen() 13042 MB/s Oct 9 01:29:09.420767 kernel: raid6: sse2x2 gen() 14381 MB/s Oct 9 01:29:09.438071 kernel: raid6: sse2x1 gen() 10126 MB/s Oct 9 01:29:09.438131 kernel: raid6: using algorithm sse2x2 gen() 14381 MB/s Oct 9 01:29:09.455993 kernel: raid6: .... xor() 9290 MB/s, rmw enabled Oct 9 01:29:09.456110 kernel: raid6: using ssse3x2 recovery algorithm Oct 9 01:29:09.479186 kernel: xor: measuring software checksum speed Oct 9 01:29:09.479302 kernel: prefetch64-sse : 17287 MB/sec Oct 9 01:29:09.479726 kernel: generic_sse : 15731 MB/sec Oct 9 01:29:09.481336 kernel: xor: using function: prefetch64-sse (17287 MB/sec) Oct 9 01:29:09.666739 kernel: Btrfs loaded, zoned=no, fsverity=no Oct 9 01:29:09.682815 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:29:09.691941 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:29:09.716473 systemd-udevd[402]: Using default interface naming scheme 'v255'. Oct 9 01:29:09.720790 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:29:09.731943 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Oct 9 01:29:09.750297 dracut-pre-trigger[416]: rd.md=0: removing MD RAID activation Oct 9 01:29:09.794925 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:29:09.804933 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:29:09.865554 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:29:09.870815 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Oct 9 01:29:09.891927 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Oct 9 01:29:09.893485 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:29:09.894797 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:29:09.897390 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:29:09.904811 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Oct 9 01:29:09.918893 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:29:09.951805 kernel: virtio_blk virtio2: 2/0/0 default/read/poll queues Oct 9 01:29:09.958686 kernel: virtio_blk virtio2: [vda] 41943040 512-byte logical blocks (21.5 GB/20.0 GiB) Oct 9 01:29:09.961179 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:29:09.961342 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:29:09.962965 systemd[1]: Stopping dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:29:09.963883 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:29:09.981027 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Oct 9 01:29:09.981050 kernel: GPT:17805311 != 41943039 Oct 9 01:29:09.981070 kernel: GPT:Alternate GPT header not at the end of the disk. Oct 9 01:29:09.981082 kernel: GPT:17805311 != 41943039 Oct 9 01:29:09.981093 kernel: GPT: Use GNU Parted to correct GPT errors. Oct 9 01:29:09.981104 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:29:09.964020 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:09.969134 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:09.985700 kernel: libata version 3.00 loaded. Oct 9 01:29:09.991096 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:09.996689 kernel: ata_piix 0000:00:01.1: version 2.13 Oct 9 01:29:10.015343 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/vda6 scanned by (udev-worker) (454) Oct 9 01:29:10.015397 kernel: scsi host0: ata_piix Oct 9 01:29:10.016373 kernel: scsi host1: ata_piix Oct 9 01:29:10.019046 kernel: ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc120 irq 14 Oct 9 01:29:10.019070 kernel: ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc128 irq 15 Oct 9 01:29:10.023703 kernel: BTRFS: device fsid 6ed52ce5-b2f8-4d16-8889-677a209bc377 devid 1 transid 36 /dev/vda3 scanned by (udev-worker) (460) Oct 9 01:29:10.043209 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT. Oct 9 01:29:10.075813 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:29:10.076761 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:10.083437 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM. Oct 9 01:29:10.088312 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132. Oct 9 01:29:10.088921 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A. Oct 9 01:29:10.096834 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Oct 9 01:29:10.099322 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Oct 9 01:29:10.107760 disk-uuid[502]: Primary Header is updated. Oct 9 01:29:10.107760 disk-uuid[502]: Secondary Entries is updated. Oct 9 01:29:10.107760 disk-uuid[502]: Secondary Header is updated. Oct 9 01:29:10.119706 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:29:10.123494 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:29:10.130173 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:29:11.142802 kernel: vda: vda1 vda2 vda3 vda4 vda6 vda7 vda9 Oct 9 01:29:11.144960 disk-uuid[503]: The operation has completed successfully. Oct 9 01:29:11.219038 systemd[1]: disk-uuid.service: Deactivated successfully. Oct 9 01:29:11.219308 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Oct 9 01:29:11.246793 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Oct 9 01:29:11.261956 sh[525]: Success Oct 9 01:29:11.276708 kernel: device-mapper: verity: sha256 using implementation "sha256-ssse3" Oct 9 01:29:11.334663 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Oct 9 01:29:11.336641 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Oct 9 01:29:11.337347 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Oct 9 01:29:11.366705 kernel: BTRFS info (device dm-0): first mount of filesystem 6ed52ce5-b2f8-4d16-8889-677a209bc377 Oct 9 01:29:11.366761 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:29:11.368228 kernel: BTRFS warning (device dm-0): 'nologreplay' is deprecated, use 'rescue=nologreplay' instead Oct 9 01:29:11.369863 kernel: BTRFS info (device dm-0): disabling log replay at mount time Oct 9 01:29:11.371825 kernel: BTRFS info (device dm-0): using free space tree Oct 9 01:29:11.383799 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Oct 9 01:29:11.384592 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Oct 9 01:29:11.390829 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Oct 9 01:29:11.392804 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Oct 9 01:29:11.407575 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:29:11.407603 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:29:11.407620 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:29:11.416745 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:29:11.433289 systemd[1]: mnt-oem.mount: Deactivated successfully. Oct 9 01:29:11.447060 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:29:11.450933 systemd[1]: Finished ignition-setup.service - Ignition (setup). Oct 9 01:29:11.458107 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Oct 9 01:29:11.502137 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:29:11.511862 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:29:11.533656 systemd-networkd[710]: lo: Link UP Oct 9 01:29:11.534387 systemd-networkd[710]: lo: Gained carrier Oct 9 01:29:11.535534 systemd-networkd[710]: Enumeration completed Oct 9 01:29:11.535768 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:29:11.536334 systemd-networkd[710]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:29:11.536338 systemd-networkd[710]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 01:29:11.537340 systemd-networkd[710]: eth0: Link UP Oct 9 01:29:11.537343 systemd-networkd[710]: eth0: Gained carrier Oct 9 01:29:11.537351 systemd-networkd[710]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:29:11.541165 systemd[1]: Reached target network.target - Network. Oct 9 01:29:11.552730 systemd-networkd[710]: eth0: DHCPv4 address 172.24.4.83/24, gateway 172.24.4.1 acquired from 172.24.4.1 Oct 9 01:29:11.611030 ignition[640]: Ignition 2.19.0 Oct 9 01:29:11.612113 ignition[640]: Stage: fetch-offline Oct 9 01:29:11.612219 ignition[640]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:11.613604 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:29:11.612232 ignition[640]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:11.612357 ignition[640]: parsed url from cmdline: "" Oct 9 01:29:11.612361 ignition[640]: no config URL provided Oct 9 01:29:11.612367 ignition[640]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:29:11.612375 ignition[640]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:29:11.612381 ignition[640]: failed to fetch config: resource requires networking Oct 9 01:29:11.612617 ignition[640]: Ignition finished successfully Oct 9 01:29:11.623139 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Oct 9 01:29:11.646108 ignition[718]: Ignition 2.19.0 Oct 9 01:29:11.646131 ignition[718]: Stage: fetch Oct 9 01:29:11.646427 ignition[718]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:11.646447 ignition[718]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:11.646591 ignition[718]: parsed url from cmdline: "" Oct 9 01:29:11.646598 ignition[718]: no config URL provided Oct 9 01:29:11.646607 ignition[718]: reading system config file "/usr/lib/ignition/user.ign" Oct 9 01:29:11.646624 ignition[718]: no config at "/usr/lib/ignition/user.ign" Oct 9 01:29:11.646814 ignition[718]: config drive ("/dev/disk/by-label/config-2") not found. Waiting... Oct 9 01:29:11.646830 ignition[718]: GET http://169.254.169.254/openstack/latest/user_data: attempt #1 Oct 9 01:29:11.646858 ignition[718]: config drive ("/dev/disk/by-label/CONFIG-2") not found. Waiting... Oct 9 01:29:11.925473 ignition[718]: GET result: OK Oct 9 01:29:11.925553 ignition[718]: parsing config with SHA512: 4763d18f9bea87159ee31e3c705e9b1097cc6b36e0ac486c70ae603d35031b783670a8fc8a05e1d2794de0f52a182641f28d35d24bb61f096c7b3f16f31aa592 Oct 9 01:29:11.932951 unknown[718]: fetched base config from "system" Oct 9 01:29:11.932973 unknown[718]: fetched base config from "system" Oct 9 01:29:11.933349 ignition[718]: fetch: fetch complete Oct 9 01:29:11.932985 unknown[718]: fetched user config from "openstack" Oct 9 01:29:11.933361 ignition[718]: fetch: fetch passed Oct 9 01:29:11.933942 systemd-resolved[216]: Detected conflict on linux IN A 172.24.4.83 Oct 9 01:29:11.933441 ignition[718]: Ignition finished successfully Oct 9 01:29:11.933966 systemd-resolved[216]: Hostname conflict, changing published hostname from 'linux' to 'linux8'. Oct 9 01:29:11.936660 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Oct 9 01:29:11.946976 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Oct 9 01:29:11.981616 ignition[724]: Ignition 2.19.0 Oct 9 01:29:11.981648 ignition[724]: Stage: kargs Oct 9 01:29:11.984417 ignition[724]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:11.984447 ignition[724]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:11.988638 ignition[724]: kargs: kargs passed Oct 9 01:29:11.991761 ignition[724]: Ignition finished successfully Oct 9 01:29:11.993645 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Oct 9 01:29:12.001890 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Oct 9 01:29:12.038102 ignition[731]: Ignition 2.19.0 Oct 9 01:29:12.038119 ignition[731]: Stage: disks Oct 9 01:29:12.038481 ignition[731]: no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:12.042176 systemd[1]: Finished ignition-disks.service - Ignition (disks). Oct 9 01:29:12.038505 ignition[731]: no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:12.044376 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Oct 9 01:29:12.040065 ignition[731]: disks: disks passed Oct 9 01:29:12.046200 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Oct 9 01:29:12.040155 ignition[731]: Ignition finished successfully Oct 9 01:29:12.048724 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:29:12.051356 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:29:12.054082 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:29:12.064003 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Oct 9 01:29:12.095322 systemd-fsck[739]: ROOT: clean, 14/1628000 files, 120691/1617920 blocks Oct 9 01:29:12.106352 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Oct 9 01:29:12.114798 systemd[1]: Mounting sysroot.mount - /sysroot... Oct 9 01:29:12.276725 kernel: EXT4-fs (vda9): mounted filesystem ba2945c1-be14-41c0-8c54-84d676c7a16b r/w with ordered data mode. Quota mode: none. Oct 9 01:29:12.277165 systemd[1]: Mounted sysroot.mount - /sysroot. Oct 9 01:29:12.278164 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Oct 9 01:29:12.286825 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:29:12.289960 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Oct 9 01:29:12.291519 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Oct 9 01:29:12.297269 systemd[1]: Starting flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent... Oct 9 01:29:12.300364 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Oct 9 01:29:12.314198 kernel: BTRFS: device label OEM devid 1 transid 13 /dev/vda6 scanned by mount (747) Oct 9 01:29:12.314240 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:29:12.314307 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:29:12.314336 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:29:12.300400 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:29:12.309920 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Oct 9 01:29:12.321817 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Oct 9 01:29:12.330715 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:29:12.337855 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:29:12.466108 initrd-setup-root[775]: cut: /sysroot/etc/passwd: No such file or directory Oct 9 01:29:12.474769 initrd-setup-root[782]: cut: /sysroot/etc/group: No such file or directory Oct 9 01:29:12.479907 initrd-setup-root[789]: cut: /sysroot/etc/shadow: No such file or directory Oct 9 01:29:12.484268 initrd-setup-root[796]: cut: /sysroot/etc/gshadow: No such file or directory Oct 9 01:29:12.576328 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Oct 9 01:29:12.580780 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Oct 9 01:29:12.583922 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Oct 9 01:29:12.591274 systemd[1]: sysroot-oem.mount: Deactivated successfully. Oct 9 01:29:12.593955 kernel: BTRFS info (device vda6): last unmount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:29:12.626265 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Oct 9 01:29:12.628527 ignition[863]: INFO : Ignition 2.19.0 Oct 9 01:29:12.628527 ignition[863]: INFO : Stage: mount Oct 9 01:29:12.628527 ignition[863]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:12.628527 ignition[863]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:12.632315 ignition[863]: INFO : mount: mount passed Oct 9 01:29:12.632315 ignition[863]: INFO : Ignition finished successfully Oct 9 01:29:12.629958 systemd[1]: Finished ignition-mount.service - Ignition (mount). Oct 9 01:29:13.423285 systemd-networkd[710]: eth0: Gained IPv6LL Oct 9 01:29:19.547075 coreos-metadata[749]: Oct 09 01:29:19.546 WARN failed to locate config-drive, using the metadata service API instead Oct 9 01:29:19.589983 coreos-metadata[749]: Oct 09 01:29:19.589 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Oct 9 01:29:19.608130 coreos-metadata[749]: Oct 09 01:29:19.608 INFO Fetch successful Oct 9 01:29:19.609836 coreos-metadata[749]: Oct 09 01:29:19.609 INFO wrote hostname ci-4116-0-0-5-9c99834064.novalocal to /sysroot/etc/hostname Oct 9 01:29:19.612404 systemd[1]: flatcar-openstack-hostname.service: Deactivated successfully. Oct 9 01:29:19.612636 systemd[1]: Finished flatcar-openstack-hostname.service - Flatcar OpenStack Metadata Hostname Agent. Oct 9 01:29:19.631057 systemd[1]: Starting ignition-files.service - Ignition (files)... Oct 9 01:29:19.670934 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Oct 9 01:29:19.686718 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/vda6 scanned by mount (881) Oct 9 01:29:19.686778 kernel: BTRFS info (device vda6): first mount of filesystem 7abc21fd-6b75-4be0-8205-dc564a91a608 Oct 9 01:29:19.691776 kernel: BTRFS info (device vda6): using crc32c (crc32c-intel) checksum algorithm Oct 9 01:29:19.696208 kernel: BTRFS info (device vda6): using free space tree Oct 9 01:29:19.703718 kernel: BTRFS info (device vda6): auto enabling async discard Oct 9 01:29:19.706942 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Oct 9 01:29:19.740149 ignition[899]: INFO : Ignition 2.19.0 Oct 9 01:29:19.740149 ignition[899]: INFO : Stage: files Oct 9 01:29:19.741912 ignition[899]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:19.741912 ignition[899]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:19.743890 ignition[899]: DEBUG : files: compiled without relabeling support, skipping Oct 9 01:29:19.743890 ignition[899]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Oct 9 01:29:19.743890 ignition[899]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Oct 9 01:29:19.749772 ignition[899]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Oct 9 01:29:19.750814 ignition[899]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Oct 9 01:29:19.750814 ignition[899]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Oct 9 01:29:19.750609 unknown[899]: wrote ssh authorized keys file for user: core Oct 9 01:29:19.755356 ignition[899]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:29:19.757518 ignition[899]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Oct 9 01:29:19.757518 ignition[899]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:29:19.757518 ignition[899]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Oct 9 01:29:19.757518 ignition[899]: INFO : files: files passed Oct 9 01:29:19.757518 ignition[899]: INFO : Ignition finished successfully Oct 9 01:29:19.757572 systemd[1]: Finished ignition-files.service - Ignition (files). Oct 9 01:29:19.765887 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Oct 9 01:29:19.774204 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Oct 9 01:29:19.778961 systemd[1]: ignition-quench.service: Deactivated successfully. Oct 9 01:29:19.779084 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Oct 9 01:29:19.785711 initrd-setup-root-after-ignition[927]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:29:19.785711 initrd-setup-root-after-ignition[927]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:29:19.788293 initrd-setup-root-after-ignition[931]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Oct 9 01:29:19.789921 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:29:19.791881 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Oct 9 01:29:19.802859 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Oct 9 01:29:19.830664 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Oct 9 01:29:19.830852 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Oct 9 01:29:19.832586 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Oct 9 01:29:19.833775 systemd[1]: Reached target initrd.target - Initrd Default Target. Oct 9 01:29:19.835139 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Oct 9 01:29:19.842872 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Oct 9 01:29:19.856129 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:29:19.861886 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Oct 9 01:29:19.882459 systemd[1]: initrd-cleanup.service: Deactivated successfully. Oct 9 01:29:19.882905 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Oct 9 01:29:19.885876 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:29:19.886570 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:29:19.888054 systemd[1]: Stopped target timers.target - Timer Units. Oct 9 01:29:19.889398 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Oct 9 01:29:19.889480 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Oct 9 01:29:19.891019 systemd[1]: Stopped target initrd.target - Initrd Default Target. Oct 9 01:29:19.891842 systemd[1]: Stopped target basic.target - Basic System. Oct 9 01:29:19.893138 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Oct 9 01:29:19.894371 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Oct 9 01:29:19.895580 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Oct 9 01:29:19.896941 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Oct 9 01:29:19.898270 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Oct 9 01:29:19.899704 systemd[1]: Stopped target sysinit.target - System Initialization. Oct 9 01:29:19.900981 systemd[1]: Stopped target local-fs.target - Local File Systems. Oct 9 01:29:19.902325 systemd[1]: Stopped target swap.target - Swaps. Oct 9 01:29:19.903598 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Oct 9 01:29:19.903694 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Oct 9 01:29:19.905182 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:29:19.906025 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:29:19.911456 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Oct 9 01:29:19.913724 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:29:19.914385 systemd[1]: dracut-initqueue.service: Deactivated successfully. Oct 9 01:29:19.914464 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Oct 9 01:29:19.916366 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Oct 9 01:29:19.916433 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Oct 9 01:29:19.917229 systemd[1]: ignition-files.service: Deactivated successfully. Oct 9 01:29:19.917296 systemd[1]: Stopped ignition-files.service - Ignition (files). Oct 9 01:29:19.928803 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Oct 9 01:29:19.931826 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Oct 9 01:29:19.933371 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Oct 9 01:29:19.934443 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:29:19.935419 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Oct 9 01:29:19.935482 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Oct 9 01:29:19.951948 ignition[952]: INFO : Ignition 2.19.0 Oct 9 01:29:19.952949 ignition[952]: INFO : Stage: umount Oct 9 01:29:19.952949 ignition[952]: INFO : no configs at "/usr/lib/ignition/base.d" Oct 9 01:29:19.952949 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/openstack" Oct 9 01:29:19.959180 ignition[952]: INFO : umount: umount passed Oct 9 01:29:19.959180 ignition[952]: INFO : Ignition finished successfully Oct 9 01:29:19.955470 systemd[1]: ignition-mount.service: Deactivated successfully. Oct 9 01:29:19.955606 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Oct 9 01:29:19.956742 systemd[1]: ignition-disks.service: Deactivated successfully. Oct 9 01:29:19.956834 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Oct 9 01:29:19.957497 systemd[1]: ignition-kargs.service: Deactivated successfully. Oct 9 01:29:19.957548 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Oct 9 01:29:19.959837 systemd[1]: ignition-fetch.service: Deactivated successfully. Oct 9 01:29:19.959886 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Oct 9 01:29:19.960623 systemd[1]: Stopped target network.target - Network. Oct 9 01:29:19.961204 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Oct 9 01:29:19.961268 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Oct 9 01:29:19.963496 systemd[1]: Stopped target paths.target - Path Units. Oct 9 01:29:19.964102 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Oct 9 01:29:19.966015 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:29:19.967054 systemd[1]: Stopped target slices.target - Slice Units. Oct 9 01:29:19.967813 systemd[1]: Stopped target sockets.target - Socket Units. Oct 9 01:29:19.968367 systemd[1]: iscsid.socket: Deactivated successfully. Oct 9 01:29:19.968406 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Oct 9 01:29:19.970971 systemd[1]: iscsiuio.socket: Deactivated successfully. Oct 9 01:29:19.971021 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Oct 9 01:29:19.973564 systemd[1]: ignition-setup.service: Deactivated successfully. Oct 9 01:29:19.973617 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Oct 9 01:29:19.974311 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Oct 9 01:29:19.974377 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Oct 9 01:29:19.975899 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Oct 9 01:29:19.977255 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Oct 9 01:29:19.979852 systemd[1]: sysroot-boot.mount: Deactivated successfully. Oct 9 01:29:19.980632 systemd[1]: sysroot-boot.service: Deactivated successfully. Oct 9 01:29:19.980787 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Oct 9 01:29:19.980824 systemd-networkd[710]: eth0: DHCPv6 lease lost Oct 9 01:29:19.984217 systemd[1]: systemd-networkd.service: Deactivated successfully. Oct 9 01:29:19.984363 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Oct 9 01:29:19.987277 systemd[1]: systemd-resolved.service: Deactivated successfully. Oct 9 01:29:19.987425 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Oct 9 01:29:19.990352 systemd[1]: systemd-networkd.socket: Deactivated successfully. Oct 9 01:29:19.990652 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:29:19.991715 systemd[1]: initrd-setup-root.service: Deactivated successfully. Oct 9 01:29:19.991789 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Oct 9 01:29:20.000898 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Oct 9 01:29:20.003191 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Oct 9 01:29:20.003285 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Oct 9 01:29:20.004545 systemd[1]: systemd-sysctl.service: Deactivated successfully. Oct 9 01:29:20.004614 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:29:20.005987 systemd[1]: systemd-modules-load.service: Deactivated successfully. Oct 9 01:29:20.006051 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Oct 9 01:29:20.007277 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Oct 9 01:29:20.007342 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:29:20.008836 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:29:20.020945 systemd[1]: network-cleanup.service: Deactivated successfully. Oct 9 01:29:20.021117 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Oct 9 01:29:20.022708 systemd[1]: systemd-udevd.service: Deactivated successfully. Oct 9 01:29:20.022909 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:29:20.024401 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Oct 9 01:29:20.024473 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Oct 9 01:29:20.026102 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Oct 9 01:29:20.026156 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:29:20.027396 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Oct 9 01:29:20.027471 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Oct 9 01:29:20.029344 systemd[1]: dracut-cmdline.service: Deactivated successfully. Oct 9 01:29:20.029400 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Oct 9 01:29:20.030733 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Oct 9 01:29:20.030794 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Oct 9 01:29:20.043095 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Oct 9 01:29:20.043917 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Oct 9 01:29:20.043990 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:29:20.044844 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Oct 9 01:29:20.044914 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:29:20.046412 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Oct 9 01:29:20.046482 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:29:20.049184 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:29:20.049257 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:20.052602 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Oct 9 01:29:20.052777 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Oct 9 01:29:20.054116 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Oct 9 01:29:20.067101 systemd[1]: Starting initrd-switch-root.service - Switch Root... Oct 9 01:29:20.075230 systemd[1]: Switching root. Oct 9 01:29:20.116375 systemd-journald[182]: Journal stopped Oct 9 01:29:21.472160 systemd-journald[182]: Received SIGTERM from PID 1 (systemd). Oct 9 01:29:21.472268 kernel: SELinux: policy capability network_peer_controls=1 Oct 9 01:29:21.472306 kernel: SELinux: policy capability open_perms=1 Oct 9 01:29:21.472333 kernel: SELinux: policy capability extended_socket_class=1 Oct 9 01:29:21.472359 kernel: SELinux: policy capability always_check_network=0 Oct 9 01:29:21.472391 kernel: SELinux: policy capability cgroup_seclabel=1 Oct 9 01:29:21.472420 kernel: SELinux: policy capability nnp_nosuid_transition=1 Oct 9 01:29:21.472452 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Oct 9 01:29:21.472478 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Oct 9 01:29:21.472504 kernel: audit: type=1403 audit(1728437360.280:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Oct 9 01:29:21.472534 systemd[1]: Successfully loaded SELinux policy in 66.048ms. Oct 9 01:29:21.472564 systemd[1]: Relabeled /dev, /dev/shm, /run, /sys/fs/cgroup in 15.959ms. Oct 9 01:29:21.472594 systemd[1]: systemd 255 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 +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT default-hierarchy=unified) Oct 9 01:29:21.472621 systemd[1]: Detected virtualization kvm. Oct 9 01:29:21.472652 systemd[1]: Detected architecture x86-64. Oct 9 01:29:21.472714 systemd[1]: Detected first boot. Oct 9 01:29:21.472744 systemd[1]: Hostname set to . Oct 9 01:29:21.472770 systemd[1]: Initializing machine ID from VM UUID. Oct 9 01:29:21.472809 zram_generator::config[994]: No configuration found. Oct 9 01:29:21.472838 systemd[1]: Populated /etc with preset unit settings. Oct 9 01:29:21.472864 systemd[1]: initrd-switch-root.service: Deactivated successfully. Oct 9 01:29:21.472890 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Oct 9 01:29:21.472923 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Oct 9 01:29:21.472952 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Oct 9 01:29:21.472982 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Oct 9 01:29:21.473009 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Oct 9 01:29:21.473035 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Oct 9 01:29:21.473061 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Oct 9 01:29:21.473092 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Oct 9 01:29:21.473119 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Oct 9 01:29:21.473145 systemd[1]: Created slice user.slice - User and Session Slice. Oct 9 01:29:21.473171 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Oct 9 01:29:21.473198 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Oct 9 01:29:21.473224 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Oct 9 01:29:21.473252 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Oct 9 01:29:21.473279 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Oct 9 01:29:21.473310 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Oct 9 01:29:21.473336 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Oct 9 01:29:21.473362 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Oct 9 01:29:21.473401 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Oct 9 01:29:21.473430 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Oct 9 01:29:21.473457 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Oct 9 01:29:21.473488 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Oct 9 01:29:21.473516 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Oct 9 01:29:21.473542 systemd[1]: Reached target remote-fs.target - Remote File Systems. Oct 9 01:29:21.473568 systemd[1]: Reached target slices.target - Slice Units. Oct 9 01:29:21.473594 systemd[1]: Reached target swap.target - Swaps. Oct 9 01:29:21.473620 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Oct 9 01:29:21.473647 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Oct 9 01:29:21.473699 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Oct 9 01:29:21.473728 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Oct 9 01:29:21.473755 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Oct 9 01:29:21.473787 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Oct 9 01:29:21.473814 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Oct 9 01:29:21.473840 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Oct 9 01:29:21.473866 systemd[1]: Mounting media.mount - External Media Directory... Oct 9 01:29:21.473899 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:21.473926 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Oct 9 01:29:21.473952 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Oct 9 01:29:21.473978 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Oct 9 01:29:21.474018 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Oct 9 01:29:21.474051 systemd[1]: Reached target machines.target - Containers. Oct 9 01:29:21.474078 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Oct 9 01:29:21.474104 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:29:21.474131 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Oct 9 01:29:21.474157 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Oct 9 01:29:21.474184 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:29:21.474210 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:29:21.474237 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:29:21.474267 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Oct 9 01:29:21.474293 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:29:21.474320 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Oct 9 01:29:21.474353 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Oct 9 01:29:21.474380 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Oct 9 01:29:21.474407 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Oct 9 01:29:21.474433 systemd[1]: Stopped systemd-fsck-usr.service. Oct 9 01:29:21.474459 systemd[1]: Starting systemd-journald.service - Journal Service... Oct 9 01:29:21.474485 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Oct 9 01:29:21.474516 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Oct 9 01:29:21.474543 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Oct 9 01:29:21.474569 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Oct 9 01:29:21.474607 systemd[1]: verity-setup.service: Deactivated successfully. Oct 9 01:29:21.474634 systemd[1]: Stopped verity-setup.service. Oct 9 01:29:21.474660 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:21.474733 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Oct 9 01:29:21.474763 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Oct 9 01:29:21.474796 systemd[1]: Mounted media.mount - External Media Directory. Oct 9 01:29:21.474823 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Oct 9 01:29:21.474849 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Oct 9 01:29:21.474874 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Oct 9 01:29:21.474900 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Oct 9 01:29:21.474932 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:29:21.474959 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:29:21.474985 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:29:21.475012 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:29:21.475043 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Oct 9 01:29:21.475074 systemd[1]: modprobe@configfs.service: Deactivated successfully. Oct 9 01:29:21.475101 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Oct 9 01:29:21.475127 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Oct 9 01:29:21.475152 kernel: fuse: init (API version 7.39) Oct 9 01:29:21.475176 kernel: loop: module loaded Oct 9 01:29:21.475201 systemd[1]: modprobe@fuse.service: Deactivated successfully. Oct 9 01:29:21.475242 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Oct 9 01:29:21.475268 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:29:21.475295 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:29:21.475325 systemd[1]: Reached target network-pre.target - Preparation for Network. Oct 9 01:29:21.475353 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Oct 9 01:29:21.475379 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Oct 9 01:29:21.475407 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Oct 9 01:29:21.475433 systemd[1]: Reached target local-fs.target - Local File Systems. Oct 9 01:29:21.475461 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management (Varlink). Oct 9 01:29:21.475489 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Oct 9 01:29:21.475538 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Oct 9 01:29:21.475571 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:29:21.475632 systemd-journald[1073]: Collecting audit messages is disabled. Oct 9 01:29:21.475723 systemd-journald[1073]: Journal started Oct 9 01:29:21.475777 systemd-journald[1073]: Runtime Journal (/run/log/journal/b3e46c96522940fbbd6871cf8f23294a) is 4.9M, max 39.3M, 34.4M free. Oct 9 01:29:21.013220 systemd[1]: Queued start job for default target multi-user.target. Oct 9 01:29:21.035232 systemd[1]: Unnecessary job was removed for dev-vda6.device - /dev/vda6. Oct 9 01:29:21.035690 systemd[1]: systemd-journald.service: Deactivated successfully. Oct 9 01:29:21.517551 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Oct 9 01:29:21.517611 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:29:21.526794 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Oct 9 01:29:21.530695 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:29:21.538767 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Oct 9 01:29:21.550557 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Oct 9 01:29:21.550614 systemd[1]: Started systemd-journald.service - Journal Service. Oct 9 01:29:21.555736 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Oct 9 01:29:21.556888 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Oct 9 01:29:21.557865 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Oct 9 01:29:21.559905 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Oct 9 01:29:21.585838 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Oct 9 01:29:21.587820 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Oct 9 01:29:21.601973 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Oct 9 01:29:21.608538 kernel: loop0: detected capacity change from 0 to 8 Oct 9 01:29:21.607928 systemd[1]: Starting systemd-udev-settle.service - Wait for udev To Complete Device Initialization... Oct 9 01:29:21.635446 systemd-journald[1073]: Time spent on flushing to /var/log/journal/b3e46c96522940fbbd6871cf8f23294a is 32.847ms for 915 entries. Oct 9 01:29:21.635446 systemd-journald[1073]: System Journal (/var/log/journal/b3e46c96522940fbbd6871cf8f23294a) is 8.0M, max 584.8M, 576.8M free. Oct 9 01:29:21.888498 systemd-journald[1073]: Received client request to flush runtime journal. Oct 9 01:29:21.888564 kernel: ACPI: bus type drm_connector registered Oct 9 01:29:21.888591 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Oct 9 01:29:21.888609 kernel: loop1: detected capacity change from 0 to 140992 Oct 9 01:29:21.640158 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:29:21.640392 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:29:21.652642 udevadm[1118]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Oct 9 01:29:21.773642 systemd-tmpfiles[1095]: ACLs are not supported, ignoring. Oct 9 01:29:21.773744 systemd-tmpfiles[1095]: ACLs are not supported, ignoring. Oct 9 01:29:21.794590 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Oct 9 01:29:21.799742 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Oct 9 01:29:21.805186 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Oct 9 01:29:21.809735 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Oct 9 01:29:21.825072 systemd[1]: Starting systemd-machine-id-commit.service - Commit a transient machine-id on disk... Oct 9 01:29:21.865371 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Oct 9 01:29:21.880832 systemd[1]: Starting systemd-sysusers.service - Create System Users... Oct 9 01:29:21.897564 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Oct 9 01:29:21.949197 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Oct 9 01:29:21.949754 systemd[1]: Finished systemd-machine-id-commit.service - Commit a transient machine-id on disk. Oct 9 01:29:21.982019 systemd[1]: Finished systemd-sysusers.service - Create System Users. Oct 9 01:29:21.988702 kernel: loop2: detected capacity change from 0 to 138192 Oct 9 01:29:21.987855 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Oct 9 01:29:22.015956 systemd-tmpfiles[1152]: ACLs are not supported, ignoring. Oct 9 01:29:22.016290 systemd-tmpfiles[1152]: ACLs are not supported, ignoring. Oct 9 01:29:22.020871 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Oct 9 01:29:22.064747 kernel: loop3: detected capacity change from 0 to 8 Oct 9 01:29:22.066736 kernel: loop4: detected capacity change from 0 to 140992 Oct 9 01:29:22.104703 kernel: loop5: detected capacity change from 0 to 138192 Oct 9 01:29:22.198141 (sd-merge)[1156]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-openstack'. Oct 9 01:29:22.200759 (sd-merge)[1156]: Merged extensions into '/usr'. Oct 9 01:29:22.206404 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Oct 9 01:29:22.215922 systemd[1]: Starting ensure-sysext.service... Oct 9 01:29:22.228234 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Oct 9 01:29:22.257842 systemd[1]: Reloading requested from client PID 1158 ('systemctl') (unit ensure-sysext.service)... Oct 9 01:29:22.257865 systemd[1]: Reloading... Oct 9 01:29:22.267250 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Oct 9 01:29:22.269169 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Oct 9 01:29:22.272166 systemd-tmpfiles[1159]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Oct 9 01:29:22.272519 systemd-tmpfiles[1159]: ACLs are not supported, ignoring. Oct 9 01:29:22.272579 systemd-tmpfiles[1159]: ACLs are not supported, ignoring. Oct 9 01:29:22.284105 systemd-tmpfiles[1159]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:29:22.284116 systemd-tmpfiles[1159]: Skipping /boot Oct 9 01:29:22.307588 systemd-tmpfiles[1159]: Detected autofs mount point /boot during canonicalization of boot. Oct 9 01:29:22.307599 systemd-tmpfiles[1159]: Skipping /boot Oct 9 01:29:22.371763 zram_generator::config[1185]: No configuration found. Oct 9 01:29:22.560218 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Oct 9 01:29:22.630358 systemd[1]: Reloading finished in 372 ms. Oct 9 01:29:22.666079 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Oct 9 01:29:22.674854 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 01:29:22.760037 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Oct 9 01:29:22.765978 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Oct 9 01:29:22.779610 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Oct 9 01:29:22.786026 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Oct 9 01:29:22.799619 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.800831 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:29:22.814831 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:29:22.820484 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:29:22.825500 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:29:22.827730 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:29:22.828042 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.830917 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:29:22.831211 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:29:22.840367 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.841763 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:29:22.849868 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Oct 9 01:29:22.850527 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:29:22.850664 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.851446 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:29:22.851601 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:29:22.852744 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:29:22.852889 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:29:22.857008 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:29:22.861123 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Oct 9 01:29:22.861282 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Oct 9 01:29:22.864055 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Oct 9 01:29:22.866612 systemd[1]: Finished ensure-sysext.service. Oct 9 01:29:22.872478 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.872661 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Oct 9 01:29:22.875839 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Oct 9 01:29:22.878471 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Oct 9 01:29:22.883353 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Oct 9 01:29:22.884012 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Oct 9 01:29:22.887859 systemd[1]: Starting systemd-timesyncd.service - Network Time Synchronization... Oct 9 01:29:22.889770 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Oct 9 01:29:22.890182 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Oct 9 01:29:22.890724 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Oct 9 01:29:22.891492 systemd[1]: modprobe@drm.service: Deactivated successfully. Oct 9 01:29:22.891767 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Oct 9 01:29:22.900154 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Oct 9 01:29:22.902875 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Oct 9 01:29:22.903775 systemd[1]: modprobe@loop.service: Deactivated successfully. Oct 9 01:29:22.903913 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Oct 9 01:29:22.905370 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Oct 9 01:29:22.961737 systemd[1]: Started systemd-userdbd.service - User Database Manager. Oct 9 01:29:23.164148 systemd[1]: Started systemd-timesyncd.service - Network Time Synchronization. Oct 9 01:29:23.164820 systemd[1]: Reached target time-set.target - System Time Set. Oct 9 01:29:23.227262 augenrules[1293]: No rules Oct 9 01:29:23.228140 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 01:29:23.228397 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 01:29:23.231881 systemd-resolved[1247]: Positive Trust Anchors: Oct 9 01:29:23.231907 systemd-resolved[1247]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Oct 9 01:29:23.231983 systemd-resolved[1247]: 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 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test Oct 9 01:29:23.260798 systemd-resolved[1247]: Using system hostname 'ci-4116-0-0-5-9c99834064.novalocal'. Oct 9 01:29:23.268259 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Oct 9 01:29:23.269393 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Oct 9 01:29:23.383073 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Oct 9 01:29:23.771125 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Oct 9 01:29:23.789107 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Oct 9 01:29:23.843692 systemd-udevd[1301]: Using default interface naming scheme 'v255'. Oct 9 01:29:23.845865 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Oct 9 01:29:23.847832 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Oct 9 01:29:23.926664 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Oct 9 01:29:23.943253 systemd[1]: Starting systemd-networkd.service - Network Configuration... Oct 9 01:29:24.011706 kernel: BTRFS info: devid 1 device path /dev/mapper/usr changed to /dev/dm-0 scanned by (udev-worker) (1312) Oct 9 01:29:24.017705 kernel: BTRFS info: devid 1 device path /dev/dm-0 changed to /dev/mapper/usr scanned by (udev-worker) (1312) Oct 9 01:29:24.023355 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Oct 9 01:29:24.030699 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1305) Oct 9 01:29:24.094725 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2 Oct 9 01:29:24.120121 systemd-networkd[1309]: lo: Link UP Oct 9 01:29:24.120135 systemd-networkd[1309]: lo: Gained carrier Oct 9 01:29:24.136629 systemd-networkd[1309]: Enumeration completed Oct 9 01:29:24.136833 systemd[1]: Started systemd-networkd.service - Network Configuration. Oct 9 01:29:24.137664 systemd[1]: Reached target network.target - Network. Oct 9 01:29:24.137973 systemd-networkd[1309]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:29:24.137978 systemd-networkd[1309]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Oct 9 01:29:24.140172 systemd-networkd[1309]: eth0: Link UP Oct 9 01:29:24.140207 systemd-networkd[1309]: eth0: Gained carrier Oct 9 01:29:24.140231 systemd-networkd[1309]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Oct 9 01:29:24.145954 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Oct 9 01:29:24.151798 systemd-networkd[1309]: eth0: DHCPv4 address 172.24.4.83/24, gateway 172.24.4.1 acquired from 172.24.4.1 Oct 9 01:29:24.155003 systemd-timesyncd[1268]: Network configuration changed, trying to establish connection. Oct 9 01:29:24.164749 kernel: ACPI: button: Power Button [PWRF] Oct 9 01:29:24.178701 kernel: piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0 Oct 9 01:29:24.184070 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Oct 9 01:29:24.210401 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM. Oct 9 01:29:24.227894 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Oct 9 01:29:24.260174 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:24.262769 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Oct 9 01:29:24.286906 kernel: [drm] pci: virtio-vga detected at 0000:00:02.0 Oct 9 01:29:24.286996 kernel: mousedev: PS/2 mouse device common for all mice Oct 9 01:29:24.287025 kernel: virtio-pci 0000:00:02.0: vgaarb: deactivate vga console Oct 9 01:29:24.292709 kernel: Console: switching to colour dummy device 80x25 Oct 9 01:29:24.295798 kernel: [drm] features: -virgl +edid -resource_blob -host_visible Oct 9 01:29:24.295864 kernel: [drm] features: -context_init Oct 9 01:29:24.297912 kernel: [drm] number of scanouts: 1 Oct 9 01:29:24.298000 kernel: [drm] number of cap sets: 0 Oct 9 01:29:24.299735 kernel: [drm] Initialized virtio_gpu 0.1.0 0 for 0000:00:02.0 on minor 0 Oct 9 01:29:24.305637 kernel: fbcon: virtio_gpudrmfb (fb0) is primary device Oct 9 01:29:24.305735 kernel: Console: switching to colour frame buffer device 128x48 Oct 9 01:29:24.314704 kernel: virtio-pci 0000:00:02.0: [drm] fb0: virtio_gpudrmfb frame buffer device Oct 9 01:29:24.317432 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:29:24.318110 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:24.327880 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:24.331039 ldconfig[1084]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Oct 9 01:29:24.333244 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Oct 9 01:29:24.333746 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:24.345050 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Oct 9 01:29:24.347733 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Oct 9 01:29:24.348307 systemd[1]: Finished systemd-udev-settle.service - Wait for udev To Complete Device Initialization. Oct 9 01:29:24.356845 systemd[1]: Starting lvm2-activation-early.service - Activation of LVM2 logical volumes... Oct 9 01:29:24.360376 systemd[1]: Starting systemd-update-done.service - Update is Completed... Oct 9 01:29:24.383526 systemd[1]: Finished systemd-update-done.service - Update is Completed. Oct 9 01:29:24.420316 lvm[1344]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:29:24.474752 systemd[1]: Finished lvm2-activation-early.service - Activation of LVM2 logical volumes. Oct 9 01:29:24.475841 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Oct 9 01:29:24.482996 systemd[1]: Starting lvm2-activation.service - Activation of LVM2 logical volumes... Oct 9 01:29:24.512460 lvm[1350]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Oct 9 01:29:24.551791 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Oct 9 01:29:24.553869 systemd[1]: Reached target sysinit.target - System Initialization. Oct 9 01:29:24.555097 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Oct 9 01:29:24.555368 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Oct 9 01:29:24.555982 systemd[1]: Started logrotate.timer - Daily rotation of log files. Oct 9 01:29:24.556372 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Oct 9 01:29:24.556621 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 9 01:29:24.558759 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Oct 9 01:29:24.558844 systemd[1]: Reached target paths.target - Path Units. Oct 9 01:29:24.558992 systemd[1]: Reached target timers.target - Timer Units. Oct 9 01:29:24.563829 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Oct 9 01:29:24.571368 systemd[1]: Starting docker.socket - Docker Socket for the API... Oct 9 01:29:24.596509 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Oct 9 01:29:24.602136 systemd[1]: Finished lvm2-activation.service - Activation of LVM2 logical volumes. Oct 9 01:29:24.605961 systemd[1]: Listening on docker.socket - Docker Socket for the API. Oct 9 01:29:24.611890 systemd[1]: Reached target sockets.target - Socket Units. Oct 9 01:29:24.616505 systemd[1]: Reached target basic.target - Basic System. Oct 9 01:29:24.619614 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:29:24.619721 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Oct 9 01:29:24.626912 systemd[1]: Starting containerd.service - containerd container runtime... Oct 9 01:29:24.648061 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Oct 9 01:29:24.665043 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Oct 9 01:29:24.671930 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Oct 9 01:29:24.684054 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Oct 9 01:29:24.687280 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment). Oct 9 01:29:24.701873 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Oct 9 01:29:24.706886 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Oct 9 01:29:24.717433 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Oct 9 01:29:24.723302 jq[1360]: false Oct 9 01:29:24.727422 systemd[1]: Starting systemd-logind.service - User Login Management... Oct 9 01:29:24.729512 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Oct 9 01:29:24.731037 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Oct 9 01:29:24.737888 systemd[1]: Starting update-engine.service - Update Engine... Oct 9 01:29:24.745302 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Oct 9 01:29:24.756187 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Oct 9 01:29:24.756751 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Oct 9 01:29:24.757053 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Oct 9 01:29:24.757253 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Oct 9 01:29:24.765184 systemd[1]: motdgen.service: Deactivated successfully. Oct 9 01:29:24.765425 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Oct 9 01:29:24.776071 extend-filesystems[1363]: Found loop3 Oct 9 01:29:24.776071 extend-filesystems[1363]: Found loop4 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found loop5 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda1 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda2 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda3 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found usr Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda4 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda6 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda7 Oct 9 01:29:24.782309 extend-filesystems[1363]: Found vda9 Oct 9 01:29:24.782309 extend-filesystems[1363]: Checking size of /dev/vda9 Oct 9 01:29:24.805035 jq[1376]: true Oct 9 01:29:24.810876 update_engine[1373]: I20241009 01:29:24.790930 1373 main.cc:92] Flatcar Update Engine starting Oct 9 01:29:24.813826 dbus-daemon[1359]: [system] SELinux support is enabled Oct 9 01:29:24.818760 systemd[1]: Started dbus.service - D-Bus System Message Bus. Oct 9 01:29:24.825459 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Oct 9 01:29:24.825486 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Oct 9 01:29:24.827885 (ntainerd)[1380]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Oct 9 01:29:24.829441 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Oct 9 01:29:24.837989 update_engine[1373]: I20241009 01:29:24.832635 1373 update_check_scheduler.cc:74] Next update check in 7m15s Oct 9 01:29:24.838021 extend-filesystems[1363]: Resized partition /dev/vda9 Oct 9 01:29:24.829461 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Oct 9 01:29:24.840225 systemd[1]: Started update-engine.service - Update Engine. Oct 9 01:29:24.849852 systemd[1]: Started locksmithd.service - Cluster reboot manager. Oct 9 01:29:24.856788 extend-filesystems[1394]: resize2fs 1.47.1 (20-May-2024) Oct 9 01:29:24.872725 jq[1389]: true Oct 9 01:29:24.877010 kernel: EXT4-fs (vda9): resizing filesystem from 1617920 to 4635643 blocks Oct 9 01:29:24.896215 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1319) Oct 9 01:29:24.903092 systemd-logind[1369]: New seat seat0. Oct 9 01:29:24.910306 systemd-logind[1369]: Watching system buttons on /dev/input/event1 (Power Button) Oct 9 01:29:24.910325 systemd-logind[1369]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Oct 9 01:29:24.923995 systemd[1]: Started systemd-logind.service - User Login Management. Oct 9 01:29:25.051885 locksmithd[1393]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Oct 9 01:29:25.115976 kernel: EXT4-fs (vda9): resized filesystem to 4635643 Oct 9 01:29:25.221979 extend-filesystems[1394]: Filesystem at /dev/vda9 is mounted on /; on-line resizing required Oct 9 01:29:25.221979 extend-filesystems[1394]: old_desc_blocks = 1, new_desc_blocks = 3 Oct 9 01:29:25.221979 extend-filesystems[1394]: The filesystem on /dev/vda9 is now 4635643 (4k) blocks long. Oct 9 01:29:25.227387 extend-filesystems[1363]: Resized filesystem in /dev/vda9 Oct 9 01:29:25.222581 systemd[1]: extend-filesystems.service: Deactivated successfully. Oct 9 01:29:25.230877 bash[1411]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:29:25.226123 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Oct 9 01:29:25.230306 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Oct 9 01:29:25.247472 systemd[1]: Starting sshkeys.service... Oct 9 01:29:25.268767 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Oct 9 01:29:25.279377 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Oct 9 01:29:25.316270 containerd[1380]: time="2024-10-09T01:29:25.316131521Z" level=info msg="starting containerd" revision=b2ce781edcbd6cb758f172ecab61c79d607cc41d version=v1.7.22 Oct 9 01:29:25.347312 containerd[1380]: time="2024-10-09T01:29:25.347241367Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349185023Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/6.6.54-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349231009Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349254102Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349497920Z" level=info msg="loading plugin \"io.containerd.warning.v1.deprecations\"..." type=io.containerd.warning.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349520963Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349599069Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.blockfile\"..." error="no scratch file generator: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349617814Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349822598Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349842876Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349857855Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." error="devmapper not configured: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:29:25.350712 containerd[1380]: time="2024-10-09T01:29:25.349869136Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.349951420Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.350180590Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.350285146Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.350303090Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.350389161Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Oct 9 01:29:25.351094 containerd[1380]: time="2024-10-09T01:29:25.350442251Z" level=info msg="metadata content store policy set" policy=shared Oct 9 01:29:25.531391 sshd_keygen[1388]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Oct 9 01:29:25.572417 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Oct 9 01:29:25.584400 systemd[1]: Starting issuegen.service - Generate /run/issue... Oct 9 01:29:25.597071 systemd[1]: issuegen.service: Deactivated successfully. Oct 9 01:29:25.597521 systemd[1]: Finished issuegen.service - Generate /run/issue. Oct 9 01:29:25.608117 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Oct 9 01:29:25.665433 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Oct 9 01:29:25.679578 systemd[1]: Started getty@tty1.service - Getty on tty1. Oct 9 01:29:25.693614 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Oct 9 01:29:25.699997 systemd[1]: Reached target getty.target - Login Prompts. Oct 9 01:29:25.788971 containerd[1380]: time="2024-10-09T01:29:25.788329959Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Oct 9 01:29:25.788971 containerd[1380]: time="2024-10-09T01:29:25.788518172Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Oct 9 01:29:25.788971 containerd[1380]: time="2024-10-09T01:29:25.788561563Z" level=info msg="loading plugin \"io.containerd.lease.v1.manager\"..." type=io.containerd.lease.v1 Oct 9 01:29:25.788971 containerd[1380]: time="2024-10-09T01:29:25.788601488Z" level=info msg="loading plugin \"io.containerd.streaming.v1.manager\"..." type=io.containerd.streaming.v1 Oct 9 01:29:25.788971 containerd[1380]: time="2024-10-09T01:29:25.788637896Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Oct 9 01:29:25.790176 containerd[1380]: time="2024-10-09T01:29:25.789074575Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Oct 9 01:29:25.790176 containerd[1380]: time="2024-10-09T01:29:25.789771482Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Oct 9 01:29:25.790328 containerd[1380]: time="2024-10-09T01:29:25.790191220Z" level=info msg="loading plugin \"io.containerd.runtime.v2.shim\"..." type=io.containerd.runtime.v2 Oct 9 01:29:25.790328 containerd[1380]: time="2024-10-09T01:29:25.790240783Z" level=info msg="loading plugin \"io.containerd.sandbox.store.v1.local\"..." type=io.containerd.sandbox.store.v1 Oct 9 01:29:25.790328 containerd[1380]: time="2024-10-09T01:29:25.790279335Z" level=info msg="loading plugin \"io.containerd.sandbox.controller.v1.local\"..." type=io.containerd.sandbox.controller.v1 Oct 9 01:29:25.790328 containerd[1380]: time="2024-10-09T01:29:25.790314471Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790348455Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790381747Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790417604Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790453962Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790488868Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790526 containerd[1380]: time="2024-10-09T01:29:25.790521159Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790552718Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790622228Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790659348Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790734509Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790772179Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790804440Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790837662Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.790871 containerd[1380]: time="2024-10-09T01:29:25.790866576Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.790900811Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.790933392Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandbox-controllers\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.790969640Z" level=info msg="loading plugin \"io.containerd.grpc.v1.sandboxes\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791000137Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791029171Z" level=info msg="loading plugin \"io.containerd.grpc.v1.streaming\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791064778Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791100505Z" level=info msg="loading plugin \"io.containerd.transfer.v1.local\"..." type=io.containerd.transfer.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791148625Z" level=info msg="loading plugin \"io.containerd.grpc.v1.transfer\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791181467Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.791282 containerd[1380]: time="2024-10-09T01:29:25.791208989Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791337339Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791386391Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.tracing.processor.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791416097Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791452605Z" level=info msg="skip loading plugin \"io.containerd.internal.v1.tracing\"..." error="skip plugin: tracing endpoint not configured" type=io.containerd.internal.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791478775Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791552873Z" level=info msg="loading plugin \"io.containerd.nri.v1.nri\"..." type=io.containerd.nri.v1 Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791583701Z" level=info msg="NRI interface is disabled by configuration." Oct 9 01:29:25.792496 containerd[1380]: time="2024-10-09T01:29:25.791610121Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Oct 9 01:29:25.793370 containerd[1380]: time="2024-10-09T01:29:25.792344598Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false PrivilegedWithoutHostDevicesAllDevicesAllowed:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0 Snapshotter: SandboxMode:podsandbox}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreBlockIONotEnabledErrors:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginSetupSerially:false NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.8 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false EnableCDI:false CDISpecDirs:[/etc/cdi /var/run/cdi] ImagePullProgressTimeout:5m0s DrainExecSyncIOTimeout:0s ImagePullWithSyncFs:false IgnoreDeprecationWarnings:[]} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Oct 9 01:29:25.793370 containerd[1380]: time="2024-10-09T01:29:25.792478830Z" level=info msg="Connect containerd service" Oct 9 01:29:25.793370 containerd[1380]: time="2024-10-09T01:29:25.792540746Z" level=info msg="using legacy CRI server" Oct 9 01:29:25.793370 containerd[1380]: time="2024-10-09T01:29:25.792558930Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Oct 9 01:29:25.793370 containerd[1380]: time="2024-10-09T01:29:25.792889029Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794424189Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794602914Z" level=info msg="Start subscribing containerd event" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794737687Z" level=info msg="Start recovering state" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794877789Z" level=info msg="Start event monitor" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794906774Z" level=info msg="Start snapshots syncer" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794928444Z" level=info msg="Start cni network conf syncer for default" Oct 9 01:29:25.795251 containerd[1380]: time="2024-10-09T01:29:25.794950586Z" level=info msg="Start streaming server" Oct 9 01:29:25.796383 containerd[1380]: time="2024-10-09T01:29:25.796319032Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Oct 9 01:29:25.797832 containerd[1380]: time="2024-10-09T01:29:25.796454857Z" level=info msg=serving... address=/run/containerd/containerd.sock Oct 9 01:29:25.797832 containerd[1380]: time="2024-10-09T01:29:25.796611080Z" level=info msg="containerd successfully booted in 0.482405s" Oct 9 01:29:25.796956 systemd[1]: Started containerd.service - containerd container runtime. Oct 9 01:29:26.095215 systemd-networkd[1309]: eth0: Gained IPv6LL Oct 9 01:29:26.097601 systemd-timesyncd[1268]: Network configuration changed, trying to establish connection. Oct 9 01:29:26.101662 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Oct 9 01:29:26.107835 systemd[1]: Reached target network-online.target - Network is Online. Oct 9 01:29:26.119546 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Oct 9 01:29:26.157790 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Oct 9 01:29:27.391271 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Oct 9 01:29:27.403603 systemd[1]: Started sshd@0-172.24.4.83:22-172.24.4.1:47376.service - OpenSSH per-connection server daemon (172.24.4.1:47376). Oct 9 01:29:28.936463 sshd[1458]: Accepted publickey for core from 172.24.4.1 port 47376 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:28.997731 sshd[1458]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:29.009734 systemd-logind[1369]: New session 1 of user core. Oct 9 01:29:29.009851 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Oct 9 01:29:29.017775 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Oct 9 01:29:29.139193 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Oct 9 01:29:29.154563 systemd[1]: Starting user@500.service - User Manager for UID 500... Oct 9 01:29:29.216784 (systemd)[1464]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Oct 9 01:29:29.723906 systemd[1464]: Queued start job for default target default.target. Oct 9 01:29:29.733707 systemd[1464]: Created slice app.slice - User Application Slice. Oct 9 01:29:29.733736 systemd[1464]: Reached target paths.target - Paths. Oct 9 01:29:29.733750 systemd[1464]: Reached target timers.target - Timers. Oct 9 01:29:29.735154 systemd[1464]: Starting dbus.socket - D-Bus User Message Bus Socket... Oct 9 01:29:29.756991 systemd[1464]: Listening on dbus.socket - D-Bus User Message Bus Socket. Oct 9 01:29:29.757146 systemd[1464]: Reached target sockets.target - Sockets. Oct 9 01:29:29.757163 systemd[1464]: Reached target basic.target - Basic System. Oct 9 01:29:29.757209 systemd[1464]: Reached target default.target - Main User Target. Oct 9 01:29:29.757238 systemd[1464]: Startup finished in 527ms. Oct 9 01:29:29.757371 systemd[1]: Started user@500.service - User Manager for UID 500. Oct 9 01:29:29.770249 systemd[1]: Started session-1.scope - Session 1 of User core. Oct 9 01:29:30.584550 systemd[1]: Started sshd@1-172.24.4.83:22-172.24.4.1:47380.service - OpenSSH per-connection server daemon (172.24.4.1:47380). Oct 9 01:29:30.737567 login[1445]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 9 01:29:30.748816 systemd-logind[1369]: New session 2 of user core. Oct 9 01:29:30.753108 login[1446]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Oct 9 01:29:30.761091 systemd[1]: Started session-2.scope - Session 2 of User core. Oct 9 01:29:30.773821 systemd-logind[1369]: New session 3 of user core. Oct 9 01:29:30.788156 systemd[1]: Started session-3.scope - Session 3 of User core. Oct 9 01:29:31.700986 sshd[1475]: Accepted publickey for core from 172.24.4.1 port 47380 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:31.703755 sshd[1475]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:31.713282 systemd-logind[1369]: New session 4 of user core. Oct 9 01:29:31.725114 systemd[1]: Started session-4.scope - Session 4 of User core. Oct 9 01:29:31.764200 coreos-metadata[1358]: Oct 09 01:29:31.764 WARN failed to locate config-drive, using the metadata service API instead Oct 9 01:29:31.842248 coreos-metadata[1358]: Oct 09 01:29:31.841 INFO Fetching http://169.254.169.254/openstack/2012-08-10/meta_data.json: Attempt #1 Oct 9 01:29:32.061532 coreos-metadata[1358]: Oct 09 01:29:32.061 INFO Fetch successful Oct 9 01:29:32.061532 coreos-metadata[1358]: Oct 09 01:29:32.061 INFO Fetching http://169.254.169.254/latest/meta-data/hostname: Attempt #1 Oct 9 01:29:32.075272 coreos-metadata[1358]: Oct 09 01:29:32.075 INFO Fetch successful Oct 9 01:29:32.075478 coreos-metadata[1358]: Oct 09 01:29:32.075 INFO Fetching http://169.254.169.254/latest/meta-data/instance-id: Attempt #1 Oct 9 01:29:32.087251 coreos-metadata[1358]: Oct 09 01:29:32.087 INFO Fetch successful Oct 9 01:29:32.087251 coreos-metadata[1358]: Oct 09 01:29:32.087 INFO Fetching http://169.254.169.254/latest/meta-data/instance-type: Attempt #1 Oct 9 01:29:32.099282 coreos-metadata[1358]: Oct 09 01:29:32.099 INFO Fetch successful Oct 9 01:29:32.099282 coreos-metadata[1358]: Oct 09 01:29:32.099 INFO Fetching http://169.254.169.254/latest/meta-data/local-ipv4: Attempt #1 Oct 9 01:29:32.110211 coreos-metadata[1358]: Oct 09 01:29:32.110 INFO Fetch successful Oct 9 01:29:32.110402 coreos-metadata[1358]: Oct 09 01:29:32.110 INFO Fetching http://169.254.169.254/latest/meta-data/public-ipv4: Attempt #1 Oct 9 01:29:32.123614 coreos-metadata[1358]: Oct 09 01:29:32.123 INFO Fetch successful Oct 9 01:29:32.178368 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Oct 9 01:29:32.179524 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Oct 9 01:29:32.210432 sshd[1475]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:32.219146 systemd[1]: sshd@1-172.24.4.83:22-172.24.4.1:47380.service: Deactivated successfully. Oct 9 01:29:32.221431 systemd[1]: session-4.scope: Deactivated successfully. Oct 9 01:29:32.224958 systemd-logind[1369]: Session 4 logged out. Waiting for processes to exit. Oct 9 01:29:32.233232 systemd[1]: Started sshd@2-172.24.4.83:22-172.24.4.1:47390.service - OpenSSH per-connection server daemon (172.24.4.1:47390). Oct 9 01:29:32.236085 systemd-logind[1369]: Removed session 4. Oct 9 01:29:32.370431 coreos-metadata[1425]: Oct 09 01:29:32.370 WARN failed to locate config-drive, using the metadata service API instead Oct 9 01:29:32.408792 coreos-metadata[1425]: Oct 09 01:29:32.408 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys: Attempt #1 Oct 9 01:29:32.425800 coreos-metadata[1425]: Oct 09 01:29:32.425 INFO Fetch successful Oct 9 01:29:32.425800 coreos-metadata[1425]: Oct 09 01:29:32.425 INFO Fetching http://169.254.169.254/latest/meta-data/public-keys/0/openssh-key: Attempt #1 Oct 9 01:29:32.439250 coreos-metadata[1425]: Oct 09 01:29:32.439 INFO Fetch successful Oct 9 01:29:32.444390 unknown[1425]: wrote ssh authorized keys file for user: core Oct 9 01:29:32.479953 update-ssh-keys[1520]: Updated "/home/core/.ssh/authorized_keys" Oct 9 01:29:32.480949 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Oct 9 01:29:32.485347 systemd[1]: Finished sshkeys.service. Oct 9 01:29:32.490498 systemd[1]: Reached target multi-user.target - Multi-User System. Oct 9 01:29:32.491087 systemd[1]: Startup finished in 1.128s (kernel) + 11.554s (initrd) + 12.275s (userspace) = 24.957s. Oct 9 01:29:33.741165 sshd[1515]: Accepted publickey for core from 172.24.4.1 port 47390 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:33.744665 sshd[1515]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:33.757812 systemd-logind[1369]: New session 5 of user core. Oct 9 01:29:33.764024 systemd[1]: Started session-5.scope - Session 5 of User core. Oct 9 01:29:34.501055 sshd[1515]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:34.507135 systemd[1]: sshd@2-172.24.4.83:22-172.24.4.1:47390.service: Deactivated successfully. Oct 9 01:29:34.510318 systemd[1]: session-5.scope: Deactivated successfully. Oct 9 01:29:34.513867 systemd-logind[1369]: Session 5 logged out. Waiting for processes to exit. Oct 9 01:29:34.516334 systemd-logind[1369]: Removed session 5. Oct 9 01:29:44.529390 systemd[1]: Started sshd@3-172.24.4.83:22-172.24.4.1:48452.service - OpenSSH per-connection server daemon (172.24.4.1:48452). Oct 9 01:29:46.409486 sshd[1528]: Accepted publickey for core from 172.24.4.1 port 48452 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:46.412978 sshd[1528]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:46.424835 systemd-logind[1369]: New session 6 of user core. Oct 9 01:29:46.434988 systemd[1]: Started session-6.scope - Session 6 of User core. Oct 9 01:29:47.211091 sshd[1528]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:47.221382 systemd[1]: sshd@3-172.24.4.83:22-172.24.4.1:48452.service: Deactivated successfully. Oct 9 01:29:47.224546 systemd[1]: session-6.scope: Deactivated successfully. Oct 9 01:29:47.226494 systemd-logind[1369]: Session 6 logged out. Waiting for processes to exit. Oct 9 01:29:47.255109 systemd[1]: Started sshd@4-172.24.4.83:22-172.24.4.1:48466.service - OpenSSH per-connection server daemon (172.24.4.1:48466). Oct 9 01:29:47.258383 systemd-logind[1369]: Removed session 6. Oct 9 01:29:48.719105 sshd[1535]: Accepted publickey for core from 172.24.4.1 port 48466 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:48.722657 sshd[1535]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:48.733569 systemd-logind[1369]: New session 7 of user core. Oct 9 01:29:48.748164 systemd[1]: Started session-7.scope - Session 7 of User core. Oct 9 01:29:49.462189 sshd[1535]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:49.475027 systemd[1]: sshd@4-172.24.4.83:22-172.24.4.1:48466.service: Deactivated successfully. Oct 9 01:29:49.478939 systemd[1]: session-7.scope: Deactivated successfully. Oct 9 01:29:49.483007 systemd-logind[1369]: Session 7 logged out. Waiting for processes to exit. Oct 9 01:29:49.499876 systemd[1]: Started sshd@5-172.24.4.83:22-172.24.4.1:48470.service - OpenSSH per-connection server daemon (172.24.4.1:48470). Oct 9 01:29:49.504075 systemd-logind[1369]: Removed session 7. Oct 9 01:29:50.968521 sshd[1542]: Accepted publickey for core from 172.24.4.1 port 48470 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:50.971266 sshd[1542]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:50.981505 systemd-logind[1369]: New session 8 of user core. Oct 9 01:29:50.990964 systemd[1]: Started session-8.scope - Session 8 of User core. Oct 9 01:29:51.711593 sshd[1542]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:51.722399 systemd[1]: sshd@5-172.24.4.83:22-172.24.4.1:48470.service: Deactivated successfully. Oct 9 01:29:51.725401 systemd[1]: session-8.scope: Deactivated successfully. Oct 9 01:29:51.726979 systemd-logind[1369]: Session 8 logged out. Waiting for processes to exit. Oct 9 01:29:51.738277 systemd[1]: Started sshd@6-172.24.4.83:22-172.24.4.1:48478.service - OpenSSH per-connection server daemon (172.24.4.1:48478). Oct 9 01:29:51.740213 systemd-logind[1369]: Removed session 8. Oct 9 01:29:53.083664 sshd[1549]: Accepted publickey for core from 172.24.4.1 port 48478 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:53.086399 sshd[1549]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:53.097518 systemd-logind[1369]: New session 9 of user core. Oct 9 01:29:53.108973 systemd[1]: Started session-9.scope - Session 9 of User core. Oct 9 01:29:53.501644 sudo[1552]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Oct 9 01:29:53.502365 sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:29:53.522437 sudo[1552]: pam_unix(sudo:session): session closed for user root Oct 9 01:29:53.694751 sshd[1549]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:53.739574 systemd[1]: Started sshd@7-172.24.4.83:22-172.24.4.1:48480.service - OpenSSH per-connection server daemon (172.24.4.1:48480). Oct 9 01:29:53.742999 systemd[1]: sshd@6-172.24.4.83:22-172.24.4.1:48478.service: Deactivated successfully. Oct 9 01:29:53.749005 systemd[1]: session-9.scope: Deactivated successfully. Oct 9 01:29:53.751473 systemd-logind[1369]: Session 9 logged out. Waiting for processes to exit. Oct 9 01:29:53.760095 systemd-logind[1369]: Removed session 9. Oct 9 01:29:55.056174 sshd[1555]: Accepted publickey for core from 172.24.4.1 port 48480 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:29:55.059800 sshd[1555]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:29:55.069922 systemd-logind[1369]: New session 10 of user core. Oct 9 01:29:55.082012 systemd[1]: Started session-10.scope - Session 10 of User core. Oct 9 01:29:55.724888 sudo[1561]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Oct 9 01:29:55.725549 sudo[1561]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:29:55.733095 sudo[1561]: pam_unix(sudo:session): session closed for user root Oct 9 01:29:55.744623 sudo[1560]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Oct 9 01:29:55.745319 sudo[1560]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:29:55.784466 systemd[1]: Starting audit-rules.service - Load Audit Rules... Oct 9 01:29:55.845128 augenrules[1583]: No rules Oct 9 01:29:55.848146 systemd[1]: audit-rules.service: Deactivated successfully. Oct 9 01:29:55.848527 systemd[1]: Finished audit-rules.service - Load Audit Rules. Oct 9 01:29:55.851453 sudo[1560]: pam_unix(sudo:session): session closed for user root Oct 9 01:29:56.002093 sshd[1555]: pam_unix(sshd:session): session closed for user core Oct 9 01:29:56.009347 systemd-logind[1369]: Session 10 logged out. Waiting for processes to exit. Oct 9 01:29:56.011000 systemd[1]: sshd@7-172.24.4.83:22-172.24.4.1:48480.service: Deactivated successfully. Oct 9 01:29:56.014050 systemd[1]: session-10.scope: Deactivated successfully. Oct 9 01:29:56.016297 systemd-logind[1369]: Removed session 10. Oct 9 01:29:56.492750 systemd-timesyncd[1268]: Contacted time server 185.123.84.51:123 (2.flatcar.pool.ntp.org). Oct 9 01:29:56.492857 systemd-timesyncd[1268]: Initial clock synchronization to Wed 2024-10-09 01:29:56.851613 UTC. Oct 9 01:30:00.856314 systemd[1]: Started sshd@8-172.24.4.83:22-172.24.4.1:50820.service - OpenSSH per-connection server daemon (172.24.4.1:50820). Oct 9 01:30:02.289484 sshd[1591]: Accepted publickey for core from 172.24.4.1 port 50820 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:30:02.292994 sshd[1591]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:30:02.305122 systemd-logind[1369]: New session 11 of user core. Oct 9 01:30:02.312993 systemd[1]: Started session-11.scope - Session 11 of User core. Oct 9 01:30:02.747764 sudo[1598]: core : PWD=/tmp/tmp.z5UDMmy81m ; USER=root ; COMMAND=/usr/sbin/ldd /usr/bin/nc /usr/bin/timeout Oct 9 01:30:02.748458 sudo[1598]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:30:02.776573 sudo[1598]: pam_unix(sudo:session): session closed for user root Oct 9 01:30:02.792412 sudo[1610]: core : PWD=/tmp/tmp.z5UDMmy81m ; USER=root ; COMMAND=/usr/sbin/rsync -av --relative --copy-links /usr/bin/nc /usr/bin/timeout /lib64/ld-linux-x86-64.so.2 /lib64/libbsd.so.0 /lib64/libc.so.6 /lib64/libmd.so.0 /lib64/libresolv.so.2 ./ Oct 9 01:30:02.793166 sudo[1610]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:30:02.884007 sudo[1610]: pam_unix(sudo:session): session closed for user root Oct 9 01:30:02.896890 sudo[1594]: core : PWD=/tmp/tmp.z5UDMmy81m ; USER=root ; COMMAND=/usr/sbin/docker build -t netcat . Oct 9 01:30:02.897567 sudo[1594]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:30:03.199212 systemd[1]: Starting docker.service - Docker Application Container Engine... Oct 9 01:30:03.216422 (dockerd)[1621]: docker.service: Referenced but unset environment variable evaluates to an empty string: DOCKER_CGROUPS, DOCKER_OPTS, DOCKER_OPT_BIP, DOCKER_OPT_IPMASQ, DOCKER_OPT_MTU Oct 9 01:30:03.824577 dockerd[1621]: time="2024-10-09T01:30:03.824483321Z" level=info msg="Starting up" Oct 9 01:30:03.987250 systemd[1]: var-lib-docker-metacopy\x2dcheck2214144723-merged.mount: Deactivated successfully. Oct 9 01:30:04.010296 dockerd[1621]: time="2024-10-09T01:30:04.010243730Z" level=info msg="Loading containers: start." Oct 9 01:30:04.261780 kernel: Initializing XFRM netlink socket Oct 9 01:30:04.430405 systemd-networkd[1309]: docker0: Link UP Oct 9 01:30:04.471215 dockerd[1621]: time="2024-10-09T01:30:04.471118247Z" level=info msg="Loading containers: done." Oct 9 01:30:04.514559 dockerd[1621]: time="2024-10-09T01:30:04.514431932Z" level=warning msg="Not using native diff for overlay2, this may cause degraded performance for building images: kernel has CONFIG_OVERLAY_FS_REDIRECT_DIR enabled" storage-driver=overlay2 Oct 9 01:30:04.514862 dockerd[1621]: time="2024-10-09T01:30:04.514663331Z" level=info msg="Docker daemon" commit=8b539b8df24032dabeaaa099cf1d0535ef0286a3 containerd-snapshotter=false storage-driver=overlay2 version=27.2.1 Oct 9 01:30:04.514995 dockerd[1621]: time="2024-10-09T01:30:04.514930913Z" level=info msg="Daemon has completed initialization" Oct 9 01:30:04.594544 dockerd[1621]: time="2024-10-09T01:30:04.593340758Z" level=info msg="API listen on /run/docker.sock" Oct 9 01:30:04.596208 systemd[1]: Started docker.service - Docker Application Container Engine. Oct 9 01:30:05.457059 sudo[1594]: pam_unix(sudo:session): session closed for user root Oct 9 01:30:05.620109 sshd[1591]: pam_unix(sshd:session): session closed for user core Oct 9 01:30:05.632666 systemd[1]: sshd@8-172.24.4.83:22-172.24.4.1:50820.service: Deactivated successfully. Oct 9 01:30:05.635830 systemd[1]: session-11.scope: Deactivated successfully. Oct 9 01:30:05.646875 systemd-logind[1369]: Session 11 logged out. Waiting for processes to exit. Oct 9 01:30:05.655279 systemd[1]: Started sshd@10-172.24.4.83:22-172.24.4.1:52708.service - OpenSSH per-connection server daemon (172.24.4.1:52708). Oct 9 01:30:05.658290 systemd[1]: Started sshd@9-172.24.4.83:22-172.24.4.1:52700.service - OpenSSH per-connection server daemon (172.24.4.1:52700). Oct 9 01:30:05.664507 systemd-logind[1369]: Removed session 11. Oct 9 01:30:06.217892 systemd[1]: var-lib-docker-tmp-buildkit\x2dmount1948593183.mount: Deactivated successfully. Oct 9 01:30:07.225985 sshd[1832]: Accepted publickey for core from 172.24.4.1 port 52700 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:30:07.226750 sshd[1831]: Accepted publickey for core from 172.24.4.1 port 52708 ssh2: RSA SHA256:lHAmmlBCs3MgipWTFOgTwhQjTHh7Rjh60OV4qeIxOiA Oct 9 01:30:07.229195 sshd[1832]: pam_lastlog(sshd:session): file /var/log/lastlog is locked/write, retrying Oct 9 01:30:07.229746 sshd[1831]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:30:07.240310 systemd-logind[1369]: New session 12 of user core. Oct 9 01:30:07.251240 systemd[1]: Started session-12.scope - Session 12 of User core. Oct 9 01:30:07.791617 systemd[1]: var-lib-docker-overlay2-7432f2381c63b04fc0bd2a2e061b42e6cbbbcc9785365215a4b93bc9d6c7d0cf\x2dinit-merged.mount: Deactivated successfully. Oct 9 01:30:08.032744 kernel: docker0: port 1(vetha23269a) entered blocking state Oct 9 01:30:08.033426 kernel: docker0: port 1(vetha23269a) entered disabled state Oct 9 01:30:08.034530 kernel: vetha23269a: entered allmulticast mode Oct 9 01:30:08.038081 kernel: vetha23269a: entered promiscuous mode Oct 9 01:30:08.040192 systemd-networkd[1309]: vetha23269a: Link UP Oct 9 01:30:08.230477 sshd[1832]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Oct 9 01:30:08.242261 systemd-logind[1369]: New session 13 of user core. Oct 9 01:30:08.252010 systemd[1]: Started session-13.scope - Session 13 of User core. Oct 9 01:30:08.454593 containerd[1380]: time="2024-10-09T01:30:08.454350237Z" level=info msg="loading plugin \"io.containerd.event.v1.publisher\"..." runtime=io.containerd.runc.v2 type=io.containerd.event.v1 Oct 9 01:30:08.455357 containerd[1380]: time="2024-10-09T01:30:08.454567677Z" level=info msg="loading plugin \"io.containerd.internal.v1.shutdown\"..." runtime=io.containerd.runc.v2 type=io.containerd.internal.v1 Oct 9 01:30:08.455357 containerd[1380]: time="2024-10-09T01:30:08.454921403Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.task\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:30:08.456637 containerd[1380]: time="2024-10-09T01:30:08.455706790Z" level=info msg="loading plugin \"io.containerd.ttrpc.v1.pause\"..." runtime=io.containerd.runc.v2 type=io.containerd.ttrpc.v1 Oct 9 01:30:08.491245 systemd[1]: Started docker-8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e.scope - libcontainer container 8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e. Oct 9 01:30:08.546172 sudo[1917]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/lsof -i TCP:9988 -s TCP:LISTEN Oct 9 01:30:08.546930 sudo[1917]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Oct 9 01:30:08.572047 kernel: eth0: renamed from veth282082b Oct 9 01:30:08.577985 kernel: docker0: port 1(vetha23269a) entered blocking state Oct 9 01:30:08.578046 kernel: docker0: port 1(vetha23269a) entered forwarding state Oct 9 01:30:08.577851 systemd-networkd[1309]: vetha23269a: Gained carrier Oct 9 01:30:08.578426 systemd-networkd[1309]: docker0: Gained carrier Oct 9 01:30:08.586647 sudo[1917]: pam_unix(sudo:session): session closed for user root Oct 9 01:30:08.789354 sshd[1832]: pam_unix(sshd:session): session closed for user core Oct 9 01:30:08.797815 systemd[1]: sshd@9-172.24.4.83:22-172.24.4.1:52700.service: Deactivated successfully. Oct 9 01:30:08.802184 systemd[1]: session-13.scope: Deactivated successfully. Oct 9 01:30:08.804168 systemd-logind[1369]: Session 13 logged out. Waiting for processes to exit. Oct 9 01:30:08.806486 systemd-logind[1369]: Removed session 13. Oct 9 01:30:09.679473 systemd-networkd[1309]: docker0: Gained IPv6LL Oct 9 01:30:09.935747 systemd-networkd[1309]: vetha23269a: Gained IPv6LL Oct 9 01:30:10.536808 update_engine[1373]: I20241009 01:30:10.535837 1373 update_attempter.cc:509] Updating boot flags... Oct 9 01:30:10.579781 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1853) Oct 9 01:30:10.678870 kernel: BTRFS warning: duplicate device /dev/vda3 devid 1 generation 36 scanned by (udev-worker) (1853) Oct 9 01:30:11.149766 systemd[1]: docker-8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e.scope: Deactivated successfully. Oct 9 01:30:11.181532 dockerd[1621]: time="2024-10-09T01:30:11.181285850Z" level=info msg="ignoring event" container=8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Oct 9 01:30:11.184113 containerd[1380]: time="2024-10-09T01:30:11.183143451Z" level=info msg="shim disconnected" id=8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e namespace=moby Oct 9 01:30:11.184113 containerd[1380]: time="2024-10-09T01:30:11.183869892Z" level=warning msg="cleaning up after shim disconnected" id=8708ccaac8419c6d91d6c12c9b065f19f94c5bcb10495549b4de4e1d754a541e namespace=moby Oct 9 01:30:11.184113 containerd[1380]: time="2024-10-09T01:30:11.183894820Z" level=info msg="cleaning up dead shim" namespace=moby Oct 9 01:30:11.268286 kernel: docker0: port 1(vetha23269a) entered disabled state Oct 9 01:30:11.268468 kernel: veth282082b: renamed from eth0 Oct 9 01:30:11.268024 systemd-networkd[1309]: vetha23269a: Lost carrier Oct 9 01:30:11.283961 kernel: docker0: port 1(vetha23269a) entered disabled state Oct 9 01:30:11.284103 kernel: vetha23269a (unregistering): left allmulticast mode Oct 9 01:30:11.284153 kernel: vetha23269a (unregistering): left promiscuous mode Oct 9 01:30:11.280311 systemd-networkd[1309]: vetha23269a: Link DOWN Oct 9 01:30:11.284576 kernel: docker0: port 1(vetha23269a) entered disabled state Oct 9 01:30:11.306571 systemd[1]: run-docker-netns-f4ca61719245.mount: Deactivated successfully. Oct 9 01:30:11.316948 systemd[1]: var-lib-docker-overlay2-7432f2381c63b04fc0bd2a2e061b42e6cbbbcc9785365215a4b93bc9d6c7d0cf-merged.mount: Deactivated successfully. Oct 9 01:30:11.621311 sshd[1831]: pam_unix(sshd:session): session closed for user core Oct 9 01:30:11.629243 systemd-logind[1369]: Session 12 logged out. Waiting for processes to exit. Oct 9 01:30:11.630592 systemd[1]: sshd@10-172.24.4.83:22-172.24.4.1:52708.service: Deactivated successfully. Oct 9 01:30:11.633756 systemd[1]: session-12.scope: Deactivated successfully. Oct 9 01:30:11.635746 systemd-logind[1369]: Removed session 12. Oct 9 01:30:12.304203 systemd-networkd[1309]: docker0: Lost carrier