Mar 17 18:45:53.024796 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 17 18:45:53.024813 kernel: Linux version 5.15.179-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 Mon Mar 17 17:11:44 -00 2025 Mar 17 18:45:53.024821 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Mar 17 18:45:53.024828 kernel: printk: bootconsole [pl11] enabled Mar 17 18:45:53.024833 kernel: efi: EFI v2.70 by EDK II Mar 17 18:45:53.024838 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f214018 RNG=0x3fd5f998 MEMRESERVE=0x3763cf98 Mar 17 18:45:53.024844 kernel: random: crng init done Mar 17 18:45:53.024850 kernel: ACPI: Early table checksum verification disabled Mar 17 18:45:53.024855 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Mar 17 18:45:53.024860 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024866 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024871 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Mar 17 18:45:53.024878 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024883 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024890 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024896 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024902 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024909 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024915 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Mar 17 18:45:53.024921 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:45:53.024926 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Mar 17 18:45:53.024932 kernel: NUMA: Failed to initialise from firmware Mar 17 18:45:53.024938 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff] Mar 17 18:45:53.024943 kernel: NUMA: NODE_DATA [mem 0x1bf7f3900-0x1bf7f8fff] Mar 17 18:45:53.024949 kernel: Zone ranges: Mar 17 18:45:53.024955 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Mar 17 18:45:53.024960 kernel: DMA32 empty Mar 17 18:45:53.024966 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 18:45:53.024972 kernel: Movable zone start for each node Mar 17 18:45:53.024978 kernel: Early memory node ranges Mar 17 18:45:53.024984 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Mar 17 18:45:53.024989 kernel: node 0: [mem 0x0000000000824000-0x000000003e54ffff] Mar 17 18:45:53.024995 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Mar 17 18:45:53.025000 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Mar 17 18:45:53.025006 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Mar 17 18:45:53.025012 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Mar 17 18:45:53.025017 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 18:45:53.025023 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Mar 17 18:45:53.025028 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Mar 17 18:45:53.025034 kernel: psci: probing for conduit method from ACPI. Mar 17 18:45:53.025044 kernel: psci: PSCIv1.1 detected in firmware. Mar 17 18:45:53.025050 kernel: psci: Using standard PSCI v0.2 function IDs Mar 17 18:45:53.025056 kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 17 18:45:53.025062 kernel: psci: SMC Calling Convention v1.4 Mar 17 18:45:53.025068 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1 Mar 17 18:45:53.025075 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1 Mar 17 18:45:53.025081 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Mar 17 18:45:53.025087 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Mar 17 18:45:53.025093 kernel: pcpu-alloc: [0] 0 [0] 1 Mar 17 18:45:53.025099 kernel: Detected PIPT I-cache on CPU0 Mar 17 18:45:53.025105 kernel: CPU features: detected: GIC system register CPU interface Mar 17 18:45:53.025111 kernel: CPU features: detected: Hardware dirty bit management Mar 17 18:45:53.025117 kernel: CPU features: detected: Spectre-BHB Mar 17 18:45:53.025123 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 17 18:45:53.025129 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 17 18:45:53.025135 kernel: CPU features: detected: ARM erratum 1418040 Mar 17 18:45:53.025142 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Mar 17 18:45:53.025148 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 17 18:45:53.025154 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Mar 17 18:45:53.025160 kernel: Policy zone: Normal Mar 17 18:45:53.025176 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=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:45:53.025183 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 18:45:53.025190 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 18:45:53.025196 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 18:45:53.025202 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:45:53.025208 kernel: software IO TLB: mapped [mem 0x000000003a550000-0x000000003e550000] (64MB) Mar 17 18:45:53.025214 kernel: Memory: 3986944K/4194160K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36416K init, 777K bss, 207216K reserved, 0K cma-reserved) Mar 17 18:45:53.025222 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 17 18:45:53.025229 kernel: trace event string verifier disabled Mar 17 18:45:53.025235 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 18:45:53.025241 kernel: rcu: RCU event tracing is enabled. Mar 17 18:45:53.025248 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Mar 17 18:45:53.025254 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 18:45:53.025260 kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:45:53.025266 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 18:45:53.025272 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 17 18:45:53.025278 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 17 18:45:53.025284 kernel: GICv3: 960 SPIs implemented Mar 17 18:45:53.025291 kernel: GICv3: 0 Extended SPIs implemented Mar 17 18:45:53.025297 kernel: GICv3: Distributor has no Range Selector support Mar 17 18:45:53.025303 kernel: Root IRQ handler: gic_handle_irq Mar 17 18:45:53.025309 kernel: GICv3: 16 PPIs implemented Mar 17 18:45:53.025315 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Mar 17 18:45:53.025321 kernel: ITS: No ITS available, not enabling LPIs Mar 17 18:45:53.025327 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:45:53.025333 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 17 18:45:53.025339 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 17 18:45:53.025346 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 17 18:45:53.025352 kernel: Console: colour dummy device 80x25 Mar 17 18:45:53.025359 kernel: printk: console [tty1] enabled Mar 17 18:45:53.025366 kernel: ACPI: Core revision 20210730 Mar 17 18:45:53.025372 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 17 18:45:53.025378 kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:45:53.025385 kernel: LSM: Security Framework initializing Mar 17 18:45:53.025391 kernel: SELinux: Initializing. Mar 17 18:45:53.025397 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:45:53.025403 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:45:53.025410 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Mar 17 18:45:53.025417 kernel: Hyper-V: Host Build 10.0.22477.1619-1-0 Mar 17 18:45:53.025423 kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:45:53.025429 kernel: Remapping and enabling EFI services. Mar 17 18:45:53.025435 kernel: smp: Bringing up secondary CPUs ... Mar 17 18:45:53.025441 kernel: Detected PIPT I-cache on CPU1 Mar 17 18:45:53.025448 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Mar 17 18:45:53.025454 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:45:53.025460 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 17 18:45:53.025466 kernel: smp: Brought up 1 node, 2 CPUs Mar 17 18:45:53.025472 kernel: SMP: Total of 2 processors activated. Mar 17 18:45:53.025480 kernel: CPU features: detected: 32-bit EL0 Support Mar 17 18:45:53.025486 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Mar 17 18:45:53.025492 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 17 18:45:53.025499 kernel: CPU features: detected: CRC32 instructions Mar 17 18:45:53.025505 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 17 18:45:53.025511 kernel: CPU features: detected: LSE atomic instructions Mar 17 18:45:53.025517 kernel: CPU features: detected: Privileged Access Never Mar 17 18:45:53.025523 kernel: CPU: All CPU(s) started at EL1 Mar 17 18:45:53.025529 kernel: alternatives: patching kernel code Mar 17 18:45:53.025537 kernel: devtmpfs: initialized Mar 17 18:45:53.025547 kernel: KASLR enabled Mar 17 18:45:53.025554 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 18:45:53.025562 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 17 18:45:53.025568 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:45:53.025575 kernel: SMBIOS 3.1.0 present. Mar 17 18:45:53.025581 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Mar 17 18:45:53.025588 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 18:45:53.025594 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 17 18:45:53.025602 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 17 18:45:53.025609 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 17 18:45:53.025616 kernel: audit: initializing netlink subsys (disabled) Mar 17 18:45:53.025622 kernel: audit: type=2000 audit(0.090:1): state=initialized audit_enabled=0 res=1 Mar 17 18:45:53.025629 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:45:53.025635 kernel: cpuidle: using governor menu Mar 17 18:45:53.025641 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 17 18:45:53.025649 kernel: ASID allocator initialised with 32768 entries Mar 17 18:45:53.025656 kernel: ACPI: bus type PCI registered Mar 17 18:45:53.025662 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:45:53.025669 kernel: Serial: AMBA PL011 UART driver Mar 17 18:45:53.025675 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 18:45:53.025682 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Mar 17 18:45:53.025688 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:45:53.025695 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Mar 17 18:45:53.025701 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:45:53.025709 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 17 18:45:53.025715 kernel: ACPI: Added _OSI(Module Device) Mar 17 18:45:53.025722 kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:45:53.025728 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:45:53.025735 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:45:53.025741 kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:45:53.025748 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:45:53.025754 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:45:53.025761 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 18:45:53.025769 kernel: ACPI: Interpreter enabled Mar 17 18:45:53.025775 kernel: ACPI: Using GIC for interrupt routing Mar 17 18:45:53.025782 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Mar 17 18:45:53.025788 kernel: printk: console [ttyAMA0] enabled Mar 17 18:45:53.025795 kernel: printk: bootconsole [pl11] disabled Mar 17 18:45:53.025801 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Mar 17 18:45:53.025808 kernel: iommu: Default domain type: Translated Mar 17 18:45:53.025814 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 17 18:45:53.025820 kernel: vgaarb: loaded Mar 17 18:45:53.025827 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 18:45:53.025835 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 18:45:53.025841 kernel: PTP clock support registered Mar 17 18:45:53.025847 kernel: Registered efivars operations Mar 17 18:45:53.025854 kernel: No ACPI PMU IRQ for CPU0 Mar 17 18:45:53.025860 kernel: No ACPI PMU IRQ for CPU1 Mar 17 18:45:53.025867 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 17 18:45:53.025873 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:45:53.025880 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:45:53.025887 kernel: pnp: PnP ACPI init Mar 17 18:45:53.025894 kernel: pnp: PnP ACPI: found 0 devices Mar 17 18:45:53.025900 kernel: NET: Registered PF_INET protocol family Mar 17 18:45:53.025907 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 18:45:53.025913 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 18:45:53.025920 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 18:45:53.025927 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 18:45:53.025933 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Mar 17 18:45:53.025940 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 18:45:53.025948 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:45:53.025954 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:45:53.025961 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 18:45:53.025967 kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:45:53.025974 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Mar 17 18:45:53.025980 kernel: kvm [1]: HYP mode not available Mar 17 18:45:53.025986 kernel: Initialise system trusted keyrings Mar 17 18:45:53.025993 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 18:45:53.026000 kernel: Key type asymmetric registered Mar 17 18:45:53.026007 kernel: Asymmetric key parser 'x509' registered Mar 17 18:45:53.026014 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 17 18:45:53.026020 kernel: io scheduler mq-deadline registered Mar 17 18:45:53.026027 kernel: io scheduler kyber registered Mar 17 18:45:53.026033 kernel: io scheduler bfq registered Mar 17 18:45:53.026040 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 18:45:53.026046 kernel: thunder_xcv, ver 1.0 Mar 17 18:45:53.026052 kernel: thunder_bgx, ver 1.0 Mar 17 18:45:53.026059 kernel: nicpf, ver 1.0 Mar 17 18:45:53.026065 kernel: nicvf, ver 1.0 Mar 17 18:45:53.026190 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 17 18:45:53.026254 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-17T18:45:52 UTC (1742237152) Mar 17 18:45:53.026263 kernel: efifb: probing for efifb Mar 17 18:45:53.026270 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 18:45:53.026277 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 18:45:53.026283 kernel: efifb: scrolling: redraw Mar 17 18:45:53.026290 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 18:45:53.026299 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:45:53.026306 kernel: fb0: EFI VGA frame buffer device Mar 17 18:45:53.026312 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Mar 17 18:45:53.026319 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:45:53.026325 kernel: NET: Registered PF_INET6 protocol family Mar 17 18:45:53.026332 kernel: Segment Routing with IPv6 Mar 17 18:45:53.026338 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 18:45:53.026345 kernel: NET: Registered PF_PACKET protocol family Mar 17 18:45:53.026351 kernel: Key type dns_resolver registered Mar 17 18:45:53.026357 kernel: registered taskstats version 1 Mar 17 18:45:53.026365 kernel: Loading compiled-in X.509 certificates Mar 17 18:45:53.026372 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.179-flatcar: c6f3fb83dc6bb7052b07ec5b1ef41d12f9b3f7e4' Mar 17 18:45:53.026378 kernel: Key type .fscrypt registered Mar 17 18:45:53.026385 kernel: Key type fscrypt-provisioning registered Mar 17 18:45:53.026391 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 18:45:53.026398 kernel: ima: Allocated hash algorithm: sha1 Mar 17 18:45:53.026404 kernel: ima: No architecture policies found Mar 17 18:45:53.026411 kernel: clk: Disabling unused clocks Mar 17 18:45:53.026418 kernel: Freeing unused kernel memory: 36416K Mar 17 18:45:53.026425 kernel: Run /init as init process Mar 17 18:45:53.026431 kernel: with arguments: Mar 17 18:45:53.026438 kernel: /init Mar 17 18:45:53.026445 kernel: with environment: Mar 17 18:45:53.026451 kernel: HOME=/ Mar 17 18:45:53.026457 kernel: TERM=linux Mar 17 18:45:53.026464 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 18:45:53.026472 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) Mar 17 18:45:53.026482 systemd[1]: Detected virtualization microsoft. Mar 17 18:45:53.026489 systemd[1]: Detected architecture arm64. Mar 17 18:45:53.026496 systemd[1]: Running in initrd. Mar 17 18:45:53.026503 systemd[1]: No hostname configured, using default hostname. Mar 17 18:45:53.026509 systemd[1]: Hostname set to . Mar 17 18:45:53.026517 systemd[1]: Initializing machine ID from random generator. Mar 17 18:45:53.026524 systemd[1]: Queued start job for default target initrd.target. Mar 17 18:45:53.026532 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:45:53.026539 systemd[1]: Reached target cryptsetup.target. Mar 17 18:45:53.026546 systemd[1]: Reached target paths.target. Mar 17 18:45:53.026552 systemd[1]: Reached target slices.target. Mar 17 18:45:53.026559 systemd[1]: Reached target swap.target. Mar 17 18:45:53.026566 systemd[1]: Reached target timers.target. Mar 17 18:45:53.026574 systemd[1]: Listening on iscsid.socket. Mar 17 18:45:53.026581 systemd[1]: Listening on iscsiuio.socket. Mar 17 18:45:53.026589 systemd[1]: Listening on systemd-journald-audit.socket. Mar 17 18:45:53.026596 systemd[1]: Listening on systemd-journald-dev-log.socket. Mar 17 18:45:53.026603 systemd[1]: Listening on systemd-journald.socket. Mar 17 18:45:53.026610 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:45:53.026617 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:45:53.026624 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:45:53.026630 systemd[1]: Reached target sockets.target. Mar 17 18:45:53.026637 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:45:53.026644 systemd[1]: Finished network-cleanup.service. Mar 17 18:45:53.026653 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 18:45:53.026660 systemd[1]: Starting systemd-journald.service... Mar 17 18:45:53.026666 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:45:53.026673 systemd[1]: Starting systemd-resolved.service... Mar 17 18:45:53.026683 systemd-journald[276]: Journal started Mar 17 18:45:53.026719 systemd-journald[276]: Runtime Journal (/run/log/journal/4fef7eee4b4149a398d60cf1a5ef639f) is 8.0M, max 78.5M, 70.5M free. Mar 17 18:45:53.016594 systemd-modules-load[277]: Inserted module 'overlay' Mar 17 18:45:53.060805 systemd[1]: Starting systemd-vconsole-setup.service... Mar 17 18:45:53.060861 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 18:45:53.074011 systemd[1]: Started systemd-journald.service. Mar 17 18:45:53.074077 kernel: Bridge firewalling registered Mar 17 18:45:53.074188 systemd-modules-load[277]: Inserted module 'br_netfilter' Mar 17 18:45:53.107913 kernel: audit: type=1130 audit(1742237153.074: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' Mar 17 18:45:53.107937 kernel: SCSI subsystem initialized Mar 17 18:45:53.074000 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' Mar 17 18:45:53.099237 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:45:53.103815 systemd-resolved[278]: Positive Trust Anchors: Mar 17 18:45:53.166266 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 18:45:53.166290 kernel: device-mapper: uevent: version 1.0.3 Mar 17 18:45:53.166299 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Mar 17 18:45:53.166308 kernel: audit: type=1130 audit(1742237153.147: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' Mar 17 18:45:53.147000 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' Mar 17 18:45:53.103822 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:45:53.103850 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 Mar 17 18:45:53.273288 kernel: audit: type=1130 audit(1742237153.169: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' Mar 17 18:45:53.273319 kernel: audit: type=1130 audit(1742237153.199: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' Mar 17 18:45:53.273328 kernel: audit: type=1130 audit(1742237153.255:6): 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' Mar 17 18:45:53.169000 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' Mar 17 18:45:53.199000 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' Mar 17 18:45:53.255000 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' Mar 17 18:45:53.105955 systemd-resolved[278]: Defaulting to hostname 'linux'. Mar 17 18:45:53.277000 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' Mar 17 18:45:53.164642 systemd[1]: Started systemd-resolved.service. Mar 17 18:45:53.164743 systemd-modules-load[277]: Inserted module 'dm_multipath' Mar 17 18:45:53.312342 kernel: audit: type=1130 audit(1742237153.277:7): 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' Mar 17 18:45:53.169479 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 18:45:53.200110 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:45:53.255785 systemd[1]: Finished systemd-vconsole-setup.service. Mar 17 18:45:53.298311 systemd[1]: Reached target nss-lookup.target. Mar 17 18:45:53.308415 systemd[1]: Starting dracut-cmdline-ask.service... Mar 17 18:45:53.317449 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:45:53.387485 kernel: audit: type=1130 audit(1742237153.359:8): 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' Mar 17 18:45:53.359000 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' Mar 17 18:45:53.325500 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:45:53.387000 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' Mar 17 18:45:53.351095 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:45:53.413000 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' Mar 17 18:45:53.378714 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:45:53.446474 kernel: audit: type=1130 audit(1742237153.387:9): 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' Mar 17 18:45:53.446498 kernel: audit: type=1130 audit(1742237153.413:10): 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' Mar 17 18:45:53.387737 systemd[1]: Finished dracut-cmdline-ask.service. Mar 17 18:45:53.452064 dracut-cmdline[298]: dracut-dracut-053 Mar 17 18:45:53.417897 systemd[1]: Starting dracut-cmdline.service... Mar 17 18:45:53.461608 dracut-cmdline[298]: 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=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:45:53.556197 kernel: Loading iSCSI transport class v2.0-870. Mar 17 18:45:53.572207 kernel: iscsi: registered transport (tcp) Mar 17 18:45:53.592935 kernel: iscsi: registered transport (qla4xxx) Mar 17 18:45:53.592991 kernel: QLogic iSCSI HBA Driver Mar 17 18:45:53.630010 systemd[1]: Finished dracut-cmdline.service. Mar 17 18:45:53.634000 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' Mar 17 18:45:53.635529 systemd[1]: Starting dracut-pre-udev.service... Mar 17 18:45:53.689190 kernel: raid6: neonx8 gen() 13812 MB/s Mar 17 18:45:53.707182 kernel: raid6: neonx8 xor() 10844 MB/s Mar 17 18:45:53.727182 kernel: raid6: neonx4 gen() 13535 MB/s Mar 17 18:45:53.748181 kernel: raid6: neonx4 xor() 11285 MB/s Mar 17 18:45:53.768189 kernel: raid6: neonx2 gen() 12958 MB/s Mar 17 18:45:53.788182 kernel: raid6: neonx2 xor() 10234 MB/s Mar 17 18:45:53.809185 kernel: raid6: neonx1 gen() 10517 MB/s Mar 17 18:45:53.829185 kernel: raid6: neonx1 xor() 8789 MB/s Mar 17 18:45:53.849180 kernel: raid6: int64x8 gen() 6265 MB/s Mar 17 18:45:53.870182 kernel: raid6: int64x8 xor() 3541 MB/s Mar 17 18:45:53.890180 kernel: raid6: int64x4 gen() 7246 MB/s Mar 17 18:45:53.910180 kernel: raid6: int64x4 xor() 3860 MB/s Mar 17 18:45:53.931182 kernel: raid6: int64x2 gen() 6146 MB/s Mar 17 18:45:53.952180 kernel: raid6: int64x2 xor() 3317 MB/s Mar 17 18:45:53.972180 kernel: raid6: int64x1 gen() 5041 MB/s Mar 17 18:45:53.998438 kernel: raid6: int64x1 xor() 2645 MB/s Mar 17 18:45:53.998449 kernel: raid6: using algorithm neonx8 gen() 13812 MB/s Mar 17 18:45:53.998457 kernel: raid6: .... xor() 10844 MB/s, rmw enabled Mar 17 18:45:54.002891 kernel: raid6: using neon recovery algorithm Mar 17 18:45:54.024323 kernel: xor: measuring software checksum speed Mar 17 18:45:54.024335 kernel: 8regs : 17177 MB/sec Mar 17 18:45:54.028497 kernel: 32regs : 20665 MB/sec Mar 17 18:45:54.032422 kernel: arm64_neon : 27682 MB/sec Mar 17 18:45:54.032433 kernel: xor: using function: arm64_neon (27682 MB/sec) Mar 17 18:45:54.093195 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Mar 17 18:45:54.104161 systemd[1]: Finished dracut-pre-udev.service. Mar 17 18:45:54.108000 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' Mar 17 18:45:54.112000 audit: BPF prog-id=7 op=LOAD Mar 17 18:45:54.112000 audit: BPF prog-id=8 op=LOAD Mar 17 18:45:54.113279 systemd[1]: Starting systemd-udevd.service... Mar 17 18:45:54.128092 systemd-udevd[475]: Using default interface naming scheme 'v252'. Mar 17 18:45:54.134294 systemd[1]: Started systemd-udevd.service. Mar 17 18:45:54.139000 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' Mar 17 18:45:54.144745 systemd[1]: Starting dracut-pre-trigger.service... Mar 17 18:45:54.160400 dracut-pre-trigger[496]: rd.md=0: removing MD RAID activation Mar 17 18:45:54.196681 systemd[1]: Finished dracut-pre-trigger.service. Mar 17 18:45:54.202000 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' Mar 17 18:45:54.203665 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:45:54.255590 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:45:54.265000 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' Mar 17 18:45:54.322452 kernel: hv_vmbus: Vmbus version:5.3 Mar 17 18:45:54.351192 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 18:45:54.351265 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 18:45:54.378205 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Mar 17 18:45:54.378271 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Mar 17 18:45:54.378286 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 18:45:54.390565 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 18:45:54.390605 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 18:45:54.403197 kernel: scsi host0: storvsc_host_t Mar 17 18:45:54.403283 kernel: scsi host1: storvsc_host_t Mar 17 18:45:54.410134 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 18:45:54.417500 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 18:45:54.436196 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 18:45:54.447472 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 18:45:54.447487 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 18:45:54.468007 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 17 18:45:54.468107 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 18:45:54.468212 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 18:45:54.468290 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 17 18:45:54.468365 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 18:45:54.468446 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:45:54.468455 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 18:45:54.481195 kernel: hv_netvsc 0022487a-6a19-0022-487a-6a190022487a eth0: VF slot 1 added Mar 17 18:45:54.496004 kernel: hv_vmbus: registering driver hv_pci Mar 17 18:45:54.496050 kernel: hv_pci a5cfc5fe-1c45-4e93-894d-6227e44ca9ae: PCI VMBus probing: Using version 0x10004 Mar 17 18:45:54.607917 kernel: hv_pci a5cfc5fe-1c45-4e93-894d-6227e44ca9ae: PCI host bridge to bus 1c45:00 Mar 17 18:45:54.608029 kernel: pci_bus 1c45:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Mar 17 18:45:54.608128 kernel: pci_bus 1c45:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 18:45:54.608226 kernel: pci 1c45:00:02.0: [15b3:1018] type 00 class 0x020000 Mar 17 18:45:54.608319 kernel: pci 1c45:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 18:45:54.608398 kernel: pci 1c45:00:02.0: enabling Extended Tags Mar 17 18:45:54.608476 kernel: pci 1c45:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 1c45:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Mar 17 18:45:54.608548 kernel: pci_bus 1c45:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 18:45:54.608620 kernel: pci 1c45:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 18:45:54.646193 kernel: mlx5_core 1c45:00:02.0: firmware version: 16.30.1284 Mar 17 18:45:54.890346 kernel: mlx5_core 1c45:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0) Mar 17 18:45:54.890463 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (530) Mar 17 18:45:54.890473 kernel: hv_netvsc 0022487a-6a19-0022-487a-6a190022487a eth0: VF registering: eth1 Mar 17 18:45:54.890553 kernel: mlx5_core 1c45:00:02.0 eth1: joined to eth0 Mar 17 18:45:54.840548 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Mar 17 18:45:54.882208 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:45:54.907209 kernel: mlx5_core 1c45:00:02.0 enP7237s1: renamed from eth1 Mar 17 18:45:55.039605 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Mar 17 18:45:55.045802 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Mar 17 18:45:55.062257 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Mar 17 18:45:55.072819 systemd[1]: Starting disk-uuid.service... Mar 17 18:45:55.099205 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:45:55.107205 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:45:56.115812 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:45:56.115868 disk-uuid[605]: The operation has completed successfully. Mar 17 18:45:56.170527 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 18:45:56.171346 systemd[1]: Finished disk-uuid.service. Mar 17 18:45:56.179000 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' Mar 17 18:45:56.179000 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' Mar 17 18:45:56.192527 systemd[1]: Starting verity-setup.service... Mar 17 18:45:56.231193 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 17 18:45:56.443642 systemd[1]: Found device dev-mapper-usr.device. Mar 17 18:45:56.453851 systemd[1]: Finished verity-setup.service. Mar 17 18:45:56.458000 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' Mar 17 18:45:56.459533 systemd[1]: Mounting sysusr-usr.mount... Mar 17 18:45:56.523397 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Mar 17 18:45:56.523840 systemd[1]: Mounted sysusr-usr.mount. Mar 17 18:45:56.528007 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Mar 17 18:45:56.528932 systemd[1]: Starting ignition-setup.service... Mar 17 18:45:56.536902 systemd[1]: Starting parse-ip-for-networkd.service... Mar 17 18:45:56.574208 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:45:56.574260 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:45:56.574270 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:45:56.628828 systemd[1]: Finished parse-ip-for-networkd.service. Mar 17 18:45:56.633000 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' Mar 17 18:45:56.638000 audit: BPF prog-id=9 op=LOAD Mar 17 18:45:56.638886 systemd[1]: Starting systemd-networkd.service... Mar 17 18:45:56.648673 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 18:45:56.676977 systemd-networkd[846]: lo: Link UP Mar 17 18:45:56.679195 systemd-networkd[846]: lo: Gained carrier Mar 17 18:45:56.684000 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' Mar 17 18:45:56.679949 systemd-networkd[846]: Enumeration completed Mar 17 18:45:56.680035 systemd[1]: Started systemd-networkd.service. Mar 17 18:45:56.684976 systemd[1]: Reached target network.target. Mar 17 18:45:56.711000 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' Mar 17 18:45:56.693957 systemd-networkd[846]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:45:56.695391 systemd[1]: Starting iscsiuio.service... Mar 17 18:45:56.730000 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' Mar 17 18:45:56.734215 iscsid[855]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:45:56.734215 iscsid[855]: 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 Mar 17 18:45:56.734215 iscsid[855]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Mar 17 18:45:56.734215 iscsid[855]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Mar 17 18:45:56.734215 iscsid[855]: If using hardware iscsi like qla4xxx this message can be ignored. Mar 17 18:45:56.734215 iscsid[855]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:45:56.734215 iscsid[855]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Mar 17 18:45:56.771000 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' Mar 17 18:45:56.707273 systemd[1]: Started iscsiuio.service. Mar 17 18:45:56.712440 systemd[1]: Starting iscsid.service... Mar 17 18:45:56.723634 systemd[1]: Started iscsid.service. Mar 17 18:45:56.731371 systemd[1]: Starting dracut-initqueue.service... Mar 17 18:45:56.766391 systemd[1]: Finished dracut-initqueue.service. Mar 17 18:45:56.860000 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' Mar 17 18:45:56.772203 systemd[1]: Reached target remote-fs-pre.target. Mar 17 18:45:56.869000 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' Mar 17 18:45:56.792554 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:45:56.889556 kernel: mlx5_core 1c45:00:02.0 enP7237s1: Link up Mar 17 18:45:56.804224 systemd[1]: Reached target remote-fs.target. Mar 17 18:45:56.816813 systemd[1]: Starting dracut-pre-mount.service... Mar 17 18:45:56.847765 systemd[1]: Finished dracut-pre-mount.service. Mar 17 18:45:56.861085 systemd[1]: Finished ignition-setup.service. Mar 17 18:45:56.879597 systemd[1]: Starting ignition-fetch-offline.service... Mar 17 18:45:56.933597 kernel: hv_netvsc 0022487a-6a19-0022-487a-6a190022487a eth0: Data path switched to VF: enP7237s1 Mar 17 18:45:56.933765 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:45:56.934102 systemd-networkd[846]: enP7237s1: Link UP Mar 17 18:45:56.934205 systemd-networkd[846]: eth0: Link UP Mar 17 18:45:56.934364 systemd-networkd[846]: eth0: Gained carrier Mar 17 18:45:56.949368 systemd-networkd[846]: enP7237s1: Gained carrier Mar 17 18:45:56.961244 systemd-networkd[846]: eth0: DHCPv4 address 10.200.20.32/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:45:58.648283 systemd-networkd[846]: eth0: Gained IPv6LL Mar 17 18:45:59.038648 ignition[870]: Ignition 2.14.0 Mar 17 18:45:59.042137 ignition[870]: Stage: fetch-offline Mar 17 18:45:59.042313 ignition[870]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:45:59.042348 ignition[870]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:45:59.158826 ignition[870]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:45:59.159009 ignition[870]: parsed url from cmdline: "" Mar 17 18:45:59.165408 systemd[1]: Finished ignition-fetch-offline.service. Mar 17 18:45:59.194953 kernel: kauditd_printk_skb: 18 callbacks suppressed Mar 17 18:45:59.194978 kernel: audit: type=1130 audit(1742237159.170: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' Mar 17 18:45:59.170000 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' Mar 17 18:45:59.159012 ignition[870]: no config URL provided Mar 17 18:45:59.171703 systemd[1]: Starting ignition-fetch.service... Mar 17 18:45:59.159018 ignition[870]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:45:59.159027 ignition[870]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:45:59.159032 ignition[870]: failed to fetch config: resource requires networking Mar 17 18:45:59.159489 ignition[870]: Ignition finished successfully Mar 17 18:45:59.203427 ignition[876]: Ignition 2.14.0 Mar 17 18:45:59.203433 ignition[876]: Stage: fetch Mar 17 18:45:59.203528 ignition[876]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:45:59.203549 ignition[876]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:45:59.207018 ignition[876]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:45:59.208850 ignition[876]: parsed url from cmdline: "" Mar 17 18:45:59.208855 ignition[876]: no config URL provided Mar 17 18:45:59.208861 ignition[876]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:45:59.208875 ignition[876]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:45:59.208912 ignition[876]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 18:45:59.313679 ignition[876]: GET result: OK Mar 17 18:45:59.313735 ignition[876]: config has been read from IMDS userdata Mar 17 18:45:59.316735 unknown[876]: fetched base config from "system" Mar 17 18:45:59.325000 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' Mar 17 18:45:59.313747 ignition[876]: parsing config with SHA512: d4f5f42f6ad40654dae18da6c84e55cd005059130e975e3d47a9ba042fcaed47ccb9f1198c0907d515860d1f56df87651eaf88408972b11d0878430a2a63cb91 Mar 17 18:45:59.316742 unknown[876]: fetched base config from "system" Mar 17 18:45:59.357267 kernel: audit: type=1130 audit(1742237159.325: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' Mar 17 18:45:59.317052 ignition[876]: fetch: fetch complete Mar 17 18:45:59.316748 unknown[876]: fetched user config from "azure" Mar 17 18:45:59.317057 ignition[876]: fetch: fetch passed Mar 17 18:45:59.318302 systemd[1]: Finished ignition-fetch.service. Mar 17 18:45:59.317098 ignition[876]: Ignition finished successfully Mar 17 18:45:59.345584 systemd[1]: Starting ignition-kargs.service... Mar 17 18:45:59.380000 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' Mar 17 18:45:59.363194 ignition[882]: Ignition 2.14.0 Mar 17 18:45:59.407163 kernel: audit: type=1130 audit(1742237159.380: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' Mar 17 18:45:59.372447 systemd[1]: Finished ignition-kargs.service. Mar 17 18:45:59.434266 kernel: audit: type=1130 audit(1742237159.411: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' Mar 17 18:45:59.411000 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' Mar 17 18:45:59.363201 ignition[882]: Stage: kargs Mar 17 18:45:59.381428 systemd[1]: Starting ignition-disks.service... Mar 17 18:45:59.363316 ignition[882]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:45:59.404425 systemd[1]: Finished ignition-disks.service. Mar 17 18:45:59.363334 ignition[882]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:45:59.411818 systemd[1]: Reached target initrd-root-device.target. Mar 17 18:45:59.367716 ignition[882]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:45:59.434782 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:45:59.370076 ignition[882]: kargs: kargs passed Mar 17 18:45:59.441920 systemd[1]: Reached target local-fs.target. Mar 17 18:45:59.370124 ignition[882]: Ignition finished successfully Mar 17 18:45:59.451206 systemd[1]: Reached target sysinit.target. Mar 17 18:45:59.390922 ignition[888]: Ignition 2.14.0 Mar 17 18:45:59.460989 systemd[1]: Reached target basic.target. Mar 17 18:45:59.390928 ignition[888]: Stage: disks Mar 17 18:45:59.469774 systemd[1]: Starting systemd-fsck-root.service... Mar 17 18:45:59.391032 ignition[888]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:45:59.391050 ignition[888]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:45:59.393673 ignition[888]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:45:59.399197 ignition[888]: disks: disks passed Mar 17 18:45:59.399250 ignition[888]: Ignition finished successfully Mar 17 18:45:59.560078 systemd-fsck[896]: ROOT: clean, 623/7326000 files, 481077/7359488 blocks Mar 17 18:45:59.569593 systemd[1]: Finished systemd-fsck-root.service. Mar 17 18:45:59.596320 kernel: audit: type=1130 audit(1742237159.574: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' Mar 17 18:45:59.574000 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' Mar 17 18:45:59.575109 systemd[1]: Mounting sysroot.mount... Mar 17 18:45:59.617578 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Mar 17 18:45:59.614193 systemd[1]: Mounted sysroot.mount. Mar 17 18:45:59.621336 systemd[1]: Reached target initrd-root-fs.target. Mar 17 18:45:59.656001 systemd[1]: Mounting sysroot-usr.mount... Mar 17 18:45:59.660505 systemd[1]: Starting flatcar-metadata-hostname.service... Mar 17 18:45:59.669562 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 18:45:59.669593 systemd[1]: Reached target ignition-diskful.target. Mar 17 18:45:59.679997 systemd[1]: Mounted sysroot-usr.mount. Mar 17 18:45:59.731739 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:45:59.737028 systemd[1]: Starting initrd-setup-root.service... Mar 17 18:45:59.759196 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (906) Mar 17 18:45:59.765904 initrd-setup-root[911]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 18:45:59.780260 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:45:59.780282 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:45:59.780291 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:45:59.784456 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:45:59.796684 initrd-setup-root[937]: cut: /sysroot/etc/group: No such file or directory Mar 17 18:45:59.818720 initrd-setup-root[945]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 18:45:59.827944 initrd-setup-root[953]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 18:46:00.184036 systemd[1]: Finished initrd-setup-root.service. Mar 17 18:46:00.188000 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' Mar 17 18:46:00.209493 systemd[1]: Starting ignition-mount.service... Mar 17 18:46:00.219316 kernel: audit: type=1130 audit(1742237160.188: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' Mar 17 18:46:00.217516 systemd[1]: Starting sysroot-boot.service... Mar 17 18:46:00.229983 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Mar 17 18:46:00.230130 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Mar 17 18:46:00.250116 ignition[972]: INFO : Ignition 2.14.0 Mar 17 18:46:00.250116 ignition[972]: INFO : Stage: mount Mar 17 18:46:00.262230 ignition[972]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:46:00.262230 ignition[972]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:46:00.262230 ignition[972]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:46:00.262230 ignition[972]: INFO : mount: mount passed Mar 17 18:46:00.262230 ignition[972]: INFO : Ignition finished successfully Mar 17 18:46:00.343836 kernel: audit: type=1130 audit(1742237160.274:35): 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' Mar 17 18:46:00.343861 kernel: audit: type=1130 audit(1742237160.310:36): 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' Mar 17 18:46:00.274000 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' Mar 17 18:46:00.310000 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' Mar 17 18:46:00.262376 systemd[1]: Finished ignition-mount.service. Mar 17 18:46:00.295663 systemd[1]: Finished sysroot-boot.service. Mar 17 18:46:00.833893 coreos-metadata[905]: Mar 17 18:46:00.833 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 18:46:00.844468 coreos-metadata[905]: Mar 17 18:46:00.844 INFO Fetch successful Mar 17 18:46:00.877878 coreos-metadata[905]: Mar 17 18:46:00.877 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 18:46:00.898596 coreos-metadata[905]: Mar 17 18:46:00.898 INFO Fetch successful Mar 17 18:46:00.915934 coreos-metadata[905]: Mar 17 18:46:00.915 INFO wrote hostname ci-3510.3.7-a-fbbca1c12b to /sysroot/etc/hostname Mar 17 18:46:00.925501 systemd[1]: Finished flatcar-metadata-hostname.service. Mar 17 18:46:00.930000 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' Mar 17 18:46:00.931428 systemd[1]: Starting ignition-files.service... Mar 17 18:46:00.959288 kernel: audit: type=1130 audit(1742237160.930:37): 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' Mar 17 18:46:00.958340 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:46:00.983120 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (984) Mar 17 18:46:00.983158 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:46:00.983180 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:46:00.992509 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:46:00.997517 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:46:01.011272 ignition[1003]: INFO : Ignition 2.14.0 Mar 17 18:46:01.011272 ignition[1003]: INFO : Stage: files Mar 17 18:46:01.021301 ignition[1003]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:46:01.021301 ignition[1003]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:46:01.021301 ignition[1003]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:46:01.021301 ignition[1003]: DEBUG : files: compiled without relabeling support, skipping Mar 17 18:46:01.021301 ignition[1003]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 18:46:01.021301 ignition[1003]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 18:46:01.083195 ignition[1003]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 18:46:01.091344 ignition[1003]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 18:46:01.099295 ignition[1003]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 18:46:01.098608 unknown[1003]: wrote ssh authorized keys file for user: core Mar 17 18:46:01.112323 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:46:01.112323 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:46:01.112323 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:46:01.112323 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem878001849" Mar 17 18:46:01.155848 ignition[1003]: CRITICAL : files: createFilesystemsFiles: createFiles: op(4): op(5): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem878001849": device or resource busy Mar 17 18:46:01.155848 ignition[1003]: ERROR : files: createFilesystemsFiles: createFiles: op(4): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem878001849", trying btrfs: device or resource busy Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(6): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem878001849" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(6): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem878001849" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(7): [started] unmounting "/mnt/oem878001849" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): op(7): [finished] unmounting "/mnt/oem878001849" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:46:01.155848 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:46:01.314717 kernel: audit: type=1130 audit(1742237161.207: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' Mar 17 18:46:01.207000 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' Mar 17 18:46:01.256000 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' Mar 17 18:46:01.256000 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' Mar 17 18:46:01.272000 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' Mar 17 18:46:01.135453 systemd[1]: mnt-oem878001849.mount: Deactivated successfully. Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3381894664" Mar 17 18:46:01.320341 ignition[1003]: CRITICAL : files: createFilesystemsFiles: createFiles: op(8): op(9): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3381894664": device or resource busy Mar 17 18:46:01.320341 ignition[1003]: ERROR : files: createFilesystemsFiles: createFiles: op(8): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3381894664", trying btrfs: device or resource busy Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(a): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3381894664" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(a): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3381894664" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(b): [started] unmounting "/mnt/oem3381894664" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): op(b): [finished] unmounting "/mnt/oem3381894664" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createFilesystemsFiles: createFiles: op(8): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(c): [started] processing unit "waagent.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(c): [finished] processing unit "waagent.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(d): [started] processing unit "nvidia.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(d): [finished] processing unit "nvidia.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(e): [started] setting preset to enabled for "waagent.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(e): [finished] setting preset to enabled for "waagent.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(f): [started] setting preset to enabled for "nvidia.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: op(f): [finished] setting preset to enabled for "nvidia.service" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createResultFile: createFiles: op(10): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: createResultFile: createFiles: op(10): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:46:01.320341 ignition[1003]: INFO : files: files passed Mar 17 18:46:01.320341 ignition[1003]: INFO : Ignition finished successfully Mar 17 18:46:01.348000 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' Mar 17 18:46:01.348000 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' Mar 17 18:46:01.447000 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' Mar 17 18:46:01.523000 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' Mar 17 18:46:01.193560 systemd[1]: Finished ignition-files.service. Mar 17 18:46:01.211280 systemd[1]: Starting initrd-setup-root-after-ignition.service... Mar 17 18:46:01.569054 initrd-setup-root-after-ignition[1028]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 18:46:01.238432 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Mar 17 18:46:01.239347 systemd[1]: Starting ignition-quench.service... Mar 17 18:46:01.249383 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 18:46:01.249514 systemd[1]: Finished ignition-quench.service. Mar 17 18:46:01.266208 systemd[1]: Finished initrd-setup-root-after-ignition.service. Mar 17 18:46:01.272430 systemd[1]: Reached target ignition-complete.target. Mar 17 18:46:01.289545 systemd[1]: Starting initrd-parse-etc.service... Mar 17 18:46:01.642000 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' Mar 17 18:46:01.333101 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 18:46:01.333238 systemd[1]: Finished initrd-parse-etc.service. Mar 17 18:46:01.659000 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' Mar 17 18:46:01.348746 systemd[1]: Reached target initrd-fs.target. Mar 17 18:46:01.668000 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' Mar 17 18:46:01.366100 systemd[1]: Reached target initrd.target. Mar 17 18:46:01.676000 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' Mar 17 18:46:01.380844 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Mar 17 18:46:01.685000 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' Mar 17 18:46:01.391481 systemd[1]: Starting dracut-pre-pivot.service... Mar 17 18:46:01.442334 systemd[1]: Finished dracut-pre-pivot.service. Mar 17 18:46:01.453824 systemd[1]: Starting initrd-cleanup.service... Mar 17 18:46:01.714000 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' Mar 17 18:46:01.719153 ignition[1041]: INFO : Ignition 2.14.0 Mar 17 18:46:01.719153 ignition[1041]: INFO : Stage: umount Mar 17 18:46:01.719153 ignition[1041]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:46:01.719153 ignition[1041]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:46:01.719153 ignition[1041]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:46:01.719153 ignition[1041]: INFO : umount: umount passed Mar 17 18:46:01.719153 ignition[1041]: INFO : Ignition finished successfully Mar 17 18:46:01.733000 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' Mar 17 18:46:01.742000 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' Mar 17 18:46:01.753000 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' Mar 17 18:46:01.771000 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' Mar 17 18:46:01.771000 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' Mar 17 18:46:01.781000 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' Mar 17 18:46:01.473372 systemd[1]: Stopped target nss-lookup.target. Mar 17 18:46:01.797000 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' Mar 17 18:46:01.802455 iscsid[855]: iscsid shutting down. Mar 17 18:46:01.806000 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' Mar 17 18:46:01.484597 systemd[1]: Stopped target remote-cryptsetup.target. Mar 17 18:46:01.813000 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' Mar 17 18:46:01.497392 systemd[1]: Stopped target timers.target. Mar 17 18:46:01.822000 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' Mar 17 18:46:01.509667 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 18:46:01.509737 systemd[1]: Stopped dracut-pre-pivot.service. Mar 17 18:46:01.523852 systemd[1]: Stopped target initrd.target. Mar 17 18:46:01.537551 systemd[1]: Stopped target basic.target. Mar 17 18:46:01.545367 systemd[1]: Stopped target ignition-complete.target. Mar 17 18:46:01.554597 systemd[1]: Stopped target ignition-diskful.target. Mar 17 18:46:01.563353 systemd[1]: Stopped target initrd-root-device.target. Mar 17 18:46:01.573985 systemd[1]: Stopped target remote-fs.target. Mar 17 18:46:01.880000 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' Mar 17 18:46:01.586126 systemd[1]: Stopped target remote-fs-pre.target. Mar 17 18:46:01.600470 systemd[1]: Stopped target sysinit.target. Mar 17 18:46:01.897000 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' Mar 17 18:46:01.608387 systemd[1]: Stopped target local-fs.target. Mar 17 18:46:01.905000 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' Mar 17 18:46:01.617148 systemd[1]: Stopped target local-fs-pre.target. Mar 17 18:46:01.625228 systemd[1]: Stopped target swap.target. Mar 17 18:46:01.634048 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 18:46:01.928000 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' Mar 17 18:46:01.634107 systemd[1]: Stopped dracut-pre-mount.service. Mar 17 18:46:01.642586 systemd[1]: Stopped target cryptsetup.target. Mar 17 18:46:01.650550 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 18:46:01.650595 systemd[1]: Stopped dracut-initqueue.service. Mar 17 18:46:01.961000 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' Mar 17 18:46:01.659808 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 18:46:01.970000 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' Mar 17 18:46:01.970000 audit: BPF prog-id=6 op=UNLOAD Mar 17 18:46:01.659848 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Mar 17 18:46:01.668763 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 18:46:01.994000 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' Mar 17 18:46:01.668799 systemd[1]: Stopped ignition-files.service. Mar 17 18:46:02.004000 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' Mar 17 18:46:01.677113 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 18:46:02.012000 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' Mar 17 18:46:01.677155 systemd[1]: Stopped flatcar-metadata-hostname.service. Mar 17 18:46:01.686234 systemd[1]: Stopping ignition-mount.service... Mar 17 18:46:01.706104 systemd[1]: Stopping iscsid.service... Mar 17 18:46:01.709289 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 18:46:02.045000 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' Mar 17 18:46:01.709352 systemd[1]: Stopped kmod-static-nodes.service. Mar 17 18:46:01.715491 systemd[1]: Stopping sysroot-boot.service... Mar 17 18:46:01.728734 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 18:46:02.072000 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' Mar 17 18:46:01.728798 systemd[1]: Stopped systemd-udev-trigger.service. Mar 17 18:46:02.081000 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' Mar 17 18:46:01.733495 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 18:46:02.105267 kernel: hv_netvsc 0022487a-6a19-0022-487a-6a190022487a eth0: Data path switched from VF: enP7237s1 Mar 17 18:46:02.099000 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' Mar 17 18:46:01.733538 systemd[1]: Stopped dracut-pre-trigger.service. Mar 17 18:46:01.743127 systemd[1]: iscsid.service: Deactivated successfully. Mar 17 18:46:01.743235 systemd[1]: Stopped iscsid.service. Mar 17 18:46:01.754356 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 18:46:02.126000 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' Mar 17 18:46:01.754436 systemd[1]: Finished initrd-cleanup.service. Mar 17 18:46:02.137000 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' Mar 17 18:46:02.137000 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' Mar 17 18:46:01.772484 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 18:46:01.772901 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 18:46:01.772971 systemd[1]: Stopped ignition-mount.service. Mar 17 18:46:01.782359 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 18:46:01.782403 systemd[1]: Stopped ignition-disks.service. Mar 17 18:46:01.798152 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 18:46:01.798231 systemd[1]: Stopped ignition-kargs.service. Mar 17 18:46:01.806542 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 18:46:01.806573 systemd[1]: Stopped ignition-fetch.service. Mar 17 18:46:01.814048 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 18:46:01.814083 systemd[1]: Stopped ignition-fetch-offline.service. Mar 17 18:46:01.822974 systemd[1]: Stopped target paths.target. Mar 17 18:46:01.830510 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 18:46:01.840196 systemd[1]: Stopped systemd-ask-password-console.path. Mar 17 18:46:02.207000 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' Mar 17 18:46:01.848314 systemd[1]: Stopped target slices.target. Mar 17 18:46:01.855901 systemd[1]: Stopped target sockets.target. Mar 17 18:46:01.864712 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 18:46:01.864759 systemd[1]: Closed iscsid.socket. Mar 17 18:46:01.872470 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 18:46:01.872514 systemd[1]: Stopped ignition-setup.service. Mar 17 18:46:01.881693 systemd[1]: Stopping iscsiuio.service... Mar 17 18:46:01.889760 systemd[1]: iscsiuio.service: Deactivated successfully. Mar 17 18:46:01.889860 systemd[1]: Stopped iscsiuio.service. Mar 17 18:46:01.898026 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 18:46:01.898103 systemd[1]: Stopped sysroot-boot.service. Mar 17 18:46:02.276727 systemd-journald[276]: Received SIGTERM from PID 1 (n/a). Mar 17 18:46:01.905593 systemd[1]: Stopped target network.target. Mar 17 18:46:01.913308 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 18:46:01.913339 systemd[1]: Closed iscsiuio.socket. Mar 17 18:46:01.920828 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 18:46:01.920874 systemd[1]: Stopped initrd-setup-root.service. Mar 17 18:46:01.928985 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:46:01.938083 systemd[1]: Stopping systemd-resolved.service... Mar 17 18:46:01.950284 systemd-networkd[846]: eth0: DHCPv6 lease lost Mar 17 18:46:02.276000 audit: BPF prog-id=9 op=UNLOAD Mar 17 18:46:01.952253 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:46:01.952361 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:46:01.962322 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 18:46:01.962415 systemd[1]: Stopped systemd-resolved.service. Mar 17 18:46:01.970773 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 18:46:01.970808 systemd[1]: Closed systemd-networkd.socket. Mar 17 18:46:01.981490 systemd[1]: Stopping network-cleanup.service... Mar 17 18:46:01.989879 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 18:46:01.989941 systemd[1]: Stopped parse-ip-for-networkd.service. Mar 17 18:46:01.994769 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 18:46:01.994827 systemd[1]: Stopped systemd-sysctl.service. Mar 17 18:46:02.008302 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 18:46:02.008350 systemd[1]: Stopped systemd-modules-load.service. Mar 17 18:46:02.013062 systemd[1]: Stopping systemd-udevd.service... Mar 17 18:46:02.027837 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 18:46:02.037291 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 18:46:02.037454 systemd[1]: Stopped systemd-udevd.service. Mar 17 18:46:02.045871 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 18:46:02.045911 systemd[1]: Closed systemd-udevd-control.socket. Mar 17 18:46:02.055743 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 18:46:02.055784 systemd[1]: Closed systemd-udevd-kernel.socket. Mar 17 18:46:02.064125 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 18:46:02.064187 systemd[1]: Stopped dracut-pre-udev.service. Mar 17 18:46:02.072408 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 18:46:02.072448 systemd[1]: Stopped dracut-cmdline.service. Mar 17 18:46:02.081825 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 18:46:02.081859 systemd[1]: Stopped dracut-cmdline-ask.service. Mar 17 18:46:02.104723 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Mar 17 18:46:02.119160 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 18:46:02.119265 systemd[1]: Stopped systemd-vconsole-setup.service. Mar 17 18:46:02.127531 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 18:46:02.127631 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Mar 17 18:46:02.198133 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 18:46:02.198271 systemd[1]: Stopped network-cleanup.service. Mar 17 18:46:02.207586 systemd[1]: Reached target initrd-switch-root.target. Mar 17 18:46:02.217761 systemd[1]: Starting initrd-switch-root.service... Mar 17 18:46:02.233857 systemd[1]: Switching root. Mar 17 18:46:02.277919 systemd-journald[276]: Journal stopped Mar 17 18:46:28.646822 kernel: SELinux: Class mctp_socket not defined in policy. Mar 17 18:46:28.646842 kernel: SELinux: Class anon_inode not defined in policy. Mar 17 18:46:28.646852 kernel: SELinux: the above unknown classes and permissions will be allowed Mar 17 18:46:28.646862 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 18:46:28.646870 kernel: SELinux: policy capability open_perms=1 Mar 17 18:46:28.646877 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 18:46:28.646886 kernel: SELinux: policy capability always_check_network=0 Mar 17 18:46:28.646893 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 18:46:28.646901 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 18:46:28.646909 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 18:46:28.646917 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 18:46:28.646926 kernel: kauditd_printk_skb: 42 callbacks suppressed Mar 17 18:46:28.646935 kernel: audit: type=1403 audit(1742237167.690:81): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:46:28.646944 systemd[1]: Successfully loaded SELinux policy in 711.870ms. Mar 17 18:46:28.646955 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 21.421ms. Mar 17 18:46:28.646967 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) Mar 17 18:46:28.646976 systemd[1]: Detected virtualization microsoft. Mar 17 18:46:28.646987 systemd[1]: Detected architecture arm64. Mar 17 18:46:28.646996 systemd[1]: Detected first boot. Mar 17 18:46:28.647005 systemd[1]: Hostname set to . Mar 17 18:46:28.647014 systemd[1]: Initializing machine ID from random generator. Mar 17 18:46:28.647023 kernel: audit: type=1400 audit(1742237169.453:82): 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 Mar 17 18:46:28.647034 kernel: audit: type=1400 audit(1742237169.456:83): 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 Mar 17 18:46:28.647042 kernel: audit: type=1334 audit(1742237169.473:84): prog-id=10 op=LOAD Mar 17 18:46:28.647050 kernel: audit: type=1334 audit(1742237169.473:85): prog-id=10 op=UNLOAD Mar 17 18:46:28.647059 kernel: audit: type=1334 audit(1742237169.473:86): prog-id=11 op=LOAD Mar 17 18:46:28.647067 kernel: audit: type=1334 audit(1742237169.473:87): prog-id=11 op=UNLOAD Mar 17 18:46:28.647075 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Mar 17 18:46:28.647084 kernel: audit: type=1400 audit(1742237172.572:88): avc: denied { associate } for pid=1075 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" Mar 17 18:46:28.647095 kernel: audit: type=1300 audit(1742237172.572:88): arch=c00000b7 syscall=5 success=yes exit=0 a0=40000227f2 a1=4000028ac8 a2=4000026d00 a3=32 items=0 ppid=1058 pid=1075 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) Mar 17 18:46:28.647105 kernel: audit: type=1327 audit(1742237172.572:88): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:46:28.647113 systemd[1]: Populated /etc with preset unit settings. Mar 17 18:46:28.647122 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:46:28.647132 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:46:28.647142 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. Mar 17 18:46:28.647151 kernel: kauditd_printk_skb: 6 callbacks suppressed Mar 17 18:46:28.647160 kernel: audit: type=1334 audit(1742237187.926:90): prog-id=12 op=LOAD Mar 17 18:46:28.647180 kernel: audit: type=1334 audit(1742237187.926:91): prog-id=3 op=UNLOAD Mar 17 18:46:28.647193 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 18:46:28.647202 kernel: audit: type=1334 audit(1742237187.932:92): prog-id=13 op=LOAD Mar 17 18:46:28.647213 systemd[1]: Stopped initrd-switch-root.service. Mar 17 18:46:28.647223 kernel: audit: type=1334 audit(1742237187.937:93): prog-id=14 op=LOAD Mar 17 18:46:28.647231 kernel: audit: type=1334 audit(1742237187.937:94): prog-id=4 op=UNLOAD Mar 17 18:46:28.647241 kernel: audit: type=1334 audit(1742237187.937:95): prog-id=5 op=UNLOAD Mar 17 18:46:28.647250 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 18:46:28.647259 kernel: audit: type=1131 audit(1742237187.938:96): 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' Mar 17 18:46:28.647269 systemd[1]: Created slice system-addon\x2dconfig.slice. Mar 17 18:46:28.647278 kernel: audit: type=1334 audit(1742237187.959:97): prog-id=12 op=UNLOAD Mar 17 18:46:28.647287 kernel: audit: type=1130 audit(1742237187.979:98): 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' Mar 17 18:46:28.647296 systemd[1]: Created slice system-addon\x2drun.slice. Mar 17 18:46:28.647305 kernel: audit: type=1131 audit(1742237187.979: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' Mar 17 18:46:28.647315 systemd[1]: Created slice system-getty.slice. Mar 17 18:46:28.647324 systemd[1]: Created slice system-modprobe.slice. Mar 17 18:46:28.647334 systemd[1]: Created slice system-serial\x2dgetty.slice. Mar 17 18:46:28.647343 systemd[1]: Created slice system-system\x2dcloudinit.slice. Mar 17 18:46:28.647353 systemd[1]: Created slice system-systemd\x2dfsck.slice. Mar 17 18:46:28.647362 systemd[1]: Created slice user.slice. Mar 17 18:46:28.647371 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:46:28.647380 systemd[1]: Started systemd-ask-password-wall.path. Mar 17 18:46:28.647391 systemd[1]: Set up automount boot.automount. Mar 17 18:46:28.647400 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Mar 17 18:46:28.647409 systemd[1]: Stopped target initrd-switch-root.target. Mar 17 18:46:28.647418 systemd[1]: Stopped target initrd-fs.target. Mar 17 18:46:28.647427 systemd[1]: Stopped target initrd-root-fs.target. Mar 17 18:46:28.647436 systemd[1]: Reached target integritysetup.target. Mar 17 18:46:28.647446 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:46:28.647455 systemd[1]: Reached target remote-fs.target. Mar 17 18:46:28.647465 systemd[1]: Reached target slices.target. Mar 17 18:46:28.647474 systemd[1]: Reached target swap.target. Mar 17 18:46:28.647483 systemd[1]: Reached target torcx.target. Mar 17 18:46:28.647492 systemd[1]: Reached target veritysetup.target. Mar 17 18:46:28.647501 systemd[1]: Listening on systemd-coredump.socket. Mar 17 18:46:28.647510 systemd[1]: Listening on systemd-initctl.socket. Mar 17 18:46:28.647519 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:46:28.647530 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:46:28.647539 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:46:28.647548 systemd[1]: Listening on systemd-userdbd.socket. Mar 17 18:46:28.647558 systemd[1]: Mounting dev-hugepages.mount... Mar 17 18:46:28.647567 systemd[1]: Mounting dev-mqueue.mount... Mar 17 18:46:28.647576 systemd[1]: Mounting media.mount... Mar 17 18:46:28.647587 systemd[1]: Mounting sys-kernel-debug.mount... Mar 17 18:46:28.647596 systemd[1]: Mounting sys-kernel-tracing.mount... Mar 17 18:46:28.647606 systemd[1]: Mounting tmp.mount... Mar 17 18:46:28.647615 systemd[1]: Starting flatcar-tmpfiles.service... Mar 17 18:46:28.647624 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:46:28.647633 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:46:28.647642 systemd[1]: Starting modprobe@configfs.service... Mar 17 18:46:28.647651 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:46:28.647660 systemd[1]: Starting modprobe@drm.service... Mar 17 18:46:28.647671 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:46:28.647680 systemd[1]: Starting modprobe@fuse.service... Mar 17 18:46:28.647689 systemd[1]: Starting modprobe@loop.service... Mar 17 18:46:28.647699 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 18:46:28.647708 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 18:46:28.647717 systemd[1]: Stopped systemd-fsck-root.service. Mar 17 18:46:28.647727 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 18:46:28.647736 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 18:46:28.647745 systemd[1]: Stopped systemd-journald.service. Mar 17 18:46:28.647755 systemd[1]: systemd-journald.service: Consumed 2.599s CPU time. Mar 17 18:46:28.647765 systemd[1]: Starting systemd-journald.service... Mar 17 18:46:28.647774 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:46:28.647784 systemd[1]: Starting systemd-network-generator.service... Mar 17 18:46:28.647793 systemd[1]: Starting systemd-remount-fs.service... Mar 17 18:46:28.647802 kernel: loop: module loaded Mar 17 18:46:28.647810 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:46:28.647819 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 18:46:28.647829 systemd[1]: Stopped verity-setup.service. Mar 17 18:46:28.647839 systemd[1]: Mounted dev-hugepages.mount. Mar 17 18:46:28.647851 systemd-journald[1148]: Journal started Mar 17 18:46:28.647888 systemd-journald[1148]: Runtime Journal (/run/log/journal/0816a00c8c2f45fb863c020c6fc7ecec) is 8.0M, max 78.5M, 70.5M free. Mar 17 18:46:07.690000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:46:09.453000 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 Mar 17 18:46:09.456000 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 Mar 17 18:46:09.473000 audit: BPF prog-id=10 op=LOAD Mar 17 18:46:09.473000 audit: BPF prog-id=10 op=UNLOAD Mar 17 18:46:09.473000 audit: BPF prog-id=11 op=LOAD Mar 17 18:46:09.473000 audit: BPF prog-id=11 op=UNLOAD Mar 17 18:46:12.572000 audit[1075]: AVC avc: denied { associate } for pid=1075 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" Mar 17 18:46:12.572000 audit[1075]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40000227f2 a1=4000028ac8 a2=4000026d00 a3=32 items=0 ppid=1058 pid=1075 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) Mar 17 18:46:12.572000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:46:12.581000 audit[1075]: AVC avc: denied { associate } for pid=1075 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 Mar 17 18:46:12.581000 audit[1075]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40000228c9 a2=1ed a3=0 items=2 ppid=1058 pid=1075 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) Mar 17 18:46:12.581000 audit: CWD cwd="/" Mar 17 18:46:12.581000 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 Mar 17 18:46:12.581000 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 Mar 17 18:46:12.581000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:46:27.926000 audit: BPF prog-id=12 op=LOAD Mar 17 18:46:27.926000 audit: BPF prog-id=3 op=UNLOAD Mar 17 18:46:27.932000 audit: BPF prog-id=13 op=LOAD Mar 17 18:46:27.937000 audit: BPF prog-id=14 op=LOAD Mar 17 18:46:27.937000 audit: BPF prog-id=4 op=UNLOAD Mar 17 18:46:27.937000 audit: BPF prog-id=5 op=UNLOAD Mar 17 18:46:27.938000 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' Mar 17 18:46:27.959000 audit: BPF prog-id=12 op=UNLOAD Mar 17 18:46:27.979000 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' Mar 17 18:46:27.979000 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' Mar 17 18:46:28.502000 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' Mar 17 18:46:28.517000 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' Mar 17 18:46:28.526000 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' Mar 17 18:46:28.526000 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' Mar 17 18:46:28.533000 audit: BPF prog-id=15 op=LOAD Mar 17 18:46:28.533000 audit: BPF prog-id=16 op=LOAD Mar 17 18:46:28.533000 audit: BPF prog-id=17 op=LOAD Mar 17 18:46:28.533000 audit: BPF prog-id=13 op=UNLOAD Mar 17 18:46:28.533000 audit: BPF prog-id=14 op=UNLOAD Mar 17 18:46:28.643000 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' Mar 17 18:46:28.644000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Mar 17 18:46:28.644000 audit[1148]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffc433b6b0 a2=4000 a3=1 items=0 ppid=1 pid=1148 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) Mar 17 18:46:28.644000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Mar 17 18:46:27.925125 systemd[1]: Queued start job for default target multi-user.target. Mar 17 18:46:12.529331 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" 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.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:46:27.925139 systemd[1]: Unnecessary job was removed for dev-sda6.device. Mar 17 18:46:12.557595 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:46:27.938676 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 18:46:12.557630 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:46:27.939035 systemd[1]: systemd-journald.service: Consumed 2.599s CPU time. Mar 17 18:46:12.557665 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Mar 17 18:46:12.557675 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="skipped missing lower profile" missing profile=oem Mar 17 18:46:12.557714 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Mar 17 18:46:12.557726 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Mar 17 18:46:12.557928 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Mar 17 18:46:12.557961 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:46:12.557972 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:46:12.558348 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" 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 Mar 17 18:46:12.558378 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" 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 Mar 17 18:46:12.558400 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Mar 17 18:46:12.558414 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Mar 17 18:46:12.558430 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Mar 17 18:46:12.558444 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:12Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Mar 17 18:46:25.160257 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:25Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:46:25.160522 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46: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 Mar 17 18:46:25.160627 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46: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 Mar 17 18:46:25.160785 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46: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 Mar 17 18:46:25.160833 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46:25Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Mar 17 18:46:25.160886 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:46: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 Mar 17 18:46:28.664189 systemd[1]: Started systemd-journald.service. Mar 17 18:46:28.664000 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' Mar 17 18:46:28.664959 systemd[1]: Mounted dev-mqueue.mount. Mar 17 18:46:28.669602 systemd[1]: Mounted media.mount. Mar 17 18:46:28.673918 systemd[1]: Mounted sys-kernel-debug.mount. Mar 17 18:46:28.680132 systemd[1]: Mounted sys-kernel-tracing.mount. Mar 17 18:46:28.684927 systemd[1]: Mounted tmp.mount. Mar 17 18:46:28.688833 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:46:28.693000 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' Mar 17 18:46:28.694008 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 18:46:28.694139 systemd[1]: Finished modprobe@configfs.service. Mar 17 18:46:28.699000 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' Mar 17 18:46:28.699000 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' Mar 17 18:46:28.699738 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:46:28.699867 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:46:28.704000 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' Mar 17 18:46:28.704000 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' Mar 17 18:46:28.704735 systemd[1]: Finished flatcar-tmpfiles.service. Mar 17 18:46:28.709000 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' Mar 17 18:46:28.709722 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:46:28.709856 systemd[1]: Finished modprobe@drm.service. Mar 17 18:46:28.714000 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' Mar 17 18:46:28.714000 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' Mar 17 18:46:28.714893 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:46:28.715017 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:46:28.720000 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' Mar 17 18:46:28.720000 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' Mar 17 18:46:28.720807 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:46:28.720934 systemd[1]: Finished modprobe@loop.service. Mar 17 18:46:28.725000 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' Mar 17 18:46:28.725000 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' Mar 17 18:46:28.725932 systemd[1]: Finished systemd-network-generator.service. Mar 17 18:46:28.731000 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' Mar 17 18:46:28.731843 systemd[1]: Finished systemd-remount-fs.service. Mar 17 18:46:28.736000 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' Mar 17 18:46:28.737012 systemd[1]: Reached target network-pre.target. Mar 17 18:46:28.743004 systemd[1]: Mounting sys-kernel-config.mount... Mar 17 18:46:28.747163 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 18:46:28.940192 kernel: fuse: init (API version 7.34) Mar 17 18:46:28.993677 systemd[1]: Starting systemd-hwdb-update.service... Mar 17 18:46:29.000737 systemd[1]: Starting systemd-journal-flush.service... Mar 17 18:46:29.005603 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:46:29.006812 systemd[1]: Starting systemd-random-seed.service... Mar 17 18:46:29.011611 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:46:29.012820 systemd[1]: Starting systemd-sysusers.service... Mar 17 18:46:29.020541 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 18:46:29.020680 systemd[1]: Finished modprobe@fuse.service. Mar 17 18:46:29.026000 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' Mar 17 18:46:29.026000 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' Mar 17 18:46:29.026383 systemd[1]: Mounted sys-kernel-config.mount. Mar 17 18:46:29.032929 systemd[1]: Mounting sys-fs-fuse-connections.mount... Mar 17 18:46:29.042152 systemd[1]: Mounted sys-fs-fuse-connections.mount. Mar 17 18:46:29.047923 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:46:29.052000 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' Mar 17 18:46:29.054151 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:46:29.067813 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:46:29.072000 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' Mar 17 18:46:29.074118 systemd[1]: Starting systemd-udev-settle.service... Mar 17 18:46:29.085212 udevadm[1195]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 18:46:29.346357 systemd-journald[1148]: Time spent on flushing to /var/log/journal/0816a00c8c2f45fb863c020c6fc7ecec is 14.209ms for 1059 entries. Mar 17 18:46:29.346357 systemd-journald[1148]: System Journal (/var/log/journal/0816a00c8c2f45fb863c020c6fc7ecec) is 8.0M, max 2.6G, 2.6G free. Mar 17 18:46:30.320598 systemd-journald[1148]: Received client request to flush runtime journal. Mar 17 18:46:29.374000 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' Mar 17 18:46:29.417000 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' Mar 17 18:46:29.369646 systemd[1]: Finished systemd-random-seed.service. Mar 17 18:46:29.374739 systemd[1]: Reached target first-boot-complete.target. Mar 17 18:46:29.412583 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:46:30.321644 systemd[1]: Finished systemd-journal-flush.service. Mar 17 18:46:30.326000 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' Mar 17 18:46:31.153425 systemd[1]: Finished systemd-sysusers.service. Mar 17 18:46:31.158000 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' Mar 17 18:46:32.480324 systemd[1]: Finished systemd-hwdb-update.service. Mar 17 18:46:32.485000 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' Mar 17 18:46:32.485000 audit: BPF prog-id=18 op=LOAD Mar 17 18:46:32.486000 audit: BPF prog-id=19 op=LOAD Mar 17 18:46:32.486000 audit: BPF prog-id=7 op=UNLOAD Mar 17 18:46:32.486000 audit: BPF prog-id=8 op=UNLOAD Mar 17 18:46:32.486903 systemd[1]: Starting systemd-udevd.service... Mar 17 18:46:32.504532 systemd-udevd[1198]: Using default interface naming scheme 'v252'. Mar 17 18:46:32.793000 systemd[1]: Started systemd-udevd.service. Mar 17 18:46:32.801000 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' Mar 17 18:46:32.803000 audit: BPF prog-id=20 op=LOAD Mar 17 18:46:32.804384 systemd[1]: Starting systemd-networkd.service... Mar 17 18:46:32.845152 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Mar 17 18:46:32.854426 systemd[1]: Starting systemd-userdbd.service... Mar 17 18:46:32.853000 audit: BPF prog-id=21 op=LOAD Mar 17 18:46:32.853000 audit: BPF prog-id=22 op=LOAD Mar 17 18:46:32.853000 audit: BPF prog-id=23 op=LOAD Mar 17 18:46:32.895201 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 18:46:32.915473 systemd[1]: Started systemd-userdbd.service. Mar 17 18:46:32.920000 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' Mar 17 18:46:32.941000 audit[1202]: AVC avc: denied { confidentiality } for pid=1202 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 Mar 17 18:46:32.949918 kernel: kauditd_printk_skb: 47 callbacks suppressed Mar 17 18:46:32.950026 kernel: audit: type=1400 audit(1742237192.941:145): avc: denied { confidentiality } for pid=1202 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 Mar 17 18:46:32.982196 kernel: hv_vmbus: registering driver hv_balloon Mar 17 18:46:32.989206 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 18:46:32.998981 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 18:46:32.999043 kernel: hv_balloon: Memory hot add disabled on ARM64 Mar 17 18:46:33.014245 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 18:46:33.014366 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 18:46:33.029809 kernel: Console: switching to colour dummy device 80x25 Mar 17 18:46:33.032208 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:46:32.941000 audit[1202]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaae9def390 a1=aa2c a2=ffff86d024b0 a3=aaaae9d50010 items=12 ppid=1198 pid=1202 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) Mar 17 18:46:32.941000 audit: CWD cwd="/" Mar 17 18:46:33.073353 kernel: audit: type=1300 audit(1742237192.941:145): arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaae9def390 a1=aa2c a2=ffff86d024b0 a3=aaaae9d50010 items=12 ppid=1198 pid=1202 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) Mar 17 18:46:33.073459 kernel: audit: type=1307 audit(1742237192.941:145): cwd="/" Mar 17 18:46:32.941000 audit: PATH item=0 name=(null) inode=6649 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 Mar 17 18:46:33.092962 kernel: audit: type=1302 audit(1742237192.941:145): item=0 name=(null) inode=6649 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 Mar 17 18:46:33.101635 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 18:46:33.101727 kernel: hv_vmbus: registering driver hv_utils Mar 17 18:46:32.941000 audit: PATH item=1 name=(null) inode=11361 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 Mar 17 18:46:33.103189 kernel: audit: type=1302 audit(1742237192.941:145): item=1 name=(null) inode=11361 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 Mar 17 18:46:33.126518 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 18:46:33.126620 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 18:46:33.126658 kernel: audit: type=1302 audit(1742237192.941:145): item=2 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=2 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=3 name=(null) inode=11362 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 Mar 17 18:46:33.161145 kernel: audit: type=1302 audit(1742237192.941:145): item=3 name=(null) inode=11362 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 Mar 17 18:46:33.161247 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 18:46:32.941000 audit: PATH item=4 name=(null) inode=11361 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 Mar 17 18:46:33.240615 kernel: audit: type=1302 audit(1742237192.941:145): item=4 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=5 name=(null) inode=11363 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 Mar 17 18:46:33.256853 kernel: audit: type=1302 audit(1742237192.941:145): item=5 name=(null) inode=11363 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 Mar 17 18:46:33.257230 kernel: audit: type=1302 audit(1742237192.941:145): item=6 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=6 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=7 name=(null) inode=11364 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 Mar 17 18:46:32.941000 audit: PATH item=8 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=9 name=(null) inode=11365 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 Mar 17 18:46:32.941000 audit: PATH item=10 name=(null) inode=11361 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 Mar 17 18:46:32.941000 audit: PATH item=11 name=(null) inode=11366 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 Mar 17 18:46:32.941000 audit: PROCTITLE proctitle="(udev-worker)" Mar 17 18:46:33.351215 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:46:33.361325 systemd[1]: Finished systemd-udev-settle.service. Mar 17 18:46:33.365000 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' Mar 17 18:46:33.367789 systemd[1]: Starting lvm2-activation-early.service... Mar 17 18:46:34.056023 lvm[1274]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:46:34.221920 systemd[1]: Finished lvm2-activation-early.service. Mar 17 18:46:34.226000 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' Mar 17 18:46:34.227237 systemd[1]: Reached target cryptsetup.target. Mar 17 18:46:34.228459 systemd-networkd[1219]: lo: Link UP Mar 17 18:46:34.228463 systemd-networkd[1219]: lo: Gained carrier Mar 17 18:46:34.228838 systemd-networkd[1219]: Enumeration completed Mar 17 18:46:34.232929 systemd[1]: Starting lvm2-activation.service... Mar 17 18:46:34.236951 systemd[1]: Started systemd-networkd.service. Mar 17 18:46:34.237531 lvm[1275]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:46:34.240000 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' Mar 17 18:46:34.242662 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:46:34.261323 systemd-networkd[1219]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:46:34.263410 systemd[1]: Finished lvm2-activation.service. Mar 17 18:46:34.267000 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' Mar 17 18:46:34.268142 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:46:34.272959 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 17 18:46:34.272992 systemd[1]: Reached target local-fs.target. Mar 17 18:46:34.277397 systemd[1]: Reached target machines.target. Mar 17 18:46:34.283139 systemd[1]: Starting ldconfig.service... Mar 17 18:46:34.287329 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:46:34.287406 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). Mar 17 18:46:34.288681 systemd[1]: Starting systemd-boot-update.service... Mar 17 18:46:34.294104 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Mar 17 18:46:34.300868 systemd[1]: Starting systemd-machine-id-commit.service... Mar 17 18:46:34.305890 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Mar 17 18:46:34.306014 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Mar 17 18:46:34.307269 systemd[1]: Starting systemd-tmpfiles-setup.service... Mar 17 18:46:34.319390 kernel: mlx5_core 1c45:00:02.0 enP7237s1: Link up Mar 17 18:46:34.344920 kernel: hv_netvsc 0022487a-6a19-0022-487a-6a190022487a eth0: Data path switched to VF: enP7237s1 Mar 17 18:46:34.345577 systemd-networkd[1219]: enP7237s1: Link UP Mar 17 18:46:34.345677 systemd-networkd[1219]: eth0: Link UP Mar 17 18:46:34.345680 systemd-networkd[1219]: eth0: Gained carrier Mar 17 18:46:34.351152 systemd-networkd[1219]: enP7237s1: Gained carrier Mar 17 18:46:34.359016 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1278 (bootctl) Mar 17 18:46:34.360000 systemd-networkd[1219]: eth0: DHCPv4 address 10.200.20.32/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:46:34.360288 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Mar 17 18:46:34.706403 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Mar 17 18:46:34.711000 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' Mar 17 18:46:35.767035 systemd-networkd[1219]: eth0: Gained IPv6LL Mar 17 18:46:35.769837 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:46:35.774000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:46:35.822929 systemd-fsck[1286]: fsck.fat 4.2 (2021-01-31) Mar 17 18:46:35.822929 systemd-fsck[1286]: /dev/sda1: 236 files, 117179/258078 clusters Mar 17 18:46:35.825399 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Mar 17 18:46:35.830000 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' Mar 17 18:46:35.833108 systemd[1]: Mounting boot.mount... Mar 17 18:46:35.947012 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Mar 17 18:46:36.032754 systemd[1]: Mounted boot.mount. Mar 17 18:46:36.042500 systemd[1]: Finished systemd-boot-update.service. Mar 17 18:46:36.046000 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' Mar 17 18:46:37.096608 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 18:46:37.602062 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 18:46:40.539329 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 18:46:40.540629 systemd[1]: Finished systemd-machine-id-commit.service. Mar 17 18:46:40.545000 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' Mar 17 18:46:40.551479 kernel: kauditd_printk_skb: 14 callbacks suppressed Mar 17 18:46:40.551588 kernel: audit: type=1130 audit(1742237200.545:154): 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' Mar 17 18:46:40.970841 systemd[1]: Finished systemd-tmpfiles-setup.service. Mar 17 18:46:40.975000 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' Mar 17 18:46:40.977461 systemd[1]: Starting audit-rules.service... Mar 17 18:46:40.995920 kernel: audit: type=1130 audit(1742237200.975:155): 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' Mar 17 18:46:41.000205 systemd[1]: Starting clean-ca-certificates.service... Mar 17 18:46:41.006524 systemd[1]: Starting systemd-journal-catalog-update.service... Mar 17 18:46:41.011000 audit: BPF prog-id=24 op=LOAD Mar 17 18:46:41.013579 systemd[1]: Starting systemd-resolved.service... Mar 17 18:46:41.022921 kernel: audit: type=1334 audit(1742237201.011:156): prog-id=24 op=LOAD Mar 17 18:46:41.023000 audit: BPF prog-id=25 op=LOAD Mar 17 18:46:41.031060 systemd[1]: Starting systemd-timesyncd.service... Mar 17 18:46:41.032915 kernel: audit: type=1334 audit(1742237201.023:157): prog-id=25 op=LOAD Mar 17 18:46:41.036745 systemd[1]: Starting systemd-update-utmp.service... Mar 17 18:46:41.399000 audit[1298]: SYSTEM_BOOT pid=1298 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' Mar 17 18:46:41.420063 kernel: audit: type=1127 audit(1742237201.399:158): pid=1298 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' Mar 17 18:46:41.420390 systemd[1]: Finished systemd-update-utmp.service. Mar 17 18:46:41.424000 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' Mar 17 18:46:41.443919 kernel: audit: type=1130 audit(1742237201.424:159): 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' Mar 17 18:46:41.445980 systemd[1]: Finished clean-ca-certificates.service. Mar 17 18:46:41.451385 systemd[1]: Started systemd-timesyncd.service. Mar 17 18:46:41.450000 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' Mar 17 18:46:41.472365 systemd[1]: Reached target time-set.target. Mar 17 18:46:41.450000 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' Mar 17 18:46:41.494965 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:46:41.496393 kernel: audit: type=1130 audit(1742237201.450:160): 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' Mar 17 18:46:41.496477 kernel: audit: type=1130 audit(1742237201.450:161): 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' Mar 17 18:46:41.854610 systemd-resolved[1296]: Positive Trust Anchors: Mar 17 18:46:41.854623 systemd-resolved[1296]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:46:41.854651 systemd-resolved[1296]: 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 Mar 17 18:46:42.333154 systemd-timesyncd[1297]: Contacted time server 23.131.160.7:123 (0.flatcar.pool.ntp.org). Mar 17 18:46:42.333215 systemd-timesyncd[1297]: Initial clock synchronization to Mon 2025-03-17 18:46:42.333875 UTC. Mar 17 18:46:42.343489 systemd-resolved[1296]: Using system hostname 'ci-3510.3.7-a-fbbca1c12b'. Mar 17 18:46:42.345236 systemd[1]: Started systemd-resolved.service. Mar 17 18:46:42.349000 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' Mar 17 18:46:42.350655 systemd[1]: Reached target network.target. Mar 17 18:46:42.370924 kernel: audit: type=1130 audit(1742237202.349:162): 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' Mar 17 18:46:42.376036 systemd[1]: Reached target network-online.target. Mar 17 18:46:42.381627 systemd[1]: Reached target nss-lookup.target. Mar 17 18:46:42.864057 systemd[1]: Finished systemd-journal-catalog-update.service. Mar 17 18:46:42.868000 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' Mar 17 18:46:42.889918 kernel: audit: type=1130 audit(1742237202.868:163): 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' Mar 17 18:46:43.272000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Mar 17 18:46:43.272000 audit[1313]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff5e401b0 a2=420 a3=0 items=0 ppid=1292 pid=1313 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) Mar 17 18:46:43.272000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Mar 17 18:46:43.304242 augenrules[1313]: No rules Mar 17 18:46:43.305263 systemd[1]: Finished audit-rules.service. Mar 17 18:47:02.699475 ldconfig[1277]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 18:47:02.853592 systemd[1]: Finished ldconfig.service. Mar 17 18:47:02.859626 systemd[1]: Starting systemd-update-done.service... Mar 17 18:47:02.880445 systemd[1]: Finished systemd-update-done.service. Mar 17 18:47:02.885300 systemd[1]: Reached target sysinit.target. Mar 17 18:47:02.889605 systemd[1]: Started motdgen.path. Mar 17 18:47:02.893302 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Mar 17 18:47:02.899459 systemd[1]: Started logrotate.timer. Mar 17 18:47:02.903652 systemd[1]: Started mdadm.timer. Mar 17 18:47:02.907205 systemd[1]: Started systemd-tmpfiles-clean.timer. Mar 17 18:47:02.911715 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 18:47:02.911748 systemd[1]: Reached target paths.target. Mar 17 18:47:02.915800 systemd[1]: Reached target timers.target. Mar 17 18:47:02.920814 systemd[1]: Listening on dbus.socket. Mar 17 18:47:02.925845 systemd[1]: Starting docker.socket... Mar 17 18:47:02.944707 systemd[1]: Listening on sshd.socket. Mar 17 18:47:02.948926 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). Mar 17 18:47:02.949466 systemd[1]: Listening on docker.socket. Mar 17 18:47:02.953849 systemd[1]: Reached target sockets.target. Mar 17 18:47:02.958600 systemd[1]: Reached target basic.target. Mar 17 18:47:02.963011 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:47:02.963043 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:47:02.964241 systemd[1]: Starting containerd.service... Mar 17 18:47:02.969233 systemd[1]: Starting dbus.service... Mar 17 18:47:02.973555 systemd[1]: Starting enable-oem-cloudinit.service... Mar 17 18:47:02.979158 systemd[1]: Starting extend-filesystems.service... Mar 17 18:47:02.986384 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Mar 17 18:47:02.987765 systemd[1]: Starting motdgen.service... Mar 17 18:47:02.992498 systemd[1]: Started nvidia.service. Mar 17 18:47:02.998799 systemd[1]: Starting ssh-key-proc-cmdline.service... Mar 17 18:47:03.004577 systemd[1]: Starting sshd-keygen.service... Mar 17 18:47:03.010855 systemd[1]: Starting systemd-logind.service... Mar 17 18:47:03.015111 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:47:03.015197 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 18:47:03.015690 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 18:47:03.017257 systemd[1]: Starting update-engine.service... Mar 17 18:47:03.022855 systemd[1]: Starting update-ssh-keys-after-ignition.service... Mar 17 18:47:03.030927 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 18:47:03.031112 systemd[1]: Finished ssh-key-proc-cmdline.service. Mar 17 18:47:03.407508 systemd-logind[1332]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Mar 17 18:47:03.407689 systemd-logind[1332]: New seat seat0. Mar 17 18:47:03.408533 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 18:47:03.408706 systemd[1]: Finished motdgen.service. Mar 17 18:47:03.813697 env[1342]: time="2025-03-17T18:47:03.813647905Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Mar 17 18:47:03.829112 env[1342]: time="2025-03-17T18:47:03.829063923Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 18:47:03.829243 env[1342]: time="2025-03-17T18:47:03.829232325Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830324 env[1342]: time="2025-03-17T18:47:03.830284620Z" 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.179-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830324 env[1342]: time="2025-03-17T18:47:03.830317301Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830560 env[1342]: time="2025-03-17T18:47:03.830531344Z" 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 Mar 17 18:47:03.830592 env[1342]: time="2025-03-17T18:47:03.830557704Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830592 env[1342]: time="2025-03-17T18:47:03.830571624Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Mar 17 18:47:03.830592 env[1342]: time="2025-03-17T18:47:03.830581304Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830668 env[1342]: time="2025-03-17T18:47:03.830649505Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.830911 env[1342]: time="2025-03-17T18:47:03.830875269Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:47:03.831053 env[1342]: time="2025-03-17T18:47:03.831028591Z" 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 Mar 17 18:47:03.831079 env[1342]: time="2025-03-17T18:47:03.831051191Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 18:47:03.831123 env[1342]: time="2025-03-17T18:47:03.831104832Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Mar 17 18:47:03.831157 env[1342]: time="2025-03-17T18:47:03.831121392Z" level=info msg="metadata content store policy set" policy=shared Mar 17 18:47:03.895507 jq[1323]: false Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda1 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda2 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda3 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found usr Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda4 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda6 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda7 Mar 17 18:47:04.290335 extend-filesystems[1324]: Found sda9 Mar 17 18:47:04.290335 extend-filesystems[1324]: Checking size of /dev/sda9 Mar 17 18:47:04.053354 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 18:47:04.384070 jq[1337]: true Mar 17 18:47:04.053514 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Mar 17 18:47:04.398042 jq[1374]: true Mar 17 18:47:04.885216 extend-filesystems[1324]: Old size kept for /dev/sda9 Mar 17 18:47:04.895567 extend-filesystems[1324]: Found sr0 Mar 17 18:47:04.890058 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 18:47:04.890216 systemd[1]: Finished extend-filesystems.service. Mar 17 18:47:05.129176 systemd[1]: nvidia.service: Deactivated successfully. Mar 17 18:47:05.144645 env[1342]: time="2025-03-17T18:47:05.144560085Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 18:47:05.144645 env[1342]: time="2025-03-17T18:47:05.144612085Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 18:47:05.144645 env[1342]: time="2025-03-17T18:47:05.144626765Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 18:47:05.144996 env[1342]: time="2025-03-17T18:47:05.144662726Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.144996 env[1342]: time="2025-03-17T18:47:05.144678486Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.144996 env[1342]: time="2025-03-17T18:47:05.144692726Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.144996 env[1342]: time="2025-03-17T18:47:05.144706046Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.145126 env[1342]: time="2025-03-17T18:47:05.145090811Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.145126 env[1342]: time="2025-03-17T18:47:05.145109171Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.145126 env[1342]: time="2025-03-17T18:47:05.145121812Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.145182 env[1342]: time="2025-03-17T18:47:05.145135452Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.145182 env[1342]: time="2025-03-17T18:47:05.145148812Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145318374Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145410935Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145638818Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145662058Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145675298Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145719339Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145731579Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145744379Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145755299Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145770460Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145782660Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145794820Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145806100Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145819540Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 18:47:05.149902 env[1342]: time="2025-03-17T18:47:05.145972782Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.147356 systemd[1]: Started containerd.service. Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.145992742Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146005143Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146017823Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146032063Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146044183Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146061183Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Mar 17 18:47:05.150506 env[1342]: time="2025-03-17T18:47:05.146094104Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.146281026Z" 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}" Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.146332987Z" level=info msg="Connect containerd service" Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.146367627Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.146970795Z" 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" Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.147213958Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 18:47:05.150677 env[1342]: time="2025-03-17T18:47:05.147248438Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.150924364Z" level=info msg="Start subscribing containerd event" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.150982044Z" level=info msg="Start recovering state" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.151049445Z" level=info msg="Start event monitor" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.151067846Z" level=info msg="Start snapshots syncer" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.151078766Z" level=info msg="Start cni network conf syncer for default" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.151086726Z" level=info msg="Start streaming server" Mar 17 18:47:05.358850 env[1342]: time="2025-03-17T18:47:05.151704413Z" level=info msg="containerd successfully booted in 1.339139s" Mar 17 18:47:05.404321 dbus-daemon[1322]: [system] SELinux support is enabled Mar 17 18:47:05.404481 systemd[1]: Started dbus.service. Mar 17 18:47:05.410391 dbus-daemon[1322]: [system] Successfully activated service 'org.freedesktop.systemd1' Mar 17 18:47:05.409885 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). Mar 17 18:47:05.409933 systemd[1]: Reached target system-config.target. Mar 17 18:47:05.414992 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 17 18:47:05.415005 systemd[1]: Reached target user-config.target. Mar 17 18:47:05.419702 systemd[1]: Started systemd-logind.service. Mar 17 18:47:05.785490 bash[1389]: Updated "/home/core/.ssh/authorized_keys" Mar 17 18:47:05.786250 systemd[1]: Finished update-ssh-keys-after-ignition.service. Mar 17 18:47:05.797679 sshd_keygen[1336]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 18:47:05.813782 systemd[1]: Finished sshd-keygen.service. Mar 17 18:47:05.819526 systemd[1]: Starting issuegen.service... Mar 17 18:47:05.824046 systemd[1]: Started waagent.service. Mar 17 18:47:05.828509 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 18:47:05.828658 systemd[1]: Finished issuegen.service. Mar 17 18:47:05.834155 systemd[1]: Starting systemd-user-sessions.service... Mar 17 18:47:06.007405 systemd[1]: Finished systemd-user-sessions.service. Mar 17 18:47:06.013691 systemd[1]: Started getty@tty1.service. Mar 17 18:47:06.018869 systemd[1]: Started serial-getty@ttyAMA0.service. Mar 17 18:47:06.023869 systemd[1]: Reached target getty.target. Mar 17 18:47:07.112987 update_engine[1335]: I0317 18:47:07.088922 1335 main.cc:92] Flatcar Update Engine starting Mar 17 18:47:07.446242 systemd[1]: Started update-engine.service. Mar 17 18:47:07.446674 update_engine[1335]: I0317 18:47:07.446271 1335 update_check_scheduler.cc:74] Next update check in 4m41s Mar 17 18:47:07.452764 systemd[1]: Started locksmithd.service. Mar 17 18:47:07.457460 systemd[1]: Reached target multi-user.target. Mar 17 18:47:07.463806 systemd[1]: Starting systemd-update-utmp-runlevel.service... Mar 17 18:47:07.473071 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 17 18:47:07.473241 systemd[1]: Finished systemd-update-utmp-runlevel.service. Mar 17 18:47:07.478870 systemd[1]: Startup finished in 743ms (kernel) + 13.880s (initrd) + 1min 894ms (userspace) = 1min 15.518s. Mar 17 18:47:10.155466 login[1437]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Mar 17 18:47:10.156453 login[1436]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:47:10.413631 systemd[1]: Created slice user-500.slice. Mar 17 18:47:10.414807 systemd[1]: Starting user-runtime-dir@500.service... Mar 17 18:47:10.417193 systemd-logind[1332]: New session 2 of user core. Mar 17 18:47:10.570447 systemd[1]: Finished user-runtime-dir@500.service. Mar 17 18:47:10.572031 systemd[1]: Starting user@500.service... Mar 17 18:47:10.595466 (systemd)[1444]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:11.156892 login[1437]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:47:11.160944 systemd-logind[1332]: New session 1 of user core. Mar 17 18:47:11.293094 systemd[1444]: Queued start job for default target default.target. Mar 17 18:47:11.293592 systemd[1444]: Reached target paths.target. Mar 17 18:47:11.293612 systemd[1444]: Reached target sockets.target. Mar 17 18:47:11.293624 systemd[1444]: Reached target timers.target. Mar 17 18:47:11.293634 systemd[1444]: Reached target basic.target. Mar 17 18:47:11.293682 systemd[1444]: Reached target default.target. Mar 17 18:47:11.293705 systemd[1444]: Startup finished in 691ms. Mar 17 18:47:11.293744 systemd[1]: Started user@500.service. Mar 17 18:47:11.294620 systemd[1]: Started session-1.scope. Mar 17 18:47:11.295128 systemd[1]: Started session-2.scope. Mar 17 18:47:13.734370 locksmithd[1438]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 18:47:17.809427 waagent[1433]: 2025-03-17T18:47:17.809304Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Mar 17 18:47:17.845823 waagent[1433]: 2025-03-17T18:47:17.845706Z INFO Daemon Daemon OS: flatcar 3510.3.7 Mar 17 18:47:17.851714 waagent[1433]: 2025-03-17T18:47:17.851605Z INFO Daemon Daemon Python: 3.9.16 Mar 17 18:47:17.856741 waagent[1433]: 2025-03-17T18:47:17.856643Z INFO Daemon Daemon Run daemon Mar 17 18:47:17.861480 waagent[1433]: 2025-03-17T18:47:17.861398Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.7' Mar 17 18:47:17.879349 waagent[1433]: 2025-03-17T18:47:17.879182Z 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. Mar 17 18:47:17.897025 waagent[1433]: 2025-03-17T18:47:17.896843Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:47:17.908145 waagent[1433]: 2025-03-17T18:47:17.908046Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:47:17.914245 waagent[1433]: 2025-03-17T18:47:17.914136Z INFO Daemon Daemon Using waagent for provisioning Mar 17 18:47:17.921229 waagent[1433]: 2025-03-17T18:47:17.921148Z INFO Daemon Daemon Activate resource disk Mar 17 18:47:17.926722 waagent[1433]: 2025-03-17T18:47:17.926632Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 18:47:17.943349 waagent[1433]: 2025-03-17T18:47:17.943251Z INFO Daemon Daemon Found device: None Mar 17 18:47:17.948424 waagent[1433]: 2025-03-17T18:47:17.948329Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Mar 17 18:47:17.957507 waagent[1433]: 2025-03-17T18:47:17.957412Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Mar 17 18:47:17.970613 waagent[1433]: 2025-03-17T18:47:17.970533Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:47:17.977491 waagent[1433]: 2025-03-17T18:47:17.977388Z INFO Daemon Daemon Running default provisioning handler Mar 17 18:47:17.991922 waagent[1433]: 2025-03-17T18:47:17.991737Z 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. Mar 17 18:47:18.009623 waagent[1433]: 2025-03-17T18:47:18.009462Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:47:18.020624 waagent[1433]: 2025-03-17T18:47:18.020527Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:47:18.026474 waagent[1433]: 2025-03-17T18:47:18.026374Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 18:47:18.143358 waagent[1433]: 2025-03-17T18:47:18.143161Z INFO Daemon Daemon Successfully mounted dvd Mar 17 18:47:18.294742 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 18:47:18.345308 waagent[1433]: 2025-03-17T18:47:18.345133Z INFO Daemon Daemon Detect protocol endpoint Mar 17 18:47:18.351259 waagent[1433]: 2025-03-17T18:47:18.351172Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:47:18.357914 waagent[1433]: 2025-03-17T18:47:18.357823Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 18:47:18.365350 waagent[1433]: 2025-03-17T18:47:18.365271Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 18:47:18.371671 waagent[1433]: 2025-03-17T18:47:18.371598Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 18:47:18.377437 waagent[1433]: 2025-03-17T18:47:18.377363Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 18:47:18.497054 waagent[1433]: 2025-03-17T18:47:18.496980Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 18:47:18.504537 waagent[1433]: 2025-03-17T18:47:18.504490Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 18:47:18.510698 waagent[1433]: 2025-03-17T18:47:18.510625Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 18:47:18.984178 waagent[1433]: 2025-03-17T18:47:18.984018Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 18:47:19.000303 waagent[1433]: 2025-03-17T18:47:19.000219Z INFO Daemon Daemon Forcing an update of the goal state.. Mar 17 18:47:19.006222 waagent[1433]: 2025-03-17T18:47:19.006146Z INFO Daemon Daemon Fetching goal state [incarnation 1] Mar 17 18:47:19.175540 waagent[1433]: 2025-03-17T18:47:19.175381Z INFO Daemon Daemon Found private key matching thumbprint 5CA2A76976FA9E7295A741CCD7F1F59F240B5C99 Mar 17 18:47:19.184367 waagent[1433]: 2025-03-17T18:47:19.184283Z INFO Daemon Daemon Certificate with thumbprint 4FB63F781910BA73D5290DD5B53848D7B9E437E7 has no matching private key. Mar 17 18:47:19.195129 waagent[1433]: 2025-03-17T18:47:19.195044Z INFO Daemon Daemon Fetch goal state completed Mar 17 18:47:19.265634 waagent[1433]: 2025-03-17T18:47:19.265533Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 7724d344-09bc-412a-8136-5162bf4d2912 New eTag: 3460177893813093262] Mar 17 18:47:19.276651 waagent[1433]: 2025-03-17T18:47:19.276565Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:47:19.293323 waagent[1433]: 2025-03-17T18:47:19.293239Z INFO Daemon Daemon Starting provisioning Mar 17 18:47:19.298756 waagent[1433]: 2025-03-17T18:47:19.298675Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 18:47:19.303677 waagent[1433]: 2025-03-17T18:47:19.303610Z INFO Daemon Daemon Set hostname [ci-3510.3.7-a-fbbca1c12b] Mar 17 18:47:19.342029 waagent[1433]: 2025-03-17T18:47:19.341869Z INFO Daemon Daemon Publish hostname [ci-3510.3.7-a-fbbca1c12b] Mar 17 18:47:19.348842 waagent[1433]: 2025-03-17T18:47:19.348755Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 18:47:19.355515 waagent[1433]: 2025-03-17T18:47:19.355442Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 18:47:19.371661 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Mar 17 18:47:19.371836 systemd[1]: Stopped systemd-networkd-wait-online.service. Mar 17 18:47:19.371913 systemd[1]: Stopping systemd-networkd-wait-online.service... Mar 17 18:47:19.372158 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:47:19.375948 systemd-networkd[1219]: eth0: DHCPv6 lease lost Mar 17 18:47:19.378272 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:47:19.378448 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:47:19.380420 systemd[1]: Starting systemd-networkd.service... Mar 17 18:47:19.407733 systemd-networkd[1484]: enP7237s1: Link UP Mar 17 18:47:19.407744 systemd-networkd[1484]: enP7237s1: Gained carrier Mar 17 18:47:19.408607 systemd-networkd[1484]: eth0: Link UP Mar 17 18:47:19.408618 systemd-networkd[1484]: eth0: Gained carrier Mar 17 18:47:19.409000 systemd-networkd[1484]: lo: Link UP Mar 17 18:47:19.409009 systemd-networkd[1484]: lo: Gained carrier Mar 17 18:47:19.409239 systemd-networkd[1484]: eth0: Gained IPv6LL Mar 17 18:47:19.409439 systemd-networkd[1484]: Enumeration completed Mar 17 18:47:19.409659 systemd[1]: Started systemd-networkd.service. Mar 17 18:47:19.410134 systemd-networkd[1484]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:47:19.411477 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:47:19.415621 waagent[1433]: 2025-03-17T18:47:19.415428Z INFO Daemon Daemon Create user account if not exists Mar 17 18:47:19.422617 waagent[1433]: 2025-03-17T18:47:19.422530Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 18:47:19.429050 waagent[1433]: 2025-03-17T18:47:19.428959Z INFO Daemon Daemon Configure sudoer Mar 17 18:47:19.433974 systemd-networkd[1484]: eth0: DHCPv4 address 10.200.20.32/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:47:19.434325 waagent[1433]: 2025-03-17T18:47:19.434249Z INFO Daemon Daemon Configure sshd Mar 17 18:47:19.439984 waagent[1433]: 2025-03-17T18:47:19.439903Z INFO Daemon Daemon Deploy ssh public key. Mar 17 18:47:19.446807 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:47:20.656956 waagent[1433]: 2025-03-17T18:47:20.656861Z INFO Daemon Daemon Provisioning complete Mar 17 18:47:20.675857 waagent[1433]: 2025-03-17T18:47:20.675793Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 18:47:20.682369 waagent[1433]: 2025-03-17T18:47:20.682286Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 18:47:20.694900 waagent[1433]: 2025-03-17T18:47:20.694811Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Mar 17 18:47:21.001759 waagent[1493]: 2025-03-17T18:47:21.001664Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Mar 17 18:47:21.002870 waagent[1493]: 2025-03-17T18:47:21.002815Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:21.003146 waagent[1493]: 2025-03-17T18:47:21.003094Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:21.015734 waagent[1493]: 2025-03-17T18:47:21.015652Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Mar 17 18:47:21.016092 waagent[1493]: 2025-03-17T18:47:21.016040Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Mar 17 18:47:21.087363 waagent[1493]: 2025-03-17T18:47:21.087219Z INFO ExtHandler ExtHandler Found private key matching thumbprint 5CA2A76976FA9E7295A741CCD7F1F59F240B5C99 Mar 17 18:47:21.087749 waagent[1493]: 2025-03-17T18:47:21.087695Z INFO ExtHandler ExtHandler Certificate with thumbprint 4FB63F781910BA73D5290DD5B53848D7B9E437E7 has no matching private key. Mar 17 18:47:21.088129 waagent[1493]: 2025-03-17T18:47:21.088078Z INFO ExtHandler ExtHandler Fetch goal state completed Mar 17 18:47:21.102855 waagent[1493]: 2025-03-17T18:47:21.102798Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: b8ad41d5-2d65-484f-a8bf-57e3f799dfc3 New eTag: 3460177893813093262] Mar 17 18:47:21.103609 waagent[1493]: 2025-03-17T18:47:21.103550Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:47:21.156751 waagent[1493]: 2025-03-17T18:47:21.156605Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:47:21.181095 waagent[1493]: 2025-03-17T18:47:21.181002Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1493 Mar 17 18:47:21.184851 waagent[1493]: 2025-03-17T18:47:21.184783Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:47:21.186236 waagent[1493]: 2025-03-17T18:47:21.186177Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:47:21.198929 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Mar 17 18:47:21.321208 waagent[1493]: 2025-03-17T18:47:21.321053Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:47:21.321708 waagent[1493]: 2025-03-17T18:47:21.321610Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:47:21.331725 waagent[1493]: 2025-03-17T18:47:21.331634Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:47:21.335076 waagent[1493]: 2025-03-17T18:47:21.334970Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:47:21.336702 waagent[1493]: 2025-03-17T18:47:21.336598Z 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] Mar 17 18:47:21.338622 waagent[1493]: 2025-03-17T18:47:21.338510Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:47:21.339506 waagent[1493]: 2025-03-17T18:47:21.339408Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:21.339848 waagent[1493]: 2025-03-17T18:47:21.339795Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:21.340785 waagent[1493]: 2025-03-17T18:47:21.340673Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:47:21.341763 waagent[1493]: 2025-03-17T18:47:21.341658Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:47:21.341938 waagent[1493]: 2025-03-17T18:47:21.341819Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:21.342950 waagent[1493]: 2025-03-17T18:47:21.342803Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:21.343706 waagent[1493]: 2025-03-17T18:47:21.343502Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:47:21.343780 waagent[1493]: 2025-03-17T18:47:21.343715Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:47:21.344025 waagent[1493]: 2025-03-17T18:47:21.343919Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:47:21.344025 waagent[1493]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:47:21.344025 waagent[1493]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:47:21.344025 waagent[1493]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:47:21.344025 waagent[1493]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:21.344025 waagent[1493]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:21.344025 waagent[1493]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:21.345071 waagent[1493]: 2025-03-17T18:47:21.344881Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:47:21.345566 waagent[1493]: 2025-03-17T18:47:21.345464Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:47:21.346224 waagent[1493]: 2025-03-17T18:47:21.346138Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:47:21.349274 waagent[1493]: 2025-03-17T18:47:21.348987Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:47:21.350233 waagent[1493]: 2025-03-17T18:47:21.350139Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:47:21.352066 waagent[1493]: 2025-03-17T18:47:21.351966Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:47:21.368247 waagent[1493]: 2025-03-17T18:47:21.368172Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Mar 17 18:47:21.369147 waagent[1493]: 2025-03-17T18:47:21.369067Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:47:21.370390 waagent[1493]: 2025-03-17T18:47:21.370320Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Mar 17 18:47:21.415197 waagent[1493]: 2025-03-17T18:47:21.415133Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Mar 17 18:47:21.481402 waagent[1493]: 2025-03-17T18:47:21.481261Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1484' Mar 17 18:47:21.578171 waagent[1493]: 2025-03-17T18:47:21.577989Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:47:21.578171 waagent[1493]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:47:21.578171 waagent[1493]: 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 Mar 17 18:47:21.578171 waagent[1493]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:6a:19 brd ff:ff:ff:ff:ff:ff Mar 17 18:47:21.578171 waagent[1493]: 3: enP7237s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:6a:19 brd ff:ff:ff:ff:ff:ff\ altname enP7237p0s2 Mar 17 18:47:21.578171 waagent[1493]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:47:21.578171 waagent[1493]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:47:21.578171 waagent[1493]: 2: eth0 inet 10.200.20.32/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:47:21.578171 waagent[1493]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:47:21.578171 waagent[1493]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:47:21.578171 waagent[1493]: 2: eth0 inet6 fe80::222:48ff:fe7a:6a19/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:47:21.695911 waagent[1493]: 2025-03-17T18:47:21.695845Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.12.0.2 -- exiting Mar 17 18:47:22.699342 waagent[1433]: 2025-03-17T18:47:22.699185Z 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 Mar 17 18:47:22.703982 waagent[1433]: 2025-03-17T18:47:22.703925Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.12.0.2 to be the latest agent Mar 17 18:47:24.002290 waagent[1524]: 2025-03-17T18:47:24.002192Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.2) Mar 17 18:47:24.003373 waagent[1524]: 2025-03-17T18:47:24.003315Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.7 Mar 17 18:47:24.003601 waagent[1524]: 2025-03-17T18:47:24.003555Z INFO ExtHandler ExtHandler Python: 3.9.16 Mar 17 18:47:24.003806 waagent[1524]: 2025-03-17T18:47:24.003762Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Mar 17 18:47:24.011981 waagent[1524]: 2025-03-17T18:47:24.011852Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:47:24.012495 waagent[1524]: 2025-03-17T18:47:24.012442Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:24.012731 waagent[1524]: 2025-03-17T18:47:24.012685Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:24.026151 waagent[1524]: 2025-03-17T18:47:24.026075Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 18:47:24.039761 waagent[1524]: 2025-03-17T18:47:24.039696Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 17 18:47:24.041097 waagent[1524]: 2025-03-17T18:47:24.041038Z INFO ExtHandler Mar 17 18:47:24.041365 waagent[1524]: 2025-03-17T18:47:24.041317Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 83b54c2b-d465-47ad-a30d-9f6f57f6f2f4 eTag: 3460177893813093262 source: Fabric] Mar 17 18:47:24.042246 waagent[1524]: 2025-03-17T18:47:24.042191Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 18:47:24.043625 waagent[1524]: 2025-03-17T18:47:24.043567Z INFO ExtHandler Mar 17 18:47:24.043846 waagent[1524]: 2025-03-17T18:47:24.043800Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 18:47:24.051864 waagent[1524]: 2025-03-17T18:47:24.051806Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 18:47:24.052636 waagent[1524]: 2025-03-17T18:47:24.052585Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:47:24.078221 waagent[1524]: 2025-03-17T18:47:24.078156Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Mar 17 18:47:24.160445 waagent[1524]: 2025-03-17T18:47:24.160296Z INFO ExtHandler Downloaded certificate {'thumbprint': '5CA2A76976FA9E7295A741CCD7F1F59F240B5C99', 'hasPrivateKey': True} Mar 17 18:47:24.161789 waagent[1524]: 2025-03-17T18:47:24.161731Z INFO ExtHandler Downloaded certificate {'thumbprint': '4FB63F781910BA73D5290DD5B53848D7B9E437E7', 'hasPrivateKey': False} Mar 17 18:47:24.163005 waagent[1524]: 2025-03-17T18:47:24.162946Z INFO ExtHandler Fetch goal state completed Mar 17 18:47:24.181763 waagent[1524]: 2025-03-17T18:47:24.181637Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.15 3 Sep 2024 (Library: OpenSSL 3.0.15 3 Sep 2024) Mar 17 18:47:24.194913 waagent[1524]: 2025-03-17T18:47:24.194786Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.2 running as process 1524 Mar 17 18:47:24.198485 waagent[1524]: 2025-03-17T18:47:24.198399Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:47:24.199761 waagent[1524]: 2025-03-17T18:47:24.199705Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 17 18:47:24.200197 waagent[1524]: 2025-03-17T18:47:24.200144Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 17 18:47:24.202470 waagent[1524]: 2025-03-17T18:47:24.202413Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:47:24.207886 waagent[1524]: 2025-03-17T18:47:24.207831Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:47:24.208469 waagent[1524]: 2025-03-17T18:47:24.208412Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:47:24.216405 waagent[1524]: 2025-03-17T18:47:24.216347Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:47:24.217117 waagent[1524]: 2025-03-17T18:47:24.217061Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:47:24.223551 waagent[1524]: 2025-03-17T18:47:24.223448Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 18:47:24.224782 waagent[1524]: 2025-03-17T18:47:24.224718Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Mar 17 18:47:24.226498 waagent[1524]: 2025-03-17T18:47:24.226426Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:47:24.226785 waagent[1524]: 2025-03-17T18:47:24.226711Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:24.227351 waagent[1524]: 2025-03-17T18:47:24.227283Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:24.228025 waagent[1524]: 2025-03-17T18:47:24.227947Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:47:24.228493 waagent[1524]: 2025-03-17T18:47:24.228429Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:47:24.229163 waagent[1524]: 2025-03-17T18:47:24.229090Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:47:24.229238 waagent[1524]: 2025-03-17T18:47:24.229174Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:47:24.229238 waagent[1524]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:47:24.229238 waagent[1524]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:47:24.229238 waagent[1524]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:47:24.229238 waagent[1524]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:24.229238 waagent[1524]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:24.229238 waagent[1524]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:47:24.231373 waagent[1524]: 2025-03-17T18:47:24.231210Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:47:24.232282 waagent[1524]: 2025-03-17T18:47:24.232198Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:47:24.232600 waagent[1524]: 2025-03-17T18:47:24.232534Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:47:24.233235 waagent[1524]: 2025-03-17T18:47:24.233166Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:47:24.234254 waagent[1524]: 2025-03-17T18:47:24.234166Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:47:24.234621 waagent[1524]: 2025-03-17T18:47:24.234549Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:47:24.234925 waagent[1524]: 2025-03-17T18:47:24.234833Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:47:24.235044 waagent[1524]: 2025-03-17T18:47:24.234969Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:47:24.237652 waagent[1524]: 2025-03-17T18:47:24.237578Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:47:24.256314 waagent[1524]: 2025-03-17T18:47:24.256127Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:47:24.256314 waagent[1524]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:47:24.256314 waagent[1524]: 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 Mar 17 18:47:24.256314 waagent[1524]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:6a:19 brd ff:ff:ff:ff:ff:ff Mar 17 18:47:24.256314 waagent[1524]: 3: enP7237s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:6a:19 brd ff:ff:ff:ff:ff:ff\ altname enP7237p0s2 Mar 17 18:47:24.256314 waagent[1524]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:47:24.256314 waagent[1524]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:47:24.256314 waagent[1524]: 2: eth0 inet 10.200.20.32/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:47:24.256314 waagent[1524]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:47:24.256314 waagent[1524]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:47:24.256314 waagent[1524]: 2: eth0 inet6 fe80::222:48ff:fe7a:6a19/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:47:24.258577 waagent[1524]: 2025-03-17T18:47:24.258498Z INFO ExtHandler ExtHandler Downloading agent manifest Mar 17 18:47:24.317133 waagent[1524]: 2025-03-17T18:47:24.317050Z INFO ExtHandler ExtHandler Mar 17 18:47:24.317476 waagent[1524]: 2025-03-17T18:47:24.317420Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: eedc07fd-edb5-410e-a3d6-a3f9ef05aea1 correlation 83132d8d-28c6-4c42-bce1-387eb59460ed created: 2025-03-17T18:45:09.752922Z] Mar 17 18:47:24.318529 waagent[1524]: 2025-03-17T18:47:24.318471Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 18:47:24.320563 waagent[1524]: 2025-03-17T18:47:24.320509Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 17 18:47:24.345849 waagent[1524]: 2025-03-17T18:47:24.345777Z INFO ExtHandler ExtHandler Looking for existing remote access users. Mar 17 18:47:24.366375 waagent[1524]: 2025-03-17T18:47:24.366290Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.2 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 18EB6047-76C7-48D7-8DF7-B11ECF5DE9EE;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1;UpdateMode: SelfUpdate;] Mar 17 18:47:24.531331 waagent[1524]: 2025-03-17T18:47:24.531128Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 17 18:47:24.531331 waagent[1524]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.531331 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.531331 waagent[1524]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.531331 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.531331 waagent[1524]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.531331 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.531331 waagent[1524]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:47:24.531331 waagent[1524]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:47:24.531331 waagent[1524]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:47:24.539292 waagent[1524]: 2025-03-17T18:47:24.539151Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 18:47:24.539292 waagent[1524]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.539292 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.539292 waagent[1524]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.539292 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.539292 waagent[1524]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:47:24.539292 waagent[1524]: pkts bytes target prot opt in out source destination Mar 17 18:47:24.539292 waagent[1524]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:47:24.539292 waagent[1524]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:47:24.539292 waagent[1524]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:47:24.539837 waagent[1524]: 2025-03-17T18:47:24.539779Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 18:47:47.126502 systemd[1]: Created slice system-sshd.slice. Mar 17 18:47:47.128188 systemd[1]: Started sshd@0-10.200.20.32:22-10.200.16.10:38648.service. Mar 17 18:47:47.739009 sshd[1579]: Accepted publickey for core from 10.200.16.10 port 38648 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:47.754386 sshd[1579]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:47.758818 systemd[1]: Started session-3.scope. Mar 17 18:47:47.759123 systemd-logind[1332]: New session 3 of user core. Mar 17 18:47:48.129038 systemd[1]: Started sshd@1-10.200.20.32:22-10.200.16.10:38658.service. Mar 17 18:47:48.559663 sshd[1584]: Accepted publickey for core from 10.200.16.10 port 38658 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:48.561343 sshd[1584]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:48.565015 systemd-logind[1332]: New session 4 of user core. Mar 17 18:47:48.565415 systemd[1]: Started session-4.scope. Mar 17 18:47:48.876097 sshd[1584]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:48.878930 systemd-logind[1332]: Session 4 logged out. Waiting for processes to exit. Mar 17 18:47:48.880194 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 18:47:48.881101 systemd-logind[1332]: Removed session 4. Mar 17 18:47:48.881731 systemd[1]: sshd@1-10.200.20.32:22-10.200.16.10:38658.service: Deactivated successfully. Mar 17 18:47:48.951392 systemd[1]: Started sshd@2-10.200.20.32:22-10.200.16.10:58710.service. Mar 17 18:47:49.393011 sshd[1590]: Accepted publickey for core from 10.200.16.10 port 58710 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:49.394633 sshd[1590]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:49.398859 systemd[1]: Started session-5.scope. Mar 17 18:47:49.399959 systemd-logind[1332]: New session 5 of user core. Mar 17 18:47:49.712763 sshd[1590]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:49.715594 systemd-logind[1332]: Session 5 logged out. Waiting for processes to exit. Mar 17 18:47:49.715594 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 18:47:49.716160 systemd[1]: sshd@2-10.200.20.32:22-10.200.16.10:58710.service: Deactivated successfully. Mar 17 18:47:49.717100 systemd-logind[1332]: Removed session 5. Mar 17 18:47:49.786281 systemd[1]: Started sshd@3-10.200.20.32:22-10.200.16.10:58712.service. Mar 17 18:47:50.228510 sshd[1596]: Accepted publickey for core from 10.200.16.10 port 58712 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:50.229844 sshd[1596]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:50.234147 systemd-logind[1332]: New session 6 of user core. Mar 17 18:47:50.234592 systemd[1]: Started session-6.scope. Mar 17 18:47:50.551831 sshd[1596]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:50.554367 systemd[1]: sshd@3-10.200.20.32:22-10.200.16.10:58712.service: Deactivated successfully. Mar 17 18:47:50.555086 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 18:47:50.555655 systemd-logind[1332]: Session 6 logged out. Waiting for processes to exit. Mar 17 18:47:50.556492 systemd-logind[1332]: Removed session 6. Mar 17 18:47:50.631401 systemd[1]: Started sshd@4-10.200.20.32:22-10.200.16.10:58722.service. Mar 17 18:47:51.110316 sshd[1602]: Accepted publickey for core from 10.200.16.10 port 58722 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:51.111858 sshd[1602]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:51.115940 systemd[1]: Started session-7.scope. Mar 17 18:47:51.116951 systemd-logind[1332]: New session 7 of user core. Mar 17 18:47:51.575351 sudo[1608]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 17 18:47:51.575557 sudo[1608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:51.620157 dbus-daemon[1322]: avc: received setenforce notice (enforcing=1) Mar 17 18:47:51.622234 sudo[1608]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:51.729954 sshd[1602]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:51.733870 systemd-logind[1332]: Session 7 logged out. Waiting for processes to exit. Mar 17 18:47:51.734522 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 18:47:51.735375 systemd[1]: sshd@4-10.200.20.32:22-10.200.16.10:58722.service: Deactivated successfully. Mar 17 18:47:51.736793 systemd-logind[1332]: Removed session 7. Mar 17 18:47:51.808175 systemd[1]: Started sshd@5-10.200.20.32:22-10.200.16.10:58736.service. Mar 17 18:47:52.282676 sshd[1612]: Accepted publickey for core from 10.200.16.10 port 58736 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:52.284267 sshd[1612]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:52.288475 systemd[1]: Started session-8.scope. Mar 17 18:47:52.289016 systemd-logind[1332]: New session 8 of user core. Mar 17 18:47:52.551429 sudo[1616]: 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 Mar 17 18:47:52.552127 sudo[1616]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:52.554866 sudo[1616]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:52.559536 sudo[1615]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Mar 17 18:47:52.559781 sudo[1615]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:52.569723 systemd[1]: Stopping audit-rules.service... Mar 17 18:47:52.575952 kernel: kauditd_printk_skb: 3 callbacks suppressed Mar 17 18:47:52.576082 kernel: audit: type=1305 audit(1742237272.570:165): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Mar 17 18:47:52.570000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Mar 17 18:47:52.587273 auditctl[1619]: No rules Mar 17 18:47:52.570000 audit[1619]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe41e9f70 a2=420 a3=0 items=0 ppid=1 pid=1619 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) Mar 17 18:47:52.587921 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 18:47:52.588099 systemd[1]: Stopped audit-rules.service. Mar 17 18:47:52.589970 systemd[1]: Starting audit-rules.service... Mar 17 18:47:52.614535 augenrules[1636]: No rules Mar 17 18:47:52.614875 kernel: audit: type=1300 audit(1742237272.570:165): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe41e9f70 a2=420 a3=0 items=0 ppid=1 pid=1619 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) Mar 17 18:47:52.614934 kernel: audit: type=1327 audit(1742237272.570:165): proctitle=2F7362696E2F617564697463746C002D44 Mar 17 18:47:52.570000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Mar 17 18:47:52.615952 systemd[1]: Finished audit-rules.service. Mar 17 18:47:52.617313 sudo[1615]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:52.586000 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' Mar 17 18:47:52.640879 kernel: audit: type=1131 audit(1742237272.586:166): 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' Mar 17 18:47:52.641072 kernel: audit: type=1130 audit(1742237272.614: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' Mar 17 18:47:52.614000 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' Mar 17 18:47:52.614000 audit[1615]: USER_END pid=1615 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' Mar 17 18:47:52.675838 kernel: audit: type=1106 audit(1742237272.614:168): pid=1615 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' Mar 17 18:47:52.675985 kernel: audit: type=1104 audit(1742237272.614:169): pid=1615 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' Mar 17 18:47:52.614000 audit[1615]: CRED_DISP pid=1615 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' Mar 17 18:47:52.698461 sshd[1612]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:52.698000 audit[1612]: USER_END pid=1612 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:52.701195 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 18:47:52.701824 systemd[1]: sshd@5-10.200.20.32:22-10.200.16.10:58736.service: Deactivated successfully. Mar 17 18:47:52.703767 systemd-logind[1332]: Session 8 logged out. Waiting for processes to exit. Mar 17 18:47:52.704674 systemd-logind[1332]: Removed session 8. Mar 17 18:47:52.698000 audit[1612]: CRED_DISP pid=1612 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:52.740662 kernel: audit: type=1106 audit(1742237272.698:170): pid=1612 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:52.740816 kernel: audit: type=1104 audit(1742237272.698:171): pid=1612 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:52.740843 kernel: audit: type=1131 audit(1742237272.698:172): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.32:22-10.200.16.10:58736 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:52.698000 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.32:22-10.200.16.10:58736 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:52.770251 systemd[1]: Started sshd@6-10.200.20.32:22-10.200.16.10:58744.service. Mar 17 18:47:52.769000 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.32:22-10.200.16.10:58744 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:52.960518 update_engine[1335]: I0317 18:47:52.960412 1335 update_attempter.cc:509] Updating boot flags... Mar 17 18:47:53.203000 audit[1642]: USER_ACCT pid=1642 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.204333 sshd[1642]: Accepted publickey for core from 10.200.16.10 port 58744 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:53.204000 audit[1642]: CRED_ACQ pid=1642 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.204000 audit[1642]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffffa71c900 a2=3 a3=1 items=0 ppid=1 pid=1642 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) Mar 17 18:47:53.204000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:47:53.205946 sshd[1642]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:53.210095 systemd[1]: Started session-9.scope. Mar 17 18:47:53.210406 systemd-logind[1332]: New session 9 of user core. Mar 17 18:47:53.213000 audit[1642]: USER_START pid=1642 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.214000 audit[1683]: CRED_ACQ pid=1683 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.451000 audit[1684]: USER_ACCT pid=1684 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:53.451000 audit[1684]: CRED_REFR pid=1684 uid=500 auid=500 ses=9 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' Mar 17 18:47:53.452447 sudo[1684]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/mkdir -p . Mar 17 18:47:53.452649 sudo[1684]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:53.453000 audit[1684]: USER_START pid=1684 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:53.454000 audit[1684]: USER_END pid=1684 uid=500 auid=500 ses=9 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' Mar 17 18:47:53.455000 audit[1684]: CRED_DISP pid=1684 uid=500 auid=500 ses=9 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' Mar 17 18:47:53.455856 sudo[1684]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:53.533560 sshd[1642]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:53.533000 audit[1642]: USER_END pid=1642 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.533000 audit[1642]: CRED_DISP pid=1642 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:53.536450 systemd-logind[1332]: Session 9 logged out. Waiting for processes to exit. Mar 17 18:47:53.537315 systemd[1]: sshd@6-10.200.20.32:22-10.200.16.10:58744.service: Deactivated successfully. Mar 17 18:47:53.536000 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.32:22-10.200.16.10:58744 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:53.537999 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 18:47:53.538451 systemd-logind[1332]: Removed session 9. Mar 17 18:47:53.607000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.32:22-10.200.16.10:58746 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:53.608723 systemd[1]: Started sshd@7-10.200.20.32:22-10.200.16.10:58746.service. Mar 17 18:47:54.048000 audit[1688]: USER_ACCT pid=1688 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:54.049930 sshd[1688]: Accepted publickey for core from 10.200.16.10 port 58746 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:54.050000 audit[1688]: CRED_ACQ pid=1688 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:54.050000 audit[1688]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffdfbfbf70 a2=3 a3=1 items=0 ppid=1 pid=1688 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=10 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:54.050000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:47:54.052096 sshd[1688]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:54.057850 systemd[1]: Started session-10.scope. Mar 17 18:47:54.058278 systemd-logind[1332]: New session 10 of user core. Mar 17 18:47:54.062000 audit[1688]: USER_START pid=1688 uid=0 auid=500 ses=10 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:54.064000 audit[1690]: CRED_ACQ pid=1690 uid=0 auid=500 ses=10 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:54.301000 audit[1691]: USER_ACCT pid=1691 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:54.302723 sudo[1691]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/install -m 0755 /dev/stdin kolet Mar 17 18:47:54.302000 audit[1691]: CRED_REFR pid=1691 uid=500 auid=500 ses=10 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' Mar 17 18:47:54.303248 sudo[1691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:54.303000 audit[1691]: USER_START pid=1691 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:56.534063 sudo[1691]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:56.533000 audit[1691]: USER_END pid=1691 uid=500 auid=500 ses=10 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' Mar 17 18:47:56.533000 audit[1691]: CRED_DISP pid=1691 uid=500 auid=500 ses=10 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' Mar 17 18:47:56.604187 sshd[1688]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:56.604000 audit[1688]: USER_END pid=1688 uid=0 auid=500 ses=10 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:56.604000 audit[1688]: CRED_DISP pid=1688 uid=0 auid=500 ses=10 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:56.607528 systemd[1]: sshd@7-10.200.20.32:22-10.200.16.10:58746.service: Deactivated successfully. Mar 17 18:47:56.606000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.32:22-10.200.16.10:58746 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:56.608078 systemd-logind[1332]: Session 10 logged out. Waiting for processes to exit. Mar 17 18:47:56.608210 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 18:47:56.609136 systemd-logind[1332]: Removed session 10. Mar 17 18:47:56.677000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.32:22-10.200.16.10:58760 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:56.678756 systemd[1]: Started sshd@8-10.200.20.32:22-10.200.16.10:58760.service. Mar 17 18:47:57.118000 audit[1695]: USER_ACCT pid=1695 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.119509 sshd[1695]: Accepted publickey for core from 10.200.16.10 port 58760 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:47:57.119000 audit[1695]: CRED_ACQ pid=1695 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.119000 audit[1695]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc8faad00 a2=3 a3=1 items=0 ppid=1 pid=1695 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=11 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:47:57.119000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:47:57.121099 sshd[1695]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:47:57.124812 systemd-logind[1332]: New session 11 of user core. Mar 17 18:47:57.125297 systemd[1]: Started session-11.scope. Mar 17 18:47:57.128000 audit[1695]: USER_START pid=1695 uid=0 auid=500 ses=11 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.129000 audit[1697]: CRED_ACQ pid=1697 uid=0 auid=500 ses=11 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.371000 audit[1698]: USER_ACCT pid=1698 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.371000 audit[1698]: CRED_REFR pid=1698 uid=500 auid=500 ses=11 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' Mar 17 18:47:57.372614 sudo[1698]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemd-run --quiet ./kolet run coreos.ignition.resource.local Serve Mar 17 18:47:57.372812 sudo[1698]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:47:57.373000 audit[1698]: USER_START pid=1698 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.410000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=run-r4e22945fae024601b7561222e657be18 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.411000 audit[1698]: USER_END pid=1698 uid=500 auid=500 ses=11 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' Mar 17 18:47:57.412000 audit[1698]: CRED_DISP pid=1698 uid=500 auid=500 ses=11 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' Mar 17 18:47:57.412774 sudo[1698]: pam_unix(sudo:session): session closed for user root Mar 17 18:47:57.410890 systemd[1]: Started run-r4e22945fae024601b7561222e657be18.service. Mar 17 18:47:57.492249 sshd[1695]: pam_unix(sshd:session): session closed for user core Mar 17 18:47:57.492000 audit[1695]: USER_END pid=1695 uid=0 auid=500 ses=11 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.492000 audit[1695]: CRED_DISP pid=1695 uid=0 auid=500 ses=11 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.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:47:57.494790 systemd[1]: sshd@8-10.200.20.32:22-10.200.16.10:58760.service: Deactivated successfully. Mar 17 18:47:57.494000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.32:22-10.200.16.10:58760 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:47:57.495497 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 18:47:57.496022 systemd-logind[1332]: Session 11 logged out. Waiting for processes to exit. Mar 17 18:47:57.496813 systemd-logind[1332]: Removed session 11. Mar 17 18:48:30.653458 waagent[1524]: 2025-03-17T18:48:30.653366Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 2] Mar 17 18:48:30.663458 waagent[1524]: 2025-03-17T18:48:30.663384Z INFO ExtHandler Mar 17 18:48:30.663782 waagent[1524]: 2025-03-17T18:48:30.663734Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 2] Mar 17 18:48:30.730770 waagent[1524]: 2025-03-17T18:48:30.730707Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 18:48:30.818029 waagent[1524]: 2025-03-17T18:48:30.817865Z INFO ExtHandler Downloaded certificate {'thumbprint': '5CA2A76976FA9E7295A741CCD7F1F59F240B5C99', 'hasPrivateKey': True} Mar 17 18:48:30.819340 waagent[1524]: 2025-03-17T18:48:30.819278Z INFO ExtHandler Downloaded certificate {'thumbprint': '4FB63F781910BA73D5290DD5B53848D7B9E437E7', 'hasPrivateKey': False} Mar 17 18:48:30.820577 waagent[1524]: 2025-03-17T18:48:30.820508Z INFO ExtHandler Fetch goal state completed Mar 17 18:48:30.821695 waagent[1524]: 2025-03-17T18:48:30.821636Z INFO ExtHandler ExtHandler VM enabled for RSM updates, switching to RSM update mode Mar 17 18:48:30.823088 waagent[1524]: 2025-03-17T18:48:30.823034Z INFO ExtHandler ExtHandler Mar 17 18:48:30.823331 waagent[1524]: 2025-03-17T18:48:30.823278Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_2 channel: WireServer source: Fabric activity: 14c142df-eed2-4fae-9887-69465096a206 correlation 83132d8d-28c6-4c42-bce1-387eb59460ed created: 2025-03-17T18:48:23.347595Z] Mar 17 18:48:30.824226 waagent[1524]: 2025-03-17T18:48:30.824170Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 18:48:30.826274 waagent[1524]: 2025-03-17T18:48:30.826221Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_2 3 ms] Mar 17 18:48:36.865526 waagent[1524]: 2025-03-17T18:48:36.865459Z INFO ExtHandler Mar 17 18:48:36.866093 waagent[1524]: 2025-03-17T18:48:36.866038Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 4c0afc23-bbd3-41b3-a4f0-aba6f9b65119 eTag: 9263114643459621171 source: Fabric] Mar 17 18:48:36.867041 waagent[1524]: 2025-03-17T18:48:36.866983Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.