Oct 13 05:32:56.866407 kernel: Linux version 6.12.51-flatcar (build@pony-truck.infra.kinvolk.io) (x86_64-cros-linux-gnu-gcc (Gentoo Hardened 14.3.1_p20250801 p4) 14.3.1 20250801, GNU ld (Gentoo 2.45 p3) 2.45.0) #1 SMP PREEMPT_DYNAMIC Mon Oct 13 03:31:29 -00 2025
Oct 13 05:32:56.866446 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d
Oct 13 05:32:56.866458 kernel: BIOS-provided physical RAM map:
Oct 13 05:32:56.866466 kernel: BIOS-e820: [mem 0x0000000000000000-0x000000000009ffff] usable
Oct 13 05:32:56.866473 kernel: BIOS-e820: [mem 0x00000000000c0000-0x00000000000fffff] reserved
Oct 13 05:32:56.866481 kernel: BIOS-e820: [mem 0x0000000000100000-0x00000000044fdfff] usable
Oct 13 05:32:56.866491 kernel: BIOS-e820: [mem 0x00000000044fe000-0x00000000048fdfff] reserved
Oct 13 05:32:56.866499 kernel: BIOS-e820: [mem 0x00000000048fe000-0x000000003ff1efff] usable
Oct 13 05:32:56.866506 kernel: BIOS-e820: [mem 0x000000003ff1f000-0x000000003ffc8fff] reserved
Oct 13 05:32:56.866515 kernel: BIOS-e820: [mem 0x000000003ffc9000-0x000000003fffafff] ACPI data
Oct 13 05:32:56.866524 kernel: BIOS-e820: [mem 0x000000003fffb000-0x000000003fffefff] ACPI NVS
Oct 13 05:32:56.866532 kernel: BIOS-e820: [mem 0x000000003ffff000-0x000000003fffffff] usable
Oct 13 05:32:56.866542 kernel: BIOS-e820: [mem 0x0000000100000000-0x00000002bfffffff] usable
Oct 13 05:32:56.866550 kernel: printk: legacy bootconsole [earlyser0] enabled
Oct 13 05:32:56.866565 kernel: NX (Execute Disable) protection: active
Oct 13 05:32:56.866575 kernel: APIC: Static calls initialized
Oct 13 05:32:56.866585 kernel: efi: EFI v2.7 by Microsoft
Oct 13 05:32:56.866594 kernel: efi: ACPI=0x3fffa000 ACPI 2.0=0x3fffa014 SMBIOS=0x3ff88000 SMBIOS 3.0=0x3ff86000 MEMATTR=0x3e9db518 RNG=0x3ffd2018 
Oct 13 05:32:56.866604 kernel: random: crng init done
Oct 13 05:32:56.866612 kernel: secureboot: Secure boot disabled
Oct 13 05:32:56.866623 kernel: SMBIOS 3.1.0 present.
Oct 13 05:32:56.866632 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 01/28/2025
Oct 13 05:32:56.866640 kernel: DMI: Memory slots populated: 2/2
Oct 13 05:32:56.866649 kernel: Hypervisor detected: Microsoft Hyper-V
Oct 13 05:32:56.866658 kernel: Hyper-V: privilege flags low 0xae7f, high 0x3b8030, hints 0x9e4e24, misc 0xe0bed7b2
Oct 13 05:32:56.866667 kernel: Hyper-V: Nested features: 0x3e0101
Oct 13 05:32:56.866675 kernel: Hyper-V: LAPIC Timer Frequency: 0x30d40
Oct 13 05:32:56.866683 kernel: Hyper-V: Using hypercall for remote TLB flush
Oct 13 05:32:56.866692 kernel: clocksource: hyperv_clocksource_tsc_page: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
Oct 13 05:32:56.866702 kernel: clocksource: hyperv_clocksource_msr: mask: 0xffffffffffffffff max_cycles: 0x24e6a1710, max_idle_ns: 440795202120 ns
Oct 13 05:32:56.866712 kernel: tsc: Detected 2300.000 MHz processor
Oct 13 05:32:56.866726 kernel: e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
Oct 13 05:32:56.866736 kernel: e820: remove [mem 0x000a0000-0x000fffff] usable
Oct 13 05:32:56.866745 kernel: last_pfn = 0x2c0000 max_arch_pfn = 0x10000000000
Oct 13 05:32:56.866755 kernel: MTRR map: 4 entries (2 fixed + 2 variable; max 18), built from 8 variable MTRRs
Oct 13 05:32:56.866765 kernel: x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- WT  
Oct 13 05:32:56.866775 kernel: e820: update [mem 0x48000000-0xffffffff] usable ==> reserved
Oct 13 05:32:56.866786 kernel: last_pfn = 0x40000 max_arch_pfn = 0x10000000000
Oct 13 05:32:56.866800 kernel: Using GB pages for direct mapping
Oct 13 05:32:56.866810 kernel: ACPI: Early table checksum verification disabled
Oct 13 05:32:56.866828 kernel: ACPI: RSDP 0x000000003FFFA014 000024 (v02 VRTUAL)
Oct 13 05:32:56.866839 kernel: ACPI: XSDT 0x000000003FFF90E8 00005C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866849 kernel: ACPI: FACP 0x000000003FFF8000 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866865 kernel: ACPI: DSDT 0x000000003FFD6000 01E27A (v02 MSFTVM DSDT01   00000001 INTL 20230628)
Oct 13 05:32:56.866877 kernel: ACPI: FACS 0x000000003FFFE000 000040
Oct 13 05:32:56.866886 kernel: ACPI: OEM0 0x000000003FFF7000 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866897 kernel: ACPI: SPCR 0x000000003FFF6000 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866907 kernel: ACPI: WAET 0x000000003FFF5000 000028 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866917 kernel: ACPI: APIC 0x000000003FFD5000 000052 (v05 HVLITE HVLITETB 00000000 MSHV 00000000)
Oct 13 05:32:56.866933 kernel: ACPI: SRAT 0x000000003FFD4000 0000A0 (v03 HVLITE HVLITETB 00000000 MSHV 00000000)
Oct 13 05:32:56.866944 kernel: ACPI: BGRT 0x000000003FFD3000 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 13 05:32:56.866954 kernel: ACPI: Reserving FACP table memory at [mem 0x3fff8000-0x3fff8113]
Oct 13 05:32:56.866963 kernel: ACPI: Reserving DSDT table memory at [mem 0x3ffd6000-0x3fff4279]
Oct 13 05:32:56.866974 kernel: ACPI: Reserving FACS table memory at [mem 0x3fffe000-0x3fffe03f]
Oct 13 05:32:56.866983 kernel: ACPI: Reserving OEM0 table memory at [mem 0x3fff7000-0x3fff7063]
Oct 13 05:32:56.866994 kernel: ACPI: Reserving SPCR table memory at [mem 0x3fff6000-0x3fff604f]
Oct 13 05:32:56.867009 kernel: ACPI: Reserving WAET table memory at [mem 0x3fff5000-0x3fff5027]
Oct 13 05:32:56.867021 kernel: ACPI: Reserving APIC table memory at [mem 0x3ffd5000-0x3ffd5051]
Oct 13 05:32:56.867031 kernel: ACPI: Reserving SRAT table memory at [mem 0x3ffd4000-0x3ffd409f]
Oct 13 05:32:56.867041 kernel: ACPI: Reserving BGRT table memory at [mem 0x3ffd3000-0x3ffd3037]
Oct 13 05:32:56.867051 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x00000000-0x3fffffff]
Oct 13 05:32:56.867061 kernel: ACPI: SRAT: Node 0 PXM 0 [mem 0x100000000-0x2bfffffff]
Oct 13 05:32:56.867072 kernel: NUMA: Node 0 [mem 0x00001000-0x3fffffff] + [mem 0x100000000-0x2bfffffff] -> [mem 0x00001000-0x2bfffffff]
Oct 13 05:32:56.867086 kernel: NODE_DATA(0) allocated [mem 0x2bfff8dc0-0x2bfffffff]
Oct 13 05:32:56.867096 kernel: Zone ranges:
Oct 13 05:32:56.867107 kernel:   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
Oct 13 05:32:56.867117 kernel:   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
Oct 13 05:32:56.867127 kernel:   Normal   [mem 0x0000000100000000-0x00000002bfffffff]
Oct 13 05:32:56.867138 kernel:   Device   empty
Oct 13 05:32:56.867150 kernel: Movable zone start for each node
Oct 13 05:32:56.867160 kernel: Early memory node ranges
Oct 13 05:32:56.867173 kernel:   node   0: [mem 0x0000000000001000-0x000000000009ffff]
Oct 13 05:32:56.867183 kernel:   node   0: [mem 0x0000000000100000-0x00000000044fdfff]
Oct 13 05:32:56.867191 kernel:   node   0: [mem 0x00000000048fe000-0x000000003ff1efff]
Oct 13 05:32:56.867200 kernel:   node   0: [mem 0x000000003ffff000-0x000000003fffffff]
Oct 13 05:32:56.867207 kernel:   node   0: [mem 0x0000000100000000-0x00000002bfffffff]
Oct 13 05:32:56.867214 kernel: Initmem setup node 0 [mem 0x0000000000001000-0x00000002bfffffff]
Oct 13 05:32:56.867220 kernel: On node 0, zone DMA: 1 pages in unavailable ranges
Oct 13 05:32:56.867229 kernel: On node 0, zone DMA: 96 pages in unavailable ranges
Oct 13 05:32:56.867236 kernel: On node 0, zone DMA32: 1024 pages in unavailable ranges
Oct 13 05:32:56.867245 kernel: On node 0, zone DMA32: 224 pages in unavailable ranges
Oct 13 05:32:56.867252 kernel: ACPI: PM-Timer IO Port: 0x408
Oct 13 05:32:56.867259 kernel: IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
Oct 13 05:32:56.867266 kernel: ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
Oct 13 05:32:56.867274 kernel: ACPI: Using ACPI (MADT) for SMP configuration information
Oct 13 05:32:56.867282 kernel: ACPI: SPCR: console: uart,io,0x3f8,115200
Oct 13 05:32:56.867289 kernel: TSC deadline timer available
Oct 13 05:32:56.867296 kernel: CPU topo: Max. logical packages:   1
Oct 13 05:32:56.867303 kernel: CPU topo: Max. logical dies:       1
Oct 13 05:32:56.867310 kernel: CPU topo: Max. dies per package:   1
Oct 13 05:32:56.867318 kernel: CPU topo: Max. threads per core:   2
Oct 13 05:32:56.867325 kernel: CPU topo: Num. cores per package:     1
Oct 13 05:32:56.867334 kernel: CPU topo: Num. threads per package:   2
Oct 13 05:32:56.867341 kernel: CPU topo: Allowing 2 present CPUs plus 0 hotplug CPUs
Oct 13 05:32:56.867348 kernel: [mem 0x40000000-0xffffffff] available for PCI devices
Oct 13 05:32:56.867355 kernel: Booting paravirtualized kernel on Hyper-V
Oct 13 05:32:56.867363 kernel: clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns
Oct 13 05:32:56.867370 kernel: setup_percpu: NR_CPUS:512 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
Oct 13 05:32:56.869447 kernel: percpu: Embedded 60 pages/cpu s207832 r8192 d29736 u1048576
Oct 13 05:32:56.869464 kernel: pcpu-alloc: s207832 r8192 d29736 u1048576 alloc=1*2097152
Oct 13 05:32:56.869472 kernel: pcpu-alloc: [0] 0 1 
Oct 13 05:32:56.869479 kernel: Hyper-V: PV spinlocks enabled
Oct 13 05:32:56.869486 kernel: PV qspinlock hash table entries: 256 (order: 0, 4096 bytes, linear)
Oct 13 05:32:56.869494 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d
Oct 13 05:32:56.869502 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Oct 13 05:32:56.869508 kernel: Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear)
Oct 13 05:32:56.869517 kernel: Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Oct 13 05:32:56.869524 kernel: Fallback order for Node 0: 0 
Oct 13 05:32:56.869531 kernel: Built 1 zonelists, mobility grouping on.  Total pages: 2095807
Oct 13 05:32:56.869537 kernel: Policy zone: Normal
Oct 13 05:32:56.869544 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Oct 13 05:32:56.869550 kernel: software IO TLB: area num 2.
Oct 13 05:32:56.869558 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Oct 13 05:32:56.869567 kernel: ftrace: allocating 40210 entries in 158 pages
Oct 13 05:32:56.869574 kernel: ftrace: allocated 158 pages with 5 groups
Oct 13 05:32:56.869581 kernel: Dynamic Preempt: voluntary
Oct 13 05:32:56.869588 kernel: rcu: Preemptible hierarchical RCU implementation.
Oct 13 05:32:56.869599 kernel: rcu:         RCU event tracing is enabled.
Oct 13 05:32:56.869615 kernel: rcu:         RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Oct 13 05:32:56.869622 kernel:         Trampoline variant of Tasks RCU enabled.
Oct 13 05:32:56.869630 kernel:         Rude variant of Tasks RCU enabled.
Oct 13 05:32:56.869638 kernel:         Tracing variant of Tasks RCU enabled.
Oct 13 05:32:56.869647 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Oct 13 05:32:56.869655 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Oct 13 05:32:56.869663 kernel: RCU Tasks: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 13 05:32:56.869671 kernel: RCU Tasks Rude: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 13 05:32:56.869679 kernel: RCU Tasks Trace: Setting shift to 1 and lim to 1 rcu_task_cb_adjust=1 rcu_task_cpu_ids=2.
Oct 13 05:32:56.869687 kernel: Using NULL legacy PIC
Oct 13 05:32:56.869697 kernel: NR_IRQS: 33024, nr_irqs: 440, preallocated irqs: 0
Oct 13 05:32:56.869705 kernel: rcu: srcu_init: Setting srcu_struct sizes based on contention.
Oct 13 05:32:56.869713 kernel: Console: colour dummy device 80x25
Oct 13 05:32:56.869720 kernel: printk: legacy console [tty1] enabled
Oct 13 05:32:56.869728 kernel: printk: legacy console [ttyS0] enabled
Oct 13 05:32:56.869738 kernel: printk: legacy bootconsole [earlyser0] disabled
Oct 13 05:32:56.869746 kernel: ACPI: Core revision 20240827
Oct 13 05:32:56.869754 kernel: Failed to register legacy timer interrupt
Oct 13 05:32:56.869762 kernel: APIC: Switch to symmetric I/O mode setup
Oct 13 05:32:56.869770 kernel: x2apic enabled
Oct 13 05:32:56.869777 kernel: APIC: Switched APIC routing to: physical x2apic
Oct 13 05:32:56.869785 kernel: Hyper-V: Host Build 10.0.26100.1381-1-0
Oct 13 05:32:56.869794 kernel: Hyper-V: enabling crash_kexec_post_notifiers
Oct 13 05:32:56.869802 kernel: Hyper-V: Disabling IBT because of Hyper-V bug
Oct 13 05:32:56.869810 kernel: Hyper-V: Using IPI hypercalls
Oct 13 05:32:56.869818 kernel: APIC: send_IPI() replaced with hv_send_ipi()
Oct 13 05:32:56.869827 kernel: APIC: send_IPI_mask() replaced with hv_send_ipi_mask()
Oct 13 05:32:56.869835 kernel: APIC: send_IPI_mask_allbutself() replaced with hv_send_ipi_mask_allbutself()
Oct 13 05:32:56.869843 kernel: APIC: send_IPI_allbutself() replaced with hv_send_ipi_allbutself()
Oct 13 05:32:56.869852 kernel: APIC: send_IPI_all() replaced with hv_send_ipi_all()
Oct 13 05:32:56.869860 kernel: APIC: send_IPI_self() replaced with hv_send_ipi_self()
Oct 13 05:32:56.869868 kernel: clocksource: tsc-early: mask: 0xffffffffffffffff max_cycles: 0x212735223b2, max_idle_ns: 440795277976 ns
Oct 13 05:32:56.869875 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 4600.00 BogoMIPS (lpj=2300000)
Oct 13 05:32:56.869883 kernel: x86/cpu: User Mode Instruction Prevention (UMIP) activated
Oct 13 05:32:56.869891 kernel: Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Oct 13 05:32:56.869899 kernel: Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
Oct 13 05:32:56.869907 kernel: Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization
Oct 13 05:32:56.869916 kernel: Spectre V2 : Mitigation: Retpolines
Oct 13 05:32:56.869923 kernel: Spectre V2 : Spectre v2 / SpectreRSB: Filling RSB on context switch and VMEXIT
Oct 13 05:32:56.869931 kernel: RETBleed: WARNING: Spectre v2 mitigation leaves CPU vulnerable to RETBleed attacks, data leaks possible!
Oct 13 05:32:56.869939 kernel: RETBleed: Vulnerable
Oct 13 05:32:56.869946 kernel: Speculative Store Bypass: Vulnerable
Oct 13 05:32:56.869953 kernel: active return thunk: its_return_thunk
Oct 13 05:32:56.869960 kernel: ITS: Mitigation: Aligned branch/return thunks
Oct 13 05:32:56.869968 kernel: x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
Oct 13 05:32:56.869975 kernel: x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
Oct 13 05:32:56.869982 kernel: x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
Oct 13 05:32:56.869991 kernel: x86/fpu: Supporting XSAVE feature 0x020: 'AVX-512 opmask'
Oct 13 05:32:56.869999 kernel: x86/fpu: Supporting XSAVE feature 0x040: 'AVX-512 Hi256'
Oct 13 05:32:56.870007 kernel: x86/fpu: Supporting XSAVE feature 0x080: 'AVX-512 ZMM_Hi256'
Oct 13 05:32:56.870014 kernel: x86/fpu: Supporting XSAVE feature 0x800: 'Control-flow User registers'
Oct 13 05:32:56.870021 kernel: x86/fpu: Supporting XSAVE feature 0x20000: 'AMX Tile config'
Oct 13 05:32:56.870029 kernel: x86/fpu: Supporting XSAVE feature 0x40000: 'AMX Tile data'
Oct 13 05:32:56.870036 kernel: x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
Oct 13 05:32:56.870044 kernel: x86/fpu: xstate_offset[5]:  832, xstate_sizes[5]:   64
Oct 13 05:32:56.870051 kernel: x86/fpu: xstate_offset[6]:  896, xstate_sizes[6]:  512
Oct 13 05:32:56.870059 kernel: x86/fpu: xstate_offset[7]: 1408, xstate_sizes[7]: 1024
Oct 13 05:32:56.870067 kernel: x86/fpu: xstate_offset[11]: 2432, xstate_sizes[11]:   16
Oct 13 05:32:56.870074 kernel: x86/fpu: xstate_offset[17]: 2496, xstate_sizes[17]:   64
Oct 13 05:32:56.870082 kernel: x86/fpu: xstate_offset[18]: 2560, xstate_sizes[18]: 8192
Oct 13 05:32:56.870089 kernel: x86/fpu: Enabled xstate features 0x608e7, context size is 10752 bytes, using 'compacted' format.
Oct 13 05:32:56.870097 kernel: Freeing SMP alternatives memory: 32K
Oct 13 05:32:56.870104 kernel: pid_max: default: 32768 minimum: 301
Oct 13 05:32:56.870112 kernel: LSM: initializing lsm=lockdown,capability,landlock,selinux,ima
Oct 13 05:32:56.870119 kernel: landlock: Up and running.
Oct 13 05:32:56.870127 kernel: SELinux:  Initializing.
Oct 13 05:32:56.870134 kernel: Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Oct 13 05:32:56.870141 kernel: Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear)
Oct 13 05:32:56.870150 kernel: smpboot: CPU0: Intel INTEL(R) XEON(R) PLATINUM 8573C (family: 0x6, model: 0xcf, stepping: 0x2)
Oct 13 05:32:56.870158 kernel: Performance Events: unsupported p6 CPU model 207 no PMU driver, software events only.
Oct 13 05:32:56.870166 kernel: signal: max sigframe size: 11952
Oct 13 05:32:56.870174 kernel: rcu: Hierarchical SRCU implementation.
Oct 13 05:32:56.870182 kernel: rcu:         Max phase no-delay instances is 400.
Oct 13 05:32:56.870190 kernel: Timer migration: 1 hierarchy levels; 8 children per group; 1 crossnode level
Oct 13 05:32:56.870198 kernel: NMI watchdog: Perf NMI watchdog permanently disabled
Oct 13 05:32:56.870205 kernel: smp: Bringing up secondary CPUs ...
Oct 13 05:32:56.870214 kernel: smpboot: x86: Booting SMP configuration:
Oct 13 05:32:56.870222 kernel: .... node  #0, CPUs:      #1
Oct 13 05:32:56.870230 kernel: smp: Brought up 1 node, 2 CPUs
Oct 13 05:32:56.870238 kernel: smpboot: Total of 2 processors activated (9200.00 BogoMIPS)
Oct 13 05:32:56.870247 kernel: Memory: 8108748K/8383228K available (14336K kernel code, 2450K rwdata, 10012K rodata, 24532K init, 1684K bss, 269268K reserved, 0K cma-reserved)
Oct 13 05:32:56.870255 kernel: devtmpfs: initialized
Oct 13 05:32:56.870262 kernel: x86/mm: Memory block size: 128MB
Oct 13 05:32:56.870272 kernel: ACPI: PM: Registering ACPI NVS region [mem 0x3fffb000-0x3fffefff] (16384 bytes)
Oct 13 05:32:56.870280 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Oct 13 05:32:56.870288 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Oct 13 05:32:56.870295 kernel: pinctrl core: initialized pinctrl subsystem
Oct 13 05:32:56.870303 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Oct 13 05:32:56.870312 kernel: audit: initializing netlink subsys (disabled)
Oct 13 05:32:56.870320 kernel: audit: type=2000 audit(1760333571.063:1): state=initialized audit_enabled=0 res=1
Oct 13 05:32:56.870329 kernel: thermal_sys: Registered thermal governor 'step_wise'
Oct 13 05:32:56.870337 kernel: thermal_sys: Registered thermal governor 'user_space'
Oct 13 05:32:56.870344 kernel: cpuidle: using governor menu
Oct 13 05:32:56.870351 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Oct 13 05:32:56.870359 kernel: dca service started, version 1.12.1
Oct 13 05:32:56.870367 kernel: e820: reserve RAM buffer [mem 0x044fe000-0x07ffffff]
Oct 13 05:32:56.870387 kernel: e820: reserve RAM buffer [mem 0x3ff1f000-0x3fffffff]
Oct 13 05:32:56.870397 kernel: kprobes: kprobe jump-optimization is enabled. All kprobes are optimized if possible.
Oct 13 05:32:56.870405 kernel: HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages
Oct 13 05:32:56.870413 kernel: HugeTLB: 16380 KiB vmemmap can be freed for a 1.00 GiB page
Oct 13 05:32:56.870421 kernel: HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages
Oct 13 05:32:56.870429 kernel: HugeTLB: 28 KiB vmemmap can be freed for a 2.00 MiB page
Oct 13 05:32:56.870437 kernel: ACPI: Added _OSI(Module Device)
Oct 13 05:32:56.870444 kernel: ACPI: Added _OSI(Processor Device)
Oct 13 05:32:56.870454 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Oct 13 05:32:56.870462 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Oct 13 05:32:56.870470 kernel: ACPI: Interpreter enabled
Oct 13 05:32:56.870477 kernel: ACPI: PM: (supports S0 S5)
Oct 13 05:32:56.870485 kernel: ACPI: Using IOAPIC for interrupt routing
Oct 13 05:32:56.870493 kernel: PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
Oct 13 05:32:56.870501 kernel: PCI: Ignoring E820 reservations for host bridge windows
Oct 13 05:32:56.870510 kernel: ACPI: Enabled 1 GPEs in block 00 to 0F
Oct 13 05:32:56.870518 kernel: iommu: Default domain type: Translated
Oct 13 05:32:56.870525 kernel: iommu: DMA domain TLB invalidation policy: lazy mode
Oct 13 05:32:56.870533 kernel: efivars: Registered efivars operations
Oct 13 05:32:56.870541 kernel: PCI: Using ACPI for IRQ routing
Oct 13 05:32:56.870549 kernel: PCI: System does not support PCI
Oct 13 05:32:56.870557 kernel: vgaarb: loaded
Oct 13 05:32:56.870566 kernel: clocksource: Switched to clocksource tsc-early
Oct 13 05:32:56.870573 kernel: VFS: Disk quotas dquot_6.6.0
Oct 13 05:32:56.870581 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Oct 13 05:32:56.870589 kernel: pnp: PnP ACPI init
Oct 13 05:32:56.870597 kernel: pnp: PnP ACPI: found 3 devices
Oct 13 05:32:56.870605 kernel: clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
Oct 13 05:32:56.870613 kernel: NET: Registered PF_INET protocol family
Oct 13 05:32:56.870622 kernel: IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear)
Oct 13 05:32:56.870630 kernel: tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear)
Oct 13 05:32:56.870638 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Oct 13 05:32:56.870646 kernel: TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear)
Oct 13 05:32:56.870654 kernel: TCP bind hash table entries: 65536 (order: 9, 2097152 bytes, linear)
Oct 13 05:32:56.870661 kernel: TCP: Hash tables configured (established 65536 bind 65536)
Oct 13 05:32:56.870669 kernel: UDP hash table entries: 4096 (order: 5, 131072 bytes, linear)
Oct 13 05:32:56.870679 kernel: UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear)
Oct 13 05:32:56.870687 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Oct 13 05:32:56.870695 kernel: NET: Registered PF_XDP protocol family
Oct 13 05:32:56.870703 kernel: PCI: CLS 0 bytes, default 64
Oct 13 05:32:56.870710 kernel: PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
Oct 13 05:32:56.870718 kernel: software IO TLB: mapped [mem 0x000000003a9db000-0x000000003e9db000] (64MB)
Oct 13 05:32:56.870726 kernel: RAPL PMU: API unit is 2^-32 Joules, 1 fixed counters, 10737418240 ms ovfl timer
Oct 13 05:32:56.870735 kernel: RAPL PMU: hw unit of domain psys 2^-0 Joules
Oct 13 05:32:56.870743 kernel: clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x212735223b2, max_idle_ns: 440795277976 ns
Oct 13 05:32:56.870751 kernel: clocksource: Switched to clocksource tsc
Oct 13 05:32:56.870759 kernel: Initialise system trusted keyrings
Oct 13 05:32:56.870767 kernel: workingset: timestamp_bits=39 max_order=21 bucket_order=0
Oct 13 05:32:56.870775 kernel: Key type asymmetric registered
Oct 13 05:32:56.870783 kernel: Asymmetric key parser 'x509' registered
Oct 13 05:32:56.870792 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 250)
Oct 13 05:32:56.870800 kernel: io scheduler mq-deadline registered
Oct 13 05:32:56.870808 kernel: io scheduler kyber registered
Oct 13 05:32:56.870816 kernel: io scheduler bfq registered
Oct 13 05:32:56.870824 kernel: ioatdma: Intel(R) QuickData Technology Driver 5.00
Oct 13 05:32:56.870832 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Oct 13 05:32:56.870840 kernel: 00:00: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
Oct 13 05:32:56.870848 kernel: 00:01: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A
Oct 13 05:32:56.870858 kernel: serial8250: ttyS2 at I/O 0x3e8 (irq = 4, base_baud = 115200) is a 16550A
Oct 13 05:32:56.870865 kernel: i8042: PNP: No PS/2 controller found.
Oct 13 05:32:56.871008 kernel: rtc_cmos 00:02: registered as rtc0
Oct 13 05:32:56.871097 kernel: rtc_cmos 00:02: setting system clock to 2025-10-13T05:32:52 UTC (1760333572)
Oct 13 05:32:56.871182 kernel: rtc_cmos 00:02: alarms up to one month, 114 bytes nvram
Oct 13 05:32:56.871193 kernel: intel_pstate: Intel P-state driver initializing
Oct 13 05:32:56.871202 kernel: efifb: probing for efifb
Oct 13 05:32:56.871210 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k
Oct 13 05:32:56.871218 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1
Oct 13 05:32:56.871226 kernel: efifb: scrolling: redraw
Oct 13 05:32:56.871234 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Oct 13 05:32:56.871241 kernel: Console: switching to colour frame buffer device 128x48
Oct 13 05:32:56.871249 kernel: fb0: EFI VGA frame buffer device
Oct 13 05:32:56.871258 kernel: pstore: Using crash dump compression: deflate
Oct 13 05:32:56.871266 kernel: pstore: Registered efi_pstore as persistent store backend
Oct 13 05:32:56.871274 kernel: NET: Registered PF_INET6 protocol family
Oct 13 05:32:56.871282 kernel: Segment Routing with IPv6
Oct 13 05:32:56.871289 kernel: In-situ OAM (IOAM) with IPv6
Oct 13 05:32:56.871297 kernel: NET: Registered PF_PACKET protocol family
Oct 13 05:32:56.871305 kernel: Key type dns_resolver registered
Oct 13 05:32:56.871314 kernel: IPI shorthand broadcast: enabled
Oct 13 05:32:56.871322 kernel: sched_clock: Marking stable (1447050434, 85962744)->(1799249953, -266236775)
Oct 13 05:32:56.871330 kernel: registered taskstats version 1
Oct 13 05:32:56.871338 kernel: Loading compiled-in X.509 certificates
Oct 13 05:32:56.871346 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 6.12.51-flatcar: 9f1258ccc510afd4f2a37f4774c4b2e958d823b7'
Oct 13 05:32:56.871354 kernel: Demotion targets for Node 0: null
Oct 13 05:32:56.871361 kernel: Key type .fscrypt registered
Oct 13 05:32:56.871370 kernel: Key type fscrypt-provisioning registered
Oct 13 05:32:56.871506 kernel: ima: No TPM chip found, activating TPM-bypass!
Oct 13 05:32:56.871515 kernel: ima: Allocated hash algorithm: sha1
Oct 13 05:32:56.871523 kernel: ima: No architecture policies found
Oct 13 05:32:56.871531 kernel: clk: Disabling unused clocks
Oct 13 05:32:56.871538 kernel: Freeing unused kernel image (initmem) memory: 24532K
Oct 13 05:32:56.871546 kernel: Write protecting the kernel read-only data: 24576k
Oct 13 05:32:56.871556 kernel: Freeing unused kernel image (rodata/data gap) memory: 228K
Oct 13 05:32:56.871564 kernel: Run /init as init process
Oct 13 05:32:56.871572 kernel:   with arguments:
Oct 13 05:32:56.871579 kernel:     /init
Oct 13 05:32:56.871587 kernel:   with environment:
Oct 13 05:32:56.871594 kernel:     HOME=/
Oct 13 05:32:56.871602 kernel:     TERM=linux
Oct 13 05:32:56.871611 kernel:     BOOT_IMAGE=/flatcar/vmlinuz-a
Oct 13 05:32:56.871619 kernel: hv_vmbus: Vmbus version:5.3
Oct 13 05:32:56.871627 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.871635 kernel: pps_core: LinuxPPS API ver. 1 registered
Oct 13 05:32:56.871643 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Oct 13 05:32:56.871652 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.871660 kernel: PTP clock support registered
Oct 13 05:32:56.871667 kernel: hv_utils: Registering HyperV Utility Driver
Oct 13 05:32:56.871676 kernel: hv_vmbus: registering driver hv_utils
Oct 13 05:32:56.871684 kernel: hv_utils: Shutdown IC version 3.2
Oct 13 05:32:56.871692 kernel: hv_utils: Heartbeat IC version 3.0
Oct 13 05:32:56.871699 kernel: hv_utils: TimeSync IC version 4.0
Oct 13 05:32:56.871707 kernel: hv_vmbus: registering driver hv_pci
Oct 13 05:32:56.871835 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI VMBus probing: Using version 0x10004
Oct 13 05:32:56.871937 kernel: hv_pci 7ad35d50-c05b-47ab-b3a0-56a9a845852b: PCI host bridge to bus c05b:00
Oct 13 05:32:56.872047 kernel: pci_bus c05b:00: root bus resource [mem 0xfc0000000-0xfc007ffff window]
Oct 13 05:32:56.872141 kernel: pci_bus c05b:00: No busn resource found for root bus, will use [bus 00-ff]
Oct 13 05:32:56.872281 kernel: pci c05b:00:00.0: [1414:00a9] type 00 class 0x010802 PCIe Endpoint
Oct 13 05:32:56.872455 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit]
Oct 13 05:32:56.872469 kernel: SCSI subsystem initialized
Oct 13 05:32:56.872478 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.872575 kernel: pci_bus c05b:00: busn_res: [bus 00-ff] end is updated to 00
Oct 13 05:32:56.872679 kernel: pci c05b:00:00.0: BAR 0 [mem 0xfc0000000-0xfc007ffff 64bit]: assigned
Oct 13 05:32:56.872689 kernel: hv_vmbus: registering driver hv_storvsc
Oct 13 05:32:56.872795 kernel: scsi host0: storvsc_host_t
Oct 13 05:32:56.872912 kernel: scsi 0:0:0:2: CD-ROM            Msft     Virtual DVD-ROM  1.0  PQ: 0 ANSI: 5
Oct 13 05:32:56.872923 kernel: hid: raw HID events driver (C) Jiri Kosina
Oct 13 05:32:56.872931 kernel: hv_vmbus: registering driver hid_hyperv
Oct 13 05:32:56.872939 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0
Oct 13 05:32:56.873040 kernel: hid-hyperv 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on 
Oct 13 05:32:56.873051 kernel: hv_vmbus: registering driver hyperv_keyboard
Oct 13 05:32:56.873061 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1
Oct 13 05:32:56.873150 kernel: nvme nvme0: pci function c05b:00:00.0
Oct 13 05:32:56.873256 kernel: nvme c05b:00:00.0: enabling device (0000 -> 0002)
Oct 13 05:32:56.873332 kernel: nvme nvme0: 2/0/0 default/read/poll queues
Oct 13 05:32:56.873342 kernel:  nvme0n1: p1 p2 p3 p4 p6 p7 p9
Oct 13 05:32:56.873453 kernel: sr 0:0:0:2: [sr0] scsi-1 drive
Oct 13 05:32:56.873466 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Oct 13 05:32:56.873565 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0
Oct 13 05:32:56.873590 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Oct 13 05:32:56.873598 kernel: device-mapper: uevent: version 1.0.3
Oct 13 05:32:56.873606 kernel: device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@lists.linux.dev
Oct 13 05:32:56.873615 kernel: device-mapper: verity: sha256 using shash "sha256-generic"
Oct 13 05:32:56.873625 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873633 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873641 kernel: raid6: avx512x4 gen() 41771 MB/s
Oct 13 05:32:56.873648 kernel: raid6: avx512x2 gen() 41006 MB/s
Oct 13 05:32:56.873656 kernel: raid6: avx512x1 gen() 30096 MB/s
Oct 13 05:32:56.873664 kernel: raid6: avx2x4   gen() 39868 MB/s
Oct 13 05:32:56.873672 kernel: raid6: avx2x2   gen() 40130 MB/s
Oct 13 05:32:56.873680 kernel: raid6: avx2x1   gen() 27326 MB/s
Oct 13 05:32:56.873691 kernel: raid6: using algorithm avx512x4 gen() 41771 MB/s
Oct 13 05:32:56.873699 kernel: raid6: .... xor() 7726 MB/s, rmw enabled
Oct 13 05:32:56.873707 kernel: raid6: using avx512x2 recovery algorithm
Oct 13 05:32:56.873716 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873724 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873732 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873744 kernel: xor: automatically using best checksumming function   avx       
Oct 13 05:32:56.873763 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873775 kernel: Btrfs loaded, zoned=no, fsverity=no
Oct 13 05:32:56.873785 kernel: BTRFS: device fsid e87b15e9-127c-40e2-bae7-d0ea05b4f2e3 devid 1 transid 36 /dev/mapper/usr (254:0) scanned by mount (933)
Oct 13 05:32:56.873796 kernel: BTRFS info (device dm-0): first mount of filesystem e87b15e9-127c-40e2-bae7-d0ea05b4f2e3
Oct 13 05:32:56.873806 kernel: BTRFS info (device dm-0): using crc32c (crc32c-intel) checksum algorithm
Oct 13 05:32:56.873815 kernel: BTRFS info (device dm-0): enabling ssd optimizations
Oct 13 05:32:56.873825 kernel: BTRFS info (device dm-0): disabling log replay at mount time
Oct 13 05:32:56.873835 kernel: BTRFS info (device dm-0): enabling free space tree
Oct 13 05:32:56.873848 kernel: Invalid ELF header magic: != \u007fELF
Oct 13 05:32:56.873857 kernel: loop: module loaded
Oct 13 05:32:56.873866 kernel: loop0: detected capacity change from 0 to 100048
Oct 13 05:32:56.873877 kernel: squashfs: version 4.0 (2009/01/31) Phillip Lougher
Oct 13 05:32:56.873890 systemd[1]: Successfully made /usr/ read-only.
Oct 13 05:32:56.873904 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
Oct 13 05:32:56.873923 systemd[1]: Detected virtualization microsoft.
Oct 13 05:32:56.873933 systemd[1]: Detected architecture x86-64.
Oct 13 05:32:56.873942 systemd[1]: Running in initrd.
Oct 13 05:32:56.873952 systemd[1]: No hostname configured, using default hostname.
Oct 13 05:32:56.873962 systemd[1]: Hostname set to <localhost>.
Oct 13 05:32:56.873972 systemd[1]: Initializing machine ID from random generator.
Oct 13 05:32:56.873986 systemd[1]: Queued start job for default target initrd.target.
Oct 13 05:32:56.873996 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr.
Oct 13 05:32:56.874007 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 13 05:32:56.874016 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 13 05:32:56.874027 systemd[1]: Expecting device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - /dev/disk/by-label/EFI-SYSTEM...
Oct 13 05:32:56.874037 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Oct 13 05:32:56.874057 systemd[1]: Expecting device dev-disk-by\x2dlabel-ROOT.device - /dev/disk/by-label/ROOT...
Oct 13 05:32:56.874068 systemd[1]: Expecting device dev-disk-by\x2dpartlabel-USR\x2dA.device - /dev/disk/by-partlabel/USR-A...
Oct 13 05:32:56.874078 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 13 05:32:56.874092 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Oct 13 05:32:56.874110 systemd[1]: Reached target initrd-usr-fs.target - Initrd /usr File System.
Oct 13 05:32:56.874121 systemd[1]: Reached target paths.target - Path Units.
Oct 13 05:32:56.874132 systemd[1]: Reached target slices.target - Slice Units.
Oct 13 05:32:56.874141 systemd[1]: Reached target swap.target - Swaps.
Oct 13 05:32:56.874151 systemd[1]: Reached target timers.target - Timer Units.
Oct 13 05:32:56.874160 systemd[1]: Listening on iscsid.socket - Open-iSCSI iscsid Socket.
Oct 13 05:32:56.874170 systemd[1]: Listening on iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Oct 13 05:32:56.874190 systemd[1]: Listening on systemd-journald-dev-log.socket - Journal Socket (/dev/log).
Oct 13 05:32:56.874201 systemd[1]: Listening on systemd-journald.socket - Journal Sockets.
Oct 13 05:32:56.874211 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Oct 13 05:32:56.874222 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Oct 13 05:32:56.874233 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 13 05:32:56.874246 systemd[1]: Reached target sockets.target - Socket Units.
Oct 13 05:32:56.874257 systemd[1]: afterburn-network-kargs.service - Afterburn Initrd Setup Network Kernel Arguments was skipped because no trigger condition checks were met.
Oct 13 05:32:56.874272 systemd[1]: Starting ignition-setup-pre.service - Ignition env setup...
Oct 13 05:32:56.874284 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Oct 13 05:32:56.874295 systemd[1]: Finished network-cleanup.service - Network Cleanup.
Oct 13 05:32:56.874305 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).
Oct 13 05:32:56.874319 systemd[1]: Starting systemd-fsck-usr.service...
Oct 13 05:32:56.874331 systemd[1]: Starting systemd-journald.service - Journal Service...
Oct 13 05:32:56.874343 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Oct 13 05:32:56.874351 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:32:56.874359 systemd[1]: Finished ignition-setup-pre.service - Ignition env setup.
Oct 13 05:32:56.874367 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 13 05:32:56.874391 systemd[1]: Finished systemd-fsck-usr.service.
Oct 13 05:32:56.874400 systemd[1]: Starting systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully...
Oct 13 05:32:56.874409 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Oct 13 05:32:56.874432 systemd-journald[1068]: Collecting audit messages is disabled.
Oct 13 05:32:56.874453 systemd[1]: Finished systemd-tmpfiles-setup-dev-early.service - Create Static Device Nodes in /dev gracefully.
Oct 13 05:32:56.874463 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Oct 13 05:32:56.874472 kernel: Bridge firewalling registered
Oct 13 05:32:56.874480 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Oct 13 05:32:56.874489 systemd-journald[1068]: Journal started
Oct 13 05:32:56.874510 systemd-journald[1068]: Runtime Journal (/run/log/journal/d1fec3e9de0e448a9f848d72762f0b70) is 8M, max 158.9M, 150.9M free.
Oct 13 05:32:56.867439 systemd-modules-load[1071]: Inserted module 'br_netfilter'
Oct 13 05:32:56.882470 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Oct 13 05:32:56.885390 systemd[1]: Started systemd-journald.service - Journal Service.
Oct 13 05:32:56.887701 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 13 05:32:56.892502 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Oct 13 05:32:56.943567 systemd-tmpfiles[1090]: /usr/lib/tmpfiles.d/var.conf:14: Duplicate line for path "/var/log", ignoring.
Oct 13 05:32:57.012312 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 13 05:32:57.028766 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Oct 13 05:32:57.031478 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Oct 13 05:32:57.040563 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:32:57.046180 systemd[1]: Starting dracut-cmdline-ask.service - dracut ask for additional cmdline parameters...
Oct 13 05:32:57.065486 systemd[1]: Finished dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Oct 13 05:32:57.071145 systemd[1]: Starting dracut-cmdline.service - dracut cmdline hook...
Oct 13 05:32:57.082421 systemd-resolved[1096]: Positive Trust Anchors:
Oct 13 05:32:57.082432 systemd-resolved[1096]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 13 05:32:57.082434 systemd-resolved[1096]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
Oct 13 05:32:57.082463 systemd-resolved[1096]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Oct 13 05:32:57.121030 dracut-cmdline[1110]: 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=4919840803704517a91afcb9d57d99e9935244ff049349c54216d9a31bc1da5d
Oct 13 05:32:57.100008 systemd-resolved[1096]: Defaulting to hostname 'linux'.
Oct 13 05:32:57.101511 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Oct 13 05:32:57.106340 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Oct 13 05:32:57.299394 kernel: Loading iSCSI transport class v2.0-870.
Oct 13 05:32:57.458397 kernel: iscsi: registered transport (tcp)
Oct 13 05:32:57.570600 kernel: iscsi: registered transport (qla4xxx)
Oct 13 05:32:57.570646 kernel: QLogic iSCSI HBA Driver
Oct 13 05:32:57.629186 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Oct 13 05:32:57.637813 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Oct 13 05:32:57.638609 systemd[1]: Reached target network-pre.target - Preparation for Network.
Oct 13 05:32:57.670124 systemd[1]: Finished dracut-cmdline.service - dracut cmdline hook.
Oct 13 05:32:57.675254 systemd[1]: Starting dracut-pre-udev.service - dracut pre-udev hook...
Oct 13 05:32:57.678339 systemd[1]: Starting parse-ip-for-networkd.service - Write systemd-networkd units from cmdline...
Oct 13 05:32:57.706848 systemd[1]: Finished dracut-pre-udev.service - dracut pre-udev hook.
Oct 13 05:32:57.715186 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 13 05:32:57.739523 systemd-udevd[1364]: Using default interface naming scheme 'v257'.
Oct 13 05:32:57.751249 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 13 05:32:57.760979 systemd[1]: Starting dracut-pre-trigger.service - dracut pre-trigger hook...
Oct 13 05:32:57.766097 systemd[1]: Finished parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Oct 13 05:32:57.779310 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Oct 13 05:32:57.789831 dracut-pre-trigger[1443]: rd.md=0: removing MD RAID activation
Oct 13 05:32:57.813882 systemd[1]: Finished dracut-pre-trigger.service - dracut pre-trigger hook.
Oct 13 05:32:57.818154 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Oct 13 05:32:57.829197 systemd-networkd[1448]: lo: Link UP
Oct 13 05:32:57.829211 systemd-networkd[1448]: lo: Gained carrier
Oct 13 05:32:57.829583 systemd[1]: Started systemd-networkd.service - Network Configuration.
Oct 13 05:32:57.830558 systemd[1]: Reached target network.target - Network.
Oct 13 05:32:57.860862 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 13 05:32:57.866892 systemd[1]: Starting dracut-initqueue.service - dracut initqueue hook...
Oct 13 05:32:58.007192 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 13 05:32:58.007414 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:32:58.016934 systemd[1]: Stopping systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:32:58.021212 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:32:58.029463 kernel: hv_vmbus: registering driver hv_netvsc
Oct 13 05:32:58.035463 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#162 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001
Oct 13 05:32:58.046692 kernel: hv_netvsc f8615163-0000-1000-2000-7ced8d2cf45d (unnamed net_device) (uninitialized): VF slot 1 added
Oct 13 05:32:58.062393 kernel: cryptd: max_cpu_qlen set to 1000
Oct 13 05:32:58.116867 systemd-networkd[1448]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network
Oct 13 05:32:58.116873 systemd-networkd[1448]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 13 05:32:58.126290 kernel: AES CTR mode by8 optimization enabled
Oct 13 05:32:58.125855 systemd-networkd[1448]: eth0: Link UP
Oct 13 05:32:58.125962 systemd-networkd[1448]: eth0: Gained carrier
Oct 13 05:32:58.125972 systemd-networkd[1448]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network
Oct 13 05:32:58.142480 systemd-networkd[1448]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16
Oct 13 05:32:58.148063 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:32:58.166390 kernel: nvme nvme0: using unchecked data buffer
Oct 13 05:32:58.336122 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device - MSFT NVMe Accelerator v1.0 USR-A.
Oct 13 05:32:58.337589 systemd[1]: Starting disk-uuid.service - Generate new UUID for disk GPT if necessary...
Oct 13 05:32:58.398119 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM.
Oct 13 05:32:58.417722 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device - MSFT NVMe Accelerator v1.0 ROOT.
Oct 13 05:32:58.443881 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device - MSFT NVMe Accelerator v1.0 EFI-SYSTEM.
Oct 13 05:32:58.542633 systemd[1]: Finished dracut-initqueue.service - dracut initqueue hook.
Oct 13 05:32:58.543495 systemd[1]: Reached target remote-fs-pre.target - Preparation for Remote File Systems.
Oct 13 05:32:58.543812 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 13 05:32:58.543842 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Oct 13 05:32:58.625696 systemd[1]: Starting dracut-pre-mount.service - dracut pre-mount hook...
Oct 13 05:32:58.757673 systemd[1]: Finished dracut-pre-mount.service - dracut pre-mount hook.
Oct 13 05:32:59.063791 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI VMBus probing: Using version 0x10004
Oct 13 05:32:59.064002 kernel: hv_pci 00000001-7870-47b5-b203-907d12ca697e: PCI host bridge to bus 7870:00
Oct 13 05:32:59.067024 kernel: pci_bus 7870:00: root bus resource [mem 0xfc2000000-0xfc4007fff window]
Oct 13 05:32:59.068645 kernel: pci_bus 7870:00: No busn resource found for root bus, will use [bus 00-ff]
Oct 13 05:32:59.073532 kernel: pci 7870:00:00.0: [1414:00ba] type 00 class 0x020000 PCIe Endpoint
Oct 13 05:32:59.077419 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref]
Oct 13 05:32:59.081497 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref]
Oct 13 05:32:59.084527 kernel: pci 7870:00:00.0: enabling Extended Tags
Oct 13 05:32:59.096596 kernel: pci_bus 7870:00: busn_res: [bus 00-ff] end is updated to 00
Oct 13 05:32:59.096804 kernel: pci 7870:00:00.0: BAR 0 [mem 0xfc2000000-0xfc3ffffff 64bit pref]: assigned
Oct 13 05:32:59.100569 kernel: pci 7870:00:00.0: BAR 4 [mem 0xfc4000000-0xfc4007fff 64bit pref]: assigned
Oct 13 05:32:59.154898 kernel: mana 7870:00:00.0: enabling device (0000 -> 0002)
Oct 13 05:32:59.164399 kernel: mana 7870:00:00.0: Microsoft Azure Network Adapter protocol version: 0.1.1
Oct 13 05:32:59.168184 kernel: hv_netvsc f8615163-0000-1000-2000-7ced8d2cf45d eth0: VF registering: eth1
Oct 13 05:32:59.168334 kernel: mana 7870:00:00.0 eth1: joined to eth0
Oct 13 05:32:59.172352 systemd-networkd[1448]: eth1: Interface name change detected, renamed to enP30832s1.
Oct 13 05:32:59.175479 kernel: mana 7870:00:00.0 enP30832s1: renamed from eth1
Oct 13 05:32:59.270394 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16
Oct 13 05:32:59.273853 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64
Oct 13 05:32:59.274126 kernel: hv_netvsc f8615163-0000-1000-2000-7ced8d2cf45d eth0: Data path switched to VF: enP30832s1
Oct 13 05:32:59.274444 systemd-networkd[1448]: enP30832s1: Link UP
Oct 13 05:32:59.274582 systemd-networkd[1448]: enP30832s1: Gained carrier
Oct 13 05:32:59.766373 disk-uuid[1632]: Warning: The kernel is still using the old partition table.
Oct 13 05:32:59.766373 disk-uuid[1632]: The new table will be used at the next reboot or after you
Oct 13 05:32:59.766373 disk-uuid[1632]: run partprobe(8) or kpartx(8)
Oct 13 05:32:59.766373 disk-uuid[1632]: The operation has completed successfully.
Oct 13 05:32:59.771319 systemd[1]: disk-uuid.service: Deactivated successfully.
Oct 13 05:32:59.771427 systemd[1]: Finished disk-uuid.service - Generate new UUID for disk GPT if necessary.
Oct 13 05:32:59.776310 systemd[1]: Starting ignition-setup.service - Ignition (setup)...
Oct 13 05:32:59.827391 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1677)
Oct 13 05:32:59.829912 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8
Oct 13 05:32:59.829956 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Oct 13 05:32:59.876546 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 13 05:32:59.876601 kernel: BTRFS info (device nvme0n1p6): turning on async discard
Oct 13 05:32:59.877650 kernel: BTRFS info (device nvme0n1p6): enabling free space tree
Oct 13 05:32:59.883394 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8
Oct 13 05:32:59.883729 systemd[1]: Finished ignition-setup.service - Ignition (setup).
Oct 13 05:32:59.887714 systemd[1]: Starting ignition-fetch-offline.service - Ignition (fetch-offline)...
Oct 13 05:33:00.131530 systemd-networkd[1448]: eth0: Gained IPv6LL
Oct 13 05:33:01.705209 ignition[1696]: Ignition 2.22.0
Oct 13 05:33:01.705220 ignition[1696]: Stage: fetch-offline
Oct 13 05:33:01.707551 systemd[1]: Finished ignition-fetch-offline.service - Ignition (fetch-offline).
Oct 13 05:33:01.705347 ignition[1696]: no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:01.705355 ignition[1696]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:01.705455 ignition[1696]: parsed url from cmdline: ""
Oct 13 05:33:01.705458 ignition[1696]: no config URL provided
Oct 13 05:33:01.705463 ignition[1696]: reading system config file "/usr/lib/ignition/user.ign"
Oct 13 05:33:01.705469 ignition[1696]: no config at "/usr/lib/ignition/user.ign"
Oct 13 05:33:01.705474 ignition[1696]: failed to fetch config: resource requires networking
Oct 13 05:33:01.718811 systemd[1]: Starting ignition-fetch.service - Ignition (fetch)...
Oct 13 05:33:01.706403 ignition[1696]: Ignition finished successfully
Oct 13 05:33:01.746578 ignition[1702]: Ignition 2.22.0
Oct 13 05:33:01.746587 ignition[1702]: Stage: fetch
Oct 13 05:33:01.747450 ignition[1702]: no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:01.747456 ignition[1702]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:01.747533 ignition[1702]: parsed url from cmdline: ""
Oct 13 05:33:01.747535 ignition[1702]: no config URL provided
Oct 13 05:33:01.747538 ignition[1702]: reading system config file "/usr/lib/ignition/user.ign"
Oct 13 05:33:01.747542 ignition[1702]: no config at "/usr/lib/ignition/user.ign"
Oct 13 05:33:01.747556 ignition[1702]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1
Oct 13 05:33:01.908660 ignition[1702]: GET result: OK
Oct 13 05:33:01.908708 ignition[1702]: config has been read from IMDS userdata
Oct 13 05:33:01.908720 ignition[1702]: parsing config with SHA512: 7153bbd9097e9997181bbc26156fb03cf2169fae359fc1a08559fca95d336610c7b6d76d5bc01a8c315f66d20a341b3990effcf9f2b6d5f8e9cea9173077effa
Oct 13 05:33:01.911811 unknown[1702]: fetched base config from "system"
Oct 13 05:33:01.911974 ignition[1702]: fetch: fetch complete
Oct 13 05:33:01.911816 unknown[1702]: fetched base config from "system"
Oct 13 05:33:01.911978 ignition[1702]: fetch: fetch passed
Oct 13 05:33:01.911821 unknown[1702]: fetched user config from "azure"
Oct 13 05:33:01.912015 ignition[1702]: Ignition finished successfully
Oct 13 05:33:01.913729 systemd[1]: Finished ignition-fetch.service - Ignition (fetch).
Oct 13 05:33:01.919498 systemd[1]: Starting ignition-kargs.service - Ignition (kargs)...
Oct 13 05:33:01.948184 ignition[1708]: Ignition 2.22.0
Oct 13 05:33:01.948194 ignition[1708]: Stage: kargs
Oct 13 05:33:01.950635 systemd[1]: Finished ignition-kargs.service - Ignition (kargs).
Oct 13 05:33:01.948406 ignition[1708]: no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:01.948413 ignition[1708]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:01.956071 systemd[1]: Starting ignition-disks.service - Ignition (disks)...
Oct 13 05:33:01.949575 ignition[1708]: kargs: kargs passed
Oct 13 05:33:01.949608 ignition[1708]: Ignition finished successfully
Oct 13 05:33:01.977261 ignition[1714]: Ignition 2.22.0
Oct 13 05:33:01.977269 ignition[1714]: Stage: disks
Oct 13 05:33:01.979126 systemd[1]: Finished ignition-disks.service - Ignition (disks).
Oct 13 05:33:01.977458 ignition[1714]: no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:01.977465 ignition[1714]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:01.977916 ignition[1714]: disks: disks passed
Oct 13 05:33:01.977943 ignition[1714]: Ignition finished successfully
Oct 13 05:33:01.985947 systemd[1]: Reached target initrd-root-device.target - Initrd Root Device.
Oct 13 05:33:01.988883 systemd[1]: Reached target local-fs-pre.target - Preparation for Local File Systems.
Oct 13 05:33:01.990579 systemd[1]: Reached target local-fs.target - Local File Systems.
Oct 13 05:33:01.995772 systemd[1]: Reached target sysinit.target - System Initialization.
Oct 13 05:33:01.998083 systemd[1]: Reached target basic.target - Basic System.
Oct 13 05:33:02.000403 systemd[1]: Starting systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT...
Oct 13 05:33:02.176111 systemd-fsck[1722]: ROOT: clean, 15/7340400 files, 470001/7359488 blocks
Oct 13 05:33:02.179984 systemd[1]: Finished systemd-fsck-root.service - File System Check on /dev/disk/by-label/ROOT.
Oct 13 05:33:02.184744 systemd[1]: Mounting sysroot.mount - /sysroot...
Oct 13 05:33:03.796749 kernel: EXT4-fs (nvme0n1p9): mounted filesystem c7d6ef00-6dd1-40b4-91f2-c4c5965e3cac r/w with ordered data mode. Quota mode: none.
Oct 13 05:33:03.797305 systemd[1]: Mounted sysroot.mount - /sysroot.
Oct 13 05:33:03.803441 systemd[1]: Reached target initrd-root-fs.target - Initrd Root File System.
Oct 13 05:33:03.841420 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Oct 13 05:33:03.865259 systemd[1]: Mounting sysroot-usr.mount - /sysroot/usr...
Oct 13 05:33:03.869813 systemd[1]: Starting flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent...
Oct 13 05:33:03.875065 systemd[1]: ignition-remount-sysroot.service - Remount /sysroot read-write for Ignition was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Oct 13 05:33:03.885006 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1732)
Oct 13 05:33:03.875157 systemd[1]: Reached target ignition-diskful.target - Ignition Boot Disk Setup.
Oct 13 05:33:03.889791 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8
Oct 13 05:33:03.889808 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Oct 13 05:33:03.876691 systemd[1]: Mounted sysroot-usr.mount - /sysroot/usr.
Oct 13 05:33:03.879496 systemd[1]: Starting initrd-setup-root.service - Root filesystem setup...
Oct 13 05:33:03.896635 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 13 05:33:03.896671 kernel: BTRFS info (device nvme0n1p6): turning on async discard
Oct 13 05:33:03.898029 kernel: BTRFS info (device nvme0n1p6): enabling free space tree
Oct 13 05:33:03.899519 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Oct 13 05:33:04.668616 coreos-metadata[1734]: Oct 13 05:33:04.668 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Oct 13 05:33:04.675508 coreos-metadata[1734]: Oct 13 05:33:04.675 INFO Fetch successful
Oct 13 05:33:04.676909 coreos-metadata[1734]: Oct 13 05:33:04.676 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1
Oct 13 05:33:04.689866 coreos-metadata[1734]: Oct 13 05:33:04.689 INFO Fetch successful
Oct 13 05:33:04.703339 coreos-metadata[1734]: Oct 13 05:33:04.703 INFO wrote hostname ci-4487.0.0-a-69b001b486 to /sysroot/etc/hostname
Oct 13 05:33:04.706868 systemd[1]: Finished flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Oct 13 05:33:05.051651 initrd-setup-root[1764]: cut: /sysroot/etc/passwd: No such file or directory
Oct 13 05:33:05.191121 initrd-setup-root[1771]: cut: /sysroot/etc/group: No such file or directory
Oct 13 05:33:05.195624 initrd-setup-root[1778]: cut: /sysroot/etc/shadow: No such file or directory
Oct 13 05:33:05.213450 initrd-setup-root[1785]: cut: /sysroot/etc/gshadow: No such file or directory
Oct 13 05:33:06.361446 systemd[1]: Finished initrd-setup-root.service - Root filesystem setup.
Oct 13 05:33:06.366628 systemd[1]: Starting ignition-mount.service - Ignition (mount)...
Oct 13 05:33:06.371939 systemd[1]: Starting sysroot-boot.service - /sysroot/boot...
Oct 13 05:33:06.393715 systemd[1]: sysroot-oem.mount: Deactivated successfully.
Oct 13 05:33:06.399633 kernel: BTRFS info (device nvme0n1p6): last unmount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8
Oct 13 05:33:06.415741 systemd[1]: Finished sysroot-boot.service - /sysroot/boot.
Oct 13 05:33:06.423240 ignition[1853]: INFO     : Ignition 2.22.0
Oct 13 05:33:06.423240 ignition[1853]: INFO     : Stage: mount
Oct 13 05:33:06.430486 ignition[1853]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:06.430486 ignition[1853]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:06.430486 ignition[1853]: INFO     : mount: mount passed
Oct 13 05:33:06.430486 ignition[1853]: INFO     : Ignition finished successfully
Oct 13 05:33:06.425410 systemd[1]: Finished ignition-mount.service - Ignition (mount).
Oct 13 05:33:06.428649 systemd[1]: Starting ignition-files.service - Ignition (files)...
Oct 13 05:33:06.444612 systemd[1]: Mounting sysroot-oem.mount - /sysroot/oem...
Oct 13 05:33:06.469396 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/nvme0n1p6 (259:5) scanned by mount (1864)
Oct 13 05:33:06.469430 kernel: BTRFS info (device nvme0n1p6): first mount of filesystem 56bbaf92-79f4-4948-a1fd-5992c383eba8
Oct 13 05:33:06.471448 kernel: BTRFS info (device nvme0n1p6): using crc32c (crc32c-intel) checksum algorithm
Oct 13 05:33:06.477575 kernel: BTRFS info (device nvme0n1p6): enabling ssd optimizations
Oct 13 05:33:06.477614 kernel: BTRFS info (device nvme0n1p6): turning on async discard
Oct 13 05:33:06.477626 kernel: BTRFS info (device nvme0n1p6): enabling free space tree
Oct 13 05:33:06.479951 systemd[1]: Mounted sysroot-oem.mount - /sysroot/oem.
Oct 13 05:33:06.501151 ignition[1881]: INFO     : Ignition 2.22.0
Oct 13 05:33:06.501151 ignition[1881]: INFO     : Stage: files
Oct 13 05:33:06.504241 ignition[1881]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:06.504241 ignition[1881]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:06.504241 ignition[1881]: DEBUG    : files: compiled without relabeling support, skipping
Oct 13 05:33:06.559790 ignition[1881]: INFO     : files: ensureUsers: op(1): [started]  creating or modifying user "core"
Oct 13 05:33:06.559790 ignition[1881]: DEBUG    : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Oct 13 05:33:06.637055 ignition[1881]: INFO     : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Oct 13 05:33:06.640458 ignition[1881]: INFO     : files: ensureUsers: op(2): [started]  adding ssh keys to user "core"
Oct 13 05:33:06.640458 ignition[1881]: INFO     : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Oct 13 05:33:06.639462 unknown[1881]: wrote ssh authorized keys file for user: core
Oct 13 05:33:06.679684 ignition[1881]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [started]  writing file "/sysroot/etc/flatcar/update.conf"
Oct 13 05:33:06.684472 ignition[1881]: INFO     : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Oct 13 05:33:06.684472 ignition[1881]: INFO     : files: createResultFile: createFiles: op(4): [started]  writing file "/sysroot/etc/.ignition-result.json"
Oct 13 05:33:06.684472 ignition[1881]: INFO     : files: createResultFile: createFiles: op(4): [finished] writing file "/sysroot/etc/.ignition-result.json"
Oct 13 05:33:06.684472 ignition[1881]: INFO     : files: files passed
Oct 13 05:33:06.684472 ignition[1881]: INFO     : Ignition finished successfully
Oct 13 05:33:06.683237 systemd[1]: Finished ignition-files.service - Ignition (files).
Oct 13 05:33:06.688676 systemd[1]: Starting ignition-quench.service - Ignition (record completion)...
Oct 13 05:33:06.698835 systemd[1]: Starting initrd-setup-root-after-ignition.service - Root filesystem completion...
Oct 13 05:33:06.709625 systemd[1]: ignition-quench.service: Deactivated successfully.
Oct 13 05:33:06.709702 systemd[1]: Finished ignition-quench.service - Ignition (record completion).
Oct 13 05:33:06.772927 initrd-setup-root-after-ignition[1911]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 13 05:33:06.772927 initrd-setup-root-after-ignition[1911]: grep: /sysroot/usr/share/flatcar/enabled-sysext.conf: No such file or directory
Oct 13 05:33:06.779883 initrd-setup-root-after-ignition[1915]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 13 05:33:06.777231 systemd[1]: Finished initrd-setup-root-after-ignition.service - Root filesystem completion.
Oct 13 05:33:06.785389 systemd[1]: Reached target ignition-complete.target - Ignition Complete.
Oct 13 05:33:06.790954 systemd[1]: Starting initrd-parse-etc.service - Mountpoints Configured in the Real Root...
Oct 13 05:33:06.826793 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Oct 13 05:33:06.826876 systemd[1]: Finished initrd-parse-etc.service - Mountpoints Configured in the Real Root.
Oct 13 05:33:06.831575 systemd[1]: Reached target initrd-fs.target - Initrd File Systems.
Oct 13 05:33:06.833027 systemd[1]: Reached target initrd.target - Initrd Default Target.
Oct 13 05:33:06.836405 systemd[1]: dracut-mount.service - dracut mount hook was skipped because no trigger condition checks were met.
Oct 13 05:33:06.838490 systemd[1]: Starting dracut-pre-pivot.service - dracut pre-pivot and cleanup hook...
Oct 13 05:33:06.860121 systemd[1]: Finished dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Oct 13 05:33:06.864993 systemd[1]: Starting initrd-cleanup.service - Cleaning Up and Shutting Down Daemons...
Oct 13 05:33:06.885722 systemd[1]: Unnecessary job was removed for dev-mapper-usr.device - /dev/mapper/usr.
Oct 13 05:33:06.885918 systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups.
Oct 13 05:33:06.886809 systemd[1]: Stopped target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 13 05:33:06.887048 systemd[1]: Stopped target timers.target - Timer Units.
Oct 13 05:33:06.887316 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Oct 13 05:33:06.887445 systemd[1]: Stopped dracut-pre-pivot.service - dracut pre-pivot and cleanup hook.
Oct 13 05:33:06.893567 systemd[1]: Stopped target initrd.target - Initrd Default Target.
Oct 13 05:33:06.897541 systemd[1]: Stopped target basic.target - Basic System.
Oct 13 05:33:06.899790 systemd[1]: Stopped target ignition-complete.target - Ignition Complete.
Oct 13 05:33:06.902754 systemd[1]: Stopped target ignition-diskful.target - Ignition Boot Disk Setup.
Oct 13 05:33:06.910110 systemd[1]: Stopped target initrd-root-device.target - Initrd Root Device.
Oct 13 05:33:06.914455 systemd[1]: Stopped target initrd-usr-fs.target - Initrd /usr File System.
Oct 13 05:33:06.926491 systemd[1]: Stopped target remote-fs.target - Remote File Systems.
Oct 13 05:33:06.926991 systemd[1]: Stopped target remote-fs-pre.target - Preparation for Remote File Systems.
Oct 13 05:33:06.927280 systemd[1]: Stopped target sysinit.target - System Initialization.
Oct 13 05:33:06.927573 systemd[1]: Stopped target local-fs.target - Local File Systems.
Oct 13 05:33:06.927847 systemd[1]: Stopped target swap.target - Swaps.
Oct 13 05:33:06.928101 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Oct 13 05:33:06.928194 systemd[1]: Stopped dracut-pre-mount.service - dracut pre-mount hook.
Oct 13 05:33:06.928743 systemd[1]: Stopped target cryptsetup.target - Local Encrypted Volumes.
Oct 13 05:33:06.929027 systemd[1]: Stopped target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 13 05:33:06.940492 systemd[1]: clevis-luks-askpass.path: Deactivated successfully.
Oct 13 05:33:06.940697 systemd[1]: Stopped clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 13 05:33:06.942648 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Oct 13 05:33:06.942767 systemd[1]: Stopped dracut-initqueue.service - dracut initqueue hook.
Oct 13 05:33:06.943874 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Oct 13 05:33:06.943982 systemd[1]: Stopped initrd-setup-root-after-ignition.service - Root filesystem completion.
Oct 13 05:33:06.944193 systemd[1]: ignition-files.service: Deactivated successfully.
Oct 13 05:33:06.944284 systemd[1]: Stopped ignition-files.service - Ignition (files).
Oct 13 05:33:06.990178 ignition[1936]: INFO     : Ignition 2.22.0
Oct 13 05:33:06.990178 ignition[1936]: INFO     : Stage: umount
Oct 13 05:33:06.990178 ignition[1936]: INFO     : no configs at "/usr/lib/ignition/base.d"
Oct 13 05:33:06.990178 ignition[1936]: INFO     : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 13 05:33:06.990178 ignition[1936]: INFO     : umount: umount passed
Oct 13 05:33:06.990178 ignition[1936]: INFO     : Ignition finished successfully
Oct 13 05:33:06.949887 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
Oct 13 05:33:06.950005 systemd[1]: Stopped flatcar-metadata-hostname.service - Flatcar Metadata Hostname Agent.
Oct 13 05:33:06.959943 systemd[1]: Stopping ignition-mount.service - Ignition (mount)...
Oct 13 05:33:06.965437 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Oct 13 05:33:06.965568 systemd[1]: Stopped kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 13 05:33:06.972235 systemd[1]: Stopping sysroot-boot.service - /sysroot/boot...
Oct 13 05:33:06.978779 systemd[1]: systemd-tmpfiles-setup.service: Deactivated successfully.
Oct 13 05:33:06.978914 systemd[1]: Stopped systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 13 05:33:06.990299 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Oct 13 05:33:06.990445 systemd[1]: Stopped systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 13 05:33:06.994587 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Oct 13 05:33:06.994703 systemd[1]: Stopped dracut-pre-trigger.service - dracut pre-trigger hook.
Oct 13 05:33:07.000229 systemd[1]: ignition-mount.service: Deactivated successfully.
Oct 13 05:33:07.000289 systemd[1]: Stopped ignition-mount.service - Ignition (mount).
Oct 13 05:33:07.005660 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Oct 13 05:33:07.005737 systemd[1]: Finished initrd-cleanup.service - Cleaning Up and Shutting Down Daemons.
Oct 13 05:33:07.015441 systemd[1]: ignition-disks.service: Deactivated successfully.
Oct 13 05:33:07.015484 systemd[1]: Stopped ignition-disks.service - Ignition (disks).
Oct 13 05:33:07.019223 systemd[1]: ignition-kargs.service: Deactivated successfully.
Oct 13 05:33:07.020058 systemd[1]: Stopped ignition-kargs.service - Ignition (kargs).
Oct 13 05:33:07.024057 systemd[1]: ignition-fetch.service: Deactivated successfully.
Oct 13 05:33:07.025284 systemd[1]: Stopped ignition-fetch.service - Ignition (fetch).
Oct 13 05:33:07.027319 systemd[1]: Stopped target network.target - Network.
Oct 13 05:33:07.043979 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Oct 13 05:33:07.044035 systemd[1]: Stopped ignition-fetch-offline.service - Ignition (fetch-offline).
Oct 13 05:33:07.046714 systemd[1]: Stopped target paths.target - Path Units.
Oct 13 05:33:07.049412 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Oct 13 05:33:07.054874 systemd[1]: Stopped systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 13 05:33:07.059152 systemd[1]: Stopped target slices.target - Slice Units.
Oct 13 05:33:07.061892 systemd[1]: Stopped target sockets.target - Socket Units.
Oct 13 05:33:07.063300 systemd[1]: iscsid.socket: Deactivated successfully.
Oct 13 05:33:07.063332 systemd[1]: Closed iscsid.socket - Open-iSCSI iscsid Socket.
Oct 13 05:33:07.069184 systemd[1]: iscsiuio.socket: Deactivated successfully.
Oct 13 05:33:07.069215 systemd[1]: Closed iscsiuio.socket - Open-iSCSI iscsiuio Socket.
Oct 13 05:33:07.072440 systemd[1]: ignition-setup.service: Deactivated successfully.
Oct 13 05:33:07.072484 systemd[1]: Stopped ignition-setup.service - Ignition (setup).
Oct 13 05:33:07.076441 systemd[1]: ignition-setup-pre.service: Deactivated successfully.
Oct 13 05:33:07.076481 systemd[1]: Stopped ignition-setup-pre.service - Ignition env setup.
Oct 13 05:33:07.080495 systemd[1]: Stopping systemd-networkd.service - Network Configuration...
Oct 13 05:33:07.084457 systemd[1]: Stopping systemd-resolved.service - Network Name Resolution...
Oct 13 05:33:07.088566 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Oct 13 05:33:07.093743 systemd[1]: systemd-networkd.service: Deactivated successfully.
Oct 13 05:33:07.093927 systemd[1]: Stopped systemd-networkd.service - Network Configuration.
Oct 13 05:33:07.102458 systemd[1]: systemd-resolved.service: Deactivated successfully.
Oct 13 05:33:07.102556 systemd[1]: Stopped systemd-resolved.service - Network Name Resolution.
Oct 13 05:33:07.106236 systemd[1]: sysroot-boot.service: Deactivated successfully.
Oct 13 05:33:07.106301 systemd[1]: Stopped sysroot-boot.service - /sysroot/boot.
Oct 13 05:33:07.109617 systemd[1]: Stopped target network-pre.target - Preparation for Network.
Oct 13 05:33:07.113471 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Oct 13 05:33:07.113507 systemd[1]: Closed systemd-networkd.socket - Network Service Netlink Socket.
Oct 13 05:33:07.117438 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Oct 13 05:33:07.117484 systemd[1]: Stopped initrd-setup-root.service - Root filesystem setup.
Oct 13 05:33:07.125274 systemd[1]: Stopping network-cleanup.service - Network Cleanup...
Oct 13 05:33:07.130650 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Oct 13 05:33:07.130705 systemd[1]: Stopped parse-ip-for-networkd.service - Write systemd-networkd units from cmdline.
Oct 13 05:33:07.132252 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Oct 13 05:33:07.132310 systemd[1]: Stopped systemd-sysctl.service - Apply Kernel Variables.
Oct 13 05:33:07.142523 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Oct 13 05:33:07.142569 systemd[1]: Stopped systemd-modules-load.service - Load Kernel Modules.
Oct 13 05:33:07.146456 systemd[1]: Stopping systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 13 05:33:07.161493 systemd[1]: systemd-udevd.service: Deactivated successfully.
Oct 13 05:33:07.161592 systemd[1]: Stopped systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 13 05:33:07.165607 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Oct 13 05:33:07.165635 systemd[1]: Closed systemd-udevd-control.socket - udev Control Socket.
Oct 13 05:33:07.168442 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Oct 13 05:33:07.168473 systemd[1]: Closed systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 13 05:33:07.170423 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Oct 13 05:33:07.170460 systemd[1]: Stopped dracut-pre-udev.service - dracut pre-udev hook.
Oct 13 05:33:07.173708 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Oct 13 05:33:07.173750 systemd[1]: Stopped dracut-cmdline.service - dracut cmdline hook.
Oct 13 05:33:07.176532 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Oct 13 05:33:07.176568 systemd[1]: Stopped dracut-cmdline-ask.service - dracut ask for additional cmdline parameters.
Oct 13 05:33:07.188066 systemd[1]: Starting initrd-udevadm-cleanup-db.service - Cleanup udev Database...
Oct 13 05:33:07.189485 systemd[1]: systemd-network-generator.service: Deactivated successfully.
Oct 13 05:33:07.189545 systemd[1]: Stopped systemd-network-generator.service - Generate network units from Kernel command line.
Oct 13 05:33:07.194712 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Oct 13 05:33:07.194758 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 13 05:33:07.203470 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 13 05:33:07.203519 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:33:07.207917 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Oct 13 05:33:07.208012 systemd[1]: Finished initrd-udevadm-cleanup-db.service - Cleanup udev Database.
Oct 13 05:33:07.271702 kernel: hv_netvsc f8615163-0000-1000-2000-7ced8d2cf45d eth0: Data path switched from VF: enP30832s1
Oct 13 05:33:07.271950 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64
Oct 13 05:33:07.275170 systemd[1]: network-cleanup.service: Deactivated successfully.
Oct 13 05:33:07.275742 systemd[1]: Stopped network-cleanup.service - Network Cleanup.
Oct 13 05:33:07.280597 systemd[1]: Reached target initrd-switch-root.target - Switch Root.
Oct 13 05:33:07.282969 systemd[1]: Starting initrd-switch-root.service - Switch Root...
Oct 13 05:33:07.313726 systemd[1]: Switching root.
Oct 13 05:33:07.418150 systemd-journald[1068]: Journal stopped
Oct 13 05:33:14.921894 systemd-journald[1068]: Received SIGTERM from PID 1 (systemd).
Oct 13 05:33:14.921923 kernel: SELinux:  policy capability network_peer_controls=1
Oct 13 05:33:14.921936 kernel: SELinux:  policy capability open_perms=1
Oct 13 05:33:14.921945 kernel: SELinux:  policy capability extended_socket_class=1
Oct 13 05:33:14.921952 kernel: SELinux:  policy capability always_check_network=0
Oct 13 05:33:14.921960 kernel: SELinux:  policy capability cgroup_seclabel=1
Oct 13 05:33:14.921970 kernel: SELinux:  policy capability nnp_nosuid_transition=1
Oct 13 05:33:14.921980 kernel: SELinux:  policy capability genfs_seclabel_symlinks=0
Oct 13 05:33:14.921987 kernel: SELinux:  policy capability ioctl_skip_cloexec=0
Oct 13 05:33:14.921995 kernel: SELinux:  policy capability userspace_initial_context=0
Oct 13 05:33:14.922003 kernel: audit: type=1403 audit(1760333588.533:2): auid=4294967295 ses=4294967295 lsm=selinux res=1
Oct 13 05:33:14.922011 systemd[1]: Successfully loaded SELinux policy in 203.913ms.
Oct 13 05:33:14.922022 systemd[1]: Relabeled /dev/, /dev/shm/, /run/ in 3.817ms.
Oct 13 05:33:14.922032 systemd[1]: systemd 257.7 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +IPE +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 -BTF -XKBCOMMON +UTMP -SYSVINIT +LIBARCHIVE)
Oct 13 05:33:14.922042 systemd[1]: Detected virtualization microsoft.
Oct 13 05:33:14.922052 systemd[1]: Detected architecture x86-64.
Oct 13 05:33:14.922060 systemd[1]: Detected first boot.
Oct 13 05:33:14.922069 systemd[1]: Hostname set to <ci-4487.0.0-a-69b001b486>.
Oct 13 05:33:14.922077 systemd[1]: Initializing machine ID from random generator.
Oct 13 05:33:14.922086 zram_generator::config[1980]: No configuration found.
Oct 13 05:33:14.922095 kernel: Guest personality initialized and is inactive
Oct 13 05:33:14.922104 kernel: VMCI host device registered (name=vmci, major=10, minor=124)
Oct 13 05:33:14.922112 kernel: Initialized host personality
Oct 13 05:33:14.922120 kernel: NET: Registered PF_VSOCK protocol family
Oct 13 05:33:14.922128 systemd[1]: Populated /etc with preset unit settings.
Oct 13 05:33:14.922136 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Oct 13 05:33:14.922145 systemd[1]: Stopped initrd-switch-root.service - Switch Root.
Oct 13 05:33:14.922155 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Oct 13 05:33:14.922164 systemd[1]: Created slice system-addon\x2dconfig.slice - Slice /system/addon-config.
Oct 13 05:33:14.922173 systemd[1]: Created slice system-addon\x2drun.slice - Slice /system/addon-run.
Oct 13 05:33:14.922182 systemd[1]: Created slice system-getty.slice - Slice /system/getty.
Oct 13 05:33:14.922190 systemd[1]: Created slice system-modprobe.slice - Slice /system/modprobe.
Oct 13 05:33:14.922199 systemd[1]: Created slice system-serial\x2dgetty.slice - Slice /system/serial-getty.
Oct 13 05:33:14.922209 systemd[1]: Created slice system-system\x2dcloudinit.slice - Slice /system/system-cloudinit.
Oct 13 05:33:14.922217 systemd[1]: Created slice system-systemd\x2dfsck.slice - Slice /system/systemd-fsck.
Oct 13 05:33:14.922226 systemd[1]: Created slice user.slice - User and Session Slice.
Oct 13 05:33:14.922235 systemd[1]: Started clevis-luks-askpass.path - Forward Password Requests to Clevis Directory Watch.
Oct 13 05:33:14.922243 systemd[1]: Started systemd-ask-password-console.path - Dispatch Password Requests to Console Directory Watch.
Oct 13 05:33:14.922252 systemd[1]: Started systemd-ask-password-wall.path - Forward Password Requests to Wall Directory Watch.
Oct 13 05:33:14.922284 systemd[1]: Set up automount boot.automount - Boot partition Automount Point.
Oct 13 05:33:14.922295 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount - Arbitrary Executable File Formats File System Automount Point.
Oct 13 05:33:14.922306 systemd[1]: Expecting device dev-disk-by\x2dlabel-OEM.device - /dev/disk/by-label/OEM...
Oct 13 05:33:14.922316 systemd[1]: Expecting device dev-ttyS0.device - /dev/ttyS0...
Oct 13 05:33:14.922325 systemd[1]: Reached target cryptsetup-pre.target - Local Encrypted Volumes (Pre).
Oct 13 05:33:14.922335 systemd[1]: Reached target cryptsetup.target - Local Encrypted Volumes.
Oct 13 05:33:14.922347 systemd[1]: Stopped target initrd-switch-root.target - Switch Root.
Oct 13 05:33:14.922356 systemd[1]: Stopped target initrd-fs.target - Initrd File Systems.
Oct 13 05:33:14.922365 systemd[1]: Stopped target initrd-root-fs.target - Initrd Root File System.
Oct 13 05:33:14.922387 systemd[1]: Reached target integritysetup.target - Local Integrity Protected Volumes.
Oct 13 05:33:14.922398 systemd[1]: Reached target remote-cryptsetup.target - Remote Encrypted Volumes.
Oct 13 05:33:14.922407 systemd[1]: Reached target remote-fs.target - Remote File Systems.
Oct 13 05:33:14.922416 systemd[1]: Reached target slices.target - Slice Units.
Oct 13 05:33:14.922427 systemd[1]: Reached target swap.target - Swaps.
Oct 13 05:33:14.922436 systemd[1]: Reached target veritysetup.target - Local Verity Protected Volumes.
Oct 13 05:33:14.922445 systemd[1]: Listening on systemd-coredump.socket - Process Core Dump Socket.
Oct 13 05:33:14.922455 systemd[1]: Listening on systemd-creds.socket - Credential Encryption/Decryption.
Oct 13 05:33:14.922464 systemd[1]: Listening on systemd-networkd.socket - Network Service Netlink Socket.
Oct 13 05:33:14.922475 systemd[1]: Listening on systemd-udevd-control.socket - udev Control Socket.
Oct 13 05:33:14.922485 systemd[1]: Listening on systemd-udevd-kernel.socket - udev Kernel Socket.
Oct 13 05:33:14.922494 systemd[1]: Listening on systemd-userdbd.socket - User Database Manager Socket.
Oct 13 05:33:14.922504 systemd[1]: Mounting dev-hugepages.mount - Huge Pages File System...
Oct 13 05:33:14.922513 systemd[1]: Mounting dev-mqueue.mount - POSIX Message Queue File System...
Oct 13 05:33:14.922522 systemd[1]: Mounting media.mount - External Media Directory...
Oct 13 05:33:14.922533 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 13 05:33:14.922543 systemd[1]: Mounting sys-kernel-debug.mount - Kernel Debug File System...
Oct 13 05:33:14.922552 systemd[1]: Mounting sys-kernel-tracing.mount - Kernel Trace File System...
Oct 13 05:33:14.922562 systemd[1]: Mounting tmp.mount - Temporary Directory /tmp...
Oct 13 05:33:14.922571 systemd[1]: var-lib-machines.mount - Virtual Machine and Container Storage (Compatibility) was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Oct 13 05:33:14.922581 systemd[1]: Reached target machines.target - Containers.
Oct 13 05:33:14.922591 systemd[1]: Starting flatcar-tmpfiles.service - Create missing system files...
Oct 13 05:33:14.922601 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 13 05:33:14.922611 systemd[1]: Starting kmod-static-nodes.service - Create List of Static Device Nodes...
Oct 13 05:33:14.922620 systemd[1]: Starting modprobe@configfs.service - Load Kernel Module configfs...
Oct 13 05:33:14.922629 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Oct 13 05:33:14.922638 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Oct 13 05:33:14.922648 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Oct 13 05:33:14.922658 systemd[1]: Starting modprobe@fuse.service - Load Kernel Module fuse...
Oct 13 05:33:14.922668 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Oct 13 05:33:14.922678 systemd[1]: setup-nsswitch.service - Create /etc/nsswitch.conf was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Oct 13 05:33:14.922687 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Oct 13 05:33:14.922696 systemd[1]: Stopped systemd-fsck-root.service - File System Check on Root Device.
Oct 13 05:33:14.922706 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Oct 13 05:33:14.922716 systemd[1]: Stopped systemd-fsck-usr.service.
Oct 13 05:33:14.922727 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).
Oct 13 05:33:14.922736 systemd[1]: Starting systemd-journald.service - Journal Service...
Oct 13 05:33:14.922745 systemd[1]: Starting systemd-modules-load.service - Load Kernel Modules...
Oct 13 05:33:14.922755 systemd[1]: Starting systemd-network-generator.service - Generate network units from Kernel command line...
Oct 13 05:33:14.922764 systemd[1]: Starting systemd-remount-fs.service - Remount Root and Kernel File Systems...
Oct 13 05:33:14.922773 systemd[1]: Starting systemd-udev-load-credentials.service - Load udev Rules from Credentials...
Oct 13 05:33:14.922784 kernel: fuse: init (API version 7.41)
Oct 13 05:33:14.922793 systemd[1]: Starting systemd-udev-trigger.service - Coldplug All udev Devices...
Oct 13 05:33:14.922802 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 13 05:33:14.922812 systemd[1]: Mounted dev-hugepages.mount - Huge Pages File System.
Oct 13 05:33:14.922821 systemd[1]: Mounted dev-mqueue.mount - POSIX Message Queue File System.
Oct 13 05:33:14.922830 systemd[1]: Mounted media.mount - External Media Directory.
Oct 13 05:33:14.922840 systemd[1]: Mounted sys-kernel-debug.mount - Kernel Debug File System.
Oct 13 05:33:14.922851 systemd[1]: Mounted sys-kernel-tracing.mount - Kernel Trace File System.
Oct 13 05:33:14.922861 systemd[1]: Mounted tmp.mount - Temporary Directory /tmp.
Oct 13 05:33:14.922870 systemd[1]: Finished flatcar-tmpfiles.service - Create missing system files.
Oct 13 05:33:14.922879 systemd[1]: Finished kmod-static-nodes.service - Create List of Static Device Nodes.
Oct 13 05:33:14.922888 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Oct 13 05:33:14.922897 systemd[1]: Finished modprobe@configfs.service - Load Kernel Module configfs.
Oct 13 05:33:14.922906 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 13 05:33:14.922917 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Oct 13 05:33:14.922926 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 13 05:33:14.922935 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Oct 13 05:33:14.922944 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Oct 13 05:33:14.922953 systemd[1]: Finished modprobe@fuse.service - Load Kernel Module fuse.
Oct 13 05:33:14.922977 systemd-journald[2063]: Collecting audit messages is disabled.
Oct 13 05:33:14.923000 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 13 05:33:14.923010 systemd-journald[2063]: Journal started
Oct 13 05:33:14.923032 systemd-journald[2063]: Runtime Journal (/run/log/journal/02cf025e9518470b9700faaef584085b) is 8M, max 158.9M, 150.9M free.
Oct 13 05:33:14.390591 systemd[1]: Queued start job for default target multi-user.target.
Oct 13 05:33:14.396786 systemd[1]: Unnecessary job was removed for dev-nvme0n1p6.device - /dev/nvme0n1p6.
Oct 13 05:33:14.397119 systemd[1]: systemd-journald.service: Deactivated successfully.
Oct 13 05:33:14.924574 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Oct 13 05:33:14.930973 systemd[1]: Started systemd-journald.service - Journal Service.
Oct 13 05:33:14.933037 systemd[1]: Finished systemd-modules-load.service - Load Kernel Modules.
Oct 13 05:33:14.934783 systemd[1]: Finished systemd-network-generator.service - Generate network units from Kernel command line.
Oct 13 05:33:14.937014 systemd[1]: Finished systemd-remount-fs.service - Remount Root and Kernel File Systems.
Oct 13 05:33:14.945140 systemd[1]: Reached target network-pre.target - Preparation for Network.
Oct 13 05:33:14.958707 systemd[1]: Listening on systemd-importd.socket - Disk Image Download Service Socket.
Oct 13 05:33:14.962033 systemd[1]: Mounting sys-fs-fuse-connections.mount - FUSE Control File System...
Oct 13 05:33:14.965515 systemd[1]: Mounting sys-kernel-config.mount - Kernel Configuration File System...
Oct 13 05:33:14.968508 systemd[1]: remount-root.service - Remount Root File System was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Oct 13 05:33:14.968612 systemd[1]: Reached target local-fs.target - Local File Systems.
Oct 13 05:33:14.972194 systemd[1]: Listening on systemd-sysext.socket - System Extension Image Management.
Oct 13 05:33:14.993940 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 13 05:33:15.059338 systemd[1]: Starting systemd-hwdb-update.service - Rebuild Hardware Database...
Oct 13 05:33:15.062502 systemd[1]: Starting systemd-journal-flush.service - Flush Journal to Persistent Storage...
Oct 13 05:33:15.064482 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 13 05:33:15.065568 systemd[1]: Starting systemd-random-seed.service - Load/Save OS Random Seed...
Oct 13 05:33:15.069001 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Oct 13 05:33:15.070370 systemd[1]: Starting systemd-sysctl.service - Apply Kernel Variables...
Oct 13 05:33:15.074502 systemd[1]: Starting systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/...
Oct 13 05:33:15.077506 systemd[1]: Starting systemd-sysusers.service - Create System Users...
Oct 13 05:33:15.082142 systemd[1]: Mounted sys-fs-fuse-connections.mount - FUSE Control File System.
Oct 13 05:33:15.085664 systemd[1]: Mounted sys-kernel-config.mount - Kernel Configuration File System.
Oct 13 05:33:15.097621 systemd-journald[2063]: Time spent on flushing to /var/log/journal/02cf025e9518470b9700faaef584085b is 11.079ms for 952 entries.
Oct 13 05:33:15.097621 systemd-journald[2063]: System Journal (/var/log/journal/02cf025e9518470b9700faaef584085b) is 8M, max 2.6G, 2.6G free.
Oct 13 05:33:15.187435 systemd-journald[2063]: Received client request to flush runtime journal.
Oct 13 05:33:15.100080 systemd[1]: Finished systemd-udev-load-credentials.service - Load udev Rules from Credentials.
Oct 13 05:33:15.106157 systemd[1]: Finished systemd-random-seed.service - Load/Save OS Random Seed.
Oct 13 05:33:15.110022 systemd[1]: Reached target first-boot-complete.target - First Boot Complete.
Oct 13 05:33:15.116488 systemd[1]: Starting systemd-machine-id-commit.service - Save Transient machine-id to Disk...
Oct 13 05:33:15.139333 systemd[1]: Finished systemd-udev-trigger.service - Coldplug All udev Devices.
Oct 13 05:33:15.188021 systemd[1]: Finished systemd-journal-flush.service - Flush Journal to Persistent Storage.
Oct 13 05:33:15.198411 kernel: loop1: detected capacity change from 0 to 128048
Oct 13 05:33:15.233609 systemd[1]: Finished systemd-sysctl.service - Apply Kernel Variables.
Oct 13 05:33:15.279657 systemd[1]: Finished systemd-machine-id-commit.service - Save Transient machine-id to Disk.
Oct 13 05:33:15.324103 kernel: ACPI: bus type drm_connector registered
Oct 13 05:33:15.323508 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 13 05:33:15.323660 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Oct 13 05:33:15.398070 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Oct 13 05:33:15.859603 systemd[1]: Finished systemd-sysusers.service - Create System Users.
Oct 13 05:33:15.867511 systemd[1]: Starting systemd-resolved.service - Network Name Resolution...
Oct 13 05:33:15.871492 systemd[1]: Starting systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev...
Oct 13 05:33:15.979918 systemd[1]: Starting systemd-userdbd.service - User Database Manager...
Oct 13 05:33:15.996115 systemd-tmpfiles[2137]: ACLs are not supported, ignoring.
Oct 13 05:33:15.996129 systemd-tmpfiles[2137]: ACLs are not supported, ignoring.
Oct 13 05:33:15.999158 systemd[1]: Finished systemd-tmpfiles-setup-dev.service - Create Static Device Nodes in /dev.
Oct 13 05:33:16.025729 systemd[1]: Started systemd-userdbd.service - User Database Manager.
Oct 13 05:33:16.161347 systemd-resolved[2136]: Positive Trust Anchors:
Oct 13 05:33:16.161359 systemd-resolved[2136]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 13 05:33:16.161363 systemd-resolved[2136]: . IN DS 38696 8 2 683d2d0acb8c9b712a1948b27f741219298d0a450d612c483af444a4c0fb2b16
Oct 13 05:33:16.161408 systemd-resolved[2136]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 170.0.0.192.in-addr.arpa 171.0.0.192.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa ipv4only.arpa resolver.arpa corp home internal intranet lan local private test
Oct 13 05:33:16.196934 systemd[1]: Finished systemd-hwdb-update.service - Rebuild Hardware Database.
Oct 13 05:33:16.201396 systemd[1]: Starting systemd-udevd.service - Rule-based Manager for Device Events and Files...
Oct 13 05:33:16.226575 systemd-udevd[2148]: Using default interface naming scheme 'v257'.
Oct 13 05:33:16.315407 kernel: loop2: detected capacity change from 0 to 110984
Oct 13 05:33:16.393700 systemd-resolved[2136]: Using system hostname 'ci-4487.0.0-a-69b001b486'.
Oct 13 05:33:16.394641 systemd[1]: Started systemd-resolved.service - Network Name Resolution.
Oct 13 05:33:16.397482 systemd[1]: Reached target nss-lookup.target - Host and Network Name Lookups.
Oct 13 05:33:16.796414 kernel: loop3: detected capacity change from 0 to 27752
Oct 13 05:33:17.116557 systemd[1]: Started systemd-udevd.service - Rule-based Manager for Device Events and Files.
Oct 13 05:33:17.121510 systemd[1]: Starting systemd-networkd.service - Network Configuration...
Oct 13 05:33:17.178696 systemd[1]: Condition check resulted in dev-ttyS0.device - /dev/ttyS0 being skipped.
Oct 13 05:33:17.221401 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#16 cmd 0x85 status: scsi 0x2 srb 0x6 hv 0xc0000001
Oct 13 05:33:17.223477 kernel: hv_vmbus: registering driver hyperv_fb
Oct 13 05:33:17.227620 kernel: hyperv_fb: Synthvid Version major 3, minor 5
Oct 13 05:33:17.227688 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
Oct 13 05:33:17.229060 kernel: Console: switching to colour dummy device 80x25
Oct 13 05:33:17.232405 kernel: Console: switching to colour frame buffer device 128x48
Oct 13 05:33:17.282410 kernel: hv_vmbus: registering driver hv_balloon
Oct 13 05:33:17.296391 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
Oct 13 05:33:17.314137 systemd-networkd[2156]: lo: Link UP
Oct 13 05:33:17.315012 systemd-networkd[2156]: lo: Gained carrier
Oct 13 05:33:17.317362 systemd[1]: Started systemd-networkd.service - Network Configuration.
Oct 13 05:33:17.320530 systemd[1]: Reached target network.target - Network.
Oct 13 05:33:17.323492 systemd-networkd[2156]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network
Oct 13 05:33:17.324519 systemd[1]: Starting systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd...
Oct 13 05:33:17.327642 systemd-networkd[2156]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 13 05:33:17.329496 systemd[1]: Starting systemd-networkd-wait-online.service - Wait for Network to be Configured...
Oct 13 05:33:17.335796 kernel: mana 7870:00:00.0 enP30832s1: Configured vPort 0 PD 18 DB 16
Oct 13 05:33:17.336024 kernel: mousedev: PS/2 mouse device common for all mice
Oct 13 05:33:17.343399 kernel: mana 7870:00:00.0 enP30832s1: Configured steering vPort 0 entries 64
Oct 13 05:33:17.344278 kernel: hv_netvsc f8615163-0000-1000-2000-7ced8d2cf45d eth0: Data path switched to VF: enP30832s1
Oct 13 05:33:17.345790 systemd-networkd[2156]: enP30832s1: Link UP
Oct 13 05:33:17.346198 systemd-networkd[2156]: eth0: Link UP
Oct 13 05:33:17.346207 systemd-networkd[2156]: eth0: Gained carrier
Oct 13 05:33:17.346219 systemd-networkd[2156]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network
Oct 13 05:33:17.352710 systemd-networkd[2156]: enP30832s1: Gained carrier
Oct 13 05:33:17.354183 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:33:17.364429 systemd-networkd[2156]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16
Oct 13 05:33:17.369409 kernel: loop4: detected capacity change from 0 to 128048
Oct 13 05:33:17.384604 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 13 05:33:17.384753 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:33:17.393576 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:33:17.399395 kernel: loop5: detected capacity change from 0 to 110984
Oct 13 05:33:17.406916 systemd[1]: Finished systemd-networkd-persistent-storage.service - Enable Persistent Storage in systemd-networkd.
Oct 13 05:33:17.416582 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 13 05:33:17.416736 systemd[1]: Stopped systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:33:17.422413 systemd[1]: Starting systemd-vconsole-setup.service - Virtual Console Setup...
Oct 13 05:33:17.433403 kernel: loop6: detected capacity change from 0 to 27752
Oct 13 05:33:17.457920 (sd-merge)[2216]: Using extensions 'containerd-flatcar.raw', 'docker-flatcar.raw', 'oem-azure.raw'.
Oct 13 05:33:17.461406 (sd-merge)[2216]: Merged extensions into '/usr'.
Oct 13 05:33:17.477253 systemd[1]: Finished systemd-sysext.service - Merge System Extension Images into /usr/ and /opt/.
Oct 13 05:33:17.480533 systemd[1]: Starting ensure-sysext.service...
Oct 13 05:33:17.484306 systemd[1]: Starting systemd-tmpfiles-setup.service - Create System Files and Directories...
Oct 13 05:33:17.496656 systemd[1]: Reload requested from client PID 2227 ('systemctl') (unit ensure-sysext.service)...
Oct 13 05:33:17.496742 systemd[1]: Reloading...
Oct 13 05:33:17.548394 zram_generator::config[2261]: No configuration found.
Oct 13 05:33:17.592570 kernel: kvm_intel: Using Hyper-V Enlightened VMCS
Oct 13 05:33:17.600129 systemd-tmpfiles[2228]: /usr/lib/tmpfiles.d/nfs-utils.conf:6: Duplicate line for path "/var/lib/nfs/sm", ignoring.
Oct 13 05:33:17.600152 systemd-tmpfiles[2228]: /usr/lib/tmpfiles.d/nfs-utils.conf:7: Duplicate line for path "/var/lib/nfs/sm.bak", ignoring.
Oct 13 05:33:17.600363 systemd-tmpfiles[2228]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Oct 13 05:33:17.600629 systemd-tmpfiles[2228]: /usr/lib/tmpfiles.d/systemd-flatcar.conf:6: Duplicate line for path "/var/log/journal", ignoring.
Oct 13 05:33:17.601214 systemd-tmpfiles[2228]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Oct 13 05:33:17.601603 systemd-tmpfiles[2228]: ACLs are not supported, ignoring.
Oct 13 05:33:17.601704 systemd-tmpfiles[2228]: ACLs are not supported, ignoring.
Oct 13 05:33:17.652785 systemd-tmpfiles[2228]: Detected autofs mount point /boot during canonicalization of boot.
Oct 13 05:33:17.652893 systemd-tmpfiles[2228]: Skipping /boot
Oct 13 05:33:17.663451 systemd-tmpfiles[2228]: Detected autofs mount point /boot during canonicalization of boot.
Oct 13 05:33:17.663524 systemd-tmpfiles[2228]: Skipping /boot
Oct 13 05:33:17.722890 systemd[1]: Reloading finished in 225 ms.
Oct 13 05:33:17.738329 systemd[1]: Finished systemd-tmpfiles-setup.service - Create System Files and Directories.
Oct 13 05:33:17.766896 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device - MSFT NVMe Accelerator v1.0 OEM.
Oct 13 05:33:17.787255 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Oct 13 05:33:17.790564 systemd[1]: Starting clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs...
Oct 13 05:33:17.791219 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 13 05:33:17.796559 systemd[1]: Starting ldconfig.service - Rebuild Dynamic Linker Cache...
Oct 13 05:33:17.799870 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Oct 13 05:33:17.803588 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Oct 13 05:33:17.805801 systemd[1]: Starting modprobe@loop.service - Load Kernel Module loop...
Oct 13 05:33:17.806255 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 13 05:33:17.809199 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM...
Oct 13 05:33:17.809665 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).
Oct 13 05:33:17.812608 systemd[1]: Starting systemd-journal-catalog-update.service - Rebuild Journal Catalog...
Oct 13 05:33:17.815248 systemd[1]: Starting systemd-update-utmp.service - Record System Boot/Shutdown in UTMP...
Oct 13 05:33:17.819326 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 13 05:33:17.819924 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 13 05:33:17.820073 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).
Oct 13 05:33:17.822705 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 13 05:33:17.823258 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Oct 13 05:33:17.824113 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 13 05:33:17.824234 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Oct 13 05:33:17.824879 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 13 05:33:17.830404 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 13 05:33:17.830602 systemd[1]: Finished modprobe@loop.service - Load Kernel Module loop.
Oct 13 05:33:17.832186 systemd[1]: ignition-delete-config.service - Ignition (delete config) was skipped because no trigger condition checks were met.
Oct 13 05:33:17.834210 systemd[1]: Starting modprobe@dm_mod.service - Load Kernel Module dm_mod...
Oct 13 05:33:17.835976 systemd[1]: Starting modprobe@drm.service - Load Kernel Module drm...
Oct 13 05:33:17.839905 systemd[1]: Starting modprobe@efi_pstore.service - Load Kernel Module efi_pstore...
Oct 13 05:33:17.840413 systemd[1]: systemd-binfmt.service - Set Up Additional Binary Formats was skipped because no trigger condition checks were met.
Oct 13 05:33:17.841479 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).
Oct 13 05:33:17.841619 systemd[1]: Reached target time-set.target - System Time Set.
Oct 13 05:33:17.845819 systemd[1]: Finished ensure-sysext.service.
Oct 13 05:33:17.851844 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 13 05:33:17.854604 systemd[1]: Finished modprobe@drm.service - Load Kernel Module drm.
Oct 13 05:33:17.857645 systemd[1]: Finished systemd-update-utmp.service - Record System Boot/Shutdown in UTMP.
Oct 13 05:33:17.858525 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 13 05:33:17.858652 systemd[1]: Finished modprobe@dm_mod.service - Load Kernel Module dm_mod.
Oct 13 05:33:17.859788 systemd[1]: systemd-repart.service - Repartition Root Disk was skipped because no trigger condition checks were met.
Oct 13 05:33:17.863200 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 13 05:33:17.863518 systemd[1]: Finished modprobe@efi_pstore.service - Load Kernel Module efi_pstore.
Oct 13 05:33:17.865323 systemd[1]: systemd-pstore.service - Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 13 05:33:17.939849 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service - File System Check on /dev/disk/by-label/OEM.
Oct 13 05:33:18.141220 systemd[1]: proc-xen.mount - /proc/xen was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 13 05:33:18.141240 systemd[1]: xenserver-pv-version.service - Set fake PV driver version for XenServer was skipped because of an unmet condition check (ConditionVirtualization=xen).
Oct 13 05:33:18.154226 systemd[1]: Finished systemd-journal-catalog-update.service - Rebuild Journal Catalog.
Oct 13 05:33:18.454298 augenrules[2373]: No rules
Oct 13 05:33:18.455057 systemd[1]: audit-rules.service: Deactivated successfully.
Oct 13 05:33:18.455197 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Oct 13 05:33:18.499516 systemd-networkd[2156]: eth0: Gained IPv6LL
Oct 13 05:33:18.501089 systemd[1]: Finished systemd-networkd-wait-online.service - Wait for Network to be Configured.
Oct 13 05:33:18.501698 systemd[1]: Reached target network-online.target - Network is Online.
Oct 13 05:33:19.110587 systemd[1]: Finished systemd-vconsole-setup.service - Virtual Console Setup.
Oct 13 05:33:20.602503 systemd[1]: Finished clean-ca-certificates.service - Clean up broken links in /etc/ssl/certs.
Oct 13 05:33:20.604236 systemd[1]: update-ca-certificates.service - Update CA bundle at /etc/ssl/certs/ca-certificates.crt was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Oct 13 05:33:27.732812 ldconfig[2333]: /sbin/ldconfig: /usr/lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Oct 13 05:33:27.741797 systemd[1]: Finished ldconfig.service - Rebuild Dynamic Linker Cache.
Oct 13 05:33:27.744666 systemd[1]: Starting systemd-update-done.service - Update is Completed...
Oct 13 05:33:27.770217 systemd[1]: Finished systemd-update-done.service - Update is Completed.
Oct 13 05:33:27.771937 systemd[1]: Reached target sysinit.target - System Initialization.
Oct 13 05:33:27.775566 systemd[1]: Started motdgen.path - Watch for update engine configuration changes.
Oct 13 05:33:27.779469 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path - Watch for a cloud-config at /var/lib/flatcar-install/user_data.
Oct 13 05:33:27.782447 systemd[1]: Started google-oslogin-cache.timer - NSS cache refresh timer.
Oct 13 05:33:27.785520 systemd[1]: Started logrotate.timer - Daily rotation of log files.
Oct 13 05:33:27.786934 systemd[1]: Started mdadm.timer - Weekly check for MD array's redundancy information..
Oct 13 05:33:27.788469 systemd[1]: Started systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories.
Oct 13 05:33:27.790097 systemd[1]: update-engine-stub.timer - Update Engine Stub Timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Oct 13 05:33:27.790127 systemd[1]: Reached target paths.target - Path Units.
Oct 13 05:33:27.791262 systemd[1]: Reached target timers.target - Timer Units.
Oct 13 05:33:27.830194 systemd[1]: Listening on dbus.socket - D-Bus System Message Bus Socket.
Oct 13 05:33:27.834275 systemd[1]: Starting docker.socket - Docker Socket for the API...
Oct 13 05:33:27.838765 systemd[1]: Listening on sshd-unix-local.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_UNIX Local).
Oct 13 05:33:27.840601 systemd[1]: Listening on sshd-vsock.socket - OpenSSH Server Socket (systemd-ssh-generator, AF_VSOCK).
Oct 13 05:33:27.843471 systemd[1]: Reached target ssh-access.target - SSH Access Available.
Oct 13 05:33:27.846174 systemd[1]: Listening on sshd.socket - OpenSSH Server Socket.
Oct 13 05:33:27.849684 systemd[1]: Listening on systemd-hostnamed.socket - Hostname Service Socket.
Oct 13 05:33:27.852870 systemd[1]: Listening on docker.socket - Docker Socket for the API.
Oct 13 05:33:27.854918 systemd[1]: Reached target sockets.target - Socket Units.
Oct 13 05:33:27.856241 systemd[1]: Reached target basic.target - Basic System.
Oct 13 05:33:27.858456 systemd[1]: addon-config@oem.service - Configure Addon /oem was skipped because no trigger condition checks were met.
Oct 13 05:33:27.858483 systemd[1]: addon-run@oem.service - Run Addon /oem was skipped because no trigger condition checks were met.
Oct 13 05:33:27.875172 systemd[1]: Starting chronyd.service - NTP client/server...
Oct 13 05:33:27.879414 systemd[1]: Starting containerd.service - containerd container runtime...
Oct 13 05:33:27.883975 systemd[1]: Starting coreos-metadata.service - Flatcar Metadata Agent...
Oct 13 05:33:27.887223 systemd[1]: Starting dbus.service - D-Bus System Message Bus...
Oct 13 05:33:27.893101 systemd[1]: Starting dracut-shutdown.service - Restore /run/initramfs on shutdown...
Oct 13 05:33:27.896448 systemd[1]: Starting enable-oem-cloudinit.service - Enable cloudinit...
Oct 13 05:33:27.903435 systemd[1]: Starting extend-filesystems.service - Extend Filesystems...
Oct 13 05:33:27.906645 systemd[1]: flatcar-setup-environment.service - Modifies /etc/environment for CoreOS was skipped because of an unmet condition check (ConditionPathExists=/oem/bin/flatcar-setup-environment).
Oct 13 05:33:27.909549 systemd[1]: Starting google-oslogin-cache.service - NSS cache refresh...
Oct 13 05:33:27.911940 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).
Oct 13 05:33:27.915401 systemd[1]: Started hv_kvp_daemon.service - Hyper-V KVP daemon.
Oct 13 05:33:27.917936 systemd[1]: hv_vss_daemon.service - Hyper-V VSS daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/vmbus/hv_vss).
Oct 13 05:33:27.922591 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Oct 13 05:33:27.922669 jq[2397]: false
Oct 13 05:33:27.926573 systemd[1]: Starting nvidia.service - NVIDIA Configure Service...
Oct 13 05:33:27.928784 systemd[1]: Starting ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline...
Oct 13 05:33:27.933449 systemd[1]: Starting sshd-keygen.service - Generate sshd host keys...
Oct 13 05:33:27.938871 systemd[1]: Starting systemd-logind.service - User Login Management...
Oct 13 05:33:27.942675 systemd[1]: tcsd.service - TCG Core Services Daemon was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Oct 13 05:33:27.943048 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Oct 13 05:33:27.944502 systemd[1]: Starting update-engine.service - Update Engine...
Oct 13 05:33:27.950741 systemd[1]: Starting update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition...
Oct 13 05:33:27.956072 systemd[1]: Finished dracut-shutdown.service - Restore /run/initramfs on shutdown.
Oct 13 05:33:27.958495 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Oct 13 05:33:27.958654 systemd[1]: Condition check resulted in enable-oem-cloudinit.service - Enable cloudinit being skipped.
Oct 13 05:33:27.962648 jq[2409]: true
Oct 13 05:33:27.967482 KVP[2400]: KVP starting; pid is:2400
Oct 13 05:33:27.969184 chronyd[2389]: chronyd version 4.7 starting (+CMDMON +REFCLOCK +RTC +PRIVDROP +SCFILTER -SIGND +NTS +SECHASH +IPV6 -DEBUG)
Oct 13 05:33:27.970702 KVP[2400]: KVP LIC Version: 3.1
Oct 13 05:33:27.971405 kernel: hv_utils: KVP IC version 4.0
Oct 13 05:33:27.972840 jq[2416]: true
Oct 13 05:33:27.990423 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Refreshing passwd entry cache
Oct 13 05:33:27.989051 oslogin_cache_refresh[2399]: Refreshing passwd entry cache
Oct 13 05:33:27.995148 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Oct 13 05:33:27.995309 systemd[1]: Finished ssh-key-proc-cmdline.service - Install an ssh key from /proc/cmdline.
Oct 13 05:33:28.011666 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Failure getting users, quitting
Oct 13 05:33:28.011666 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Oct 13 05:33:28.011659 oslogin_cache_refresh[2399]: Failure getting users, quitting
Oct 13 05:33:28.011784 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Refreshing group entry cache
Oct 13 05:33:28.011672 oslogin_cache_refresh[2399]: Produced empty passwd cache file, removing /etc/oslogin_passwd.cache.bak.
Oct 13 05:33:28.011706 oslogin_cache_refresh[2399]: Refreshing group entry cache
Oct 13 05:33:28.014640 update_engine[2406]: I20251013 05:33:28.014392  2406 main.cc:92] Flatcar Update Engine starting
Oct 13 05:33:28.022643 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Failure getting groups, quitting
Oct 13 05:33:28.022699 oslogin_cache_refresh[2399]: Failure getting groups, quitting
Oct 13 05:33:28.023422 google_oslogin_nss_cache[2399]: oslogin_cache_refresh[2399]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Oct 13 05:33:28.022707 oslogin_cache_refresh[2399]: Produced empty group cache file, removing /etc/oslogin_group.cache.bak.
Oct 13 05:33:28.023719 systemd[1]: google-oslogin-cache.service: Deactivated successfully.
Oct 13 05:33:28.023861 systemd[1]: Finished google-oslogin-cache.service - NSS cache refresh.
Oct 13 05:33:28.055357 chronyd[2389]: Timezone right/UTC failed leap second check, ignoring
Oct 13 05:33:28.055493 chronyd[2389]: Loaded seccomp filter (level 2)
Oct 13 05:33:28.055579 systemd[1]: Started chronyd.service - NTP client/server.
Oct 13 05:33:28.069137 extend-filesystems[2398]: Found /dev/nvme0n1p6
Oct 13 05:33:28.080872 sshd_keygen[2422]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Oct 13 05:33:28.081156 systemd[1]: motdgen.service: Deactivated successfully.
Oct 13 05:33:28.081519 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Oct 13 05:33:28.086899 extend-filesystems[2398]: Found /dev/nvme0n1p9
Oct 13 05:33:28.090251 extend-filesystems[2398]: Checking size of /dev/nvme0n1p9
Oct 13 05:33:28.091636 (ntainerd)[2451]: containerd.service: Referenced but unset environment variable evaluates to an empty string: TORCX_IMAGEDIR, TORCX_UNPACKDIR
Oct 13 05:33:28.101761 systemd[1]: Finished sshd-keygen.service - Generate sshd host keys.
Oct 13 05:33:28.104422 systemd[1]: Starting issuegen.service - Generate /run/issue...
Oct 13 05:33:28.107715 systemd[1]: Starting waagent.service - Microsoft Azure Linux Agent...
Oct 13 05:33:28.119784 systemd[1]: issuegen.service: Deactivated successfully.
Oct 13 05:33:28.119935 systemd[1]: Finished issuegen.service - Generate /run/issue.
Oct 13 05:33:28.122004 systemd[1]: Starting systemd-user-sessions.service - Permit User Sessions...
Oct 13 05:33:28.128935 systemd-logind[2405]: New seat seat0.
Oct 13 05:33:28.176291 systemd[1]: Finished systemd-user-sessions.service - Permit User Sessions.
Oct 13 05:33:28.176492 systemd-logind[2405]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard)
Oct 13 05:33:28.179577 systemd[1]: Started systemd-logind.service - User Login Management.
Oct 13 05:33:28.185782 systemd[1]: Started getty@tty1.service - Getty on tty1.
Oct 13 05:33:28.190455 systemd[1]: Started serial-getty@ttyS0.service - Serial Getty on ttyS0.
Oct 13 05:33:28.193255 systemd[1]: Reached target getty.target - Login Prompts.
Oct 13 05:33:28.202366 systemd[1]: Finished nvidia.service - NVIDIA Configure Service.
Oct 13 05:33:28.208038 bash[2444]: Updated "/home/core/.ssh/authorized_keys"
Oct 13 05:33:28.209939 systemd[1]: Finished update-ssh-keys-after-ignition.service - Run update-ssh-keys once after Ignition.
Oct 13 05:33:28.213107 systemd[1]: sshkeys.service was skipped because no trigger condition checks were met.
Oct 13 05:33:28.215652 extend-filesystems[2398]: Resized partition /dev/nvme0n1p9
Oct 13 05:33:28.217795 systemd[1]: Started waagent.service - Microsoft Azure Linux Agent.
Oct 13 05:33:28.275525 extend-filesystems[2501]: resize2fs 1.47.3 (8-Jul-2025)
Oct 13 05:33:28.323399 kernel: EXT4-fs (nvme0n1p9): resizing filesystem from 7359488 to 7376891 blocks
Oct 13 05:33:28.337389 kernel: EXT4-fs (nvme0n1p9): resized filesystem to 7376891
Oct 13 05:33:28.386952 extend-filesystems[2501]: Filesystem at /dev/nvme0n1p9 is mounted on /; on-line resizing required
Oct 13 05:33:28.386952 extend-filesystems[2501]: old_desc_blocks = 4, new_desc_blocks = 4
Oct 13 05:33:28.386952 extend-filesystems[2501]: The filesystem on /dev/nvme0n1p9 is now 7376891 (4k) blocks long.
Oct 13 05:33:28.395053 extend-filesystems[2398]: Resized filesystem in /dev/nvme0n1p9
Oct 13 05:33:28.387526 systemd[1]: extend-filesystems.service: Deactivated successfully.
Oct 13 05:33:28.387697 systemd[1]: Finished extend-filesystems.service - Extend Filesystems.
Oct 13 05:33:28.447761 dbus-daemon[2392]: [system] SELinux support is enabled
Oct 13 05:33:28.448217 systemd[1]: Started dbus.service - D-Bus System Message Bus.
Oct 13 05:33:28.453808 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service - Load cloud-config from /usr/share/oem/cloud-config.yml was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml).
Oct 13 05:33:28.453841 systemd[1]: Reached target system-config.target - Load system-provided cloud configs.
Oct 13 05:33:28.456304 update_engine[2406]: I20251013 05:33:28.454439  2406 update_check_scheduler.cc:74] Next update check in 9m34s
Oct 13 05:33:28.455603 dbus-daemon[2392]: [system] Successfully activated service 'org.freedesktop.systemd1'
Oct 13 05:33:28.456314 systemd[1]: user-cloudinit-proc-cmdline.service - Load cloud-config from url defined in /proc/cmdline was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Oct 13 05:33:28.456334 systemd[1]: Reached target user-config.target - Load user-provided cloud configs.
Oct 13 05:33:28.458216 systemd[1]: Started update-engine.service - Update Engine.
Oct 13 05:33:28.463257 systemd[1]: Started locksmithd.service - Cluster reboot manager.
Oct 13 05:33:28.549189 coreos-metadata[2391]: Oct 13 05:33:28.549 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Oct 13 05:33:28.552572 coreos-metadata[2391]: Oct 13 05:33:28.552 INFO Fetch successful
Oct 13 05:33:28.552720 coreos-metadata[2391]: Oct 13 05:33:28.552 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1
Oct 13 05:33:28.559002 coreos-metadata[2391]: Oct 13 05:33:28.558 INFO Fetch successful
Oct 13 05:33:28.559252 coreos-metadata[2391]: Oct 13 05:33:28.559 INFO Fetching http://168.63.129.16/machine/fdc7c586-6cbb-4c35-a69d-b47314eaea4e/d5324ce9%2Da941%2D49e1%2D98bc%2D6e5b419f9952.%5Fci%2D4487.0.0%2Da%2D69b001b486?comp=config&type=sharedConfig&incarnation=1: Attempt #1
Oct 13 05:33:28.561799 coreos-metadata[2391]: Oct 13 05:33:28.561 INFO Fetch successful
Oct 13 05:33:28.561799 coreos-metadata[2391]: Oct 13 05:33:28.561 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1
Oct 13 05:33:28.572795 coreos-metadata[2391]: Oct 13 05:33:28.572 INFO Fetch successful
Oct 13 05:33:28.594565 systemd[1]: Finished coreos-metadata.service - Flatcar Metadata Agent.
Oct 13 05:33:28.598016 systemd[1]: packet-phone-home.service - Report Success to Packet was skipped because no trigger condition checks were met.
Oct 13 05:33:28.728939 locksmithd[2516]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Oct 13 05:33:29.324490 containerd[2451]: time="2025-10-13T05:33:29Z" 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
Oct 13 05:33:29.325046 containerd[2451]: time="2025-10-13T05:33:29.325014310Z" level=info msg="starting containerd" revision=fb4c30d4ede3531652d86197bf3fc9515e5276d9 version=v2.0.5
Oct 13 05:33:29.330385 containerd[2451]: time="2025-10-13T05:33:29.330351368Z" level=warning msg="Configuration migrated from version 2, use `containerd config migrate` to avoid migration" t="6.226µs"
Oct 13 05:33:29.330385 containerd[2451]: time="2025-10-13T05:33:29.330373456Z" level=info msg="loading plugin" id=io.containerd.image-verifier.v1.bindir type=io.containerd.image-verifier.v1
Oct 13 05:33:29.330495 containerd[2451]: time="2025-10-13T05:33:29.330398422Z" level=info msg="loading plugin" id=io.containerd.internal.v1.opt type=io.containerd.internal.v1
Oct 13 05:33:29.330524 containerd[2451]: time="2025-10-13T05:33:29.330512954Z" level=info msg="loading plugin" id=io.containerd.warning.v1.deprecations type=io.containerd.warning.v1
Oct 13 05:33:29.330543 containerd[2451]: time="2025-10-13T05:33:29.330524250Z" level=info msg="loading plugin" id=io.containerd.content.v1.content type=io.containerd.content.v1
Oct 13 05:33:29.330558 containerd[2451]: time="2025-10-13T05:33:29.330544177Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330616 containerd[2451]: time="2025-10-13T05:33:29.330586148Z" level=info msg="skip loading plugin" error="no scratch file generator: skip plugin" id=io.containerd.snapshotter.v1.blockfile type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330616 containerd[2451]: time="2025-10-13T05:33:29.330610136Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.btrfs type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330775 containerd[2451]: time="2025-10-13T05:33:29.330756930Z" 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
Oct 13 05:33:29.330775 containerd[2451]: time="2025-10-13T05:33:29.330769283Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330823 containerd[2451]: time="2025-10-13T05:33:29.330778943Z" level=info msg="skip loading plugin" error="devmapper not configured: skip plugin" id=io.containerd.snapshotter.v1.devmapper type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330823 containerd[2451]: time="2025-10-13T05:33:29.330786005Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.native type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330862 containerd[2451]: time="2025-10-13T05:33:29.330836665Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.overlayfs type=io.containerd.snapshotter.v1
Oct 13 05:33:29.330992 containerd[2451]: time="2025-10-13T05:33:29.330976706Z" level=info msg="loading plugin" id=io.containerd.snapshotter.v1.zfs type=io.containerd.snapshotter.v1
Oct 13 05:33:29.331013 containerd[2451]: time="2025-10-13T05:33:29.330996650Z" 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
Oct 13 05:33:29.331013 containerd[2451]: time="2025-10-13T05:33:29.331005651Z" level=info msg="loading plugin" id=io.containerd.event.v1.exchange type=io.containerd.event.v1
Oct 13 05:33:29.331059 containerd[2451]: time="2025-10-13T05:33:29.331044410Z" level=info msg="loading plugin" id=io.containerd.monitor.task.v1.cgroups type=io.containerd.monitor.task.v1
Oct 13 05:33:29.331345 containerd[2451]: time="2025-10-13T05:33:29.331329070Z" level=info msg="loading plugin" id=io.containerd.metadata.v1.bolt type=io.containerd.metadata.v1
Oct 13 05:33:29.331420 containerd[2451]: time="2025-10-13T05:33:29.331396222Z" level=info msg="metadata content store policy set" policy=shared
Oct 13 05:33:29.346813 containerd[2451]: time="2025-10-13T05:33:29.346789264Z" level=info msg="loading plugin" id=io.containerd.gc.v1.scheduler type=io.containerd.gc.v1
Oct 13 05:33:29.346868 containerd[2451]: time="2025-10-13T05:33:29.346824374Z" level=info msg="loading plugin" id=io.containerd.differ.v1.walking type=io.containerd.differ.v1
Oct 13 05:33:29.346868 containerd[2451]: time="2025-10-13T05:33:29.346841424Z" level=info msg="loading plugin" id=io.containerd.lease.v1.manager type=io.containerd.lease.v1
Oct 13 05:33:29.346868 containerd[2451]: time="2025-10-13T05:33:29.346854027Z" level=info msg="loading plugin" id=io.containerd.service.v1.containers-service type=io.containerd.service.v1
Oct 13 05:33:29.346868 containerd[2451]: time="2025-10-13T05:33:29.346864339Z" level=info msg="loading plugin" id=io.containerd.service.v1.content-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346873224Z" level=info msg="loading plugin" id=io.containerd.service.v1.diff-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346884526Z" level=info msg="loading plugin" id=io.containerd.service.v1.images-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346899401Z" level=info msg="loading plugin" id=io.containerd.service.v1.introspection-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346909859Z" level=info msg="loading plugin" id=io.containerd.service.v1.namespaces-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346919208Z" level=info msg="loading plugin" id=io.containerd.service.v1.snapshots-service type=io.containerd.service.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346927204Z" level=info msg="loading plugin" id=io.containerd.shim.v1.manager type=io.containerd.shim.v1
Oct 13 05:33:29.346944 containerd[2451]: time="2025-10-13T05:33:29.346937681Z" level=info msg="loading plugin" id=io.containerd.runtime.v2.task type=io.containerd.runtime.v2
Oct 13 05:33:29.347055 containerd[2451]: time="2025-10-13T05:33:29.347019352Z" level=info msg="loading plugin" id=io.containerd.service.v1.tasks-service type=io.containerd.service.v1
Oct 13 05:33:29.347055 containerd[2451]: time="2025-10-13T05:33:29.347034229Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.containers type=io.containerd.grpc.v1
Oct 13 05:33:29.347055 containerd[2451]: time="2025-10-13T05:33:29.347047042Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.content type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347055834Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.diff type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347064203Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.events type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347073120Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.images type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347082809Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.introspection type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347091039Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.leases type=io.containerd.grpc.v1
Oct 13 05:33:29.347103 containerd[2451]: time="2025-10-13T05:33:29.347100522Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.namespaces type=io.containerd.grpc.v1
Oct 13 05:33:29.347197 containerd[2451]: time="2025-10-13T05:33:29.347109441Z" level=info msg="loading plugin" id=io.containerd.sandbox.store.v1.local type=io.containerd.sandbox.store.v1
Oct 13 05:33:29.347197 containerd[2451]: time="2025-10-13T05:33:29.347118247Z" level=info msg="loading plugin" id=io.containerd.cri.v1.images type=io.containerd.cri.v1
Oct 13 05:33:29.347197 containerd[2451]: time="2025-10-13T05:33:29.347167900Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\" for snapshotter \"overlayfs\""
Oct 13 05:33:29.347197 containerd[2451]: time="2025-10-13T05:33:29.347178658Z" level=info msg="Start snapshots syncer"
Oct 13 05:33:29.347259 containerd[2451]: time="2025-10-13T05:33:29.347205328Z" level=info msg="loading plugin" id=io.containerd.cri.v1.runtime type=io.containerd.cri.v1
Oct 13 05:33:29.347457 containerd[2451]: time="2025-10-13T05:33:29.347429204Z" 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\"}"
Oct 13 05:33:29.347562 containerd[2451]: time="2025-10-13T05:33:29.347468102Z" level=info msg="loading plugin" id=io.containerd.podsandbox.controller.v1.podsandbox type=io.containerd.podsandbox.controller.v1
Oct 13 05:33:29.347562 containerd[2451]: time="2025-10-13T05:33:29.347537405Z" level=info msg="loading plugin" id=io.containerd.sandbox.controller.v1.shim type=io.containerd.sandbox.controller.v1
Oct 13 05:33:29.347676 containerd[2451]: time="2025-10-13T05:33:29.347660866Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandbox-controllers type=io.containerd.grpc.v1
Oct 13 05:33:29.347697 containerd[2451]: time="2025-10-13T05:33:29.347679361Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.sandboxes type=io.containerd.grpc.v1
Oct 13 05:33:29.347697 containerd[2451]: time="2025-10-13T05:33:29.347688826Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.snapshots type=io.containerd.grpc.v1
Oct 13 05:33:29.347733 containerd[2451]: time="2025-10-13T05:33:29.347698187Z" level=info msg="loading plugin" id=io.containerd.streaming.v1.manager type=io.containerd.streaming.v1
Oct 13 05:33:29.347733 containerd[2451]: time="2025-10-13T05:33:29.347708751Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.streaming type=io.containerd.grpc.v1
Oct 13 05:33:29.347733 containerd[2451]: time="2025-10-13T05:33:29.347719289Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.tasks type=io.containerd.grpc.v1
Oct 13 05:33:29.347781 containerd[2451]: time="2025-10-13T05:33:29.347732082Z" level=info msg="loading plugin" id=io.containerd.transfer.v1.local type=io.containerd.transfer.v1
Oct 13 05:33:29.347781 containerd[2451]: time="2025-10-13T05:33:29.347753127Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.transfer type=io.containerd.grpc.v1
Oct 13 05:33:29.347781 containerd[2451]: time="2025-10-13T05:33:29.347762283Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.version type=io.containerd.grpc.v1
Oct 13 05:33:29.347781 containerd[2451]: time="2025-10-13T05:33:29.347771310Z" level=info msg="loading plugin" id=io.containerd.monitor.container.v1.restart type=io.containerd.monitor.container.v1
Oct 13 05:33:29.347844 containerd[2451]: time="2025-10-13T05:33:29.347805718Z" level=info msg="loading plugin" id=io.containerd.tracing.processor.v1.otlp type=io.containerd.tracing.processor.v1
Oct 13 05:33:29.347844 containerd[2451]: time="2025-10-13T05:33:29.347823414Z" 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
Oct 13 05:33:29.347844 containerd[2451]: time="2025-10-13T05:33:29.347831982Z" level=info msg="loading plugin" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Oct 13 05:33:29.347844 containerd[2451]: time="2025-10-13T05:33:29.347839696Z" level=info msg="skip loading plugin" error="skip plugin: tracing endpoint not configured" id=io.containerd.internal.v1.tracing type=io.containerd.internal.v1
Oct 13 05:33:29.347907 containerd[2451]: time="2025-10-13T05:33:29.347846008Z" level=info msg="loading plugin" id=io.containerd.ttrpc.v1.otelttrpc type=io.containerd.ttrpc.v1
Oct 13 05:33:29.347907 containerd[2451]: time="2025-10-13T05:33:29.347884524Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.healthcheck type=io.containerd.grpc.v1
Oct 13 05:33:29.347907 containerd[2451]: time="2025-10-13T05:33:29.347894004Z" level=info msg="loading plugin" id=io.containerd.nri.v1.nri type=io.containerd.nri.v1
Oct 13 05:33:29.347960 containerd[2451]: time="2025-10-13T05:33:29.347906762Z" level=info msg="runtime interface created"
Oct 13 05:33:29.347960 containerd[2451]: time="2025-10-13T05:33:29.347920621Z" level=info msg="created NRI interface"
Oct 13 05:33:29.347960 containerd[2451]: time="2025-10-13T05:33:29.347927471Z" level=info msg="loading plugin" id=io.containerd.grpc.v1.cri type=io.containerd.grpc.v1
Oct 13 05:33:29.347960 containerd[2451]: time="2025-10-13T05:33:29.347937401Z" level=info msg="Connect containerd service"
Oct 13 05:33:29.347960 containerd[2451]: time="2025-10-13T05:33:29.347957267Z" level=info msg="using experimental NRI integration - disable nri plugin to prevent this"
Oct 13 05:33:29.348525 containerd[2451]: time="2025-10-13T05:33:29.348501475Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Oct 13 05:33:30.148256 containerd[2451]: time="2025-10-13T05:33:30.148216369Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Oct 13 05:33:30.148256 containerd[2451]: time="2025-10-13T05:33:30.148268683Z" level=info msg=serving... address=/run/containerd/containerd.sock
Oct 13 05:33:30.148427 containerd[2451]: time="2025-10-13T05:33:30.148287514Z" level=info msg="Start subscribing containerd event"
Oct 13 05:33:30.148427 containerd[2451]: time="2025-10-13T05:33:30.148313111Z" level=info msg="Start recovering state"
Oct 13 05:33:30.148427 containerd[2451]: time="2025-10-13T05:33:30.148403409Z" level=info msg="Start event monitor"
Oct 13 05:33:30.148427 containerd[2451]: time="2025-10-13T05:33:30.148413039Z" level=info msg="Start cni network conf syncer for default"
Oct 13 05:33:30.148427 containerd[2451]: time="2025-10-13T05:33:30.148423965Z" level=info msg="Start streaming server"
Oct 13 05:33:30.148512 containerd[2451]: time="2025-10-13T05:33:30.148431847Z" level=info msg="Registered namespace \"k8s.io\" with NRI"
Oct 13 05:33:30.148512 containerd[2451]: time="2025-10-13T05:33:30.148438781Z" level=info msg="runtime interface starting up..."
Oct 13 05:33:30.148512 containerd[2451]: time="2025-10-13T05:33:30.148444600Z" level=info msg="starting plugins..."
Oct 13 05:33:30.148512 containerd[2451]: time="2025-10-13T05:33:30.148456220Z" level=info msg="Synchronizing NRI (plugin) with current runtime state"
Oct 13 05:33:30.148573 containerd[2451]: time="2025-10-13T05:33:30.148545134Z" level=info msg="containerd successfully booted in 0.824465s"
Oct 13 05:33:30.148922 systemd[1]: Started containerd.service - containerd container runtime.
Oct 13 05:33:30.151669 systemd[1]: Reached target multi-user.target - Multi-User System.
Oct 13 05:33:30.156702 systemd[1]: Startup finished in 4.875s (kernel) + 12.586s (initrd) + 21.826s (userspace) = 39.288s.
Oct 13 05:33:31.035372 login[2488]: pam_lastlog(login:session): file /var/log/lastlog is locked/write, retrying
Oct 13 05:33:31.035558 login[2487]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Oct 13 05:33:31.044393 systemd-logind[2405]: New session 2 of user core.
Oct 13 05:33:31.045232 systemd[1]: Created slice user-500.slice - User Slice of UID 500.
Oct 13 05:33:31.046547 systemd[1]: Starting user-runtime-dir@500.service - User Runtime Directory /run/user/500...
Oct 13 05:33:31.078655 systemd[1]: Finished user-runtime-dir@500.service - User Runtime Directory /run/user/500.
Oct 13 05:33:31.080432 systemd[1]: Starting user@500.service - User Manager for UID 500...
Oct 13 05:33:31.111552 (systemd)[2552]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Oct 13 05:33:31.113412 systemd-logind[2405]: New session c1 of user core.
Oct 13 05:33:31.205557 waagent[2500]: 2025-10-13T05:33:31.205498Z INFO Daemon Daemon Azure Linux Agent Version: 2.12.0.4
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.205895Z INFO Daemon Daemon OS: flatcar 4487.0.0
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.205982Z INFO Daemon Daemon Python: 3.11.13
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.206649Z INFO Daemon Daemon Run daemon
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.207023Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='4487.0.0'
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.207236Z INFO Daemon Daemon Using waagent for provisioning
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.207387Z INFO Daemon Daemon Activate resource disk
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.207590Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.208930Z INFO Daemon Daemon Found device: None
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.209074Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.209126Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.209813Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Oct 13 05:33:31.213415 waagent[2500]: 2025-10-13T05:33:31.209902Z INFO Daemon Daemon Running default provisioning handler
Oct 13 05:33:31.228834 waagent[2500]: 2025-10-13T05:33:31.228643Z 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.
Oct 13 05:33:31.234393 waagent[2500]: 2025-10-13T05:33:31.233404Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Oct 13 05:33:31.234393 waagent[2500]: 2025-10-13T05:33:31.233954Z INFO Daemon Daemon cloud-init is enabled: False
Oct 13 05:33:31.234393 waagent[2500]: 2025-10-13T05:33:31.234278Z INFO Daemon Daemon Copying ovf-env.xml
Oct 13 05:33:31.417034 waagent[2500]: 2025-10-13T05:33:31.416951Z INFO Daemon Daemon Successfully mounted dvd
Oct 13 05:33:31.457372 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
Oct 13 05:33:31.460575 waagent[2500]: 2025-10-13T05:33:31.460540Z INFO Daemon Daemon Detect protocol endpoint
Oct 13 05:33:31.461157 waagent[2500]: 2025-10-13T05:33:31.461131Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Oct 13 05:33:31.461858 waagent[2500]: 2025-10-13T05:33:31.461837Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
Oct 13 05:33:31.462164 waagent[2500]: 2025-10-13T05:33:31.462149Z INFO Daemon Daemon Test for route to 168.63.129.16
Oct 13 05:33:31.462355 waagent[2500]: 2025-10-13T05:33:31.462338Z INFO Daemon Daemon Route to 168.63.129.16 exists
Oct 13 05:33:31.462580 waagent[2500]: 2025-10-13T05:33:31.462564Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
Oct 13 05:33:31.498791 waagent[2500]: 2025-10-13T05:33:31.498764Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
Oct 13 05:33:31.501388 waagent[2500]: 2025-10-13T05:33:31.500998Z INFO Daemon Daemon Wire protocol version:2012-11-30
Oct 13 05:33:31.503333 waagent[2500]: 2025-10-13T05:33:31.503258Z INFO Daemon Daemon Server preferred version:2015-04-05
Oct 13 05:33:31.521224 systemd[2552]: Queued start job for default target default.target.
Oct 13 05:33:31.534954 systemd[2552]: Created slice app.slice - User Application Slice.
Oct 13 05:33:31.534980 systemd[2552]: Reached target paths.target - Paths.
Oct 13 05:33:31.535047 systemd[2552]: Reached target timers.target - Timers.
Oct 13 05:33:31.535895 systemd[2552]: Starting dbus.socket - D-Bus User Message Bus Socket...
Oct 13 05:33:31.543219 systemd[2552]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Oct 13 05:33:31.543349 systemd[2552]: Reached target sockets.target - Sockets.
Oct 13 05:33:31.543453 systemd[2552]: Reached target basic.target - Basic System.
Oct 13 05:33:31.543532 systemd[2552]: Reached target default.target - Main User Target.
Oct 13 05:33:31.543562 systemd[1]: Started user@500.service - User Manager for UID 500.
Oct 13 05:33:31.543633 systemd[2552]: Startup finished in 424ms.
Oct 13 05:33:31.546499 systemd[1]: Started session-2.scope - Session 2 of User core.
Oct 13 05:33:31.642356 waagent[2500]: 2025-10-13T05:33:31.642311Z INFO Daemon Daemon Initializing goal state during protocol detection
Oct 13 05:33:31.643298 waagent[2500]: 2025-10-13T05:33:31.642574Z INFO Daemon Daemon Forcing an update of the goal state.
Oct 13 05:33:31.647133 waagent[2500]: 2025-10-13T05:33:31.647106Z INFO Daemon Fetched a new incarnation for the WireServer goal state [incarnation 1]
Oct 13 05:33:31.690116 waagent[2500]: 2025-10-13T05:33:31.690052Z INFO Daemon Daemon HostGAPlugin version: 1.0.8.177
Oct 13 05:33:31.691903 waagent[2500]: 2025-10-13T05:33:31.690604Z INFO Daemon
Oct 13 05:33:31.691903 waagent[2500]: 2025-10-13T05:33:31.690858Z INFO Daemon Fetched new vmSettings [HostGAPlugin correlation ID: fa0112a8-98eb-4a2a-9b29-6f318604ff21 eTag: 5791192646590226337 source: Fabric]
Oct 13 05:33:31.691903 waagent[2500]: 2025-10-13T05:33:31.691082Z INFO Daemon The vmSettings originated via Fabric; will ignore them.
Oct 13 05:33:31.691903 waagent[2500]: 2025-10-13T05:33:31.691365Z INFO Daemon
Oct 13 05:33:31.691903 waagent[2500]: 2025-10-13T05:33:31.691524Z INFO Daemon Fetching full goal state from the WireServer [incarnation 1]
Oct 13 05:33:31.700823 waagent[2500]: 2025-10-13T05:33:31.700799Z INFO Daemon Daemon Downloading artifacts profile blob
Oct 13 05:33:31.876921 waagent[2500]: 2025-10-13T05:33:31.876880Z INFO Daemon Downloaded certificate {'thumbprint': 'D2C62DE76F70B087DDFC8F829B3EEFBCCCA16549', 'hasPrivateKey': True}
Oct 13 05:33:31.879502 waagent[2500]: 2025-10-13T05:33:31.879467Z INFO Daemon Fetch goal state completed
Oct 13 05:33:31.887328 waagent[2500]: 2025-10-13T05:33:31.887301Z INFO Daemon Daemon Starting provisioning
Oct 13 05:33:31.888339 waagent[2500]: 2025-10-13T05:33:31.888002Z INFO Daemon Daemon Handle ovf-env.xml.
Oct 13 05:33:31.889412 waagent[2500]: 2025-10-13T05:33:31.889222Z INFO Daemon Daemon Set hostname [ci-4487.0.0-a-69b001b486]
Oct 13 05:33:31.918724 waagent[2500]: 2025-10-13T05:33:31.918685Z INFO Daemon Daemon Publish hostname [ci-4487.0.0-a-69b001b486]
Oct 13 05:33:31.920224 waagent[2500]: 2025-10-13T05:33:31.920189Z INFO Daemon Daemon Examine /proc/net/route for primary interface
Oct 13 05:33:31.921850 waagent[2500]: 2025-10-13T05:33:31.921821Z INFO Daemon Daemon Primary interface is [eth0]
Oct 13 05:33:31.928195 systemd-networkd[2156]: eth0: Found matching .network file, based on potentially unpredictable interface name: /usr/lib/systemd/network/zz-default.network
Oct 13 05:33:31.928202 systemd-networkd[2156]: eth0: Reconfiguring with /usr/lib/systemd/network/zz-default.network.
Oct 13 05:33:31.928251 systemd-networkd[2156]: eth0: DHCP lease lost
Oct 13 05:33:31.945496 waagent[2500]: 2025-10-13T05:33:31.945432Z INFO Daemon Daemon Create user account if not exists
Oct 13 05:33:31.949036 waagent[2500]: 2025-10-13T05:33:31.946003Z INFO Daemon Daemon User core already exists, skip useradd
Oct 13 05:33:31.949036 waagent[2500]: 2025-10-13T05:33:31.946251Z INFO Daemon Daemon Configure sudoer
Oct 13 05:33:31.950608 waagent[2500]: 2025-10-13T05:33:31.950570Z INFO Daemon Daemon Configure sshd
Oct 13 05:33:31.952417 systemd-networkd[2156]: eth0: DHCPv4 address 10.200.8.19/24, gateway 10.200.8.1 acquired from 168.63.129.16
Oct 13 05:33:31.954463 waagent[2500]: 2025-10-13T05:33:31.954430Z INFO Daemon Daemon Added a configuration snippet disabling SSH password-based authentication methods. It also configures SSH client probing to keep connections alive.
Oct 13 05:33:31.957801 waagent[2500]: 2025-10-13T05:33:31.954913Z INFO Daemon Daemon Deploy ssh public key.
Oct 13 05:33:32.036625 login[2488]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Oct 13 05:33:32.040198 systemd-logind[2405]: New session 1 of user core.
Oct 13 05:33:32.049470 systemd[1]: Started session-1.scope - Session 1 of User core.
Oct 13 05:33:33.110350 waagent[2500]: 2025-10-13T05:33:33.110300Z INFO Daemon Daemon Provisioning complete
Oct 13 05:33:33.122164 waagent[2500]: 2025-10-13T05:33:33.122132Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
Oct 13 05:33:33.122851 waagent[2500]: 2025-10-13T05:33:33.122824Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
Oct 13 05:33:33.125768 waagent[2500]: 2025-10-13T05:33:33.125705Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.12.0.4 is the most current agent
Oct 13 05:33:33.215827 waagent[2601]: 2025-10-13T05:33:33.215765Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.4)
Oct 13 05:33:33.216086 waagent[2601]: 2025-10-13T05:33:33.215846Z INFO ExtHandler ExtHandler OS: flatcar 4487.0.0
Oct 13 05:33:33.216086 waagent[2601]: 2025-10-13T05:33:33.215882Z INFO ExtHandler ExtHandler Python: 3.11.13
Oct 13 05:33:33.216086 waagent[2601]: 2025-10-13T05:33:33.215914Z INFO ExtHandler ExtHandler CPU Arch: x86_64
Oct 13 05:33:33.280315 waagent[2601]: 2025-10-13T05:33:33.280272Z INFO ExtHandler ExtHandler Distro: flatcar-4487.0.0; OSUtil: FlatcarUtil; AgentService: waagent; Python: 3.11.13; Arch: x86_64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.22.0;
Oct 13 05:33:33.280462 waagent[2601]: 2025-10-13T05:33:33.280438Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 13 05:33:33.280521 waagent[2601]: 2025-10-13T05:33:33.280489Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 13 05:33:33.289711 waagent[2601]: 2025-10-13T05:33:33.289661Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
Oct 13 05:33:33.300329 waagent[2601]: 2025-10-13T05:33:33.300300Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.177
Oct 13 05:33:33.300645 waagent[2601]: 2025-10-13T05:33:33.300617Z INFO ExtHandler
Oct 13 05:33:33.300691 waagent[2601]: 2025-10-13T05:33:33.300667Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 6109e044-e4aa-4d0c-b254-98f6016882ae eTag: 5791192646590226337 source: Fabric]
Oct 13 05:33:33.300866 waagent[2601]: 2025-10-13T05:33:33.300844Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
Oct 13 05:33:33.301160 waagent[2601]: 2025-10-13T05:33:33.301140Z INFO ExtHandler
Oct 13 05:33:33.301196 waagent[2601]: 2025-10-13T05:33:33.301173Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
Oct 13 05:33:33.305934 waagent[2601]: 2025-10-13T05:33:33.305905Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
Oct 13 05:33:33.383297 waagent[2601]: 2025-10-13T05:33:33.383225Z INFO ExtHandler Downloaded certificate {'thumbprint': 'D2C62DE76F70B087DDFC8F829B3EEFBCCCA16549', 'hasPrivateKey': True}
Oct 13 05:33:33.383609 waagent[2601]: 2025-10-13T05:33:33.383583Z INFO ExtHandler Fetch goal state completed
Oct 13 05:33:33.399812 waagent[2601]: 2025-10-13T05:33:33.399772Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.4.2 1 Jul 2025 (Library: OpenSSL 3.4.2 1 Jul 2025)
Oct 13 05:33:33.403373 waagent[2601]: 2025-10-13T05:33:33.403325Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.4 running as process 2601
Oct 13 05:33:33.403482 waagent[2601]: 2025-10-13T05:33:33.403455Z INFO ExtHandler ExtHandler ******** AutoUpdate.Enabled is set to False, not processing the operation ********
Oct 13 05:33:33.403713 waagent[2601]: 2025-10-13T05:33:33.403686Z INFO ExtHandler ExtHandler ******** AutoUpdate.UpdateToLatestVersion is set to False, not processing the operation ********
Oct 13 05:33:33.404613 waagent[2601]: 2025-10-13T05:33:33.404587Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk']
Oct 13 05:33:33.404859 waagent[2601]: 2025-10-13T05:33:33.404838Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '4487.0.0', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported
Oct 13 05:33:33.404942 waagent[2601]: 2025-10-13T05:33:33.404925Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False
Oct 13 05:33:33.405265 waagent[2601]: 2025-10-13T05:33:33.405245Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Oct 13 05:33:33.476573 waagent[2601]: 2025-10-13T05:33:33.476549Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Oct 13 05:33:33.476689 waagent[2601]: 2025-10-13T05:33:33.476669Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Oct 13 05:33:33.481704 waagent[2601]: 2025-10-13T05:33:33.481392Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Oct 13 05:33:33.486068 systemd[1]: Reload requested from client PID 2616 ('systemctl') (unit waagent.service)...
Oct 13 05:33:33.486081 systemd[1]: Reloading...
Oct 13 05:33:33.567755 zram_generator::config[2659]: No configuration found.
Oct 13 05:33:33.722482 systemd[1]: Reloading finished in 236 ms.
Oct 13 05:33:33.737118 waagent[2601]: 2025-10-13T05:33:33.737060Z INFO ExtHandler ExtHandler Successfully added and enabled the waagent-network-setup.service
Oct 13 05:33:33.737205 waagent[2601]: 2025-10-13T05:33:33.737180Z INFO ExtHandler ExtHandler Persistent firewall rules setup successfully
Oct 13 05:33:34.098235 waagent[2601]: 2025-10-13T05:33:34.098168Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up.
Oct 13 05:33:34.098550 waagent[2601]: 2025-10-13T05:33:34.098520Z 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]
Oct 13 05:33:34.099133 waagent[2601]: 2025-10-13T05:33:34.099092Z INFO ExtHandler ExtHandler Starting env monitor service.
Oct 13 05:33:34.099437 waagent[2601]: 2025-10-13T05:33:34.099411Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Oct 13 05:33:34.099709 waagent[2601]: 2025-10-13T05:33:34.099680Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Oct 13 05:33:34.099846 waagent[2601]: 2025-10-13T05:33:34.099822Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Oct 13 05:33:34.099879 waagent[2601]: 2025-10-13T05:33:34.099797Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 13 05:33:34.100168 waagent[2601]: 2025-10-13T05:33:34.100071Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Oct 13 05:33:34.100202 waagent[2601]: 2025-10-13T05:33:34.100181Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 13 05:33:34.100276 waagent[2601]: 2025-10-13T05:33:34.100258Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Oct 13 05:33:34.100535 waagent[2601]: 2025-10-13T05:33:34.100518Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Oct 13 05:33:34.100603 waagent[2601]: 2025-10-13T05:33:34.100587Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 13 05:33:34.100647 waagent[2601]: 2025-10-13T05:33:34.100628Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 13 05:33:34.100891 waagent[2601]: 2025-10-13T05:33:34.100871Z INFO EnvHandler ExtHandler Configure routes
Oct 13 05:33:34.101101 waagent[2601]: 2025-10-13T05:33:34.101082Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Oct 13 05:33:34.101196 waagent[2601]: 2025-10-13T05:33:34.101178Z INFO EnvHandler ExtHandler Gateway:None
Oct 13 05:33:34.101248 waagent[2601]: 2025-10-13T05:33:34.101219Z INFO EnvHandler ExtHandler Routes:None
Oct 13 05:33:34.102001 waagent[2601]: 2025-10-13T05:33:34.101977Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Oct 13 05:33:34.102001 waagent[2601]: Iface        Destination        Gateway         Flags        RefCnt        Use        Metric        Mask                MTU        Window        IRTT
Oct 13 05:33:34.102001 waagent[2601]: eth0        00000000        0108C80A        0003        0        0        1024        00000000        0        0        0
Oct 13 05:33:34.102001 waagent[2601]: eth0        0008C80A        00000000        0001        0        0        1024        00FFFFFF        0        0        0
Oct 13 05:33:34.102001 waagent[2601]: eth0        0108C80A        00000000        0005        0        0        1024        FFFFFFFF        0        0        0
Oct 13 05:33:34.102001 waagent[2601]: eth0        10813FA8        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
Oct 13 05:33:34.102001 waagent[2601]: eth0        FEA9FEA9        0108C80A        0007        0        0        1024        FFFFFFFF        0        0        0
Oct 13 05:33:34.111948 waagent[2601]: 2025-10-13T05:33:34.111909Z INFO ExtHandler ExtHandler
Oct 13 05:33:34.112005 waagent[2601]: 2025-10-13T05:33:34.111979Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: ad7d6bbd-f75a-4aba-ae60-bb6c82db410d correlation 6bceb8f2-f755-4677-a555-bc829f0d41c9 created: 2025-10-13T05:32:24.964096Z]
Oct 13 05:33:34.112238 waagent[2601]: 2025-10-13T05:33:34.112215Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
Oct 13 05:33:34.112607 waagent[2601]: 2025-10-13T05:33:34.112585Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 0 ms]
Oct 13 05:33:34.148023 waagent[2601]: 2025-10-13T05:33:34.147982Z 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
Oct 13 05:33:34.148023 waagent[2601]: Try `iptables -h' or 'iptables --help' for more information.)
Oct 13 05:33:34.148273 waagent[2601]: 2025-10-13T05:33:34.148250Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.4 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 11C6C41C-9B09-4516-A778-2E251487B2E3;DroppedPackets: -1;UpdateGSErrors: 0;AutoUpdate: 0;UpdateMode: SelfUpdate;]
Oct 13 05:33:34.179854 waagent[2601]: 2025-10-13T05:33:34.179813Z INFO MonitorHandler ExtHandler Network interfaces:
Oct 13 05:33:34.179854 waagent[2601]: Executing ['ip', '-a', '-o', 'link']:
Oct 13 05:33:34.179854 waagent[2601]: 1: lo: <LOOPBACK,UP,LOWER_UP> 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
Oct 13 05:33:34.179854 waagent[2601]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\    link/ether 7c:ed:8d:2c:f4:5d brd ff:ff:ff:ff:ff:ff\    alias Network Device\    altname enx7ced8d2cf45d
Oct 13 05:33:34.179854 waagent[2601]: 3: enP30832s1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\    link/ether 7c:ed:8d:2c:f4:5d brd ff:ff:ff:ff:ff:ff\    altname enP30832p0s0
Oct 13 05:33:34.179854 waagent[2601]: Executing ['ip', '-4', '-a', '-o', 'address']:
Oct 13 05:33:34.179854 waagent[2601]: 1: lo    inet 127.0.0.1/8 scope host lo\       valid_lft forever preferred_lft forever
Oct 13 05:33:34.179854 waagent[2601]: 2: eth0    inet 10.200.8.19/24 metric 1024 brd 10.200.8.255 scope global eth0\       valid_lft forever preferred_lft forever
Oct 13 05:33:34.179854 waagent[2601]: Executing ['ip', '-6', '-a', '-o', 'address']:
Oct 13 05:33:34.179854 waagent[2601]: 1: lo    inet6 ::1/128 scope host noprefixroute \       valid_lft forever preferred_lft forever
Oct 13 05:33:34.179854 waagent[2601]: 2: eth0    inet6 fe80::7eed:8dff:fe2c:f45d/64 scope link proto kernel_ll \       valid_lft forever preferred_lft forever
Oct 13 05:33:34.218294 waagent[2601]: 2025-10-13T05:33:34.218255Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric:
Oct 13 05:33:34.218294 waagent[2601]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.218294 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.218294 waagent[2601]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.218294 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.218294 waagent[2601]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.218294 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.218294 waagent[2601]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
Oct 13 05:33:34.218294 waagent[2601]:        2      294 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
Oct 13 05:33:34.218294 waagent[2601]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
Oct 13 05:33:34.220704 waagent[2601]: 2025-10-13T05:33:34.220663Z INFO EnvHandler ExtHandler Current Firewall rules:
Oct 13 05:33:34.220704 waagent[2601]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.220704 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.220704 waagent[2601]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.220704 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.220704 waagent[2601]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 13 05:33:34.220704 waagent[2601]:     pkts      bytes target     prot opt in     out     source               destination
Oct 13 05:33:34.220704 waagent[2601]:        0        0 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        tcp dpt:53
Oct 13 05:33:34.220704 waagent[2601]:        7      940 ACCEPT     tcp  --  *      *       0.0.0.0/0            168.63.129.16        owner UID match 0
Oct 13 05:33:34.220704 waagent[2601]:        0        0 DROP       tcp  --  *      *       0.0.0.0/0            168.63.129.16        ctstate INVALID,NEW
Oct 13 05:33:41.136859 systemd[1]: Created slice system-sshd.slice - Slice /system/sshd.
Oct 13 05:33:41.137823 systemd[1]: Started sshd@0-10.200.8.19:22-10.200.16.10:60856.service - OpenSSH per-connection server daemon (10.200.16.10:60856).
Oct 13 05:33:41.967084 sshd[2749]: Accepted publickey for core from 10.200.16.10 port 60856 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:41.968105 sshd-session[2749]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:41.972707 systemd-logind[2405]: New session 3 of user core.
Oct 13 05:33:41.977536 systemd[1]: Started session-3.scope - Session 3 of User core.
Oct 13 05:33:42.527450 systemd[1]: Started sshd@1-10.200.8.19:22-10.200.16.10:60864.service - OpenSSH per-connection server daemon (10.200.16.10:60864).
Oct 13 05:33:43.182233 sshd[2755]: Accepted publickey for core from 10.200.16.10 port 60864 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:43.183232 sshd-session[2755]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:43.187009 systemd-logind[2405]: New session 4 of user core.
Oct 13 05:33:43.192503 systemd[1]: Started session-4.scope - Session 4 of User core.
Oct 13 05:33:43.632065 sshd[2758]: Connection closed by 10.200.16.10 port 60864
Oct 13 05:33:43.632528 sshd-session[2755]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:43.635390 systemd[1]: sshd@1-10.200.8.19:22-10.200.16.10:60864.service: Deactivated successfully.
Oct 13 05:33:43.636866 systemd[1]: session-4.scope: Deactivated successfully.
Oct 13 05:33:43.637535 systemd-logind[2405]: Session 4 logged out. Waiting for processes to exit.
Oct 13 05:33:43.638455 systemd-logind[2405]: Removed session 4.
Oct 13 05:33:43.744501 systemd[1]: Started sshd@2-10.200.8.19:22-10.200.16.10:60878.service - OpenSSH per-connection server daemon (10.200.16.10:60878).
Oct 13 05:33:44.390389 sshd[2764]: Accepted publickey for core from 10.200.16.10 port 60878 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:44.391308 sshd-session[2764]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:44.395588 systemd-logind[2405]: New session 5 of user core.
Oct 13 05:33:44.401514 systemd[1]: Started session-5.scope - Session 5 of User core.
Oct 13 05:33:44.838464 sshd[2767]: Connection closed by 10.200.16.10 port 60878
Oct 13 05:33:44.838894 sshd-session[2764]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:44.841713 systemd[1]: sshd@2-10.200.8.19:22-10.200.16.10:60878.service: Deactivated successfully.
Oct 13 05:33:44.842991 systemd[1]: session-5.scope: Deactivated successfully.
Oct 13 05:33:44.843710 systemd-logind[2405]: Session 5 logged out. Waiting for processes to exit.
Oct 13 05:33:44.844542 systemd-logind[2405]: Removed session 5.
Oct 13 05:33:44.954494 systemd[1]: Started sshd@3-10.200.8.19:22-10.200.16.10:60888.service - OpenSSH per-connection server daemon (10.200.16.10:60888).
Oct 13 05:33:45.594803 sshd[2773]: Accepted publickey for core from 10.200.16.10 port 60888 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:45.595791 sshd-session[2773]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:45.600024 systemd-logind[2405]: New session 6 of user core.
Oct 13 05:33:45.605538 systemd[1]: Started session-6.scope - Session 6 of User core.
Oct 13 05:33:46.043274 sshd[2776]: Connection closed by 10.200.16.10 port 60888
Oct 13 05:33:46.043756 sshd-session[2773]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:46.046687 systemd[1]: sshd@3-10.200.8.19:22-10.200.16.10:60888.service: Deactivated successfully.
Oct 13 05:33:46.047990 systemd[1]: session-6.scope: Deactivated successfully.
Oct 13 05:33:46.048596 systemd-logind[2405]: Session 6 logged out. Waiting for processes to exit.
Oct 13 05:33:46.049784 systemd-logind[2405]: Removed session 6.
Oct 13 05:33:46.162700 systemd[1]: Started sshd@4-10.200.8.19:22-10.200.16.10:60900.service - OpenSSH per-connection server daemon (10.200.16.10:60900).
Oct 13 05:33:46.806321 sshd[2782]: Accepted publickey for core from 10.200.16.10 port 60900 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:46.807324 sshd-session[2782]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:46.811243 systemd-logind[2405]: New session 7 of user core.
Oct 13 05:33:46.816501 systemd[1]: Started session-7.scope - Session 7 of User core.
Oct 13 05:33:47.315292 sudo[2786]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
Oct 13 05:33:47.315513 sudo[2786]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Oct 13 05:33:47.377102 sudo[2786]: pam_unix(sudo:session): session closed for user root
Oct 13 05:33:47.479123 sshd[2785]: Connection closed by 10.200.16.10 port 60900
Oct 13 05:33:47.479738 sshd-session[2782]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:47.482788 systemd[1]: sshd@4-10.200.8.19:22-10.200.16.10:60900.service: Deactivated successfully.
Oct 13 05:33:47.484214 systemd[1]: session-7.scope: Deactivated successfully.
Oct 13 05:33:47.484898 systemd-logind[2405]: Session 7 logged out. Waiting for processes to exit.
Oct 13 05:33:47.486497 systemd-logind[2405]: Removed session 7.
Oct 13 05:33:47.590577 systemd[1]: Started sshd@5-10.200.8.19:22-10.200.16.10:60904.service - OpenSSH per-connection server daemon (10.200.16.10:60904).
Oct 13 05:33:48.227657 sshd[2792]: Accepted publickey for core from 10.200.16.10 port 60904 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:48.228662 sshd-session[2792]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:48.232444 systemd-logind[2405]: New session 8 of user core.
Oct 13 05:33:48.238497 systemd[1]: Started session-8.scope - Session 8 of User core.
Oct 13 05:33:48.576196 sudo[2797]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules
Oct 13 05:33:48.576418 sudo[2797]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Oct 13 05:33:48.581607 sudo[2797]: pam_unix(sudo:session): session closed for user root
Oct 13 05:33:48.585830 sudo[2796]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/systemctl restart audit-rules
Oct 13 05:33:48.586015 sudo[2796]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Oct 13 05:33:48.592939 systemd[1]: Starting audit-rules.service - Load Audit Rules...
Oct 13 05:33:48.619282 augenrules[2819]: No rules
Oct 13 05:33:48.620100 systemd[1]: audit-rules.service: Deactivated successfully.
Oct 13 05:33:48.620235 systemd[1]: Finished audit-rules.service - Load Audit Rules.
Oct 13 05:33:48.621031 sudo[2796]: pam_unix(sudo:session): session closed for user root
Oct 13 05:33:48.725242 sshd[2795]: Connection closed by 10.200.16.10 port 60904
Oct 13 05:33:48.725579 sshd-session[2792]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:48.727827 systemd[1]: sshd@5-10.200.8.19:22-10.200.16.10:60904.service: Deactivated successfully.
Oct 13 05:33:48.729060 systemd[1]: session-8.scope: Deactivated successfully.
Oct 13 05:33:48.730099 systemd-logind[2405]: Session 8 logged out. Waiting for processes to exit.
Oct 13 05:33:48.731012 systemd-logind[2405]: Removed session 8.
Oct 13 05:33:48.837528 systemd[1]: Started sshd@6-10.200.8.19:22-10.200.16.10:60914.service - OpenSSH per-connection server daemon (10.200.16.10:60914).
Oct 13 05:33:49.474693 sshd[2828]: Accepted publickey for core from 10.200.16.10 port 60914 ssh2: RSA SHA256:AbXmOVBj6dCWUgMVoY4vqYS1kqBAdKFjDdmWPRs43lM
Oct 13 05:33:49.475675 sshd-session[2828]: pam_unix(sshd:session): session opened for user core(uid=500) by core(uid=0)
Oct 13 05:33:49.479774 systemd-logind[2405]: New session 9 of user core.
Oct 13 05:33:49.484527 systemd[1]: Started session-9.scope - Session 9 of User core.
Oct 13 05:33:49.823475 sudo[2834]:     core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/tee /etc/flatcar/update.conf
Oct 13 05:33:49.823679 sudo[2834]: pam_unix(sudo:session): session opened for user root(uid=0) by core(uid=500)
Oct 13 05:33:49.841868 sudo[2834]: pam_unix(sudo:session): session closed for user root
Oct 13 05:33:49.847690 systemd[1]: Starting motdgen.service - Generate /run/flatcar/motd...
Oct 13 05:33:49.864014 systemd[1]: motdgen.service: Deactivated successfully.
Oct 13 05:33:49.864196 systemd[1]: Finished motdgen.service - Generate /run/flatcar/motd.
Oct 13 05:33:49.946369 sshd[2831]: Connection closed by 10.200.16.10 port 60914
Oct 13 05:33:49.946713 sshd-session[2828]: pam_unix(sshd:session): session closed for user core
Oct 13 05:33:49.948994 systemd[1]: sshd@6-10.200.8.19:22-10.200.16.10:60914.service: Deactivated successfully.
Oct 13 05:33:49.950247 systemd[1]: session-9.scope: Deactivated successfully.
Oct 13 05:33:49.951571 systemd-logind[2405]: Session 9 logged out. Waiting for processes to exit.
Oct 13 05:33:49.952155 systemd-logind[2405]: Removed session 9.
Oct 13 05:33:51.835973 chronyd[2389]: Selected source PHC0