Feb 9 18:32:05.000059 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Feb 9 18:32:05.000078 kernel: Linux version 5.15.148-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Feb 9 17:24:35 -00 2024 Feb 9 18:32:05.000086 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Feb 9 18:32:05.000093 kernel: printk: bootconsole [pl11] enabled Feb 9 18:32:05.000099 kernel: efi: EFI v2.70 by EDK II Feb 9 18:32:05.000104 kernel: efi: ACPI 2.0=0x3fd89018 SMBIOS=0x3fd66000 SMBIOS 3.0=0x3fd64000 MEMATTR=0x3ef2e698 RNG=0x3fd89998 MEMRESERVE=0x37e73f98 Feb 9 18:32:05.000111 kernel: random: crng init done Feb 9 18:32:05.000116 kernel: ACPI: Early table checksum verification disabled Feb 9 18:32:05.000122 kernel: ACPI: RSDP 0x000000003FD89018 000024 (v02 VRTUAL) Feb 9 18:32:05.000127 kernel: ACPI: XSDT 0x000000003FD89F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000133 kernel: ACPI: FACP 0x000000003FD89C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000139 kernel: ACPI: DSDT 0x000000003EBD2018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Feb 9 18:32:05.000145 kernel: ACPI: DBG2 0x000000003FD89B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000150 kernel: ACPI: GTDT 0x000000003FD89D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000157 kernel: ACPI: OEM0 0x000000003FD89098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000163 kernel: ACPI: SPCR 0x000000003FD89A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000168 kernel: ACPI: APIC 0x000000003FD89818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000175 kernel: ACPI: SRAT 0x000000003FD89198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000181 kernel: ACPI: PPTT 0x000000003FD89418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Feb 9 18:32:05.000186 kernel: ACPI: BGRT 0x000000003FD89E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Feb 9 18:32:05.000192 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Feb 9 18:32:05.000198 kernel: NUMA: Failed to initialise from firmware Feb 9 18:32:05.000204 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff] Feb 9 18:32:05.000209 kernel: NUMA: NODE_DATA [mem 0x1bf7f1900-0x1bf7f6fff] Feb 9 18:32:05.000215 kernel: Zone ranges: Feb 9 18:32:05.000220 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Feb 9 18:32:05.000226 kernel: DMA32 empty Feb 9 18:32:05.000233 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Feb 9 18:32:05.000238 kernel: Movable zone start for each node Feb 9 18:32:05.000244 kernel: Early memory node ranges Feb 9 18:32:05.000249 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Feb 9 18:32:05.000255 kernel: node 0: [mem 0x0000000000824000-0x000000003ec80fff] Feb 9 18:32:05.000261 kernel: node 0: [mem 0x000000003ec81000-0x000000003eca9fff] Feb 9 18:32:05.000267 kernel: node 0: [mem 0x000000003ecaa000-0x000000003fd29fff] Feb 9 18:32:05.000272 kernel: node 0: [mem 0x000000003fd2a000-0x000000003fd7dfff] Feb 9 18:32:05.000278 kernel: node 0: [mem 0x000000003fd7e000-0x000000003fd89fff] Feb 9 18:32:05.000284 kernel: node 0: [mem 0x000000003fd8a000-0x000000003fd8dfff] Feb 9 18:32:05.000289 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fffffff] Feb 9 18:32:05.000295 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Feb 9 18:32:05.000302 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Feb 9 18:32:05.000310 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Feb 9 18:32:05.000316 kernel: psci: probing for conduit method from ACPI. Feb 9 18:32:05.000322 kernel: psci: PSCIv1.1 detected in firmware. Feb 9 18:32:05.000328 kernel: psci: Using standard PSCI v0.2 function IDs Feb 9 18:32:05.000335 kernel: psci: MIGRATE_INFO_TYPE not supported. Feb 9 18:32:05.000341 kernel: psci: SMC Calling Convention v1.4 Feb 9 18:32:05.000347 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1 Feb 9 18:32:05.000353 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1 Feb 9 18:32:05.000360 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784 Feb 9 18:32:05.000366 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096 Feb 9 18:32:05.000372 kernel: pcpu-alloc: [0] 0 [0] 1 Feb 9 18:32:05.000378 kernel: Detected PIPT I-cache on CPU0 Feb 9 18:32:05.000384 kernel: CPU features: detected: GIC system register CPU interface Feb 9 18:32:05.000390 kernel: CPU features: detected: Hardware dirty bit management Feb 9 18:32:05.000396 kernel: CPU features: detected: Spectre-BHB Feb 9 18:32:05.000402 kernel: CPU features: kernel page table isolation forced ON by KASLR Feb 9 18:32:05.000409 kernel: CPU features: detected: Kernel page table isolation (KPTI) Feb 9 18:32:05.000415 kernel: CPU features: detected: ARM erratum 1418040 Feb 9 18:32:05.000421 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Feb 9 18:32:05.000428 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Feb 9 18:32:05.000434 kernel: Policy zone: Normal Feb 9 18:32:05.000441 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=680ffc8c0dfb23738bd19ec96ea37b5bbadfb5cebf23767d1d52c89a6d5c00b4 Feb 9 18:32:05.000447 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Feb 9 18:32:05.000454 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Feb 9 18:32:05.000460 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Feb 9 18:32:05.000466 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Feb 9 18:32:05.000473 kernel: software IO TLB: mapped [mem 0x000000003abd2000-0x000000003ebd2000] (64MB) Feb 9 18:32:05.000480 kernel: Memory: 3991932K/4194160K available (9792K kernel code, 2092K rwdata, 7556K rodata, 34688K init, 778K bss, 202228K reserved, 0K cma-reserved) Feb 9 18:32:05.000486 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Feb 9 18:32:05.000492 kernel: trace event string verifier disabled Feb 9 18:32:05.000497 kernel: rcu: Preemptible hierarchical RCU implementation. Feb 9 18:32:05.000504 kernel: rcu: RCU event tracing is enabled. Feb 9 18:32:05.000510 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Feb 9 18:32:05.000516 kernel: Trampoline variant of Tasks RCU enabled. Feb 9 18:32:05.000522 kernel: Tracing variant of Tasks RCU enabled. Feb 9 18:32:05.000528 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Feb 9 18:32:05.000535 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Feb 9 18:32:05.000542 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Feb 9 18:32:05.000548 kernel: GICv3: 960 SPIs implemented Feb 9 18:32:05.000554 kernel: GICv3: 0 Extended SPIs implemented Feb 9 18:32:05.000559 kernel: GICv3: Distributor has no Range Selector support Feb 9 18:32:05.000565 kernel: Root IRQ handler: gic_handle_irq Feb 9 18:32:05.000571 kernel: GICv3: 16 PPIs implemented Feb 9 18:32:05.000577 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Feb 9 18:32:05.000583 kernel: ITS: No ITS available, not enabling LPIs Feb 9 18:32:05.000589 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Feb 9 18:32:05.000595 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Feb 9 18:32:05.000601 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Feb 9 18:32:05.000607 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Feb 9 18:32:05.000615 kernel: Console: colour dummy device 80x25 Feb 9 18:32:05.000621 kernel: printk: console [tty1] enabled Feb 9 18:32:05.000628 kernel: ACPI: Core revision 20210730 Feb 9 18:32:05.000635 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Feb 9 18:32:05.000641 kernel: pid_max: default: 32768 minimum: 301 Feb 9 18:32:05.000647 kernel: LSM: Security Framework initializing Feb 9 18:32:05.000653 kernel: SELinux: Initializing. Feb 9 18:32:05.000659 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 9 18:32:05.000666 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Feb 9 18:32:05.000673 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Feb 9 18:32:05.000680 kernel: Hyper-V: Host Build 10.0.22477.1369-1-0 Feb 9 18:32:05.005717 kernel: rcu: Hierarchical SRCU implementation. Feb 9 18:32:05.005736 kernel: Remapping and enabling EFI services. Feb 9 18:32:05.005743 kernel: smp: Bringing up secondary CPUs ... Feb 9 18:32:05.005750 kernel: Detected PIPT I-cache on CPU1 Feb 9 18:32:05.005757 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Feb 9 18:32:05.005764 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Feb 9 18:32:05.005770 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Feb 9 18:32:05.005781 kernel: smp: Brought up 1 node, 2 CPUs Feb 9 18:32:05.005787 kernel: SMP: Total of 2 processors activated. Feb 9 18:32:05.005793 kernel: CPU features: detected: 32-bit EL0 Support Feb 9 18:32:05.005800 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Feb 9 18:32:05.005807 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Feb 9 18:32:05.005813 kernel: CPU features: detected: CRC32 instructions Feb 9 18:32:05.005820 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Feb 9 18:32:05.005826 kernel: CPU features: detected: LSE atomic instructions Feb 9 18:32:05.005833 kernel: CPU features: detected: Privileged Access Never Feb 9 18:32:05.005840 kernel: CPU: All CPU(s) started at EL1 Feb 9 18:32:05.005847 kernel: alternatives: patching kernel code Feb 9 18:32:05.005858 kernel: devtmpfs: initialized Feb 9 18:32:05.005866 kernel: KASLR enabled Feb 9 18:32:05.005873 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Feb 9 18:32:05.005880 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Feb 9 18:32:05.005887 kernel: pinctrl core: initialized pinctrl subsystem Feb 9 18:32:05.005893 kernel: SMBIOS 3.1.0 present. Feb 9 18:32:05.005900 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 07/12/2023 Feb 9 18:32:05.005907 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Feb 9 18:32:05.005915 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Feb 9 18:32:05.005922 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Feb 9 18:32:05.005928 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Feb 9 18:32:05.005935 kernel: audit: initializing netlink subsys (disabled) Feb 9 18:32:05.005942 kernel: audit: type=2000 audit(0.086:1): state=initialized audit_enabled=0 res=1 Feb 9 18:32:05.005949 kernel: thermal_sys: Registered thermal governor 'step_wise' Feb 9 18:32:05.005956 kernel: cpuidle: using governor menu Feb 9 18:32:05.005964 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Feb 9 18:32:05.005971 kernel: ASID allocator initialised with 32768 entries Feb 9 18:32:05.005977 kernel: ACPI: bus type PCI registered Feb 9 18:32:05.005984 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Feb 9 18:32:05.005991 kernel: Serial: AMBA PL011 UART driver Feb 9 18:32:05.005997 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Feb 9 18:32:05.006004 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Feb 9 18:32:05.006011 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Feb 9 18:32:05.006017 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Feb 9 18:32:05.006025 kernel: cryptd: max_cpu_qlen set to 1000 Feb 9 18:32:05.006032 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Feb 9 18:32:05.006038 kernel: ACPI: Added _OSI(Module Device) Feb 9 18:32:05.006045 kernel: ACPI: Added _OSI(Processor Device) Feb 9 18:32:05.006051 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Feb 9 18:32:05.006058 kernel: ACPI: Added _OSI(Processor Aggregator Device) Feb 9 18:32:05.006065 kernel: ACPI: Added _OSI(Linux-Dell-Video) Feb 9 18:32:05.006071 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Feb 9 18:32:05.006078 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Feb 9 18:32:05.006086 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Feb 9 18:32:05.006093 kernel: ACPI: Interpreter enabled Feb 9 18:32:05.006099 kernel: ACPI: Using GIC for interrupt routing Feb 9 18:32:05.006106 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Feb 9 18:32:05.006112 kernel: printk: console [ttyAMA0] enabled Feb 9 18:32:05.006119 kernel: printk: bootconsole [pl11] disabled Feb 9 18:32:05.006126 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Feb 9 18:32:05.006132 kernel: iommu: Default domain type: Translated Feb 9 18:32:05.006139 kernel: iommu: DMA domain TLB invalidation policy: strict mode Feb 9 18:32:05.006147 kernel: vgaarb: loaded Feb 9 18:32:05.006154 kernel: pps_core: LinuxPPS API ver. 1 registered Feb 9 18:32:05.006160 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Feb 9 18:32:05.006167 kernel: PTP clock support registered Feb 9 18:32:05.006173 kernel: Registered efivars operations Feb 9 18:32:05.006180 kernel: No ACPI PMU IRQ for CPU0 Feb 9 18:32:05.006187 kernel: No ACPI PMU IRQ for CPU1 Feb 9 18:32:05.006193 kernel: clocksource: Switched to clocksource arch_sys_counter Feb 9 18:32:05.006200 kernel: VFS: Disk quotas dquot_6.6.0 Feb 9 18:32:05.006208 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Feb 9 18:32:05.006214 kernel: pnp: PnP ACPI init Feb 9 18:32:05.006221 kernel: pnp: PnP ACPI: found 0 devices Feb 9 18:32:05.006228 kernel: NET: Registered PF_INET protocol family Feb 9 18:32:05.006234 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Feb 9 18:32:05.006241 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Feb 9 18:32:05.006248 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Feb 9 18:32:05.006254 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Feb 9 18:32:05.006261 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Feb 9 18:32:05.006269 kernel: TCP: Hash tables configured (established 32768 bind 32768) Feb 9 18:32:05.006276 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 9 18:32:05.006283 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Feb 9 18:32:05.006290 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Feb 9 18:32:05.006296 kernel: PCI: CLS 0 bytes, default 64 Feb 9 18:32:05.006303 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Feb 9 18:32:05.006310 kernel: kvm [1]: HYP mode not available Feb 9 18:32:05.006316 kernel: Initialise system trusted keyrings Feb 9 18:32:05.006323 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Feb 9 18:32:05.006330 kernel: Key type asymmetric registered Feb 9 18:32:05.006337 kernel: Asymmetric key parser 'x509' registered Feb 9 18:32:05.006344 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Feb 9 18:32:05.006351 kernel: io scheduler mq-deadline registered Feb 9 18:32:05.006357 kernel: io scheduler kyber registered Feb 9 18:32:05.006364 kernel: io scheduler bfq registered Feb 9 18:32:05.006371 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Feb 9 18:32:05.006377 kernel: thunder_xcv, ver 1.0 Feb 9 18:32:05.006383 kernel: thunder_bgx, ver 1.0 Feb 9 18:32:05.006391 kernel: nicpf, ver 1.0 Feb 9 18:32:05.011761 kernel: nicvf, ver 1.0 Feb 9 18:32:05.011905 kernel: rtc-efi rtc-efi.0: registered as rtc0 Feb 9 18:32:05.011970 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-02-09T18:32:04 UTC (1707503524) Feb 9 18:32:05.011980 kernel: efifb: probing for efifb Feb 9 18:32:05.011987 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Feb 9 18:32:05.011995 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Feb 9 18:32:05.012002 kernel: efifb: scrolling: redraw Feb 9 18:32:05.012012 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Feb 9 18:32:05.012019 kernel: Console: switching to colour frame buffer device 128x48 Feb 9 18:32:05.012026 kernel: fb0: EFI VGA frame buffer device Feb 9 18:32:05.012033 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Feb 9 18:32:05.012040 kernel: hid: raw HID events driver (C) Jiri Kosina Feb 9 18:32:05.012047 kernel: NET: Registered PF_INET6 protocol family Feb 9 18:32:05.012053 kernel: Segment Routing with IPv6 Feb 9 18:32:05.012060 kernel: In-situ OAM (IOAM) with IPv6 Feb 9 18:32:05.012067 kernel: NET: Registered PF_PACKET protocol family Feb 9 18:32:05.012075 kernel: Key type dns_resolver registered Feb 9 18:32:05.012082 kernel: registered taskstats version 1 Feb 9 18:32:05.012089 kernel: Loading compiled-in X.509 certificates Feb 9 18:32:05.012096 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.148-flatcar: 947a80114e81e2815f6db72a0d388260762488f9' Feb 9 18:32:05.012103 kernel: Key type .fscrypt registered Feb 9 18:32:05.012110 kernel: Key type fscrypt-provisioning registered Feb 9 18:32:05.012116 kernel: ima: No TPM chip found, activating TPM-bypass! Feb 9 18:32:05.012123 kernel: ima: Allocated hash algorithm: sha1 Feb 9 18:32:05.012130 kernel: ima: No architecture policies found Feb 9 18:32:05.012137 kernel: Freeing unused kernel memory: 34688K Feb 9 18:32:05.012144 kernel: Run /init as init process Feb 9 18:32:05.012151 kernel: with arguments: Feb 9 18:32:05.012158 kernel: /init Feb 9 18:32:05.012164 kernel: with environment: Feb 9 18:32:05.012171 kernel: HOME=/ Feb 9 18:32:05.012177 kernel: TERM=linux Feb 9 18:32:05.012184 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Feb 9 18:32:05.012193 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Feb 9 18:32:05.012203 systemd[1]: Detected virtualization microsoft. Feb 9 18:32:05.012210 systemd[1]: Detected architecture arm64. Feb 9 18:32:05.012217 systemd[1]: Running in initrd. Feb 9 18:32:05.012224 systemd[1]: No hostname configured, using default hostname. Feb 9 18:32:05.012231 systemd[1]: Hostname set to . Feb 9 18:32:05.012239 systemd[1]: Initializing machine ID from random generator. Feb 9 18:32:05.012246 systemd[1]: Queued start job for default target initrd.target. Feb 9 18:32:05.012255 systemd[1]: Started systemd-ask-password-console.path. Feb 9 18:32:05.012262 systemd[1]: Reached target cryptsetup.target. Feb 9 18:32:05.012269 systemd[1]: Reached target paths.target. Feb 9 18:32:05.012276 systemd[1]: Reached target slices.target. Feb 9 18:32:05.012283 systemd[1]: Reached target swap.target. Feb 9 18:32:05.012290 systemd[1]: Reached target timers.target. Feb 9 18:32:05.012298 systemd[1]: Listening on iscsid.socket. Feb 9 18:32:05.012305 systemd[1]: Listening on iscsiuio.socket. Feb 9 18:32:05.012313 systemd[1]: Listening on systemd-journald-audit.socket. Feb 9 18:32:05.012320 systemd[1]: Listening on systemd-journald-dev-log.socket. Feb 9 18:32:05.012327 systemd[1]: Listening on systemd-journald.socket. Feb 9 18:32:05.012334 systemd[1]: Listening on systemd-networkd.socket. Feb 9 18:32:05.012341 systemd[1]: Listening on systemd-udevd-control.socket. Feb 9 18:32:05.012349 systemd[1]: Listening on systemd-udevd-kernel.socket. Feb 9 18:32:05.012356 systemd[1]: Reached target sockets.target. Feb 9 18:32:05.012363 systemd[1]: Starting kmod-static-nodes.service... Feb 9 18:32:05.012370 systemd[1]: Finished network-cleanup.service. Feb 9 18:32:05.012378 systemd[1]: Starting systemd-fsck-usr.service... Feb 9 18:32:05.012385 systemd[1]: Starting systemd-journald.service... Feb 9 18:32:05.012392 systemd[1]: Starting systemd-modules-load.service... Feb 9 18:32:05.012400 systemd[1]: Starting systemd-resolved.service... Feb 9 18:32:05.012407 systemd[1]: Starting systemd-vconsole-setup.service... Feb 9 18:32:05.012417 systemd-journald[276]: Journal started Feb 9 18:32:05.012457 systemd-journald[276]: Runtime Journal (/run/log/journal/b612f12317b249c38f6b585463e26ae2) is 8.0M, max 78.6M, 70.6M free. Feb 9 18:32:04.990167 systemd-modules-load[277]: Inserted module 'overlay' Feb 9 18:32:05.038338 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Feb 9 18:32:05.038358 systemd[1]: Started systemd-journald.service. Feb 9 18:32:05.038376 kernel: Bridge firewalling registered Feb 9 18:32:05.038762 systemd-modules-load[277]: Inserted module 'br_netfilter' Feb 9 18:32:05.043000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.059109 systemd[1]: Finished kmod-static-nodes.service. Feb 9 18:32:05.091557 kernel: audit: type=1130 audit(1707503525.043:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.091588 kernel: audit: type=1130 audit(1707503525.070:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.070000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.063347 systemd-resolved[278]: Positive Trust Anchors: Feb 9 18:32:05.063355 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 9 18:32:05.127106 kernel: audit: type=1130 audit(1707503525.095:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.095000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.063385 systemd-resolved[278]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Feb 9 18:32:05.197587 kernel: audit: type=1130 audit(1707503525.104:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.197612 kernel: SCSI subsystem initialized Feb 9 18:32:05.197621 kernel: audit: type=1130 audit(1707503525.131:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.104000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.131000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.065527 systemd-resolved[278]: Defaulting to hostname 'linux'. Feb 9 18:32:05.221117 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Feb 9 18:32:05.071189 systemd[1]: Started systemd-resolved.service. Feb 9 18:32:05.229176 kernel: device-mapper: uevent: version 1.0.3 Feb 9 18:32:05.229191 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Feb 9 18:32:05.095817 systemd[1]: Finished systemd-fsck-usr.service. Feb 9 18:32:05.126924 systemd[1]: Finished systemd-vconsole-setup.service. Feb 9 18:32:05.132353 systemd[1]: Reached target nss-lookup.target. Feb 9 18:32:05.203203 systemd[1]: Starting dracut-cmdline-ask.service... Feb 9 18:32:05.289169 kernel: audit: type=1130 audit(1707503525.262:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.262000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.229358 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Feb 9 18:32:05.330227 kernel: audit: type=1130 audit(1707503525.288:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.330250 kernel: audit: type=1130 audit(1707503525.312:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.288000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.312000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.246158 systemd-modules-load[277]: Inserted module 'dm_multipath' Feb 9 18:32:05.247555 systemd[1]: Finished systemd-modules-load.service. Feb 9 18:32:05.262974 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Feb 9 18:32:05.289459 systemd[1]: Finished dracut-cmdline-ask.service. Feb 9 18:32:05.333928 systemd[1]: Starting dracut-cmdline.service... Feb 9 18:32:05.385330 kernel: audit: type=1130 audit(1707503525.364:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.364000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.385390 dracut-cmdline[296]: dracut-dracut-053 Feb 9 18:32:05.339798 systemd[1]: Starting systemd-sysctl.service... Feb 9 18:32:05.394594 dracut-cmdline[296]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=680ffc8c0dfb23738bd19ec96ea37b5bbadfb5cebf23767d1d52c89a6d5c00b4 Feb 9 18:32:05.354540 systemd[1]: Finished systemd-sysctl.service. Feb 9 18:32:05.451705 kernel: Loading iSCSI transport class v2.0-870. Feb 9 18:32:05.460709 kernel: iscsi: registered transport (tcp) Feb 9 18:32:05.479775 kernel: iscsi: registered transport (qla4xxx) Feb 9 18:32:05.479818 kernel: QLogic iSCSI HBA Driver Feb 9 18:32:05.514625 systemd[1]: Finished dracut-cmdline.service. Feb 9 18:32:05.518000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.520179 systemd[1]: Starting dracut-pre-udev.service... Feb 9 18:32:05.572719 kernel: raid6: neonx8 gen() 13817 MB/s Feb 9 18:32:05.592705 kernel: raid6: neonx8 xor() 10836 MB/s Feb 9 18:32:05.612702 kernel: raid6: neonx4 gen() 13501 MB/s Feb 9 18:32:05.633708 kernel: raid6: neonx4 xor() 11189 MB/s Feb 9 18:32:05.653708 kernel: raid6: neonx2 gen() 12953 MB/s Feb 9 18:32:05.673704 kernel: raid6: neonx2 xor() 10390 MB/s Feb 9 18:32:05.694700 kernel: raid6: neonx1 gen() 10507 MB/s Feb 9 18:32:05.714700 kernel: raid6: neonx1 xor() 8796 MB/s Feb 9 18:32:05.734718 kernel: raid6: int64x8 gen() 6297 MB/s Feb 9 18:32:05.755706 kernel: raid6: int64x8 xor() 3550 MB/s Feb 9 18:32:05.775711 kernel: raid6: int64x4 gen() 7223 MB/s Feb 9 18:32:05.795697 kernel: raid6: int64x4 xor() 3856 MB/s Feb 9 18:32:05.816702 kernel: raid6: int64x2 gen() 6153 MB/s Feb 9 18:32:05.836699 kernel: raid6: int64x2 xor() 3322 MB/s Feb 9 18:32:05.856700 kernel: raid6: int64x1 gen() 5050 MB/s Feb 9 18:32:05.881611 kernel: raid6: int64x1 xor() 2646 MB/s Feb 9 18:32:05.881632 kernel: raid6: using algorithm neonx8 gen() 13817 MB/s Feb 9 18:32:05.881648 kernel: raid6: .... xor() 10836 MB/s, rmw enabled Feb 9 18:32:05.885634 kernel: raid6: using neon recovery algorithm Feb 9 18:32:05.902699 kernel: xor: measuring software checksum speed Feb 9 18:32:05.912169 kernel: 8regs : 17282 MB/sec Feb 9 18:32:05.912188 kernel: 32regs : 20755 MB/sec Feb 9 18:32:05.916231 kernel: arm64_neon : 27911 MB/sec Feb 9 18:32:05.916250 kernel: xor: using function: arm64_neon (27911 MB/sec) Feb 9 18:32:05.976721 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Feb 9 18:32:05.986593 systemd[1]: Finished dracut-pre-udev.service. Feb 9 18:32:05.990000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:05.994000 audit: BPF prog-id=7 op=LOAD Feb 9 18:32:05.994000 audit: BPF prog-id=8 op=LOAD Feb 9 18:32:05.995490 systemd[1]: Starting systemd-udevd.service... Feb 9 18:32:06.010168 systemd-udevd[475]: Using default interface naming scheme 'v252'. Feb 9 18:32:06.015341 systemd[1]: Started systemd-udevd.service. Feb 9 18:32:06.020000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:06.025376 systemd[1]: Starting dracut-pre-trigger.service... Feb 9 18:32:06.040457 dracut-pre-trigger[496]: rd.md=0: removing MD RAID activation Feb 9 18:32:06.065580 systemd[1]: Finished dracut-pre-trigger.service. Feb 9 18:32:06.069000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:06.071002 systemd[1]: Starting systemd-udev-trigger.service... Feb 9 18:32:06.108404 systemd[1]: Finished systemd-udev-trigger.service. Feb 9 18:32:06.116000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:06.166712 kernel: hv_vmbus: Vmbus version:5.3 Feb 9 18:32:06.179705 kernel: hv_vmbus: registering driver hyperv_keyboard Feb 9 18:32:06.179754 kernel: hv_vmbus: registering driver hv_netvsc Feb 9 18:32:06.185700 kernel: hv_vmbus: registering driver hid_hyperv Feb 9 18:32:06.193704 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Feb 9 18:32:06.193742 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Feb 9 18:32:06.213741 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Feb 9 18:32:06.215361 kernel: hv_vmbus: registering driver hv_storvsc Feb 9 18:32:06.226218 kernel: scsi host0: storvsc_host_t Feb 9 18:32:06.226365 kernel: scsi host1: storvsc_host_t Feb 9 18:32:06.226387 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Feb 9 18:32:06.239250 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Feb 9 18:32:06.257621 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Feb 9 18:32:06.257832 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Feb 9 18:32:06.268530 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Feb 9 18:32:06.268674 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Feb 9 18:32:06.268775 kernel: sd 0:0:0:0: [sda] Write Protect is off Feb 9 18:32:06.272612 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Feb 9 18:32:06.272757 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Feb 9 18:32:06.272842 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Feb 9 18:32:06.287718 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Feb 9 18:32:06.292706 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Feb 9 18:32:06.298708 kernel: hv_netvsc 002248b9-fff4-0022-48b9-fff4002248b9 eth0: VF slot 1 added Feb 9 18:32:06.314938 kernel: hv_vmbus: registering driver hv_pci Feb 9 18:32:06.314972 kernel: hv_pci f487ed72-79f8-498c-a896-eefeef37facd: PCI VMBus probing: Using version 0x10004 Feb 9 18:32:06.331264 kernel: hv_pci f487ed72-79f8-498c-a896-eefeef37facd: PCI host bridge to bus 79f8:00 Feb 9 18:32:06.331408 kernel: pci_bus 79f8:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Feb 9 18:32:06.331510 kernel: pci_bus 79f8:00: No busn resource found for root bus, will use [bus 00-ff] Feb 9 18:32:06.344248 kernel: pci 79f8:00:02.0: [15b3:1018] type 00 class 0x020000 Feb 9 18:32:06.359695 kernel: pci 79f8:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Feb 9 18:32:06.379310 kernel: pci 79f8:00:02.0: enabling Extended Tags Feb 9 18:32:06.404722 kernel: pci 79f8:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 79f8:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Feb 9 18:32:06.404904 kernel: pci_bus 79f8:00: busn_res: [bus 00-ff] end is updated to 00 Feb 9 18:32:06.404997 kernel: pci 79f8:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Feb 9 18:32:06.453716 kernel: mlx5_core 79f8:00:02.0: firmware version: 16.30.1284 Feb 9 18:32:06.614832 kernel: mlx5_core 79f8:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0) Feb 9 18:32:06.672556 kernel: hv_netvsc 002248b9-fff4-0022-48b9-fff4002248b9 eth0: VF registering: eth1 Feb 9 18:32:06.672763 kernel: mlx5_core 79f8:00:02.0 eth1: joined to eth0 Feb 9 18:32:06.683706 kernel: mlx5_core 79f8:00:02.0 enP31224s1: renamed from eth1 Feb 9 18:32:06.757901 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Feb 9 18:32:06.809714 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (537) Feb 9 18:32:06.822984 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Feb 9 18:32:06.979813 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Feb 9 18:32:06.986054 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Feb 9 18:32:07.000365 systemd[1]: Starting disk-uuid.service... Feb 9 18:32:07.102535 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Feb 9 18:32:08.029706 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Feb 9 18:32:08.029755 disk-uuid[599]: The operation has completed successfully. Feb 9 18:32:08.100252 systemd[1]: disk-uuid.service: Deactivated successfully. Feb 9 18:32:08.102840 systemd[1]: Finished disk-uuid.service. Feb 9 18:32:08.109000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.109000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.113195 systemd[1]: Starting verity-setup.service... Feb 9 18:32:08.151826 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Feb 9 18:32:08.394592 systemd[1]: Found device dev-mapper-usr.device. Feb 9 18:32:08.400842 systemd[1]: Mounting sysusr-usr.mount... Feb 9 18:32:08.410325 systemd[1]: Finished verity-setup.service. Feb 9 18:32:08.417000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.470717 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Feb 9 18:32:08.470920 systemd[1]: Mounted sysusr-usr.mount. Feb 9 18:32:08.474924 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Feb 9 18:32:08.475669 systemd[1]: Starting ignition-setup.service... Feb 9 18:32:08.492022 systemd[1]: Starting parse-ip-for-networkd.service... Feb 9 18:32:08.524310 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Feb 9 18:32:08.524359 kernel: BTRFS info (device sda6): using free space tree Feb 9 18:32:08.529006 kernel: BTRFS info (device sda6): has skinny extents Feb 9 18:32:08.579543 systemd[1]: Finished parse-ip-for-networkd.service. Feb 9 18:32:08.584000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.588000 audit: BPF prog-id=9 op=LOAD Feb 9 18:32:08.589386 systemd[1]: Starting systemd-networkd.service... Feb 9 18:32:08.614883 systemd-networkd[866]: lo: Link UP Feb 9 18:32:08.614893 systemd-networkd[866]: lo: Gained carrier Feb 9 18:32:08.624000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.615288 systemd-networkd[866]: Enumeration completed Feb 9 18:32:08.619383 systemd[1]: Started systemd-networkd.service. Feb 9 18:32:08.620530 systemd-networkd[866]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 9 18:32:08.657000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.624973 systemd[1]: Reached target network.target. Feb 9 18:32:08.633407 systemd[1]: Starting iscsiuio.service... Feb 9 18:32:08.670000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.675260 iscsid[877]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Feb 9 18:32:08.675260 iscsid[877]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log Feb 9 18:32:08.675260 iscsid[877]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Feb 9 18:32:08.675260 iscsid[877]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Feb 9 18:32:08.675260 iscsid[877]: If using hardware iscsi like qla4xxx this message can be ignored. Feb 9 18:32:08.675260 iscsid[877]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Feb 9 18:32:08.675260 iscsid[877]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Feb 9 18:32:08.709000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.653815 systemd[1]: Started iscsiuio.service. Feb 9 18:32:08.659496 systemd[1]: Starting iscsid.service... Feb 9 18:32:08.779000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:08.666853 systemd[1]: Started iscsid.service. Feb 9 18:32:08.672013 systemd[1]: mnt-oem.mount: Deactivated successfully. Feb 9 18:32:08.673033 systemd[1]: Starting dracut-initqueue.service... Feb 9 18:32:08.689260 systemd[1]: Finished dracut-initqueue.service. Feb 9 18:32:08.709639 systemd[1]: Reached target remote-fs-pre.target. Feb 9 18:32:08.727194 systemd[1]: Reached target remote-cryptsetup.target. Feb 9 18:32:08.738556 systemd[1]: Reached target remote-fs.target. Feb 9 18:32:08.749762 systemd[1]: Starting dracut-pre-mount.service... Feb 9 18:32:08.775018 systemd[1]: Finished dracut-pre-mount.service. Feb 9 18:32:08.825823 kernel: mlx5_core 79f8:00:02.0 enP31224s1: Link up Feb 9 18:32:08.868762 kernel: hv_netvsc 002248b9-fff4-0022-48b9-fff4002248b9 eth0: Data path switched to VF: enP31224s1 Feb 9 18:32:08.868946 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Feb 9 18:32:08.869172 systemd-networkd[866]: enP31224s1: Link UP Feb 9 18:32:08.869386 systemd-networkd[866]: eth0: Link UP Feb 9 18:32:08.869769 systemd-networkd[866]: eth0: Gained carrier Feb 9 18:32:08.883226 systemd-networkd[866]: enP31224s1: Gained carrier Feb 9 18:32:08.897735 systemd-networkd[866]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Feb 9 18:32:09.005140 systemd[1]: Finished ignition-setup.service. Feb 9 18:32:09.009000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:09.010582 systemd[1]: Starting ignition-fetch-offline.service... Feb 9 18:32:10.930862 systemd-networkd[866]: eth0: Gained IPv6LL Feb 9 18:32:11.983000 ignition[893]: Ignition 2.14.0 Feb 9 18:32:11.983013 ignition[893]: Stage: fetch-offline Feb 9 18:32:11.983079 ignition[893]: reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:11.983103 ignition[893]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:12.072876 ignition[893]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:12.073040 ignition[893]: parsed url from cmdline: "" Feb 9 18:32:12.073043 ignition[893]: no config URL provided Feb 9 18:32:12.073048 ignition[893]: reading system config file "/usr/lib/ignition/user.ign" Feb 9 18:32:12.087000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.079521 systemd[1]: Finished ignition-fetch-offline.service. Feb 9 18:32:12.118300 kernel: kauditd_printk_skb: 18 callbacks suppressed Feb 9 18:32:12.118323 kernel: audit: type=1130 audit(1707503532.087:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.073056 ignition[893]: no config at "/usr/lib/ignition/user.ign" Feb 9 18:32:12.097891 systemd[1]: Starting ignition-fetch.service... Feb 9 18:32:12.073062 ignition[893]: failed to fetch config: resource requires networking Feb 9 18:32:12.073381 ignition[893]: Ignition finished successfully Feb 9 18:32:12.104451 ignition[899]: Ignition 2.14.0 Feb 9 18:32:12.104457 ignition[899]: Stage: fetch Feb 9 18:32:12.104552 ignition[899]: reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:12.104570 ignition[899]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:12.107031 ignition[899]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:12.107135 ignition[899]: parsed url from cmdline: "" Feb 9 18:32:12.107138 ignition[899]: no config URL provided Feb 9 18:32:12.107143 ignition[899]: reading system config file "/usr/lib/ignition/user.ign" Feb 9 18:32:12.107150 ignition[899]: no config at "/usr/lib/ignition/user.ign" Feb 9 18:32:12.107173 ignition[899]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Feb 9 18:32:12.242982 ignition[899]: GET result: OK Feb 9 18:32:12.243034 ignition[899]: config has been read from IMDS userdata Feb 9 18:32:12.246087 unknown[899]: fetched base config from "system" Feb 9 18:32:12.255000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.243045 ignition[899]: parsing config with SHA512: be78c381698e4aa3e98304dd4e70ec889392a70f7c1bb78d03ed8a853c57bf9d230b9598ba75e0bac39a849a07845b99e2ae9d804f908f4acd1f439b7166058d Feb 9 18:32:12.246094 unknown[899]: fetched base config from "system" Feb 9 18:32:12.286845 kernel: audit: type=1130 audit(1707503532.255:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.246407 ignition[899]: fetch: fetch complete Feb 9 18:32:12.246108 unknown[899]: fetched user config from "azure" Feb 9 18:32:12.246412 ignition[899]: fetch: fetch passed Feb 9 18:32:12.251253 systemd[1]: Finished ignition-fetch.service. Feb 9 18:32:12.307000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.246454 ignition[899]: Ignition finished successfully Feb 9 18:32:12.337527 kernel: audit: type=1130 audit(1707503532.307:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.275465 systemd[1]: Starting ignition-kargs.service... Feb 9 18:32:12.291934 ignition[905]: Ignition 2.14.0 Feb 9 18:32:12.303530 systemd[1]: Finished ignition-kargs.service. Feb 9 18:32:12.291940 ignition[905]: Stage: kargs Feb 9 18:32:12.308673 systemd[1]: Starting ignition-disks.service... Feb 9 18:32:12.292042 ignition[905]: reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:12.292062 ignition[905]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:12.301141 ignition[905]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:12.302670 ignition[905]: kargs: kargs passed Feb 9 18:32:12.302734 ignition[905]: Ignition finished successfully Feb 9 18:32:12.318527 ignition[911]: Ignition 2.14.0 Feb 9 18:32:12.318532 ignition[911]: Stage: disks Feb 9 18:32:12.318626 ignition[911]: reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:12.318648 ignition[911]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:12.322108 ignition[911]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:12.323515 ignition[911]: disks: createFilesystems: op(1): [started] waiting for devices [/dev/disk/by-partlabel/ROOT] Feb 9 18:32:12.336442 ignition[911]: disks: createFilesystems: op(1): [finished] waiting for devices [/dev/disk/by-partlabel/ROOT] Feb 9 18:32:12.336524 ignition[911]: disks: createFilesystems: created device alias for "/dev/disk/by-partlabel/ROOT": "/run/ignition/dev_aliases/dev/disk/by-partlabel/ROOT" -> "/dev/sda9" Feb 9 18:32:12.336634 ignition[911]: disks: createFilesystems: op(2): [started] determining filesystem type of "/dev/disk/by-partlabel/ROOT" Feb 9 18:32:12.445884 ignition[911]: disks: createFilesystems: op(2): [finished] determining filesystem type of "/dev/disk/by-partlabel/ROOT" Feb 9 18:32:12.445903 ignition[911]: disks: createFilesystems: found ext4 filesystem at "/dev/disk/by-partlabel/ROOT" with uuid "6b463d96-d983-42ed-9543-3fd976a169b0" and label "ROOT" Feb 9 18:32:12.458000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.448021 systemd[1]: Finished ignition-disks.service. Feb 9 18:32:12.495213 kernel: audit: type=1130 audit(1707503532.458:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.445908 ignition[911]: disks: createFilesystems: filesystem at "/dev/disk/by-partlabel/ROOT" is already correctly formatted. Skipping mkfs... Feb 9 18:32:12.459037 systemd[1]: Reached target initrd-root-device.target. Feb 9 18:32:12.445962 ignition[911]: disks: disks passed Feb 9 18:32:12.491336 systemd[1]: Reached target local-fs-pre.target. Feb 9 18:32:12.446055 ignition[911]: Ignition finished successfully Feb 9 18:32:12.499485 systemd[1]: Reached target local-fs.target. Feb 9 18:32:12.513181 systemd[1]: Reached target sysinit.target. Feb 9 18:32:12.523000 systemd[1]: Reached target basic.target. Feb 9 18:32:12.531390 systemd[1]: Starting systemd-fsck-root.service... Feb 9 18:32:12.614465 systemd-fsck[919]: ROOT: clean, 602/7326000 files, 481069/7359488 blocks Feb 9 18:32:12.631246 systemd[1]: Finished systemd-fsck-root.service. Feb 9 18:32:12.635000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.656936 systemd[1]: Mounting sysroot.mount... Feb 9 18:32:12.665565 kernel: audit: type=1130 audit(1707503532.635:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:12.680712 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Feb 9 18:32:12.680905 systemd[1]: Mounted sysroot.mount. Feb 9 18:32:12.684594 systemd[1]: Reached target initrd-root-fs.target. Feb 9 18:32:12.720568 systemd[1]: Mounting sysroot-usr.mount... Feb 9 18:32:12.725287 systemd[1]: Starting flatcar-metadata-hostname.service... Feb 9 18:32:12.732819 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Feb 9 18:32:12.732849 systemd[1]: Reached target ignition-diskful.target. Feb 9 18:32:12.739017 systemd[1]: Mounted sysroot-usr.mount. Feb 9 18:32:12.808454 systemd[1]: Mounting sysroot-usr-share-oem.mount... Feb 9 18:32:12.813338 systemd[1]: Starting initrd-setup-root.service... Feb 9 18:32:12.835704 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (930) Feb 9 18:32:12.847386 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Feb 9 18:32:12.847424 kernel: BTRFS info (device sda6): using free space tree Feb 9 18:32:12.847435 initrd-setup-root[935]: cut: /sysroot/etc/passwd: No such file or directory Feb 9 18:32:12.857739 kernel: BTRFS info (device sda6): has skinny extents Feb 9 18:32:12.864711 systemd[1]: Mounted sysroot-usr-share-oem.mount. Feb 9 18:32:12.875576 initrd-setup-root[961]: cut: /sysroot/etc/group: No such file or directory Feb 9 18:32:12.885495 initrd-setup-root[969]: cut: /sysroot/etc/shadow: No such file or directory Feb 9 18:32:12.909882 initrd-setup-root[977]: cut: /sysroot/etc/gshadow: No such file or directory Feb 9 18:32:13.415389 systemd[1]: Finished initrd-setup-root.service. Feb 9 18:32:13.419000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:13.439680 systemd[1]: Starting ignition-mount.service... Feb 9 18:32:13.450818 kernel: audit: type=1130 audit(1707503533.419:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:13.448846 systemd[1]: Starting sysroot-boot.service... Feb 9 18:32:13.455454 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Feb 9 18:32:13.455567 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Feb 9 18:32:13.481496 ignition[997]: INFO : Ignition 2.14.0 Feb 9 18:32:13.485950 ignition[997]: INFO : Stage: mount Feb 9 18:32:13.491658 ignition[997]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:13.491658 ignition[997]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:13.532516 kernel: audit: type=1130 audit(1707503533.504:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:13.504000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:13.493389 systemd[1]: Finished sysroot-boot.service. Feb 9 18:32:13.536613 ignition[997]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:13.536613 ignition[997]: INFO : mount: op(1): [started] mounting "/dev/disk/by-partlabel/ROOT" at "/sysroot/tmp/_translate-filesystem-0-ign1" with type "ext4" and options "" Feb 9 18:32:13.536613 ignition[997]: DEBUG : mount: op(1): executing: "mount" "-o" "" "-t" "ext4" "/dev/disk/by-partlabel/ROOT" "/sysroot/tmp/_translate-filesystem-0-ign1" Feb 9 18:32:13.997548 coreos-metadata[929]: Feb 09 18:32:13.997 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Feb 9 18:32:14.007505 coreos-metadata[929]: Feb 09 18:32:14.007 INFO Fetch successful Feb 9 18:32:14.046850 coreos-metadata[929]: Feb 09 18:32:14.046 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Feb 9 18:32:14.068181 coreos-metadata[929]: Feb 09 18:32:14.068 INFO Fetch successful Feb 9 18:32:14.073526 coreos-metadata[929]: Feb 09 18:32:14.073 INFO wrote hostname ci-3510.3.2-a-7e5e2e1465 to /sysroot/etc/hostname Feb 9 18:32:14.081925 systemd[1]: Finished flatcar-metadata-hostname.service. Feb 9 18:32:14.086000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.108738 kernel: audit: type=1130 audit(1707503534.086:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.109603 ignition[997]: INFO : mount: op(1): [finished] mounting "/dev/disk/by-partlabel/ROOT" at "/sysroot/tmp/_translate-filesystem-0-ign1" with type "ext4" and options "" Feb 9 18:32:14.109603 ignition[997]: INFO : mount: mount passed Feb 9 18:32:14.109603 ignition[997]: INFO : Ignition finished successfully Feb 9 18:32:14.154248 kernel: audit: type=1130 audit(1707503534.125:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.125000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.121396 systemd[1]: Finished ignition-mount.service. Feb 9 18:32:14.126738 systemd[1]: Starting ignition-files.service... Feb 9 18:32:14.152958 systemd[1]: Mounting sysroot-usr-share-oem.mount... Feb 9 18:32:14.186092 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (1009) Feb 9 18:32:14.186130 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Feb 9 18:32:14.186141 kernel: BTRFS info (device sda6): using free space tree Feb 9 18:32:14.190889 kernel: BTRFS info (device sda6): has skinny extents Feb 9 18:32:14.202643 systemd[1]: Mounted sysroot-usr-share-oem.mount. Feb 9 18:32:14.216085 ignition[1028]: INFO : Ignition 2.14.0 Feb 9 18:32:14.216085 ignition[1028]: INFO : Stage: files Feb 9 18:32:14.225123 ignition[1028]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:14.225123 ignition[1028]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:14.225123 ignition[1028]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:14.225123 ignition[1028]: DEBUG : files: compiled without relabeling support, skipping Feb 9 18:32:14.225123 ignition[1028]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Feb 9 18:32:14.225123 ignition[1028]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Feb 9 18:32:14.301861 ignition[1028]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Feb 9 18:32:14.317544 ignition[1028]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Feb 9 18:32:14.317544 ignition[1028]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Feb 9 18:32:14.317544 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Feb 9 18:32:14.317544 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): oem config not found in "/usr/share/oem", looking on oem partition Feb 9 18:32:14.317544 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4280291801" Feb 9 18:32:14.317544 ignition[1028]: CRITICAL : files: createFilesystemsFiles: createFiles: op(3): op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4280291801": device or resource busy Feb 9 18:32:14.317544 ignition[1028]: ERROR : files: createFilesystemsFiles: createFiles: op(3): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem4280291801", trying btrfs: device or resource busy Feb 9 18:32:14.317544 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4280291801" Feb 9 18:32:14.428821 kernel: BTRFS info: devid 1 device path /dev/sda6 changed to /dev/disk/by-label/OEM scanned by ignition (1033) Feb 9 18:32:14.428843 kernel: audit: type=1130 audit(1707503534.382:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.382000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.302278 unknown[1028]: wrote ssh authorized keys file for user: core Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem4280291801" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(6): [started] unmounting "/mnt/oem4280291801" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(6): [finished] unmounting "/mnt/oem4280291801" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): oem config not found in "/usr/share/oem", looking on oem partition Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2690003851" Feb 9 18:32:14.437018 ignition[1028]: CRITICAL : files: createFilesystemsFiles: createFiles: op(7): op(8): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2690003851": device or resource busy Feb 9 18:32:14.437018 ignition[1028]: ERROR : files: createFilesystemsFiles: createFiles: op(7): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2690003851", trying btrfs: device or resource busy Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2690003851" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2690003851" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [started] unmounting "/mnt/oem2690003851" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [finished] unmounting "/mnt/oem2690003851" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Feb 9 18:32:14.437018 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(b): [started] writing file "/sysroot/tmp/_translate-filesystem-0-ign1/etc/flatcar/update.conf" Feb 9 18:32:14.452000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.452000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.466000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.509000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.509000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.624000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.329951 systemd[1]: mnt-oem4280291801.mount: Deactivated successfully. Feb 9 18:32:14.646282 ignition[1028]: INFO : files: createFilesystemsFiles: createFiles: op(b): [finished] writing file "/sysroot/tmp/_translate-filesystem-0-ign1/etc/flatcar/update.conf" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(c): [started] processing unit "waagent.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(c): [finished] processing unit "waagent.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(d): [started] processing unit "nvidia.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(d): [finished] processing unit "nvidia.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(e): [started] processing unit "coreos-metadata.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(e): [finished] processing unit "coreos-metadata.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(f): [started] processing unit "metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(f): op(10): [started] writing unit "metadata.target" at "/sysroot/etc/systemd/system/metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(f): op(10): [finished] writing unit "metadata.target" at "/sysroot/etc/systemd/system/metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(f): [finished] processing unit "metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(11): [started] setting preset to enabled for "waagent.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(11): [finished] setting preset to enabled for "waagent.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(12): [started] setting preset to enabled for "nvidia.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(12): [finished] setting preset to enabled for "nvidia.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(13): [started] setting preset to enabled for "coreos-metadata.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(13): [finished] setting preset to enabled for "coreos-metadata.service" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(14): [started] setting preset to enabled for "metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: op(14): [finished] setting preset to enabled for "metadata.target" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: createResultFile: createFiles: op(15): [started] writing file "/sysroot/etc/.ignition-result.json" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: createResultFile: createFiles: op(15): [finished] writing file "/sysroot/etc/.ignition-result.json" Feb 9 18:32:14.646282 ignition[1028]: INFO : files: files passed Feb 9 18:32:14.646282 ignition[1028]: INFO : Ignition finished successfully Feb 9 18:32:14.694000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.841000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.864000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.877000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.890000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.904000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.366660 systemd[1]: Finished ignition-files.service. Feb 9 18:32:14.926490 initrd-setup-root-after-ignition[1053]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Feb 9 18:32:14.939608 iscsid[877]: iscsid shutting down. Feb 9 18:32:14.945000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.412037 systemd[1]: Starting initrd-setup-root-after-ignition.service... Feb 9 18:32:14.417008 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Feb 9 18:32:14.959000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.417771 systemd[1]: Starting ignition-quench.service... Feb 9 18:32:14.974000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.433548 systemd[1]: ignition-quench.service: Deactivated successfully. Feb 9 18:32:14.982000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.433636 systemd[1]: Finished ignition-quench.service. Feb 9 18:32:14.991000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.453015 systemd[1]: Finished initrd-setup-root-after-ignition.service. Feb 9 18:32:15.000000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.467270 systemd[1]: Reached target ignition-complete.target. Feb 9 18:32:15.009000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:15.014245 ignition[1066]: INFO : Ignition 2.14.0 Feb 9 18:32:15.014245 ignition[1066]: INFO : Stage: umount Feb 9 18:32:15.014245 ignition[1066]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Feb 9 18:32:15.014245 ignition[1066]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Feb 9 18:32:15.014245 ignition[1066]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Feb 9 18:32:15.014245 ignition[1066]: INFO : umount: op(1): [started] umounting "/sysroot/tmp/_translate-filesystem-0-ign1" Feb 9 18:32:15.014245 ignition[1066]: INFO : umount: op(1): [finished] umounting "/sysroot/tmp/_translate-filesystem-0-ign1" Feb 9 18:32:15.014245 ignition[1066]: INFO : umount: umount passed Feb 9 18:32:15.014245 ignition[1066]: INFO : Ignition finished successfully Feb 9 18:32:15.018000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:15.096000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.481432 systemd[1]: Starting initrd-parse-etc.service... Feb 9 18:32:14.504762 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Feb 9 18:32:14.504849 systemd[1]: Finished initrd-parse-etc.service. Feb 9 18:32:15.118000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.510215 systemd[1]: Reached target initrd-fs.target. Feb 9 18:32:15.127000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.523988 systemd[1]: Reached target initrd.target. Feb 9 18:32:14.538440 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Feb 9 18:32:14.539223 systemd[1]: Starting dracut-pre-pivot.service... Feb 9 18:32:15.157000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.609717 systemd[1]: Finished dracut-pre-pivot.service. Feb 9 18:32:14.625322 systemd[1]: Starting initrd-cleanup.service... Feb 9 18:32:14.648930 systemd[1]: Stopped target nss-lookup.target. Feb 9 18:32:15.185000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:15.185000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.662776 systemd[1]: Stopped target remote-cryptsetup.target. Feb 9 18:32:15.195000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.673999 systemd[1]: Stopped target timers.target. Feb 9 18:32:15.205000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:15.205000 audit: BPF prog-id=6 op=UNLOAD Feb 9 18:32:14.684527 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Feb 9 18:32:14.684638 systemd[1]: Stopped dracut-pre-pivot.service. Feb 9 18:32:14.695021 systemd[1]: Stopped target initrd.target. Feb 9 18:32:14.705923 systemd[1]: Stopped target basic.target. Feb 9 18:32:15.238000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.717138 systemd[1]: Stopped target ignition-complete.target. Feb 9 18:32:15.245000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.729507 systemd[1]: Stopped target ignition-diskful.target. Feb 9 18:32:15.254000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.740374 systemd[1]: Stopped target initrd-root-device.target. Feb 9 18:32:14.755085 systemd[1]: Stopped target remote-fs.target. Feb 9 18:32:14.768903 systemd[1]: Stopped target remote-fs-pre.target. Feb 9 18:32:14.780666 systemd[1]: Stopped target sysinit.target. Feb 9 18:32:15.280000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.793322 systemd[1]: Stopped target local-fs.target. Feb 9 18:32:14.805136 systemd[1]: Stopped target local-fs-pre.target. Feb 9 18:32:14.817198 systemd[1]: Stopped target swap.target. Feb 9 18:32:15.308000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.829104 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Feb 9 18:32:15.317000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.829226 systemd[1]: Stopped dracut-pre-mount.service. Feb 9 18:32:15.342656 kernel: hv_netvsc 002248b9-fff4-0022-48b9-fff4002248b9 eth0: Data path switched from VF: enP31224s1 Feb 9 18:32:15.337000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.841475 systemd[1]: Stopped target cryptsetup.target. Feb 9 18:32:14.853499 systemd[1]: dracut-initqueue.service: Deactivated successfully. Feb 9 18:32:14.853602 systemd[1]: Stopped dracut-initqueue.service. Feb 9 18:32:14.865092 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Feb 9 18:32:15.369000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.865184 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Feb 9 18:32:15.382000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.877713 systemd[1]: ignition-files.service: Deactivated successfully. Feb 9 18:32:15.395000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.877798 systemd[1]: Stopped ignition-files.service. Feb 9 18:32:15.407000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:15.407000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.891212 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Feb 9 18:32:14.891299 systemd[1]: Stopped flatcar-metadata-hostname.service. Feb 9 18:32:15.425000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:14.906055 systemd[1]: Stopping ignition-mount.service... Feb 9 18:32:14.922029 systemd[1]: Stopping iscsid.service... Feb 9 18:32:14.930492 systemd[1]: Stopping sysroot-boot.service... Feb 9 18:32:14.941752 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Feb 9 18:32:14.941884 systemd[1]: Stopped systemd-udev-trigger.service. Feb 9 18:32:14.948641 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Feb 9 18:32:14.948758 systemd[1]: Stopped dracut-pre-trigger.service. Feb 9 18:32:14.961634 systemd[1]: iscsid.service: Deactivated successfully. Feb 9 18:32:14.961751 systemd[1]: Stopped iscsid.service. Feb 9 18:32:14.975553 systemd[1]: ignition-mount.service: Deactivated successfully. Feb 9 18:32:14.975628 systemd[1]: Stopped ignition-mount.service. Feb 9 18:32:14.982957 systemd[1]: ignition-disks.service: Deactivated successfully. Feb 9 18:32:14.983054 systemd[1]: Stopped ignition-disks.service. Feb 9 18:32:14.992578 systemd[1]: ignition-kargs.service: Deactivated successfully. Feb 9 18:32:14.992671 systemd[1]: Stopped ignition-kargs.service. Feb 9 18:32:15.000916 systemd[1]: ignition-fetch.service: Deactivated successfully. Feb 9 18:32:15.001008 systemd[1]: Stopped ignition-fetch.service. Feb 9 18:32:15.009862 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Feb 9 18:32:15.009955 systemd[1]: Stopped ignition-fetch-offline.service. Feb 9 18:32:15.019590 systemd[1]: Stopped target paths.target. Feb 9 18:32:15.031420 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Feb 9 18:32:15.043705 systemd[1]: Stopped systemd-ask-password-console.path. Feb 9 18:32:15.055743 systemd[1]: Stopped target slices.target. Feb 9 18:32:15.066095 systemd[1]: Stopped target sockets.target. Feb 9 18:32:15.077903 systemd[1]: iscsid.socket: Deactivated successfully. Feb 9 18:32:15.077992 systemd[1]: Closed iscsid.socket. Feb 9 18:32:15.088825 systemd[1]: ignition-setup.service: Deactivated successfully. Feb 9 18:32:15.088915 systemd[1]: Stopped ignition-setup.service. Feb 9 18:32:15.097109 systemd[1]: Stopping iscsiuio.service... Feb 9 18:32:15.111420 systemd[1]: sysroot-tmp-_translate\x2dfilesystem\x2d0\x2dign1.mount: Deactivated successfully. Feb 9 18:32:15.111512 systemd[1]: sysroot-boot.mount: Deactivated successfully. Feb 9 18:32:15.112294 systemd[1]: iscsiuio.service: Deactivated successfully. Feb 9 18:32:15.112396 systemd[1]: Stopped iscsiuio.service. Feb 9 18:32:15.119107 systemd[1]: sysroot-boot.service: Deactivated successfully. Feb 9 18:32:15.119190 systemd[1]: Stopped sysroot-boot.service. Feb 9 18:32:15.127778 systemd[1]: Stopped target network.target. Feb 9 18:32:15.137280 systemd[1]: iscsiuio.socket: Deactivated successfully. Feb 9 18:32:15.137313 systemd[1]: Closed iscsiuio.socket. Feb 9 18:32:15.145518 systemd[1]: initrd-setup-root.service: Deactivated successfully. Feb 9 18:32:15.486703 systemd-journald[276]: Received SIGTERM from PID 1 (n/a). Feb 9 18:32:15.145562 systemd[1]: Stopped initrd-setup-root.service. Feb 9 18:32:15.158603 systemd[1]: Stopping systemd-networkd.service... Feb 9 18:32:15.166582 systemd[1]: Stopping systemd-resolved.service... Feb 9 18:32:15.175672 systemd[1]: initrd-cleanup.service: Deactivated successfully. Feb 9 18:32:15.175751 systemd-networkd[866]: eth0: DHCPv6 lease lost Feb 9 18:32:15.486000 audit: BPF prog-id=9 op=UNLOAD Feb 9 18:32:15.176549 systemd[1]: Finished initrd-cleanup.service. Feb 9 18:32:15.186145 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 9 18:32:15.186231 systemd[1]: Stopped systemd-networkd.service. Feb 9 18:32:15.195765 systemd[1]: systemd-resolved.service: Deactivated successfully. Feb 9 18:32:15.195852 systemd[1]: Stopped systemd-resolved.service. Feb 9 18:32:15.206797 systemd[1]: systemd-networkd.socket: Deactivated successfully. Feb 9 18:32:15.206840 systemd[1]: Closed systemd-networkd.socket. Feb 9 18:32:15.220640 systemd[1]: Stopping network-cleanup.service... Feb 9 18:32:15.229973 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Feb 9 18:32:15.230045 systemd[1]: Stopped parse-ip-for-networkd.service. Feb 9 18:32:15.238643 systemd[1]: systemd-sysctl.service: Deactivated successfully. Feb 9 18:32:15.238702 systemd[1]: Stopped systemd-sysctl.service. Feb 9 18:32:15.250497 systemd[1]: systemd-modules-load.service: Deactivated successfully. Feb 9 18:32:15.250536 systemd[1]: Stopped systemd-modules-load.service. Feb 9 18:32:15.255372 systemd[1]: Stopping systemd-udevd.service... Feb 9 18:32:15.264390 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Feb 9 18:32:15.272788 systemd[1]: systemd-udevd.service: Deactivated successfully. Feb 9 18:32:15.272948 systemd[1]: Stopped systemd-udevd.service. Feb 9 18:32:15.281412 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Feb 9 18:32:15.281455 systemd[1]: Closed systemd-udevd-control.socket. Feb 9 18:32:15.289776 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Feb 9 18:32:15.289808 systemd[1]: Closed systemd-udevd-kernel.socket. Feb 9 18:32:15.299517 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Feb 9 18:32:15.299563 systemd[1]: Stopped dracut-pre-udev.service. Feb 9 18:32:15.309004 systemd[1]: dracut-cmdline.service: Deactivated successfully. Feb 9 18:32:15.309044 systemd[1]: Stopped dracut-cmdline.service. Feb 9 18:32:15.317663 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Feb 9 18:32:15.317738 systemd[1]: Stopped dracut-cmdline-ask.service. Feb 9 18:32:15.342118 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Feb 9 18:32:15.358817 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully. Feb 9 18:32:15.358889 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service. Feb 9 18:32:15.377188 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Feb 9 18:32:15.377265 systemd[1]: Stopped kmod-static-nodes.service. Feb 9 18:32:15.383209 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Feb 9 18:32:15.383254 systemd[1]: Stopped systemd-vconsole-setup.service. Feb 9 18:32:15.396563 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dsetup\x2ddev.service.mount: Deactivated successfully. Feb 9 18:32:15.397094 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Feb 9 18:32:15.397192 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Feb 9 18:32:15.413412 systemd[1]: network-cleanup.service: Deactivated successfully. Feb 9 18:32:15.413510 systemd[1]: Stopped network-cleanup.service. Feb 9 18:32:15.426587 systemd[1]: Reached target initrd-switch-root.target. Feb 9 18:32:15.440305 systemd[1]: Starting initrd-switch-root.service... Feb 9 18:32:15.457557 systemd[1]: Switching root. Feb 9 18:32:15.488014 systemd-journald[276]: Journal stopped Feb 9 18:32:27.880084 kernel: SELinux: Class mctp_socket not defined in policy. Feb 9 18:32:27.880112 kernel: SELinux: Class anon_inode not defined in policy. Feb 9 18:32:27.880123 kernel: SELinux: the above unknown classes and permissions will be allowed Feb 9 18:32:27.880135 kernel: SELinux: policy capability network_peer_controls=1 Feb 9 18:32:27.880143 kernel: SELinux: policy capability open_perms=1 Feb 9 18:32:27.880151 kernel: SELinux: policy capability extended_socket_class=1 Feb 9 18:32:27.880160 kernel: SELinux: policy capability always_check_network=0 Feb 9 18:32:27.880171 kernel: SELinux: policy capability cgroup_seclabel=1 Feb 9 18:32:27.880179 kernel: SELinux: policy capability nnp_nosuid_transition=1 Feb 9 18:32:27.880187 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Feb 9 18:32:27.880197 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Feb 9 18:32:27.880207 kernel: kauditd_printk_skb: 43 callbacks suppressed Feb 9 18:32:27.880215 kernel: audit: type=1403 audit(1707503537.241:82): auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 9 18:32:27.880230 systemd[1]: Successfully loaded SELinux policy in 277.008ms. Feb 9 18:32:27.880242 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 46.232ms. Feb 9 18:32:27.880254 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Feb 9 18:32:27.880263 systemd[1]: Detected virtualization microsoft. Feb 9 18:32:27.880272 systemd[1]: Detected architecture arm64. Feb 9 18:32:27.880285 systemd[1]: Detected first boot. Feb 9 18:32:27.880296 systemd[1]: Hostname set to . Feb 9 18:32:27.880305 systemd[1]: Initializing machine ID from random generator. Feb 9 18:32:27.880314 kernel: audit: type=1400 audit(1707503538.018:83): avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:32:27.880326 kernel: audit: type=1400 audit(1707503538.018:84): avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:32:27.880334 kernel: audit: type=1334 audit(1707503538.037:85): prog-id=10 op=LOAD Feb 9 18:32:27.880346 kernel: audit: type=1334 audit(1707503538.037:86): prog-id=10 op=UNLOAD Feb 9 18:32:27.880355 kernel: audit: type=1334 audit(1707503538.055:87): prog-id=11 op=LOAD Feb 9 18:32:27.880363 kernel: audit: type=1334 audit(1707503538.055:88): prog-id=11 op=UNLOAD Feb 9 18:32:27.880372 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Feb 9 18:32:27.880382 kernel: audit: type=1400 audit(1707503539.311:89): avc: denied { associate } for pid=1101 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Feb 9 18:32:27.880393 kernel: audit: type=1300 audit(1707503539.311:89): arch=c00000b7 syscall=5 success=yes exit=0 a0=40001458c4 a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1084 pid=1101 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:27.880402 kernel: audit: type=1327 audit(1707503539.311:89): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:32:27.880411 systemd[1]: Populated /etc with preset unit settings. Feb 9 18:32:27.880422 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Feb 9 18:32:27.880434 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Feb 9 18:32:27.880445 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Feb 9 18:32:27.880455 kernel: kauditd_printk_skb: 6 callbacks suppressed Feb 9 18:32:27.880464 kernel: audit: type=1334 audit(1707503547.065:91): prog-id=12 op=LOAD Feb 9 18:32:27.880472 kernel: audit: type=1334 audit(1707503547.065:92): prog-id=3 op=UNLOAD Feb 9 18:32:27.880481 systemd[1]: initrd-switch-root.service: Deactivated successfully. Feb 9 18:32:27.880490 kernel: audit: type=1334 audit(1707503547.072:93): prog-id=13 op=LOAD Feb 9 18:32:27.880504 systemd[1]: Stopped initrd-switch-root.service. Feb 9 18:32:27.880514 kernel: audit: type=1334 audit(1707503547.078:94): prog-id=14 op=LOAD Feb 9 18:32:27.880524 kernel: audit: type=1334 audit(1707503547.078:95): prog-id=4 op=UNLOAD Feb 9 18:32:27.880533 kernel: audit: type=1334 audit(1707503547.078:96): prog-id=5 op=UNLOAD Feb 9 18:32:27.880542 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Feb 9 18:32:27.880552 kernel: audit: type=1131 audit(1707503547.080:97): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.880561 systemd[1]: Created slice system-addon\x2dconfig.slice. Feb 9 18:32:27.880573 kernel: audit: type=1334 audit(1707503547.117:98): prog-id=12 op=UNLOAD Feb 9 18:32:27.880583 kernel: audit: type=1130 audit(1707503547.128:99): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.880592 kernel: audit: type=1131 audit(1707503547.128:100): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.880603 systemd[1]: Created slice system-addon\x2drun.slice. Feb 9 18:32:27.880612 systemd[1]: Created slice system-getty.slice. Feb 9 18:32:27.880624 systemd[1]: Created slice system-modprobe.slice. Feb 9 18:32:27.880635 systemd[1]: Created slice system-serial\x2dgetty.slice. Feb 9 18:32:27.880645 systemd[1]: Created slice system-system\x2dcloudinit.slice. Feb 9 18:32:27.880654 systemd[1]: Created slice system-systemd\x2dfsck.slice. Feb 9 18:32:27.880663 systemd[1]: Created slice user.slice. Feb 9 18:32:27.880673 systemd[1]: Started systemd-ask-password-console.path. Feb 9 18:32:27.880682 systemd[1]: Started systemd-ask-password-wall.path. Feb 9 18:32:27.880705 systemd[1]: Set up automount boot.automount. Feb 9 18:32:27.880715 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Feb 9 18:32:27.880725 systemd[1]: Stopped target initrd-switch-root.target. Feb 9 18:32:27.880734 systemd[1]: Stopped target initrd-fs.target. Feb 9 18:32:27.880743 systemd[1]: Stopped target initrd-root-fs.target. Feb 9 18:32:27.880753 systemd[1]: Reached target integritysetup.target. Feb 9 18:32:27.880766 systemd[1]: Reached target remote-cryptsetup.target. Feb 9 18:32:27.880777 systemd[1]: Reached target remote-fs.target. Feb 9 18:32:27.880786 systemd[1]: Reached target slices.target. Feb 9 18:32:27.880796 systemd[1]: Reached target swap.target. Feb 9 18:32:27.880805 systemd[1]: Reached target torcx.target. Feb 9 18:32:27.880817 systemd[1]: Reached target veritysetup.target. Feb 9 18:32:27.880826 systemd[1]: Listening on systemd-coredump.socket. Feb 9 18:32:27.880836 systemd[1]: Listening on systemd-initctl.socket. Feb 9 18:32:27.880845 systemd[1]: Listening on systemd-networkd.socket. Feb 9 18:32:27.880858 systemd[1]: Listening on systemd-udevd-control.socket. Feb 9 18:32:27.880868 systemd[1]: Listening on systemd-udevd-kernel.socket. Feb 9 18:32:27.880877 systemd[1]: Listening on systemd-userdbd.socket. Feb 9 18:32:27.880887 systemd[1]: Mounting dev-hugepages.mount... Feb 9 18:32:27.880896 systemd[1]: Mounting dev-mqueue.mount... Feb 9 18:32:27.880906 systemd[1]: Mounting media.mount... Feb 9 18:32:27.880917 systemd[1]: Mounting sys-kernel-debug.mount... Feb 9 18:32:27.880926 systemd[1]: Mounting sys-kernel-tracing.mount... Feb 9 18:32:27.880936 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Feb 9 18:32:27.880945 systemd[1]: Mounting tmp.mount... Feb 9 18:32:27.880955 systemd[1]: Starting flatcar-tmpfiles.service... Feb 9 18:32:27.880965 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Feb 9 18:32:27.880974 systemd[1]: Starting kmod-static-nodes.service... Feb 9 18:32:27.880984 systemd[1]: Starting modprobe@configfs.service... Feb 9 18:32:27.880993 systemd[1]: Starting modprobe@dm_mod.service... Feb 9 18:32:27.881004 systemd[1]: Starting modprobe@drm.service... Feb 9 18:32:27.881013 systemd[1]: Starting modprobe@efi_pstore.service... Feb 9 18:32:27.881023 systemd[1]: Starting modprobe@fuse.service... Feb 9 18:32:27.881032 systemd[1]: Starting modprobe@loop.service... Feb 9 18:32:27.881042 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Feb 9 18:32:27.881051 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Feb 9 18:32:27.881062 systemd[1]: Stopped systemd-fsck-root.service. Feb 9 18:32:27.881071 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Feb 9 18:32:27.881080 kernel: loop: module loaded Feb 9 18:32:27.881091 kernel: fuse: init (API version 7.34) Feb 9 18:32:27.881100 systemd[1]: Stopped systemd-fsck-usr.service. Feb 9 18:32:27.881109 systemd[1]: Stopped systemd-journald.service. Feb 9 18:32:27.881119 systemd[1]: systemd-journald.service: Consumed 2.592s CPU time. Feb 9 18:32:27.881128 systemd[1]: Starting systemd-journald.service... Feb 9 18:32:27.881138 systemd[1]: Starting systemd-modules-load.service... Feb 9 18:32:27.881147 systemd[1]: Starting systemd-network-generator.service... Feb 9 18:32:27.881157 systemd[1]: Starting systemd-remount-fs.service... Feb 9 18:32:27.881166 systemd[1]: Starting systemd-udev-trigger.service... Feb 9 18:32:27.881177 systemd[1]: verity-setup.service: Deactivated successfully. Feb 9 18:32:27.881186 systemd[1]: Stopped verity-setup.service. Feb 9 18:32:27.881199 systemd-journald[1207]: Journal started Feb 9 18:32:27.881241 systemd-journald[1207]: Runtime Journal (/run/log/journal/8f4026f18fbe48c4acacf11ebed54f14) is 8.0M, max 78.6M, 70.6M free. Feb 9 18:32:17.241000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Feb 9 18:32:18.018000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:32:18.018000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Feb 9 18:32:18.037000 audit: BPF prog-id=10 op=LOAD Feb 9 18:32:18.037000 audit: BPF prog-id=10 op=UNLOAD Feb 9 18:32:18.055000 audit: BPF prog-id=11 op=LOAD Feb 9 18:32:18.055000 audit: BPF prog-id=11 op=UNLOAD Feb 9 18:32:19.311000 audit[1101]: AVC avc: denied { associate } for pid=1101 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Feb 9 18:32:19.311000 audit[1101]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001458c4 a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1084 pid=1101 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:19.311000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:32:19.321000 audit[1101]: AVC avc: denied { associate } for pid=1101 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Feb 9 18:32:19.321000 audit[1101]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40001459a9 a2=1ed a3=0 items=2 ppid=1084 pid=1101 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:19.321000 audit: CWD cwd="/" Feb 9 18:32:19.321000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:19.321000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:19.321000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Feb 9 18:32:27.065000 audit: BPF prog-id=12 op=LOAD Feb 9 18:32:27.065000 audit: BPF prog-id=3 op=UNLOAD Feb 9 18:32:27.072000 audit: BPF prog-id=13 op=LOAD Feb 9 18:32:27.078000 audit: BPF prog-id=14 op=LOAD Feb 9 18:32:27.078000 audit: BPF prog-id=4 op=UNLOAD Feb 9 18:32:27.078000 audit: BPF prog-id=5 op=UNLOAD Feb 9 18:32:27.080000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.117000 audit: BPF prog-id=12 op=UNLOAD Feb 9 18:32:27.128000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.128000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.748000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.775000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.786000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.786000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.793000 audit: BPF prog-id=15 op=LOAD Feb 9 18:32:27.793000 audit: BPF prog-id=16 op=LOAD Feb 9 18:32:27.793000 audit: BPF prog-id=17 op=LOAD Feb 9 18:32:27.793000 audit: BPF prog-id=13 op=UNLOAD Feb 9 18:32:27.793000 audit: BPF prog-id=14 op=UNLOAD Feb 9 18:32:27.872000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Feb 9 18:32:27.872000 audit[1207]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffd71120f0 a2=4000 a3=1 items=0 ppid=1 pid=1207 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:27.872000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Feb 9 18:32:19.228348 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.2 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.2 /var/lib/torcx/store]" Feb 9 18:32:27.064640 systemd[1]: Queued start job for default target multi-user.target. Feb 9 18:32:19.255531 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Feb 9 18:32:27.080386 systemd[1]: systemd-journald.service: Deactivated successfully. Feb 9 18:32:19.255576 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Feb 9 18:32:27.080770 systemd[1]: systemd-journald.service: Consumed 2.592s CPU time. Feb 9 18:32:19.255634 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Feb 9 18:32:19.255645 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="skipped missing lower profile" missing profile=oem Feb 9 18:32:19.255712 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Feb 9 18:32:19.255727 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Feb 9 18:32:19.255970 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Feb 9 18:32:19.256016 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Feb 9 18:32:19.256029 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Feb 9 18:32:19.290766 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Feb 9 18:32:19.290848 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Feb 9 18:32:19.290894 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.2: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.2 Feb 9 18:32:19.290908 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Feb 9 18:32:19.290927 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.2: no such file or directory" path=/var/lib/torcx/store/3510.3.2 Feb 9 18:32:19.290941 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:19Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Feb 9 18:32:25.487241 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:32:25.487530 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:32:25.487645 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:32:25.487836 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Feb 9 18:32:25.487897 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Feb 9 18:32:25.487965 /usr/lib/systemd/system-generators/torcx-generator[1101]: time="2024-02-09T18:32:25Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Feb 9 18:32:27.891000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.904274 systemd[1]: Started systemd-journald.service. Feb 9 18:32:27.903000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.905095 systemd[1]: Mounted dev-hugepages.mount. Feb 9 18:32:27.910159 systemd[1]: Mounted dev-mqueue.mount. Feb 9 18:32:27.915447 systemd[1]: Mounted media.mount. Feb 9 18:32:27.927915 systemd[1]: Mounted sys-kernel-debug.mount. Feb 9 18:32:27.933590 systemd[1]: Mounted sys-kernel-tracing.mount. Feb 9 18:32:27.939711 systemd[1]: Mounted tmp.mount. Feb 9 18:32:27.944381 systemd[1]: Finished flatcar-tmpfiles.service. Feb 9 18:32:27.950000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.951702 systemd[1]: Finished kmod-static-nodes.service. Feb 9 18:32:27.957000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.958271 systemd[1]: modprobe@configfs.service: Deactivated successfully. Feb 9 18:32:27.958403 systemd[1]: Finished modprobe@configfs.service. Feb 9 18:32:27.963000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.963000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.964140 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Feb 9 18:32:27.964392 systemd[1]: Finished modprobe@dm_mod.service. Feb 9 18:32:27.969000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.969000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.969993 systemd[1]: modprobe@drm.service: Deactivated successfully. Feb 9 18:32:27.970111 systemd[1]: Finished modprobe@drm.service. Feb 9 18:32:27.974000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.974000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.975576 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Feb 9 18:32:27.975713 systemd[1]: Finished modprobe@efi_pstore.service. Feb 9 18:32:27.981000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.981000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.981830 systemd[1]: modprobe@fuse.service: Deactivated successfully. Feb 9 18:32:27.981941 systemd[1]: Finished modprobe@fuse.service. Feb 9 18:32:27.986000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.986000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.987105 systemd[1]: modprobe@loop.service: Deactivated successfully. Feb 9 18:32:27.987255 systemd[1]: Finished modprobe@loop.service. Feb 9 18:32:27.992000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.992000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.993148 systemd[1]: Finished systemd-modules-load.service. Feb 9 18:32:27.998000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:27.999256 systemd[1]: Finished systemd-network-generator.service. Feb 9 18:32:28.004000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.005657 systemd[1]: Finished systemd-remount-fs.service. Feb 9 18:32:28.011000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.011568 systemd[1]: Finished systemd-udev-trigger.service. Feb 9 18:32:28.016000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.017681 systemd[1]: Reached target network-pre.target. Feb 9 18:32:28.024533 systemd[1]: Mounting sys-fs-fuse-connections.mount... Feb 9 18:32:28.030938 systemd[1]: Mounting sys-kernel-config.mount... Feb 9 18:32:28.035893 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Feb 9 18:32:28.037237 systemd[1]: Starting systemd-hwdb-update.service... Feb 9 18:32:28.043141 systemd[1]: Starting systemd-journal-flush.service... Feb 9 18:32:28.048436 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Feb 9 18:32:28.049417 systemd[1]: Starting systemd-random-seed.service... Feb 9 18:32:28.054717 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Feb 9 18:32:28.055720 systemd[1]: Starting systemd-sysctl.service... Feb 9 18:32:28.061333 systemd[1]: Starting systemd-sysusers.service... Feb 9 18:32:28.067103 systemd[1]: Starting systemd-udev-settle.service... Feb 9 18:32:28.075940 systemd[1]: Mounted sys-fs-fuse-connections.mount. Feb 9 18:32:28.081935 systemd[1]: Mounted sys-kernel-config.mount. Feb 9 18:32:28.089171 udevadm[1221]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Feb 9 18:32:28.090523 systemd-journald[1207]: Time spent on flushing to /var/log/journal/8f4026f18fbe48c4acacf11ebed54f14 is 15.638ms for 1085 entries. Feb 9 18:32:28.090523 systemd-journald[1207]: System Journal (/var/log/journal/8f4026f18fbe48c4acacf11ebed54f14) is 8.0M, max 2.6G, 2.6G free. Feb 9 18:32:28.160378 systemd-journald[1207]: Received client request to flush runtime journal. Feb 9 18:32:28.105000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.145000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.100550 systemd[1]: Finished systemd-random-seed.service. Feb 9 18:32:28.106466 systemd[1]: Reached target first-boot-complete.target. Feb 9 18:32:28.136747 systemd[1]: Finished systemd-sysctl.service. Feb 9 18:32:28.161291 systemd[1]: Finished systemd-journal-flush.service. Feb 9 18:32:28.166000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.606279 systemd[1]: Finished systemd-sysusers.service. Feb 9 18:32:28.610000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.612363 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Feb 9 18:32:28.958000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:28.953013 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Feb 9 18:32:29.019174 systemd[1]: Finished systemd-hwdb-update.service. Feb 9 18:32:29.023000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:29.023000 audit: BPF prog-id=18 op=LOAD Feb 9 18:32:29.023000 audit: BPF prog-id=19 op=LOAD Feb 9 18:32:29.023000 audit: BPF prog-id=7 op=UNLOAD Feb 9 18:32:29.024000 audit: BPF prog-id=8 op=UNLOAD Feb 9 18:32:29.025330 systemd[1]: Starting systemd-udevd.service... Feb 9 18:32:29.042738 systemd-udevd[1226]: Using default interface naming scheme 'v252'. Feb 9 18:32:29.268524 systemd[1]: Started systemd-udevd.service. Feb 9 18:32:29.277000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:29.278000 audit: BPF prog-id=20 op=LOAD Feb 9 18:32:29.279756 systemd[1]: Starting systemd-networkd.service... Feb 9 18:32:29.306659 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Feb 9 18:32:29.350000 audit: BPF prog-id=21 op=LOAD Feb 9 18:32:29.350000 audit: BPF prog-id=22 op=LOAD Feb 9 18:32:29.350000 audit: BPF prog-id=23 op=LOAD Feb 9 18:32:29.352330 systemd[1]: Starting systemd-userdbd.service... Feb 9 18:32:29.357000 audit[1230]: AVC avc: denied { confidentiality } for pid=1230 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Feb 9 18:32:29.376937 kernel: mousedev: PS/2 mouse device common for all mice Feb 9 18:32:29.377318 kernel: hv_vmbus: registering driver hv_balloon Feb 9 18:32:29.377336 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Feb 9 18:32:29.377349 kernel: hv_balloon: Memory hot add disabled on ARM64 Feb 9 18:32:29.357000 audit[1230]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaaf3b70080 a1=aa2c a2=ffff82f924b0 a3=aaaaf3ad0010 items=12 ppid=1226 pid=1230 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:29.357000 audit: CWD cwd="/" Feb 9 18:32:29.357000 audit: PATH item=0 name=(null) inode=6396 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=1 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=2 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=3 name=(null) inode=11307 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=4 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=5 name=(null) inode=11308 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=6 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=7 name=(null) inode=11309 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=8 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=9 name=(null) inode=11310 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=10 name=(null) inode=11306 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PATH item=11 name=(null) inode=11311 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Feb 9 18:32:29.357000 audit: PROCTITLE proctitle="(udev-worker)" Feb 9 18:32:29.413715 kernel: hv_vmbus: registering driver hyperv_fb Feb 9 18:32:29.413805 kernel: hv_utils: Registering HyperV Utility Driver Feb 9 18:32:29.427012 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Feb 9 18:32:29.427099 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Feb 9 18:32:29.438660 kernel: Console: switching to colour dummy device 80x25 Feb 9 18:32:29.438751 kernel: hv_vmbus: registering driver hv_utils Feb 9 18:32:29.440815 kernel: hv_utils: Heartbeat IC version 3.0 Feb 9 18:32:29.447812 kernel: hv_utils: Shutdown IC version 3.2 Feb 9 18:32:29.448749 kernel: Console: switching to colour frame buffer device 128x48 Feb 9 18:32:29.457387 kernel: hv_utils: TimeSync IC version 4.0 Feb 9 18:32:29.457518 systemd[1]: Started systemd-userdbd.service. Feb 9 18:32:29.600000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:29.801254 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1234) Feb 9 18:32:29.823178 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Feb 9 18:32:29.830166 systemd[1]: Finished systemd-udev-settle.service. Feb 9 18:32:29.834000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:29.836183 systemd[1]: Starting lvm2-activation-early.service... Feb 9 18:32:29.901381 systemd-networkd[1247]: lo: Link UP Feb 9 18:32:29.901635 systemd-networkd[1247]: lo: Gained carrier Feb 9 18:32:29.902089 systemd-networkd[1247]: Enumeration completed Feb 9 18:32:29.902248 systemd[1]: Started systemd-networkd.service. Feb 9 18:32:29.906000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:29.907918 systemd[1]: Starting systemd-networkd-wait-online.service... Feb 9 18:32:29.939633 systemd-networkd[1247]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 9 18:32:29.987252 kernel: mlx5_core 79f8:00:02.0 enP31224s1: Link up Feb 9 18:32:30.013240 kernel: hv_netvsc 002248b9-fff4-0022-48b9-fff4002248b9 eth0: Data path switched to VF: enP31224s1 Feb 9 18:32:30.013361 systemd-networkd[1247]: enP31224s1: Link UP Feb 9 18:32:30.013466 systemd-networkd[1247]: eth0: Link UP Feb 9 18:32:30.013470 systemd-networkd[1247]: eth0: Gained carrier Feb 9 18:32:30.021545 systemd-networkd[1247]: enP31224s1: Gained carrier Feb 9 18:32:30.039333 systemd-networkd[1247]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Feb 9 18:32:30.136240 lvm[1303]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 9 18:32:30.176119 systemd[1]: Finished lvm2-activation-early.service. Feb 9 18:32:30.180000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.181016 systemd[1]: Reached target cryptsetup.target. Feb 9 18:32:30.186716 systemd[1]: Starting lvm2-activation.service... Feb 9 18:32:30.190732 lvm[1305]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Feb 9 18:32:30.216137 systemd[1]: Finished lvm2-activation.service. Feb 9 18:32:30.220000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.220816 systemd[1]: Reached target local-fs-pre.target. Feb 9 18:32:30.225392 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Feb 9 18:32:30.225418 systemd[1]: Reached target local-fs.target. Feb 9 18:32:30.229576 systemd[1]: Reached target machines.target. Feb 9 18:32:30.235140 systemd[1]: Starting ldconfig.service... Feb 9 18:32:30.238992 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Feb 9 18:32:30.239055 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:32:30.240156 systemd[1]: Starting systemd-boot-update.service... Feb 9 18:32:30.245327 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Feb 9 18:32:30.251895 systemd[1]: Starting systemd-machine-id-commit.service... Feb 9 18:32:30.256497 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Feb 9 18:32:30.256551 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Feb 9 18:32:30.257576 systemd[1]: Starting systemd-tmpfiles-setup.service... Feb 9 18:32:30.283460 systemd-tmpfiles[1310]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Feb 9 18:32:30.298591 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1307 (bootctl) Feb 9 18:32:30.299836 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Feb 9 18:32:30.351132 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Feb 9 18:32:30.356000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.365668 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Feb 9 18:32:30.366241 systemd[1]: Finished systemd-machine-id-commit.service. Feb 9 18:32:30.371000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.371383 systemd-tmpfiles[1310]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Feb 9 18:32:30.372552 systemd-tmpfiles[1310]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Feb 9 18:32:30.509411 systemd-fsck[1315]: fsck.fat 4.2 (2021-01-31) Feb 9 18:32:30.509411 systemd-fsck[1315]: /dev/sda1: 236 files, 113719/258078 clusters Feb 9 18:32:30.511353 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Feb 9 18:32:30.517000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.518892 systemd[1]: Mounting boot.mount... Feb 9 18:32:30.531753 systemd[1]: Mounted boot.mount. Feb 9 18:32:30.541603 systemd[1]: Finished systemd-boot-update.service. Feb 9 18:32:30.546000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.844478 systemd[1]: Finished systemd-tmpfiles-setup.service. Feb 9 18:32:30.849000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.851285 systemd[1]: Starting audit-rules.service... Feb 9 18:32:30.856241 systemd[1]: Starting clean-ca-certificates.service... Feb 9 18:32:30.861802 systemd[1]: Starting systemd-journal-catalog-update.service... Feb 9 18:32:30.867000 audit: BPF prog-id=24 op=LOAD Feb 9 18:32:30.869060 systemd[1]: Starting systemd-resolved.service... Feb 9 18:32:30.873000 audit: BPF prog-id=25 op=LOAD Feb 9 18:32:30.874916 systemd[1]: Starting systemd-timesyncd.service... Feb 9 18:32:30.880449 systemd[1]: Starting systemd-update-utmp.service... Feb 9 18:32:30.915000 audit[1327]: SYSTEM_BOOT pid=1327 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.920000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.915583 systemd[1]: Finished clean-ca-certificates.service. Feb 9 18:32:30.923074 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Feb 9 18:32:30.926191 systemd[1]: Finished systemd-update-utmp.service. Feb 9 18:32:30.931000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.980142 systemd[1]: Finished systemd-journal-catalog-update.service. Feb 9 18:32:30.986000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.986600 systemd[1]: Started systemd-timesyncd.service. Feb 9 18:32:30.992000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:30.992459 systemd[1]: Reached target time-set.target. Feb 9 18:32:31.078109 systemd-resolved[1324]: Positive Trust Anchors: Feb 9 18:32:31.078480 systemd-resolved[1324]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Feb 9 18:32:31.078567 systemd-resolved[1324]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Feb 9 18:32:31.115316 systemd-resolved[1324]: Using system hostname 'ci-3510.3.2-a-7e5e2e1465'. Feb 9 18:32:31.117299 systemd[1]: Started systemd-resolved.service. Feb 9 18:32:31.122000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:32:31.122333 systemd[1]: Reached target network.target. Feb 9 18:32:31.127001 systemd[1]: Reached target nss-lookup.target. Feb 9 18:32:31.237792 systemd-timesyncd[1326]: Contacted time server 5.78.89.3:123 (0.flatcar.pool.ntp.org). Feb 9 18:32:31.238166 systemd-timesyncd[1326]: Initial clock synchronization to Fri 2024-02-09 18:32:31.238876 UTC. Feb 9 18:32:31.247000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Feb 9 18:32:31.247000 audit[1342]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe435b1d0 a2=420 a3=0 items=0 ppid=1321 pid=1342 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:32:31.247000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Feb 9 18:32:31.248067 augenrules[1342]: No rules Feb 9 18:32:31.248836 systemd[1]: Finished audit-rules.service. Feb 9 18:32:32.060400 systemd-networkd[1247]: eth0: Gained IPv6LL Feb 9 18:32:32.062224 systemd[1]: Finished systemd-networkd-wait-online.service. Feb 9 18:32:32.069485 systemd[1]: Reached target network-online.target. Feb 9 18:32:39.436837 ldconfig[1306]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Feb 9 18:32:39.446685 systemd[1]: Finished ldconfig.service. Feb 9 18:32:39.453065 systemd[1]: Starting systemd-update-done.service... Feb 9 18:32:39.487669 systemd[1]: Finished systemd-update-done.service. Feb 9 18:32:39.493078 systemd[1]: Reached target sysinit.target. Feb 9 18:32:39.498081 systemd[1]: Started motdgen.path. Feb 9 18:32:39.502169 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Feb 9 18:32:39.509338 systemd[1]: Started logrotate.timer. Feb 9 18:32:39.513461 systemd[1]: Started mdadm.timer. Feb 9 18:32:39.517397 systemd[1]: Started systemd-tmpfiles-clean.timer. Feb 9 18:32:39.522286 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Feb 9 18:32:39.522315 systemd[1]: Reached target paths.target. Feb 9 18:32:39.526754 systemd[1]: Reached target timers.target. Feb 9 18:32:39.531764 systemd[1]: Listening on dbus.socket. Feb 9 18:32:39.537137 systemd[1]: Starting docker.socket... Feb 9 18:32:39.570111 systemd[1]: Listening on sshd.socket. Feb 9 18:32:39.574466 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:32:39.574948 systemd[1]: Listening on docker.socket. Feb 9 18:32:39.579039 systemd[1]: Reached target sockets.target. Feb 9 18:32:39.583668 systemd[1]: Reached target basic.target. Feb 9 18:32:39.588682 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Feb 9 18:32:39.588709 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Feb 9 18:32:39.589793 systemd[1]: Starting containerd.service... Feb 9 18:32:39.594816 systemd[1]: Starting coreos-metadata.service... Feb 9 18:32:39.599945 systemd[1]: Starting dbus.service... Feb 9 18:32:39.604864 systemd[1]: Starting enable-oem-cloudinit.service... Feb 9 18:32:39.610411 systemd[1]: Starting extend-filesystems.service... Feb 9 18:32:39.615165 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Feb 9 18:32:39.616158 systemd[1]: Starting motdgen.service... Feb 9 18:32:39.623132 systemd[1]: Started nvidia.service. Feb 9 18:32:39.627956 systemd[1]: Starting ssh-key-proc-cmdline.service... Feb 9 18:32:39.633696 systemd[1]: Starting sshd-keygen.service... Feb 9 18:32:39.639987 systemd[1]: Starting systemd-logind.service... Feb 9 18:32:39.644582 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Feb 9 18:32:39.644644 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Feb 9 18:32:39.645104 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Feb 9 18:32:39.646965 systemd[1]: Starting update-engine.service... Feb 9 18:32:39.652717 systemd[1]: Starting update-ssh-keys-after-ignition.service... Feb 9 18:32:39.661378 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Feb 9 18:32:39.661586 systemd[1]: Finished ssh-key-proc-cmdline.service. Feb 9 18:32:39.699861 jq[1371]: true Feb 9 18:32:39.700653 jq[1355]: false Feb 9 18:32:39.701204 coreos-metadata[1351]: Feb 09 18:32:39.701 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Feb 9 18:32:39.703388 coreos-metadata[1351]: Feb 09 18:32:39.703 INFO Fetch successful Feb 9 18:32:39.704331 coreos-metadata[1351]: Feb 09 18:32:39.704 INFO Fetching http://168.63.129.16/machine/?comp=goalstate: Attempt #1 Feb 9 18:32:39.708984 coreos-metadata[1351]: Feb 09 18:32:39.708 INFO Fetch successful Feb 9 18:32:39.711082 coreos-metadata[1351]: Feb 09 18:32:39.711 INFO Fetching http://168.63.129.16/machine/8bd0f7d9-a380-4400-91c5-beeefb4ed55a/d9c16755%2D2cc2%2D4c56%2D9b90%2D57d72734ba1e.%5Fci%2D3510.3.2%2Da%2D7e5e2e1465?comp=config&type=sharedConfig&incarnation=1: Attempt #1 Feb 9 18:32:39.711460 coreos-metadata[1351]: Feb 09 18:32:39.711 INFO Fetch successful Feb 9 18:32:39.712953 systemd[1]: motdgen.service: Deactivated successfully. Feb 9 18:32:39.713124 systemd[1]: Finished motdgen.service. Feb 9 18:32:39.718684 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Feb 9 18:32:39.718852 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Feb 9 18:32:39.730419 extend-filesystems[1356]: Found sda Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda1 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda2 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda3 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found usr Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda4 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda6 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda7 Feb 9 18:32:39.736285 extend-filesystems[1356]: Found sda9 Feb 9 18:32:39.736285 extend-filesystems[1356]: Checking size of /dev/sda9 Feb 9 18:32:39.741434 systemd-logind[1364]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Feb 9 18:32:39.795689 jq[1383]: true Feb 9 18:32:39.741630 systemd-logind[1364]: New seat seat0. Feb 9 18:32:39.798260 coreos-metadata[1351]: Feb 09 18:32:39.797 INFO Fetching http://169.254.169.254/metadata/instance/compute/vmSize?api-version=2017-08-01&format=text: Attempt #1 Feb 9 18:32:39.805618 env[1377]: time="2024-02-09T18:32:39.805575778Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Feb 9 18:32:39.816313 coreos-metadata[1351]: Feb 09 18:32:39.813 INFO Fetch successful Feb 9 18:32:39.825293 systemd[1]: Finished coreos-metadata.service. Feb 9 18:32:39.830051 systemd[1]: Reached target metadata.target. Feb 9 18:32:39.843950 extend-filesystems[1356]: Old size kept for /dev/sda9 Feb 9 18:32:39.848930 extend-filesystems[1356]: Found sr0 Feb 9 18:32:39.844577 systemd[1]: extend-filesystems.service: Deactivated successfully. Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.868370012Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.868535580Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.871856736Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.148-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.871887338Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872106668Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872124109Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872137869Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872147670Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872235274Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.872912 env[1377]: time="2024-02-09T18:32:39.872437684Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Feb 9 18:32:39.844744 systemd[1]: Finished extend-filesystems.service. Feb 9 18:32:39.873197 env[1377]: time="2024-02-09T18:32:39.872557729Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Feb 9 18:32:39.873197 env[1377]: time="2024-02-09T18:32:39.872571810Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Feb 9 18:32:39.873197 env[1377]: time="2024-02-09T18:32:39.872619612Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Feb 9 18:32:39.873197 env[1377]: time="2024-02-09T18:32:39.872630973Z" level=info msg="metadata content store policy set" policy=shared Feb 9 18:32:39.886586 systemd[1]: nvidia.service: Deactivated successfully. Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891744152Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891783674Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891798235Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891830796Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891846597Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891860797Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892251 env[1377]: time="2024-02-09T18:32:39.891873078Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892676 env[1377]: time="2024-02-09T18:32:39.892203614Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892676 env[1377]: time="2024-02-09T18:32:39.892524749Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892676 env[1377]: time="2024-02-09T18:32:39.892541990Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892676 env[1377]: time="2024-02-09T18:32:39.892554510Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.892676 env[1377]: time="2024-02-09T18:32:39.892580071Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.892919767Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893012292Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893248703Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893274504Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893287225Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893327466Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893339547Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893352908Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893364348Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893375669Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893388189Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893399310Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893411350Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893425431Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Feb 9 18:32:39.894332 env[1377]: time="2024-02-09T18:32:39.893537356Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893552237Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893567198Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893579278Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893592919Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893602879Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893619600Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Feb 9 18:32:39.896086 env[1377]: time="2024-02-09T18:32:39.893652682Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Feb 9 18:32:39.896270 env[1377]: time="2024-02-09T18:32:39.893848371Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} 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}" Feb 9 18:32:39.896270 env[1377]: time="2024-02-09T18:32:39.893898773Z" level=info msg="Connect containerd service" Feb 9 18:32:39.896270 env[1377]: time="2024-02-09T18:32:39.893931255Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896694065Z" 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" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896786549Z" level=info msg="Start subscribing containerd event" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896824511Z" level=info msg="Start recovering state" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896887074Z" level=info msg="Start event monitor" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896904435Z" level=info msg="Start snapshots syncer" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896913235Z" level=info msg="Start cni network conf syncer for default" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.896920196Z" level=info msg="Start streaming server" Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.897290213Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.897334015Z" level=info msg=serving... address=/run/containerd/containerd.sock Feb 9 18:32:39.915588 env[1377]: time="2024-02-09T18:32:39.897412659Z" level=info msg="containerd successfully booted in 0.093300s" Feb 9 18:32:39.915788 bash[1413]: Updated "/home/core/.ssh/authorized_keys" Feb 9 18:32:39.897503 systemd[1]: Started containerd.service. Feb 9 18:32:39.909073 systemd[1]: Finished update-ssh-keys-after-ignition.service. Feb 9 18:32:39.954297 dbus-daemon[1354]: [system] SELinux support is enabled Feb 9 18:32:39.954444 systemd[1]: Started dbus.service. Feb 9 18:32:39.959744 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Feb 9 18:32:39.959772 systemd[1]: Reached target system-config.target. Feb 9 18:32:39.966605 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Feb 9 18:32:39.966629 systemd[1]: Reached target user-config.target. Feb 9 18:32:39.972695 dbus-daemon[1354]: [system] Successfully activated service 'org.freedesktop.systemd1' Feb 9 18:32:39.974236 systemd[1]: Started systemd-logind.service. Feb 9 18:32:40.425156 update_engine[1368]: I0209 18:32:40.411921 1368 main.cc:92] Flatcar Update Engine starting Feb 9 18:32:40.482131 systemd[1]: Started update-engine.service. Feb 9 18:32:40.486583 update_engine[1368]: I0209 18:32:40.486553 1368 update_check_scheduler.cc:74] Next update check in 7m57s Feb 9 18:32:40.489136 systemd[1]: Started locksmithd.service. Feb 9 18:32:41.030881 sshd_keygen[1372]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Feb 9 18:32:41.048154 systemd[1]: Finished sshd-keygen.service. Feb 9 18:32:41.054314 systemd[1]: Starting issuegen.service... Feb 9 18:32:41.059502 systemd[1]: Started waagent.service. Feb 9 18:32:41.064106 systemd[1]: issuegen.service: Deactivated successfully. Feb 9 18:32:41.064286 systemd[1]: Finished issuegen.service. Feb 9 18:32:41.069607 systemd[1]: Starting systemd-user-sessions.service... Feb 9 18:32:41.114530 systemd[1]: Finished systemd-user-sessions.service. Feb 9 18:32:41.121010 systemd[1]: Started getty@tty1.service. Feb 9 18:32:41.126620 systemd[1]: Started serial-getty@ttyAMA0.service. Feb 9 18:32:41.131473 systemd[1]: Reached target getty.target. Feb 9 18:32:41.136108 systemd[1]: Reached target multi-user.target. Feb 9 18:32:41.142043 systemd[1]: Starting systemd-update-utmp-runlevel.service... Feb 9 18:32:41.153393 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Feb 9 18:32:41.153562 systemd[1]: Finished systemd-update-utmp-runlevel.service. Feb 9 18:32:41.158978 systemd[1]: Startup finished in 712ms (kernel) + 12.136s (initrd) + 24.248s (userspace) = 37.097s. Feb 9 18:32:41.801505 locksmithd[1453]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Feb 9 18:32:41.892153 login[1471]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 9 18:32:41.893704 login[1472]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Feb 9 18:32:41.931426 systemd[1]: Created slice user-500.slice. Feb 9 18:32:41.932502 systemd[1]: Starting user-runtime-dir@500.service... Feb 9 18:32:41.936529 systemd-logind[1364]: New session 1 of user core. Feb 9 18:32:41.938977 systemd-logind[1364]: New session 2 of user core. Feb 9 18:32:41.975455 systemd[1]: Finished user-runtime-dir@500.service. Feb 9 18:32:41.976849 systemd[1]: Starting user@500.service... Feb 9 18:32:41.996389 (systemd)[1475]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:32:42.189363 systemd[1475]: Queued start job for default target default.target. Feb 9 18:32:42.189948 systemd[1475]: Reached target paths.target. Feb 9 18:32:42.189968 systemd[1475]: Reached target sockets.target. Feb 9 18:32:42.189978 systemd[1475]: Reached target timers.target. Feb 9 18:32:42.189988 systemd[1475]: Reached target basic.target. Feb 9 18:32:42.190031 systemd[1475]: Reached target default.target. Feb 9 18:32:42.190056 systemd[1475]: Startup finished in 188ms. Feb 9 18:32:42.190102 systemd[1]: Started user@500.service. Feb 9 18:32:42.191030 systemd[1]: Started session-1.scope. Feb 9 18:32:42.191574 systemd[1]: Started session-2.scope. Feb 9 18:32:46.292678 waagent[1469]: 2024-02-09T18:32:46.292566Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Feb 9 18:32:46.299814 waagent[1469]: 2024-02-09T18:32:46.299748Z INFO Daemon Daemon OS: flatcar 3510.3.2 Feb 9 18:32:46.304656 waagent[1469]: 2024-02-09T18:32:46.304597Z INFO Daemon Daemon Python: 3.9.16 Feb 9 18:32:46.309524 waagent[1469]: 2024-02-09T18:32:46.309457Z INFO Daemon Daemon Run daemon Feb 9 18:32:46.314426 waagent[1469]: 2024-02-09T18:32:46.314364Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.2' Feb 9 18:32:46.331189 waagent[1469]: 2024-02-09T18:32:46.331075Z 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 1. Feb 9 18:32:46.346704 waagent[1469]: 2024-02-09T18:32:46.346584Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Feb 9 18:32:46.356840 waagent[1469]: 2024-02-09T18:32:46.356775Z INFO Daemon Daemon cloud-init is enabled: False Feb 9 18:32:46.362047 waagent[1469]: 2024-02-09T18:32:46.361985Z INFO Daemon Daemon Using waagent for provisioning Feb 9 18:32:46.367987 waagent[1469]: 2024-02-09T18:32:46.367927Z INFO Daemon Daemon Activate resource disk Feb 9 18:32:46.373091 waagent[1469]: 2024-02-09T18:32:46.373032Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Feb 9 18:32:46.387432 waagent[1469]: 2024-02-09T18:32:46.387372Z INFO Daemon Daemon Found device: None Feb 9 18:32:46.393019 waagent[1469]: 2024-02-09T18:32:46.392959Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Feb 9 18:32:46.401652 waagent[1469]: 2024-02-09T18:32:46.401594Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Feb 9 18:32:46.416037 waagent[1469]: 2024-02-09T18:32:46.415975Z INFO Daemon Daemon Clean protocol and wireserver endpoint Feb 9 18:32:46.423949 waagent[1469]: 2024-02-09T18:32:46.423889Z INFO Daemon Daemon Running default provisioning handler Feb 9 18:32:46.436913 waagent[1469]: 2024-02-09T18:32:46.436804Z 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 1. Feb 9 18:32:46.451891 waagent[1469]: 2024-02-09T18:32:46.451780Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Feb 9 18:32:46.461643 waagent[1469]: 2024-02-09T18:32:46.461580Z INFO Daemon Daemon cloud-init is enabled: False Feb 9 18:32:46.466967 waagent[1469]: 2024-02-09T18:32:46.466905Z INFO Daemon Daemon Copying ovf-env.xml Feb 9 18:32:46.553850 waagent[1469]: 2024-02-09T18:32:46.553668Z INFO Daemon Daemon Successfully mounted dvd Feb 9 18:32:46.630248 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Feb 9 18:32:46.654038 waagent[1469]: 2024-02-09T18:32:46.653898Z INFO Daemon Daemon Detect protocol endpoint Feb 9 18:32:46.659106 waagent[1469]: 2024-02-09T18:32:46.659041Z INFO Daemon Daemon Clean protocol and wireserver endpoint Feb 9 18:32:46.664898 waagent[1469]: 2024-02-09T18:32:46.664837Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Feb 9 18:32:46.671502 waagent[1469]: 2024-02-09T18:32:46.671445Z INFO Daemon Daemon Test for route to 168.63.129.16 Feb 9 18:32:46.676906 waagent[1469]: 2024-02-09T18:32:46.676847Z INFO Daemon Daemon Route to 168.63.129.16 exists Feb 9 18:32:46.681972 waagent[1469]: 2024-02-09T18:32:46.681915Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Feb 9 18:32:46.770554 waagent[1469]: 2024-02-09T18:32:46.770487Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Feb 9 18:32:46.777824 waagent[1469]: 2024-02-09T18:32:46.777781Z INFO Daemon Daemon Wire protocol version:2012-11-30 Feb 9 18:32:46.783572 waagent[1469]: 2024-02-09T18:32:46.783513Z INFO Daemon Daemon Server preferred version:2015-04-05 Feb 9 18:32:47.312687 waagent[1469]: 2024-02-09T18:32:47.312531Z INFO Daemon Daemon Initializing goal state during protocol detection Feb 9 18:32:47.325407 waagent[1469]: 2024-02-09T18:32:47.325335Z INFO Daemon Daemon Forcing an update of the goal state.. Feb 9 18:32:47.331295 waagent[1469]: 2024-02-09T18:32:47.331232Z INFO Daemon Daemon Fetching goal state [incarnation 1] Feb 9 18:32:47.403372 waagent[1469]: 2024-02-09T18:32:47.403248Z INFO Daemon Daemon Found private key matching thumbprint AFCF24CAFB0FCF0C9472916A11E67CAB9A25E5B0 Feb 9 18:32:47.412027 waagent[1469]: 2024-02-09T18:32:47.411952Z INFO Daemon Daemon Certificate with thumbprint 92591A1CE5E9640DB5B5FA97644C8C6E6700CFBD has no matching private key. Feb 9 18:32:47.422077 waagent[1469]: 2024-02-09T18:32:47.422008Z INFO Daemon Daemon Fetch goal state completed Feb 9 18:32:47.467689 waagent[1469]: 2024-02-09T18:32:47.467632Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 038dfc99-c0bd-4f49-97d1-9f921c8b1b8b New eTag: 8686271238725122128] Feb 9 18:32:47.478932 waagent[1469]: 2024-02-09T18:32:47.478861Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Feb 9 18:32:47.499326 waagent[1469]: 2024-02-09T18:32:47.499259Z INFO Daemon Daemon Starting provisioning Feb 9 18:32:47.505880 waagent[1469]: 2024-02-09T18:32:47.505798Z INFO Daemon Daemon Handle ovf-env.xml. Feb 9 18:32:47.510918 waagent[1469]: 2024-02-09T18:32:47.510854Z INFO Daemon Daemon Set hostname [ci-3510.3.2-a-7e5e2e1465] Feb 9 18:32:47.586355 waagent[1469]: 2024-02-09T18:32:47.585434Z INFO Daemon Daemon Publish hostname [ci-3510.3.2-a-7e5e2e1465] Feb 9 18:32:47.593254 waagent[1469]: 2024-02-09T18:32:47.593165Z INFO Daemon Daemon Examine /proc/net/route for primary interface Feb 9 18:32:47.599951 waagent[1469]: 2024-02-09T18:32:47.599890Z INFO Daemon Daemon Primary interface is [eth0] Feb 9 18:32:47.616114 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Feb 9 18:32:47.616337 systemd[1]: Stopped systemd-networkd-wait-online.service. Feb 9 18:32:47.616398 systemd[1]: Stopping systemd-networkd-wait-online.service... Feb 9 18:32:47.616632 systemd[1]: Stopping systemd-networkd.service... Feb 9 18:32:47.622246 systemd-networkd[1247]: eth0: DHCPv6 lease lost Feb 9 18:32:47.623452 systemd[1]: systemd-networkd.service: Deactivated successfully. Feb 9 18:32:47.623622 systemd[1]: Stopped systemd-networkd.service. Feb 9 18:32:47.625506 systemd[1]: Starting systemd-networkd.service... Feb 9 18:32:47.652311 systemd-networkd[1521]: enP31224s1: Link UP Feb 9 18:32:47.652323 systemd-networkd[1521]: enP31224s1: Gained carrier Feb 9 18:32:47.653198 systemd-networkd[1521]: eth0: Link UP Feb 9 18:32:47.653221 systemd-networkd[1521]: eth0: Gained carrier Feb 9 18:32:47.653552 systemd-networkd[1521]: lo: Link UP Feb 9 18:32:47.653561 systemd-networkd[1521]: lo: Gained carrier Feb 9 18:32:47.653786 systemd-networkd[1521]: eth0: Gained IPv6LL Feb 9 18:32:47.655037 systemd-networkd[1521]: Enumeration completed Feb 9 18:32:47.655134 systemd[1]: Started systemd-networkd.service. Feb 9 18:32:47.656706 systemd[1]: Starting systemd-networkd-wait-online.service... Feb 9 18:32:47.662547 waagent[1469]: 2024-02-09T18:32:47.657122Z INFO Daemon Daemon Create user account if not exists Feb 9 18:32:47.663950 waagent[1469]: 2024-02-09T18:32:47.663870Z INFO Daemon Daemon User core already exists, skip useradd Feb 9 18:32:47.669619 systemd-networkd[1521]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Feb 9 18:32:47.670438 waagent[1469]: 2024-02-09T18:32:47.670341Z INFO Daemon Daemon Configure sudoer Feb 9 18:32:47.675520 waagent[1469]: 2024-02-09T18:32:47.675455Z INFO Daemon Daemon Configure sshd Feb 9 18:32:47.679828 waagent[1469]: 2024-02-09T18:32:47.679766Z INFO Daemon Daemon Deploy ssh public key. Feb 9 18:32:47.708271 systemd-networkd[1521]: eth0: DHCPv4 address 10.200.20.19/24, gateway 10.200.20.1 acquired from 168.63.129.16 Feb 9 18:32:47.710126 systemd[1]: Finished systemd-networkd-wait-online.service. Feb 9 18:32:48.999072 waagent[1469]: 2024-02-09T18:32:48.998981Z INFO Daemon Daemon Provisioning complete Feb 9 18:32:49.017586 waagent[1469]: 2024-02-09T18:32:49.017517Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Feb 9 18:32:49.024470 waagent[1469]: 2024-02-09T18:32:49.024401Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Feb 9 18:32:49.036355 waagent[1469]: 2024-02-09T18:32:49.036289Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Feb 9 18:32:49.337040 waagent[1530]: 2024-02-09T18:32:49.336891Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Feb 9 18:32:49.338124 waagent[1530]: 2024-02-09T18:32:49.338068Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:49.338389 waagent[1530]: 2024-02-09T18:32:49.338340Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:49.351804 waagent[1530]: 2024-02-09T18:32:49.351732Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Feb 9 18:32:49.352105 waagent[1530]: 2024-02-09T18:32:49.352056Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Feb 9 18:32:49.419029 waagent[1530]: 2024-02-09T18:32:49.418903Z INFO ExtHandler ExtHandler Found private key matching thumbprint AFCF24CAFB0FCF0C9472916A11E67CAB9A25E5B0 Feb 9 18:32:49.419417 waagent[1530]: 2024-02-09T18:32:49.419362Z INFO ExtHandler ExtHandler Certificate with thumbprint 92591A1CE5E9640DB5B5FA97644C8C6E6700CFBD has no matching private key. Feb 9 18:32:49.419746 waagent[1530]: 2024-02-09T18:32:49.419698Z INFO ExtHandler ExtHandler Fetch goal state completed Feb 9 18:32:49.435943 waagent[1530]: 2024-02-09T18:32:49.435887Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: 94f9b8c3-ba57-4b58-9458-95de0f4c0d5a New eTag: 8686271238725122128] Feb 9 18:32:49.436696 waagent[1530]: 2024-02-09T18:32:49.436639Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Feb 9 18:32:49.516460 waagent[1530]: 2024-02-09T18:32:49.516319Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Feb 9 18:32:49.527223 waagent[1530]: 2024-02-09T18:32:49.527136Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1530 Feb 9 18:32:49.530985 waagent[1530]: 2024-02-09T18:32:49.530919Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk'] Feb 9 18:32:49.532331 waagent[1530]: 2024-02-09T18:32:49.532275Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Feb 9 18:32:49.657940 waagent[1530]: 2024-02-09T18:32:49.657816Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Feb 9 18:32:49.658307 waagent[1530]: 2024-02-09T18:32:49.658249Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Feb 9 18:32:49.666088 waagent[1530]: 2024-02-09T18:32:49.666025Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Feb 9 18:32:49.666585 waagent[1530]: 2024-02-09T18:32:49.666530Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Feb 9 18:32:49.667745 waagent[1530]: 2024-02-09T18:32:49.667680Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True] Feb 9 18:32:49.669085 waagent[1530]: 2024-02-09T18:32:49.669013Z INFO ExtHandler ExtHandler Starting env monitor service. Feb 9 18:32:49.669746 waagent[1530]: 2024-02-09T18:32:49.669687Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:49.670009 waagent[1530]: 2024-02-09T18:32:49.669950Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:49.670702 waagent[1530]: 2024-02-09T18:32:49.670634Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Feb 9 18:32:49.671369 waagent[1530]: 2024-02-09T18:32:49.671293Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Feb 9 18:32:49.671947 waagent[1530]: 2024-02-09T18:32:49.671876Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Feb 9 18:32:49.671947 waagent[1530]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Feb 9 18:32:49.671947 waagent[1530]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Feb 9 18:32:49.671947 waagent[1530]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Feb 9 18:32:49.671947 waagent[1530]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:49.671947 waagent[1530]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:49.671947 waagent[1530]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:49.672124 waagent[1530]: 2024-02-09T18:32:49.671985Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:49.672205 waagent[1530]: 2024-02-09T18:32:49.672152Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:49.672850 waagent[1530]: 2024-02-09T18:32:49.672776Z INFO EnvHandler ExtHandler Configure routes Feb 9 18:32:49.673174 waagent[1530]: 2024-02-09T18:32:49.672995Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Feb 9 18:32:49.673246 waagent[1530]: 2024-02-09T18:32:49.673179Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Feb 9 18:32:49.675346 waagent[1530]: 2024-02-09T18:32:49.675185Z INFO EnvHandler ExtHandler Gateway:None Feb 9 18:32:49.675717 waagent[1530]: 2024-02-09T18:32:49.675647Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Feb 9 18:32:49.676077 waagent[1530]: 2024-02-09T18:32:49.676006Z INFO EnvHandler ExtHandler Routes:None Feb 9 18:32:49.676398 waagent[1530]: 2024-02-09T18:32:49.676337Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Feb 9 18:32:49.676510 waagent[1530]: 2024-02-09T18:32:49.676441Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Feb 9 18:32:49.689818 waagent[1530]: 2024-02-09T18:32:49.689723Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Feb 9 18:32:49.690456 waagent[1530]: 2024-02-09T18:32:49.690403Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Feb 9 18:32:49.691386 waagent[1530]: 2024-02-09T18:32:49.691323Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Feb 9 18:32:49.717272 waagent[1530]: 2024-02-09T18:32:49.717083Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1521' Feb 9 18:32:49.730229 waagent[1530]: 2024-02-09T18:32:49.730156Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Feb 9 18:32:49.846076 waagent[1530]: 2024-02-09T18:32:49.845944Z INFO MonitorHandler ExtHandler Network interfaces: Feb 9 18:32:49.846076 waagent[1530]: Executing ['ip', '-a', '-o', 'link']: Feb 9 18:32:49.846076 waagent[1530]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Feb 9 18:32:49.846076 waagent[1530]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b9:ff:f4 brd ff:ff:ff:ff:ff:ff Feb 9 18:32:49.846076 waagent[1530]: 3: enP31224s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b9:ff:f4 brd ff:ff:ff:ff:ff:ff\ altname enP31224p0s2 Feb 9 18:32:49.846076 waagent[1530]: Executing ['ip', '-4', '-a', '-o', 'address']: Feb 9 18:32:49.846076 waagent[1530]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Feb 9 18:32:49.846076 waagent[1530]: 2: eth0 inet 10.200.20.19/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Feb 9 18:32:49.846076 waagent[1530]: Executing ['ip', '-6', '-a', '-o', 'address']: Feb 9 18:32:49.846076 waagent[1530]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Feb 9 18:32:49.846076 waagent[1530]: 2: eth0 inet6 fe80::222:48ff:feb9:fff4/64 scope link \ valid_lft forever preferred_lft forever Feb 9 18:32:49.883264 waagent[1530]: 2024-02-09T18:32:49.883175Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.9.1.1 -- exiting Feb 9 18:32:50.039831 waagent[1469]: 2024-02-09T18:32:50.039711Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running Feb 9 18:32:50.046351 waagent[1469]: 2024-02-09T18:32:50.046293Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.9.1.1 to be the latest agent Feb 9 18:32:51.181057 waagent[1559]: 2024-02-09T18:32:51.180958Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1) Feb 9 18:32:51.182081 waagent[1559]: 2024-02-09T18:32:51.182026Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.2 Feb 9 18:32:51.182330 waagent[1559]: 2024-02-09T18:32:51.182280Z INFO ExtHandler ExtHandler Python: 3.9.16 Feb 9 18:32:51.190139 waagent[1559]: 2024-02-09T18:32:51.190042Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Feb 9 18:32:51.190655 waagent[1559]: 2024-02-09T18:32:51.190600Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:51.190913 waagent[1559]: 2024-02-09T18:32:51.190864Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:51.203630 waagent[1559]: 2024-02-09T18:32:51.203561Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Feb 9 18:32:51.214467 waagent[1559]: 2024-02-09T18:32:51.214413Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.143 Feb 9 18:32:51.215582 waagent[1559]: 2024-02-09T18:32:51.215526Z INFO ExtHandler Feb 9 18:32:51.215816 waagent[1559]: 2024-02-09T18:32:51.215767Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: aed7740c-dc93-4c85-b0bf-7abc923cc439 eTag: 8686271238725122128 source: Fabric] Feb 9 18:32:51.216718 waagent[1559]: 2024-02-09T18:32:51.216660Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Feb 9 18:32:51.218021 waagent[1559]: 2024-02-09T18:32:51.217962Z INFO ExtHandler Feb 9 18:32:51.218261 waagent[1559]: 2024-02-09T18:32:51.218195Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Feb 9 18:32:51.227996 waagent[1559]: 2024-02-09T18:32:51.227950Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Feb 9 18:32:51.228567 waagent[1559]: 2024-02-09T18:32:51.228519Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Feb 9 18:32:51.247464 waagent[1559]: 2024-02-09T18:32:51.247406Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Feb 9 18:32:51.323834 waagent[1559]: 2024-02-09T18:32:51.323706Z INFO ExtHandler Downloaded certificate {'thumbprint': 'AFCF24CAFB0FCF0C9472916A11E67CAB9A25E5B0', 'hasPrivateKey': True} Feb 9 18:32:51.325128 waagent[1559]: 2024-02-09T18:32:51.325068Z INFO ExtHandler Downloaded certificate {'thumbprint': '92591A1CE5E9640DB5B5FA97644C8C6E6700CFBD', 'hasPrivateKey': False} Feb 9 18:32:51.326307 waagent[1559]: 2024-02-09T18:32:51.326248Z INFO ExtHandler Fetch goal state completed Feb 9 18:32:51.351000 waagent[1559]: 2024-02-09T18:32:51.350930Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1559 Feb 9 18:32:51.354641 waagent[1559]: 2024-02-09T18:32:51.354580Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk'] Feb 9 18:32:51.356232 waagent[1559]: 2024-02-09T18:32:51.356153Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Feb 9 18:32:51.361104 waagent[1559]: 2024-02-09T18:32:51.361056Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Feb 9 18:32:51.361642 waagent[1559]: 2024-02-09T18:32:51.361584Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Feb 9 18:32:51.369377 waagent[1559]: 2024-02-09T18:32:51.369321Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Feb 9 18:32:51.369957 waagent[1559]: 2024-02-09T18:32:51.369903Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Feb 9 18:32:51.375751 waagent[1559]: 2024-02-09T18:32:51.375655Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Feb 9 18:32:51.379415 waagent[1559]: 2024-02-09T18:32:51.379357Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Feb 9 18:32:51.380967 waagent[1559]: 2024-02-09T18:32:51.380898Z INFO ExtHandler ExtHandler Starting env monitor service. Feb 9 18:32:51.381272 waagent[1559]: 2024-02-09T18:32:51.381182Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:51.381464 waagent[1559]: 2024-02-09T18:32:51.381409Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:51.382493 waagent[1559]: 2024-02-09T18:32:51.382412Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Feb 9 18:32:51.382835 waagent[1559]: 2024-02-09T18:32:51.382770Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Feb 9 18:32:51.382835 waagent[1559]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Feb 9 18:32:51.382835 waagent[1559]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Feb 9 18:32:51.382835 waagent[1559]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Feb 9 18:32:51.382835 waagent[1559]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:51.382835 waagent[1559]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:51.382835 waagent[1559]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Feb 9 18:32:51.384941 waagent[1559]: 2024-02-09T18:32:51.384819Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Feb 9 18:32:51.385410 waagent[1559]: 2024-02-09T18:32:51.385336Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Feb 9 18:32:51.385751 waagent[1559]: 2024-02-09T18:32:51.385673Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Feb 9 18:32:51.386650 waagent[1559]: 2024-02-09T18:32:51.386575Z INFO EnvHandler ExtHandler Configure routes Feb 9 18:32:51.386808 waagent[1559]: 2024-02-09T18:32:51.386757Z INFO EnvHandler ExtHandler Gateway:None Feb 9 18:32:51.386930 waagent[1559]: 2024-02-09T18:32:51.386886Z INFO EnvHandler ExtHandler Routes:None Feb 9 18:32:51.389173 waagent[1559]: 2024-02-09T18:32:51.388990Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Feb 9 18:32:51.389581 waagent[1559]: 2024-02-09T18:32:51.389496Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Feb 9 18:32:51.391003 waagent[1559]: 2024-02-09T18:32:51.390906Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Feb 9 18:32:51.392666 waagent[1559]: 2024-02-09T18:32:51.392487Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Feb 9 18:32:51.394603 waagent[1559]: 2024-02-09T18:32:51.394542Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Feb 9 18:32:51.416693 waagent[1559]: 2024-02-09T18:32:51.416626Z INFO ExtHandler ExtHandler No requested version specified, checking for all versions for agent update (family: Prod) Feb 9 18:32:51.419081 waagent[1559]: 2024-02-09T18:32:51.418907Z INFO ExtHandler ExtHandler Downloading manifest Feb 9 18:32:51.424366 waagent[1559]: 2024-02-09T18:32:51.424294Z INFO MonitorHandler ExtHandler Network interfaces: Feb 9 18:32:51.424366 waagent[1559]: Executing ['ip', '-a', '-o', 'link']: Feb 9 18:32:51.424366 waagent[1559]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Feb 9 18:32:51.424366 waagent[1559]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b9:ff:f4 brd ff:ff:ff:ff:ff:ff Feb 9 18:32:51.424366 waagent[1559]: 3: enP31224s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:b9:ff:f4 brd ff:ff:ff:ff:ff:ff\ altname enP31224p0s2 Feb 9 18:32:51.424366 waagent[1559]: Executing ['ip', '-4', '-a', '-o', 'address']: Feb 9 18:32:51.424366 waagent[1559]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Feb 9 18:32:51.424366 waagent[1559]: 2: eth0 inet 10.200.20.19/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Feb 9 18:32:51.424366 waagent[1559]: Executing ['ip', '-6', '-a', '-o', 'address']: Feb 9 18:32:51.424366 waagent[1559]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Feb 9 18:32:51.424366 waagent[1559]: 2: eth0 inet6 fe80::222:48ff:feb9:fff4/64 scope link \ valid_lft forever preferred_lft forever Feb 9 18:32:51.453787 waagent[1559]: 2024-02-09T18:32:51.453733Z INFO ExtHandler ExtHandler Feb 9 18:32:51.454082 waagent[1559]: 2024-02-09T18:32:51.454026Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 97ad0186-adf8-4312-9b33-d9096998fae4 correlation 612648c5-a8ce-4e1a-a833-35cdd64dddfe created: 2024-02-09T18:31:22.848457Z] Feb 9 18:32:51.455110 waagent[1559]: 2024-02-09T18:32:51.455048Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Feb 9 18:32:51.457020 waagent[1559]: 2024-02-09T18:32:51.456964Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Feb 9 18:32:51.483607 waagent[1559]: 2024-02-09T18:32:51.483540Z INFO ExtHandler ExtHandler Looking for existing remote access users. Feb 9 18:32:51.496687 waagent[1559]: 2024-02-09T18:32:51.496613Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 6ACE3451-58A2-4650-8087-C1067ED6ADF0;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1] Feb 9 18:32:51.623579 waagent[1559]: 2024-02-09T18:32:51.623440Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Feb 9 18:32:51.623579 waagent[1559]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.623579 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.623579 waagent[1559]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.623579 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.623579 waagent[1559]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.623579 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.623579 waagent[1559]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Feb 9 18:32:51.623579 waagent[1559]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Feb 9 18:32:51.623579 waagent[1559]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Feb 9 18:32:51.630794 waagent[1559]: 2024-02-09T18:32:51.630676Z INFO EnvHandler ExtHandler Current Firewall rules: Feb 9 18:32:51.630794 waagent[1559]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.630794 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.630794 waagent[1559]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.630794 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.630794 waagent[1559]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Feb 9 18:32:51.630794 waagent[1559]: pkts bytes target prot opt in out source destination Feb 9 18:32:51.630794 waagent[1559]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Feb 9 18:32:51.630794 waagent[1559]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Feb 9 18:32:51.630794 waagent[1559]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Feb 9 18:32:51.631330 waagent[1559]: 2024-02-09T18:32:51.631277Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Feb 9 18:33:17.615115 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Feb 9 18:33:26.100716 update_engine[1368]: I0209 18:33:26.100330 1368 update_attempter.cc:509] Updating boot flags... Feb 9 18:33:29.848792 systemd[1]: Created slice system-sshd.slice. Feb 9 18:33:29.849915 systemd[1]: Started sshd@0-10.200.20.19:22-10.200.12.6:49360.service. Feb 9 18:33:30.499643 sshd[1678]: Accepted publickey for core from 10.200.12.6 port 49360 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:30.517626 sshd[1678]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:30.522259 systemd[1]: Started session-3.scope. Feb 9 18:33:30.523193 systemd-logind[1364]: New session 3 of user core. Feb 9 18:33:30.897792 systemd[1]: Started sshd@1-10.200.20.19:22-10.200.12.6:49366.service. Feb 9 18:33:31.348500 sshd[1683]: Accepted publickey for core from 10.200.12.6 port 49366 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:31.349792 sshd[1683]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:31.353622 systemd-logind[1364]: New session 4 of user core. Feb 9 18:33:31.354070 systemd[1]: Started session-4.scope. Feb 9 18:33:31.674390 sshd[1683]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:31.676811 systemd[1]: sshd@1-10.200.20.19:22-10.200.12.6:49366.service: Deactivated successfully. Feb 9 18:33:31.677521 systemd[1]: session-4.scope: Deactivated successfully. Feb 9 18:33:31.678060 systemd-logind[1364]: Session 4 logged out. Waiting for processes to exit. Feb 9 18:33:31.678861 systemd-logind[1364]: Removed session 4. Feb 9 18:33:31.743696 systemd[1]: Started sshd@2-10.200.20.19:22-10.200.12.6:49380.service. Feb 9 18:33:32.157054 sshd[1689]: Accepted publickey for core from 10.200.12.6 port 49380 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:32.158651 sshd[1689]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:32.162720 systemd[1]: Started session-5.scope. Feb 9 18:33:32.163341 systemd-logind[1364]: New session 5 of user core. Feb 9 18:33:32.455318 sshd[1689]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:32.458076 systemd[1]: sshd@2-10.200.20.19:22-10.200.12.6:49380.service: Deactivated successfully. Feb 9 18:33:32.458777 systemd[1]: session-5.scope: Deactivated successfully. Feb 9 18:33:32.459307 systemd-logind[1364]: Session 5 logged out. Waiting for processes to exit. Feb 9 18:33:32.460118 systemd-logind[1364]: Removed session 5. Feb 9 18:33:32.530747 systemd[1]: Started sshd@3-10.200.20.19:22-10.200.12.6:49392.service. Feb 9 18:33:32.981762 sshd[1695]: Accepted publickey for core from 10.200.12.6 port 49392 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:32.985510 sshd[1695]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:32.989178 systemd-logind[1364]: New session 6 of user core. Feb 9 18:33:32.989611 systemd[1]: Started session-6.scope. Feb 9 18:33:33.307821 sshd[1695]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:33.310289 systemd[1]: sshd@3-10.200.20.19:22-10.200.12.6:49392.service: Deactivated successfully. Feb 9 18:33:33.310953 systemd[1]: session-6.scope: Deactivated successfully. Feb 9 18:33:33.311505 systemd-logind[1364]: Session 6 logged out. Waiting for processes to exit. Feb 9 18:33:33.312347 systemd-logind[1364]: Removed session 6. Feb 9 18:33:33.382115 systemd[1]: Started sshd@4-10.200.20.19:22-10.200.12.6:49408.service. Feb 9 18:33:33.829668 sshd[1701]: Accepted publickey for core from 10.200.12.6 port 49408 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:33.830931 sshd[1701]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:33.835252 systemd[1]: Started session-7.scope. Feb 9 18:33:33.836275 systemd-logind[1364]: New session 7 of user core. Feb 9 18:33:34.371482 sudo[1704]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Feb 9 18:33:34.371683 sudo[1704]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:33:34.397345 dbus-daemon[1354]: avc: received setenforce notice (enforcing=1) Feb 9 18:33:34.398286 sudo[1704]: pam_unix(sudo:session): session closed for user root Feb 9 18:33:34.483925 sshd[1701]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:34.486737 systemd[1]: sshd@4-10.200.20.19:22-10.200.12.6:49408.service: Deactivated successfully. Feb 9 18:33:34.487540 systemd[1]: session-7.scope: Deactivated successfully. Feb 9 18:33:34.488103 systemd-logind[1364]: Session 7 logged out. Waiting for processes to exit. Feb 9 18:33:34.488988 systemd-logind[1364]: Removed session 7. Feb 9 18:33:34.552679 systemd[1]: Started sshd@5-10.200.20.19:22-10.200.12.6:49412.service. Feb 9 18:33:34.966894 sshd[1708]: Accepted publickey for core from 10.200.12.6 port 49412 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:34.968542 sshd[1708]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:34.972200 systemd-logind[1364]: New session 8 of user core. Feb 9 18:33:34.972636 systemd[1]: Started session-8.scope. Feb 9 18:33:35.204803 sudo[1712]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Feb 9 18:33:35.205354 sudo[1712]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:33:35.207936 sudo[1712]: pam_unix(sudo:session): session closed for user root Feb 9 18:33:35.212357 sudo[1711]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Feb 9 18:33:35.212560 sudo[1711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Feb 9 18:33:35.220490 systemd[1]: Stopping audit-rules.service... Feb 9 18:33:35.227191 kernel: kauditd_printk_skb: 84 callbacks suppressed Feb 9 18:33:35.227301 kernel: audit: type=1305 audit(1707503615.220:166): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Feb 9 18:33:35.220000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Feb 9 18:33:35.222031 systemd[1]: audit-rules.service: Deactivated successfully. Feb 9 18:33:35.227507 auditctl[1715]: No rules Feb 9 18:33:35.222191 systemd[1]: Stopped audit-rules.service. Feb 9 18:33:35.223573 systemd[1]: Starting audit-rules.service... Feb 9 18:33:35.220000 audit[1715]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff116fce0 a2=420 a3=0 items=0 ppid=1 pid=1715 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:33:35.261777 kernel: audit: type=1300 audit(1707503615.220:166): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff116fce0 a2=420 a3=0 items=0 ppid=1 pid=1715 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:33:35.220000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Feb 9 18:33:35.270232 kernel: audit: type=1327 audit(1707503615.220:166): proctitle=2F7362696E2F617564697463746C002D44 Feb 9 18:33:35.220000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.287815 kernel: audit: type=1131 audit(1707503615.220:167): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.293647 augenrules[1732]: No rules Feb 9 18:33:35.294513 systemd[1]: Finished audit-rules.service. Feb 9 18:33:35.293000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.295374 sudo[1711]: pam_unix(sudo:session): session closed for user root Feb 9 18:33:35.294000 audit[1711]: USER_END pid=1711 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.330162 kernel: audit: type=1130 audit(1707503615.293:168): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.330238 kernel: audit: type=1106 audit(1707503615.294:169): pid=1711 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.330264 kernel: audit: type=1104 audit(1707503615.294:170): pid=1711 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.294000 audit[1711]: CRED_DISP pid=1711 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.360426 sshd[1708]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:35.360000 audit[1708]: USER_END pid=1708 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.363178 systemd-logind[1364]: Session 8 logged out. Waiting for processes to exit. Feb 9 18:33:35.363902 systemd[1]: sshd@5-10.200.20.19:22-10.200.12.6:49412.service: Deactivated successfully. Feb 9 18:33:35.364598 systemd[1]: session-8.scope: Deactivated successfully. Feb 9 18:33:35.365691 systemd-logind[1364]: Removed session 8. Feb 9 18:33:35.360000 audit[1708]: CRED_DISP pid=1708 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.403597 kernel: audit: type=1106 audit(1707503615.360:171): pid=1708 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.403646 kernel: audit: type=1104 audit(1707503615.360:172): pid=1708 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.403671 kernel: audit: type=1131 audit(1707503615.362:173): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.19:22-10.200.12.6:49412 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.362000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.19:22-10.200.12.6:49412 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.439903 systemd[1]: Started sshd@6-10.200.20.19:22-10.200.12.6:49420.service. Feb 9 18:33:35.438000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.200.20.19:22-10.200.12.6:49420 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:35.885000 audit[1738]: USER_ACCT pid=1738 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.886787 sshd[1738]: Accepted publickey for core from 10.200.12.6 port 49420 ssh2: RSA SHA256:AExcTof2ms2RC04cAfR/26ykZOGA1PeppPBnNP0o6qE Feb 9 18:33:35.886000 audit[1738]: CRED_ACQ pid=1738 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.886000 audit[1738]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffff28faf90 a2=3 a3=1 items=0 ppid=1 pid=1738 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Feb 9 18:33:35.886000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Feb 9 18:33:35.888335 sshd[1738]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Feb 9 18:33:35.892436 systemd[1]: Started session-9.scope. Feb 9 18:33:35.893262 systemd-logind[1364]: New session 9 of user core. Feb 9 18:33:35.895000 audit[1738]: USER_START pid=1738 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:35.897000 audit[1740]: CRED_ACQ pid=1740 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:36.205951 sshd[1738]: pam_unix(sshd:session): session closed for user core Feb 9 18:33:36.205000 audit[1738]: USER_END pid=1738 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:36.205000 audit[1738]: CRED_DISP pid=1738 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Feb 9 18:33:36.208433 systemd[1]: sshd@6-10.200.20.19:22-10.200.12.6:49420.service: Deactivated successfully. Feb 9 18:33:36.207000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@6-10.200.20.19:22-10.200.12.6:49420 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Feb 9 18:33:36.209090 systemd[1]: session-9.scope: Deactivated successfully. Feb 9 18:33:36.209611 systemd-logind[1364]: Session 9 logged out. Waiting for processes to exit. Feb 9 18:33:36.210419 systemd-logind[1364]: Removed session 9. Feb 9 18:33:39.696895 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.712016 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.726395 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.741629 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.758387 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.773762 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.781229 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.789324 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.789459 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.807928 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.808111 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.824783 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.843086 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.859406 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.859525 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.859627 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.869828 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.869984 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.889477 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.889641 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.906845 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.923228 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.923446 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.933882 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.942604 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.942808 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001 Feb 9 18:33:39.960903 kernel: hv_storvsc f8b3781a-1e82-4818-a1c3-63d806ec15bb: tag#23 cmd 0x2a status: scsi 0x2 srb 0x4 hv 0xc0000001