Jul 1 08:40:35.203066 kernel: Linux version 6.12.34-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 p1) 2.44.0) #1 SMP PREEMPT_DYNAMIC Mon Jun 30 19:26:54 -00 2025 Jul 1 08:40:35.203111 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:40:35.203123 kernel: BIOS-provided physical RAM map: Jul 1 08:40:35.203130 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable Jul 1 08:40:35.203137 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved Jul 1 08:40:35.203145 kernel: BIOS-e820: [mem 0x0000000000100000-0x000000003ff40fff] usable Jul 1 08:40:35.203154 kernel: BIOS-e820: [mem 0x000000003ff41000-0x000000003ffc8fff] reserved Jul 1 08:40:35.203166 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data Jul 1 08:40:35.203174 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS Jul 1 08:40:35.203181 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable Jul 1 08:40:35.203189 kernel: BIOS-e820: [mem 0x0000000100000000-0x0000000fdfffffff] usable Jul 1 08:40:35.203196 kernel: BIOS-e820: [mem 0x0000004fe0000000-0x0000005cbfffffff] usable Jul 1 08:40:35.203204 kernel: printk: legacy bootconsole [earlyser0] enabled Jul 1 08:40:35.203212 kernel: NX (Execute Disable) protection: active Jul 1 08:40:35.203224 kernel: APIC: Static calls initialized Jul 1 08:40:35.203233 kernel: efi: EFI v2.7 by Microsoft Jul 1 08:40:35.203241 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff85000 SMBIOS 3.0=0x3ff83000 MEMATTR=0x3f5bfa98 RNG=0x3ffd1018 Jul 1 08:40:35.203250 kernel: random: crng init done Jul 1 08:40:35.203258 kernel: secureboot: Secure boot disabled Jul 1 08:40:35.203266 kernel: SMBIOS 3.1.0 present. Jul 1 08:40:35.203275 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 03/08/2024 Jul 1 08:40:35.203283 kernel: DMI: Memory slots populated: 3/3 Jul 1 08:40:35.203292 kernel: Hypervisor detected: Microsoft Hyper-V Jul 1 08:40:35.203300 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3b8030, hints 0x62c2c, misc 0xed7b2 Jul 1 08:40:35.203311 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40 Jul 1 08:40:35.203320 kernel: Hyper-V: Using hypercall for remote TLB flush Jul 1 08:40:35.203328 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 1 08:40:35.203337 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns Jul 1 08:40:35.203345 kernel: tsc: Marking TSC unstable due to running on Hyper-V Jul 1 08:40:35.203354 kernel: tsc: Detected 2593.991 MHz processor Jul 1 08:40:35.203363 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved Jul 1 08:40:35.203371 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable Jul 1 08:40:35.203380 kernel: last_pfn = 0x5cc0000 max_arch_pfn = 0x400000000 Jul 1 08:40:35.203391 kernel: MTRR map: 5 entries (2 fixed + 3 variable; max 18), built from 8 variable MTRRs Jul 1 08:40:35.203400 kernel: x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT Jul 1 08:40:35.203408 kernel: e820: update [mem 0x40000000-0xffffffff] usable ==> reserved Jul 1 08:40:35.203417 kernel: e820: update [mem 0x1100000000-0x4fdfffffff] usable ==> reserved Jul 1 08:40:35.203425 kernel: last_pfn = 0x40000 max_arch_pfn = 0x400000000 Jul 1 08:40:35.203433 kernel: Using GB pages for direct mapping Jul 1 08:40:35.203442 kernel: ACPI: Early table checksum verification disabled Jul 1 08:40:35.203454 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL) Jul 1 08:40:35.203474 kernel: ACPI: XSDT 0x000000003FFF90E8 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203494 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203509 kernel: ACPI: DSDT 0x000000003FFD6000 01E184 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Jul 1 08:40:35.203517 kernel: ACPI: FACS 0x000000003FFFE000 000040 Jul 1 08:40:35.203526 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203535 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203548 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203557 kernel: ACPI: APIC 0x000000003FFD5000 000078 (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203566 kernel: ACPI: SRAT 0x000000003FFD4000 0001D0 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203575 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203584 kernel: ACPI: FPDT 0x000000003FFD2000 000034 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Jul 1 08:40:35.203593 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113] Jul 1 08:40:35.203602 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4183] Jul 1 08:40:35.203611 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f] Jul 1 08:40:35.203619 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063] Jul 1 08:40:35.203631 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f] Jul 1 08:40:35.203640 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027] Jul 1 08:40:35.203649 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5077] Jul 1 08:40:35.203658 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd41cf] Jul 1 08:40:35.203667 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037] Jul 1 08:40:35.203676 kernel: ACPI: Reserving FPDT table memory at [mem 0x3ffd2000-0x3ffd2033] Jul 1 08:40:35.203684 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff] hotplug Jul 1 08:40:35.203693 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0xfdfffffff] hotplug Jul 1 08:40:35.203705 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x4fe0000000-0x5cbfffffff] hotplug Jul 1 08:40:35.203718 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x5cc0200000-0xffffffffff] hotplug Jul 1 08:40:35.203727 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x10000200000-0x1ffffffffff] hotplug Jul 1 08:40:35.203736 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x20000200000-0x3ffffffffff] hotplug Jul 1 08:40:35.203745 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x40000200000-0x7ffffffffff] hotplug Jul 1 08:40:35.203754 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x80000200000-0xfffffffffff] hotplug Jul 1 08:40:35.203763 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0xfdfffffff] -> [mem 0x00001000-0xfdfffffff] Jul 1 08:40:35.203772 kernel: NUMA: Node 0 [mem 0x00001000-0xfdfffffff] + [mem 0x4fe0000000-0x5cbfffffff] -> [mem 0x00001000-0x5cbfffffff] Jul 1 08:40:35.203781 kernel: NODE_DATA(0) allocated [mem 0x5cbfff7dc0-0x5cbfffefff] Jul 1 08:40:35.203793 kernel: Zone ranges: Jul 1 08:40:35.203802 kernel: DMA [mem 0x0000000000001000-0x0000000000ffffff] Jul 1 08:40:35.203811 kernel: DMA32 [mem 0x0000000001000000-0x00000000ffffffff] Jul 1 08:40:35.203820 kernel: Normal [mem 0x0000000100000000-0x0000005cbfffffff] Jul 1 08:40:35.203829 kernel: Device empty Jul 1 08:40:35.203837 kernel: Movable zone start for each node Jul 1 08:40:35.203846 kernel: Early memory node ranges Jul 1 08:40:35.203855 kernel: node 0: [mem 0x0000000000001000-0x000000000009ffff] Jul 1 08:40:35.203864 kernel: node 0: [mem 0x0000000000100000-0x000000003ff40fff] Jul 1 08:40:35.203876 kernel: node 0: [mem 0x000000003ffff000-0x000000003fffffff] Jul 1 08:40:35.203885 kernel: node 0: [mem 0x0000000100000000-0x0000000fdfffffff] Jul 1 08:40:35.203894 kernel: node 0: [mem 0x0000004fe0000000-0x0000005cbfffffff] Jul 1 08:40:35.203902 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x0000005cbfffffff] Jul 1 08:40:35.203911 kernel: On node 0, zone DMA: 1 pages in unavailable ranges Jul 1 08:40:35.203920 kernel: On node 0, zone DMA: 96 pages in unavailable ranges Jul 1 08:40:35.203929 kernel: On node 0, zone DMA32: 190 pages in unavailable ranges Jul 1 08:40:35.203937 kernel: ACPI: PM-Timer IO Port: 0x408 Jul 1 08:40:35.203946 kernel: ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1]) Jul 1 08:40:35.203958 kernel: IOAPIC[0]: apic_id 6, version 17, address 0xfec00000, GSI 0-23 Jul 1 08:40:35.203967 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) Jul 1 08:40:35.203976 kernel: ACPI: Using ACPI (MADT) for SMP configuration information Jul 1 08:40:35.203985 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200 Jul 1 08:40:35.203994 kernel: CPU topo: Max. logical packages: 1 Jul 1 08:40:35.204003 kernel: CPU topo: Max. logical dies: 1 Jul 1 08:40:35.204011 kernel: CPU topo: Max. dies per package: 1 Jul 1 08:40:35.204020 kernel: CPU topo: Max. threads per core: 1 Jul 1 08:40:35.204029 kernel: CPU topo: Num. cores per package: 6 Jul 1 08:40:35.204040 kernel: CPU topo: Num. threads per package: 6 Jul 1 08:40:35.204049 kernel: CPU topo: Allowing 6 present CPUs plus 0 hotplug CPUs Jul 1 08:40:35.204058 kernel: [mem 0x40000000-0xffffffff] available for PCI devices Jul 1 08:40:35.204067 kernel: Booting paravirtualized kernel on Hyper-V Jul 1 08:40:35.204076 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns Jul 1 08:40:35.204085 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:6 nr_cpu_ids:6 nr_node_ids:1 Jul 1 08:40:35.204107 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u262144 Jul 1 08:40:35.204116 kernel: pcpu-alloc: s207832 r8192 d29736 u262144 alloc=1*2097152 Jul 1 08:40:35.204125 kernel: pcpu-alloc: [0] 0 1 2 3 4 5 - - Jul 1 08:40:35.204138 kernel: Hyper-V: PV spinlocks enabled Jul 1 08:40:35.204147 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear) Jul 1 08:40:35.204157 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:40:35.204166 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Jul 1 08:40:35.204182 kernel: Dentry cache hash table entries: 8388608 (order: 14, 67108864 bytes, linear) Jul 1 08:40:35.204196 kernel: Inode-cache hash table entries: 4194304 (order: 13, 33554432 bytes, linear) Jul 1 08:40:35.204210 kernel: Fallback order for Node 0: 0 Jul 1 08:40:35.204224 kernel: Built 1 zonelists, mobility grouping on. Total pages: 29359841 Jul 1 08:40:35.204240 kernel: Policy zone: Normal Jul 1 08:40:35.204257 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Jul 1 08:40:35.204267 kernel: software IO TLB: area num 8. Jul 1 08:40:35.204279 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=6, Nodes=1 Jul 1 08:40:35.204289 kernel: Kernel/User page tables isolation: enabled Jul 1 08:40:35.204298 kernel: ftrace: allocating 40095 entries in 157 pages Jul 1 08:40:35.204307 kernel: ftrace: allocated 157 pages with 5 groups Jul 1 08:40:35.204316 kernel: Dynamic Preempt: voluntary Jul 1 08:40:35.204326 kernel: rcu: Preemptible hierarchical RCU implementation. Jul 1 08:40:35.204336 kernel: rcu: RCU event tracing is enabled. Jul 1 08:40:35.204345 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=6. Jul 1 08:40:35.204355 kernel: Trampoline variant of Tasks RCU enabled. Jul 1 08:40:35.204367 kernel: Rude variant of Tasks RCU enabled. Jul 1 08:40:35.204377 kernel: Tracing variant of Tasks RCU enabled. Jul 1 08:40:35.204386 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Jul 1 08:40:35.204396 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=6 Jul 1 08:40:35.204411 kernel: RCU Tasks: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 1 08:40:35.204431 kernel: RCU Tasks Rude: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 1 08:40:35.204441 kernel: RCU Tasks Trace: Setting shift to 3 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=6. Jul 1 08:40:35.204450 kernel: Using NULL legacy PIC Jul 1 08:40:35.204459 kernel: NR_IRQS: 33024, nr_irqs: 472, preallocated irqs: 0 Jul 1 08:40:35.204468 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention. Jul 1 08:40:35.204478 kernel: Console: colour dummy device 80x25 Jul 1 08:40:35.204491 kernel: printk: legacy console [tty1] enabled Jul 1 08:40:35.204500 kernel: printk: legacy console [ttyS0] enabled Jul 1 08:40:35.204510 kernel: printk: legacy bootconsole [earlyser0] disabled Jul 1 08:40:35.204523 kernel: ACPI: Core revision 20240827 Jul 1 08:40:35.204532 kernel: Failed to register legacy timer interrupt Jul 1 08:40:35.204541 kernel: APIC: Switch to symmetric I/O mode setup Jul 1 08:40:35.204551 kernel: Hyper-V: Host Build 10.0.20348.1827-1-0 Jul 1 08:40:35.204560 kernel: Hyper-V: enabling crash_kexec_post_notifiers Jul 1 08:40:35.204569 kernel: Hyper-V: Using IPI hypercalls Jul 1 08:40:35.204579 kernel: APIC: send_IPI() replaced with hv_send_ipi() Jul 1 08:40:35.204588 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask() Jul 1 08:40:35.204598 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself() Jul 1 08:40:35.204610 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself() Jul 1 08:40:35.204620 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all() Jul 1 08:40:35.204629 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self() Jul 1 08:40:35.204638 kernel: Hyper-V: Using enlightened APIC (xapic mode) Jul 1 08:40:35.204648 kernel: APIC: eoi() replaced with hv_apic_eoi_write() Jul 1 08:40:35.204657 kernel: APIC: read() replaced with hv_apic_read() Jul 1 08:40:35.204667 kernel: APIC: write() replaced with hv_apic_write() Jul 1 08:40:35.204676 kernel: APIC: icr_write() replaced with hv_apic_icr_write() Jul 1 08:40:35.204685 kernel: APIC: icr_read() replaced with hv_apic_icr_read() Jul 1 08:40:35.204698 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 5187.98 BogoMIPS (lpj=2593991) Jul 1 08:40:35.204707 kernel: Last level iTLB entries: 4KB 128, 2MB 8, 4MB 8 Jul 1 08:40:35.204717 kernel: Last level dTLB entries: 4KB 64, 2MB 32, 4MB 32, 1GB 4 Jul 1 08:40:35.204726 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization Jul 1 08:40:35.204735 kernel: Spectre V2 : Mitigation: Retpolines Jul 1 08:40:35.204744 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT Jul 1 08:40:35.204753 kernel: Speculative Store Bypass: Vulnerable Jul 1 08:40:35.204763 kernel: MDS: Mitigation: Clear CPU buffers Jul 1 08:40:35.204772 kernel: TAA: Mitigation: Clear CPU buffers Jul 1 08:40:35.204781 kernel: MMIO Stale Data: Vulnerable: Clear CPU buffers attempted, no microcode Jul 1 08:40:35.204793 kernel: ITS: Mitigation: Aligned branch/return thunks Jul 1 08:40:35.204802 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers' Jul 1 08:40:35.204812 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers' Jul 1 08:40:35.204821 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers' Jul 1 08:40:35.204830 kernel: x86/fpu: xstate_offset[2]: 576, xstate_sizes[2]: 256 Jul 1 08:40:35.204839 kernel: x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format. Jul 1 08:40:35.204848 kernel: Freeing SMP alternatives memory: 32K Jul 1 08:40:35.204857 kernel: pid_max: default: 32768 minimum: 301 Jul 1 08:40:35.204867 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima Jul 1 08:40:35.204876 kernel: landlock: Up and running. Jul 1 08:40:35.204885 kernel: SELinux: Initializing. Jul 1 08:40:35.204897 kernel: Mount-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 1 08:40:35.204907 kernel: Mountpoint-cache hash table entries: 131072 (order: 8, 1048576 bytes, linear) Jul 1 08:40:35.204916 kernel: smpboot: CPU0: Intel(R) Xeon(R) CPU E5-2690 v4 @ 2.60GHz (family: 0x6, model: 0x4f, stepping: 0x1) Jul 1 08:40:35.204925 kernel: Performance Events: unsupported p6 CPU model 79 no PMU driver, software events only. Jul 1 08:40:35.204934 kernel: signal: max sigframe size: 1776 Jul 1 08:40:35.204944 kernel: rcu: Hierarchical SRCU implementation. Jul 1 08:40:35.204953 kernel: rcu: Max phase no-delay instances is 400. Jul 1 08:40:35.204963 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level Jul 1 08:40:35.204972 kernel: NMI watchdog: Perf NMI watchdog permanently disabled Jul 1 08:40:35.204981 kernel: smp: Bringing up secondary CPUs ... Jul 1 08:40:35.204993 kernel: smpboot: x86: Booting SMP configuration: Jul 1 08:40:35.205003 kernel: .... node #0, CPUs: #1 #2 #3 #4 #5 Jul 1 08:40:35.205012 kernel: smp: Brought up 1 node, 6 CPUs Jul 1 08:40:35.205021 kernel: smpboot: Total of 6 processors activated (31127.89 BogoMIPS) Jul 1 08:40:35.205031 kernel: Memory: 115339092K/117439364K available (14336K kernel code, 2430K rwdata, 9956K rodata, 54508K init, 2460K bss, 2089996K reserved, 0K cma-reserved) Jul 1 08:40:35.205040 kernel: devtmpfs: initialized Jul 1 08:40:35.205050 kernel: x86/mm: Memory block size: 1024MB Jul 1 08:40:35.205059 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes) Jul 1 08:40:35.205068 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Jul 1 08:40:35.205081 kernel: futex hash table entries: 2048 (order: 5, 131072 bytes, linear) Jul 1 08:40:35.205116 kernel: pinctrl core: initialized pinctrl subsystem Jul 1 08:40:35.205126 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Jul 1 08:40:35.205135 kernel: audit: initializing netlink subsys (disabled) Jul 1 08:40:35.205145 kernel: audit: type=2000 audit(1751359231.028:1): state=initialized audit_enabled=0 res=1 Jul 1 08:40:35.205154 kernel: thermal_sys: Registered thermal governor 'step_wise' Jul 1 08:40:35.205163 kernel: thermal_sys: Registered thermal governor 'user_space' Jul 1 08:40:35.205173 kernel: cpuidle: using governor menu Jul 1 08:40:35.205182 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Jul 1 08:40:35.205196 kernel: dca service started, version 1.12.1 Jul 1 08:40:35.205206 kernel: e820: reserve RAM buffer [mem 0x3ff41000-0x3fffffff] Jul 1 08:40:35.205215 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible. Jul 1 08:40:35.205224 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages Jul 1 08:40:35.205234 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page Jul 1 08:40:35.205243 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages Jul 1 08:40:35.205252 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page Jul 1 08:40:35.205261 kernel: ACPI: Added _OSI(Module Device) Jul 1 08:40:35.205271 kernel: ACPI: Added _OSI(Processor Device) Jul 1 08:40:35.205283 kernel: ACPI: Added _OSI(Processor Aggregator Device) Jul 1 08:40:35.205293 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Jul 1 08:40:35.205302 kernel: ACPI: Interpreter enabled Jul 1 08:40:35.205311 kernel: ACPI: PM: (supports S0 S5) Jul 1 08:40:35.205320 kernel: ACPI: Using IOAPIC for interrupt routing Jul 1 08:40:35.205330 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug Jul 1 08:40:35.205339 kernel: PCI: Ignoring E820 reservations for host bridge windows Jul 1 08:40:35.205348 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F Jul 1 08:40:35.205361 kernel: iommu: Default domain type: Translated Jul 1 08:40:35.205374 kernel: iommu: DMA domain TLB invalidation policy: lazy mode Jul 1 08:40:35.205384 kernel: efivars: Registered efivars operations Jul 1 08:40:35.205393 kernel: PCI: Using ACPI for IRQ routing Jul 1 08:40:35.205402 kernel: PCI: System does not support PCI Jul 1 08:40:35.205412 kernel: vgaarb: loaded Jul 1 08:40:35.205421 kernel: clocksource: Switched to clocksource hyperv_clocksource_tsc_page Jul 1 08:40:35.205430 kernel: VFS: Disk quotas dquot_6.6.0 Jul 1 08:40:35.205440 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Jul 1 08:40:35.205449 kernel: pnp: PnP ACPI init Jul 1 08:40:35.205462 kernel: pnp: PnP ACPI: found 3 devices Jul 1 08:40:35.205471 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns Jul 1 08:40:35.205480 kernel: NET: Registered PF_INET protocol family Jul 1 08:40:35.205498 kernel: IP idents hash table entries: 262144 (order: 9, 2097152 bytes, linear) Jul 1 08:40:35.205508 kernel: tcp_listen_portaddr_hash hash table entries: 65536 (order: 8, 1048576 bytes, linear) Jul 1 08:40:35.205517 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Jul 1 08:40:35.205527 kernel: TCP established hash table entries: 524288 (order: 10, 4194304 bytes, linear) Jul 1 08:40:35.205536 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 1 08:40:35.205550 kernel: TCP: Hash tables configured (established 524288 bind 65536) Jul 1 08:40:35.205559 kernel: UDP hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 1 08:40:35.205569 kernel: UDP-Lite hash table entries: 65536 (order: 9, 2097152 bytes, linear) Jul 1 08:40:35.205578 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Jul 1 08:40:35.205587 kernel: NET: Registered PF_XDP protocol family Jul 1 08:40:35.205596 kernel: PCI: CLS 0 bytes, default 64 Jul 1 08:40:35.205606 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB) Jul 1 08:40:35.205615 kernel: software IO TLB: mapped [mem 0x000000003b5bf000-0x000000003f5bf000] (64MB) Jul 1 08:40:35.205625 kernel: RAPL PMU: API unit is 2^-32 Joules, 0 fixed counters, 10737418240 ms ovfl timer Jul 1 08:40:35.205637 kernel: Initialise system trusted keyrings Jul 1 08:40:35.205646 kernel: workingset: timestamp_bits=39 max_order=25 bucket_order=0 Jul 1 08:40:35.205656 kernel: Key type asymmetric registered Jul 1 08:40:35.205665 kernel: Asymmetric key parser 'x509' registered Jul 1 08:40:35.205674 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250) Jul 1 08:40:35.205683 kernel: io scheduler mq-deadline registered Jul 1 08:40:35.205692 kernel: io scheduler kyber registered Jul 1 08:40:35.205702 kernel: io scheduler bfq registered Jul 1 08:40:35.205711 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00 Jul 1 08:40:35.205720 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Jul 1 08:40:35.205733 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A Jul 1 08:40:35.205742 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A Jul 1 08:40:35.205752 kernel: i8042: PNP: No PS/2 controller found. Jul 1 08:40:35.205916 kernel: rtc_cmos 00:02: registered as rtc0 Jul 1 08:40:35.206035 kernel: rtc_cmos 00:02: setting system clock to 2025-07-01T08:40:34 UTC (1751359234) Jul 1 08:40:35.206168 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram Jul 1 08:40:35.206183 kernel: intel_pstate: CPU model not supported Jul 1 08:40:35.206198 kernel: efifb: probing for efifb Jul 1 08:40:35.206209 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Jul 1 08:40:35.206218 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Jul 1 08:40:35.206227 kernel: efifb: scrolling: redraw Jul 1 08:40:35.206237 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Jul 1 08:40:35.206246 kernel: Console: switching to colour frame buffer device 128x48 Jul 1 08:40:35.206256 kernel: fb0: EFI VGA frame buffer device Jul 1 08:40:35.206265 kernel: pstore: Using crash dump compression: deflate Jul 1 08:40:35.206274 kernel: pstore: Registered efi_pstore as persistent store backend Jul 1 08:40:35.206287 kernel: NET: Registered PF_INET6 protocol family Jul 1 08:40:35.206296 kernel: Segment Routing with IPv6 Jul 1 08:40:35.206306 kernel: In-situ OAM (IOAM) with IPv6 Jul 1 08:40:35.206315 kernel: NET: Registered PF_PACKET protocol family Jul 1 08:40:35.206324 kernel: Key type dns_resolver registered Jul 1 08:40:35.206333 kernel: IPI shorthand broadcast: enabled Jul 1 08:40:35.206343 kernel: sched_clock: Marking stable (3739005000, 117663800)->(4214579600, -357910800) Jul 1 08:40:35.206352 kernel: registered taskstats version 1 Jul 1 08:40:35.206361 kernel: Loading compiled-in X.509 certificates Jul 1 08:40:35.206374 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.34-flatcar: bdab85da21e6e40e781d68d3bf17f0a40ee7357c' Jul 1 08:40:35.206383 kernel: Demotion targets for Node 0: null Jul 1 08:40:35.206392 kernel: Key type .fscrypt registered Jul 1 08:40:35.206402 kernel: Key type fscrypt-provisioning registered Jul 1 08:40:35.206411 kernel: ima: No TPM chip found, activating TPM-bypass! Jul 1 08:40:35.206420 kernel: ima: Allocated hash algorithm: sha1 Jul 1 08:40:35.206429 kernel: ima: No architecture policies found Jul 1 08:40:35.206438 kernel: clk: Disabling unused clocks Jul 1 08:40:35.206448 kernel: Warning: unable to open an initial console. Jul 1 08:40:35.206460 kernel: Freeing unused kernel image (initmem) memory: 54508K Jul 1 08:40:35.206470 kernel: Write protecting the kernel read-only data: 24576k Jul 1 08:40:35.206479 kernel: Freeing unused kernel image (rodata/data gap) memory: 284K Jul 1 08:40:35.206489 kernel: Run /init as init process Jul 1 08:40:35.206498 kernel: with arguments: Jul 1 08:40:35.206507 kernel: /init Jul 1 08:40:35.206516 kernel: with environment: Jul 1 08:40:35.206525 kernel: HOME=/ Jul 1 08:40:35.206534 kernel: TERM=linux Jul 1 08:40:35.206547 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Jul 1 08:40:35.206558 systemd[1]: Successfully made /usr/ read-only. Jul 1 08:40:35.206570 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) Jul 1 08:40:35.206581 systemd[1]: Detected virtualization microsoft. Jul 1 08:40:35.206591 systemd[1]: Detected architecture x86-64. Jul 1 08:40:35.206600 systemd[1]: Running in initrd. Jul 1 08:40:35.206610 systemd[1]: No hostname configured, using default hostname. Jul 1 08:40:35.206623 systemd[1]: Hostname set to . Jul 1 08:40:35.206633 systemd[1]: Initializing machine ID from random generator. Jul 1 08:40:35.206643 systemd[1]: Queued start job for default target initrd.target. Jul 1 08:40:35.206653 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:40:35.206664 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:40:35.206674 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM... Jul 1 08:40:35.206684 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 1 08:40:35.206695 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT... Jul 1 08:40:35.206709 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A... Jul 1 08:40:35.206720 systemd[1]: Expecting device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - /dev/disk/by-partuuid/7130c94a-213a-4e5a-8e26-6cce9662f132... Jul 1 08:40:35.206730 systemd[1]: Expecting device dev-mapper-usr.device - /dev/mapper/usr... Jul 1 08:40:35.206740 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:40:35.206750 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:40:35.206760 systemd[1]: Reached target paths.target - Path Units. Jul 1 08:40:35.206770 systemd[1]: Reached target slices.target - Slice Units. Jul 1 08:40:35.206783 systemd[1]: Reached target swap.target - Swaps. Jul 1 08:40:35.206794 systemd[1]: Reached target timers.target - Timer Units. Jul 1 08:40:35.206804 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket. Jul 1 08:40:35.206814 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 1 08:40:35.206824 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log). Jul 1 08:40:35.206834 systemd[1]: Listening on systemd-journald.socket - Journal Sockets. Jul 1 08:40:35.206844 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:40:35.206854 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 1 08:40:35.206864 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:40:35.206878 systemd[1]: Reached target sockets.target - Socket Units. Jul 1 08:40:35.206888 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup... Jul 1 08:40:35.206898 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 1 08:40:35.206908 systemd[1]: Finished network-cleanup.service - Network Cleanup. Jul 1 08:40:35.206918 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). Jul 1 08:40:35.206929 systemd[1]: Starting systemd-fsck-usr.service... Jul 1 08:40:35.206939 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 1 08:40:35.206949 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 1 08:40:35.206962 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:35.206994 systemd-journald[232]: Collecting audit messages is disabled. Jul 1 08:40:35.207018 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup. Jul 1 08:40:35.207034 systemd-journald[232]: Journal started Jul 1 08:40:35.207055 systemd-journald[232]: Runtime Journal (/run/log/journal/f5873be6542b4f9e850bc92be7310899) is 8M, max 2.2G, 2.1G free. Jul 1 08:40:35.204447 systemd-modules-load[234]: Inserted module 'overlay' Jul 1 08:40:35.216445 systemd[1]: Started systemd-journald.service - Journal Service. Jul 1 08:40:35.216830 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:40:35.217376 systemd[1]: Finished systemd-fsck-usr.service. Jul 1 08:40:35.220306 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 1 08:40:35.221426 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 1 08:40:35.252570 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:35.270960 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters... Jul 1 08:40:35.273334 systemd-tmpfiles[245]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring. Jul 1 08:40:35.280973 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:40:35.283075 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:40:35.304315 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 1 08:40:35.312459 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 1 08:40:35.318530 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook... Jul 1 08:40:35.326782 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:40:35.340981 dracut-cmdline[264]: 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=tty1 console=ttyS0,115200n8 earlyprintk=ttyS0,115200 flatcar.first_boot=detected flatcar.oem.id=azure flatcar.autologin verity.usrhash=03b744fdab9d0c2a6ce16909d1444c286b74402b7ab027472687ca33469d417f Jul 1 08:40:35.375113 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Jul 1 08:40:35.378495 systemd-modules-load[234]: Inserted module 'br_netfilter' Jul 1 08:40:35.380031 kernel: Bridge firewalling registered Jul 1 08:40:35.379417 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 1 08:40:35.387342 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 1 08:40:35.404245 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:40:35.406356 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 1 08:40:35.453663 systemd-resolved[321]: Positive Trust Anchors: Jul 1 08:40:35.453678 systemd-resolved[321]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 1 08:40:35.453709 systemd-resolved[321]: 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 Jul 1 08:40:35.456464 systemd-resolved[321]: Defaulting to hostname 'linux'. Jul 1 08:40:35.457486 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 1 08:40:35.467145 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:40:35.535115 kernel: SCSI subsystem initialized Jul 1 08:40:35.546111 kernel: Loading iSCSI transport class v2.0-870. Jul 1 08:40:35.558112 kernel: iscsi: registered transport (tcp) Jul 1 08:40:35.580839 kernel: iscsi: registered transport (qla4xxx) Jul 1 08:40:35.580866 kernel: QLogic iSCSI HBA Driver Jul 1 08:40:35.599667 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 1 08:40:35.631397 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:40:35.643626 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 1 08:40:35.687457 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook. Jul 1 08:40:35.690260 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook... Jul 1 08:40:35.760110 kernel: raid6: avx2x4 gen() 30556 MB/s Jul 1 08:40:35.779100 kernel: raid6: avx2x2 gen() 30875 MB/s Jul 1 08:40:35.800277 kernel: raid6: avx2x1 gen() 26837 MB/s Jul 1 08:40:35.800301 kernel: raid6: using algorithm avx2x2 gen() 30875 MB/s Jul 1 08:40:35.823360 kernel: raid6: .... xor() 18260 MB/s, rmw enabled Jul 1 08:40:35.823387 kernel: raid6: using avx2x2 recovery algorithm Jul 1 08:40:35.846112 kernel: xor: automatically using best checksumming function avx Jul 1 08:40:36.030121 kernel: Btrfs loaded, zoned=no, fsverity=no Jul 1 08:40:36.036984 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook. Jul 1 08:40:36.039941 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:40:36.078188 systemd-udevd[484]: Using default interface naming scheme 'v255'. Jul 1 08:40:36.084560 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:40:36.086869 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook... Jul 1 08:40:36.128173 dracut-pre-trigger[491]: rd.md=0: removing MD RAID activation Jul 1 08:40:36.156121 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook. Jul 1 08:40:36.166325 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 1 08:40:36.252267 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:40:36.266143 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook... Jul 1 08:40:36.282108 kernel: cryptd: max_cpu_qlen set to 1000 Jul 1 08:40:36.291120 kernel: AES CTR mode by8 optimization enabled Jul 1 08:40:36.294104 kernel: hv_vmbus: Vmbus version:4.0 Jul 1 08:40:36.306736 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:40:36.307504 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:36.315366 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:36.321213 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:36.337555 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:40:36.346104 kernel: hv_vmbus: registering driver hyperv_keyboard Jul 1 08:40:36.346141 kernel: pps_core: LinuxPPS API ver. 1 registered Jul 1 08:40:36.349564 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:40:36.367149 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Jul 1 08:40:36.367172 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Jul 1 08:40:36.367185 kernel: hid: raw HID events driver (C) Jiri Kosina Jul 1 08:40:36.349686 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:36.378644 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:40:36.381887 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:36.394228 kernel: hv_vmbus: registering driver hid_hyperv Jul 1 08:40:36.394257 kernel: hv_vmbus: registering driver hv_pci Jul 1 08:40:36.394270 kernel: hv_vmbus: registering driver hv_netvsc Jul 1 08:40:36.396103 kernel: PTP clock support registered Jul 1 08:40:36.400996 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Jul 1 08:40:36.409108 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI VMBus probing: Using version 0x10004 Jul 1 08:40:36.409300 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Jul 1 08:40:36.420200 kernel: hv_vmbus: registering driver hv_storvsc Jul 1 08:40:36.420229 kernel: hv_pci 47505500-0001-0000-3130-444531444234: PCI host bridge to bus 0001:00 Jul 1 08:40:36.426446 kernel: scsi host0: storvsc_host_t Jul 1 08:40:36.428113 kernel: pci_bus 0001:00: root bus resource [mem 0x41000000-0x41ffffff window] Jul 1 08:40:36.428318 kernel: scsi host1: storvsc_host_t Jul 1 08:40:36.432236 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 1 08:40:36.432281 kernel: pci_bus 0001:00: root bus resource [mem 0x1000000000-0x1401ffffff window] Jul 1 08:40:36.432453 kernel: pci_bus 0001:00: No busn resource found for root bus, will use [bus 00-ff] Jul 1 08:40:36.434105 kernel: pci 0001:00:00.0: [10de:1db4] type 00 class 0x030200 PCIe Endpoint Jul 1 08:40:36.442066 kernel: scsi 0:0:0:1: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Jul 1 08:40:36.442125 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff] Jul 1 08:40:36.446164 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Jul 1 08:40:36.450134 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref] Jul 1 08:40:36.455138 kernel: hv_utils: Registering HyperV Utility Driver Jul 1 08:40:36.462097 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref] Jul 1 08:40:36.462149 kernel: hv_vmbus: registering driver hv_utils Jul 1 08:40:36.466113 kernel: pci 0001:00:00.0: Enabling HDA controller Jul 1 08:40:36.473134 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Jul 1 08:40:36.473326 kernel: sd 0:0:0:1: [sdb] 1543503872 512-byte logical blocks: (790 GB/736 GiB) Jul 1 08:40:36.473485 kernel: sd 0:0:0:1: [sdb] 4096-byte physical blocks Jul 1 08:40:36.476159 kernel: sd 0:0:0:1: [sdb] Write Protect is off Jul 1 08:40:36.476334 kernel: pci 0001:00:00.0: quirk_nvidia_hda+0x0/0x70 took 11392 usecs Jul 1 08:40:36.481615 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Jul 1 08:40:36.481776 kernel: sd 0:0:0:1: [sdb] Mode Sense: 0f 00 10 00 Jul 1 08:40:36.489105 kernel: sd 0:0:0:0: [sda] Write Protect is off Jul 1 08:40:36.493125 kernel: sd 0:0:0:1: [sdb] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 1 08:40:36.493287 kernel: pci_bus 0001:00: busn_res: [bus 00-ff] end is updated to 00 Jul 1 08:40:36.493413 kernel: pci 0001:00:00.0: BAR 1 [mem 0x1000000000-0x13ffffffff 64bit pref]: assigned Jul 1 08:40:36.493572 kernel: pci 0001:00:00.0: BAR 3 [mem 0x1400000000-0x1401ffffff 64bit pref]: assigned Jul 1 08:40:36.494197 kernel: pci 0001:00:00.0: BAR 0 [mem 0x41000000-0x41ffffff]: assigned Jul 1 08:40:36.497061 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Jul 1 08:40:36.499911 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#245 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:36.517605 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#252 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:36.539107 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Jul 1 08:40:36.544108 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#515 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:36.555349 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#522 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:36.555557 kernel: sdb: sdb1 Jul 1 08:40:36.556182 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:36.568286 kernel: sd 0:0:0:1: [sdb] Attached SCSI disk Jul 1 08:40:36.574948 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 1 08:40:36.574977 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Jul 1 08:40:36.702338 kernel: hv_utils: Heartbeat IC version 3.0 Jul 1 08:40:36.702386 kernel: hv_utils: Shutdown IC version 3.2 Jul 1 08:40:36.705027 kernel: hv_utils: TimeSync IC version 4.0 Jul 1 08:40:36.706111 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Jul 1 08:40:36.859997 systemd-resolved[321]: Clock change detected. Flushing caches. Jul 1 08:40:36.903159 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Jul 1 08:40:36.903182 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Jul 1 08:40:37.393151 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - Virtual_Disk EFI-SYSTEM. Jul 1 08:40:37.418090 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - Virtual_Disk USR-A. Jul 1 08:40:37.423218 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device - Virtual_Disk USR-A. Jul 1 08:40:37.439769 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 1 08:40:37.442552 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary... Jul 1 08:40:37.490069 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - Virtual_Disk ROOT. Jul 1 08:40:37.505143 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#618 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:37.515706 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 1 08:40:37.524715 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#46 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:37.542716 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 1 08:40:38.550427 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#199 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:40:38.561457 disk-uuid[727]: The operation has completed successfully. Jul 1 08:40:38.565245 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Jul 1 08:40:39.247524 systemd[1]: disk-uuid.service: Deactivated successfully. Jul 1 08:40:39.247639 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary. Jul 1 08:40:39.252561 systemd[1]: Starting verity-setup.service - Verity Setup for /dev/mapper/usr... Jul 1 08:40:39.271844 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook. Jul 1 08:40:39.281029 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems. Jul 1 08:40:39.289190 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:40:39.298739 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 1 08:40:39.301717 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook... Jul 1 08:40:39.320287 sh[890]: Success Jul 1 08:40:39.337361 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook. Jul 1 08:40:39.400267 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Jul 1 08:40:39.400304 kernel: device-mapper: uevent: version 1.0.3 Jul 1 08:40:39.405714 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev Jul 1 08:40:39.417710 kernel: device-mapper: verity: sha256 using shash "sha256-avx2" Jul 1 08:40:39.657970 systemd[1]: Found device dev-mapper-usr.device - /dev/mapper/usr. Jul 1 08:40:39.667274 systemd[1]: Mounting sysusr-usr.mount - /sysusr/usr... Jul 1 08:40:39.704519 systemd[1]: Finished verity-setup.service - Verity Setup for /dev/mapper/usr. Jul 1 08:40:39.724780 kernel: BTRFS info: 'norecovery' is for compatibility only, recommended to use 'rescue=nologreplay' Jul 1 08:40:39.724806 kernel: BTRFS: device fsid aeab36fb-d8a9-440c-a872-a8cce0218739 devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (919) Jul 1 08:40:39.735519 kernel: BTRFS info (device dm-0): first mount of filesystem aeab36fb-d8a9-440c-a872-a8cce0218739 Jul 1 08:40:39.735552 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:40:39.738759 kernel: BTRFS info (device dm-0): using free-space-tree Jul 1 08:40:40.298376 systemd[1]: Mounted sysusr-usr.mount - /sysusr/usr. Jul 1 08:40:40.300405 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System. Jul 1 08:40:40.306734 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met. Jul 1 08:40:40.307544 systemd[1]: Starting ignition-setup.service - Ignition (setup)... Jul 1 08:40:40.338248 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline... Jul 1 08:40:40.378734 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (948) Jul 1 08:40:40.378785 kernel: BTRFS info (device sda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:40:40.387394 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:40:40.390435 kernel: BTRFS info (device sda6): using free-space-tree Jul 1 08:40:40.414706 kernel: BTRFS info (device sda6): last unmount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:40:40.414769 systemd[1]: Finished ignition-setup.service - Ignition (setup). Jul 1 08:40:40.420229 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)... Jul 1 08:40:40.457961 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 1 08:40:40.460846 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 1 08:40:40.509046 systemd-networkd[1088]: lo: Link UP Jul 1 08:40:40.509055 systemd-networkd[1088]: lo: Gained carrier Jul 1 08:40:40.510103 systemd-networkd[1088]: Enumeration completed Jul 1 08:40:40.510183 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 1 08:40:40.510469 systemd-networkd[1088]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:40:40.510473 systemd-networkd[1088]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 1 08:40:40.511263 systemd-networkd[1088]: eth0: Link UP Jul 1 08:40:40.511468 systemd-networkd[1088]: eth0: Gained carrier Jul 1 08:40:40.511469 systemd[1]: Reached target network.target - Network. Jul 1 08:40:40.511478 systemd-networkd[1088]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:40:40.580734 systemd-networkd[1088]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 1 08:40:42.069514 ignition[1041]: Ignition 2.21.0 Jul 1 08:40:42.069540 ignition[1041]: Stage: fetch-offline Jul 1 08:40:42.069660 ignition[1041]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:42.069670 ignition[1041]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:42.069791 ignition[1041]: parsed url from cmdline: "" Jul 1 08:40:42.069795 ignition[1041]: no config URL provided Jul 1 08:40:42.069801 ignition[1041]: reading system config file "/usr/lib/ignition/user.ign" Jul 1 08:40:42.069810 ignition[1041]: no config at "/usr/lib/ignition/user.ign" Jul 1 08:40:42.069815 ignition[1041]: failed to fetch config: resource requires networking Jul 1 08:40:42.069970 ignition[1041]: Ignition finished successfully Jul 1 08:40:42.101262 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline). Jul 1 08:40:42.103760 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)... Jul 1 08:40:42.152604 ignition[1101]: Ignition 2.21.0 Jul 1 08:40:42.152619 ignition[1101]: Stage: fetch Jul 1 08:40:42.152826 ignition[1101]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:42.152836 ignition[1101]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:42.152932 ignition[1101]: parsed url from cmdline: "" Jul 1 08:40:42.152936 ignition[1101]: no config URL provided Jul 1 08:40:42.152942 ignition[1101]: reading system config file "/usr/lib/ignition/user.ign" Jul 1 08:40:42.152954 ignition[1101]: no config at "/usr/lib/ignition/user.ign" Jul 1 08:40:42.152986 ignition[1101]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Jul 1 08:40:42.265471 ignition[1101]: GET result: OK Jul 1 08:40:42.265623 ignition[1101]: config has been read from IMDS userdata Jul 1 08:40:42.265643 ignition[1101]: parsing config with SHA512: 657faf92986b96e699a6766979e131ec2daeddfa78b8a6db61e44a447e66281c259f8a8ee75eccc594ebe4cb06a5e3712291c48cd9010c59cf1c4d22a5bf5f24 Jul 1 08:40:42.269513 unknown[1101]: fetched base config from "system" Jul 1 08:40:42.269671 ignition[1101]: fetch: fetch complete Jul 1 08:40:42.269520 unknown[1101]: fetched base config from "system" Jul 1 08:40:42.269676 ignition[1101]: fetch: fetch passed Jul 1 08:40:42.269526 unknown[1101]: fetched user config from "azure" Jul 1 08:40:42.269750 ignition[1101]: Ignition finished successfully Jul 1 08:40:42.286161 systemd[1]: Finished ignition-fetch.service - Ignition (fetch). Jul 1 08:40:42.288994 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)... Jul 1 08:40:42.324381 ignition[1110]: Ignition 2.21.0 Jul 1 08:40:42.324393 ignition[1110]: Stage: kargs Jul 1 08:40:42.324634 ignition[1110]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:42.324646 ignition[1110]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:42.333064 systemd[1]: Finished ignition-kargs.service - Ignition (kargs). Jul 1 08:40:42.325942 ignition[1110]: kargs: kargs passed Jul 1 08:40:42.341671 systemd[1]: Starting ignition-disks.service - Ignition (disks)... Jul 1 08:40:42.326001 ignition[1110]: Ignition finished successfully Jul 1 08:40:42.381151 ignition[1119]: Ignition 2.21.0 Jul 1 08:40:42.381165 ignition[1119]: Stage: disks Jul 1 08:40:42.384412 ignition[1119]: no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:42.384429 ignition[1119]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:42.384971 ignition[1119]: disks: disks passed Jul 1 08:40:42.385015 ignition[1119]: Ignition finished successfully Jul 1 08:40:42.393796 systemd-networkd[1088]: eth0: Gained IPv6LL Jul 1 08:40:42.394832 systemd[1]: Finished ignition-disks.service - Ignition (disks). Jul 1 08:40:42.400161 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device. Jul 1 08:40:42.407584 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems. Jul 1 08:40:42.415506 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 1 08:40:42.423393 systemd[1]: Reached target sysinit.target - System Initialization. Jul 1 08:40:42.431772 systemd[1]: Reached target basic.target - Basic System. Jul 1 08:40:42.434072 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT... Jul 1 08:40:42.546096 systemd-fsck[1130]: ROOT: clean, 15/7326000 files, 477845/7359488 blocks Jul 1 08:40:42.551230 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT. Jul 1 08:40:42.553619 systemd[1]: Mounting sysroot.mount - /sysroot... Jul 1 08:40:42.793716 kernel: EXT4-fs (sda9): mounted filesystem 18421243-07cc-41b2-b496-d6a2cef84352 r/w with ordered data mode. Quota mode: none. Jul 1 08:40:42.793984 systemd[1]: Mounted sysroot.mount - /sysroot. Jul 1 08:40:42.795999 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System. Jul 1 08:40:42.813776 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 1 08:40:42.817088 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr... Jul 1 08:40:42.840269 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent... Jul 1 08:40:42.854653 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1144) Jul 1 08:40:42.841714 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Jul 1 08:40:42.869607 kernel: BTRFS info (device sda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:40:42.869627 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:40:42.869641 kernel: BTRFS info (device sda6): using free-space-tree Jul 1 08:40:42.841748 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup. Jul 1 08:40:42.866519 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr. Jul 1 08:40:42.884848 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 1 08:40:42.895780 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup... Jul 1 08:40:43.800892 coreos-metadata[1146]: Jul 01 08:40:43.800 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 1 08:40:43.809010 coreos-metadata[1146]: Jul 01 08:40:43.808 INFO Fetch successful Jul 1 08:40:43.812915 coreos-metadata[1146]: Jul 01 08:40:43.809 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Jul 1 08:40:43.827908 coreos-metadata[1146]: Jul 01 08:40:43.827 INFO Fetch successful Jul 1 08:40:43.839794 coreos-metadata[1146]: Jul 01 08:40:43.839 INFO wrote hostname ci-9999.9.9-s-69685fc6e4 to /sysroot/etc/hostname Jul 1 08:40:43.841727 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 1 08:40:44.420458 initrd-setup-root[1174]: cut: /sysroot/etc/passwd: No such file or directory Jul 1 08:40:44.507708 initrd-setup-root[1181]: cut: /sysroot/etc/group: No such file or directory Jul 1 08:40:44.525886 initrd-setup-root[1188]: cut: /sysroot/etc/shadow: No such file or directory Jul 1 08:40:44.552441 initrd-setup-root[1195]: cut: /sysroot/etc/gshadow: No such file or directory Jul 1 08:40:45.671520 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup. Jul 1 08:40:45.674515 systemd[1]: Starting ignition-mount.service - Ignition (mount)... Jul 1 08:40:45.699242 systemd[1]: Starting sysroot-boot.service - /sysroot/boot... Jul 1 08:40:45.704952 systemd[1]: sysroot-oem.mount: Deactivated successfully. Jul 1 08:40:45.717178 kernel: BTRFS info (device sda6): last unmount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:40:45.737529 ignition[1263]: INFO : Ignition 2.21.0 Jul 1 08:40:45.737529 ignition[1263]: INFO : Stage: mount Jul 1 08:40:45.743250 ignition[1263]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:45.743250 ignition[1263]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:45.743250 ignition[1263]: INFO : mount: mount passed Jul 1 08:40:45.743250 ignition[1263]: INFO : Ignition finished successfully Jul 1 08:40:45.744437 systemd[1]: Finished ignition-mount.service - Ignition (mount). Jul 1 08:40:45.759109 systemd[1]: Starting ignition-files.service - Ignition (files)... Jul 1 08:40:45.767787 systemd[1]: Finished sysroot-boot.service - /sysroot/boot. Jul 1 08:40:45.793202 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem... Jul 1 08:40:45.816238 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 (8:6) scanned by mount (1278) Jul 1 08:40:45.816272 kernel: BTRFS info (device sda6): first mount of filesystem 583bafe8-d373-434e-a8d4-4cb362bb932b Jul 1 08:40:45.816709 kernel: BTRFS info (device sda6): using crc32c (crc32c-intel) checksum algorithm Jul 1 08:40:45.823845 kernel: BTRFS info (device sda6): using free-space-tree Jul 1 08:40:45.828246 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem. Jul 1 08:40:45.875599 ignition[1295]: INFO : Ignition 2.21.0 Jul 1 08:40:45.875599 ignition[1295]: INFO : Stage: files Jul 1 08:40:45.881410 ignition[1295]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:45.881410 ignition[1295]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:45.881410 ignition[1295]: DEBUG : files: compiled without relabeling support, skipping Jul 1 08:40:45.895132 ignition[1295]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Jul 1 08:40:45.895132 ignition[1295]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Jul 1 08:40:45.946606 ignition[1295]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Jul 1 08:40:45.952028 ignition[1295]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Jul 1 08:40:45.952028 ignition[1295]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Jul 1 08:40:45.947289 unknown[1295]: wrote ssh authorized keys file for user: core Jul 1 08:40:46.078629 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Jul 1 08:40:46.086329 ignition[1295]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Jul 1 08:40:46.086329 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [started] writing file "/sysroot/etc/.ignition-result.json" Jul 1 08:40:46.086329 ignition[1295]: INFO : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json" Jul 1 08:40:46.086329 ignition[1295]: INFO : files: files passed Jul 1 08:40:46.086329 ignition[1295]: INFO : Ignition finished successfully Jul 1 08:40:46.115368 systemd[1]: Finished ignition-files.service - Ignition (files). Jul 1 08:40:46.117818 systemd[1]: Starting ignition-quench.service - Ignition (record completion)... Jul 1 08:40:46.149289 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion... Jul 1 08:40:46.154203 systemd[1]: ignition-quench.service: Deactivated successfully. Jul 1 08:40:46.154300 systemd[1]: Finished ignition-quench.service - Ignition (record completion). Jul 1 08:40:46.203801 initrd-setup-root-after-ignition[1327]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:40:46.210141 initrd-setup-root-after-ignition[1327]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:40:46.205833 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 1 08:40:46.231099 initrd-setup-root-after-ignition[1331]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Jul 1 08:40:46.211889 systemd[1]: Reached target ignition-complete.target - Ignition Complete. Jul 1 08:40:46.224037 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root... Jul 1 08:40:46.304826 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Jul 1 08:40:46.305180 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root. Jul 1 08:40:46.313965 systemd[1]: Reached target initrd-fs.target - Initrd File Systems. Jul 1 08:40:46.316720 systemd[1]: Reached target initrd.target - Initrd Default Target. Jul 1 08:40:46.327616 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met. Jul 1 08:40:46.328576 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook... Jul 1 08:40:46.371600 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 1 08:40:46.382643 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons... Jul 1 08:40:46.420148 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:40:46.421744 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:40:46.438752 systemd[1]: Stopped target timers.target - Timer Units. Jul 1 08:40:46.439620 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Jul 1 08:40:46.439753 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook. Jul 1 08:40:46.454378 systemd[1]: Stopped target initrd.target - Initrd Default Target. Jul 1 08:40:46.462990 systemd[1]: Stopped target basic.target - Basic System. Jul 1 08:40:46.464379 systemd[1]: Stopped target ignition-complete.target - Ignition Complete. Jul 1 08:40:46.465463 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup. Jul 1 08:40:46.466961 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device. Jul 1 08:40:46.467662 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System. Jul 1 08:40:46.468395 systemd[1]: Stopped target remote-fs.target - Remote File Systems. Jul 1 08:40:46.469117 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems. Jul 1 08:40:46.469852 systemd[1]: Stopped target sysinit.target - System Initialization. Jul 1 08:40:46.470559 systemd[1]: Stopped target local-fs.target - Local File Systems. Jul 1 08:40:46.471286 systemd[1]: Stopped target swap.target - Swaps. Jul 1 08:40:46.607729 ignition[1351]: INFO : Ignition 2.21.0 Jul 1 08:40:46.607729 ignition[1351]: INFO : Stage: umount Jul 1 08:40:46.607729 ignition[1351]: INFO : no configs at "/usr/lib/ignition/base.d" Jul 1 08:40:46.607729 ignition[1351]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Jul 1 08:40:46.607729 ignition[1351]: INFO : umount: umount passed Jul 1 08:40:46.607729 ignition[1351]: INFO : Ignition finished successfully Jul 1 08:40:46.471984 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Jul 1 08:40:46.472096 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook. Jul 1 08:40:46.473484 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:40:46.474222 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:40:46.474897 systemd[1]: clevis-luks-askpass.path: Deactivated successfully. Jul 1 08:40:46.475027 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:40:46.475622 systemd[1]: dracut-initqueue.service: Deactivated successfully. Jul 1 08:40:46.475746 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook. Jul 1 08:40:46.477138 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Jul 1 08:40:46.477254 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion. Jul 1 08:40:46.477878 systemd[1]: ignition-files.service: Deactivated successfully. Jul 1 08:40:46.477986 systemd[1]: Stopped ignition-files.service - Ignition (files). Jul 1 08:40:46.478595 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Jul 1 08:40:46.478714 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent. Jul 1 08:40:46.480064 systemd[1]: Stopping ignition-mount.service - Ignition (mount)... Jul 1 08:40:46.481363 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot... Jul 1 08:40:46.481421 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Jul 1 08:40:46.481535 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:40:46.482542 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Jul 1 08:40:46.482670 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook. Jul 1 08:40:46.486391 systemd[1]: initrd-cleanup.service: Deactivated successfully. Jul 1 08:40:46.579851 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons. Jul 1 08:40:46.595064 systemd[1]: sysroot-boot.mount: Deactivated successfully. Jul 1 08:40:46.609073 systemd[1]: ignition-mount.service: Deactivated successfully. Jul 1 08:40:46.609176 systemd[1]: Stopped ignition-mount.service - Ignition (mount). Jul 1 08:40:46.614883 systemd[1]: ignition-disks.service: Deactivated successfully. Jul 1 08:40:46.614930 systemd[1]: Stopped ignition-disks.service - Ignition (disks). Jul 1 08:40:46.621293 systemd[1]: ignition-kargs.service: Deactivated successfully. Jul 1 08:40:46.621339 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs). Jul 1 08:40:46.629044 systemd[1]: ignition-fetch.service: Deactivated successfully. Jul 1 08:40:46.629090 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch). Jul 1 08:40:46.631152 systemd[1]: Stopped target network.target - Network. Jul 1 08:40:46.641911 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Jul 1 08:40:46.642003 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline). Jul 1 08:40:46.649031 systemd[1]: Stopped target paths.target - Path Units. Jul 1 08:40:46.659395 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Jul 1 08:40:46.664282 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:40:46.664968 systemd[1]: Stopped target slices.target - Slice Units. Jul 1 08:40:46.674044 systemd[1]: Stopped target sockets.target - Socket Units. Jul 1 08:40:46.681504 systemd[1]: iscsid.socket: Deactivated successfully. Jul 1 08:40:46.681546 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket. Jul 1 08:40:46.691998 systemd[1]: iscsiuio.socket: Deactivated successfully. Jul 1 08:40:46.692035 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket. Jul 1 08:40:46.701436 systemd[1]: ignition-setup.service: Deactivated successfully. Jul 1 08:40:46.701486 systemd[1]: Stopped ignition-setup.service - Ignition (setup). Jul 1 08:40:46.709223 systemd[1]: ignition-setup-pre.service: Deactivated successfully. Jul 1 08:40:46.709268 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup. Jul 1 08:40:46.717609 systemd[1]: Stopping systemd-networkd.service - Network Configuration... Jul 1 08:40:46.726042 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution... Jul 1 08:40:46.739056 systemd[1]: systemd-resolved.service: Deactivated successfully. Jul 1 08:40:46.739162 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution. Jul 1 08:40:46.752968 systemd[1]: run-credentials-systemd\x2dresolved.service.mount: Deactivated successfully. Jul 1 08:40:46.753205 systemd[1]: systemd-networkd.service: Deactivated successfully. Jul 1 08:40:46.753321 systemd[1]: Stopped systemd-networkd.service - Network Configuration. Jul 1 08:40:46.765245 systemd[1]: run-credentials-systemd\x2dnetworkd.service.mount: Deactivated successfully. Jul 1 08:40:46.765508 systemd[1]: sysroot-boot.service: Deactivated successfully. Jul 1 08:40:46.765612 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot. Jul 1 08:40:46.772931 systemd[1]: Stopped target network-pre.target - Preparation for Network. Jul 1 08:40:46.780018 systemd[1]: systemd-networkd.socket: Deactivated successfully. Jul 1 08:40:46.780094 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:40:46.788954 systemd[1]: initrd-setup-root.service: Deactivated successfully. Jul 1 08:40:46.789022 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup. Jul 1 08:40:46.791899 systemd[1]: Stopping network-cleanup.service - Network Cleanup... Jul 1 08:40:46.802479 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Jul 1 08:40:46.802535 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline. Jul 1 08:40:46.809637 systemd[1]: systemd-sysctl.service: Deactivated successfully. Jul 1 08:40:46.809684 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:40:46.825628 systemd[1]: systemd-modules-load.service: Deactivated successfully. Jul 1 08:40:46.825678 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules. Jul 1 08:40:46.827190 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully. Jul 1 08:40:46.827238 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:40:46.842750 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:40:46.845296 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Jul 1 08:40:46.845359 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:40:46.882306 systemd[1]: systemd-udevd.service: Deactivated successfully. Jul 1 08:40:46.882454 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:40:46.891048 systemd[1]: network-cleanup.service: Deactivated successfully. Jul 1 08:40:46.891144 systemd[1]: Stopped network-cleanup.service - Network Cleanup. Jul 1 08:40:46.899249 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Jul 1 08:40:46.899338 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket. Jul 1 08:40:46.901011 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Jul 1 08:40:46.901048 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:40:46.913496 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Jul 1 08:40:46.913562 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook. Jul 1 08:40:47.166317 systemd-journald[232]: Received SIGTERM from PID 1 (systemd). Jul 1 08:40:46.927042 systemd[1]: dracut-cmdline.service: Deactivated successfully. Jul 1 08:40:46.927090 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook. Jul 1 08:40:46.938486 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Jul 1 08:40:46.938541 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters. Jul 1 08:40:46.954677 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database... Jul 1 08:40:46.962548 systemd[1]: systemd-network-generator.service: Deactivated successfully. Jul 1 08:40:46.962604 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:40:46.972477 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Jul 1 08:40:46.972530 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:40:46.981554 systemd[1]: systemd-tmpfiles-setup-dev-early.service: Deactivated successfully. Jul 1 08:40:46.981603 systemd[1]: Stopped systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:40:46.990828 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Jul 1 08:40:46.990873 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:40:46.998662 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:40:46.998730 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:47.013118 systemd[1]: run-credentials-systemd\x2dnetwork\x2dgenerator.service.mount: Deactivated successfully. Jul 1 08:40:47.013175 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev\x2dearly.service.mount: Deactivated successfully. Jul 1 08:40:47.013220 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Jul 1 08:40:47.013264 systemd[1]: run-credentials-systemd\x2dvconsole\x2dsetup.service.mount: Deactivated successfully. Jul 1 08:40:47.013612 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Jul 1 08:40:47.013712 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database. Jul 1 08:40:47.016685 systemd[1]: Reached target initrd-switch-root.target - Switch Root. Jul 1 08:40:47.029264 systemd[1]: Starting initrd-switch-root.service - Switch Root... Jul 1 08:40:47.064483 systemd[1]: Switching root. Jul 1 08:40:47.178824 systemd-journald[232]: Journal stopped Jul 1 08:40:52.850906 kernel: SELinux: policy capability network_peer_controls=1 Jul 1 08:40:52.850935 kernel: SELinux: policy capability open_perms=1 Jul 1 08:40:52.850947 kernel: SELinux: policy capability extended_socket_class=1 Jul 1 08:40:52.850956 kernel: SELinux: policy capability always_check_network=0 Jul 1 08:40:52.850965 kernel: SELinux: policy capability cgroup_seclabel=1 Jul 1 08:40:52.850974 kernel: SELinux: policy capability nnp_nosuid_transition=1 Jul 1 08:40:52.850984 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Jul 1 08:40:52.850997 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Jul 1 08:40:52.851006 kernel: SELinux: policy capability userspace_initial_context=0 Jul 1 08:40:52.851015 kernel: audit: type=1403 audit(1751359248.163:2): auid=4294967295 ses=4294967295 lsm=selinux res=1 Jul 1 08:40:52.851031 systemd[1]: Successfully loaded SELinux policy in 203.485ms. Jul 1 08:40:52.851043 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 7.648ms. Jul 1 08:40:52.851057 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) Jul 1 08:40:52.851068 systemd[1]: Detected virtualization microsoft. Jul 1 08:40:52.851081 systemd[1]: Detected architecture x86-64. Jul 1 08:40:52.851092 systemd[1]: Detected first boot. Jul 1 08:40:52.851103 systemd[1]: Hostname set to . Jul 1 08:40:52.851114 systemd[1]: Initializing machine ID from random generator. Jul 1 08:40:52.851124 zram_generator::config[1395]: No configuration found. Jul 1 08:40:52.851138 kernel: Guest personality initialized and is inactive Jul 1 08:40:52.851148 kernel: VMCI host device registered (name=vmci, major=10, minor=124) Jul 1 08:40:52.851158 kernel: Initialized host personality Jul 1 08:40:52.851167 kernel: NET: Registered PF_VSOCK protocol family Jul 1 08:40:52.851177 systemd[1]: Populated /etc with preset unit settings. Jul 1 08:40:52.851189 systemd[1]: run-credentials-systemd\x2djournald.service.mount: Deactivated successfully. Jul 1 08:40:52.851199 systemd[1]: initrd-switch-root.service: Deactivated successfully. Jul 1 08:40:52.851212 systemd[1]: Stopped initrd-switch-root.service - Switch Root. Jul 1 08:40:52.851222 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Jul 1 08:40:52.851233 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config. Jul 1 08:40:52.851244 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run. Jul 1 08:40:52.851255 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Jul 1 08:40:52.851268 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe. Jul 1 08:40:52.851279 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty. Jul 1 08:40:52.851290 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit. Jul 1 08:40:52.851303 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck. Jul 1 08:40:52.851314 systemd[1]: Created slice user.slice - User and Session Slice. Jul 1 08:40:52.851325 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch. Jul 1 08:40:52.851336 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch. Jul 1 08:40:52.851347 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch. Jul 1 08:40:52.851358 systemd[1]: Set up automount boot.automount - Boot partition Automount Point. Jul 1 08:40:52.851372 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point. Jul 1 08:40:52.851384 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM... Jul 1 08:40:52.851397 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0... Jul 1 08:40:52.851408 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre). Jul 1 08:40:52.851419 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes. Jul 1 08:40:52.851430 systemd[1]: Stopped target initrd-switch-root.target - Switch Root. Jul 1 08:40:52.851441 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems. Jul 1 08:40:52.851452 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System. Jul 1 08:40:52.851463 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes. Jul 1 08:40:52.851476 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes. Jul 1 08:40:52.851487 systemd[1]: Reached target remote-fs.target - Remote File Systems. Jul 1 08:40:52.851498 systemd[1]: Reached target slices.target - Slice Units. Jul 1 08:40:52.851512 systemd[1]: Reached target swap.target - Swaps. Jul 1 08:40:52.851522 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes. Jul 1 08:40:52.851533 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket. Jul 1 08:40:52.851548 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption. Jul 1 08:40:52.851559 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket. Jul 1 08:40:52.851570 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket. Jul 1 08:40:52.851582 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket. Jul 1 08:40:52.851593 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket. Jul 1 08:40:52.851604 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System... Jul 1 08:40:52.851615 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System... Jul 1 08:40:52.851629 systemd[1]: Mounting media.mount - External Media Directory... Jul 1 08:40:52.851640 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:52.851651 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System... Jul 1 08:40:52.851662 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System... Jul 1 08:40:52.851674 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp... Jul 1 08:40:52.851685 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). Jul 1 08:40:52.851706 systemd[1]: Reached target machines.target - Containers. Jul 1 08:40:52.851718 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files... Jul 1 08:40:52.851729 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:40:52.851743 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes... Jul 1 08:40:52.851755 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs... Jul 1 08:40:52.851769 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 1 08:40:52.851781 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 1 08:40:52.851792 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 1 08:40:52.851803 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse... Jul 1 08:40:52.851814 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 1 08:40:52.851825 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Jul 1 08:40:52.851840 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Jul 1 08:40:52.851851 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device. Jul 1 08:40:52.851862 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Jul 1 08:40:52.851873 systemd[1]: Stopped systemd-fsck-usr.service. Jul 1 08:40:52.851885 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). Jul 1 08:40:52.851896 systemd[1]: Starting systemd-journald.service - Journal Service... Jul 1 08:40:52.851907 kernel: fuse: init (API version 7.41) Jul 1 08:40:52.851917 kernel: loop: module loaded Jul 1 08:40:52.851930 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules... Jul 1 08:40:52.851941 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line... Jul 1 08:40:52.851952 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems... Jul 1 08:40:52.851963 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials... Jul 1 08:40:52.851997 systemd-journald[1502]: Collecting audit messages is disabled. Jul 1 08:40:52.852025 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices... Jul 1 08:40:52.852037 systemd-journald[1502]: Journal started Jul 1 08:40:52.852063 systemd-journald[1502]: Runtime Journal (/run/log/journal/491313a4a75a497c8ea1365f1a9c633e) is 8M, max 2.2G, 2.1G free. Jul 1 08:40:52.080610 systemd[1]: Queued start job for default target multi-user.target. Jul 1 08:40:52.113041 systemd[1]: Unnecessary job was removed for dev-sda6.device - /dev/sda6. Jul 1 08:40:52.113585 systemd[1]: systemd-journald.service: Deactivated successfully. Jul 1 08:40:52.863710 systemd[1]: verity-setup.service: Deactivated successfully. Jul 1 08:40:52.868290 systemd[1]: Stopped verity-setup.service. Jul 1 08:40:52.878712 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:52.886790 systemd[1]: Started systemd-journald.service - Journal Service. Jul 1 08:40:52.890874 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System. Jul 1 08:40:52.895219 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System. Jul 1 08:40:52.899634 systemd[1]: Mounted media.mount - External Media Directory. Jul 1 08:40:52.903576 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System. Jul 1 08:40:52.908023 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System. Jul 1 08:40:52.912648 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp. Jul 1 08:40:52.916687 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files. Jul 1 08:40:52.921851 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes. Jul 1 08:40:52.927222 systemd[1]: modprobe@configfs.service: Deactivated successfully. Jul 1 08:40:52.927417 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs. Jul 1 08:40:52.932542 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 1 08:40:52.932733 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 1 08:40:52.937628 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 1 08:40:52.937825 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 1 08:40:52.943213 systemd[1]: modprobe@fuse.service: Deactivated successfully. Jul 1 08:40:52.943443 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse. Jul 1 08:40:52.948341 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 1 08:40:52.948556 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 1 08:40:52.953414 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules. Jul 1 08:40:52.958367 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line. Jul 1 08:40:52.963814 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems. Jul 1 08:40:52.969170 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials. Jul 1 08:40:52.980713 kernel: ACPI: bus type drm_connector registered Jul 1 08:40:52.981062 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 1 08:40:52.981268 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 1 08:40:52.989762 systemd[1]: Reached target network-pre.target - Preparation for Network. Jul 1 08:40:52.995543 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System... Jul 1 08:40:53.008909 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System... Jul 1 08:40:53.013541 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Jul 1 08:40:53.013585 systemd[1]: Reached target local-fs.target - Local File Systems. Jul 1 08:40:53.018631 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management. Jul 1 08:40:53.024758 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache... Jul 1 08:40:53.028987 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:40:53.030232 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database... Jul 1 08:40:53.035488 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage... Jul 1 08:40:53.040029 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 1 08:40:53.040897 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed... Jul 1 08:40:53.045884 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 1 08:40:53.046979 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables... Jul 1 08:40:53.053315 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/... Jul 1 08:40:53.058997 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully... Jul 1 08:40:53.066425 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices. Jul 1 08:40:53.071519 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System. Jul 1 08:40:53.077139 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System. Jul 1 08:40:53.085188 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed. Jul 1 08:40:53.090297 systemd[1]: Reached target first-boot-complete.target - First Boot Complete. Jul 1 08:40:53.090594 systemd-journald[1502]: Time spent on flushing to /var/log/journal/491313a4a75a497c8ea1365f1a9c633e is 14.256ms for 958 entries. Jul 1 08:40:53.090594 systemd-journald[1502]: System Journal (/var/log/journal/491313a4a75a497c8ea1365f1a9c633e) is 8M, max 2.6G, 2.6G free. Jul 1 08:40:53.131642 systemd-journald[1502]: Received client request to flush runtime journal. Jul 1 08:40:53.100597 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk... Jul 1 08:40:53.133295 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage. Jul 1 08:40:53.159618 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Jul 1 08:40:53.159639 systemd-tmpfiles[1538]: ACLs are not supported, ignoring. Jul 1 08:40:53.163635 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully. Jul 1 08:40:53.169779 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Jul 1 08:40:53.171120 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk. Jul 1 08:40:53.174711 kernel: loop0: detected capacity change from 0 to 28616 Jul 1 08:40:53.180745 systemd[1]: Starting systemd-sysusers.service - Create System Users... Jul 1 08:40:53.184713 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables. Jul 1 08:40:53.469681 systemd[1]: Finished systemd-sysusers.service - Create System Users. Jul 1 08:40:53.475761 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev... Jul 1 08:40:53.515496 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Jul 1 08:40:53.515518 systemd-tmpfiles[1557]: ACLs are not supported, ignoring. Jul 1 08:40:53.518918 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev. Jul 1 08:40:53.664718 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher Jul 1 08:40:53.777714 kernel: loop1: detected capacity change from 0 to 114000 Jul 1 08:40:54.252131 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database. Jul 1 08:40:54.258561 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files... Jul 1 08:40:54.280713 kernel: loop2: detected capacity change from 0 to 146336 Jul 1 08:40:54.301864 systemd-udevd[1564]: Using default interface naming scheme 'v255'. Jul 1 08:40:54.506596 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files. Jul 1 08:40:54.515375 systemd[1]: Starting systemd-networkd.service - Network Configuration... Jul 1 08:40:54.561637 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped. Jul 1 08:40:54.581719 kernel: hv_vmbus: registering driver hyperv_fb Jul 1 08:40:54.581804 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Jul 1 08:40:54.589415 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Jul 1 08:40:54.596458 kernel: Console: switching to colour dummy device 80x25 Jul 1 08:40:54.601722 kernel: Console: switching to colour frame buffer device 128x48 Jul 1 08:40:54.603817 systemd[1]: Starting systemd-userdbd.service - User Database Manager... Jul 1 08:40:54.633712 kernel: mousedev: PS/2 mouse device common for all mice Jul 1 08:40:54.637716 kernel: hv_vmbus: registering driver hv_balloon Jul 1 08:40:54.637770 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Jul 1 08:40:54.652278 systemd[1]: Started systemd-userdbd.service - User Database Manager. Jul 1 08:40:54.687799 kernel: EDAC MC: Ver: 3.0.0 Jul 1 08:40:54.721996 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:54.739193 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Jul 1 08:40:54.739389 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:54.746546 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup... Jul 1 08:40:54.812051 systemd-networkd[1587]: lo: Link UP Jul 1 08:40:54.812061 systemd-networkd[1587]: lo: Gained carrier Jul 1 08:40:54.813562 systemd-networkd[1587]: Enumeration completed Jul 1 08:40:54.813676 systemd[1]: Started systemd-networkd.service - Network Configuration. Jul 1 08:40:54.813995 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:40:54.814006 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 1 08:40:54.814541 systemd-networkd[1587]: eth0: Link UP Jul 1 08:40:54.814550 systemd-networkd[1587]: eth0: Gained carrier Jul 1 08:40:54.814564 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:40:54.819546 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd... Jul 1 08:40:54.825192 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured... Jul 1 08:40:54.848734 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 1 08:40:54.868659 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd. Jul 1 08:40:54.929576 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - Virtual_Disk OEM. Jul 1 08:40:54.935396 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM... Jul 1 08:40:54.973335 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM. Jul 1 08:40:55.004975 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup. Jul 1 08:40:55.192717 kernel: loop3: detected capacity change from 0 to 28616 Jul 1 08:40:55.201708 kernel: loop4: detected capacity change from 0 to 114000 Jul 1 08:40:55.215719 kernel: loop5: detected capacity change from 0 to 146336 Jul 1 08:40:55.239622 (sd-merge)[1722]: Using extensions 'containerd-flatcar', 'docker-flatcar', 'oem-azure'. Jul 1 08:40:55.240835 (sd-merge)[1722]: Merged extensions into '/usr'. Jul 1 08:40:55.245112 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/. Jul 1 08:40:55.251541 systemd[1]: Starting ensure-sysext.service... Jul 1 08:40:55.255227 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories... Jul 1 08:40:55.289222 systemd[1]: Reload requested from client PID 1724 ('systemctl') (unit ensure-sysext.service)... Jul 1 08:40:55.289239 systemd[1]: Reloading... Jul 1 08:40:55.298031 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring. Jul 1 08:40:55.298072 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring. Jul 1 08:40:55.298374 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Jul 1 08:40:55.298673 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring. Jul 1 08:40:55.299608 systemd-tmpfiles[1725]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Jul 1 08:40:55.299921 systemd-tmpfiles[1725]: ACLs are not supported, ignoring. Jul 1 08:40:55.299987 systemd-tmpfiles[1725]: ACLs are not supported, ignoring. Jul 1 08:40:55.303750 systemd-tmpfiles[1725]: Detected autofs mount point /boot during canonicalization of boot. Jul 1 08:40:55.303762 systemd-tmpfiles[1725]: Skipping /boot Jul 1 08:40:55.312290 systemd-tmpfiles[1725]: Detected autofs mount point /boot during canonicalization of boot. Jul 1 08:40:55.312304 systemd-tmpfiles[1725]: Skipping /boot Jul 1 08:40:55.338717 zram_generator::config[1756]: No configuration found. Jul 1 08:40:55.463431 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 1 08:40:55.613850 systemd[1]: Reloading finished in 324 ms. Jul 1 08:40:55.663309 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories. Jul 1 08:40:55.679625 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:55.680796 systemd[1]: Starting audit-rules.service - Load Audit Rules... Jul 1 08:40:55.685569 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs... Jul 1 08:40:55.690348 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:40:55.691477 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 1 08:40:55.696681 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 1 08:40:55.702312 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 1 08:40:55.706466 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:40:55.706591 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). Jul 1 08:40:55.707732 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog... Jul 1 08:40:55.717435 systemd[1]: Starting systemd-resolved.service - Network Name Resolution... Jul 1 08:40:55.722768 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP... Jul 1 08:40:55.727478 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:55.729621 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 1 08:40:55.729835 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 1 08:40:55.734984 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 1 08:40:55.735164 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 1 08:40:55.740763 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 1 08:40:55.740965 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 1 08:40:55.755910 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP. Jul 1 08:40:55.765701 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:55.766029 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met. Jul 1 08:40:55.767634 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod... Jul 1 08:40:55.778170 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm... Jul 1 08:40:55.783020 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore... Jul 1 08:40:55.788574 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop... Jul 1 08:40:55.792567 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met. Jul 1 08:40:55.792747 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). Jul 1 08:40:55.792945 systemd[1]: Reached target time-set.target - System Time Set. Jul 1 08:40:55.797130 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen). Jul 1 08:40:55.798788 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Jul 1 08:40:55.799034 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod. Jul 1 08:40:55.804023 systemd[1]: modprobe@drm.service: Deactivated successfully. Jul 1 08:40:55.804215 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm. Jul 1 08:40:55.809330 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Jul 1 08:40:55.809507 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore. Jul 1 08:40:55.814821 systemd[1]: modprobe@loop.service: Deactivated successfully. Jul 1 08:40:55.815055 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop. Jul 1 08:40:55.822149 systemd[1]: Finished ensure-sysext.service. Jul 1 08:40:55.828844 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Jul 1 08:40:55.828900 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met. Jul 1 08:40:55.835093 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog. Jul 1 08:40:55.859030 systemd-resolved[1828]: Positive Trust Anchors: Jul 1 08:40:55.859047 systemd-resolved[1828]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Jul 1 08:40:55.859079 systemd-resolved[1828]: 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 Jul 1 08:40:55.863406 systemd-resolved[1828]: Using system hostname 'ci-9999.9.9-s-69685fc6e4'. Jul 1 08:40:55.865075 systemd[1]: Started systemd-resolved.service - Network Name Resolution. Jul 1 08:40:55.869643 systemd[1]: Reached target network.target - Network. Jul 1 08:40:55.873313 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups. Jul 1 08:40:55.969924 augenrules[1858]: No rules Jul 1 08:40:55.971088 systemd[1]: audit-rules.service: Deactivated successfully. Jul 1 08:40:55.971341 systemd[1]: Finished audit-rules.service - Load Audit Rules. Jul 1 08:40:56.140480 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs. Jul 1 08:40:56.145791 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). Jul 1 08:40:56.599831 systemd-networkd[1587]: eth0: Gained IPv6LL Jul 1 08:40:56.602040 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured. Jul 1 08:40:56.607496 systemd[1]: Reached target network-online.target - Network is Online. Jul 1 08:41:00.056684 ldconfig[1531]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 1 08:41:00.072634 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache. Jul 1 08:41:00.079075 systemd[1]: Starting systemd-update-done.service - Update is Completed... Jul 1 08:41:00.112719 systemd[1]: Finished systemd-update-done.service - Update is Completed. Jul 1 08:41:00.117387 systemd[1]: Reached target sysinit.target - System Initialization. Jul 1 08:41:00.121864 systemd[1]: Started motdgen.path - Watch for update engine configuration changes. Jul 1 08:41:00.126925 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data. Jul 1 08:41:00.131947 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer. Jul 1 08:41:00.136907 systemd[1]: Started logrotate.timer - Daily rotation of log files. Jul 1 08:41:00.141327 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information.. Jul 1 08:41:00.146502 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Jul 1 08:41:00.151603 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Jul 1 08:41:00.151646 systemd[1]: Reached target paths.target - Path Units. Jul 1 08:41:00.155340 systemd[1]: Reached target timers.target - Timer Units. Jul 1 08:41:00.159739 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket. Jul 1 08:41:00.165334 systemd[1]: Starting docker.socket - Docker Socket for the API... Jul 1 08:41:00.171362 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local). Jul 1 08:41:00.176593 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK). Jul 1 08:41:00.181708 systemd[1]: Reached target ssh-access.target - SSH Access Available. Jul 1 08:41:00.187946 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket. Jul 1 08:41:00.192384 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket. Jul 1 08:41:00.197792 systemd[1]: Listening on docker.socket - Docker Socket for the API. Jul 1 08:41:00.203038 systemd[1]: Reached target sockets.target - Socket Units. Jul 1 08:41:00.206932 systemd[1]: Reached target basic.target - Basic System. Jul 1 08:41:00.210703 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met. Jul 1 08:41:00.210738 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met. Jul 1 08:41:00.212981 systemd[1]: Starting chronyd.service - NTP client/server... Jul 1 08:41:00.217364 systemd[1]: Starting containerd.service - containerd container runtime... Jul 1 08:41:00.234490 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent... Jul 1 08:41:00.239513 systemd[1]: Starting dbus.service - D-Bus System Message Bus... Jul 1 08:41:00.243850 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown... Jul 1 08:41:00.249354 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit... Jul 1 08:41:00.254334 systemd[1]: Starting extend-filesystems.service - Extend Filesystems... Jul 1 08:41:00.258362 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). Jul 1 08:41:00.260807 jq[1879]: false Jul 1 08:41:00.260816 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh... Jul 1 08:41:00.280724 systemd[1]: hv_fcopy_uio_daemon.service - Hyper-V FCOPY UIO daemon was skipped because of an unmet condition check (ConditionPathExists=/sys/bus/vmbus/devices/eb765408-105f-49b6-b4aa-c123b64d17d4/uio). Jul 1 08:41:00.281871 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon. Jul 1 08:41:00.286081 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss). Jul 1 08:41:00.287106 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd... Jul 1 08:41:00.287773 (chronyd)[1871]: chronyd.service: Referenced but unset environment variable evaluates to an empty string: OPTIONS Jul 1 08:41:00.288725 KVP[1882]: KVP starting; pid is:1882 Jul 1 08:41:00.291790 KVP[1882]: KVP LIC Version: 3.1 Jul 1 08:41:00.293104 chronyd[1888]: chronyd version 4.6.1 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +ASYNCDNS +NTS +SECHASH +IPV6 -DEBUG) Jul 1 08:41:00.293750 kernel: hv_utils: KVP IC version 4.0 Jul 1 08:41:00.294180 systemd[1]: Starting nvidia.service - NVIDIA Configure Service... Jul 1 08:41:00.294829 chronyd[1888]: Timezone right/UTC failed leap second check, ignoring Jul 1 08:41:00.294986 chronyd[1888]: Loaded seccomp filter (level 2) Jul 1 08:41:00.299172 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline... Jul 1 08:41:00.304664 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys... Jul 1 08:41:00.311189 systemd[1]: Starting systemd-logind.service - User Login Management... Jul 1 08:41:00.313500 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Jul 1 08:41:00.313934 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Jul 1 08:41:00.315452 systemd[1]: Starting update-engine.service - Update Engine... Jul 1 08:41:00.319903 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition... Jul 1 08:41:00.325963 systemd[1]: Started chronyd.service - NTP client/server. Jul 1 08:41:00.335660 extend-filesystems[1880]: Found /dev/sda6 Jul 1 08:41:00.340454 extend-filesystems[1880]: Found /dev/sda9 Jul 1 08:41:00.343725 extend-filesystems[1880]: Checking size of /dev/sda9 Jul 1 08:41:00.350434 jq[1898]: true Jul 1 08:41:00.342056 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown. Jul 1 08:41:00.349977 oslogin_cache_refresh[1881]: Refreshing passwd entry cache Jul 1 08:41:00.350902 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Refreshing passwd entry cache Jul 1 08:41:00.349305 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Jul 1 08:41:00.349521 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped. Jul 1 08:41:00.349821 systemd[1]: motdgen.service: Deactivated successfully. Jul 1 08:41:00.350013 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd. Jul 1 08:41:00.356744 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Jul 1 08:41:00.356976 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline. Jul 1 08:41:00.362363 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Failure getting users, quitting Jul 1 08:41:00.362353 oslogin_cache_refresh[1881]: Failure getting users, quitting Jul 1 08:41:00.362588 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 1 08:41:00.362377 oslogin_cache_refresh[1881]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak. Jul 1 08:41:00.363908 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Refreshing group entry cache Jul 1 08:41:00.363904 oslogin_cache_refresh[1881]: Refreshing group entry cache Jul 1 08:41:00.368522 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Failure getting groups, quitting Jul 1 08:41:00.368522 google_oslogin_nss_cache[1881]: oslogin_cache_refresh[1881]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 1 08:41:00.368506 oslogin_cache_refresh[1881]: Failure getting groups, quitting Jul 1 08:41:00.368516 oslogin_cache_refresh[1881]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak. Jul 1 08:41:00.369532 systemd[1]: google-oslogin-cache.service: Deactivated successfully. Jul 1 08:41:00.377950 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh. Jul 1 08:41:00.378118 (ntainerd)[1913]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR Jul 1 08:41:00.380783 jq[1912]: true Jul 1 08:41:00.386904 extend-filesystems[1880]: Old size kept for /dev/sda9 Jul 1 08:41:00.387769 systemd[1]: extend-filesystems.service: Deactivated successfully. Jul 1 08:41:00.388022 systemd[1]: Finished extend-filesystems.service - Extend Filesystems. Jul 1 08:41:00.397056 sshd_keygen[1904]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Jul 1 08:41:00.398431 update_engine[1896]: I20250701 08:41:00.398361 1896 main.cc:92] Flatcar Update Engine starting Jul 1 08:41:00.418895 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys. Jul 1 08:41:00.427848 systemd[1]: Starting issuegen.service - Generate /run/issue... Jul 1 08:41:00.433006 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent... Jul 1 08:41:00.451638 systemd[1]: issuegen.service: Deactivated successfully. Jul 1 08:41:00.451879 systemd[1]: Finished issuegen.service - Generate /run/issue. Jul 1 08:41:00.466323 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent. Jul 1 08:41:00.472496 systemd-logind[1895]: New seat seat0. Jul 1 08:41:00.472785 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions... Jul 1 08:41:00.475254 systemd-logind[1895]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Jul 1 08:41:00.477306 systemd[1]: Started systemd-logind.service - User Login Management. Jul 1 08:41:00.544935 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions. Jul 1 08:41:00.550814 setup-nvidia[1889]: Downloading Flatcar Container Linux Developer Container for version: 9999.9.9+sayan-publish-nighlies-sig Jul 1 08:41:00.551249 systemd[1]: Started getty@tty1.service - Getty on tty1. Jul 1 08:41:00.556054 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0. Jul 1 08:41:00.560606 systemd[1]: Reached target getty.target - Login Prompts. Jul 1 08:41:00.574187 dbus-daemon[1874]: [system] SELinux support is enabled Jul 1 08:41:00.574352 systemd[1]: Started dbus.service - D-Bus System Message Bus. Jul 1 08:41:00.576714 update_engine[1896]: I20250701 08:41:00.576649 1896 update_check_scheduler.cc:74] Next update check in 11m37s Jul 1 08:41:00.580718 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). Jul 1 08:41:00.580757 systemd[1]: Reached target system-config.target - Load system-provided cloud configs. Jul 1 08:41:00.581498 dbus-daemon[1874]: [system] Successfully activated service 'org.freedesktop.systemd1' Jul 1 08:41:00.586858 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). Jul 1 08:41:00.586884 systemd[1]: Reached target user-config.target - Load user-provided cloud configs. Jul 1 08:41:00.591794 systemd[1]: Started update-engine.service - Update Engine. Jul 1 08:41:00.597402 systemd[1]: Started locksmithd.service - Cluster reboot manager. Jul 1 08:41:00.695425 bash[1950]: Updated "/home/core/.ssh/authorized_keys" Jul 1 08:41:00.696838 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition. Jul 1 08:41:00.702759 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met. Jul 1 08:41:00.703726 coreos-metadata[1873]: Jul 01 08:41:00.703 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Jul 1 08:41:00.706105 coreos-metadata[1873]: Jul 01 08:41:00.706 INFO Fetch successful Jul 1 08:41:00.706253 coreos-metadata[1873]: Jul 01 08:41:00.706 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Jul 1 08:41:00.710319 coreos-metadata[1873]: Jul 01 08:41:00.710 INFO Fetch successful Jul 1 08:41:00.710589 coreos-metadata[1873]: Jul 01 08:41:00.710 INFO Fetching http://168.63.129.16/machine/79407006-a3ef-4ce8-9178-ccee3c7d7115/2eba2e9e%2D4180%2D4863%2D9b7a%2D975af002c2e4.%5Fci%2D9999.9.9%2Ds%2D69685fc6e4?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Jul 1 08:41:00.745366 coreos-metadata[1873]: Jul 01 08:41:00.745 INFO Fetch successful Jul 1 08:41:00.745515 coreos-metadata[1873]: Jul 01 08:41:00.745 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Jul 1 08:41:00.752641 coreos-metadata[1873]: Jul 01 08:41:00.752 INFO Fetch successful Jul 1 08:41:00.785711 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent. Jul 1 08:41:00.790652 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met. Jul 1 08:41:01.014121 locksmithd[2038]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Jul 1 08:41:02.134385 containerd[1913]: time="2025-07-01T08:41:02Z" 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 Jul 1 08:41:02.134991 containerd[1913]: time="2025-07-01T08:41:02.134957800Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5 Jul 1 08:41:02.143086 containerd[1913]: time="2025-07-01T08:41:02.143045700Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="8.1µs" Jul 1 08:41:02.143086 containerd[1913]: time="2025-07-01T08:41:02.143077700Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1 Jul 1 08:41:02.143187 containerd[1913]: time="2025-07-01T08:41:02.143096900Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1 Jul 1 08:41:02.143279 containerd[1913]: time="2025-07-01T08:41:02.143253000Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1 Jul 1 08:41:02.143308 containerd[1913]: time="2025-07-01T08:41:02.143277800Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1 Jul 1 08:41:02.143330 containerd[1913]: time="2025-07-01T08:41:02.143305100Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143398 containerd[1913]: time="2025-07-01T08:41:02.143374400Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143436 containerd[1913]: time="2025-07-01T08:41:02.143396000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143630 containerd[1913]: time="2025-07-01T08:41:02.143605000Z" 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 Jul 1 08:41:02.143659 containerd[1913]: time="2025-07-01T08:41:02.143627500Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143659 containerd[1913]: time="2025-07-01T08:41:02.143639600Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143659 containerd[1913]: time="2025-07-01T08:41:02.143648200Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1 Jul 1 08:41:02.143776 containerd[1913]: time="2025-07-01T08:41:02.143753800Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1 Jul 1 08:41:02.144027 containerd[1913]: time="2025-07-01T08:41:02.144005000Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1 Jul 1 08:41:02.144058 containerd[1913]: time="2025-07-01T08:41:02.144046100Z" 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 Jul 1 08:41:02.144083 containerd[1913]: time="2025-07-01T08:41:02.144059400Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1 Jul 1 08:41:02.144109 containerd[1913]: time="2025-07-01T08:41:02.144088800Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1 Jul 1 08:41:02.144317 containerd[1913]: time="2025-07-01T08:41:02.144286700Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1 Jul 1 08:41:02.144399 containerd[1913]: time="2025-07-01T08:41:02.144379400Z" level=info msg="metadata content store policy set" policy=shared Jul 1 08:41:02.160639 containerd[1913]: time="2025-07-01T08:41:02.160600600Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1 Jul 1 08:41:02.160670 containerd[1913]: time="2025-07-01T08:41:02.160654400Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1 Jul 1 08:41:02.160703 containerd[1913]: time="2025-07-01T08:41:02.160669900Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1 Jul 1 08:41:02.160703 containerd[1913]: time="2025-07-01T08:41:02.160682200Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1 Jul 1 08:41:02.160758 containerd[1913]: time="2025-07-01T08:41:02.160705900Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1 Jul 1 08:41:02.160758 containerd[1913]: time="2025-07-01T08:41:02.160718900Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1 Jul 1 08:41:02.160758 containerd[1913]: time="2025-07-01T08:41:02.160731300Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1 Jul 1 08:41:02.160758 containerd[1913]: time="2025-07-01T08:41:02.160742400Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1 Jul 1 08:41:02.160758 containerd[1913]: time="2025-07-01T08:41:02.160752900Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1 Jul 1 08:41:02.160859 containerd[1913]: time="2025-07-01T08:41:02.160763500Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1 Jul 1 08:41:02.160859 containerd[1913]: time="2025-07-01T08:41:02.160773300Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1 Jul 1 08:41:02.160859 containerd[1913]: time="2025-07-01T08:41:02.160785000Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2 Jul 1 08:41:02.160929 containerd[1913]: time="2025-07-01T08:41:02.160890400Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1 Jul 1 08:41:02.160929 containerd[1913]: time="2025-07-01T08:41:02.160910900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1 Jul 1 08:41:02.160929 containerd[1913]: time="2025-07-01T08:41:02.160925000Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160935600Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160945200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160954700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160964800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160974500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1 Jul 1 08:41:02.160991 containerd[1913]: time="2025-07-01T08:41:02.160984900Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1 Jul 1 08:41:02.161121 containerd[1913]: time="2025-07-01T08:41:02.160994800Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1 Jul 1 08:41:02.161121 containerd[1913]: time="2025-07-01T08:41:02.161004300Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1 Jul 1 08:41:02.161121 containerd[1913]: time="2025-07-01T08:41:02.161058200Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\"" Jul 1 08:41:02.161121 containerd[1913]: time="2025-07-01T08:41:02.161071300Z" level=info msg="Start snapshots syncer" Jul 1 08:41:02.161121 containerd[1913]: time="2025-07-01T08:41:02.161099100Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1 Jul 1 08:41:02.161320 containerd[1913]: time="2025-07-01T08:41:02.161281000Z" 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\"}" Jul 1 08:41:02.161432 containerd[1913]: time="2025-07-01T08:41:02.161333100Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1 Jul 1 08:41:02.161432 containerd[1913]: time="2025-07-01T08:41:02.161390000Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1 Jul 1 08:41:02.161510 containerd[1913]: time="2025-07-01T08:41:02.161486100Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1 Jul 1 08:41:02.161550 containerd[1913]: time="2025-07-01T08:41:02.161535700Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1 Jul 1 08:41:02.161584 containerd[1913]: time="2025-07-01T08:41:02.161551500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1 Jul 1 08:41:02.161584 containerd[1913]: time="2025-07-01T08:41:02.161561400Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1 Jul 1 08:41:02.161584 containerd[1913]: time="2025-07-01T08:41:02.161573800Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1 Jul 1 08:41:02.161651 containerd[1913]: time="2025-07-01T08:41:02.161583300Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1 Jul 1 08:41:02.161651 containerd[1913]: time="2025-07-01T08:41:02.161593400Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1 Jul 1 08:41:02.161651 containerd[1913]: time="2025-07-01T08:41:02.161613200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1 Jul 1 08:41:02.161651 containerd[1913]: time="2025-07-01T08:41:02.161635500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161659600Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161689100Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161718700Z" 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 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161727600Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161736500Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161743700Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1 Jul 1 08:41:02.161759 containerd[1913]: time="2025-07-01T08:41:02.161752500Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1 Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161762200Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1 Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161777700Z" level=info msg="runtime interface created" Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161783100Z" level=info msg="created NRI interface" Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161791200Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1 Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161801300Z" level=info msg="Connect containerd service" Jul 1 08:41:02.161900 containerd[1913]: time="2025-07-01T08:41:02.161824900Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this" Jul 1 08:41:02.162434 containerd[1913]: time="2025-07-01T08:41:02.162406000Z" 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" Jul 1 08:41:03.202913 waagent[2007]: 2025-07-01T08:41:03.202829Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4 Jul 1 08:41:03.207327 waagent[2007]: 2025-07-01T08:41:03.207255Z INFO Daemon Daemon OS: flatcar 9999.9.9 Jul 1 08:41:03.210844 waagent[2007]: 2025-07-01T08:41:03.210793Z INFO Daemon Daemon Python: 3.11.12 Jul 1 08:41:03.214238 waagent[2007]: 2025-07-01T08:41:03.214186Z INFO Daemon Daemon Run daemon Jul 1 08:41:03.217251 waagent[2007]: 2025-07-01T08:41:03.217210Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='9999.9.9' Jul 1 08:41:03.223888 waagent[2007]: 2025-07-01T08:41:03.223844Z INFO Daemon Daemon Using waagent for provisioning Jul 1 08:41:03.227840 waagent[2007]: 2025-07-01T08:41:03.227792Z INFO Daemon Daemon Activate resource disk Jul 1 08:41:03.231445 waagent[2007]: 2025-07-01T08:41:03.231398Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Jul 1 08:41:03.239293 waagent[2007]: 2025-07-01T08:41:03.239246Z INFO Daemon Daemon Found device: sdb Jul 1 08:41:03.248095 waagent[2007]: 2025-07-01T08:41:03.248040Z INFO Daemon Daemon Examining partition table Jul 1 08:41:03.273754 waagent[2007]: 2025-07-01T08:41:03.273685Z INFO Daemon Daemon GPT not detected, determining filesystem Jul 1 08:41:03.307940 waagent[2007]: 2025-07-01T08:41:03.307878Z INFO Daemon Daemon sfdisk --part-type -f /dev/sdb 1 -n succeeded Jul 1 08:41:03.312484 waagent[2007]: 2025-07-01T08:41:03.312434Z INFO Daemon Daemon The partition is formatted with ntfs, updating partition type to 83 Jul 1 08:41:03.535138 containerd[1913]: time="2025-07-01T08:41:03.535020700Z" level=info msg="Start subscribing containerd event" Jul 1 08:41:03.535138 containerd[1913]: time="2025-07-01T08:41:03.535085100Z" level=info msg="Start recovering state" Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535200000Z" level=info msg="Start event monitor" Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535224300Z" level=info msg="Start cni network conf syncer for default" Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535205500Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535301800Z" level=info msg=serving... address=/run/containerd/containerd.sock Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535234500Z" level=info msg="Start streaming server" Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535360800Z" level=info msg="Registered namespace \"k8s.io\" with NRI" Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535373200Z" level=info msg="runtime interface starting up..." Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535379800Z" level=info msg="starting plugins..." Jul 1 08:41:03.535505 containerd[1913]: time="2025-07-01T08:41:03.535400300Z" level=info msg="Synchronizing NRI (plugin) with current runtime state" Jul 1 08:41:03.535909 systemd[1]: Started containerd.service - containerd container runtime. Jul 1 08:41:03.536441 containerd[1913]: time="2025-07-01T08:41:03.536408500Z" level=info msg="containerd successfully booted in 1.402556s" Jul 1 08:41:03.682708 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#250 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:41:03.694710 kernel: sdb: sdb1 Jul 1 08:41:03.701718 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#11 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:41:03.711329 waagent[2007]: 2025-07-01T08:41:03.711253Z INFO Daemon Daemon sfdisk --part-type /dev/sdb 1 83 succeeded Jul 1 08:41:03.722712 kernel: sdb: sdb1 Jul 1 08:41:03.729712 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#581 cmd 0x5a status: scsi 0x2 srb 0x86 hv 0xc0000001 Jul 1 08:41:03.745715 kernel: sdb: sdb1 Jul 1 08:41:03.745766 waagent[2007]: 2025-07-01T08:41:03.745666Z INFO Daemon Daemon Format partition [mkfs.ext4 -F /dev/sdb1] Jul 1 08:41:08.274568 login[2035]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying Jul 1 08:41:08.274876 login[2032]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 1 08:41:08.281586 systemd[1]: Created slice user-500.slice - User Slice of UID 500. Jul 1 08:41:08.282741 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500... Jul 1 08:41:08.288965 systemd-logind[1895]: New session 2 of user core. Jul 1 08:41:08.331184 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500. Jul 1 08:41:08.333662 systemd[1]: Starting user@500.service - User Manager for UID 500... Jul 1 08:41:08.423369 (systemd)[2143]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Jul 1 08:41:08.425829 systemd-logind[1895]: New session c1 of user core. Jul 1 08:41:09.276439 login[2035]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Jul 1 08:41:09.281101 systemd-logind[1895]: New session 1 of user core. Jul 1 08:41:09.466519 waagent[2007]: 2025-07-01T08:41:09.466439Z INFO Daemon Daemon Mount resource disk [mount -t ext4 /dev/sdb1 /mnt/resource] Jul 1 08:41:09.573539 waagent[2007]: 2025-07-01T08:41:09.573473Z INFO Daemon Daemon Resource disk /dev/sdb is mounted at /mnt/resource with ext4 Jul 1 08:41:09.610051 kernel: EXT4-fs (sdb1): mounted filesystem 6aa93d3f-89a1-48ed-80f8-fa49e2cb3272 r/w with ordered data mode. Quota mode: none. Jul 1 08:41:09.578979 systemd[2143]: Queued start job for default target default.target. Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.575533Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.576597Z INFO Daemon Daemon Running default provisioning handler Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.584020Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 4. Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.585848Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.586268Z INFO Daemon Daemon cloud-init is enabled: False Jul 1 08:41:09.610363 waagent[2007]: 2025-07-01T08:41:09.587737Z INFO Daemon Daemon Copying ovf-env.xml Jul 1 08:41:09.639247 systemd[2143]: Created slice app.slice - User Application Slice. Jul 1 08:41:09.639294 systemd[2143]: Reached target paths.target - Paths. Jul 1 08:41:09.639343 systemd[2143]: Reached target timers.target - Timers. Jul 1 08:41:09.640562 systemd[2143]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 1 08:41:09.651294 systemd[2143]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 1 08:41:09.651411 systemd[2143]: Reached target sockets.target - Sockets. Jul 1 08:41:09.651465 systemd[2143]: Reached target basic.target - Basic System. Jul 1 08:41:09.651511 systemd[2143]: Reached target default.target - Main User Target. Jul 1 08:41:09.651545 systemd[2143]: Startup finished in 1.219s. Jul 1 08:41:09.651565 systemd[1]: Started user@500.service - User Manager for UID 500. Jul 1 08:41:09.652908 systemd[1]: Started session-1.scope - Session 1 of User core. Jul 1 08:41:09.653589 systemd[1]: Started session-2.scope - Session 2 of User core. Jul 1 08:41:09.737249 waagent[2007]: 2025-07-01T08:41:09.737177Z INFO Daemon Daemon Successfully mounted dvd Jul 1 08:41:09.751804 waagent[2007]: 2025-07-01T08:41:09.751757Z INFO Daemon Daemon Detect protocol endpoint Jul 1 08:41:09.751871 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Jul 1 08:41:09.755657 waagent[2007]: 2025-07-01T08:41:09.755603Z INFO Daemon Daemon Clean protocol and wireserver endpoint Jul 1 08:41:09.760173 waagent[2007]: 2025-07-01T08:41:09.760126Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Jul 1 08:41:09.765335 waagent[2007]: 2025-07-01T08:41:09.765289Z INFO Daemon Daemon Test for route to 168.63.129.16 Jul 1 08:41:09.769502 waagent[2007]: 2025-07-01T08:41:09.769461Z INFO Daemon Daemon Route to 168.63.129.16 exists Jul 1 08:41:09.773556 waagent[2007]: 2025-07-01T08:41:09.773510Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Jul 1 08:41:09.798579 waagent[2007]: 2025-07-01T08:41:09.798528Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Jul 1 08:41:09.800330 waagent[2007]: 2025-07-01T08:41:09.800301Z INFO Daemon Daemon Wire protocol version:2012-11-30 Jul 1 08:41:09.801646 waagent[2007]: 2025-07-01T08:41:09.801611Z INFO Daemon Daemon Server preferred version:2015-04-05 Jul 1 08:41:09.986734 waagent[2007]: 2025-07-01T08:41:09.986589Z INFO Daemon Daemon Initializing goal state during protocol detection Jul 1 08:41:09.988343 waagent[2007]: 2025-07-01T08:41:09.988299Z INFO Daemon Daemon Forcing an update of the goal state. Jul 1 08:41:09.992981 waagent[2007]: 2025-07-01T08:41:09.992937Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 1 08:41:10.029539 waagent[2007]: 2025-07-01T08:41:10.029494Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.175 Jul 1 08:41:10.031521 waagent[2007]: 2025-07-01T08:41:10.031482Z INFO Daemon Jul 1 08:41:10.032650 waagent[2007]: 2025-07-01T08:41:10.032616Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: b6d7f23e-7b11-4a01-98cc-3619375adc6d eTag: 12777832232645394369 source: Fabric] Jul 1 08:41:10.033681 waagent[2007]: 2025-07-01T08:41:10.033636Z INFO Daemon The vmSettings originated via Fabric; will ignore them. Jul 1 08:41:10.034362 waagent[2007]: 2025-07-01T08:41:10.034316Z INFO Daemon Jul 1 08:41:10.035611 waagent[2007]: 2025-07-01T08:41:10.035564Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1] Jul 1 08:41:10.040633 waagent[2007]: 2025-07-01T08:41:10.040596Z INFO Daemon Daemon Downloading artifacts profile blob Jul 1 08:41:10.190265 waagent[2007]: 2025-07-01T08:41:10.190194Z INFO Daemon Downloaded certificate {'thumbprint': 'E3962E2F0EDF561DE42127A86E3ACEF2233F486E', 'hasPrivateKey': True} Jul 1 08:41:10.192385 waagent[2007]: 2025-07-01T08:41:10.192328Z INFO Daemon Fetch goal state completed Jul 1 08:41:10.231630 waagent[2007]: 2025-07-01T08:41:10.231574Z INFO Daemon Daemon Starting provisioning Jul 1 08:41:10.234542 waagent[2007]: 2025-07-01T08:41:10.234482Z INFO Daemon Daemon Handle ovf-env.xml. Jul 1 08:41:10.238216 waagent[2007]: 2025-07-01T08:41:10.238128Z INFO Daemon Daemon Set hostname [ci-9999.9.9-s-69685fc6e4] Jul 1 08:41:10.266155 waagent[2007]: 2025-07-01T08:41:10.266095Z INFO Daemon Daemon Publish hostname [ci-9999.9.9-s-69685fc6e4] Jul 1 08:41:10.268048 waagent[2007]: 2025-07-01T08:41:10.267994Z INFO Daemon Daemon Examine /proc/net/route for primary interface Jul 1 08:41:10.269481 waagent[2007]: 2025-07-01T08:41:10.269437Z INFO Daemon Daemon Primary interface is [eth0] Jul 1 08:41:10.288975 systemd-networkd[1587]: eth0: found matching network '/usr/lib/systemd/network/zz-default.network', based on potentially unpredictable interface name. Jul 1 08:41:10.288985 systemd-networkd[1587]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Jul 1 08:41:10.289017 systemd-networkd[1587]: eth0: DHCP lease lost Jul 1 08:41:10.289509 waagent[2007]: 2025-07-01T08:41:10.289462Z INFO Daemon Daemon Create user account if not exists Jul 1 08:41:10.291172 waagent[2007]: 2025-07-01T08:41:10.291129Z INFO Daemon Daemon User core already exists, skip useradd Jul 1 08:41:10.292561 waagent[2007]: 2025-07-01T08:41:10.292527Z INFO Daemon Daemon Configure sudoer Jul 1 08:41:10.298195 waagent[2007]: 2025-07-01T08:41:10.298147Z INFO Daemon Daemon Configure sshd Jul 1 08:41:10.302019 waagent[2007]: 2025-07-01T08:41:10.301972Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive. Jul 1 08:41:10.302169 waagent[2007]: 2025-07-01T08:41:10.302130Z INFO Daemon Daemon Deploy ssh public key. Jul 1 08:41:10.371749 systemd-networkd[1587]: eth0: DHCPv4 address 10.200.8.12/24, gateway 10.200.8.1 acquired from 168.63.129.16 Jul 1 08:41:11.482399 waagent[2007]: 2025-07-01T08:41:11.482340Z INFO Daemon Daemon Provisioning complete Jul 1 08:41:11.494195 waagent[2007]: 2025-07-01T08:41:11.494150Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Jul 1 08:41:11.495822 waagent[2007]: 2025-07-01T08:41:11.495767Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Jul 1 08:41:11.496480 waagent[2007]: 2025-07-01T08:41:11.496439Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent Jul 1 08:41:11.642710 waagent[2210]: 2025-07-01T08:41:11.642615Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4) Jul 1 08:41:11.643024 waagent[2210]: 2025-07-01T08:41:11.642766Z INFO ExtHandler ExtHandler OS: flatcar 9999.9.9 Jul 1 08:41:11.643024 waagent[2210]: 2025-07-01T08:41:11.642837Z INFO ExtHandler ExtHandler Python: 3.11.12 Jul 1 08:41:11.643024 waagent[2210]: 2025-07-01T08:41:11.642902Z INFO ExtHandler ExtHandler CPU Arch: x86_64 Jul 1 08:41:11.815607 waagent[2210]: 2025-07-01T08:41:11.815542Z INFO ExtHandler ExtHandler Distro: flatcar-9999.9.9; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.12; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0; Jul 1 08:41:11.815828 waagent[2210]: 2025-07-01T08:41:11.815789Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 1 08:41:11.815909 waagent[2210]: 2025-07-01T08:41:11.815875Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 1 08:41:11.822421 waagent[2210]: 2025-07-01T08:41:11.822362Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Jul 1 08:41:11.827370 waagent[2210]: 2025-07-01T08:41:11.827330Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.175 Jul 1 08:41:11.827821 waagent[2210]: 2025-07-01T08:41:11.827783Z INFO ExtHandler Jul 1 08:41:11.827904 waagent[2210]: 2025-07-01T08:41:11.827872Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 64eecee1-95e3-4c19-b185-d47c1436da92 eTag: 12777832232645394369 source: Fabric] Jul 1 08:41:11.828214 waagent[2210]: 2025-07-01T08:41:11.828176Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Jul 1 08:41:11.828852 waagent[2210]: 2025-07-01T08:41:11.828805Z INFO ExtHandler Jul 1 08:41:11.828917 waagent[2210]: 2025-07-01T08:41:11.828886Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Jul 1 08:41:11.838547 waagent[2210]: 2025-07-01T08:41:11.838513Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Jul 1 08:41:11.902281 waagent[2210]: 2025-07-01T08:41:11.902214Z INFO ExtHandler Downloaded certificate {'thumbprint': 'E3962E2F0EDF561DE42127A86E3ACEF2233F486E', 'hasPrivateKey': True} Jul 1 08:41:11.902807 waagent[2210]: 2025-07-01T08:41:11.902762Z INFO ExtHandler Fetch goal state completed Jul 1 08:41:11.918158 waagent[2210]: 2025-07-01T08:41:11.918111Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.3.3 11 Feb 2025 (Library: OpenSSL 3.3.3 11 Feb 2025) Jul 1 08:41:11.923012 waagent[2210]: 2025-07-01T08:41:11.922965Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2210 Jul 1 08:41:11.923147 waagent[2210]: 2025-07-01T08:41:11.923111Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ******** Jul 1 08:41:11.923478 waagent[2210]: 2025-07-01T08:41:11.923441Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ******** Jul 1 08:41:11.925079 waagent[2210]: 2025-07-01T08:41:11.925038Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '9999.9.9', '', 'Flatcar Container Linux by Kinvolk'] Jul 1 08:41:11.925503 waagent[2210]: 2025-07-01T08:41:11.925464Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '9999.9.9', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Jul 1 08:41:11.925653 waagent[2210]: 2025-07-01T08:41:11.925617Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Jul 1 08:41:11.926282 waagent[2210]: 2025-07-01T08:41:11.926243Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Jul 1 08:41:11.981852 waagent[2210]: 2025-07-01T08:41:11.981803Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Jul 1 08:41:11.982019 waagent[2210]: 2025-07-01T08:41:11.981986Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Jul 1 08:41:11.988957 waagent[2210]: 2025-07-01T08:41:11.988875Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Jul 1 08:41:11.996092 systemd[1]: Reload requested from client PID 2225 ('systemctl') (unit waagent.service)... Jul 1 08:41:11.996112 systemd[1]: Reloading... Jul 1 08:41:12.058723 zram_generator::config[2263]: No configuration found. Jul 1 08:41:12.170660 systemd[1]: /usr/lib/systemd/system/docker.socket:6: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Jul 1 08:41:12.342731 systemd[1]: Reloading finished in 346 ms. Jul 1 08:41:12.367275 waagent[2210]: 2025-07-01T08:41:12.367205Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service Jul 1 08:41:12.367379 waagent[2210]: 2025-07-01T08:41:12.367345Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully Jul 1 08:41:12.740566 waagent[2210]: 2025-07-01T08:41:12.740488Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Jul 1 08:41:12.740926 waagent[2210]: 2025-07-01T08:41:12.740845Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Jul 1 08:41:12.741619 waagent[2210]: 2025-07-01T08:41:12.741571Z INFO ExtHandler ExtHandler Starting env monitor service. Jul 1 08:41:12.742083 waagent[2210]: 2025-07-01T08:41:12.742043Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Jul 1 08:41:12.742172 waagent[2210]: 2025-07-01T08:41:12.742126Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 1 08:41:12.742542 waagent[2210]: 2025-07-01T08:41:12.742489Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Jul 1 08:41:12.742676 waagent[2210]: 2025-07-01T08:41:12.742639Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 1 08:41:12.742731 waagent[2210]: 2025-07-01T08:41:12.742677Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Jul 1 08:41:12.743031 waagent[2210]: 2025-07-01T08:41:12.742988Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Jul 1 08:41:12.743245 waagent[2210]: 2025-07-01T08:41:12.743208Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Jul 1 08:41:12.743526 waagent[2210]: 2025-07-01T08:41:12.743484Z INFO EnvHandler ExtHandler Configure routes Jul 1 08:41:12.743875 waagent[2210]: 2025-07-01T08:41:12.743823Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Jul 1 08:41:12.744088 waagent[2210]: 2025-07-01T08:41:12.744036Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Jul 1 08:41:12.744154 waagent[2210]: 2025-07-01T08:41:12.744115Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Jul 1 08:41:12.744206 waagent[2210]: 2025-07-01T08:41:12.744170Z INFO EnvHandler ExtHandler Gateway:None Jul 1 08:41:12.744457 waagent[2210]: 2025-07-01T08:41:12.744420Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Jul 1 08:41:12.744741 waagent[2210]: 2025-07-01T08:41:12.744687Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Jul 1 08:41:12.744741 waagent[2210]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Jul 1 08:41:12.744741 waagent[2210]: eth0 00000000 0108C80A 0003 0 0 1024 00000000 0 0 0 Jul 1 08:41:12.744741 waagent[2210]: eth0 0008C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Jul 1 08:41:12.744741 waagent[2210]: eth0 0108C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Jul 1 08:41:12.744741 waagent[2210]: eth0 10813FA8 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 1 08:41:12.744741 waagent[2210]: eth0 FEA9FEA9 0108C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Jul 1 08:41:12.745477 waagent[2210]: 2025-07-01T08:41:12.745442Z INFO EnvHandler ExtHandler Routes:None Jul 1 08:41:12.750436 waagent[2210]: 2025-07-01T08:41:12.750389Z INFO ExtHandler ExtHandler Jul 1 08:41:12.750715 waagent[2210]: 2025-07-01T08:41:12.750663Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ef0740de-e460-4153-bb85-d981db32b3ac correlation ee00e758-fbc3-42ab-a676-17d0f9a982e7 created: 2025-07-01T08:38:53.071846Z] Jul 1 08:41:12.751599 waagent[2210]: 2025-07-01T08:41:12.751555Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Jul 1 08:41:12.753447 waagent[2210]: 2025-07-01T08:41:12.753400Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Jul 1 08:41:12.813299 waagent[2210]: 2025-07-01T08:41:12.813252Z WARNING ExtHandler ExtHandler Failed to get firewall packets: 'iptables -w -t security -L OUTPUT --zero OUTPUT -nxv' failed: 2 (iptables v1.8.11 (nf_tables): Illegal option `--numeric' with this command Jul 1 08:41:12.813299 waagent[2210]: Try `iptables -h' or 'iptables --help' for more information.) Jul 1 08:41:12.813664 waagent[2210]: 2025-07-01T08:41:12.813624Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: F4EDCCDD-B2AE-4D78-8C4A-0D7CAF9CD99F;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;] Jul 1 08:41:12.835948 waagent[2210]: 2025-07-01T08:41:12.835899Z INFO MonitorHandler ExtHandler Network interfaces: Jul 1 08:41:12.835948 waagent[2210]: Executing ['ip', '-a', '-o', 'link']: Jul 1 08:41:12.835948 waagent[2210]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Jul 1 08:41:12.835948 waagent[2210]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 7c:ed:8d:49:73:06 brd ff:ff:ff:ff:ff:ff Jul 1 08:41:12.835948 waagent[2210]: Executing ['ip', '-4', '-a', '-o', 'address']: Jul 1 08:41:12.835948 waagent[2210]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Jul 1 08:41:12.835948 waagent[2210]: 2: eth0 inet 10.200.8.12/24 metric 1024 brd 10.200.8.255 scope global eth0\ valid_lft forever preferred_lft forever Jul 1 08:41:12.835948 waagent[2210]: Executing ['ip', '-6', '-a', '-o', 'address']: Jul 1 08:41:12.835948 waagent[2210]: 1: lo inet6 ::1/128 scope host noprefixroute \ valid_lft forever preferred_lft forever Jul 1 08:41:12.835948 waagent[2210]: 2: eth0 inet6 fe80::7eed:8dff:fe49:7306/64 scope link proto kernel_ll \ valid_lft forever preferred_lft forever Jul 1 08:41:13.025507 waagent[2210]: 2025-07-01T08:41:13.025397Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Jul 1 08:41:13.025507 waagent[2210]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.025507 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.025507 waagent[2210]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.025507 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.025507 waagent[2210]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.025507 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.025507 waagent[2210]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 1 08:41:13.025507 waagent[2210]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 1 08:41:13.025507 waagent[2210]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 1 08:41:13.028887 waagent[2210]: 2025-07-01T08:41:13.028841Z INFO EnvHandler ExtHandler Current Firewall rules: Jul 1 08:41:13.028887 waagent[2210]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.028887 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.028887 waagent[2210]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.028887 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.028887 waagent[2210]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Jul 1 08:41:13.028887 waagent[2210]: pkts bytes target prot opt in out source destination Jul 1 08:41:13.028887 waagent[2210]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Jul 1 08:41:13.028887 waagent[2210]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Jul 1 08:41:13.028887 waagent[2210]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Jul 1 08:41:13.029457 waagent[2210]: 2025-07-01T08:41:13.029099Z INFO EnvHandler ExtHandler Set block dev timeout: sdb with timeout: 300 Jul 1 08:41:13.029754 waagent[2210]: 2025-07-01T08:41:13.029674Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Jul 1 08:41:24.075232 chronyd[1888]: Selected source PHC0 Jul 1 08:41:39.723686 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd. Jul 1 08:41:39.726436 systemd[1]: Started sshd@0-10.200.8.12:22-10.200.16.10:39526.service - OpenSSH per-connection server daemon (10.200.16.10:39526). Jul 1 08:41:40.725903 sshd[2355]: Accepted publickey for core from 10.200.16.10 port 39526 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:41:40.727037 sshd-session[2355]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:41:40.731230 systemd-logind[1895]: New session 3 of user core. Jul 1 08:41:40.753838 systemd[1]: Started session-3.scope - Session 3 of User core. Jul 1 08:41:41.279900 systemd[1]: Started sshd@1-10.200.8.12:22-10.200.16.10:39534.service - OpenSSH per-connection server daemon (10.200.16.10:39534). Jul 1 08:41:41.907655 sshd[2361]: Accepted publickey for core from 10.200.16.10 port 39534 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:41:41.933681 sshd-session[2361]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:41:41.937942 systemd-logind[1895]: New session 4 of user core. Jul 1 08:41:41.948820 systemd[1]: Started session-4.scope - Session 4 of User core. Jul 1 08:41:42.354581 sshd[2364]: Connection closed by 10.200.16.10 port 39534 Jul 1 08:41:42.354934 sshd-session[2361]: pam_unix(sshd:session): session closed for user core Jul 1 08:41:42.358389 systemd[1]: sshd@1-10.200.8.12:22-10.200.16.10:39534.service: Deactivated successfully. Jul 1 08:41:42.360227 systemd[1]: session-4.scope: Deactivated successfully. Jul 1 08:41:42.361037 systemd-logind[1895]: Session 4 logged out. Waiting for processes to exit. Jul 1 08:41:42.362007 systemd-logind[1895]: Removed session 4. Jul 1 08:41:42.469875 systemd[1]: Started sshd@2-10.200.8.12:22-10.200.16.10:39542.service - OpenSSH per-connection server daemon (10.200.16.10:39542). Jul 1 08:41:42.841817 kernel: hv_balloon: Max. dynamic memory size: 114688 MB Jul 1 08:41:43.100394 sshd[2370]: Accepted publickey for core from 10.200.16.10 port 39542 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:41:43.101540 sshd-session[2370]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:41:43.105699 systemd-logind[1895]: New session 5 of user core. Jul 1 08:41:43.115823 systemd[1]: Started session-5.scope - Session 5 of User core. Jul 1 08:41:43.554772 sshd[2373]: Connection closed by 10.200.16.10 port 39542 Jul 1 08:41:43.555034 sshd-session[2370]: pam_unix(sshd:session): session closed for user core Jul 1 08:41:43.558328 systemd[1]: sshd@2-10.200.8.12:22-10.200.16.10:39542.service: Deactivated successfully. Jul 1 08:41:43.559924 systemd[1]: session-5.scope: Deactivated successfully. Jul 1 08:41:43.560588 systemd-logind[1895]: Session 5 logged out. Waiting for processes to exit. Jul 1 08:41:43.561554 systemd-logind[1895]: Removed session 5. Jul 1 08:41:45.999261 update_engine[1896]: I20250701 08:41:45.999174 1896 update_attempter.cc:509] Updating boot flags... Jul 1 08:41:53.669977 systemd[1]: Started sshd@3-10.200.8.12:22-10.200.16.10:34938.service - OpenSSH per-connection server daemon (10.200.16.10:34938). Jul 1 08:41:54.296535 sshd[2443]: Accepted publickey for core from 10.200.16.10 port 34938 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:41:54.297719 sshd-session[2443]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:41:54.301917 systemd-logind[1895]: New session 6 of user core. Jul 1 08:41:54.317821 systemd[1]: Started session-6.scope - Session 6 of User core. Jul 1 08:41:54.743154 sshd[2446]: Connection closed by 10.200.16.10 port 34938 Jul 1 08:41:54.743499 sshd-session[2443]: pam_unix(sshd:session): session closed for user core Jul 1 08:41:54.746531 systemd[1]: sshd@3-10.200.8.12:22-10.200.16.10:34938.service: Deactivated successfully. Jul 1 08:41:54.748134 systemd[1]: session-6.scope: Deactivated successfully. Jul 1 08:41:54.748978 systemd-logind[1895]: Session 6 logged out. Waiting for processes to exit. Jul 1 08:41:54.750150 systemd-logind[1895]: Removed session 6. Jul 1 08:41:54.857835 systemd[1]: Started sshd@4-10.200.8.12:22-10.200.16.10:34950.service - OpenSSH per-connection server daemon (10.200.16.10:34950). Jul 1 08:41:55.483932 sshd[2452]: Accepted publickey for core from 10.200.16.10 port 34950 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:41:55.485118 sshd-session[2452]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:41:55.489236 systemd-logind[1895]: New session 7 of user core. Jul 1 08:41:55.501817 systemd[1]: Started session-7.scope - Session 7 of User core. Jul 1 08:41:55.929094 sshd[2455]: Connection closed by 10.200.16.10 port 34950 Jul 1 08:41:55.929426 sshd-session[2452]: pam_unix(sshd:session): session closed for user core Jul 1 08:41:55.932596 systemd[1]: sshd@4-10.200.8.12:22-10.200.16.10:34950.service: Deactivated successfully. Jul 1 08:41:55.934892 systemd[1]: session-7.scope: Deactivated successfully. Jul 1 08:41:55.935563 systemd-logind[1895]: Session 7 logged out. Waiting for processes to exit. Jul 1 08:41:55.936469 systemd-logind[1895]: Removed session 7. Jul 1 08:42:01.177351 setup-nvidia[2460]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 1 08:42:01.177720 setup-nvidia[2460]: Dload Upload Total Spent Left Speed Jul 1 08:42:06.050001 systemd[1]: Started sshd@5-10.200.8.12:22-10.200.16.10:36480.service - OpenSSH per-connection server daemon (10.200.16.10:36480). Jul 1 08:42:06.679966 sshd[2462]: Accepted publickey for core from 10.200.16.10 port 36480 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:06.681204 sshd-session[2462]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:06.686121 systemd-logind[1895]: New session 8 of user core. Jul 1 08:42:06.701832 systemd[1]: Started session-8.scope - Session 8 of User core. Jul 1 08:42:07.124201 sshd[2465]: Connection closed by 10.200.16.10 port 36480 Jul 1 08:42:07.124574 sshd-session[2462]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:07.127963 systemd[1]: sshd@5-10.200.8.12:22-10.200.16.10:36480.service: Deactivated successfully. Jul 1 08:42:07.129553 systemd[1]: session-8.scope: Deactivated successfully. Jul 1 08:42:07.130242 systemd-logind[1895]: Session 8 logged out. Waiting for processes to exit. Jul 1 08:42:07.131136 systemd-logind[1895]: Removed session 8. Jul 1 08:42:07.238869 systemd[1]: Started sshd@6-10.200.8.12:22-10.200.16.10:36494.service - OpenSSH per-connection server daemon (10.200.16.10:36494). Jul 1 08:42:07.867602 sshd[2471]: Accepted publickey for core from 10.200.16.10 port 36494 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:07.869018 sshd-session[2471]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:07.873201 systemd-logind[1895]: New session 9 of user core. Jul 1 08:42:07.886825 systemd[1]: Started session-9.scope - Session 9 of User core. Jul 1 08:42:08.312471 sshd[2474]: Connection closed by 10.200.16.10 port 36494 Jul 1 08:42:08.312836 sshd-session[2471]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:08.316186 systemd[1]: sshd@6-10.200.8.12:22-10.200.16.10:36494.service: Deactivated successfully. Jul 1 08:42:08.317916 systemd[1]: session-9.scope: Deactivated successfully. Jul 1 08:42:08.318650 systemd-logind[1895]: Session 9 logged out. Waiting for processes to exit. Jul 1 08:42:08.319634 systemd-logind[1895]: Removed session 9. Jul 1 08:42:18.439229 systemd[1]: Started sshd@7-10.200.8.12:22-10.200.16.10:33846.service - OpenSSH per-connection server daemon (10.200.16.10:33846). Jul 1 08:42:19.086232 sshd[2480]: Accepted publickey for core from 10.200.16.10 port 33846 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:19.087437 sshd-session[2480]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:19.091653 systemd-logind[1895]: New session 10 of user core. Jul 1 08:42:19.102818 systemd[1]: Started session-10.scope - Session 10 of User core. Jul 1 08:42:19.532846 sshd[2483]: Connection closed by 10.200.16.10 port 33846 Jul 1 08:42:19.533130 sshd-session[2480]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:19.536615 systemd[1]: sshd@7-10.200.8.12:22-10.200.16.10:33846.service: Deactivated successfully. Jul 1 08:42:19.538210 systemd[1]: session-10.scope: Deactivated successfully. Jul 1 08:42:19.538890 systemd-logind[1895]: Session 10 logged out. Waiting for processes to exit. Jul 1 08:42:19.539783 systemd-logind[1895]: Removed session 10. Jul 1 08:42:19.647917 systemd[1]: Started sshd@8-10.200.8.12:22-10.200.16.10:51648.service - OpenSSH per-connection server daemon (10.200.16.10:51648). Jul 1 08:42:20.276059 sshd[2489]: Accepted publickey for core from 10.200.16.10 port 51648 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:20.277248 sshd-session[2489]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:20.281501 systemd-logind[1895]: New session 11 of user core. Jul 1 08:42:20.293817 systemd[1]: Started session-11.scope - Session 11 of User core. Jul 1 08:42:20.721210 sshd[2492]: Connection closed by 10.200.16.10 port 51648 Jul 1 08:42:20.721562 sshd-session[2489]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:20.724933 systemd[1]: sshd@8-10.200.8.12:22-10.200.16.10:51648.service: Deactivated successfully. Jul 1 08:42:20.726515 systemd[1]: session-11.scope: Deactivated successfully. Jul 1 08:42:20.727211 systemd-logind[1895]: Session 11 logged out. Waiting for processes to exit. Jul 1 08:42:20.728092 systemd-logind[1895]: Removed session 11. Jul 1 08:42:24.811129 setup-nvidia[2460]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 0 547M 0 303k 0 0 288k 0 0:32:25 0:00:01 0:32:24 288k\u000d 2 547M 2 13.2M 0 0 6677k 0 0:01:23 0:00:02 0:01:21 6675k\u000d 6 547M 6 36.7M 0 0 12.1M 0 0:00:44 0:00:03 0:00:41 12.1M\u000d 11 547M 11 62.6M 0 0 15.6M 0 0:00:34 0:00:04 0:00:30 15.6M\u000d 16 547M 16 87.9M 0 0 17.1M 0 0:00:31 0:00:05 0:00:26 17.1M\u000d 20 547M 20 112M 0 0 18.3M 0 0:00:29 0:00:06 0:00:23 22.1M\u000d 25 547M 25 137M 0 0 19.3M 0 0:00:28 0:00:07 0:00:21 24.4M\u000d 29 547M 29 162M 0 0 20.0M 0 0:00:27 0:00:08 0:00:19 24.7M\u000d 34 547M 34 187M 0 0 20.6M 0 0:00:26 0:00:09 0:00:17 24.6M\u000d 38 547M 38 212M 0 0 21.0M 0 0:00:26 0:00:10 0:00:16 25.1M\u000d 43 547M 43 236M 0 0 21.3M 0 0:00:25 0:00:11 0:00:14 25.0M\u000d 47 547M 47 260M 0 0 21.5M 0 0:00:25 0:00:12 0:00:13 24.8M\u000d 51 547M 51 283M 0 0 21.7M 0 0:00:25 0:00:13 0:00:12 24.5M\u000d 56 547M 56 307M 0 0 21.9M 0 0:00:24 0:00:14 0:00:10 24.3M\u000d 60 547M 60 332M 0 0 22.1M 0 0:00:24 0:00:15 0:00:09 24.3M\u000d 65 547M 65 357M 0 0 22.3M 0 0:00:24 0:00:16 0:00:08 24.5M\u000d 70 547M 70 383M 0 0 22.3M 0 0:00:24 0:00:17 0:00:07 24.3M\u000d 74 547M 74 407M 0 0 22.5M 0 0:00:24 0:00:18 0:00:06 24.4M\u000d 78 547M 78 431M 0 0 22.6M 0 0:00:24 0:00:19 0:00:05 24.4M\u000d 83 547M 83 456M 0 0 22.7M 0 0:00:24 0:00:20 0:00:04 24.3M\u000d 87 547M 87 481M 0 0 22.8M 0 0:00:23 0:00:21 0:00:02 24.3M\u000d 92 547M 92 506M 0 0 22.9M 0 0:00:23 0:00:22 0:00:01 24.7M\u000d 97 547M 97 531M 0 0 23.0M 0 0:00:23 0:00:23 --:--:-- 24.9M\u000d100 547M 100 547M 0 0 23.1M 0 0:00:23 0:00:23 --:--:-- 25.4M Jul 1 08:42:30.837166 systemd[1]: Started sshd@9-10.200.8.12:22-10.200.16.10:44590.service - OpenSSH per-connection server daemon (10.200.16.10:44590). Jul 1 08:42:31.482622 sshd[2508]: Accepted publickey for core from 10.200.16.10 port 44590 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:31.483891 sshd-session[2508]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:31.492686 systemd-logind[1895]: New session 12 of user core. Jul 1 08:42:31.498851 systemd[1]: Started session-12.scope - Session 12 of User core. Jul 1 08:42:31.935991 sshd[2511]: Connection closed by 10.200.16.10 port 44590 Jul 1 08:42:31.934902 sshd-session[2508]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:31.939973 systemd[1]: sshd@9-10.200.8.12:22-10.200.16.10:44590.service: Deactivated successfully. Jul 1 08:42:31.943489 systemd[1]: session-12.scope: Deactivated successfully. Jul 1 08:42:31.945472 systemd-logind[1895]: Session 12 logged out. Waiting for processes to exit. Jul 1 08:42:31.947587 systemd-logind[1895]: Removed session 12. Jul 1 08:42:32.051342 systemd[1]: Started sshd@10-10.200.8.12:22-10.200.16.10:44606.service - OpenSSH per-connection server daemon (10.200.16.10:44606). Jul 1 08:42:32.706449 sshd[2517]: Accepted publickey for core from 10.200.16.10 port 44606 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:32.701636 sshd-session[2517]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:32.710005 systemd-logind[1895]: New session 13 of user core. Jul 1 08:42:32.715828 systemd[1]: Started session-13.scope - Session 13 of User core. Jul 1 08:42:33.155715 sshd[2520]: Connection closed by 10.200.16.10 port 44606 Jul 1 08:42:33.156906 sshd-session[2517]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:33.163058 systemd-logind[1895]: Session 13 logged out. Waiting for processes to exit. Jul 1 08:42:33.163859 systemd[1]: sshd@10-10.200.8.12:22-10.200.16.10:44606.service: Deactivated successfully. Jul 1 08:42:33.166441 systemd[1]: session-13.scope: Deactivated successfully. Jul 1 08:42:33.169667 systemd-logind[1895]: Removed session 13. Jul 1 08:42:35.640217 setup-nvidia[1889]: Downloading NVIDIA 535.247.01 Driver Jul 1 08:42:35.644535 setup-nvidia[2526]: % Total % Received % Xferd Average Speed Time Time Time Current Jul 1 08:42:35.644758 setup-nvidia[2526]: Dload Upload Total Spent Left Speed Jul 1 08:42:37.362313 setup-nvidia[2526]: \u000d 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0\u000d 35 326M 35 115M 0 0 216M 0 0:00:01 --:--:-- 0:00:01 215M\u000d 83 326M 83 272M 0 0 177M 0 0:00:01 0:00:01 --:--:-- 177M\u000d100 326M 100 326M 0 0 189M 0 0:00:01 0:00:01 --:--:-- 189M Jul 1 08:42:37.428926 setup-nvidia[1889]: Extract the NVIDIA Driver Installer 535.247.01 Jul 1 08:42:37.429136 setup-nvidia[1889]: /opt/nvidia/workdir/nvidia-workdir / Jul 1 08:42:37.447043 setup-nvidia[2529]: Creating directory NVIDIA-Linux-x86_64-535.247.01 Jul 1 08:42:37.685033 setup-nvidia[2529]: Verifying archive integrity... OK Jul 1 08:42:38.433571 setup-nvidia[2529]: Uncompressing NVIDIA Accelerated Graphics Driver for Linux-x86_64 535.247.01 Jul 1 08:42:40.235770 setup-nvidia[2555]: ........................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................................ Jul 1 08:42:40.287510 setup-nvidia[1889]: / Jul 1 08:42:40.287729 setup-nvidia[1889]: Spawn system-nspawn container to install the NVIDIA drivers Jul 1 08:42:40.450865 sudo[2562]: root : PWD=/ ; USER=root ; COMMAND=/usr/sbin/systemd-nspawn --keep-unit --register=no --read-only --volatile=overlay --image=/opt/nvidia/workdir/flatcar_developer_container-9999.9.9+sayan-publish-nighlies-sig.bin --overlay=/usr/lib/modules::/usr/lib/modules --bind=/opt/nvidia/workdir/nvidia-workdir:/nvidia --bind=/usr/lib/nvidia/bin:/app/bin/ /app/bin/install-nvidia NVIDIA-Linux-x86_64-535.247.01 Jul 1 08:42:40.452609 sudo[2562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jul 1 08:42:40.458370 systemd[1]: Created slice user-0.slice - User Slice of UID 0. Jul 1 08:42:40.461063 systemd[1]: Starting user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 1 08:42:40.494066 systemd[1]: Finished user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 1 08:42:40.497756 systemd[1]: Starting user@0.service - User Manager for UID 0... Jul 1 08:42:40.513650 (systemd)[2565]: pam_unix(systemd-user:session): session opened for user root(uid=0) by (uid=0) Jul 1 08:42:40.516306 systemd-logind[1895]: New session c3 of user root. Jul 1 08:42:40.694103 systemd[2565]: Queued start job for default target default.target. Jul 1 08:42:40.712739 systemd[2565]: Created slice app.slice - User Application Slice. Jul 1 08:42:40.712768 systemd[2565]: Reached target paths.target - Paths. Jul 1 08:42:40.712816 systemd[2565]: Reached target timers.target - Timers. Jul 1 08:42:40.713891 systemd[2565]: Starting dbus.socket - D-Bus User Message Bus Socket... Jul 1 08:42:40.724499 systemd[2565]: Listening on dbus.socket - D-Bus User Message Bus Socket. Jul 1 08:42:40.724557 systemd[2565]: Reached target sockets.target - Sockets. Jul 1 08:42:40.724607 systemd[2565]: Reached target basic.target - Basic System. Jul 1 08:42:40.724652 systemd[2565]: Reached target default.target - Main User Target. Jul 1 08:42:40.724682 systemd[2565]: Startup finished in 202ms. Jul 1 08:42:40.724754 systemd[1]: Started user@0.service - User Manager for UID 0. Jul 1 08:42:40.727957 systemd[1]: Started session-c2.scope - Session c2 of User root. Jul 1 08:42:40.835719 kernel: loop6: detected capacity change from 0 to 12644352 Jul 1 08:42:40.843710 kernel: loop6: p9 Jul 1 08:42:40.902727 setup-nvidia[2574]: ░ Spawning container flatcardevelopercontainer-9999.9.9sayan-publish-nighlies-sig.bin on /opt/nvidia/workdir/flatcar_developer_container-9999.9.9+sayan-publish-nighlies-sig.bin. Jul 1 08:42:41.570323 kernel: EXT4-fs (loop6p9): write access unavailable, skipping orphan cleanup Jul 1 08:42:41.570455 kernel: EXT4-fs (loop6p9): mounted filesystem 00ecce5b-39d7-4436-8e6a-86c848c6447a ro without journal. Quota mode: none. Jul 1 08:42:43.270930 systemd[1]: Started sshd@11-10.200.8.12:22-10.200.16.10:49192.service - OpenSSH per-connection server daemon (10.200.16.10:49192). Jul 1 08:42:43.904351 sshd[2637]: Accepted publickey for core from 10.200.16.10 port 49192 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:43.905471 sshd-session[2637]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:43.909601 systemd-logind[1895]: New session 14 of user core. Jul 1 08:42:43.918824 systemd[1]: Started session-14.scope - Session 14 of User core. Jul 1 08:42:44.349297 sshd[2692]: Connection closed by 10.200.16.10 port 49192 Jul 1 08:42:44.349631 sshd-session[2637]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:44.352883 systemd[1]: sshd@11-10.200.8.12:22-10.200.16.10:49192.service: Deactivated successfully. Jul 1 08:42:44.354361 systemd[1]: session-14.scope: Deactivated successfully. Jul 1 08:42:44.355063 systemd-logind[1895]: Session 14 logged out. Waiting for processes to exit. Jul 1 08:42:44.355986 systemd-logind[1895]: Removed session 14. Jul 1 08:42:44.462034 systemd[1]: Started sshd@12-10.200.8.12:22-10.200.16.10:49198.service - OpenSSH per-connection server daemon (10.200.16.10:49198). Jul 1 08:42:45.097766 sshd[2824]: Accepted publickey for core from 10.200.16.10 port 49198 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:45.098940 sshd-session[2824]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:45.103590 systemd-logind[1895]: New session 15 of user core. Jul 1 08:42:45.115856 systemd[1]: Started session-15.scope - Session 15 of User core. Jul 1 08:42:45.542301 sshd[3016]: Connection closed by 10.200.16.10 port 49198 Jul 1 08:42:45.542567 sshd-session[2824]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:45.546110 systemd[1]: sshd@12-10.200.8.12:22-10.200.16.10:49198.service: Deactivated successfully. Jul 1 08:42:45.547590 systemd[1]: session-15.scope: Deactivated successfully. Jul 1 08:42:45.548342 systemd-logind[1895]: Session 15 logged out. Waiting for processes to exit. Jul 1 08:42:45.549471 systemd-logind[1895]: Removed session 15. Jul 1 08:42:55.660331 systemd[1]: Started sshd@13-10.200.8.12:22-10.200.16.10:48312.service - OpenSSH per-connection server daemon (10.200.16.10:48312). Jul 1 08:42:56.297260 sshd[6232]: Accepted publickey for core from 10.200.16.10 port 48312 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:56.298491 sshd-session[6232]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:56.303550 systemd-logind[1895]: New session 16 of user core. Jul 1 08:42:56.309833 systemd[1]: Started session-16.scope - Session 16 of User core. Jul 1 08:42:56.747871 sshd[6353]: Connection closed by 10.200.16.10 port 48312 Jul 1 08:42:56.747766 sshd-session[6232]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:56.751488 systemd[1]: sshd@13-10.200.8.12:22-10.200.16.10:48312.service: Deactivated successfully. Jul 1 08:42:56.753081 systemd[1]: session-16.scope: Deactivated successfully. Jul 1 08:42:56.753939 systemd-logind[1895]: Session 16 logged out. Waiting for processes to exit. Jul 1 08:42:56.754983 systemd-logind[1895]: Removed session 16. Jul 1 08:42:56.860961 systemd[1]: Started sshd@14-10.200.8.12:22-10.200.16.10:48324.service - OpenSSH per-connection server daemon (10.200.16.10:48324). Jul 1 08:42:57.490231 sshd[6567]: Accepted publickey for core from 10.200.16.10 port 48324 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:42:57.491421 sshd-session[6567]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:42:57.495687 systemd-logind[1895]: New session 17 of user core. Jul 1 08:42:57.507933 systemd[1]: Started session-17.scope - Session 17 of User core. Jul 1 08:42:57.937718 sshd[6638]: Connection closed by 10.200.16.10 port 48324 Jul 1 08:42:57.937731 sshd-session[6567]: pam_unix(sshd:session): session closed for user core Jul 1 08:42:57.941272 systemd-logind[1895]: Session 17 logged out. Waiting for processes to exit. Jul 1 08:42:57.942245 systemd[1]: sshd@14-10.200.8.12:22-10.200.16.10:48324.service: Deactivated successfully. Jul 1 08:42:57.944651 systemd[1]: session-17.scope: Deactivated successfully. Jul 1 08:42:57.948038 systemd-logind[1895]: Removed session 17. Jul 1 08:43:08.054945 systemd[1]: Started sshd@15-10.200.8.12:22-10.200.16.10:44706.service - OpenSSH per-connection server daemon (10.200.16.10:44706). Jul 1 08:43:08.707161 sshd[9702]: Accepted publickey for core from 10.200.16.10 port 44706 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:08.708127 sshd-session[9702]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:08.716347 systemd-logind[1895]: New session 18 of user core. Jul 1 08:43:08.723418 systemd[1]: Started session-18.scope - Session 18 of User core. Jul 1 08:43:09.152551 sshd[9863]: Connection closed by 10.200.16.10 port 44706 Jul 1 08:43:09.152919 sshd-session[9702]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:09.158138 systemd-logind[1895]: Session 18 logged out. Waiting for processes to exit. Jul 1 08:43:09.158246 systemd[1]: sshd@15-10.200.8.12:22-10.200.16.10:44706.service: Deactivated successfully. Jul 1 08:43:09.160120 systemd[1]: session-18.scope: Deactivated successfully. Jul 1 08:43:09.163350 systemd-logind[1895]: Removed session 18. Jul 1 08:43:09.267405 systemd[1]: Started sshd@16-10.200.8.12:22-10.200.16.10:44718.service - OpenSSH per-connection server daemon (10.200.16.10:44718). Jul 1 08:43:09.922618 sshd[10157]: Accepted publickey for core from 10.200.16.10 port 44718 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:09.924247 sshd-session[10157]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:09.937875 systemd-logind[1895]: New session 19 of user core. Jul 1 08:43:09.944780 systemd[1]: Started session-19.scope - Session 19 of User core. Jul 1 08:43:10.377367 sshd[10353]: Connection closed by 10.200.16.10 port 44718 Jul 1 08:43:10.379529 sshd-session[10157]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:10.383056 systemd-logind[1895]: Session 19 logged out. Waiting for processes to exit. Jul 1 08:43:10.383833 systemd[1]: sshd@16-10.200.8.12:22-10.200.16.10:44718.service: Deactivated successfully. Jul 1 08:43:10.386624 systemd[1]: session-19.scope: Deactivated successfully. Jul 1 08:43:10.390007 systemd-logind[1895]: Removed session 19. Jul 1 08:43:20.498925 systemd[1]: Started sshd@17-10.200.8.12:22-10.200.16.10:44062.service - OpenSSH per-connection server daemon (10.200.16.10:44062). Jul 1 08:43:21.147291 sshd[11808]: Accepted publickey for core from 10.200.16.10 port 44062 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:21.149988 sshd-session[11808]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:21.157161 systemd-logind[1895]: New session 20 of user core. Jul 1 08:43:21.162304 systemd[1]: Started session-20.scope - Session 20 of User core. Jul 1 08:43:21.601878 sshd[11893]: Connection closed by 10.200.16.10 port 44062 Jul 1 08:43:21.603851 sshd-session[11808]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:21.607175 systemd-logind[1895]: Session 20 logged out. Waiting for processes to exit. Jul 1 08:43:21.608779 systemd[1]: sshd@17-10.200.8.12:22-10.200.16.10:44062.service: Deactivated successfully. Jul 1 08:43:21.610379 systemd[1]: session-20.scope: Deactivated successfully. Jul 1 08:43:21.614503 systemd-logind[1895]: Removed session 20. Jul 1 08:43:21.716482 systemd[1]: Started sshd@18-10.200.8.12:22-10.200.16.10:44070.service - OpenSSH per-connection server daemon (10.200.16.10:44070). Jul 1 08:43:22.376224 sshd[11922]: Accepted publickey for core from 10.200.16.10 port 44070 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:22.377175 sshd-session[11922]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:22.383427 systemd-logind[1895]: New session 21 of user core. Jul 1 08:43:22.392874 systemd[1]: Started session-21.scope - Session 21 of User core. Jul 1 08:43:22.822107 sshd[11943]: Connection closed by 10.200.16.10 port 44070 Jul 1 08:43:22.822779 sshd-session[11922]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:22.826339 systemd[1]: sshd@18-10.200.8.12:22-10.200.16.10:44070.service: Deactivated successfully. Jul 1 08:43:22.826842 systemd-logind[1895]: Session 21 logged out. Waiting for processes to exit. Jul 1 08:43:22.829279 systemd[1]: session-21.scope: Deactivated successfully. Jul 1 08:43:22.832089 systemd-logind[1895]: Removed session 21. Jul 1 08:43:32.936068 systemd[1]: Started sshd@19-10.200.8.12:22-10.200.16.10:52926.service - OpenSSH per-connection server daemon (10.200.16.10:52926). Jul 1 08:43:33.589720 sshd[12273]: Accepted publickey for core from 10.200.16.10 port 52926 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:33.590503 sshd-session[12273]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:33.598668 systemd-logind[1895]: New session 22 of user core. Jul 1 08:43:33.607878 systemd[1]: Started session-22.scope - Session 22 of User core. Jul 1 08:43:34.045540 sshd[12306]: Connection closed by 10.200.16.10 port 52926 Jul 1 08:43:34.047036 sshd-session[12273]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:34.050916 systemd-logind[1895]: Session 22 logged out. Waiting for processes to exit. Jul 1 08:43:34.053671 systemd[1]: sshd@19-10.200.8.12:22-10.200.16.10:52926.service: Deactivated successfully. Jul 1 08:43:34.062340 systemd[1]: session-22.scope: Deactivated successfully. Jul 1 08:43:34.068625 systemd-logind[1895]: Removed session 22. Jul 1 08:43:34.165772 systemd[1]: Started sshd@20-10.200.8.12:22-10.200.16.10:52942.service - OpenSSH per-connection server daemon (10.200.16.10:52942). Jul 1 08:43:34.811053 sshd[12324]: Accepted publickey for core from 10.200.16.10 port 52942 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:34.812367 sshd-session[12324]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:34.820445 systemd-logind[1895]: New session 23 of user core. Jul 1 08:43:34.829788 systemd[1]: Started session-23.scope - Session 23 of User core. Jul 1 08:43:35.263393 sshd[12357]: Connection closed by 10.200.16.10 port 52942 Jul 1 08:43:35.263677 sshd-session[12324]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:35.267022 systemd-logind[1895]: Session 23 logged out. Waiting for processes to exit. Jul 1 08:43:35.268452 systemd[1]: sshd@20-10.200.8.12:22-10.200.16.10:52942.service: Deactivated successfully. Jul 1 08:43:35.269998 systemd[1]: session-23.scope: Deactivated successfully. Jul 1 08:43:35.273605 systemd-logind[1895]: Removed session 23. Jul 1 08:43:45.379125 systemd[1]: Started sshd@21-10.200.8.12:22-10.200.16.10:55896.service - OpenSSH per-connection server daemon (10.200.16.10:55896). Jul 1 08:43:46.027720 sshd[12670]: Accepted publickey for core from 10.200.16.10 port 55896 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:46.027636 sshd-session[12670]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:46.034309 systemd-logind[1895]: New session 24 of user core. Jul 1 08:43:46.041334 systemd[1]: Started session-24.scope - Session 24 of User core. Jul 1 08:43:46.482809 sshd[12708]: Connection closed by 10.200.16.10 port 55896 Jul 1 08:43:46.482106 systemd-logind[1895]: Session 24 logged out. Waiting for processes to exit. Jul 1 08:43:46.478579 sshd-session[12670]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:46.483478 systemd[1]: sshd@21-10.200.8.12:22-10.200.16.10:55896.service: Deactivated successfully. Jul 1 08:43:46.485038 systemd[1]: session-24.scope: Deactivated successfully. Jul 1 08:43:46.487341 systemd-logind[1895]: Removed session 24. Jul 1 08:43:46.600219 systemd[1]: Started sshd@22-10.200.8.12:22-10.200.16.10:55902.service - OpenSSH per-connection server daemon (10.200.16.10:55902). Jul 1 08:43:47.242167 sshd[12740]: Accepted publickey for core from 10.200.16.10 port 55902 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:47.243850 sshd-session[12740]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:47.248920 systemd-logind[1895]: New session 25 of user core. Jul 1 08:43:47.258815 systemd[1]: Started session-25.scope - Session 25 of User core. Jul 1 08:43:47.692722 sshd[12778]: Connection closed by 10.200.16.10 port 55902 Jul 1 08:43:47.694911 sshd-session[12740]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:47.700255 systemd-logind[1895]: Session 25 logged out. Waiting for processes to exit. Jul 1 08:43:47.702754 systemd[1]: sshd@22-10.200.8.12:22-10.200.16.10:55902.service: Deactivated successfully. Jul 1 08:43:47.704400 systemd[1]: session-25.scope: Deactivated successfully. Jul 1 08:43:47.706657 systemd-logind[1895]: Removed session 25. Jul 1 08:43:56.915728 setup-nvidia[2574]: \u000d Jul 1 08:43:56.915728 setup-nvidia[2574]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most Jul 1 08:43:56.915728 setup-nvidia[2574]: frequently when this kernel module was built against the wrong or Jul 1 08:43:56.915728 setup-nvidia[2574]: improperly configured kernel sources, with a version of gcc that differs Jul 1 08:43:56.915728 setup-nvidia[2574]: from the one used to build the target kernel, or if another driver, such Jul 1 08:43:56.915728 setup-nvidia[2574]: as nouveau, is present and prevents the NVIDIA kernel module from Jul 1 08:43:56.915728 setup-nvidia[2574]: obtaining ownership of the NVIDIA device(s), or no NVIDIA device Jul 1 08:43:56.915728 setup-nvidia[2574]: installed in this system is supported by this NVIDIA Linux graphics Jul 1 08:43:56.915728 setup-nvidia[2574]: driver release. Jul 1 08:43:56.915728 setup-nvidia[2574]: \u000d Jul 1 08:43:56.915728 setup-nvidia[2574]: Please see the log entries 'Kernel module load error' and 'Kernel Jul 1 08:43:56.915728 setup-nvidia[2574]: messages' at the end of the file Jul 1 08:43:56.915728 setup-nvidia[2574]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more Jul 1 08:43:56.915728 setup-nvidia[2574]: information. Jul 1 08:43:56.915728 setup-nvidia[2574]: \u000d Jul 1 08:43:56.930975 setup-nvidia[2574]: dmesg: read kernel buffer failed: Operation not permitted Jul 1 08:43:56.949734 setup-nvidia[2574]: \u000d Jul 1 08:43:56.949734 setup-nvidia[2574]: ERROR: Installation has failed. Please see the file Jul 1 08:43:56.949734 setup-nvidia[2574]: '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for Jul 1 08:43:56.949734 setup-nvidia[2574]: details. You may find suggestions on fixing installation problems in Jul 1 08:43:56.949734 setup-nvidia[2574]: the README available on the Linux driver download page at Jul 1 08:43:56.949734 setup-nvidia[2574]: www.nvidia.com. Jul 1 08:43:56.949734 setup-nvidia[2574]: \u000d Jul 1 08:43:56.950344 setup-nvidia[2574]: Last 50 lines of nvidia-installer.log: Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039045rm+0x53: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039042rm+0x90: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039035rm+0x2b: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039091rm+0x93: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039087rm+0x36: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039086rm+0x9d: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039088rm+0x77: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039032rm+0x7f: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030603rm+0x3e: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030602rm+0x27: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030606rm+0x37: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030605rm+0x24: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030604rm+0xca: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030607rm+0x193: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv030609rm+0x17f: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv011864rm+0x5d: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.o: warning: objtool: _nv039399rm+0x12f: 'naked' return found in MITIGATION_RETHUNK build Jul 1 08:43:56.951159 setup-nvidia[2574]: MODPOST /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/Module.symvers Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.mod.o Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/.module-common.o Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.mod.o Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.mod.o Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.mod.o Jul 1 08:43:56.951159 setup-nvidia[2574]: CC [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.mod.o Jul 1 08:43:56.951159 setup-nvidia[2574]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: LD [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-peermem.ko due to unavailability of vmlinux Jul 1 08:43:56.951159 setup-nvidia[2574]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-modeset.ko due to unavailability of vmlinux Jul 1 08:43:56.951159 setup-nvidia[2574]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-drm.ko due to unavailability of vmlinux Jul 1 08:43:56.951159 setup-nvidia[2574]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia-uvm.ko due to unavailability of vmlinux Jul 1 08:43:56.951159 setup-nvidia[2574]: BTF [M] /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko Jul 1 08:43:56.951159 setup-nvidia[2574]: Skipping BTF generation for /nvidia/NVIDIA-Linux-x86_64-535.247.01/kernel/nvidia.ko due to unavailability of vmlinux Jul 1 08:43:56.951159 setup-nvidia[2574]: make[2]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/build' Jul 1 08:43:56.951159 setup-nvidia[2574]: make[1]: Leaving directory '/usr/lib/modules/6.12.34-flatcar/source' Jul 1 08:43:56.951159 setup-nvidia[2574]: -> done. Jul 1 08:43:56.951159 setup-nvidia[2574]: -> Kernel module compilation complete. Jul 1 08:43:56.951159 setup-nvidia[2574]: -> Unable to determine if Secure Boot is enabled: No such file or directory Jul 1 08:43:56.951159 setup-nvidia[2574]: ERROR: Unable to load the kernel module 'nvidia.ko'. This happens most frequently when this kernel module was built against the wrong or improperly configured kernel sources, with a version of gcc that differs from the one used to build the target kernel, or if another driver, such as nouveau, is present and prevents the NVIDIA kernel module from obtaining ownership of the NVIDIA device(s), or no NVIDIA device installed in this system is supported by this NVIDIA Linux graphics driver release. Jul 1 08:43:56.952332 setup-nvidia[2574]: \u000d Jul 1 08:43:56.952332 setup-nvidia[2574]: Please see the log entries 'Kernel module load error' and 'Kernel messages' at the end of the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for more information. Jul 1 08:43:56.952332 setup-nvidia[2574]: -> Kernel module load error: Operation not permitted Jul 1 08:43:56.952332 setup-nvidia[2574]: -> Kernel messages: Jul 1 08:43:56.952332 setup-nvidia[2574]: ERROR: Installation has failed. Please see the file '/nvidia/NVIDIA-Linux-x86_64-535.247.01/nvidia-installer.log' for details. You may find suggestions on fixing installation problems in the README available on the Linux driver download page at www.nvidia.com. Jul 1 08:43:57.815148 systemd[1]: Started sshd@23-10.200.8.12:22-10.200.16.10:41742.service - OpenSSH per-connection server daemon (10.200.16.10:41742). Jul 1 08:43:58.443422 sshd[13008]: Accepted publickey for core from 10.200.16.10 port 41742 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:58.444599 sshd-session[13008]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:58.448946 systemd-logind[1895]: New session 26 of user core. Jul 1 08:43:58.464827 systemd[1]: Started session-26.scope - Session 26 of User core. Jul 1 08:43:58.888526 sshd[13011]: Connection closed by 10.200.16.10 port 41742 Jul 1 08:43:58.888903 sshd-session[13008]: pam_unix(sshd:session): session closed for user core Jul 1 08:43:58.895916 systemd[1]: sshd@23-10.200.8.12:22-10.200.16.10:41742.service: Deactivated successfully. Jul 1 08:43:58.897480 systemd[1]: session-26.scope: Deactivated successfully. Jul 1 08:43:58.898138 systemd-logind[1895]: Session 26 logged out. Waiting for processes to exit. Jul 1 08:43:58.899053 systemd-logind[1895]: Removed session 26. Jul 1 08:43:59.014804 systemd[1]: Started sshd@24-10.200.8.12:22-10.200.16.10:41754.service - OpenSSH per-connection server daemon (10.200.16.10:41754). Jul 1 08:43:59.642407 sshd[13017]: Accepted publickey for core from 10.200.16.10 port 41754 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:43:59.643566 sshd-session[13017]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:43:59.647821 systemd-logind[1895]: New session 27 of user core. Jul 1 08:43:59.665816 systemd[1]: Started session-27.scope - Session 27 of User core. Jul 1 08:44:00.086784 sshd[13020]: Connection closed by 10.200.16.10 port 41754 Jul 1 08:44:00.087124 sshd-session[13017]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:00.091240 systemd[1]: sshd@24-10.200.8.12:22-10.200.16.10:41754.service: Deactivated successfully. Jul 1 08:44:00.092852 systemd[1]: session-27.scope: Deactivated successfully. Jul 1 08:44:00.093522 systemd-logind[1895]: Session 27 logged out. Waiting for processes to exit. Jul 1 08:44:00.094637 systemd-logind[1895]: Removed session 27. Jul 1 08:44:10.201853 systemd[1]: Started sshd@25-10.200.8.12:22-10.200.16.10:36908.service - OpenSSH per-connection server daemon (10.200.16.10:36908). Jul 1 08:44:10.606713 kernel: EXT4-fs (loop6p9): unmounting filesystem 00ecce5b-39d7-4436-8e6a-86c848c6447a. Jul 1 08:44:10.606970 setup-nvidia[2574]: Container flatcardevelopercontainer-9999.9.9sayan-publish-nighlies-sig.bin exited successfully. Jul 1 08:44:10.609077 systemd[1]: run-systemd-nspawn-unix\x2dexport-flatcardevelopercontainer\x2d9999.9.9sayan\x2dpublish\x2dnighlies\x2dsig.bin.mount: Deactivated successfully. Jul 1 08:44:10.658502 sudo[2562]: pam_unix(sudo:session): session closed for user root Jul 1 08:44:10.660415 systemd[1]: session-c2.scope: Deactivated successfully. Jul 1 08:44:10.660678 systemd[1]: session-c2.scope: Consumed 5min 14.162s CPU time, 888.7M memory peak. Jul 1 08:44:10.845046 sshd[13027]: Accepted publickey for core from 10.200.16.10 port 36908 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:10.846349 sshd-session[13027]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:10.850929 systemd-logind[1895]: New session 28 of user core. Jul 1 08:44:10.863915 systemd[1]: Started session-28.scope - Session 28 of User core. Jul 1 08:44:11.079197 setup-nvidia[1889]: /opt/nvidia / Jul 1 08:44:11.080202 setup-nvidia[1889]: / Jul 1 08:44:11.089721 kernel: IPMI message handler: version 39.2 Jul 1 08:44:11.095718 kernel: ipmi device interface Jul 1 08:44:11.110024 setup-nvidia[1889]: /opt/nvidia/current/usr/lib/modules/6.12.34-flatcar/video / Jul 1 08:44:11.162394 kernel: nvidia: loading out-of-tree module taints kernel. Jul 1 08:44:11.162528 kernel: nvidia: module license 'NVIDIA' taints kernel. Jul 1 08:44:11.162562 kernel: Disabling lock debugging due to kernel taint Jul 1 08:44:11.170164 kernel: nvidia: module verification failed: signature and/or required key missing - tainting kernel Jul 1 08:44:11.170217 kernel: nvidia: module license taints kernel. Jul 1 08:44:11.291045 sshd[13035]: Connection closed by 10.200.16.10 port 36908 Jul 1 08:44:11.291401 sshd-session[13027]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:11.294425 systemd[1]: sshd@25-10.200.8.12:22-10.200.16.10:36908.service: Deactivated successfully. Jul 1 08:44:11.296100 systemd[1]: session-28.scope: Deactivated successfully. Jul 1 08:44:11.297841 systemd-logind[1895]: Session 28 logged out. Waiting for processes to exit. Jul 1 08:44:11.298815 systemd-logind[1895]: Removed session 28. Jul 1 08:44:11.407938 systemd[1]: Started sshd@26-10.200.8.12:22-10.200.16.10:36920.service - OpenSSH per-connection server daemon (10.200.16.10:36920). Jul 1 08:44:11.559720 kernel: nvidia-nvlink: Nvlink Core is being initialized, major device number 243 Jul 1 08:44:11.958882 kernel: Jul 1 08:44:11.958983 kernel: nvidia 0001:00:00.0: enabling device (0000 -> 0002) Jul 1 08:44:12.062629 sshd[13061]: Accepted publickey for core from 10.200.16.10 port 36920 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:12.063854 sshd-session[13061]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:12.068376 systemd-logind[1895]: New session 29 of user core. Jul 1 08:44:12.082991 systemd[1]: Started session-29.scope - Session 29 of User core. Jul 1 08:44:12.168724 kernel: NVRM: loading NVIDIA UNIX x86_64 Kernel Module 535.247.01 Wed Mar 26 11:50:32 UTC 2025 Jul 1 08:44:12.190718 kernel: nvidia-modeset: Loading NVIDIA Kernel Mode Setting Driver for UNIX platforms 535.247.01 Wed Mar 26 06:12:31 UTC 2025 Jul 1 08:44:12.210728 kernel: nvidia_uvm: module uses symbols nvUvmInterfaceDisableAccessCntr from proprietary module nvidia, inheriting taint. Jul 1 08:44:12.259719 kernel: nvidia-uvm: Loaded the UVM driver, major device number 241. Jul 1 08:44:12.259843 setup-nvidia[1889]: / Jul 1 08:44:12.276134 setup-nvidia[13076]: ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Jul 1 08:44:12.507365 sshd[13068]: Connection closed by 10.200.16.10 port 36920 Jul 1 08:44:12.507612 sshd-session[13061]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:12.510716 systemd[1]: sshd@26-10.200.8.12:22-10.200.16.10:36920.service: Deactivated successfully. Jul 1 08:44:12.512271 systemd[1]: session-29.scope: Deactivated successfully. Jul 1 08:44:12.512965 systemd-logind[1895]: Session 29 logged out. Waiting for processes to exit. Jul 1 08:44:12.513856 systemd-logind[1895]: Removed session 29. Jul 1 08:44:14.009159 setup-nvidia[13093]: Tue Jul 1 08:44:14 2025 Jul 1 08:44:14.009159 setup-nvidia[13093]: +---------------------------------------------------------------------------------------+ Jul 1 08:44:14.009159 setup-nvidia[13093]: | NVIDIA-SMI 535.247.01 Driver Version: 535.247.01 CUDA Version: 12.2 | Jul 1 08:44:14.009159 setup-nvidia[13093]: |-----------------------------------------+----------------------+----------------------+ Jul 1 08:44:14.009159 setup-nvidia[13093]: | GPU Name Persistence-M | Bus-Id Disp.A | Volatile Uncorr. ECC | Jul 1 08:44:14.009159 setup-nvidia[13093]: | Fan Temp Perf Pwr:Usage/Cap | Memory-Usage | GPU-Util Compute M. | Jul 1 08:44:14.009159 setup-nvidia[13093]: | | | MIG M. | Jul 1 08:44:14.009159 setup-nvidia[13093]: |=========================================+======================+======================| Jul 1 08:44:14.012660 setup-nvidia[13093]: | 0 Tesla V100-PCIE-16GB Off | 00000001:00:00.0 Off | Off | Jul 1 08:44:14.012660 setup-nvidia[13093]: | N/A 26C P0 37W / 250W | 0MiB / 16384MiB | 0% Default | Jul 1 08:44:14.012660 setup-nvidia[13093]: | | | N/A | Jul 1 08:44:14.012660 setup-nvidia[13093]: +-----------------------------------------+----------------------+----------------------+ Jul 1 08:44:14.012660 setup-nvidia[13093]: Jul 1 08:44:14.012660 setup-nvidia[13093]: +---------------------------------------------------------------------------------------+ Jul 1 08:44:14.012660 setup-nvidia[13093]: | Processes: | Jul 1 08:44:14.012660 setup-nvidia[13093]: | GPU GI CI PID Type Process name GPU Memory | Jul 1 08:44:14.012660 setup-nvidia[13093]: | ID ID Usage | Jul 1 08:44:14.012660 setup-nvidia[13093]: |=======================================================================================| Jul 1 08:44:14.012973 setup-nvidia[13093]: | No running processes found | Jul 1 08:44:14.012973 setup-nvidia[13093]: +---------------------------------------------------------------------------------------+ Jul 1 08:44:14.386621 systemd[1]: Finished nvidia.service - NVIDIA Configure Service. Jul 1 08:44:14.388676 systemd[1]: Reached target multi-user.target - Multi-User System. Jul 1 08:44:14.389032 systemd[1]: Startup finished in 1.143s (firmware) + 28.572s (loader) + 3.869s (kernel) + 13.095s (initrd) + 3min 26.427s (userspace) = 4min 13.108s. Jul 1 08:44:20.809452 systemd[1]: Stopping user@0.service - User Manager for UID 0... Jul 1 08:44:20.810244 systemd[2565]: Activating special unit exit.target... Jul 1 08:44:20.810332 systemd[2565]: Stopped target default.target - Main User Target. Jul 1 08:44:20.810373 systemd[2565]: Stopped target basic.target - Basic System. Jul 1 08:44:20.810403 systemd[2565]: Stopped target paths.target - Paths. Jul 1 08:44:20.810429 systemd[2565]: Stopped target sockets.target - Sockets. Jul 1 08:44:20.810455 systemd[2565]: Stopped target timers.target - Timers. Jul 1 08:44:20.810811 systemd[2565]: Closed dbus.socket - D-Bus User Message Bus Socket. Jul 1 08:44:20.811024 systemd[2565]: Removed slice app.slice - User Application Slice. Jul 1 08:44:20.811057 systemd[2565]: Reached target shutdown.target - Shutdown. Jul 1 08:44:20.811204 systemd[2565]: Finished systemd-exit.service - Exit the Session. Jul 1 08:44:20.811241 systemd[2565]: Reached target exit.target - Exit the Session. Jul 1 08:44:20.823132 (sd-pam)[2566]: pam_unix(systemd-user:session): session closed for user root Jul 1 08:44:20.824359 systemd[1]: user@0.service: Deactivated successfully. Jul 1 08:44:20.824726 systemd[1]: Stopped user@0.service - User Manager for UID 0. Jul 1 08:44:20.826863 systemd[1]: Stopping user-runtime-dir@0.service - User Runtime Directory /run/user/0... Jul 1 08:44:20.851910 systemd[1]: run-user-0.mount: Deactivated successfully. Jul 1 08:44:20.852795 systemd[1]: user-runtime-dir@0.service: Deactivated successfully. Jul 1 08:44:20.852973 systemd[1]: Stopped user-runtime-dir@0.service - User Runtime Directory /run/user/0. Jul 1 08:44:20.854435 systemd[1]: Removed slice user-0.slice - User Slice of UID 0. Jul 1 08:44:20.854527 systemd[1]: user-0.slice: Consumed 5min 14.382s CPU time, 894.8M memory peak. Jul 1 08:44:20.855006 systemd-logind[1895]: Removed session c3. Jul 1 08:44:22.623133 systemd[1]: Started sshd@27-10.200.8.12:22-10.200.16.10:40670.service - OpenSSH per-connection server daemon (10.200.16.10:40670). Jul 1 08:44:23.250098 sshd[13108]: Accepted publickey for core from 10.200.16.10 port 40670 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:23.251285 sshd-session[13108]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:23.255777 systemd-logind[1895]: New session 30 of user core. Jul 1 08:44:23.266880 systemd[1]: Started session-30.scope - Session 30 of User core. Jul 1 08:44:23.695157 sshd[13112]: Connection closed by 10.200.16.10 port 40670 Jul 1 08:44:23.695516 sshd-session[13108]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:23.698986 systemd[1]: sshd@27-10.200.8.12:22-10.200.16.10:40670.service: Deactivated successfully. Jul 1 08:44:23.700612 systemd[1]: session-30.scope: Deactivated successfully. Jul 1 08:44:23.701292 systemd-logind[1895]: Session 30 logged out. Waiting for processes to exit. Jul 1 08:44:23.702328 systemd-logind[1895]: Removed session 30. Jul 1 08:44:23.822743 systemd[1]: Started sshd@28-10.200.8.12:22-10.200.16.10:40676.service - OpenSSH per-connection server daemon (10.200.16.10:40676). Jul 1 08:44:24.462497 sshd[13118]: Accepted publickey for core from 10.200.16.10 port 40676 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:24.463730 sshd-session[13118]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:24.468012 systemd-logind[1895]: New session 31 of user core. Jul 1 08:44:24.476815 systemd[1]: Started session-31.scope - Session 31 of User core. Jul 1 08:44:24.902521 sshd[13121]: Connection closed by 10.200.16.10 port 40676 Jul 1 08:44:24.902874 sshd-session[13118]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:24.906288 systemd[1]: sshd@28-10.200.8.12:22-10.200.16.10:40676.service: Deactivated successfully. Jul 1 08:44:24.907967 systemd[1]: session-31.scope: Deactivated successfully. Jul 1 08:44:24.908650 systemd-logind[1895]: Session 31 logged out. Waiting for processes to exit. Jul 1 08:44:24.909577 systemd-logind[1895]: Removed session 31. Jul 1 08:44:25.012946 systemd[1]: Started sshd@29-10.200.8.12:22-10.200.16.10:40688.service - OpenSSH per-connection server daemon (10.200.16.10:40688). Jul 1 08:44:25.638436 sshd[13127]: Accepted publickey for core from 10.200.16.10 port 40688 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:25.641205 sshd-session[13127]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:25.645329 systemd-logind[1895]: New session 32 of user core. Jul 1 08:44:25.655830 systemd[1]: Started session-32.scope - Session 32 of User core. Jul 1 08:44:26.085152 sshd[13130]: Connection closed by 10.200.16.10 port 40688 Jul 1 08:44:26.085492 sshd-session[13127]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:26.088807 systemd[1]: sshd@29-10.200.8.12:22-10.200.16.10:40688.service: Deactivated successfully. Jul 1 08:44:26.090421 systemd[1]: session-32.scope: Deactivated successfully. Jul 1 08:44:26.091114 systemd-logind[1895]: Session 32 logged out. Waiting for processes to exit. Jul 1 08:44:26.092045 systemd-logind[1895]: Removed session 32. Jul 1 08:44:26.216827 systemd[1]: Started sshd@30-10.200.8.12:22-10.200.16.10:40700.service - OpenSSH per-connection server daemon (10.200.16.10:40700). Jul 1 08:44:26.865201 sshd[13136]: Accepted publickey for core from 10.200.16.10 port 40700 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:26.866390 sshd-session[13136]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:26.870597 systemd-logind[1895]: New session 33 of user core. Jul 1 08:44:26.880815 systemd[1]: Started session-33.scope - Session 33 of User core. Jul 1 08:44:27.313944 sshd[13139]: Connection closed by 10.200.16.10 port 40700 Jul 1 08:44:27.314289 sshd-session[13136]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:27.317711 systemd[1]: sshd@30-10.200.8.12:22-10.200.16.10:40700.service: Deactivated successfully. Jul 1 08:44:27.319353 systemd[1]: session-33.scope: Deactivated successfully. Jul 1 08:44:27.320042 systemd-logind[1895]: Session 33 logged out. Waiting for processes to exit. Jul 1 08:44:27.320972 systemd-logind[1895]: Removed session 33. Jul 1 08:44:27.428810 systemd[1]: Started sshd@31-10.200.8.12:22-10.200.16.10:40708.service - OpenSSH per-connection server daemon (10.200.16.10:40708). Jul 1 08:44:28.078391 sshd[13145]: Accepted publickey for core from 10.200.16.10 port 40708 ssh2: RSA SHA256:EXYJJAPVcUOEZav5YPuTI5EYsznQ9IPMAA1CG+vzhQE Jul 1 08:44:28.079605 sshd-session[13145]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0) Jul 1 08:44:28.083983 systemd-logind[1895]: New session 34 of user core. Jul 1 08:44:28.099824 systemd[1]: Started session-34.scope - Session 34 of User core. Jul 1 08:44:29.988798 sshd[13148]: Connection closed by 10.200.16.10 port 40708 Jul 1 08:44:29.989168 sshd-session[13145]: pam_unix(sshd:session): session closed for user core Jul 1 08:44:29.992587 systemd[1]: sshd@31-10.200.8.12:22-10.200.16.10:40708.service: Deactivated successfully. Jul 1 08:44:29.994211 systemd[1]: session-34.scope: Deactivated successfully. Jul 1 08:44:29.994408 systemd[1]: session-34.scope: Consumed 1.402s CPU time, 14.2M memory peak. Jul 1 08:44:29.994902 systemd-logind[1895]: Session 34 logged out. Waiting for processes to exit. Jul 1 08:44:29.996055 systemd-logind[1895]: Removed session 34.