Sep 9 05:37:46.608303 kernel: Linux version 6.12.45-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.0 p8) 14.3.0, GNU ld (Gentoo 2.44 p4) 2.44.0) #1 SMP PREEMPT_DYNAMIC Tue Sep 9 03:39:34 -00 2025 Sep 9 05:37:46.608344 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:37:46.608362 kernel: BIOS-provided physical RAM map: Sep 9 05:37:46.608375 kernel: BIOS-e820: [mem 0x0000000000000000-0x0000000000000fff] reserved Sep 9 05:37:46.608388 kernel: BIOS-e820: [mem 0x0000000000001000-0x0000000000054fff] usable Sep 9 05:37:46.608408 kernel: BIOS-e820: [mem 0x0000000000055000-0x000000000005ffff] reserved Sep 9 05:37:46.608428 kernel: BIOS-e820: [mem 0x0000000000060000-0x0000000000097fff] usable Sep 9 05:37:46.608442 kernel: BIOS-e820: [mem 0x0000000000098000-0x000000000009ffff] reserved Sep 9 05:37:46.608456 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000bd329fff] usable Sep 9 05:37:46.608470 kernel: BIOS-e820: [mem 0x00000000bd32a000-0x00000000bd331fff] ACPI data Sep 9 05:37:46.608484 kernel: BIOS-e820: [mem 0x00000000bd332000-0x00000000bf8ecfff] usable Sep 9 05:37:46.608497 kernel: BIOS-e820: [mem 0x00000000bf8ed000-0x00000000bfb6cfff] reserved Sep 9 05:37:46.608510 kernel: BIOS-e820: [mem 0x00000000bfb6d000-0x00000000bfb7efff] ACPI data Sep 9 05:37:46.608525 kernel: BIOS-e820: [mem 0x00000000bfb7f000-0x00000000bfbfefff] ACPI NVS Sep 9 05:37:46.608546 kernel: BIOS-e820: [mem 0x00000000bfbff000-0x00000000bffdffff] usable Sep 9 05:37:46.608561 kernel: BIOS-e820: [mem 0x00000000bffe0000-0x00000000bfffffff] reserved Sep 9 05:37:46.608577 kernel: BIOS-e820: [mem 0x0000000100000000-0x000000021fffffff] usable Sep 9 05:37:46.608592 kernel: NX (Execute Disable) protection: active Sep 9 05:37:46.608606 kernel: APIC: Static calls initialized Sep 9 05:37:46.608622 kernel: efi: EFI v2.7 by EDK II Sep 9 05:37:46.608637 kernel: efi: TPMFinalLog=0xbfbf7000 ACPI=0xbfb7e000 ACPI 2.0=0xbfb7e014 SMBIOS=0xbf9e8000 RNG=0xbfb73018 TPMEventLog=0xbd32a018 Sep 9 05:37:46.608653 kernel: random: crng init done Sep 9 05:37:46.608671 kernel: secureboot: Secure boot disabled Sep 9 05:37:46.608686 kernel: SMBIOS 2.4 present. Sep 9 05:37:46.608701 kernel: DMI: Google Google Compute Engine/Google Compute Engine, BIOS Google 08/14/2025 Sep 9 05:37:46.608716 kernel: DMI: Memory slots populated: 1/1 Sep 9 05:37:46.608731 kernel: Hypervisor detected: KVM Sep 9 05:37:46.619476 kernel: kvm-clock: Using msrs 4b564d01 and 4b564d00 Sep 9 05:37:46.619504 kernel: kvm-clock: using sched offset of 14474877845 cycles Sep 9 05:37:46.619523 kernel: clocksource: kvm-clock: mask: 0xffffffffffffffff max_cycles: 0x1cd42e4dffb, max_idle_ns: 881590591483 ns Sep 9 05:37:46.619541 kernel: tsc: Detected 2299.998 MHz processor Sep 9 05:37:46.619558 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Sep 9 05:37:46.619583 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Sep 9 05:37:46.619600 kernel: last_pfn = 0x220000 max_arch_pfn = 0x400000000 Sep 9 05:37:46.619617 kernel: MTRR map: 3 entries (2 fixed + 1 variable; max 18), built from 8 variable MTRRs Sep 9 05:37:46.619634 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Sep 9 05:37:46.619650 kernel: last_pfn = 0xbffe0 max_arch_pfn = 0x400000000 Sep 9 05:37:46.619668 kernel: Using GB pages for direct mapping Sep 9 05:37:46.619684 kernel: ACPI: Early table checksum verification disabled Sep 9 05:37:46.619702 kernel: ACPI: RSDP 0x00000000BFB7E014 000024 (v02 Google) Sep 9 05:37:46.619729 kernel: ACPI: XSDT 0x00000000BFB7D0E8 00005C (v01 Google GOOGFACP 00000001 01000013) Sep 9 05:37:46.619764 kernel: ACPI: FACP 0x00000000BFB78000 0000F4 (v02 Google GOOGFACP 00000001 GOOG 00000001) Sep 9 05:37:46.619782 kernel: ACPI: DSDT 0x00000000BFB79000 001A64 (v01 Google GOOGDSDT 00000001 GOOG 00000001) Sep 9 05:37:46.619799 kernel: ACPI: FACS 0x00000000BFBF2000 000040 Sep 9 05:37:46.619817 kernel: ACPI: SSDT 0x00000000BFB7C000 000316 (v02 GOOGLE Tpm2Tabl 00001000 INTL 20250404) Sep 9 05:37:46.619835 kernel: ACPI: TPM2 0x00000000BFB7B000 000034 (v04 GOOGLE 00000001 GOOG 00000001) Sep 9 05:37:46.619857 kernel: ACPI: SRAT 0x00000000BFB77000 0000C8 (v03 Google GOOGSRAT 00000001 GOOG 00000001) Sep 9 05:37:46.619875 kernel: ACPI: APIC 0x00000000BFB76000 000076 (v05 Google GOOGAPIC 00000001 GOOG 00000001) Sep 9 05:37:46.619893 kernel: ACPI: SSDT 0x00000000BFB75000 000980 (v01 Google GOOGSSDT 00000001 GOOG 00000001) Sep 9 05:37:46.619911 kernel: ACPI: WAET 0x00000000BFB74000 000028 (v01 Google GOOGWAET 00000001 GOOG 00000001) Sep 9 05:37:46.619929 kernel: ACPI: Reserving FACP table memory at [mem 0xbfb78000-0xbfb780f3] Sep 9 05:37:46.619946 kernel: ACPI: Reserving DSDT table memory at [mem 0xbfb79000-0xbfb7aa63] Sep 9 05:37:46.619964 kernel: ACPI: Reserving FACS table memory at [mem 0xbfbf2000-0xbfbf203f] Sep 9 05:37:46.619982 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb7c000-0xbfb7c315] Sep 9 05:37:46.619999 kernel: ACPI: Reserving TPM2 table memory at [mem 0xbfb7b000-0xbfb7b033] Sep 9 05:37:46.620020 kernel: ACPI: Reserving SRAT table memory at [mem 0xbfb77000-0xbfb770c7] Sep 9 05:37:46.620038 kernel: ACPI: Reserving APIC table memory at [mem 0xbfb76000-0xbfb76075] Sep 9 05:37:46.620055 kernel: ACPI: Reserving SSDT table memory at [mem 0xbfb75000-0xbfb7597f] Sep 9 05:37:46.620072 kernel: ACPI: Reserving WAET table memory at [mem 0xbfb74000-0xbfb74027] Sep 9 05:37:46.620089 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x0009ffff] Sep 9 05:37:46.620107 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00100000-0xbfffffff] Sep 9 05:37:46.620125 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x21fffffff] Sep 9 05:37:46.620143 kernel: NUMA: Node 0 [mem 0x00001000-0x0009ffff] + [mem 0x00100000-0xbfffffff] -> [mem 0x00001000-0xbfffffff] Sep 9 05:37:46.620161 kernel: NUMA: Node 0 [mem 0x00001000-0xbfffffff] + [mem 0x100000000-0x21fffffff] -> [mem 0x00001000-0x21fffffff] Sep 9 05:37:46.620182 kernel: NODE_DATA(0) allocated [mem 0x21fff8dc0-0x21fffffff] Sep 9 05:37:46.620201 kernel: Zone ranges: Sep 9 05:37:46.620219 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Sep 9 05:37:46.620237 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Sep 9 05:37:46.620254 kernel: Normal [mem 0x0000000100000000-0x000000021fffffff] Sep 9 05:37:46.620272 kernel: Device empty Sep 9 05:37:46.620290 kernel: Movable zone start for each node Sep 9 05:37:46.620308 kernel: Early memory node ranges Sep 9 05:37:46.620326 kernel: node 0: [mem 0x0000000000001000-0x0000000000054fff] Sep 9 05:37:46.620343 kernel: node 0: [mem 0x0000000000060000-0x0000000000097fff] Sep 9 05:37:46.620364 kernel: node 0: [mem 0x0000000000100000-0x00000000bd329fff] Sep 9 05:37:46.620381 kernel: node 0: [mem 0x00000000bd332000-0x00000000bf8ecfff] Sep 9 05:37:46.620406 kernel: node 0: [mem 0x00000000bfbff000-0x00000000bffdffff] Sep 9 05:37:46.620423 kernel: node 0: [mem 0x0000000100000000-0x000000021fffffff] Sep 9 05:37:46.620441 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x000000021fffffff] Sep 9 05:37:46.620459 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Sep 9 05:37:46.620476 kernel: On node 0, zone DMA: 11 pages in unavailable ranges Sep 9 05:37:46.620493 kernel: On node 0, zone DMA: 104 pages in unavailable ranges Sep 9 05:37:46.620511 kernel: On node 0, zone DMA32: 8 pages in unavailable ranges Sep 9 05:37:46.620533 kernel: On node 0, zone DMA32: 786 pages in unavailable ranges Sep 9 05:37:46.620550 kernel: On node 0, zone Normal: 32 pages in unavailable ranges Sep 9 05:37:46.620569 kernel: ACPI: PM-Timer IO Port: 0xb008 Sep 9 05:37:46.620587 kernel: ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) Sep 9 05:37:46.620605 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23 Sep 9 05:37:46.620623 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) Sep 9 05:37:46.620641 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Sep 9 05:37:46.620658 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) Sep 9 05:37:46.620677 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) Sep 9 05:37:46.620697 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Sep 9 05:37:46.620715 kernel: CPU topo: Max. logical packages: 1 Sep 9 05:37:46.620737 kernel: CPU topo: Max. logical dies: 1 Sep 9 05:37:46.620830 kernel: CPU topo: Max. dies per package: 1 Sep 9 05:37:46.620845 kernel: CPU topo: Max. threads per core: 2 Sep 9 05:37:46.620860 kernel: CPU topo: Num. cores per package: 1 Sep 9 05:37:46.620876 kernel: CPU topo: Num. threads per package: 2 Sep 9 05:37:46.620894 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs Sep 9 05:37:46.620910 kernel: [mem 0xc0000000-0xffffffff] available for PCI devices Sep 9 05:37:46.620933 kernel: Booting paravirtualized kernel on KVM Sep 9 05:37:46.620950 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Sep 9 05:37:46.620968 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 Sep 9 05:37:46.620987 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576 Sep 9 05:37:46.621001 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152 Sep 9 05:37:46.621017 kernel: pcpu-alloc: [0] 0 1 Sep 9 05:37:46.621032 kernel: kvm-guest: PV spinlocks enabled Sep 9 05:37:46.621049 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Sep 9 05:37:46.621067 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:37:46.621089 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Sep 9 05:37:46.621105 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) Sep 9 05:37:46.621124 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Sep 9 05:37:46.621143 kernel: Fallback order for Node 0: 0 Sep 9 05:37:46.621160 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1965138 Sep 9 05:37:46.621176 kernel: Policy zone: Normal Sep 9 05:37:46.621191 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Sep 9 05:37:46.621207 kernel: software IO TLB: area num 2. Sep 9 05:37:46.621240 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Sep 9 05:37:46.621258 kernel: Kernel/User page tables isolation: enabled Sep 9 05:37:46.621276 kernel: ftrace: allocating 40102 entries in 157 pages Sep 9 05:37:46.621298 kernel: ftrace: allocated 157 pages with 5 groups Sep 9 05:37:46.621316 kernel: Dynamic Preempt: voluntary Sep 9 05:37:46.621335 kernel: rcu: Preemptible hierarchical RCU implementation. Sep 9 05:37:46.621355 kernel: rcu: RCU event tracing is enabled. Sep 9 05:37:46.621374 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Sep 9 05:37:46.621406 kernel: Trampoline variant of Tasks RCU enabled. Sep 9 05:37:46.621424 kernel: Rude variant of Tasks RCU enabled. Sep 9 05:37:46.621441 kernel: Tracing variant of Tasks RCU enabled. Sep 9 05:37:46.621457 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Sep 9 05:37:46.621475 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Sep 9 05:37:46.621494 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 05:37:46.621513 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 05:37:46.621532 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2. Sep 9 05:37:46.621552 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 16 Sep 9 05:37:46.621574 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Sep 9 05:37:46.621592 kernel: Console: colour dummy device 80x25 Sep 9 05:37:46.621607 kernel: printk: legacy console [ttyS0] enabled Sep 9 05:37:46.621624 kernel: ACPI: Core revision 20240827 Sep 9 05:37:46.621641 kernel: APIC: Switch to symmetric I/O mode setup Sep 9 05:37:46.621657 kernel: x2apic enabled Sep 9 05:37:46.621674 kernel: APIC: Switched APIC routing to: physical x2apic Sep 9 05:37:46.621690 kernel: ..TIMER: vector=0x30 apic1=0 pin1=0 apic2=-1 pin2=-1 Sep 9 05:37:46.621707 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns Sep 9 05:37:46.621730 kernel: Calibrating delay loop (skipped) preset value.. 4599.99 BogoMIPS (lpj=2299998) Sep 9 05:37:46.621817 kernel: Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024 Sep 9 05:37:46.621838 kernel: Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4 Sep 9 05:37:46.621857 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Sep 9 05:37:46.621876 kernel: Spectre V2 : Spectre BHI mitigation: SW BHB clearing on syscall and VM exit Sep 9 05:37:46.621895 kernel: Spectre V2 : Mitigation: IBRS Sep 9 05:37:46.621915 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Sep 9 05:37:46.621934 kernel: RETBleed: Mitigation: IBRS Sep 9 05:37:46.621953 kernel: Spectre V2 : mitigation: Enabling conditional Indirect Branch Prediction Barrier Sep 9 05:37:46.621978 kernel: Spectre V2 : User space: Mitigation: STIBP via prctl Sep 9 05:37:46.621997 kernel: Speculative Store Bypass: Mitigation: Speculative Store Bypass disabled via prctl Sep 9 05:37:46.622016 kernel: MDS: Mitigation: Clear CPU buffers Sep 9 05:37:46.622035 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Sep 9 05:37:46.622054 kernel: active return thunk: its_return_thunk Sep 9 05:37:46.622073 kernel: ITS: Mitigation: Aligned branch/return thunks Sep 9 05:37:46.622092 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Sep 9 05:37:46.622111 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Sep 9 05:37:46.622130 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Sep 9 05:37:46.622152 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Sep 9 05:37:46.622171 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Sep 9 05:37:46.622190 kernel: Freeing SMP alternatives memory: 32K Sep 9 05:37:46.622210 kernel: pid_max: default: 32768 minimum: 301 Sep 9 05:37:46.622228 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Sep 9 05:37:46.622248 kernel: landlock: Up and running. Sep 9 05:37:46.622267 kernel: SELinux: Initializing. Sep 9 05:37:46.622285 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 9 05:37:46.622304 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) Sep 9 05:37:46.622328 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU @ 2.30GHz (family: 0x6, model: 0x3f, stepping: 0x0) Sep 9 05:37:46.622346 kernel: Performance Events: unsupported p6 CPU model 63 no PMU driver, software events only. Sep 9 05:37:46.622366 kernel: signal: max sigframe size: 1776 Sep 9 05:37:46.622385 kernel: rcu: Hierarchical SRCU implementation. Sep 9 05:37:46.622413 kernel: rcu: Max phase no-delay instances is 400. Sep 9 05:37:46.622432 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Sep 9 05:37:46.622451 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Sep 9 05:37:46.622469 kernel: smp: Bringing up secondary CPUs ... Sep 9 05:37:46.622492 kernel: smpboot: x86: Booting SMP configuration: Sep 9 05:37:46.622512 kernel: .... node #0, CPUs: #1 Sep 9 05:37:46.622532 kernel: Transient Scheduler Attacks: MDS CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/mds.html for more details. Sep 9 05:37:46.622552 kernel: Transient Scheduler Attacks: MMIO Stale Data CPU bug present and SMT on, data leak possible. See https://www.kernel.org/doc/html/latest/admin-guide/hw-vuln/processor_mmio_stale_data.html for more details. Sep 9 05:37:46.622570 kernel: smp: Brought up 1 node, 2 CPUs Sep 9 05:37:46.622589 kernel: smpboot: Total of 2 processors activated (9199.99 BogoMIPS) Sep 9 05:37:46.622610 kernel: Memory: 7564024K/7860552K available (14336K kernel code, 2428K rwdata, 9988K rodata, 54076K init, 2892K bss, 290704K reserved, 0K cma-reserved) Sep 9 05:37:46.622629 kernel: devtmpfs: initialized Sep 9 05:37:46.622648 kernel: x86/mm: Memory block size: 128MB Sep 9 05:37:46.622670 kernel: ACPI: PM: Registering ACPI NVS region [mem 0xbfb7f000-0xbfbfefff] (524288 bytes) Sep 9 05:37:46.622690 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Sep 9 05:37:46.622709 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Sep 9 05:37:46.622728 kernel: pinctrl core: initialized pinctrl subsystem Sep 9 05:37:46.622776 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Sep 9 05:37:46.622798 kernel: audit: initializing netlink subsys (disabled) Sep 9 05:37:46.622819 kernel: audit: type=2000 audit(1757396262.402:1): state=initialized audit_enabled=0 res=1 Sep 9 05:37:46.622838 kernel: thermal_sys: Registered thermal governor 'step_wise' Sep 9 05:37:46.622863 kernel: thermal_sys: Registered thermal governor 'user_space' Sep 9 05:37:46.622882 kernel: cpuidle: using governor menu Sep 9 05:37:46.622899 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Sep 9 05:37:46.622916 kernel: dca service started, version 1.12.1 Sep 9 05:37:46.622932 kernel: PCI: Using configuration type 1 for base access Sep 9 05:37:46.622949 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Sep 9 05:37:46.622967 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Sep 9 05:37:46.622985 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Sep 9 05:37:46.623003 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Sep 9 05:37:46.623026 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Sep 9 05:37:46.623044 kernel: ACPI: Added _OSI(Module Device) Sep 9 05:37:46.623061 kernel: ACPI: Added _OSI(Processor Device) Sep 9 05:37:46.623080 kernel: ACPI: Added _OSI(Processor Aggregator Device) Sep 9 05:37:46.623098 kernel: ACPI: 3 ACPI AML tables successfully acquired and loaded Sep 9 05:37:46.623117 kernel: ACPI: Interpreter enabled Sep 9 05:37:46.623135 kernel: ACPI: PM: (supports S0 S3 S5) Sep 9 05:37:46.623153 kernel: ACPI: Using IOAPIC for interrupt routing Sep 9 05:37:46.623171 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Sep 9 05:37:46.623189 kernel: PCI: Ignoring E820 reservations for host bridge windows Sep 9 05:37:46.623212 kernel: ACPI: Enabled 16 GPEs in block 00 to 0F Sep 9 05:37:46.623231 kernel: ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) Sep 9 05:37:46.623527 kernel: acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI HPX-Type3] Sep 9 05:37:46.623731 kernel: acpi PNP0A03:00: _OSC: not requesting OS control; OS requires [ExtendedConfig ASPM ClockPM MSI] Sep 9 05:37:46.624095 kernel: acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended configuration space under this bridge Sep 9 05:37:46.624123 kernel: PCI host bridge to bus 0000:00 Sep 9 05:37:46.624304 kernel: pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] Sep 9 05:37:46.624492 kernel: pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] Sep 9 05:37:46.624659 kernel: pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] Sep 9 05:37:46.624851 kernel: pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfefff window] Sep 9 05:37:46.625017 kernel: pci_bus 0000:00: root bus resource [bus 00-ff] Sep 9 05:37:46.625225 kernel: pci 0000:00:00.0: [8086:1237] type 00 class 0x060000 conventional PCI endpoint Sep 9 05:37:46.625465 kernel: pci 0000:00:01.0: [8086:7110] type 00 class 0x060100 conventional PCI endpoint Sep 9 05:37:46.625668 kernel: pci 0000:00:01.3: [8086:7113] type 00 class 0x068000 conventional PCI endpoint Sep 9 05:37:46.625892 kernel: pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI Sep 9 05:37:46.626101 kernel: pci 0000:00:03.0: [1af4:1004] type 00 class 0x000000 conventional PCI endpoint Sep 9 05:37:46.626296 kernel: pci 0000:00:03.0: BAR 0 [io 0xc040-0xc07f] Sep 9 05:37:46.626496 kernel: pci 0000:00:03.0: BAR 1 [mem 0xc0001000-0xc000107f] Sep 9 05:37:46.626695 kernel: pci 0000:00:04.0: [1af4:1000] type 00 class 0x020000 conventional PCI endpoint Sep 9 05:37:46.626952 kernel: pci 0000:00:04.0: BAR 0 [io 0xc000-0xc03f] Sep 9 05:37:46.627158 kernel: pci 0000:00:04.0: BAR 1 [mem 0xc0000000-0xc000007f] Sep 9 05:37:46.627362 kernel: pci 0000:00:05.0: [1af4:1005] type 00 class 0x00ff00 conventional PCI endpoint Sep 9 05:37:46.627558 kernel: pci 0000:00:05.0: BAR 0 [io 0xc080-0xc09f] Sep 9 05:37:46.627779 kernel: pci 0000:00:05.0: BAR 1 [mem 0xc0002000-0xc000203f] Sep 9 05:37:46.627806 kernel: ACPI: PCI: Interrupt link LNKA configured for IRQ 10 Sep 9 05:37:46.627826 kernel: ACPI: PCI: Interrupt link LNKB configured for IRQ 10 Sep 9 05:37:46.627850 kernel: ACPI: PCI: Interrupt link LNKC configured for IRQ 11 Sep 9 05:37:46.627869 kernel: ACPI: PCI: Interrupt link LNKD configured for IRQ 11 Sep 9 05:37:46.627888 kernel: ACPI: PCI: Interrupt link LNKS configured for IRQ 9 Sep 9 05:37:46.627907 kernel: iommu: Default domain type: Translated Sep 9 05:37:46.627926 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Sep 9 05:37:46.627945 kernel: efivars: Registered efivars operations Sep 9 05:37:46.627963 kernel: PCI: Using ACPI for IRQ routing Sep 9 05:37:46.627982 kernel: PCI: pci_cache_line_size set to 64 bytes Sep 9 05:37:46.628001 kernel: e820: reserve RAM buffer [mem 0x00055000-0x0005ffff] Sep 9 05:37:46.628023 kernel: e820: reserve RAM buffer [mem 0x00098000-0x0009ffff] Sep 9 05:37:46.628041 kernel: e820: reserve RAM buffer [mem 0xbd32a000-0xbfffffff] Sep 9 05:37:46.628059 kernel: e820: reserve RAM buffer [mem 0xbf8ed000-0xbfffffff] Sep 9 05:37:46.628076 kernel: e820: reserve RAM buffer [mem 0xbffe0000-0xbfffffff] Sep 9 05:37:46.628092 kernel: vgaarb: loaded Sep 9 05:37:46.628110 kernel: clocksource: Switched to clocksource kvm-clock Sep 9 05:37:46.628129 kernel: VFS: Disk quotas dquot_6.6.0 Sep 9 05:37:46.628148 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Sep 9 05:37:46.628166 kernel: pnp: PnP ACPI init Sep 9 05:37:46.628184 kernel: pnp: PnP ACPI: found 7 devices Sep 9 05:37:46.628216 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Sep 9 05:37:46.628234 kernel: NET: Registered PF_INET protocol family Sep 9 05:37:46.628253 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) Sep 9 05:37:46.628273 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) Sep 9 05:37:46.628292 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Sep 9 05:37:46.628310 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) Sep 9 05:37:46.628329 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Sep 9 05:37:46.628348 kernel: TCP: Hash tables configured (established 65536 bind 65536) Sep 9 05:37:46.628367 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) Sep 9 05:37:46.628391 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) Sep 9 05:37:46.628419 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Sep 9 05:37:46.628438 kernel: NET: Registered PF_XDP protocol family Sep 9 05:37:46.628625 kernel: pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7 window] Sep 9 05:37:46.628813 kernel: pci_bus 0000:00: resource 5 [io 0x0d00-0xffff window] Sep 9 05:37:46.628980 kernel: pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window] Sep 9 05:37:46.629146 kernel: pci_bus 0000:00: resource 7 [mem 0xc0000000-0xfebfefff window] Sep 9 05:37:46.629336 kernel: pci 0000:00:00.0: Limiting direct PCI/PCI transfers Sep 9 05:37:46.629367 kernel: PCI: CLS 0 bytes, default 64 Sep 9 05:37:46.629387 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Sep 9 05:37:46.629413 kernel: software IO TLB: mapped [mem 0x00000000b7f7f000-0x00000000bbf7f000] (64MB) Sep 9 05:37:46.629433 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Sep 9 05:37:46.629453 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212733415c7, max_idle_ns: 440795236380 ns Sep 9 05:37:46.629470 kernel: clocksource: Switched to clocksource tsc Sep 9 05:37:46.629488 kernel: Initialise system trusted keyrings Sep 9 05:37:46.629506 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0 Sep 9 05:37:46.629528 kernel: Key type asymmetric registered Sep 9 05:37:46.629546 kernel: Asymmetric key parser 'x509' registered Sep 9 05:37:46.629563 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Sep 9 05:37:46.629594 kernel: io scheduler mq-deadline registered Sep 9 05:37:46.629614 kernel: io scheduler kyber registered Sep 9 05:37:46.629633 kernel: io scheduler bfq registered Sep 9 05:37:46.629652 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Sep 9 05:37:46.629672 kernel: ACPI: \_SB_.LNKC: Enabled at IRQ 11 Sep 9 05:37:46.629899 kernel: virtio-pci 0000:00:03.0: virtio_pci: leaving for legacy driver Sep 9 05:37:46.629929 kernel: ACPI: \_SB_.LNKD: Enabled at IRQ 10 Sep 9 05:37:46.630105 kernel: virtio-pci 0000:00:04.0: virtio_pci: leaving for legacy driver Sep 9 05:37:46.630128 kernel: ACPI: \_SB_.LNKA: Enabled at IRQ 10 Sep 9 05:37:46.630304 kernel: virtio-pci 0000:00:05.0: virtio_pci: leaving for legacy driver Sep 9 05:37:46.630327 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Sep 9 05:37:46.630345 kernel: 00:03: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Sep 9 05:37:46.630363 kernel: 00:04: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Sep 9 05:37:46.630380 kernel: 00:05: ttyS2 at I/O 0x3e8 (irq = 6, base_baud = 115200) is a 16550A Sep 9 05:37:46.630407 kernel: 00:06: ttyS3 at I/O 0x2e8 (irq = 7, base_baud = 115200) is a 16550A Sep 9 05:37:46.630600 kernel: tpm_tis MSFT0101:00: 2.0 TPM (device-id 0x9009, rev-id 0) Sep 9 05:37:46.630625 kernel: i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 Sep 9 05:37:46.630644 kernel: i8042: Warning: Keylock active Sep 9 05:37:46.630661 kernel: serio: i8042 KBD port at 0x60,0x64 irq 1 Sep 9 05:37:46.630679 kernel: serio: i8042 AUX port at 0x60,0x64 irq 12 Sep 9 05:37:46.630909 kernel: rtc_cmos 00:00: RTC can wake from S4 Sep 9 05:37:46.631115 kernel: rtc_cmos 00:00: registered as rtc0 Sep 9 05:37:46.631299 kernel: rtc_cmos 00:00: setting system clock to 2025-09-09T05:37:45 UTC (1757396265) Sep 9 05:37:46.631485 kernel: rtc_cmos 00:00: alarms up to one day, 114 bytes nvram Sep 9 05:37:46.631509 kernel: intel_pstate: CPU model not supported Sep 9 05:37:46.631526 kernel: pstore: Using crash dump compression: deflate Sep 9 05:37:46.631544 kernel: pstore: Registered efi_pstore as persistent store backend Sep 9 05:37:46.631561 kernel: NET: Registered PF_INET6 protocol family Sep 9 05:37:46.631577 kernel: Segment Routing with IPv6 Sep 9 05:37:46.631595 kernel: In-situ OAM (IOAM) with IPv6 Sep 9 05:37:46.631614 kernel: NET: Registered PF_PACKET protocol family Sep 9 05:37:46.631637 kernel: Key type dns_resolver registered Sep 9 05:37:46.631654 kernel: IPI shorthand broadcast: enabled Sep 9 05:37:46.631672 kernel: sched_clock: Marking stable (3458004169, 140992247)->(3620678081, -21681665) Sep 9 05:37:46.631690 kernel: registered taskstats version 1 Sep 9 05:37:46.631707 kernel: Loading compiled-in X.509 certificates Sep 9 05:37:46.631725 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.45-flatcar: 884b9ad6a330f59ae6e6488b20a5491e41ff24a3' Sep 9 05:37:46.631759 kernel: Demotion targets for Node 0: null Sep 9 05:37:46.631787 kernel: Key type .fscrypt registered Sep 9 05:37:46.631811 kernel: Key type fscrypt-provisioning registered Sep 9 05:37:46.631835 kernel: ima: Allocated hash algorithm: sha1 Sep 9 05:37:46.631853 kernel: ima: No architecture policies found Sep 9 05:37:46.631873 kernel: input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input0 Sep 9 05:37:46.631891 kernel: clk: Disabling unused clocks Sep 9 05:37:46.631910 kernel: Warning: unable to open an initial console. Sep 9 05:37:46.631929 kernel: Freeing unused kernel image (initmem) memory: 54076K Sep 9 05:37:46.631947 kernel: Write protecting the kernel read-only data: 24576k Sep 9 05:37:46.631963 kernel: Freeing unused kernel image (rodata/data gap) memory: 252K Sep 9 05:37:46.631982 kernel: Run /init as init process Sep 9 05:37:46.632002 kernel: with arguments: Sep 9 05:37:46.632021 kernel: /init Sep 9 05:37:46.632042 kernel: with environment: Sep 9 05:37:46.632065 kernel: HOME=/ Sep 9 05:37:46.632090 kernel: TERM=linux Sep 9 05:37:46.632113 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Sep 9 05:37:46.632133 systemd[1]: Successfully made /usr/ read-only. Sep 9 05:37:46.632161 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 9 05:37:46.632180 systemd[1]: Detected virtualization google. Sep 9 05:37:46.632203 systemd[1]: Detected architecture x86-64. Sep 9 05:37:46.632223 systemd[1]: Running in initrd. Sep 9 05:37:46.632243 systemd[1]: No hostname configured, using default hostname. Sep 9 05:37:46.632264 systemd[1]: Hostname set to . Sep 9 05:37:46.632284 systemd[1]: Initializing machine ID from random generator. Sep 9 05:37:46.632303 systemd[1]: Queued start job for default target initrd.target. Sep 9 05:37:46.632351 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:37:46.632379 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:37:46.632415 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Sep 9 05:37:46.632437 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:37:46.632458 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Sep 9 05:37:46.632486 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Sep 9 05:37:46.632508 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Sep 9 05:37:46.632530 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Sep 9 05:37:46.632552 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:37:46.632574 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:37:46.632600 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:37:46.632623 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:37:46.632644 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:37:46.632666 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:37:46.632686 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:37:46.632706 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:37:46.632727 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Sep 9 05:37:46.642502 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Sep 9 05:37:46.642534 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:37:46.642556 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:37:46.642577 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:37:46.642606 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:37:46.642627 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Sep 9 05:37:46.642648 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:37:46.642668 systemd[1]: Finished network-cleanup.service - Network Cleanup. Sep 9 05:37:46.642690 systemd[1]: systemd-battery-check.service - Check battery level during early boot was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/power_supply). Sep 9 05:37:46.642711 systemd[1]: Starting systemd-fsck-usr.service... Sep 9 05:37:46.642732 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:37:46.642769 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:37:46.642789 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:37:46.642858 systemd-journald[207]: Collecting audit messages is disabled. Sep 9 05:37:46.642900 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Sep 9 05:37:46.642926 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:37:46.642947 systemd-journald[207]: Journal started Sep 9 05:37:46.642987 systemd-journald[207]: Runtime Journal (/run/log/journal/a5ae39c0b3744127a60abb792747143d) is 8M, max 148.9M, 140.9M free. Sep 9 05:37:46.607236 systemd-modules-load[208]: Inserted module 'overlay' Sep 9 05:37:46.653777 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:37:46.654262 systemd[1]: Finished systemd-fsck-usr.service. Sep 9 05:37:46.658914 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Sep 9 05:37:46.660892 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:37:46.688782 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Sep 9 05:37:46.697086 systemd-modules-load[208]: Inserted module 'br_netfilter' Sep 9 05:37:46.748062 kernel: Bridge firewalling registered Sep 9 05:37:46.698829 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:37:46.705919 systemd-tmpfiles[219]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Sep 9 05:37:46.757541 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:37:46.776332 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Sep 9 05:37:46.789463 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:37:46.811207 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Sep 9 05:37:46.839959 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:37:46.859966 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:37:46.887790 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:37:46.895017 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:37:46.904868 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:37:46.915255 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:37:46.927802 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Sep 9 05:37:46.956346 systemd-resolved[236]: Positive Trust Anchors: Sep 9 05:37:46.956403 systemd-resolved[236]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:37:46.956470 systemd-resolved[236]: 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 Sep 9 05:37:47.046240 dracut-cmdline[246]: Using kernel command line parameters: rd.driver.pre=btrfs SYSTEMD_SULOGIN_FORCE=1 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 flatcar.first_boot=detected flatcar.oem.id=gce verity.usrhash=107bc9be805328e5e30844239fa87d36579f371e3de2c34fec43f6ff6d17b104 Sep 9 05:37:47.092940 kernel: SCSI subsystem initialized Sep 9 05:37:46.959882 systemd-resolved[236]: Defaulting to hostname 'linux'. Sep 9 05:37:46.961198 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:37:47.114913 kernel: Loading iSCSI transport class v2.0-870. Sep 9 05:37:46.984002 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:37:47.129927 kernel: iscsi: registered transport (tcp) Sep 9 05:37:47.157928 kernel: iscsi: registered transport (qla4xxx) Sep 9 05:37:47.158014 kernel: QLogic iSCSI HBA Driver Sep 9 05:37:47.181507 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:37:47.217522 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:37:47.240971 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:37:47.314412 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Sep 9 05:37:47.325889 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Sep 9 05:37:47.405802 kernel: raid6: avx2x4 gen() 18155 MB/s Sep 9 05:37:47.426787 kernel: raid6: avx2x2 gen() 18249 MB/s Sep 9 05:37:47.452795 kernel: raid6: avx2x1 gen() 14102 MB/s Sep 9 05:37:47.452881 kernel: raid6: using algorithm avx2x2 gen() 18249 MB/s Sep 9 05:37:47.479872 kernel: raid6: .... xor() 18289 MB/s, rmw enabled Sep 9 05:37:47.479961 kernel: raid6: using avx2x2 recovery algorithm Sep 9 05:37:47.509791 kernel: xor: automatically using best checksumming function avx Sep 9 05:37:47.697779 kernel: Btrfs loaded, zoned=no, fsverity=no Sep 9 05:37:47.706682 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:37:47.718186 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:37:47.779386 systemd-udevd[454]: Using default interface naming scheme 'v255'. Sep 9 05:37:47.788618 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:37:47.811018 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Sep 9 05:37:47.862012 dracut-pre-trigger[465]: rd.md=0: removing MD RAID activation Sep 9 05:37:47.896260 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:37:47.916994 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:37:48.018121 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:37:48.032049 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Sep 9 05:37:48.125848 kernel: virtio_scsi virtio0: 1/0/0 default/read/poll queues Sep 9 05:37:48.137775 kernel: scsi host0: Virtio SCSI HBA Sep 9 05:37:48.150734 kernel: scsi 0:0:1:0: Direct-Access Google PersistentDisk 1 PQ: 0 ANSI: 6 Sep 9 05:37:48.172768 kernel: cryptd: max_cpu_qlen set to 1000 Sep 9 05:37:48.205781 kernel: AES CTR mode by8 optimization enabled Sep 9 05:37:48.228909 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:37:48.257172 kernel: input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 Sep 9 05:37:48.229118 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:37:48.267071 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:37:48.296812 kernel: sd 0:0:1:0: [sda] 25165824 512-byte logical blocks: (12.9 GB/12.0 GiB) Sep 9 05:37:48.297136 kernel: sd 0:0:1:0: [sda] 4096-byte physical blocks Sep 9 05:37:48.304776 kernel: sd 0:0:1:0: [sda] Write Protect is off Sep 9 05:37:48.305079 kernel: sd 0:0:1:0: [sda] Mode Sense: 1f 00 00 08 Sep 9 05:37:48.305308 kernel: sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA Sep 9 05:37:48.320978 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:37:48.350653 kernel: GPT:Primary header thinks Alt. header is not at the end of the disk. Sep 9 05:37:48.350711 kernel: GPT:17805311 != 25165823 Sep 9 05:37:48.350739 kernel: GPT:Alternate GPT header not at the end of the disk. Sep 9 05:37:48.350773 kernel: GPT:17805311 != 25165823 Sep 9 05:37:48.350804 kernel: GPT: Use GNU Parted to correct GPT errors. Sep 9 05:37:48.356783 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:37:48.367788 kernel: sd 0:0:1:0: [sda] Attached SCSI disk Sep 9 05:37:48.376596 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:37:48.410437 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:37:48.468686 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - PersistentDisk EFI-SYSTEM. Sep 9 05:37:48.469368 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Sep 9 05:37:48.519955 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - PersistentDisk ROOT. Sep 9 05:37:48.532322 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. Sep 9 05:37:48.549528 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - PersistentDisk USR-A. Sep 9 05:37:48.557124 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - PersistentDisk USR-A. Sep 9 05:37:48.577248 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:37:48.619938 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:37:48.620213 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:37:48.640225 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Sep 9 05:37:48.666106 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Sep 9 05:37:48.692439 disk-uuid[608]: Primary Header is updated. Sep 9 05:37:48.692439 disk-uuid[608]: Secondary Entries is updated. Sep 9 05:37:48.692439 disk-uuid[608]: Secondary Header is updated. Sep 9 05:37:48.718798 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:37:48.729431 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:37:49.758848 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Sep 9 05:37:49.758933 disk-uuid[609]: The operation has completed successfully. Sep 9 05:37:49.830346 systemd[1]: disk-uuid.service: Deactivated successfully. Sep 9 05:37:49.830526 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Sep 9 05:37:49.886558 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Sep 9 05:37:49.919132 sh[630]: Success Sep 9 05:37:49.956315 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Sep 9 05:37:49.956406 kernel: device-mapper: uevent: version 1.0.3 Sep 9 05:37:49.956435 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Sep 9 05:37:49.982770 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Sep 9 05:37:50.055014 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Sep 9 05:37:50.059863 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Sep 9 05:37:50.100945 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Sep 9 05:37:50.140729 kernel: BTRFS: device fsid 9ca60a92-6b53-4529-adc0-1f4392d2ad56 devid 1 transid 37 /dev/mapper/usr (254:0) scanned by mount (642) Sep 9 05:37:50.140790 kernel: BTRFS info (device dm-0): first mount of filesystem 9ca60a92-6b53-4529-adc0-1f4392d2ad56 Sep 9 05:37:50.140815 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:37:50.181038 kernel: BTRFS info (device dm-0): enabling ssd optimizations Sep 9 05:37:50.181134 kernel: BTRFS info (device dm-0): disabling log replay at mount time Sep 9 05:37:50.181181 kernel: BTRFS info (device dm-0): enabling free space tree Sep 9 05:37:50.191375 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Sep 9 05:37:50.199626 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:37:50.206195 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Sep 9 05:37:50.207260 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Sep 9 05:37:50.224070 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Sep 9 05:37:50.284919 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (665) Sep 9 05:37:50.302273 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:37:50.302353 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:37:50.318850 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 9 05:37:50.318930 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:37:50.318955 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:37:50.339995 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:37:50.341659 systemd[1]: Finished ignition-setup.service - Ignition (setup). Sep 9 05:37:50.361026 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Sep 9 05:37:50.490983 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:37:50.524974 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:37:50.602968 ignition[729]: Ignition 2.22.0 Sep 9 05:37:50.602991 ignition[729]: Stage: fetch-offline Sep 9 05:37:50.607279 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:37:50.603035 ignition[729]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:50.623308 systemd-networkd[811]: lo: Link UP Sep 9 05:37:50.603048 ignition[729]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:50.623314 systemd-networkd[811]: lo: Gained carrier Sep 9 05:37:50.603189 ignition[729]: parsed url from cmdline: "" Sep 9 05:37:50.625294 systemd-networkd[811]: Enumeration completed Sep 9 05:37:50.603197 ignition[729]: no config URL provided Sep 9 05:37:50.625987 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:37:50.603207 ignition[729]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:37:50.626090 systemd-networkd[811]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:37:50.603220 ignition[729]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:37:50.626096 systemd-networkd[811]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:37:50.603232 ignition[729]: failed to fetch config: resource requires networking Sep 9 05:37:50.627409 systemd-networkd[811]: eth0: Link UP Sep 9 05:37:50.603610 ignition[729]: Ignition finished successfully Sep 9 05:37:50.627612 systemd-networkd[811]: eth0: Gained carrier Sep 9 05:37:50.725285 ignition[821]: Ignition 2.22.0 Sep 9 05:37:50.627629 systemd-networkd[811]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:37:50.725294 ignition[821]: Stage: fetch Sep 9 05:37:50.634216 systemd[1]: Reached target network.target - Network. Sep 9 05:37:50.725447 ignition[821]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:50.652840 systemd-networkd[811]: eth0: Overlong DHCP hostname received, shortened from 'ci-4452-0-0-nightly-20250908-2100-c026c183201b1c7616f3.c.flatcar-212911.internal' to 'ci-4452-0-0-nightly-20250908-2100-c026c183201b1c7616f3' Sep 9 05:37:50.725458 ignition[821]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:50.652857 systemd-networkd[811]: eth0: DHCPv4 address 10.128.0.20/32, gateway 10.128.0.1 acquired from 169.254.169.254 Sep 9 05:37:50.725572 ignition[821]: parsed url from cmdline: "" Sep 9 05:37:50.658965 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Sep 9 05:37:50.725577 ignition[821]: no config URL provided Sep 9 05:37:50.737355 unknown[821]: fetched base config from "system" Sep 9 05:37:50.725583 ignition[821]: reading system config file "/usr/lib/ignition/user.ign" Sep 9 05:37:50.737381 unknown[821]: fetched base config from "system" Sep 9 05:37:50.725593 ignition[821]: no config at "/usr/lib/ignition/user.ign" Sep 9 05:37:50.737393 unknown[821]: fetched user config from "gcp" Sep 9 05:37:50.725625 ignition[821]: GET http://169.254.169.254/computeMetadata/v1/instance/attributes/user-data: attempt #1 Sep 9 05:37:50.740596 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Sep 9 05:37:50.729291 ignition[821]: GET result: OK Sep 9 05:37:50.747509 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Sep 9 05:37:50.729343 ignition[821]: parsing config with SHA512: dbded1e92ea23d8614601be9de9eb522aaa001748de418a9430746cdd252523adab7d4733544c932bcc9187beb8f9979560fde26605023b63df6a754ea5d7960 Sep 9 05:37:50.805927 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Sep 9 05:37:50.737647 ignition[821]: fetch: fetch complete Sep 9 05:37:50.821598 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Sep 9 05:37:50.737657 ignition[821]: fetch: fetch passed Sep 9 05:37:50.886028 systemd[1]: Finished ignition-disks.service - Ignition (disks). Sep 9 05:37:50.737726 ignition[821]: Ignition finished successfully Sep 9 05:37:50.899158 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Sep 9 05:37:50.802453 ignition[827]: Ignition 2.22.0 Sep 9 05:37:50.920966 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Sep 9 05:37:50.802463 ignition[827]: Stage: kargs Sep 9 05:37:50.937991 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:37:50.802646 ignition[827]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:50.950979 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:37:50.802658 ignition[827]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:50.964957 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:37:50.803603 ignition[827]: kargs: kargs passed Sep 9 05:37:50.982245 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Sep 9 05:37:50.803667 ignition[827]: Ignition finished successfully Sep 9 05:37:50.883633 ignition[834]: Ignition 2.22.0 Sep 9 05:37:50.883641 ignition[834]: Stage: disks Sep 9 05:37:50.883851 ignition[834]: no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:50.883865 ignition[834]: no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:50.884466 ignition[834]: disks: disks passed Sep 9 05:37:50.884518 ignition[834]: Ignition finished successfully Sep 9 05:37:51.059507 systemd-fsck[843]: ROOT: clean, 15/1628000 files, 120826/1617920 blocks Sep 9 05:37:51.197451 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Sep 9 05:37:51.219100 systemd[1]: Mounting sysroot.mount - /sysroot... Sep 9 05:37:51.405786 kernel: EXT4-fs (sda9): mounted filesystem d2d7815e-fa16-4396-ab9d-ac540c1d8856 r/w with ordered data mode. Quota mode: none. Sep 9 05:37:51.406559 systemd[1]: Mounted sysroot.mount - /sysroot. Sep 9 05:37:51.414545 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Sep 9 05:37:51.432159 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:37:51.440775 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Sep 9 05:37:51.452796 systemd[1]: flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent was skipped because no trigger condition checks were met. Sep 9 05:37:51.533138 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (851) Sep 9 05:37:51.533180 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:37:51.533196 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:37:51.533211 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 9 05:37:51.533226 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:37:51.533252 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:37:51.452884 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Sep 9 05:37:51.452931 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:37:51.485830 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Sep 9 05:37:51.542638 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:37:51.566130 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Sep 9 05:37:51.710981 initrd-setup-root[875]: cut: /sysroot/etc/passwd: No such file or directory Sep 9 05:37:51.721229 initrd-setup-root[882]: cut: /sysroot/etc/group: No such file or directory Sep 9 05:37:51.729900 initrd-setup-root[889]: cut: /sysroot/etc/shadow: No such file or directory Sep 9 05:37:51.738935 initrd-setup-root[896]: cut: /sysroot/etc/gshadow: No such file or directory Sep 9 05:37:51.862637 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Sep 9 05:37:51.873783 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Sep 9 05:37:51.888957 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Sep 9 05:37:51.924812 kernel: BTRFS info (device sda6): last unmount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:37:51.926539 systemd[1]: sysroot-oem.mount: Deactivated successfully. Sep 9 05:37:51.948708 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Sep 9 05:37:51.971622 ignition[964]: INFO : Ignition 2.22.0 Sep 9 05:37:51.977924 ignition[964]: INFO : Stage: mount Sep 9 05:37:51.977924 ignition[964]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:51.977924 ignition[964]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:51.977924 ignition[964]: INFO : mount: mount passed Sep 9 05:37:51.977924 ignition[964]: INFO : Ignition finished successfully Sep 9 05:37:51.975164 systemd[1]: Finished ignition-mount.service - Ignition (mount). Sep 9 05:37:51.979498 systemd[1]: Starting ignition-files.service - Ignition (files)... Sep 9 05:37:52.353993 systemd-networkd[811]: eth0: Gained IPv6LL Sep 9 05:37:52.408789 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Sep 9 05:37:52.450788 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (977) Sep 9 05:37:52.468346 kernel: BTRFS info (device sda6): first mount of filesystem d4e5a7a8-c50a-463e-827d-ca249a0b8b8b Sep 9 05:37:52.468428 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Sep 9 05:37:52.484232 kernel: BTRFS info (device sda6): enabling ssd optimizations Sep 9 05:37:52.484321 kernel: BTRFS info (device sda6): turning on async discard Sep 9 05:37:52.484347 kernel: BTRFS info (device sda6): enabling free space tree Sep 9 05:37:52.492842 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Sep 9 05:37:52.536335 ignition[994]: INFO : Ignition 2.22.0 Sep 9 05:37:52.536335 ignition[994]: INFO : Stage: files Sep 9 05:37:52.548925 ignition[994]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:52.548925 ignition[994]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:52.548925 ignition[994]: DEBUG : files: compiled without relabeling support, skipping Sep 9 05:37:52.548925 ignition[994]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Sep 9 05:37:52.548925 ignition[994]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Sep 9 05:37:52.548925 ignition[994]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Sep 9 05:37:52.548925 ignition[994]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Sep 9 05:37:52.548925 ignition[994]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Sep 9 05:37:52.544909 unknown[994]: wrote ssh authorized keys file for user: core Sep 9 05:37:52.665044 ignition[994]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:37:52.665044 ignition[994]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Sep 9 05:37:52.665044 ignition[994]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:37:52.665044 ignition[994]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Sep 9 05:37:52.665044 ignition[994]: INFO : files: files passed Sep 9 05:37:52.665044 ignition[994]: INFO : Ignition finished successfully Sep 9 05:37:52.551643 systemd[1]: Finished ignition-files.service - Ignition (files). Sep 9 05:37:52.568252 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Sep 9 05:37:52.577959 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Sep 9 05:37:52.803922 initrd-setup-root-after-ignition[1022]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:37:52.803922 initrd-setup-root-after-ignition[1022]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:37:52.626414 systemd[1]: ignition-quench.service: Deactivated successfully. Sep 9 05:37:52.817100 initrd-setup-root-after-ignition[1026]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Sep 9 05:37:52.626542 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Sep 9 05:37:52.705322 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:37:52.729191 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Sep 9 05:37:52.752956 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Sep 9 05:37:52.877514 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Sep 9 05:37:52.877644 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Sep 9 05:37:52.888114 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Sep 9 05:37:52.906979 systemd[1]: Reached target initrd.target - Initrd Default Target. Sep 9 05:37:52.923075 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Sep 9 05:37:52.924256 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Sep 9 05:37:53.001681 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:37:53.022183 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Sep 9 05:37:53.063224 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:37:53.082088 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:37:53.101159 systemd[1]: Stopped target timers.target - Timer Units. Sep 9 05:37:53.101572 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Sep 9 05:37:53.101797 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Sep 9 05:37:53.133272 systemd[1]: Stopped target initrd.target - Initrd Default Target. Sep 9 05:37:53.143253 systemd[1]: Stopped target basic.target - Basic System. Sep 9 05:37:53.159255 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Sep 9 05:37:53.173275 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Sep 9 05:37:53.190299 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Sep 9 05:37:53.209311 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Sep 9 05:37:53.226325 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Sep 9 05:37:53.243357 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Sep 9 05:37:53.259372 systemd[1]: Stopped target sysinit.target - System Initialization. Sep 9 05:37:53.279317 systemd[1]: Stopped target local-fs.target - Local File Systems. Sep 9 05:37:53.311111 systemd[1]: Stopped target swap.target - Swaps. Sep 9 05:37:53.311453 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Sep 9 05:37:53.311669 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Sep 9 05:37:53.340327 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:37:53.349300 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:37:53.366247 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Sep 9 05:37:53.366424 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:37:53.385255 systemd[1]: dracut-initqueue.service: Deactivated successfully. Sep 9 05:37:53.385457 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Sep 9 05:37:53.422360 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Sep 9 05:37:53.422638 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Sep 9 05:37:53.430342 systemd[1]: ignition-files.service: Deactivated successfully. Sep 9 05:37:53.430528 systemd[1]: Stopped ignition-files.service - Ignition (files). Sep 9 05:37:53.449591 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Sep 9 05:37:53.465078 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Sep 9 05:37:53.465295 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:37:53.545923 ignition[1047]: INFO : Ignition 2.22.0 Sep 9 05:37:53.545923 ignition[1047]: INFO : Stage: umount Sep 9 05:37:53.545923 ignition[1047]: INFO : no configs at "/usr/lib/ignition/base.d" Sep 9 05:37:53.545923 ignition[1047]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/gcp" Sep 9 05:37:53.545923 ignition[1047]: INFO : umount: umount passed Sep 9 05:37:53.545923 ignition[1047]: INFO : Ignition finished successfully Sep 9 05:37:53.521291 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Sep 9 05:37:53.535898 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Sep 9 05:37:53.536165 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:37:53.562186 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Sep 9 05:37:53.562370 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Sep 9 05:37:53.590659 systemd[1]: sysroot-boot.mount: Deactivated successfully. Sep 9 05:37:53.592295 systemd[1]: ignition-mount.service: Deactivated successfully. Sep 9 05:37:53.592423 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Sep 9 05:37:53.606488 systemd[1]: sysroot-boot.service: Deactivated successfully. Sep 9 05:37:53.606610 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Sep 9 05:37:53.626098 systemd[1]: initrd-cleanup.service: Deactivated successfully. Sep 9 05:37:53.626242 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Sep 9 05:37:53.639717 systemd[1]: ignition-disks.service: Deactivated successfully. Sep 9 05:37:53.639893 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Sep 9 05:37:53.655041 systemd[1]: ignition-kargs.service: Deactivated successfully. Sep 9 05:37:53.655126 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Sep 9 05:37:53.673013 systemd[1]: ignition-fetch.service: Deactivated successfully. Sep 9 05:37:53.673111 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Sep 9 05:37:53.690997 systemd[1]: Stopped target network.target - Network. Sep 9 05:37:53.706909 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Sep 9 05:37:53.707026 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Sep 9 05:37:53.725970 systemd[1]: Stopped target paths.target - Path Units. Sep 9 05:37:53.739909 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Sep 9 05:37:53.743874 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:37:53.758904 systemd[1]: Stopped target slices.target - Slice Units. Sep 9 05:37:53.772966 systemd[1]: Stopped target sockets.target - Socket Units. Sep 9 05:37:53.787028 systemd[1]: iscsid.socket: Deactivated successfully. Sep 9 05:37:53.787120 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Sep 9 05:37:53.802975 systemd[1]: iscsiuio.socket: Deactivated successfully. Sep 9 05:37:53.803065 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Sep 9 05:37:53.818981 systemd[1]: ignition-setup.service: Deactivated successfully. Sep 9 05:37:53.819100 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Sep 9 05:37:53.838003 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Sep 9 05:37:53.838096 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Sep 9 05:37:53.854007 systemd[1]: initrd-setup-root.service: Deactivated successfully. Sep 9 05:37:53.854120 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Sep 9 05:37:53.872201 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Sep 9 05:37:53.890102 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Sep 9 05:37:53.908477 systemd[1]: systemd-resolved.service: Deactivated successfully. Sep 9 05:37:53.908614 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Sep 9 05:37:53.927292 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Sep 9 05:37:53.927572 systemd[1]: systemd-networkd.service: Deactivated successfully. Sep 9 05:37:53.927722 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Sep 9 05:37:53.943475 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Sep 9 05:37:53.945440 systemd[1]: Stopped target network-pre.target - Preparation for Network. Sep 9 05:37:53.957950 systemd[1]: systemd-networkd.socket: Deactivated successfully. Sep 9 05:37:53.958032 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:37:53.969261 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Sep 9 05:37:53.995906 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Sep 9 05:37:53.996035 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Sep 9 05:37:54.013026 systemd[1]: systemd-sysctl.service: Deactivated successfully. Sep 9 05:37:54.437926 systemd-journald[207]: Received SIGTERM from PID 1 (systemd). Sep 9 05:37:54.013130 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:37:54.022183 systemd[1]: systemd-modules-load.service: Deactivated successfully. Sep 9 05:37:54.022257 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Sep 9 05:37:54.031164 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Sep 9 05:37:54.031242 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:37:54.051399 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:37:54.079363 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Sep 9 05:37:54.079576 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:37:54.080140 systemd[1]: systemd-udevd.service: Deactivated successfully. Sep 9 05:37:54.080308 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:37:54.097352 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Sep 9 05:37:54.097493 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Sep 9 05:37:54.102135 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Sep 9 05:37:54.102184 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:37:54.118153 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Sep 9 05:37:54.118232 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Sep 9 05:37:54.167010 systemd[1]: dracut-cmdline.service: Deactivated successfully. Sep 9 05:37:54.167117 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Sep 9 05:37:54.194099 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Sep 9 05:37:54.194304 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Sep 9 05:37:54.222210 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Sep 9 05:37:54.238910 systemd[1]: systemd-network-generator.service: Deactivated successfully. Sep 9 05:37:54.239034 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:37:54.258164 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Sep 9 05:37:54.258272 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:37:54.269225 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Sep 9 05:37:54.269314 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:37:54.288734 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Sep 9 05:37:54.288833 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Sep 9 05:37:54.288882 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Sep 9 05:37:54.289350 systemd[1]: network-cleanup.service: Deactivated successfully. Sep 9 05:37:54.289471 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Sep 9 05:37:54.305418 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Sep 9 05:37:54.305537 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Sep 9 05:37:54.326253 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Sep 9 05:37:54.344061 systemd[1]: Starting initrd-switch-root.service - Switch Root... Sep 9 05:37:54.385535 systemd[1]: Switching root. Sep 9 05:37:54.440496 systemd-journald[207]: Journal stopped Sep 9 05:37:56.782217 kernel: SELinux: policy capability network_peer_controls=1 Sep 9 05:37:56.782273 kernel: SELinux: policy capability open_perms=1 Sep 9 05:37:56.782295 kernel: SELinux: policy capability extended_socket_class=1 Sep 9 05:37:56.782313 kernel: SELinux: policy capability always_check_network=0 Sep 9 05:37:56.782330 kernel: SELinux: policy capability cgroup_seclabel=1 Sep 9 05:37:56.782348 kernel: SELinux: policy capability nnp_nosuid_transition=1 Sep 9 05:37:56.782372 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Sep 9 05:37:56.782391 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Sep 9 05:37:56.782409 kernel: SELinux: policy capability userspace_initial_context=0 Sep 9 05:37:56.782427 kernel: audit: type=1403 audit(1757396274.623:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Sep 9 05:37:56.782449 systemd[1]: Successfully loaded SELinux policy in 120.085ms. Sep 9 05:37:56.782471 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 8.007ms. Sep 9 05:37:56.782493 systemd[1]: systemd 256.8 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP -GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL +ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBCRYPTSETUP_PLUGINS +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE +TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE) Sep 9 05:37:56.782517 systemd[1]: Detected virtualization google. Sep 9 05:37:56.782539 systemd[1]: Detected architecture x86-64. Sep 9 05:37:56.782559 systemd[1]: Detected first boot. Sep 9 05:37:56.782581 systemd[1]: Initializing machine ID from random generator. Sep 9 05:37:56.782601 kernel: Guest personality initialized and is inactive Sep 9 05:37:56.782623 kernel: VMCI host device registered (name=vmci, major=10, minor=125) Sep 9 05:37:56.782642 kernel: Initialized host personality Sep 9 05:37:56.782661 kernel: NET: Registered PF_VSOCK protocol family Sep 9 05:37:56.782682 zram_generator::config[1090]: No configuration found. Sep 9 05:37:56.782704 systemd[1]: Populated /etc with preset unit settings. Sep 9 05:37:56.782726 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Sep 9 05:37:56.784789 systemd[1]: initrd-switch-root.service: Deactivated successfully. Sep 9 05:37:56.784842 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Sep 9 05:37:56.784864 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Sep 9 05:37:56.784887 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Sep 9 05:37:56.784908 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Sep 9 05:37:56.784931 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Sep 9 05:37:56.784951 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Sep 9 05:37:56.784973 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Sep 9 05:37:56.785001 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Sep 9 05:37:56.785023 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Sep 9 05:37:56.785044 systemd[1]: Created slice user.slice - User and Session Slice. Sep 9 05:37:56.785065 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Sep 9 05:37:56.785087 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Sep 9 05:37:56.785109 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Sep 9 05:37:56.785130 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Sep 9 05:37:56.785152 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Sep 9 05:37:56.785179 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Sep 9 05:37:56.785204 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Sep 9 05:37:56.785226 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Sep 9 05:37:56.785258 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Sep 9 05:37:56.785287 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Sep 9 05:37:56.785308 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Sep 9 05:37:56.785330 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Sep 9 05:37:56.785352 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Sep 9 05:37:56.785377 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Sep 9 05:37:56.785399 systemd[1]: Reached target remote-fs.target - Remote File Systems. Sep 9 05:37:56.785420 systemd[1]: Reached target slices.target - Slice Units. Sep 9 05:37:56.785442 systemd[1]: Reached target swap.target - Swaps. Sep 9 05:37:56.785463 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Sep 9 05:37:56.785490 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Sep 9 05:37:56.785512 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Sep 9 05:37:56.785539 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Sep 9 05:37:56.785562 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Sep 9 05:37:56.785584 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Sep 9 05:37:56.785606 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Sep 9 05:37:56.785628 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Sep 9 05:37:56.785650 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Sep 9 05:37:56.785676 systemd[1]: Mounting media.mount - External Media Directory... Sep 9 05:37:56.785698 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:56.785721 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Sep 9 05:37:56.785756 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Sep 9 05:37:56.785779 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Sep 9 05:37:56.785802 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). Sep 9 05:37:56.785824 systemd[1]: Reached target machines.target - Containers. Sep 9 05:37:56.785847 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Sep 9 05:37:56.785874 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:37:56.785896 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Sep 9 05:37:56.785918 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Sep 9 05:37:56.785940 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:37:56.785962 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:37:56.785985 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:37:56.786007 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Sep 9 05:37:56.786029 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:37:56.786052 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Sep 9 05:37:56.786078 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Sep 9 05:37:56.786100 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Sep 9 05:37:56.786122 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Sep 9 05:37:56.786144 kernel: ACPI: bus type drm_connector registered Sep 9 05:37:56.786164 systemd[1]: Stopped systemd-fsck-usr.service. Sep 9 05:37:56.786186 kernel: fuse: init (API version 7.41) Sep 9 05:37:56.786208 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 05:37:56.786229 kernel: loop: module loaded Sep 9 05:37:56.786261 systemd[1]: Starting systemd-journald.service - Journal Service... Sep 9 05:37:56.786284 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Sep 9 05:37:56.786306 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Sep 9 05:37:56.786372 systemd-journald[1178]: Collecting audit messages is disabled. Sep 9 05:37:56.786421 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Sep 9 05:37:56.786445 systemd-journald[1178]: Journal started Sep 9 05:37:56.786488 systemd-journald[1178]: Runtime Journal (/run/log/journal/cdbd5e51a82542589defcb9240947082) is 8M, max 148.9M, 140.9M free. Sep 9 05:37:55.576129 systemd[1]: Queued start job for default target multi-user.target. Sep 9 05:37:55.596628 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Sep 9 05:37:55.597307 systemd[1]: systemd-journald.service: Deactivated successfully. Sep 9 05:37:56.823798 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Sep 9 05:37:56.844816 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Sep 9 05:37:56.861782 systemd[1]: verity-setup.service: Deactivated successfully. Sep 9 05:37:56.868777 systemd[1]: Stopped verity-setup.service. Sep 9 05:37:56.892785 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:56.904802 systemd[1]: Started systemd-journald.service - Journal Service. Sep 9 05:37:56.914486 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Sep 9 05:37:56.924155 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Sep 9 05:37:56.933189 systemd[1]: Mounted media.mount - External Media Directory. Sep 9 05:37:56.942142 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Sep 9 05:37:56.951085 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Sep 9 05:37:56.960090 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Sep 9 05:37:56.969258 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Sep 9 05:37:56.980235 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Sep 9 05:37:56.991301 systemd[1]: modprobe@configfs.service: Deactivated successfully. Sep 9 05:37:56.991590 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Sep 9 05:37:57.002304 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:37:57.002588 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:37:57.013274 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:37:57.013553 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:37:57.022238 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:37:57.022520 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:37:57.033307 systemd[1]: modprobe@fuse.service: Deactivated successfully. Sep 9 05:37:57.033596 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Sep 9 05:37:57.043332 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:37:57.043616 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:37:57.053364 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Sep 9 05:37:57.063312 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Sep 9 05:37:57.074346 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Sep 9 05:37:57.085305 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Sep 9 05:37:57.096369 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Sep 9 05:37:57.118494 systemd[1]: Reached target network-pre.target - Preparation for Network. Sep 9 05:37:57.130575 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Sep 9 05:37:57.147872 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Sep 9 05:37:57.156900 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Sep 9 05:37:57.157118 systemd[1]: Reached target local-fs.target - Local File Systems. Sep 9 05:37:57.167218 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Sep 9 05:37:57.179212 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Sep 9 05:37:57.188137 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:37:57.194861 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Sep 9 05:37:57.206347 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Sep 9 05:37:57.216946 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:37:57.218673 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Sep 9 05:37:57.228581 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:37:57.231992 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Sep 9 05:37:57.234718 systemd-journald[1178]: Time spent on flushing to /var/log/journal/cdbd5e51a82542589defcb9240947082 is 149.265ms for 933 entries. Sep 9 05:37:57.234718 systemd-journald[1178]: System Journal (/var/log/journal/cdbd5e51a82542589defcb9240947082) is 8M, max 584.8M, 576.8M free. Sep 9 05:37:57.416288 systemd-journald[1178]: Received client request to flush runtime journal. Sep 9 05:37:57.416388 kernel: loop0: detected capacity change from 0 to 50720 Sep 9 05:37:57.416436 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Sep 9 05:37:57.254692 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Sep 9 05:37:57.272003 systemd[1]: Starting systemd-sysusers.service - Create System Users... Sep 9 05:37:57.287297 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Sep 9 05:37:57.297073 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Sep 9 05:37:57.310071 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Sep 9 05:37:57.322187 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Sep 9 05:37:57.335414 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Sep 9 05:37:57.365083 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Sep 9 05:37:57.421801 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Sep 9 05:37:57.435317 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Sep 9 05:37:57.437550 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Sep 9 05:37:57.459110 kernel: loop1: detected capacity change from 0 to 128016 Sep 9 05:37:57.485807 systemd[1]: Finished systemd-sysusers.service - Create System Users. Sep 9 05:37:57.502411 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Sep 9 05:37:57.531818 kernel: loop2: detected capacity change from 0 to 110984 Sep 9 05:37:57.570027 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. Sep 9 05:37:57.570712 systemd-tmpfiles[1230]: ACLs are not supported, ignoring. Sep 9 05:37:57.584457 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Sep 9 05:37:57.616785 kernel: loop3: detected capacity change from 0 to 50720 Sep 9 05:37:57.654845 kernel: loop4: detected capacity change from 0 to 128016 Sep 9 05:37:57.692812 kernel: loop5: detected capacity change from 0 to 110984 Sep 9 05:37:57.739802 (sd-merge)[1235]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-gce'. Sep 9 05:37:57.740687 (sd-merge)[1235]: Merged extensions into '/usr'. Sep 9 05:37:57.747374 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Sep 9 05:37:57.760514 systemd[1]: Starting ensure-sysext.service... Sep 9 05:37:57.771301 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Sep 9 05:37:57.820584 systemd[1]: Reload requested from client PID 1237 ('systemctl') (unit ensure-sysext.service)... Sep 9 05:37:57.820911 systemd[1]: Reloading... Sep 9 05:37:57.828720 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Sep 9 05:37:57.828803 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Sep 9 05:37:57.829280 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Sep 9 05:37:57.831848 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Sep 9 05:37:57.835187 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Sep 9 05:37:57.839367 systemd-tmpfiles[1238]: ACLs are not supported, ignoring. Sep 9 05:37:57.840966 systemd-tmpfiles[1238]: ACLs are not supported, ignoring. Sep 9 05:37:57.875271 systemd-tmpfiles[1238]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:37:57.875494 systemd-tmpfiles[1238]: Skipping /boot Sep 9 05:37:57.943715 systemd-tmpfiles[1238]: Detected autofs mount point /boot during canonicalization of boot. Sep 9 05:37:57.945832 systemd-tmpfiles[1238]: Skipping /boot Sep 9 05:37:57.988715 zram_generator::config[1262]: No configuration found. Sep 9 05:37:58.342708 ldconfig[1208]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Sep 9 05:37:58.493361 systemd[1]: Reloading finished in 671 ms. Sep 9 05:37:58.520926 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Sep 9 05:37:58.546089 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Sep 9 05:37:58.566092 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:37:58.579039 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Sep 9 05:37:58.590938 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Sep 9 05:37:58.607944 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Sep 9 05:37:58.622047 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Sep 9 05:37:58.643925 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:58.644497 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:37:58.650274 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:37:58.662179 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:37:58.677458 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:37:58.686024 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:37:58.686586 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 05:37:58.686889 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:58.702617 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Sep 9 05:37:58.715614 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:37:58.716432 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:37:58.729176 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:37:58.729548 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:37:58.740281 augenrules[1335]: No rules Sep 9 05:37:58.740683 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:37:58.741017 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:37:58.750500 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:37:58.750822 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:37:58.771567 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Sep 9 05:37:58.791796 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:58.795084 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:37:58.803206 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Sep 9 05:37:58.811075 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Sep 9 05:37:58.823969 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Sep 9 05:37:58.835701 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Sep 9 05:37:58.851285 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Sep 9 05:37:58.863091 systemd[1]: Starting setup-oem.service - Setup OEM... Sep 9 05:37:58.868156 augenrules[1346]: /sbin/augenrules: No change Sep 9 05:37:58.872040 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Sep 9 05:37:58.872670 systemd[1]: systemd-hibernate-clear.service - Clear Stale Hibernate Storage Info was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/HibernateLocation-8cf2644b-4b0b-428f-9387-6d876050dc67). Sep 9 05:37:58.873247 systemd[1]: Reached target time-set.target - System Time Set. Sep 9 05:37:58.887098 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Sep 9 05:37:58.893289 augenrules[1369]: No rules Sep 9 05:37:58.896910 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Sep 9 05:37:58.901279 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Sep 9 05:37:58.914010 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:37:58.914694 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:37:58.924663 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Sep 9 05:37:58.924992 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Sep 9 05:37:58.935694 systemd[1]: modprobe@drm.service: Deactivated successfully. Sep 9 05:37:58.935982 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Sep 9 05:37:58.945602 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Sep 9 05:37:58.945933 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Sep 9 05:37:58.956633 systemd[1]: Started systemd-userdbd.service - User Database Manager. Sep 9 05:37:58.970023 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Sep 9 05:37:58.983533 systemd[1]: modprobe@loop.service: Deactivated successfully. Sep 9 05:37:58.984045 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Sep 9 05:37:59.003377 systemd[1]: Finished ensure-sysext.service. Sep 9 05:37:59.022377 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Sep 9 05:37:59.022540 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Sep 9 05:37:59.025106 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Sep 9 05:37:59.040543 systemd[1]: Starting systemd-update-done.service - Update is Completed... Sep 9 05:37:59.049888 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). Sep 9 05:37:59.050686 systemd[1]: Finished setup-oem.service - Setup OEM. Sep 9 05:37:59.061163 systemd-resolved[1312]: Positive Trust Anchors: Sep 9 05:37:59.061626 systemd-resolved[1312]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Sep 9 05:37:59.061831 systemd-resolved[1312]: 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 Sep 9 05:37:59.062556 systemd[1]: Starting oem-gce-enable-oslogin.service - Enable GCE OS Login... Sep 9 05:37:59.077860 systemd-resolved[1312]: Defaulting to hostname 'linux'. Sep 9 05:37:59.081064 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Sep 9 05:37:59.092818 systemd[1]: Finished systemd-update-done.service - Update is Completed. Sep 9 05:37:59.103727 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Sep 9 05:37:59.106481 systemd-udevd[1390]: Using default interface naming scheme 'v255'. Sep 9 05:37:59.129903 systemd[1]: Finished oem-gce-enable-oslogin.service - Enable GCE OS Login. Sep 9 05:37:59.151900 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Sep 9 05:37:59.163417 systemd[1]: Reached target sysinit.target - System Initialization. Sep 9 05:37:59.173091 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Sep 9 05:37:59.182972 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Sep 9 05:37:59.193906 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Sep 9 05:37:59.204147 systemd[1]: Started logrotate.timer - Daily rotation of log files. Sep 9 05:37:59.213366 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Sep 9 05:37:59.223908 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Sep 9 05:37:59.233929 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Sep 9 05:37:59.233996 systemd[1]: Reached target paths.target - Path Units. Sep 9 05:37:59.241903 systemd[1]: Reached target timers.target - Timer Units. Sep 9 05:37:59.252282 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Sep 9 05:37:59.262973 systemd[1]: Starting docker.socket - Docker Socket for the API... Sep 9 05:37:59.276404 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Sep 9 05:37:59.287131 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Sep 9 05:37:59.296898 systemd[1]: Reached target ssh-access.target - SSH Access Available. Sep 9 05:37:59.317826 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Sep 9 05:37:59.327452 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Sep 9 05:37:59.342450 systemd[1]: Starting systemd-networkd.service - Network Configuration... Sep 9 05:37:59.352912 systemd[1]: Listening on docker.socket - Docker Socket for the API. Sep 9 05:37:59.373016 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Sep 9 05:37:59.373781 systemd[1]: Condition check resulted in dev-tpmrm0.device - /dev/tpmrm0 being skipped. Sep 9 05:37:59.374347 systemd[1]: Reached target sockets.target - Socket Units. Sep 9 05:37:59.382921 systemd[1]: Reached target basic.target - Basic System. Sep 9 05:37:59.391959 systemd[1]: Reached target tpm2.target - Trusted Platform Module. Sep 9 05:37:59.401914 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:37:59.401970 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Sep 9 05:37:59.404248 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Sep 9 05:37:59.416707 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Sep 9 05:37:59.427970 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Sep 9 05:37:59.442467 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Sep 9 05:37:59.472048 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Sep 9 05:37:59.480921 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). Sep 9 05:37:59.487255 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Sep 9 05:37:59.504017 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Sep 9 05:37:59.517711 jq[1443]: false Sep 9 05:37:59.518229 systemd[1]: Started ntpd.service - Network Time Service. Sep 9 05:37:59.529768 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Sep 9 05:37:59.541447 coreos-metadata[1439]: Sep 09 05:37:59.541 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #1 Sep 9 05:37:59.542229 coreos-metadata[1439]: Sep 09 05:37:59.542 INFO Failed to fetch: error sending request for url (http://169.254.169.254/computeMetadata/v1/instance/hostname) Sep 9 05:37:59.548798 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Sep 9 05:37:59.570037 systemd[1]: Starting systemd-logind.service - User Login Management... Sep 9 05:37:59.577388 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Refreshing passwd entry cache Sep 9 05:37:59.577804 kernel: mousedev: PS/2 mouse device common for all mice Sep 9 05:37:59.572321 oslogin_cache_refresh[1447]: Refreshing passwd entry cache Sep 9 05:37:59.588425 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionSecurity=!tpm2). Sep 9 05:37:59.589315 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Sep 9 05:37:59.609005 systemd[1]: Starting update-engine.service - Update Engine... Sep 9 05:37:59.611249 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Failure getting users, quitting Sep 9 05:37:59.611344 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:37:59.611344 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Refreshing group entry cache Sep 9 05:37:59.611246 oslogin_cache_refresh[1447]: Failure getting users, quitting Sep 9 05:37:59.611285 oslogin_cache_refresh[1447]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Sep 9 05:37:59.611350 oslogin_cache_refresh[1447]: Refreshing group entry cache Sep 9 05:37:59.617399 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Failure getting groups, quitting Sep 9 05:37:59.617501 google_oslogin_nss_cache[1447]: oslogin_cache_refresh[1447]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:37:59.617394 oslogin_cache_refresh[1447]: Failure getting groups, quitting Sep 9 05:37:59.617416 oslogin_cache_refresh[1447]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Sep 9 05:37:59.631992 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Sep 9 05:37:59.637778 kernel: input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input4 Sep 9 05:37:59.644054 extend-filesystems[1444]: Found /dev/sda6 Sep 9 05:37:59.674713 extend-filesystems[1444]: Found /dev/sda9 Sep 9 05:37:59.759949 kernel: ACPI: button: Power Button [PWRF] Sep 9 05:37:59.760054 kernel: input: Sleep Button as /devices/LNXSYSTM:00/LNXSLPBN:00/input/input5 Sep 9 05:37:59.760090 kernel: piix4_smbus 0000:00:01.3: SMBus base address uninitialized - upgrade BIOS or use force_addr=0xaddr Sep 9 05:37:59.760548 extend-filesystems[1444]: Checking size of /dev/sda9 Sep 9 05:37:59.680832 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Sep 9 05:37:59.772834 kernel: ACPI: button: Sleep Button [SLPF] Sep 9 05:37:59.696422 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Sep 9 05:37:59.698941 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Sep 9 05:37:59.699454 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Sep 9 05:37:59.699820 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Sep 9 05:37:59.709445 systemd[1]: motdgen.service: Deactivated successfully. Sep 9 05:37:59.709868 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Sep 9 05:37:59.733353 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Sep 9 05:37:59.733688 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Sep 9 05:37:59.784000 extend-filesystems[1444]: Resized partition /dev/sda9 Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: ntpd 4.2.8p17@1.4004-o Tue Sep 9 03:09:56 UTC 2025 (1): Starting Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: ---------------------------------------------------- Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: ntp-4 is maintained by Network Time Foundation, Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: corporation. Support and training for ntp-4 are Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: available at https://www.nwtime.org/support Sep 9 05:37:59.791935 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: ---------------------------------------------------- Sep 9 05:37:59.785504 ntpd[1450]: ntpd 4.2.8p17@1.4004-o Tue Sep 9 03:09:56 UTC 2025 (1): Starting Sep 9 05:37:59.785536 ntpd[1450]: Command line: /usr/sbin/ntpd -g -n -u ntp:ntp Sep 9 05:37:59.785550 ntpd[1450]: ---------------------------------------------------- Sep 9 05:37:59.785564 ntpd[1450]: ntp-4 is maintained by Network Time Foundation, Sep 9 05:37:59.785577 ntpd[1450]: Inc. (NTF), a non-profit 501(c)(3) public-benefit Sep 9 05:37:59.785591 ntpd[1450]: corporation. Support and training for ntp-4 are Sep 9 05:37:59.785603 ntpd[1450]: available at https://www.nwtime.org/support Sep 9 05:37:59.785616 ntpd[1450]: ---------------------------------------------------- Sep 9 05:37:59.803543 extend-filesystems[1480]: resize2fs 1.47.3 (8-Jul-2025) Sep 9 05:37:59.818943 kernel: EXT4-fs (sda9): resizing filesystem from 1617920 to 2538491 blocks Sep 9 05:37:59.819272 ntpd[1450]: proto: precision = 0.070 usec (-24) Sep 9 05:37:59.819406 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: proto: precision = 0.070 usec (-24) Sep 9 05:37:59.823616 ntpd[1450]: basedate set to 2025-08-28 Sep 9 05:37:59.823655 ntpd[1450]: gps base set to 2025-08-31 (week 2382) Sep 9 05:37:59.823817 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: basedate set to 2025-08-28 Sep 9 05:37:59.823817 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: gps base set to 2025-08-31 (week 2382) Sep 9 05:37:59.829779 kernel: EXT4-fs (sda9): resized filesystem to 2538491 Sep 9 05:37:59.841553 jq[1464]: true Sep 9 05:37:59.841932 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Listen and drop on 0 v6wildcard [::]:123 Sep 9 05:37:59.841932 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 9 05:37:59.841382 ntpd[1450]: Listen and drop on 0 v6wildcard [::]:123 Sep 9 05:37:59.841454 ntpd[1450]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 9 05:37:59.842312 ntpd[1450]: Listen normally on 2 lo 127.0.0.1:123 Sep 9 05:37:59.842429 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Listen normally on 2 lo 127.0.0.1:123 Sep 9 05:37:59.842828 ntpd[1450]: Listen normally on 3 lo [::1]:123 Sep 9 05:37:59.842907 ntpd[1450]: Listening on routing socket on fd #20 for interface updates Sep 9 05:37:59.842996 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Listen normally on 3 lo [::1]:123 Sep 9 05:37:59.842996 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: Listening on routing socket on fd #20 for interface updates Sep 9 05:37:59.844464 extend-filesystems[1480]: Filesystem at /dev/sda9 is mounted on /; on-line resizing required Sep 9 05:37:59.844464 extend-filesystems[1480]: old_desc_blocks = 1, new_desc_blocks = 2 Sep 9 05:37:59.844464 extend-filesystems[1480]: The filesystem on /dev/sda9 is now 2538491 (4k) blocks long. Sep 9 05:37:59.874911 extend-filesystems[1444]: Resized filesystem in /dev/sda9 Sep 9 05:37:59.881943 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 9 05:37:59.881943 ntpd[1450]: 9 Sep 05:37:59 ntpd[1450]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 9 05:37:59.872401 ntpd[1450]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 9 05:37:59.872439 ntpd[1450]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Sep 9 05:37:59.887235 update_engine[1458]: I20250909 05:37:59.887054 1458 main.cc:92] Flatcar Update Engine starting Sep 9 05:37:59.906526 systemd[1]: extend-filesystems.service: Deactivated successfully. Sep 9 05:37:59.906901 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Sep 9 05:37:59.940139 jq[1492]: true Sep 9 05:38:00.001967 bash[1518]: Updated "/home/core/.ssh/authorized_keys" Sep 9 05:38:00.003853 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Sep 9 05:38:00.030038 systemd[1]: Starting sshkeys.service... Sep 9 05:38:00.045780 kernel: EDAC MC: Ver: 3.0.0 Sep 9 05:38:00.079555 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - PersistentDisk OEM. Sep 9 05:38:00.096455 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Sep 9 05:38:00.114033 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Sep 9 05:38:00.119540 dbus-daemon[1440]: [system] SELinux support is enabled Sep 9 05:38:00.123695 systemd[1]: Started dbus.service - D-Bus System Message Bus. Sep 9 05:38:00.143017 update_engine[1458]: I20250909 05:38:00.142884 1458 update_check_scheduler.cc:74] Next update check in 11m34s Sep 9 05:38:00.194229 systemd[1]: Started update-engine.service - Update Engine. Sep 9 05:38:00.205603 systemd[1]: Created slice system-coreos\x2dmetadata\x2dsshkeys.slice - Slice /system/coreos-metadata-sshkeys. Sep 9 05:38:00.219105 systemd[1]: Starting coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys)... Sep 9 05:38:00.219293 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). Sep 9 05:38:00.219527 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Sep 9 05:38:00.219708 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). Sep 9 05:38:00.219931 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Sep 9 05:38:00.225871 systemd[1]: Started locksmithd.service - Cluster reboot manager. Sep 9 05:38:00.268200 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Sep 9 05:38:00.397837 coreos-metadata[1529]: Sep 09 05:38:00.396 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #1 Sep 9 05:38:00.398372 coreos-metadata[1529]: Sep 09 05:38:00.397 INFO Failed to fetch: error sending request for url (http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys) Sep 9 05:38:00.452706 systemd-logind[1453]: Watching system buttons on /dev/input/event2 (Power Button) Sep 9 05:38:00.453779 systemd-logind[1453]: Watching system buttons on /dev/input/event3 (Sleep Button) Sep 9 05:38:00.453831 systemd-logind[1453]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Sep 9 05:38:00.454164 systemd-logind[1453]: New seat seat0. Sep 9 05:38:00.457009 systemd[1]: Started systemd-logind.service - User Login Management. Sep 9 05:38:00.533411 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Sep 9 05:38:00.542546 coreos-metadata[1439]: Sep 09 05:38:00.542 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #2 Sep 9 05:38:00.543997 coreos-metadata[1439]: Sep 09 05:38:00.543 INFO Failed to fetch: error sending request for url (http://169.254.169.254/computeMetadata/v1/instance/hostname) Sep 9 05:38:00.720644 systemd-networkd[1436]: lo: Link UP Sep 9 05:38:00.721168 systemd-networkd[1436]: lo: Gained carrier Sep 9 05:38:00.723870 systemd-networkd[1436]: Enumeration completed Sep 9 05:38:00.724198 systemd[1]: Started systemd-networkd.service - Network Configuration. Sep 9 05:38:00.725019 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:38:00.725145 systemd-networkd[1436]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Sep 9 05:38:00.726014 systemd-networkd[1436]: eth0: Link UP Sep 9 05:38:00.726412 systemd-networkd[1436]: eth0: Gained carrier Sep 9 05:38:00.726565 systemd-networkd[1436]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Sep 9 05:38:00.734445 systemd[1]: Reached target network.target - Network. Sep 9 05:38:00.738942 systemd-networkd[1436]: eth0: Overlong DHCP hostname received, shortened from 'ci-4452-0-0-nightly-20250908-2100-c026c183201b1c7616f3.c.flatcar-212911.internal' to 'ci-4452-0-0-nightly-20250908-2100-c026c183201b1c7616f3' Sep 9 05:38:00.738980 systemd-networkd[1436]: eth0: DHCPv4 address 10.128.0.20/32, gateway 10.128.0.1 acquired from 169.254.169.254 Sep 9 05:38:00.744011 systemd[1]: Starting containerd.service - containerd container runtime... Sep 9 05:38:00.745786 dbus-daemon[1440]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.4' (uid=244 pid=1436 comm="/usr/lib/systemd/systemd-networkd" label="system_u:system_r:kernel_t:s0") Sep 9 05:38:00.757340 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Sep 9 05:38:00.771572 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Sep 9 05:38:00.779433 locksmithd[1531]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Sep 9 05:38:00.806310 systemd[1]: Starting systemd-hostnamed.service - Hostname Service... Sep 9 05:38:00.844571 (ntainerd)[1553]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Sep 9 05:38:00.889349 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Sep 9 05:38:01.025409 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Sep 9 05:38:01.066446 systemd[1]: Started systemd-hostnamed.service - Hostname Service. Sep 9 05:38:01.067316 dbus-daemon[1440]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 9 05:38:01.067995 dbus-daemon[1440]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.7' (uid=0 pid=1552 comm="/usr/lib/systemd/systemd-hostnamed" label="system_u:system_r:kernel_t:s0") Sep 9 05:38:01.091004 systemd[1]: Starting polkit.service - Authorization Manager... Sep 9 05:38:01.316072 polkitd[1557]: Started polkitd version 126 Sep 9 05:38:01.329317 polkitd[1557]: Loading rules from directory /etc/polkit-1/rules.d Sep 9 05:38:01.331015 polkitd[1557]: Loading rules from directory /run/polkit-1/rules.d Sep 9 05:38:01.331098 polkitd[1557]: Error opening rules directory: Error opening directory “/run/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Sep 9 05:38:01.331654 polkitd[1557]: Loading rules from directory /usr/local/share/polkit-1/rules.d Sep 9 05:38:01.334474 systemd[1]: Started polkit.service - Authorization Manager. Sep 9 05:38:01.331695 polkitd[1557]: Error opening rules directory: Error opening directory “/usr/local/share/polkit-1/rules.d”: No such file or directory (g-file-error-quark, 4) Sep 9 05:38:01.331790 polkitd[1557]: Loading rules from directory /usr/share/polkit-1/rules.d Sep 9 05:38:01.334121 polkitd[1557]: Finished loading, compiling and executing 2 rules Sep 9 05:38:01.337158 dbus-daemon[1440]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 9 05:38:01.337901 polkitd[1557]: Acquired the name org.freedesktop.PolicyKit1 on the system bus Sep 9 05:38:01.369625 systemd-hostnamed[1552]: Hostname set to (transient) Sep 9 05:38:01.370603 systemd-resolved[1312]: System hostname changed to 'ci-4452-0-0-nightly-20250908-2100-c026c183201b1c7616f3'. Sep 9 05:38:01.382362 containerd[1553]: time="2025-09-09T05:38:01Z" level=warning msg="Ignoring unknown key in TOML" column=1 error="strict mode: fields in the document are missing in the target struct" file=/usr/share/containerd/config.toml key=subreaper row=8 Sep 9 05:38:01.384780 containerd[1553]: time="2025-09-09T05:38:01.383960593Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Sep 9 05:38:01.398025 coreos-metadata[1529]: Sep 09 05:38:01.397 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/sshKeys: Attempt #2 Sep 9 05:38:01.400981 coreos-metadata[1529]: Sep 09 05:38:01.400 INFO Fetch failed with 404: resource not found Sep 9 05:38:01.400981 coreos-metadata[1529]: Sep 09 05:38:01.400 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/ssh-keys: Attempt #1 Sep 9 05:38:01.401417 coreos-metadata[1529]: Sep 09 05:38:01.401 INFO Fetch successful Sep 9 05:38:01.401417 coreos-metadata[1529]: Sep 09 05:38:01.401 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/attributes/block-project-ssh-keys: Attempt #1 Sep 9 05:38:01.401706 coreos-metadata[1529]: Sep 09 05:38:01.401 INFO Fetch failed with 404: resource not found Sep 9 05:38:01.401976 coreos-metadata[1529]: Sep 09 05:38:01.401 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/sshKeys: Attempt #1 Sep 9 05:38:01.402302 coreos-metadata[1529]: Sep 09 05:38:01.402 INFO Fetch failed with 404: resource not found Sep 9 05:38:01.404420 coreos-metadata[1529]: Sep 09 05:38:01.402 INFO Fetching http://169.254.169.254/computeMetadata/v1/project/attributes/ssh-keys: Attempt #1 Sep 9 05:38:01.404420 coreos-metadata[1529]: Sep 09 05:38:01.404 INFO Fetch successful Sep 9 05:38:01.406081 unknown[1529]: wrote ssh authorized keys file for user: core Sep 9 05:38:01.406852 containerd[1553]: time="2025-09-09T05:38:01.406741103Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="13.189µs" Sep 9 05:38:01.407780 containerd[1553]: time="2025-09-09T05:38:01.406940119Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Sep 9 05:38:01.407780 containerd[1553]: time="2025-09-09T05:38:01.406975978Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408025155Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408061901Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408099944Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408184980Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408201283Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408525003Z" level=info msg="skip loading plugin" 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" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408545964Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408563209Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408577696Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408675698Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Sep 9 05:38:01.409780 containerd[1553]: time="2025-09-09T05:38:01.408984676Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 05:38:01.410254 containerd[1553]: time="2025-09-09T05:38:01.409026139Z" level=info msg="skip loading plugin" error="lstat /var/lib/containerd/io.containerd.snapshotter.v1.zfs: no such file or directory: skip plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Sep 9 05:38:01.410254 containerd[1553]: time="2025-09-09T05:38:01.409043058Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Sep 9 05:38:01.411026 containerd[1553]: time="2025-09-09T05:38:01.410934221Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Sep 9 05:38:01.411432 containerd[1553]: time="2025-09-09T05:38:01.411396623Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Sep 9 05:38:01.411812 containerd[1553]: time="2025-09-09T05:38:01.411726462Z" level=info msg="metadata content store policy set" policy=shared Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418200299Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418280711Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418305312Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418373614Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418394875Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418414779Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418434748Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418452377Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418469131Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418485570Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418501382Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418521829Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418679316Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Sep 9 05:38:01.418815 containerd[1553]: time="2025-09-09T05:38:01.418710165Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419790174Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419835259Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419852887Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419869549Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419886629Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419902858Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419921717Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419948031Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.419974645Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.420069809Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Sep 9 05:38:01.420129 containerd[1553]: time="2025-09-09T05:38:01.420090559Z" level=info msg="Start snapshots syncer" Sep 9 05:38:01.422315 containerd[1553]: time="2025-09-09T05:38:01.420637008Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Sep 9 05:38:01.422315 containerd[1553]: time="2025-09-09T05:38:01.422149199Z" level=info msg="starting cri plugin" config="{\"containerd\":{\"defaultRuntimeName\":\"runc\",\"runtimes\":{\"runc\":{\"runtimeType\":\"io.containerd.runc.v2\",\"runtimePath\":\"\",\"PodAnnotations\":null,\"ContainerAnnotations\":null,\"options\":{\"BinaryName\":\"\",\"CriuImagePath\":\"\",\"CriuWorkPath\":\"\",\"IoGid\":0,\"IoUid\":0,\"NoNewKeyring\":false,\"Root\":\"\",\"ShimCgroup\":\"\",\"SystemdCgroup\":true},\"privileged_without_host_devices\":false,\"privileged_without_host_devices_all_devices_allowed\":false,\"baseRuntimeSpec\":\"\",\"cniConfDir\":\"\",\"cniMaxConfNum\":0,\"snapshotter\":\"\",\"sandboxer\":\"podsandbox\",\"io_type\":\"\"}},\"ignoreBlockIONotEnabledErrors\":false,\"ignoreRdtNotEnabledErrors\":false},\"cni\":{\"binDir\":\"/opt/cni/bin\",\"confDir\":\"/etc/cni/net.d\",\"maxConfNum\":1,\"setupSerially\":false,\"confTemplate\":\"\",\"ipPref\":\"\",\"useInternalLoopback\":false},\"enableSelinux\":true,\"selinuxCategoryRange\":1024,\"maxContainerLogSize\":16384,\"disableApparmor\":false,\"restrictOOMScoreAdj\":false,\"disableProcMount\":false,\"unsetSeccompProfile\":\"\",\"tolerateMissingHugetlbController\":true,\"disableHugetlbController\":true,\"device_ownership_from_security_context\":false,\"ignoreImageDefinedVolumes\":false,\"netnsMountsUnderStateDir\":false,\"enableUnprivilegedPorts\":true,\"enableUnprivilegedICMP\":true,\"enableCDI\":true,\"cdiSpecDirs\":[\"/etc/cdi\",\"/var/run/cdi\"],\"drainExecSyncIOTimeout\":\"0s\",\"ignoreDeprecationWarnings\":null,\"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\"}" Sep 9 05:38:01.422573 containerd[1553]: time="2025-09-09T05:38:01.422235597Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Sep 9 05:38:01.422853 containerd[1553]: time="2025-09-09T05:38:01.422696890Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423080549Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423135749Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423158180Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423178740Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423199151Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423217194Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423235833Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423270430Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423289278Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Sep 9 05:38:01.423788 containerd[1553]: time="2025-09-09T05:38:01.423308895Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Sep 9 05:38:01.424615 containerd[1553]: time="2025-09-09T05:38:01.424567847Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 05:38:01.424760 containerd[1553]: time="2025-09-09T05:38:01.424712305Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Sep 9 05:38:01.429816 containerd[1553]: time="2025-09-09T05:38:01.429779401Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 05:38:01.429951 containerd[1553]: time="2025-09-09T05:38:01.429929521Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Sep 9 05:38:01.430057 containerd[1553]: time="2025-09-09T05:38:01.430039030Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Sep 9 05:38:01.430169 containerd[1553]: time="2025-09-09T05:38:01.430144348Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Sep 9 05:38:01.430264 containerd[1553]: time="2025-09-09T05:38:01.430248417Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Sep 9 05:38:01.430358 containerd[1553]: time="2025-09-09T05:38:01.430344245Z" level=info msg="runtime interface created" Sep 9 05:38:01.430421 containerd[1553]: time="2025-09-09T05:38:01.430409590Z" level=info msg="created NRI interface" Sep 9 05:38:01.430509 containerd[1553]: time="2025-09-09T05:38:01.430487158Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Sep 9 05:38:01.430584 containerd[1553]: time="2025-09-09T05:38:01.430570865Z" level=info msg="Connect containerd service" Sep 9 05:38:01.430699 containerd[1553]: time="2025-09-09T05:38:01.430684492Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Sep 9 05:38:01.432308 containerd[1553]: time="2025-09-09T05:38:01.432266898Z" 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" Sep 9 05:38:01.442122 update-ssh-keys[1573]: Updated "/home/core/.ssh/authorized_keys" Sep 9 05:38:01.445309 systemd[1]: Finished coreos-metadata-sshkeys@core.service - Flatcar Metadata Agent (SSH Keys). Sep 9 05:38:01.459554 systemd[1]: Finished sshkeys.service. Sep 9 05:38:01.599066 containerd[1553]: time="2025-09-09T05:38:01.598930089Z" level=info msg="Start subscribing containerd event" Sep 9 05:38:01.599066 containerd[1553]: time="2025-09-09T05:38:01.599015773Z" level=info msg="Start recovering state" Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599141822Z" level=info msg="Start event monitor" Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599163103Z" level=info msg="Start cni network conf syncer for default" Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599178159Z" level=info msg="Start streaming server" Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599198234Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599208623Z" level=info msg="runtime interface starting up..." Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599218143Z" level=info msg="starting plugins..." Sep 9 05:38:01.599257 containerd[1553]: time="2025-09-09T05:38:01.599238366Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Sep 9 05:38:01.599965 containerd[1553]: time="2025-09-09T05:38:01.599929123Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Sep 9 05:38:01.600091 containerd[1553]: time="2025-09-09T05:38:01.600004774Z" level=info msg=serving... address=/run/containerd/containerd.sock Sep 9 05:38:01.600236 systemd[1]: Started containerd.service - containerd container runtime. Sep 9 05:38:01.601765 containerd[1553]: time="2025-09-09T05:38:01.600429492Z" level=info msg="containerd successfully booted in 0.218616s" Sep 9 05:38:01.648527 sshd_keygen[1479]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Sep 9 05:38:01.677074 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Sep 9 05:38:01.688322 systemd[1]: Starting issuegen.service - Generate /run/issue... Sep 9 05:38:01.700336 systemd[1]: Started sshd@0-10.128.0.20:22-139.178.89.65:33682.service - OpenSSH per-connection server daemon (139.178.89.65:33682). Sep 9 05:38:01.712523 systemd[1]: issuegen.service: Deactivated successfully. Sep 9 05:38:01.719031 systemd[1]: Finished issuegen.service - Generate /run/issue. Sep 9 05:38:01.733910 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Sep 9 05:38:01.762344 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Sep 9 05:38:01.776323 systemd[1]: Started getty@tty1.service - Getty on tty1. Sep 9 05:38:01.786139 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Sep 9 05:38:01.795213 systemd[1]: Reached target getty.target - Login Prompts. Sep 9 05:38:02.026693 sshd[1598]: Accepted publickey for core from 139.178.89.65 port 33682 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:02.030208 sshd-session[1598]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:02.048435 systemd-logind[1453]: New session 1 of user core. Sep 9 05:38:02.050735 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Sep 9 05:38:02.062179 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Sep 9 05:38:02.098462 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Sep 9 05:38:02.112124 systemd[1]: Starting user@500.service - User Manager for UID 500... Sep 9 05:38:02.145361 (systemd)[1610]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Sep 9 05:38:02.148902 systemd-logind[1453]: New session c1 of user core. Sep 9 05:38:02.339246 systemd[1610]: Queued start job for default target default.target. Sep 9 05:38:02.350261 systemd[1610]: Created slice app.slice - User Application Slice. Sep 9 05:38:02.350310 systemd[1610]: Reached target paths.target - Paths. Sep 9 05:38:02.350389 systemd[1610]: Reached target timers.target - Timers. Sep 9 05:38:02.352257 systemd[1610]: Starting dbus.socket - D-Bus User Message Bus Socket... Sep 9 05:38:02.367356 systemd[1610]: Listening on dbus.socket - D-Bus User Message Bus Socket. Sep 9 05:38:02.367534 systemd[1610]: Reached target sockets.target - Sockets. Sep 9 05:38:02.367609 systemd[1610]: Reached target basic.target - Basic System. Sep 9 05:38:02.367683 systemd[1610]: Reached target default.target - Main User Target. Sep 9 05:38:02.367737 systemd[1610]: Startup finished in 209ms. Sep 9 05:38:02.367828 systemd[1]: Started user@500.service - User Manager for UID 500. Sep 9 05:38:02.388012 systemd[1]: Started session-1.scope - Session 1 of User core. Sep 9 05:38:02.402976 systemd-networkd[1436]: eth0: Gained IPv6LL Sep 9 05:38:02.406779 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Sep 9 05:38:02.417766 systemd[1]: Reached target network-online.target - Network is Online. Sep 9 05:38:02.428889 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Sep 9 05:38:02.443573 systemd[1]: Starting oem-gce.service - GCE Linux Agent... Sep 9 05:38:02.463386 init.sh[1621]: + '[' -e /etc/default/instance_configs.cfg.template ']' Sep 9 05:38:02.463934 init.sh[1621]: + echo -e '[InstanceSetup]\nset_host_keys = false' Sep 9 05:38:02.465596 init.sh[1621]: + /usr/bin/google_instance_setup Sep 9 05:38:02.471432 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Sep 9 05:38:02.544479 coreos-metadata[1439]: Sep 09 05:38:02.544 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/hostname: Attempt #3 Sep 9 05:38:02.546648 coreos-metadata[1439]: Sep 09 05:38:02.546 INFO Fetch successful Sep 9 05:38:02.546787 coreos-metadata[1439]: Sep 09 05:38:02.546 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/access-configs/0/external-ip: Attempt #1 Sep 9 05:38:02.547104 coreos-metadata[1439]: Sep 09 05:38:02.547 INFO Fetch successful Sep 9 05:38:02.547189 coreos-metadata[1439]: Sep 09 05:38:02.547 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/network-interfaces/0/ip: Attempt #1 Sep 9 05:38:02.547500 coreos-metadata[1439]: Sep 09 05:38:02.547 INFO Fetch successful Sep 9 05:38:02.547569 coreos-metadata[1439]: Sep 09 05:38:02.547 INFO Fetching http://169.254.169.254/computeMetadata/v1/instance/machine-type: Attempt #1 Sep 9 05:38:02.548121 coreos-metadata[1439]: Sep 09 05:38:02.548 INFO Fetch successful Sep 9 05:38:02.627410 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Sep 9 05:38:02.640710 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Sep 9 05:38:02.645152 systemd[1]: Started sshd@1-10.128.0.20:22-139.178.89.65:33690.service - OpenSSH per-connection server daemon (139.178.89.65:33690). Sep 9 05:38:02.935891 instance-setup[1627]: INFO Running google_set_multiqueue. Sep 9 05:38:02.951120 instance-setup[1627]: INFO Set channels for eth0 to 2. Sep 9 05:38:02.955327 instance-setup[1627]: INFO Setting /proc/irq/31/smp_affinity_list to 0 for device virtio1. Sep 9 05:38:02.957322 instance-setup[1627]: INFO /proc/irq/31/smp_affinity_list: real affinity 0 Sep 9 05:38:02.957897 instance-setup[1627]: INFO Setting /proc/irq/32/smp_affinity_list to 0 for device virtio1. Sep 9 05:38:02.959314 instance-setup[1627]: INFO /proc/irq/32/smp_affinity_list: real affinity 0 Sep 9 05:38:02.959732 instance-setup[1627]: INFO Setting /proc/irq/33/smp_affinity_list to 1 for device virtio1. Sep 9 05:38:02.961390 instance-setup[1627]: INFO /proc/irq/33/smp_affinity_list: real affinity 1 Sep 9 05:38:02.961783 instance-setup[1627]: INFO Setting /proc/irq/34/smp_affinity_list to 1 for device virtio1. Sep 9 05:38:02.965314 instance-setup[1627]: INFO /proc/irq/34/smp_affinity_list: real affinity 1 Sep 9 05:38:02.969534 sshd[1637]: Accepted publickey for core from 139.178.89.65 port 33690 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:02.972865 sshd-session[1637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:02.973026 instance-setup[1627]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type Sep 9 05:38:02.978173 instance-setup[1627]: INFO /usr/sbin/google_set_multiqueue: line 133: echo: write error: Value too large for defined data type Sep 9 05:38:02.980444 instance-setup[1627]: INFO Queue 0 XPS=1 for /sys/class/net/eth0/queues/tx-0/xps_cpus Sep 9 05:38:02.980512 instance-setup[1627]: INFO Queue 1 XPS=2 for /sys/class/net/eth0/queues/tx-1/xps_cpus Sep 9 05:38:02.984818 systemd-logind[1453]: New session 2 of user core. Sep 9 05:38:02.994021 systemd[1]: Started session-2.scope - Session 2 of User core. Sep 9 05:38:03.003664 init.sh[1621]: + /usr/bin/google_metadata_script_runner --script-type startup Sep 9 05:38:03.129860 startup-script[1670]: INFO Starting startup scripts. Sep 9 05:38:03.134213 startup-script[1670]: INFO No startup scripts found in metadata. Sep 9 05:38:03.134293 startup-script[1670]: INFO Finished running startup scripts. Sep 9 05:38:03.156821 init.sh[1621]: + trap 'stopping=1 ; kill "${daemon_pids[@]}" || :' SIGTERM Sep 9 05:38:03.156821 init.sh[1621]: + daemon_pids=() Sep 9 05:38:03.156821 init.sh[1621]: + for d in accounts clock_skew network Sep 9 05:38:03.157056 init.sh[1621]: + daemon_pids+=($!) Sep 9 05:38:03.157056 init.sh[1621]: + for d in accounts clock_skew network Sep 9 05:38:03.157279 init.sh[1621]: + daemon_pids+=($!) Sep 9 05:38:03.157337 init.sh[1621]: + for d in accounts clock_skew network Sep 9 05:38:03.157783 init.sh[1621]: + daemon_pids+=($!) Sep 9 05:38:03.157783 init.sh[1621]: + NOTIFY_SOCKET=/run/systemd/notify Sep 9 05:38:03.157783 init.sh[1621]: + /usr/bin/systemd-notify --ready Sep 9 05:38:03.157947 init.sh[1677]: + /usr/bin/google_network_daemon Sep 9 05:38:03.158275 init.sh[1675]: + /usr/bin/google_accounts_daemon Sep 9 05:38:03.158601 init.sh[1676]: + /usr/bin/google_clock_skew_daemon Sep 9 05:38:03.171464 systemd[1]: Started oem-gce.service - GCE Linux Agent. Sep 9 05:38:03.181920 systemd[1]: Reached target multi-user.target - Multi-User System. Sep 9 05:38:03.184958 init.sh[1621]: + wait -n 1675 1676 1677 Sep 9 05:38:03.196065 sshd-session[1637]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:03.196341 systemd[1]: Startup finished in 3.667s (kernel) + 8.744s (initrd) + 8.681s (userspace) = 21.092s. Sep 9 05:38:03.198063 sshd[1671]: Connection closed by 139.178.89.65 port 33690 Sep 9 05:38:03.210198 systemd[1]: sshd@1-10.128.0.20:22-139.178.89.65:33690.service: Deactivated successfully. Sep 9 05:38:03.210438 systemd-logind[1453]: Session 2 logged out. Waiting for processes to exit. Sep 9 05:38:03.217658 systemd[1]: session-2.scope: Deactivated successfully. Sep 9 05:38:03.223355 systemd-logind[1453]: Removed session 2. Sep 9 05:38:03.263919 systemd[1]: Started sshd@2-10.128.0.20:22-139.178.89.65:33694.service - OpenSSH per-connection server daemon (139.178.89.65:33694). Sep 9 05:38:03.501529 google-clock-skew[1676]: INFO Starting Google Clock Skew daemon. Sep 9 05:38:03.513909 google-clock-skew[1676]: INFO Clock drift token has changed: 0. Sep 9 05:38:03.549517 google-networking[1677]: INFO Starting Google Networking daemon. Sep 9 05:38:03.552944 groupadd[1696]: group added to /etc/group: name=google-sudoers, GID=1000 Sep 9 05:38:03.556430 groupadd[1696]: group added to /etc/gshadow: name=google-sudoers Sep 9 05:38:03.591079 sshd[1687]: Accepted publickey for core from 139.178.89.65 port 33694 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:03.593363 sshd-session[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:03.596703 groupadd[1696]: new group: name=google-sudoers, GID=1000 Sep 9 05:38:03.602985 systemd-logind[1453]: New session 3 of user core. Sep 9 05:38:03.609987 systemd[1]: Started session-3.scope - Session 3 of User core. Sep 9 05:38:03.627001 google-accounts[1675]: INFO Starting Google Accounts daemon. Sep 9 05:38:03.636684 google-accounts[1675]: WARNING OS Login not installed. Sep 9 05:38:03.638312 google-accounts[1675]: INFO Creating a new user account for 0. Sep 9 05:38:03.642263 init.sh[1707]: useradd: invalid user name '0': use --badname to ignore Sep 9 05:38:03.642441 google-accounts[1675]: WARNING Could not create user 0. Command '['useradd', '-m', '-s', '/bin/bash', '-p', '*', '0']' returned non-zero exit status 3.. Sep 9 05:38:03.807820 sshd[1705]: Connection closed by 139.178.89.65 port 33694 Sep 9 05:38:03.809459 sshd-session[1687]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:03.814549 systemd[1]: sshd@2-10.128.0.20:22-139.178.89.65:33694.service: Deactivated successfully. Sep 9 05:38:03.817156 systemd[1]: session-3.scope: Deactivated successfully. Sep 9 05:38:03.820119 systemd-logind[1453]: Session 3 logged out. Waiting for processes to exit. Sep 9 05:38:03.822150 systemd-logind[1453]: Removed session 3. Sep 9 05:38:04.000346 systemd-resolved[1312]: Clock change detected. Flushing caches. Sep 9 05:38:04.001238 google-clock-skew[1676]: INFO Synced system time with hardware clock. Sep 9 05:38:04.937366 ntpd[1450]: Listen normally on 4 eth0 10.128.0.20:123 Sep 9 05:38:04.937928 ntpd[1450]: 9 Sep 05:38:04 ntpd[1450]: Listen normally on 4 eth0 10.128.0.20:123 Sep 9 05:38:04.937928 ntpd[1450]: 9 Sep 05:38:04 ntpd[1450]: Listen normally on 5 eth0 [fe80::4001:aff:fe80:14%2]:123 Sep 9 05:38:04.937496 ntpd[1450]: Listen normally on 5 eth0 [fe80::4001:aff:fe80:14%2]:123 Sep 9 05:38:14.012760 systemd[1]: Started sshd@3-10.128.0.20:22-139.178.89.65:48076.service - OpenSSH per-connection server daemon (139.178.89.65:48076). Sep 9 05:38:14.330428 sshd[1714]: Accepted publickey for core from 139.178.89.65 port 48076 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:14.332159 sshd-session[1714]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:14.339905 systemd-logind[1453]: New session 4 of user core. Sep 9 05:38:14.349090 systemd[1]: Started session-4.scope - Session 4 of User core. Sep 9 05:38:14.546649 sshd[1717]: Connection closed by 139.178.89.65 port 48076 Sep 9 05:38:14.547538 sshd-session[1714]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:14.553278 systemd[1]: sshd@3-10.128.0.20:22-139.178.89.65:48076.service: Deactivated successfully. Sep 9 05:38:14.555910 systemd[1]: session-4.scope: Deactivated successfully. Sep 9 05:38:14.557361 systemd-logind[1453]: Session 4 logged out. Waiting for processes to exit. Sep 9 05:38:14.559655 systemd-logind[1453]: Removed session 4. Sep 9 05:38:14.602626 systemd[1]: Started sshd@4-10.128.0.20:22-139.178.89.65:48082.service - OpenSSH per-connection server daemon (139.178.89.65:48082). Sep 9 05:38:14.926339 sshd[1723]: Accepted publickey for core from 139.178.89.65 port 48082 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:14.928068 sshd-session[1723]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:14.935904 systemd-logind[1453]: New session 5 of user core. Sep 9 05:38:14.943043 systemd[1]: Started session-5.scope - Session 5 of User core. Sep 9 05:38:15.139440 sshd[1726]: Connection closed by 139.178.89.65 port 48082 Sep 9 05:38:15.140316 sshd-session[1723]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:15.146533 systemd[1]: sshd@4-10.128.0.20:22-139.178.89.65:48082.service: Deactivated successfully. Sep 9 05:38:15.149055 systemd[1]: session-5.scope: Deactivated successfully. Sep 9 05:38:15.150128 systemd-logind[1453]: Session 5 logged out. Waiting for processes to exit. Sep 9 05:38:15.152353 systemd-logind[1453]: Removed session 5. Sep 9 05:38:15.193307 systemd[1]: Started sshd@5-10.128.0.20:22-139.178.89.65:48094.service - OpenSSH per-connection server daemon (139.178.89.65:48094). Sep 9 05:38:15.504965 sshd[1732]: Accepted publickey for core from 139.178.89.65 port 48094 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:15.506579 sshd-session[1732]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:15.512894 systemd-logind[1453]: New session 6 of user core. Sep 9 05:38:15.520088 systemd[1]: Started session-6.scope - Session 6 of User core. Sep 9 05:38:15.721661 sshd[1735]: Connection closed by 139.178.89.65 port 48094 Sep 9 05:38:15.722537 sshd-session[1732]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:15.728519 systemd[1]: sshd@5-10.128.0.20:22-139.178.89.65:48094.service: Deactivated successfully. Sep 9 05:38:15.730795 systemd[1]: session-6.scope: Deactivated successfully. Sep 9 05:38:15.732051 systemd-logind[1453]: Session 6 logged out. Waiting for processes to exit. Sep 9 05:38:15.734215 systemd-logind[1453]: Removed session 6. Sep 9 05:38:15.773183 systemd[1]: Started sshd@6-10.128.0.20:22-139.178.89.65:48106.service - OpenSSH per-connection server daemon (139.178.89.65:48106). Sep 9 05:38:16.078816 sshd[1741]: Accepted publickey for core from 139.178.89.65 port 48106 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:16.080465 sshd-session[1741]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:16.086351 systemd-logind[1453]: New session 7 of user core. Sep 9 05:38:16.097114 systemd[1]: Started session-7.scope - Session 7 of User core. Sep 9 05:38:16.270000 sudo[1745]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Sep 9 05:38:16.270497 sudo[1745]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:16.288508 sudo[1745]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:16.331667 sshd[1744]: Connection closed by 139.178.89.65 port 48106 Sep 9 05:38:16.333150 sshd-session[1741]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:16.339439 systemd[1]: sshd@6-10.128.0.20:22-139.178.89.65:48106.service: Deactivated successfully. Sep 9 05:38:16.341821 systemd[1]: session-7.scope: Deactivated successfully. Sep 9 05:38:16.343054 systemd-logind[1453]: Session 7 logged out. Waiting for processes to exit. Sep 9 05:38:16.345119 systemd-logind[1453]: Removed session 7. Sep 9 05:38:16.397488 systemd[1]: Started sshd@7-10.128.0.20:22-139.178.89.65:48116.service - OpenSSH per-connection server daemon (139.178.89.65:48116). Sep 9 05:38:16.715933 sshd[1751]: Accepted publickey for core from 139.178.89.65 port 48116 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:16.717624 sshd-session[1751]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:16.724895 systemd-logind[1453]: New session 8 of user core. Sep 9 05:38:16.732033 systemd[1]: Started session-8.scope - Session 8 of User core. Sep 9 05:38:16.896657 sudo[1756]: 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 Sep 9 05:38:16.897157 sudo[1756]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:16.904513 sudo[1756]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:16.918365 sudo[1755]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules Sep 9 05:38:16.918863 sudo[1755]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:16.932423 systemd[1]: Starting audit-rules.service - Load Audit Rules... Sep 9 05:38:16.986380 augenrules[1778]: No rules Sep 9 05:38:16.987960 systemd[1]: audit-rules.service: Deactivated successfully. Sep 9 05:38:16.988291 systemd[1]: Finished audit-rules.service - Load Audit Rules. Sep 9 05:38:16.989649 sudo[1755]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:17.033193 sshd[1754]: Connection closed by 139.178.89.65 port 48116 Sep 9 05:38:17.034039 sshd-session[1751]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:17.040543 systemd[1]: sshd@7-10.128.0.20:22-139.178.89.65:48116.service: Deactivated successfully. Sep 9 05:38:17.043176 systemd[1]: session-8.scope: Deactivated successfully. Sep 9 05:38:17.044868 systemd-logind[1453]: Session 8 logged out. Waiting for processes to exit. Sep 9 05:38:17.046500 systemd-logind[1453]: Removed session 8. Sep 9 05:38:19.586290 systemd[1]: Started sshd@8-10.128.0.20:22-139.178.89.65:48118.service - OpenSSH per-connection server daemon (139.178.89.65:48118). Sep 9 05:38:19.897246 sshd[1787]: Accepted publickey for core from 139.178.89.65 port 48118 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:19.898917 sshd-session[1787]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:19.906600 systemd-logind[1453]: New session 9 of user core. Sep 9 05:38:19.912089 systemd[1]: Started session-9.scope - Session 9 of User core. Sep 9 05:38:20.085154 sudo[1795]: core : PWD=/tmp/tmp.rgt6L7QCSm ; USER=root ; COMMAND=/usr/sbin/ldd /usr/bin/nc /usr/bin/timeout Sep 9 05:38:20.085937 sudo[1795]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:20.101966 sudo[1795]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:20.116744 sudo[1807]: core : PWD=/tmp/tmp.rgt6L7QCSm ; 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 ./ Sep 9 05:38:20.117254 sudo[1807]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:20.189130 sudo[1807]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:20.203842 sudo[1791]: core : PWD=/tmp/tmp.rgt6L7QCSm ; USER=root ; COMMAND=/usr/sbin/docker build -t netcat . Sep 9 05:38:20.204349 sudo[1791]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:20.379059 systemd[1]: Starting docker.service - Docker Application Container Engine... Sep 9 05:38:20.408599 (dockerd)[1818]: 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 Sep 9 05:38:20.746299 dockerd[1818]: time="2025-09-09T05:38:20.746229382Z" level=info msg="Starting up" Sep 9 05:38:20.751588 dockerd[1818]: time="2025-09-09T05:38:20.751533459Z" level=info msg="OTEL tracing is not configured, using no-op tracer provider" Sep 9 05:38:20.767919 dockerd[1818]: time="2025-09-09T05:38:20.767821004Z" level=info msg="Creating a containerd client" address=/var/run/docker/libcontainerd/docker-containerd.sock timeout=1m0s Sep 9 05:38:20.791277 systemd[1]: var-lib-docker-check\x2doverlayfs\x2dsupport3150266532-merged.mount: Deactivated successfully. Sep 9 05:38:20.813089 systemd[1]: var-lib-docker-metacopy\x2dcheck1359898053-merged.mount: Deactivated successfully. Sep 9 05:38:20.834636 dockerd[1818]: time="2025-09-09T05:38:20.834385354Z" level=info msg="Loading containers: start." Sep 9 05:38:20.852086 kernel: Initializing XFRM netlink socket Sep 9 05:38:21.191331 systemd-networkd[1436]: docker0: Link UP Sep 9 05:38:21.197376 dockerd[1818]: time="2025-09-09T05:38:21.197300562Z" level=info msg="Loading containers: done." Sep 9 05:38:21.217399 dockerd[1818]: time="2025-09-09T05:38:21.217327924Z" 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 Sep 9 05:38:21.217624 dockerd[1818]: time="2025-09-09T05:38:21.217441964Z" level=info msg="Docker daemon" commit=6430e49a55babd9b8f4d08e70ecb2b68900770fe containerd-snapshotter=false storage-driver=overlay2 version=28.0.4 Sep 9 05:38:21.217624 dockerd[1818]: time="2025-09-09T05:38:21.217576930Z" level=info msg="Initializing buildkit" Sep 9 05:38:21.247793 dockerd[1818]: time="2025-09-09T05:38:21.247723061Z" level=info msg="Completed buildkit initialization" Sep 9 05:38:21.256808 dockerd[1818]: time="2025-09-09T05:38:21.256713657Z" level=info msg="Daemon has completed initialization" Sep 9 05:38:21.257096 dockerd[1818]: time="2025-09-09T05:38:21.256963520Z" level=info msg="API listen on /run/docker.sock" Sep 9 05:38:21.257260 systemd[1]: Started docker.service - Docker Application Container Engine. Sep 9 05:38:21.572919 dockerd[1818]: 2025/09/09 05:38:21 http2: server: error reading preface from client @: read unix /run/docker.sock->@: read: connection reset by peer Sep 9 05:38:21.859405 sudo[1791]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:21.903464 sshd[1790]: Connection closed by 139.178.89.65 port 48118 Sep 9 05:38:21.904433 sshd-session[1787]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:21.909769 systemd[1]: sshd@8-10.128.0.20:22-139.178.89.65:48118.service: Deactivated successfully. Sep 9 05:38:21.912479 systemd[1]: session-9.scope: Deactivated successfully. Sep 9 05:38:21.912776 systemd[1]: session-9.scope: Consumed 560ms CPU time, 129.1M memory peak. Sep 9 05:38:21.915085 systemd-logind[1453]: Session 9 logged out. Waiting for processes to exit. Sep 9 05:38:21.917757 systemd-logind[1453]: Removed session 9. Sep 9 05:38:21.969948 systemd[1]: Started sshd@10-10.128.0.20:22-139.178.89.65:48298.service - OpenSSH per-connection server daemon (139.178.89.65:48298). Sep 9 05:38:21.973076 systemd[1]: Started sshd@9-10.128.0.20:22-139.178.89.65:48300.service - OpenSSH per-connection server daemon (139.178.89.65:48300). Sep 9 05:38:22.279753 sshd[2050]: Accepted publickey for core from 139.178.89.65 port 48300 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:22.282433 sshd-session[2050]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:22.285408 sshd[2049]: Accepted publickey for core from 139.178.89.65 port 48298 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:22.287342 sshd-session[2049]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:22.290997 systemd-logind[1453]: New session 10 of user core. Sep 9 05:38:22.299015 systemd[1]: Started session-10.scope - Session 10 of User core. Sep 9 05:38:22.304351 systemd-logind[1453]: New session 11 of user core. Sep 9 05:38:22.312079 systemd[1]: Started session-11.scope - Session 11 of User core. Sep 9 05:38:22.466958 sudo[2058]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/lsof -i TCP:9988 -s TCP:LISTEN Sep 9 05:38:22.467458 sudo[2058]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:22.512986 sudo[2058]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:22.525127 systemd[1]: var-lib-docker-overlay2-874741ee26ae405c440f110af494185008eb66fca67e9b8703a02cf9b0221e05-merged.mount: Deactivated successfully. Sep 9 05:38:22.556923 sshd[2055]: Connection closed by 139.178.89.65 port 48300 Sep 9 05:38:22.559148 sshd-session[2050]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:22.567085 systemd[1]: sshd@9-10.128.0.20:22-139.178.89.65:48300.service: Deactivated successfully. Sep 9 05:38:22.574343 systemd[1]: session-10.scope: Deactivated successfully. Sep 9 05:38:22.576034 systemd-logind[1453]: Session 10 logged out. Waiting for processes to exit. Sep 9 05:38:22.578747 systemd-logind[1453]: Removed session 10. Sep 9 05:38:22.581543 containerd[1553]: time="2025-09-09T05:38:22.581469169Z" level=info msg="connecting to shim 339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac" address="unix:///run/containerd/s/b742b245b685c8012a64b175f1cd8e3d6ed25a507d810c1b49d21f4e36ee02e2" namespace=moby protocol=ttrpc version=3 Sep 9 05:38:22.613144 systemd[1]: Started docker-339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac.scope - libcontainer container 339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac. Sep 9 05:38:22.698033 kernel: docker0: port 1(veth1f6ac1b) entered blocking state Sep 9 05:38:22.698178 kernel: docker0: port 1(veth1f6ac1b) entered disabled state Sep 9 05:38:22.704531 kernel: veth1f6ac1b: entered allmulticast mode Sep 9 05:38:22.709851 kernel: veth1f6ac1b: entered promiscuous mode Sep 9 05:38:22.710919 systemd-networkd[1436]: veth1f6ac1b: Link UP Sep 9 05:38:22.719731 systemd[1]: Started sshd@11-10.128.0.20:22-139.178.89.65:48308.service - OpenSSH per-connection server daemon (139.178.89.65:48308). Sep 9 05:38:22.725856 kernel: eth0: renamed from vethe54d496 Sep 9 05:38:22.732762 systemd-networkd[1436]: veth1f6ac1b: Gained carrier Sep 9 05:38:22.732963 kernel: docker0: port 1(veth1f6ac1b) entered blocking state Sep 9 05:38:22.733012 kernel: docker0: port 1(veth1f6ac1b) entered forwarding state Sep 9 05:38:22.733706 systemd-networkd[1436]: docker0: Gained carrier Sep 9 05:38:23.043896 sshd[2123]: Accepted publickey for core from 139.178.89.65 port 48308 ssh2: RSA SHA256:QSDpUihtIai1/X8svdSqOld/LKc/E5lpY4TpkeXfmcw Sep 9 05:38:23.044638 sshd-session[2123]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Sep 9 05:38:23.052335 systemd-logind[1453]: New session 12 of user core. Sep 9 05:38:23.060091 systemd[1]: Started session-12.scope - Session 12 of User core. Sep 9 05:38:23.224624 sudo[2158]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/lsof -i TCP:9988 -s TCP:LISTEN Sep 9 05:38:23.225165 sudo[2158]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500) Sep 9 05:38:23.257064 sudo[2158]: pam_unix(sudo:session): session closed for user root Sep 9 05:38:23.300706 sshd[2156]: Connection closed by 139.178.89.65 port 48308 Sep 9 05:38:23.301539 sshd-session[2123]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:23.307966 systemd[1]: sshd@11-10.128.0.20:22-139.178.89.65:48308.service: Deactivated successfully. Sep 9 05:38:23.310530 systemd[1]: session-12.scope: Deactivated successfully. Sep 9 05:38:23.312091 systemd-logind[1453]: Session 12 logged out. Waiting for processes to exit. Sep 9 05:38:23.314334 systemd-logind[1453]: Removed session 12. Sep 9 05:38:23.929135 systemd-networkd[1436]: veth1f6ac1b: Gained IPv6LL Sep 9 05:38:24.185222 systemd-networkd[1436]: docker0: Gained IPv6LL Sep 9 05:38:24.208087 systemd[1]: docker-339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac.scope: Deactivated successfully. Sep 9 05:38:24.230943 containerd[1553]: time="2025-09-09T05:38:24.230704348Z" level=info msg="shim disconnected" id=339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac namespace=moby Sep 9 05:38:24.231971 containerd[1553]: time="2025-09-09T05:38:24.231495564Z" level=warning msg="cleaning up after shim disconnected" id=339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac namespace=moby Sep 9 05:38:24.231971 containerd[1553]: time="2025-09-09T05:38:24.231531573Z" level=info msg="cleaning up dead shim" namespace=moby Sep 9 05:38:24.232160 dockerd[1818]: time="2025-09-09T05:38:24.231777889Z" level=info msg="ignoring event" container=339d055ef4f0b2fdfe719c617aeb381a840e729c3eabf5bf0734be0db24f61ac module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete" Sep 9 05:38:24.298143 systemd-networkd[1436]: veth1f6ac1b: Lost carrier Sep 9 05:38:24.298877 kernel: docker0: port 1(veth1f6ac1b) entered disabled state Sep 9 05:38:24.298939 kernel: vethe54d496: renamed from eth0 Sep 9 05:38:24.315968 systemd-networkd[1436]: veth1f6ac1b: Link DOWN Sep 9 05:38:24.324358 kernel: docker0: port 1(veth1f6ac1b) entered disabled state Sep 9 05:38:24.324468 kernel: veth1f6ac1b (unregistering): left allmulticast mode Sep 9 05:38:24.336173 kernel: veth1f6ac1b (unregistering): left promiscuous mode Sep 9 05:38:24.336332 kernel: docker0: port 1(veth1f6ac1b) entered disabled state Sep 9 05:38:24.353554 systemd[1]: run-docker-netns-cb61b5467abc.mount: Deactivated successfully. Sep 9 05:38:24.362395 systemd[1]: var-lib-docker-overlay2-874741ee26ae405c440f110af494185008eb66fca67e9b8703a02cf9b0221e05-merged.mount: Deactivated successfully. Sep 9 05:38:24.414146 sshd[2056]: Connection closed by 139.178.89.65 port 48298 Sep 9 05:38:24.415180 sshd-session[2049]: pam_unix(sshd:session): session closed for user core Sep 9 05:38:24.420604 systemd[1]: sshd@10-10.128.0.20:22-139.178.89.65:48298.service: Deactivated successfully. Sep 9 05:38:24.423365 systemd[1]: session-11.scope: Deactivated successfully. Sep 9 05:38:24.426280 systemd-logind[1453]: Session 11 logged out. Waiting for processes to exit. Sep 9 05:38:24.428234 systemd-logind[1453]: Removed session 11. Sep 9 05:38:25.337313 systemd-networkd[1436]: docker0: Lost carrier