Apr 12 18:25:20.991114 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Apr 12 18:25:20.991132 kernel: Linux version 5.15.154-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Apr 12 17:21:24 -00 2024 Apr 12 18:25:20.991140 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Apr 12 18:25:20.991147 kernel: printk: bootconsole [pl11] enabled Apr 12 18:25:20.991152 kernel: efi: EFI v2.70 by EDK II Apr 12 18:25:20.991158 kernel: efi: ACPI 2.0=0x3fd89018 SMBIOS=0x3fd66000 SMBIOS 3.0=0x3fd64000 MEMATTR=0x3ef2e698 RNG=0x3fd89998 MEMRESERVE=0x37b33f98 Apr 12 18:25:20.991164 kernel: random: crng init done Apr 12 18:25:20.991170 kernel: ACPI: Early table checksum verification disabled Apr 12 18:25:20.991175 kernel: ACPI: RSDP 0x000000003FD89018 000024 (v02 VRTUAL) Apr 12 18:25:20.991181 kernel: ACPI: XSDT 0x000000003FD89F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991186 kernel: ACPI: FACP 0x000000003FD89C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991193 kernel: ACPI: DSDT 0x000000003EBD2018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000) Apr 12 18:25:20.991198 kernel: ACPI: DBG2 0x000000003FD89B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991204 kernel: ACPI: GTDT 0x000000003FD89D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991210 kernel: ACPI: OEM0 0x000000003FD89098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991216 kernel: ACPI: SPCR 0x000000003FD89A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991222 kernel: ACPI: APIC 0x000000003FD89818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991229 kernel: ACPI: SRAT 0x000000003FD89198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991235 kernel: ACPI: PPTT 0x000000003FD89418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Apr 12 18:25:20.991241 kernel: ACPI: BGRT 0x000000003FD89E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Apr 12 18:25:20.991247 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Apr 12 18:25:20.991252 kernel: NUMA: Failed to initialise from firmware Apr 12 18:25:20.991258 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff] Apr 12 18:25:20.991264 kernel: NUMA: NODE_DATA [mem 0x1bf7f3900-0x1bf7f8fff] Apr 12 18:25:20.991270 kernel: Zone ranges: Apr 12 18:25:20.991275 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Apr 12 18:25:20.991281 kernel: DMA32 empty Apr 12 18:25:20.991288 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Apr 12 18:25:20.991294 kernel: Movable zone start for each node Apr 12 18:25:20.991299 kernel: Early memory node ranges Apr 12 18:25:20.991305 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Apr 12 18:25:20.991311 kernel: node 0: [mem 0x0000000000824000-0x000000003ec80fff] Apr 12 18:25:20.991316 kernel: node 0: [mem 0x000000003ec81000-0x000000003eca9fff] Apr 12 18:25:20.991322 kernel: node 0: [mem 0x000000003ecaa000-0x000000003fd29fff] Apr 12 18:25:20.991328 kernel: node 0: [mem 0x000000003fd2a000-0x000000003fd7dfff] Apr 12 18:25:20.991334 kernel: node 0: [mem 0x000000003fd7e000-0x000000003fd89fff] Apr 12 18:25:20.991339 kernel: node 0: [mem 0x000000003fd8a000-0x000000003fd8dfff] Apr 12 18:25:20.991345 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fffffff] Apr 12 18:25:20.991350 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Apr 12 18:25:20.991357 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Apr 12 18:25:20.991366 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Apr 12 18:25:20.991372 kernel: psci: probing for conduit method from ACPI. Apr 12 18:25:20.991378 kernel: psci: PSCIv1.1 detected in firmware. Apr 12 18:25:20.991384 kernel: psci: Using standard PSCI v0.2 function IDs Apr 12 18:25:20.991392 kernel: psci: MIGRATE_INFO_TYPE not supported. Apr 12 18:25:20.991398 kernel: psci: SMC Calling Convention v1.4 Apr 12 18:25:20.991404 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1 Apr 12 18:25:20.991410 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1 Apr 12 18:25:20.991416 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Apr 12 18:25:20.991422 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Apr 12 18:25:20.991428 kernel: pcpu-alloc: [0] 0 [0] 1 Apr 12 18:25:20.991434 kernel: Detected PIPT I-cache on CPU0 Apr 12 18:25:20.991440 kernel: CPU features: detected: GIC system register CPU interface Apr 12 18:25:20.991446 kernel: CPU features: detected: Hardware dirty bit management Apr 12 18:25:20.991452 kernel: CPU features: detected: Spectre-BHB Apr 12 18:25:20.991458 kernel: CPU features: kernel page table isolation forced ON by KASLR Apr 12 18:25:20.991466 kernel: CPU features: detected: Kernel page table isolation (KPTI) Apr 12 18:25:20.991472 kernel: CPU features: detected: ARM erratum 1418040 Apr 12 18:25:20.991478 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Apr 12 18:25:20.991484 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Apr 12 18:25:20.991490 kernel: Policy zone: Normal Apr 12 18:25:20.991497 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=c0b96868344262519ffdb2dae3782c942008a0fecdbc0bc85d2e170bd2e8b8a8 Apr 12 18:25:20.996618 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Apr 12 18:25:20.996638 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Apr 12 18:25:20.996646 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Apr 12 18:25:20.996652 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Apr 12 18:25:20.996665 kernel: software IO TLB: mapped [mem 0x000000003abd2000-0x000000003ebd2000] (64MB) Apr 12 18:25:20.996672 kernel: Memory: 3990268K/4194160K available (9792K kernel code, 2092K rwdata, 7568K rodata, 36352K init, 777K bss, 203892K reserved, 0K cma-reserved) Apr 12 18:25:20.996678 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Apr 12 18:25:20.996685 kernel: trace event string verifier disabled Apr 12 18:25:20.996691 kernel: rcu: Preemptible hierarchical RCU implementation. Apr 12 18:25:20.996698 kernel: rcu: RCU event tracing is enabled. Apr 12 18:25:20.996704 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Apr 12 18:25:20.996711 kernel: Trampoline variant of Tasks RCU enabled. Apr 12 18:25:20.996717 kernel: Tracing variant of Tasks RCU enabled. Apr 12 18:25:20.996723 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Apr 12 18:25:20.996729 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Apr 12 18:25:20.996737 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Apr 12 18:25:20.996743 kernel: GICv3: 960 SPIs implemented Apr 12 18:25:20.996749 kernel: GICv3: 0 Extended SPIs implemented Apr 12 18:25:20.996756 kernel: GICv3: Distributor has no Range Selector support Apr 12 18:25:20.996762 kernel: Root IRQ handler: gic_handle_irq Apr 12 18:25:20.996768 kernel: GICv3: 16 PPIs implemented Apr 12 18:25:20.996774 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Apr 12 18:25:20.996780 kernel: ITS: No ITS available, not enabling LPIs Apr 12 18:25:20.996787 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:25:20.996793 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Apr 12 18:25:20.996799 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Apr 12 18:25:20.996806 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Apr 12 18:25:20.996814 kernel: Console: colour dummy device 80x25 Apr 12 18:25:20.996821 kernel: printk: console [tty1] enabled Apr 12 18:25:20.996827 kernel: ACPI: Core revision 20210730 Apr 12 18:25:20.996834 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Apr 12 18:25:20.996840 kernel: pid_max: default: 32768 minimum: 301 Apr 12 18:25:20.996847 kernel: LSM: Security Framework initializing Apr 12 18:25:20.996853 kernel: SELinux: Initializing. Apr 12 18:25:20.996859 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 12 18:25:20.996866 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Apr 12 18:25:20.996874 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Apr 12 18:25:20.996880 kernel: Hyper-V: Host Build 10.0.22477.1369-1-0 Apr 12 18:25:20.996887 kernel: rcu: Hierarchical SRCU implementation. Apr 12 18:25:20.996893 kernel: Remapping and enabling EFI services. Apr 12 18:25:20.996899 kernel: smp: Bringing up secondary CPUs ... Apr 12 18:25:20.996906 kernel: Detected PIPT I-cache on CPU1 Apr 12 18:25:20.996912 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Apr 12 18:25:20.996918 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Apr 12 18:25:20.996925 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Apr 12 18:25:20.996932 kernel: smp: Brought up 1 node, 2 CPUs Apr 12 18:25:20.996938 kernel: SMP: Total of 2 processors activated. Apr 12 18:25:20.996945 kernel: CPU features: detected: 32-bit EL0 Support Apr 12 18:25:20.996952 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Apr 12 18:25:20.996959 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Apr 12 18:25:20.996965 kernel: CPU features: detected: CRC32 instructions Apr 12 18:25:20.996971 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Apr 12 18:25:20.996978 kernel: CPU features: detected: LSE atomic instructions Apr 12 18:25:20.996984 kernel: CPU features: detected: Privileged Access Never Apr 12 18:25:20.996991 kernel: CPU: All CPU(s) started at EL1 Apr 12 18:25:20.996998 kernel: alternatives: patching kernel code Apr 12 18:25:20.997009 kernel: devtmpfs: initialized Apr 12 18:25:20.997017 kernel: KASLR enabled Apr 12 18:25:20.997023 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Apr 12 18:25:20.997030 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Apr 12 18:25:20.997037 kernel: pinctrl core: initialized pinctrl subsystem Apr 12 18:25:20.997043 kernel: SMBIOS 3.1.0 present. Apr 12 18:25:20.997050 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 11/28/2023 Apr 12 18:25:20.997057 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Apr 12 18:25:20.997066 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Apr 12 18:25:20.997072 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Apr 12 18:25:20.997079 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Apr 12 18:25:20.997086 kernel: audit: initializing netlink subsys (disabled) Apr 12 18:25:20.997093 kernel: audit: type=2000 audit(0.088:1): state=initialized audit_enabled=0 res=1 Apr 12 18:25:20.997100 kernel: thermal_sys: Registered thermal governor 'step_wise' Apr 12 18:25:20.997106 kernel: cpuidle: using governor menu Apr 12 18:25:20.997115 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Apr 12 18:25:20.997121 kernel: ASID allocator initialised with 32768 entries Apr 12 18:25:20.997128 kernel: ACPI: bus type PCI registered Apr 12 18:25:20.997134 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Apr 12 18:25:20.997141 kernel: Serial: AMBA PL011 UART driver Apr 12 18:25:20.997148 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Apr 12 18:25:20.997155 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Apr 12 18:25:20.997161 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Apr 12 18:25:20.997168 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Apr 12 18:25:20.997176 kernel: cryptd: max_cpu_qlen set to 1000 Apr 12 18:25:20.997182 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Apr 12 18:25:20.997189 kernel: ACPI: Added _OSI(Module Device) Apr 12 18:25:20.997195 kernel: ACPI: Added _OSI(Processor Device) Apr 12 18:25:20.997202 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Apr 12 18:25:20.997209 kernel: ACPI: Added _OSI(Processor Aggregator Device) Apr 12 18:25:20.997215 kernel: ACPI: Added _OSI(Linux-Dell-Video) Apr 12 18:25:20.997222 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Apr 12 18:25:20.997228 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Apr 12 18:25:20.997237 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Apr 12 18:25:20.997243 kernel: ACPI: Interpreter enabled Apr 12 18:25:20.997250 kernel: ACPI: Using GIC for interrupt routing Apr 12 18:25:20.997256 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Apr 12 18:25:20.997263 kernel: printk: console [ttyAMA0] enabled Apr 12 18:25:20.997270 kernel: printk: bootconsole [pl11] disabled Apr 12 18:25:20.997277 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Apr 12 18:25:20.997283 kernel: iommu: Default domain type: Translated Apr 12 18:25:20.997290 kernel: iommu: DMA domain TLB invalidation policy: strict mode Apr 12 18:25:20.997298 kernel: vgaarb: loaded Apr 12 18:25:20.997305 kernel: pps_core: LinuxPPS API ver. 1 registered Apr 12 18:25:20.997312 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Apr 12 18:25:20.997318 kernel: PTP clock support registered Apr 12 18:25:20.997325 kernel: Registered efivars operations Apr 12 18:25:20.997332 kernel: No ACPI PMU IRQ for CPU0 Apr 12 18:25:20.997338 kernel: No ACPI PMU IRQ for CPU1 Apr 12 18:25:20.997345 kernel: clocksource: Switched to clocksource arch_sys_counter Apr 12 18:25:20.997351 kernel: VFS: Disk quotas dquot_6.6.0 Apr 12 18:25:20.997360 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Apr 12 18:25:20.997366 kernel: pnp: PnP ACPI init Apr 12 18:25:20.997373 kernel: pnp: PnP ACPI: found 0 devices Apr 12 18:25:20.997379 kernel: NET: Registered PF_INET protocol family Apr 12 18:25:20.997386 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Apr 12 18:25:20.997393 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Apr 12 18:25:20.997399 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Apr 12 18:25:20.997406 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Apr 12 18:25:20.997413 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Apr 12 18:25:20.997421 kernel: TCP: Hash tables configured (established 32768 bind 32768) Apr 12 18:25:20.997428 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 12 18:25:20.997434 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Apr 12 18:25:20.997441 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Apr 12 18:25:20.997448 kernel: PCI: CLS 0 bytes, default 64 Apr 12 18:25:20.997455 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Apr 12 18:25:20.997461 kernel: kvm [1]: HYP mode not available Apr 12 18:25:20.997468 kernel: Initialise system trusted keyrings Apr 12 18:25:20.997474 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Apr 12 18:25:20.997482 kernel: Key type asymmetric registered Apr 12 18:25:20.997489 kernel: Asymmetric key parser 'x509' registered Apr 12 18:25:20.997495 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Apr 12 18:25:21.001540 kernel: io scheduler mq-deadline registered Apr 12 18:25:21.001570 kernel: io scheduler kyber registered Apr 12 18:25:21.001578 kernel: io scheduler bfq registered Apr 12 18:25:21.001585 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Apr 12 18:25:21.001592 kernel: thunder_xcv, ver 1.0 Apr 12 18:25:21.001599 kernel: thunder_bgx, ver 1.0 Apr 12 18:25:21.001611 kernel: nicpf, ver 1.0 Apr 12 18:25:21.001617 kernel: nicvf, ver 1.0 Apr 12 18:25:21.001755 kernel: rtc-efi rtc-efi.0: registered as rtc0 Apr 12 18:25:21.001819 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-04-12T18:25:20 UTC (1712946320) Apr 12 18:25:21.001829 kernel: efifb: probing for efifb Apr 12 18:25:21.001835 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Apr 12 18:25:21.001842 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Apr 12 18:25:21.001849 kernel: efifb: scrolling: redraw Apr 12 18:25:21.001858 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Apr 12 18:25:21.001865 kernel: Console: switching to colour frame buffer device 128x48 Apr 12 18:25:21.001871 kernel: fb0: EFI VGA frame buffer device Apr 12 18:25:21.001878 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Apr 12 18:25:21.001885 kernel: hid: raw HID events driver (C) Jiri Kosina Apr 12 18:25:21.001892 kernel: NET: Registered PF_INET6 protocol family Apr 12 18:25:21.001898 kernel: Segment Routing with IPv6 Apr 12 18:25:21.001905 kernel: In-situ OAM (IOAM) with IPv6 Apr 12 18:25:21.001912 kernel: NET: Registered PF_PACKET protocol family Apr 12 18:25:21.001920 kernel: Key type dns_resolver registered Apr 12 18:25:21.001927 kernel: registered taskstats version 1 Apr 12 18:25:21.001934 kernel: Loading compiled-in X.509 certificates Apr 12 18:25:21.001941 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.154-flatcar: 8c258d82bbd8df4a9da2c0ea4108142f04be6b34' Apr 12 18:25:21.001948 kernel: Key type .fscrypt registered Apr 12 18:25:21.001954 kernel: Key type fscrypt-provisioning registered Apr 12 18:25:21.001961 kernel: ima: No TPM chip found, activating TPM-bypass! Apr 12 18:25:21.001968 kernel: ima: Allocated hash algorithm: sha1 Apr 12 18:25:21.001975 kernel: ima: No architecture policies found Apr 12 18:25:21.001983 kernel: Freeing unused kernel memory: 36352K Apr 12 18:25:21.001990 kernel: Run /init as init process Apr 12 18:25:21.001996 kernel: with arguments: Apr 12 18:25:21.002003 kernel: /init Apr 12 18:25:21.002009 kernel: with environment: Apr 12 18:25:21.002016 kernel: HOME=/ Apr 12 18:25:21.002023 kernel: TERM=linux Apr 12 18:25:21.002029 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Apr 12 18:25:21.002038 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) Apr 12 18:25:21.002049 systemd[1]: Detected virtualization microsoft. Apr 12 18:25:21.002056 systemd[1]: Detected architecture arm64. Apr 12 18:25:21.002063 systemd[1]: Running in initrd. Apr 12 18:25:21.002070 systemd[1]: No hostname configured, using default hostname. Apr 12 18:25:21.002077 systemd[1]: Hostname set to . Apr 12 18:25:21.002085 systemd[1]: Initializing machine ID from random generator. Apr 12 18:25:21.002092 systemd[1]: Queued start job for default target initrd.target. Apr 12 18:25:21.002100 systemd[1]: Started systemd-ask-password-console.path. Apr 12 18:25:21.002107 systemd[1]: Reached target cryptsetup.target. Apr 12 18:25:21.002114 systemd[1]: Reached target paths.target. Apr 12 18:25:21.002121 systemd[1]: Reached target slices.target. Apr 12 18:25:21.002128 systemd[1]: Reached target swap.target. Apr 12 18:25:21.002135 systemd[1]: Reached target timers.target. Apr 12 18:25:21.002143 systemd[1]: Listening on iscsid.socket. Apr 12 18:25:21.002150 systemd[1]: Listening on iscsiuio.socket. Apr 12 18:25:21.002158 systemd[1]: Listening on systemd-journald-audit.socket. Apr 12 18:25:21.002165 systemd[1]: Listening on systemd-journald-dev-log.socket. Apr 12 18:25:21.002172 systemd[1]: Listening on systemd-journald.socket. Apr 12 18:25:21.002179 systemd[1]: Listening on systemd-networkd.socket. Apr 12 18:25:21.002187 systemd[1]: Listening on systemd-udevd-control.socket. Apr 12 18:25:21.002195 systemd[1]: Listening on systemd-udevd-kernel.socket. Apr 12 18:25:21.002202 systemd[1]: Reached target sockets.target. Apr 12 18:25:21.002209 systemd[1]: Starting kmod-static-nodes.service... Apr 12 18:25:21.002216 systemd[1]: Finished network-cleanup.service. Apr 12 18:25:21.002224 systemd[1]: Starting systemd-fsck-usr.service... Apr 12 18:25:21.002232 systemd[1]: Starting systemd-journald.service... Apr 12 18:25:21.002239 systemd[1]: Starting systemd-modules-load.service... Apr 12 18:25:21.002245 systemd[1]: Starting systemd-resolved.service... Apr 12 18:25:21.002253 systemd[1]: Starting systemd-vconsole-setup.service... Apr 12 18:25:21.002264 systemd-journald[236]: Journal started Apr 12 18:25:21.002310 systemd-journald[236]: Runtime Journal (/run/log/journal/b8780e774c7f4d428a78965d47f43e50) is 8.0M, max 78.6M, 70.6M free. Apr 12 18:25:20.994478 systemd-modules-load[237]: Inserted module 'overlay' Apr 12 18:25:21.036751 systemd[1]: Started systemd-journald.service. Apr 12 18:25:21.036818 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Apr 12 18:25:21.030202 systemd-resolved[238]: Positive Trust Anchors: Apr 12 18:25:21.063894 kernel: audit: type=1130 audit(1712946321.041: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' Apr 12 18:25:21.041000 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' Apr 12 18:25:21.030224 systemd-resolved[238]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 12 18:25:21.104459 kernel: Bridge firewalling registered Apr 12 18:25:21.104485 kernel: audit: type=1130 audit(1712946321.072:3): 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' Apr 12 18:25:21.072000 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' Apr 12 18:25:21.030251 systemd-resolved[238]: 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 Apr 12 18:25:21.147313 kernel: SCSI subsystem initialized Apr 12 18:25:21.032379 systemd-resolved[238]: Defaulting to hostname 'linux'. Apr 12 18:25:21.155726 kernel: audit: type=1130 audit(1712946321.154:4): 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' Apr 12 18:25:21.154000 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' Apr 12 18:25:21.041660 systemd[1]: Started systemd-resolved.service. Apr 12 18:25:21.240600 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Apr 12 18:25:21.240625 kernel: device-mapper: uevent: version 1.0.3 Apr 12 18:25:21.240634 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Apr 12 18:25:21.240643 kernel: audit: type=1130 audit(1712946321.198: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' Apr 12 18:25:21.240652 kernel: audit: type=1130 audit(1712946321.220:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:21.198000 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' Apr 12 18:25:21.220000 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' Apr 12 18:25:21.073277 systemd[1]: Finished kmod-static-nodes.service. Apr 12 18:25:21.267693 kernel: audit: type=1130 audit(1712946321.244:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:21.244000 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' Apr 12 18:25:21.081953 systemd-modules-load[237]: Inserted module 'br_netfilter' Apr 12 18:25:21.155650 systemd[1]: Finished systemd-fsck-usr.service. Apr 12 18:25:21.199571 systemd[1]: Finished systemd-vconsole-setup.service. Apr 12 18:25:21.220849 systemd-modules-load[237]: Inserted module 'dm_multipath' Apr 12 18:25:21.221808 systemd[1]: Finished systemd-modules-load.service. Apr 12 18:25:21.309000 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' Apr 12 18:25:21.244685 systemd[1]: Reached target nss-lookup.target. Apr 12 18:25:21.267836 systemd[1]: Starting dracut-cmdline-ask.service... Apr 12 18:25:21.339000 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' Apr 12 18:25:21.280375 systemd[1]: Starting systemd-sysctl.service... Apr 12 18:25:21.381837 kernel: audit: type=1130 audit(1712946321.309:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:21.381865 kernel: audit: type=1130 audit(1712946321.339:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:21.381875 kernel: audit: type=1130 audit(1712946321.363:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:21.363000 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' Apr 12 18:25:21.290740 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Apr 12 18:25:21.302764 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Apr 12 18:25:21.331114 systemd[1]: Finished dracut-cmdline-ask.service. Apr 12 18:25:21.339538 systemd[1]: Finished systemd-sysctl.service. Apr 12 18:25:21.408372 dracut-cmdline[258]: dracut-dracut-053 Apr 12 18:25:21.383207 systemd[1]: Starting dracut-cmdline.service... Apr 12 18:25:21.416943 dracut-cmdline[258]: 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=c0b96868344262519ffdb2dae3782c942008a0fecdbc0bc85d2e170bd2e8b8a8 Apr 12 18:25:21.507535 kernel: Loading iSCSI transport class v2.0-870. Apr 12 18:25:21.523535 kernel: iscsi: registered transport (tcp) Apr 12 18:25:21.544715 kernel: iscsi: registered transport (qla4xxx) Apr 12 18:25:21.544736 kernel: QLogic iSCSI HBA Driver Apr 12 18:25:21.581130 systemd[1]: Finished dracut-cmdline.service. Apr 12 18:25:21.585000 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' Apr 12 18:25:21.586683 systemd[1]: Starting dracut-pre-udev.service... Apr 12 18:25:21.642529 kernel: raid6: neonx8 gen() 13814 MB/s Apr 12 18:25:21.660515 kernel: raid6: neonx8 xor() 10828 MB/s Apr 12 18:25:21.680519 kernel: raid6: neonx4 gen() 13527 MB/s Apr 12 18:25:21.701516 kernel: raid6: neonx4 xor() 11096 MB/s Apr 12 18:25:21.721518 kernel: raid6: neonx2 gen() 12968 MB/s Apr 12 18:25:21.741520 kernel: raid6: neonx2 xor() 10391 MB/s Apr 12 18:25:21.762520 kernel: raid6: neonx1 gen() 10529 MB/s Apr 12 18:25:21.782515 kernel: raid6: neonx1 xor() 8791 MB/s Apr 12 18:25:21.802519 kernel: raid6: int64x8 gen() 6275 MB/s Apr 12 18:25:21.823515 kernel: raid6: int64x8 xor() 3542 MB/s Apr 12 18:25:21.843519 kernel: raid6: int64x4 gen() 7250 MB/s Apr 12 18:25:21.863519 kernel: raid6: int64x4 xor() 3859 MB/s Apr 12 18:25:21.884520 kernel: raid6: int64x2 gen() 6152 MB/s Apr 12 18:25:21.904515 kernel: raid6: int64x2 xor() 3322 MB/s Apr 12 18:25:21.924515 kernel: raid6: int64x1 gen() 5044 MB/s Apr 12 18:25:21.949612 kernel: raid6: int64x1 xor() 2641 MB/s Apr 12 18:25:21.949641 kernel: raid6: using algorithm neonx8 gen() 13814 MB/s Apr 12 18:25:21.949658 kernel: raid6: .... xor() 10828 MB/s, rmw enabled Apr 12 18:25:21.953901 kernel: raid6: using neon recovery algorithm Apr 12 18:25:21.971519 kernel: xor: measuring software checksum speed Apr 12 18:25:21.975515 kernel: 8regs : 17282 MB/sec Apr 12 18:25:21.979515 kernel: 32regs : 20755 MB/sec Apr 12 18:25:21.988199 kernel: arm64_neon : 27901 MB/sec Apr 12 18:25:21.988215 kernel: xor: using function: arm64_neon (27901 MB/sec) Apr 12 18:25:22.044522 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Apr 12 18:25:22.055072 systemd[1]: Finished dracut-pre-udev.service. Apr 12 18:25:22.059000 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' Apr 12 18:25:22.062000 audit: BPF prog-id=7 op=LOAD Apr 12 18:25:22.062000 audit: BPF prog-id=8 op=LOAD Apr 12 18:25:22.063988 systemd[1]: Starting systemd-udevd.service... Apr 12 18:25:22.081889 systemd-udevd[435]: Using default interface naming scheme 'v252'. Apr 12 18:25:22.089257 systemd[1]: Started systemd-udevd.service. Apr 12 18:25:22.095000 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' Apr 12 18:25:22.101369 systemd[1]: Starting dracut-pre-trigger.service... Apr 12 18:25:22.112381 dracut-pre-trigger[456]: rd.md=0: removing MD RAID activation Apr 12 18:25:22.143079 systemd[1]: Finished dracut-pre-trigger.service. Apr 12 18:25:22.147000 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' Apr 12 18:25:22.148716 systemd[1]: Starting systemd-udev-trigger.service... Apr 12 18:25:22.185079 systemd[1]: Finished systemd-udev-trigger.service. Apr 12 18:25:22.194000 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' Apr 12 18:25:22.231533 kernel: hv_vmbus: Vmbus version:5.3 Apr 12 18:25:22.242607 kernel: hv_vmbus: registering driver hyperv_keyboard Apr 12 18:25:22.242663 kernel: hv_vmbus: registering driver hid_hyperv Apr 12 18:25:22.242673 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0 Apr 12 18:25:22.262442 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Apr 12 18:25:22.274537 kernel: hv_vmbus: registering driver hv_storvsc Apr 12 18:25:22.291336 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1 Apr 12 18:25:22.291381 kernel: hv_vmbus: registering driver hv_netvsc Apr 12 18:25:22.297520 kernel: scsi host0: storvsc_host_t Apr 12 18:25:22.297689 kernel: scsi host1: storvsc_host_t Apr 12 18:25:22.305553 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Apr 12 18:25:22.313536 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Apr 12 18:25:22.334749 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Apr 12 18:25:22.334984 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Apr 12 18:25:22.341079 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Apr 12 18:25:22.341224 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Apr 12 18:25:22.341317 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Apr 12 18:25:22.349167 kernel: sd 0:0:0:0: [sda] Write Protect is off Apr 12 18:25:22.349328 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Apr 12 18:25:22.349410 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Apr 12 18:25:22.361540 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Apr 12 18:25:22.366533 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Apr 12 18:25:22.393535 kernel: hv_netvsc 000d3a01-5fc7-000d-3a01-5fc7000d3a01 eth0: VF slot 1 added Apr 12 18:25:22.402027 kernel: hv_vmbus: registering driver hv_pci Apr 12 18:25:22.409519 kernel: hv_pci 74516b00-33c9-4ad6-82ec-4fb5511ce945: PCI VMBus probing: Using version 0x10004 Apr 12 18:25:22.425199 kernel: hv_pci 74516b00-33c9-4ad6-82ec-4fb5511ce945: PCI host bridge to bus 33c9:00 Apr 12 18:25:22.425357 kernel: pci_bus 33c9:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Apr 12 18:25:22.431675 kernel: pci_bus 33c9:00: No busn resource found for root bus, will use [bus 00-ff] Apr 12 18:25:22.438797 kernel: pci 33c9:00:02.0: [15b3:1018] type 00 class 0x020000 Apr 12 18:25:22.454308 kernel: pci 33c9:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Apr 12 18:25:22.473629 kernel: pci 33c9:00:02.0: enabling Extended Tags Apr 12 18:25:22.492590 kernel: pci 33c9:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at 33c9:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Apr 12 18:25:22.505206 kernel: pci_bus 33c9:00: busn_res: [bus 00-ff] end is updated to 00 Apr 12 18:25:22.505355 kernel: pci 33c9:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Apr 12 18:25:22.546525 kernel: mlx5_core 33c9:00:02.0: firmware version: 16.30.1284 Apr 12 18:25:22.701541 kernel: mlx5_core 33c9:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0) Apr 12 18:25:22.761020 kernel: hv_netvsc 000d3a01-5fc7-000d-3a01-5fc7000d3a01 eth0: VF registering: eth1 Apr 12 18:25:22.761200 kernel: mlx5_core 33c9:00:02.0 eth1: joined to eth0 Apr 12 18:25:22.772564 kernel: mlx5_core 33c9:00:02.0 enP13257s1: renamed from eth1 Apr 12 18:25:22.894826 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Apr 12 18:25:22.929553 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (496) Apr 12 18:25:22.942672 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Apr 12 18:25:23.150884 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Apr 12 18:25:23.170048 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Apr 12 18:25:23.187014 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Apr 12 18:25:23.195191 systemd[1]: Starting disk-uuid.service... Apr 12 18:25:24.228480 disk-uuid[560]: The operation has completed successfully. Apr 12 18:25:24.234482 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Apr 12 18:25:24.288597 systemd[1]: disk-uuid.service: Deactivated successfully. Apr 12 18:25:24.293000 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' Apr 12 18:25:24.293000 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' Apr 12 18:25:24.288722 systemd[1]: Finished disk-uuid.service. Apr 12 18:25:24.302897 systemd[1]: Starting verity-setup.service... Apr 12 18:25:24.344164 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Apr 12 18:25:24.638492 systemd[1]: Found device dev-mapper-usr.device. Apr 12 18:25:24.643738 systemd[1]: Finished verity-setup.service. Apr 12 18:25:24.651000 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' Apr 12 18:25:24.652611 systemd[1]: Mounting sysusr-usr.mount... Apr 12 18:25:24.711535 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Apr 12 18:25:24.711644 systemd[1]: Mounted sysusr-usr.mount. Apr 12 18:25:24.715620 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Apr 12 18:25:24.716396 systemd[1]: Starting ignition-setup.service... Apr 12 18:25:24.723476 systemd[1]: Starting parse-ip-for-networkd.service... Apr 12 18:25:24.757995 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Apr 12 18:25:24.758059 kernel: BTRFS info (device sda6): using free space tree Apr 12 18:25:24.762763 kernel: BTRFS info (device sda6): has skinny extents Apr 12 18:25:24.820335 systemd[1]: Finished parse-ip-for-networkd.service. Apr 12 18:25:24.824000 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' Apr 12 18:25:24.828000 audit: BPF prog-id=9 op=LOAD Apr 12 18:25:24.830308 systemd[1]: Starting systemd-networkd.service... Apr 12 18:25:24.862692 systemd-networkd[827]: lo: Link UP Apr 12 18:25:24.866819 systemd-networkd[827]: lo: Gained carrier Apr 12 18:25:24.867377 systemd-networkd[827]: Enumeration completed Apr 12 18:25:24.876000 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' Apr 12 18:25:24.871699 systemd[1]: Started systemd-networkd.service. Apr 12 18:25:24.872165 systemd-networkd[827]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 12 18:25:24.877387 systemd[1]: Reached target network.target. Apr 12 18:25:24.888056 systemd[1]: Starting iscsiuio.service... Apr 12 18:25:24.914000 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' Apr 12 18:25:24.905912 systemd[1]: Started iscsiuio.service. Apr 12 18:25:24.926239 iscsid[835]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Apr 12 18:25:24.926239 iscsid[835]: 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 Apr 12 18:25:24.926239 iscsid[835]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Apr 12 18:25:24.926239 iscsid[835]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Apr 12 18:25:24.926239 iscsid[835]: If using hardware iscsi like qla4xxx this message can be ignored. Apr 12 18:25:24.926239 iscsid[835]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Apr 12 18:25:24.926239 iscsid[835]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Apr 12 18:25:24.935000 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' Apr 12 18:25:24.980000 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' Apr 12 18:25:24.919663 systemd[1]: Starting iscsid.service... Apr 12 18:25:24.930928 systemd[1]: Started iscsid.service. Apr 12 18:25:25.085717 kernel: kauditd_printk_skb: 16 callbacks suppressed Apr 12 18:25:25.085749 kernel: audit: type=1130 audit(1712946325.060:27): 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' Apr 12 18:25:25.060000 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' Apr 12 18:25:24.936723 systemd[1]: Starting dracut-initqueue.service... Apr 12 18:25:24.974068 systemd[1]: mnt-oem.mount: Deactivated successfully. Apr 12 18:25:24.974703 systemd[1]: Finished dracut-initqueue.service. Apr 12 18:25:25.103048 kernel: mlx5_core 33c9:00:02.0 enP13257s1: Link up Apr 12 18:25:24.980837 systemd[1]: Reached target remote-fs-pre.target. Apr 12 18:25:24.988604 systemd[1]: Reached target remote-cryptsetup.target. Apr 12 18:25:25.008464 systemd[1]: Reached target remote-fs.target. Apr 12 18:25:25.020648 systemd[1]: Starting dracut-pre-mount.service... Apr 12 18:25:25.051198 systemd[1]: Finished dracut-pre-mount.service. Apr 12 18:25:25.142615 kernel: hv_netvsc 000d3a01-5fc7-000d-3a01-5fc7000d3a01 eth0: Data path switched to VF: enP13257s1 Apr 12 18:25:25.142899 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Apr 12 18:25:25.143530 systemd-networkd[827]: enP13257s1: Link UP Apr 12 18:25:25.143619 systemd-networkd[827]: eth0: Link UP Apr 12 18:25:25.143713 systemd-networkd[827]: eth0: Gained carrier Apr 12 18:25:25.156232 systemd-networkd[827]: enP13257s1: Gained carrier Apr 12 18:25:25.166544 systemd[1]: Finished ignition-setup.service. Apr 12 18:25:25.170000 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' Apr 12 18:25:25.196121 kernel: audit: type=1130 audit(1712946325.170:28): 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' Apr 12 18:25:25.191481 systemd[1]: Starting ignition-fetch-offline.service... Apr 12 18:25:25.191711 systemd-networkd[827]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Apr 12 18:25:27.108682 systemd-networkd[827]: eth0: Gained IPv6LL Apr 12 18:25:28.808617 ignition[854]: Ignition 2.14.0 Apr 12 18:25:28.808629 ignition[854]: Stage: fetch-offline Apr 12 18:25:28.808713 ignition[854]: reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:28.808737 ignition[854]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:29.002774 ignition[854]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:29.002919 ignition[854]: parsed url from cmdline: "" Apr 12 18:25:29.002923 ignition[854]: no config URL provided Apr 12 18:25:29.002928 ignition[854]: reading system config file "/usr/lib/ignition/user.ign" Apr 12 18:25:29.051652 kernel: audit: type=1130 audit(1712946329.024: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' Apr 12 18:25:29.024000 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' Apr 12 18:25:29.016626 systemd[1]: Finished ignition-fetch-offline.service. Apr 12 18:25:29.002935 ignition[854]: no config at "/usr/lib/ignition/user.ign" Apr 12 18:25:29.025858 systemd[1]: Starting ignition-fetch.service... Apr 12 18:25:29.002940 ignition[854]: failed to fetch config: resource requires networking Apr 12 18:25:29.003164 ignition[854]: Ignition finished successfully Apr 12 18:25:29.056642 ignition[860]: Ignition 2.14.0 Apr 12 18:25:29.056648 ignition[860]: Stage: fetch Apr 12 18:25:29.056738 ignition[860]: reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:29.089000 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' Apr 12 18:25:29.082114 unknown[860]: fetched base config from "system" Apr 12 18:25:29.116823 kernel: audit: type=1130 audit(1712946329.089: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' Apr 12 18:25:29.056757 ignition[860]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:29.082122 unknown[860]: fetched base config from "system" Apr 12 18:25:29.061956 ignition[860]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:29.082127 unknown[860]: fetched user config from "azure" Apr 12 18:25:29.062914 ignition[860]: parsed url from cmdline: "" Apr 12 18:25:29.084494 systemd[1]: Finished ignition-fetch.service. Apr 12 18:25:29.062919 ignition[860]: no config URL provided Apr 12 18:25:29.144000 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' Apr 12 18:25:29.090557 systemd[1]: Starting ignition-kargs.service... Apr 12 18:25:29.172798 kernel: audit: type=1130 audit(1712946329.144: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' Apr 12 18:25:29.062925 ignition[860]: reading system config file "/usr/lib/ignition/user.ign" Apr 12 18:25:29.140484 systemd[1]: Finished ignition-kargs.service. Apr 12 18:25:29.062953 ignition[860]: no config at "/usr/lib/ignition/user.ign" Apr 12 18:25:29.162807 systemd[1]: Starting ignition-disks.service... Apr 12 18:25:29.063033 ignition[860]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Apr 12 18:25:29.079767 ignition[860]: GET result: OK Apr 12 18:25:29.079819 ignition[860]: config has been read from IMDS userdata Apr 12 18:25:29.079832 ignition[860]: parsing config with SHA512: 97e8a969244a0105f88ccad93a03b33f67d6412f924ab9e0a4ac26f8b3f399a26e812508d59f255498d49a0c7ddefde852e3f4a95f795c28eba6bb23c99ab7bd Apr 12 18:25:29.082435 ignition[860]: fetch: fetch complete Apr 12 18:25:29.082440 ignition[860]: fetch: fetch passed Apr 12 18:25:29.082480 ignition[860]: Ignition finished successfully Apr 12 18:25:29.124237 ignition[866]: Ignition 2.14.0 Apr 12 18:25:29.124242 ignition[866]: Stage: kargs Apr 12 18:25:29.124352 ignition[866]: reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:29.124370 ignition[866]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:29.127046 ignition[866]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:29.135367 ignition[866]: kargs: kargs passed Apr 12 18:25:29.135915 ignition[866]: Ignition finished successfully Apr 12 18:25:29.182068 ignition[872]: Ignition 2.14.0 Apr 12 18:25:29.253474 systemd[1]: Finished ignition-disks.service. Apr 12 18:25:29.260000 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' Apr 12 18:25:29.182075 ignition[872]: Stage: disks Apr 12 18:25:29.292889 kernel: audit: type=1130 audit(1712946329.260: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' Apr 12 18:25:29.182181 ignition[872]: reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:29.279368 systemd[1]: Reached target initrd-root-device.target. Apr 12 18:25:29.182198 ignition[872]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:29.285742 systemd[1]: Reached target local-fs-pre.target. Apr 12 18:25:29.184759 ignition[872]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:29.290582 systemd[1]: Reached target local-fs.target. Apr 12 18:25:29.186275 ignition[872]: disks: createFilesystems: op(1): [started] waiting for devices [/dev/disk/by-partlabel/ROOT] Apr 12 18:25:29.296932 systemd[1]: Reached target sysinit.target. Apr 12 18:25:29.192160 ignition[872]: disks: createFilesystems: op(1): [finished] waiting for devices [/dev/disk/by-partlabel/ROOT] Apr 12 18:25:29.306743 systemd[1]: Reached target basic.target. Apr 12 18:25:29.192240 ignition[872]: disks: createFilesystems: created device alias for "/dev/disk/by-partlabel/ROOT": "/run/ignition/dev_aliases/dev/disk/by-partlabel/ROOT" -> "/dev/sda9" Apr 12 18:25:29.315909 systemd[1]: Starting systemd-fsck-root.service... Apr 12 18:25:29.192358 ignition[872]: disks: createFilesystems: op(2): [started] determining filesystem type of "/dev/disk/by-partlabel/ROOT" Apr 12 18:25:29.251937 ignition[872]: disks: createFilesystems: op(2): [finished] determining filesystem type of "/dev/disk/by-partlabel/ROOT" Apr 12 18:25:29.251947 ignition[872]: disks: createFilesystems: found ext4 filesystem at "/dev/disk/by-partlabel/ROOT" with uuid "6e9578cc-42a5-4dea-aa9a-39f6ef4eec20" and label "ROOT" Apr 12 18:25:29.251952 ignition[872]: disks: createFilesystems: filesystem at "/dev/disk/by-partlabel/ROOT" is already correctly formatted. Skipping mkfs... Apr 12 18:25:29.403227 systemd[1]: Finished systemd-fsck-root.service. Apr 12 18:25:29.418000 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' Apr 12 18:25:29.437365 systemd-fsck[880]: ROOT: clean, 612/7326000 files, 481074/7359488 blocks Apr 12 18:25:29.445710 kernel: audit: type=1130 audit(1712946329.418: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' Apr 12 18:25:29.251971 ignition[872]: disks: disks passed Apr 12 18:25:29.445143 systemd[1]: Mounting sysroot.mount... Apr 12 18:25:29.252076 ignition[872]: Ignition finished successfully Apr 12 18:25:29.468535 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Apr 12 18:25:29.469465 systemd[1]: Mounted sysroot.mount. Apr 12 18:25:29.473168 systemd[1]: Reached target initrd-root-fs.target. Apr 12 18:25:29.520996 systemd[1]: Mounting sysroot-usr.mount... Apr 12 18:25:29.525703 systemd[1]: Starting flatcar-metadata-hostname.service... Apr 12 18:25:29.532988 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Apr 12 18:25:29.533021 systemd[1]: Reached target ignition-diskful.target. Apr 12 18:25:29.538608 systemd[1]: Mounted sysroot-usr.mount. Apr 12 18:25:29.640682 systemd[1]: Mounting sysroot-usr-share-oem.mount... Apr 12 18:25:29.645758 systemd[1]: Starting initrd-setup-root.service... Apr 12 18:25:29.668528 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (891) Apr 12 18:25:29.679740 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Apr 12 18:25:29.679766 kernel: BTRFS info (device sda6): using free space tree Apr 12 18:25:29.679786 initrd-setup-root[896]: cut: /sysroot/etc/passwd: No such file or directory Apr 12 18:25:29.690740 kernel: BTRFS info (device sda6): has skinny extents Apr 12 18:25:29.693650 systemd[1]: Mounted sysroot-usr-share-oem.mount. Apr 12 18:25:29.715411 initrd-setup-root[922]: cut: /sysroot/etc/group: No such file or directory Apr 12 18:25:29.741191 initrd-setup-root[930]: cut: /sysroot/etc/shadow: No such file or directory Apr 12 18:25:29.750382 initrd-setup-root[938]: cut: /sysroot/etc/gshadow: No such file or directory Apr 12 18:25:30.452397 systemd[1]: Finished initrd-setup-root.service. Apr 12 18:25:30.456000 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' Apr 12 18:25:30.457881 systemd[1]: Starting ignition-mount.service... Apr 12 18:25:30.488378 kernel: audit: type=1130 audit(1712946330.456: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' Apr 12 18:25:30.480244 systemd[1]: Starting sysroot-boot.service... Apr 12 18:25:30.486488 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Apr 12 18:25:30.486614 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Apr 12 18:25:30.517455 ignition[958]: INFO : Ignition 2.14.0 Apr 12 18:25:30.517455 ignition[958]: INFO : Stage: mount Apr 12 18:25:30.531000 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' Apr 12 18:25:30.527328 systemd[1]: Finished sysroot-boot.service. Apr 12 18:25:30.552640 kernel: audit: type=1130 audit(1712946330.531:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:30.552662 ignition[958]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:30.552662 ignition[958]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:30.552662 ignition[958]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:30.552662 ignition[958]: INFO : mount: op(1): [started] mounting "/dev/disk/by-partlabel/ROOT" at "/sysroot/tmp/_translate-filesystem-0-ign1" with type "ext4" and options "" Apr 12 18:25:30.552662 ignition[958]: DEBUG : mount: op(1): executing: "mount" "-o" "" "-t" "ext4" "/dev/disk/by-partlabel/ROOT" "/sysroot/tmp/_translate-filesystem-0-ign1" Apr 12 18:25:31.239000 coreos-metadata[890]: Apr 12 18:25:31.238 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Apr 12 18:25:31.342006 ignition[958]: INFO : mount: op(1): [finished] mounting "/dev/disk/by-partlabel/ROOT" at "/sysroot/tmp/_translate-filesystem-0-ign1" with type "ext4" and options "" Apr 12 18:25:31.342006 ignition[958]: INFO : mount: mount passed Apr 12 18:25:31.357000 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' Apr 12 18:25:31.375798 ignition[958]: INFO : Ignition finished successfully Apr 12 18:25:31.383006 kernel: audit: type=1130 audit(1712946331.357:36): 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' Apr 12 18:25:31.342850 systemd[1]: Finished ignition-mount.service. Apr 12 18:25:31.478524 coreos-metadata[890]: Apr 12 18:25:31.478 INFO Fetch successful Apr 12 18:25:31.512556 coreos-metadata[890]: Apr 12 18:25:31.512 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Apr 12 18:25:31.532801 coreos-metadata[890]: Apr 12 18:25:31.532 INFO Fetch successful Apr 12 18:25:31.552969 coreos-metadata[890]: Apr 12 18:25:31.552 INFO wrote hostname ci-3510.3.3-a-4700c11e05 to /sysroot/etc/hostname Apr 12 18:25:31.561583 systemd[1]: Finished flatcar-metadata-hostname.service. Apr 12 18:25:31.566000 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' Apr 12 18:25:31.567356 systemd[1]: Starting ignition-files.service... Apr 12 18:25:31.593341 kernel: audit: type=1130 audit(1712946331.566: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' Apr 12 18:25:31.592658 systemd[1]: Mounting sysroot-usr-share-oem.mount... Apr 12 18:25:31.615434 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (970) Apr 12 18:25:31.615462 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Apr 12 18:25:31.615472 kernel: BTRFS info (device sda6): using free space tree Apr 12 18:25:31.625845 kernel: BTRFS info (device sda6): has skinny extents Apr 12 18:25:31.634043 systemd[1]: Mounted sysroot-usr-share-oem.mount. Apr 12 18:25:31.647470 ignition[989]: INFO : Ignition 2.14.0 Apr 12 18:25:31.647470 ignition[989]: INFO : Stage: files Apr 12 18:25:31.657727 ignition[989]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:31.657727 ignition[989]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:31.657727 ignition[989]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:31.657727 ignition[989]: DEBUG : files: compiled without relabeling support, skipping Apr 12 18:25:31.657727 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Apr 12 18:25:31.657727 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): oem config not found in "/usr/share/oem", looking on oem partition Apr 12 18:25:31.721664 kernel: BTRFS info: devid 1 device path /dev/sda6 changed to /dev/disk/by-label/OEM scanned by ignition (994) Apr 12 18:25:31.702203 systemd[1]: mnt-oem332154369.mount: Deactivated successfully. Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): op(2): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem332154369" Apr 12 18:25:31.727129 ignition[989]: CRITICAL : files: createFilesystemsFiles: createFiles: op(1): op(2): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem332154369": device or resource busy Apr 12 18:25:31.727129 ignition[989]: ERROR : files: createFilesystemsFiles: createFiles: op(1): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem332154369", trying btrfs: device or resource busy Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): op(3): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem332154369" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): op(3): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem332154369" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): op(4): [started] unmounting "/mnt/oem332154369" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): op(4): [finished] unmounting "/mnt/oem332154369" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(1): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Apr 12 18:25:31.727129 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): oem config not found in "/usr/share/oem", looking on oem partition Apr 12 18:25:31.882528 kernel: audit: type=1130 audit(1712946331.788: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' Apr 12 18:25:31.882558 kernel: audit: type=1130 audit(1712946331.857:39): 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' Apr 12 18:25:31.788000 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' Apr 12 18:25:31.857000 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' Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): op(6): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3867744859" Apr 12 18:25:31.882654 ignition[989]: CRITICAL : files: createFilesystemsFiles: createFiles: op(5): op(6): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3867744859": device or resource busy Apr 12 18:25:31.882654 ignition[989]: ERROR : files: createFilesystemsFiles: createFiles: op(5): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3867744859", trying btrfs: device or resource busy Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): op(7): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3867744859" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): op(7): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3867744859" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): op(8): [started] unmounting "/mnt/oem3867744859" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): op(8): [finished] unmounting "/mnt/oem3867744859" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(9): [started] writing file "/sysroot/tmp/_translate-filesystem-0-ign1/etc/flatcar/update.conf" Apr 12 18:25:31.882654 ignition[989]: INFO : files: createFilesystemsFiles: createFiles: op(9): [finished] writing file "/sysroot/tmp/_translate-filesystem-0-ign1/etc/flatcar/update.conf" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(a): [started] processing unit "waagent.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(a): [finished] processing unit "waagent.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(b): [started] processing unit "nvidia.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(b): [finished] processing unit "nvidia.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(c): [started] setting preset to enabled for "nvidia.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(c): [finished] setting preset to enabled for "nvidia.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(d): [started] setting preset to enabled for "waagent.service" Apr 12 18:25:31.882654 ignition[989]: INFO : files: op(d): [finished] setting preset to enabled for "waagent.service" Apr 12 18:25:32.180228 kernel: audit: type=1130 audit(1712946331.900:40): 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' Apr 12 18:25:32.180257 kernel: audit: type=1131 audit(1712946331.900:41): 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' Apr 12 18:25:32.180267 kernel: audit: type=1130 audit(1712946332.002:42): 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' Apr 12 18:25:32.180277 kernel: audit: type=1131 audit(1712946332.002:43): 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' Apr 12 18:25:31.900000 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' Apr 12 18:25:31.900000 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' Apr 12 18:25:32.002000 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' Apr 12 18:25:32.002000 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' Apr 12 18:25:32.114000 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' Apr 12 18:25:31.741283 systemd[1]: mnt-oem3867744859.mount: Deactivated successfully. Apr 12 18:25:32.184000 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' Apr 12 18:25:32.189485 ignition[989]: INFO : files: createResultFile: createFiles: op(e): [started] writing file "/sysroot/etc/.ignition-result.json" Apr 12 18:25:32.189485 ignition[989]: INFO : files: createResultFile: createFiles: op(e): [finished] writing file "/sysroot/etc/.ignition-result.json" Apr 12 18:25:32.189485 ignition[989]: INFO : files: files passed Apr 12 18:25:32.189485 ignition[989]: INFO : Ignition finished successfully Apr 12 18:25:31.777283 systemd[1]: Finished ignition-files.service. Apr 12 18:25:32.243720 initrd-setup-root-after-ignition[1006]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Apr 12 18:25:31.789904 systemd[1]: Starting initrd-setup-root-after-ignition.service... Apr 12 18:25:31.816892 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Apr 12 18:25:31.826890 systemd[1]: Starting ignition-quench.service... Apr 12 18:25:31.842274 systemd[1]: Finished initrd-setup-root-after-ignition.service. Apr 12 18:25:31.885219 systemd[1]: ignition-quench.service: Deactivated successfully. Apr 12 18:25:31.885316 systemd[1]: Finished ignition-quench.service. Apr 12 18:25:32.309000 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' Apr 12 18:25:31.900365 systemd[1]: Reached target ignition-complete.target. Apr 12 18:25:31.967834 systemd[1]: Starting initrd-parse-etc.service... Apr 12 18:25:32.326000 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' Apr 12 18:25:31.998048 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Apr 12 18:25:32.335000 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' Apr 12 18:25:31.998143 systemd[1]: Finished initrd-parse-etc.service. Apr 12 18:25:32.344000 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' Apr 12 18:25:32.002961 systemd[1]: Reached target initrd-fs.target. Apr 12 18:25:32.353000 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' Apr 12 18:25:32.039894 systemd[1]: Reached target initrd.target. Apr 12 18:25:32.052813 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Apr 12 18:25:32.368000 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' Apr 12 18:25:32.061486 systemd[1]: Starting dracut-pre-pivot.service... Apr 12 18:25:32.389000 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' Apr 12 18:25:32.394476 ignition[1019]: INFO : Ignition 2.14.0 Apr 12 18:25:32.394476 ignition[1019]: INFO : Stage: umount Apr 12 18:25:32.394476 ignition[1019]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Apr 12 18:25:32.394476 ignition[1019]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Apr 12 18:25:32.394476 ignition[1019]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Apr 12 18:25:32.394476 ignition[1019]: INFO : umount: op(1): [started] umounting "/sysroot/tmp/_translate-filesystem-0-ign1" Apr 12 18:25:32.394476 ignition[1019]: INFO : umount: op(1): [finished] umounting "/sysroot/tmp/_translate-filesystem-0-ign1" Apr 12 18:25:32.394476 ignition[1019]: INFO : umount: umount passed Apr 12 18:25:32.394476 ignition[1019]: INFO : Ignition finished successfully Apr 12 18:25:32.398000 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' Apr 12 18:25:32.406000 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' Apr 12 18:25:32.406000 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' Apr 12 18:25:32.414000 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' Apr 12 18:25:32.425000 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' Apr 12 18:25:32.442000 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' Apr 12 18:25:32.453000 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' Apr 12 18:25:32.478000 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' Apr 12 18:25:32.110255 systemd[1]: Finished dracut-pre-pivot.service. Apr 12 18:25:32.121279 systemd[1]: Starting initrd-cleanup.service... Apr 12 18:25:32.140173 systemd[1]: Stopped target nss-lookup.target. Apr 12 18:25:32.149736 systemd[1]: Stopped target remote-cryptsetup.target. Apr 12 18:25:32.161554 systemd[1]: Stopped target timers.target. Apr 12 18:25:32.172869 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Apr 12 18:25:32.172936 systemd[1]: Stopped dracut-pre-pivot.service. Apr 12 18:25:32.184543 systemd[1]: Stopped target initrd.target. Apr 12 18:25:32.545000 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' Apr 12 18:25:32.193706 systemd[1]: Stopped target basic.target. Apr 12 18:25:32.207624 systemd[1]: Stopped target ignition-complete.target. Apr 12 18:25:32.221365 systemd[1]: Stopped target ignition-diskful.target. Apr 12 18:25:32.570000 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' Apr 12 18:25:32.230568 systemd[1]: Stopped target initrd-root-device.target. Apr 12 18:25:32.578000 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' Apr 12 18:25:32.239861 systemd[1]: Stopped target remote-fs.target. Apr 12 18:25:32.587000 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' Apr 12 18:25:32.587000 audit: BPF prog-id=6 op=UNLOAD Apr 12 18:25:32.247753 systemd[1]: Stopped target remote-fs-pre.target. Apr 12 18:25:32.260229 systemd[1]: Stopped target sysinit.target. Apr 12 18:25:32.603000 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' Apr 12 18:25:32.269116 systemd[1]: Stopped target local-fs.target. Apr 12 18:25:32.283134 systemd[1]: Stopped target local-fs-pre.target. Apr 12 18:25:32.291674 systemd[1]: Stopped target swap.target. Apr 12 18:25:32.623000 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' Apr 12 18:25:32.300574 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Apr 12 18:25:32.631000 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' Apr 12 18:25:32.300637 systemd[1]: Stopped dracut-pre-mount.service. Apr 12 18:25:32.641000 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' Apr 12 18:25:32.310244 systemd[1]: Stopped target cryptsetup.target. Apr 12 18:25:32.318261 systemd[1]: dracut-initqueue.service: Deactivated successfully. Apr 12 18:25:32.318302 systemd[1]: Stopped dracut-initqueue.service. Apr 12 18:25:32.326917 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Apr 12 18:25:32.668000 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' Apr 12 18:25:32.326958 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Apr 12 18:25:32.336035 systemd[1]: ignition-files.service: Deactivated successfully. Apr 12 18:25:32.336071 systemd[1]: Stopped ignition-files.service. Apr 12 18:25:32.725397 kernel: hv_netvsc 000d3a01-5fc7-000d-3a01-5fc7000d3a01 eth0: Data path switched from VF: enP13257s1 Apr 12 18:25:32.701000 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' Apr 12 18:25:32.344919 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Apr 12 18:25:32.731000 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' Apr 12 18:25:32.344957 systemd[1]: Stopped flatcar-metadata-hostname.service. Apr 12 18:25:32.735000 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' Apr 12 18:25:32.354216 systemd[1]: Stopping ignition-mount.service... Apr 12 18:25:32.360760 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Apr 12 18:25:32.360828 systemd[1]: Stopped kmod-static-nodes.service. Apr 12 18:25:32.369815 systemd[1]: Stopping sysroot-boot.service... Apr 12 18:25:32.763000 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' Apr 12 18:25:32.373548 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Apr 12 18:25:32.772000 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' Apr 12 18:25:32.772000 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' Apr 12 18:25:32.373629 systemd[1]: Stopped systemd-udev-trigger.service. Apr 12 18:25:32.390105 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Apr 12 18:25:32.390155 systemd[1]: Stopped dracut-pre-trigger.service. Apr 12 18:25:32.399864 systemd[1]: initrd-cleanup.service: Deactivated successfully. Apr 12 18:25:32.399951 systemd[1]: Finished initrd-cleanup.service. Apr 12 18:25:32.801000 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' Apr 12 18:25:32.407390 systemd[1]: ignition-mount.service: Deactivated successfully. Apr 12 18:25:32.407468 systemd[1]: Stopped ignition-mount.service. Apr 12 18:25:32.415183 systemd[1]: ignition-disks.service: Deactivated successfully. Apr 12 18:25:32.415275 systemd[1]: Stopped ignition-disks.service. Apr 12 18:25:32.425657 systemd[1]: ignition-kargs.service: Deactivated successfully. Apr 12 18:25:32.425699 systemd[1]: Stopped ignition-kargs.service. Apr 12 18:25:32.443065 systemd[1]: ignition-fetch.service: Deactivated successfully. Apr 12 18:25:32.443110 systemd[1]: Stopped ignition-fetch.service. Apr 12 18:25:32.453427 systemd[1]: Stopped target network.target. Apr 12 18:25:32.465659 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Apr 12 18:25:32.465722 systemd[1]: Stopped ignition-fetch-offline.service. Apr 12 18:25:32.478988 systemd[1]: Stopped target paths.target. Apr 12 18:25:32.873966 systemd-journald[236]: Received SIGTERM from PID 1 (n/a). Apr 12 18:25:32.874006 iscsid[835]: iscsid shutting down. Apr 12 18:25:32.487217 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Apr 12 18:25:32.500561 systemd[1]: Stopped systemd-ask-password-console.path. Apr 12 18:25:32.505516 systemd[1]: Stopped target slices.target. Apr 12 18:25:32.513453 systemd[1]: Stopped target sockets.target. Apr 12 18:25:32.521694 systemd[1]: iscsid.socket: Deactivated successfully. Apr 12 18:25:32.521726 systemd[1]: Closed iscsid.socket. Apr 12 18:25:32.529184 systemd[1]: iscsiuio.socket: Deactivated successfully. Apr 12 18:25:32.529208 systemd[1]: Closed iscsiuio.socket. Apr 12 18:25:32.537729 systemd[1]: ignition-setup.service: Deactivated successfully. Apr 12 18:25:32.537770 systemd[1]: Stopped ignition-setup.service. Apr 12 18:25:32.545903 systemd[1]: Stopping systemd-networkd.service... Apr 12 18:25:32.553123 systemd[1]: Stopping systemd-resolved.service... Apr 12 18:25:32.560203 systemd-networkd[827]: eth0: DHCPv6 lease lost Apr 12 18:25:32.873000 audit: BPF prog-id=9 op=UNLOAD Apr 12 18:25:32.561621 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 12 18:25:32.561722 systemd[1]: Stopped systemd-networkd.service. Apr 12 18:25:32.571013 systemd[1]: sysroot-boot.service: Deactivated successfully. Apr 12 18:25:32.571093 systemd[1]: Stopped sysroot-boot.service. Apr 12 18:25:32.578913 systemd[1]: systemd-resolved.service: Deactivated successfully. Apr 12 18:25:32.579012 systemd[1]: Stopped systemd-resolved.service. Apr 12 18:25:32.587764 systemd[1]: systemd-networkd.socket: Deactivated successfully. Apr 12 18:25:32.587808 systemd[1]: Closed systemd-networkd.socket. Apr 12 18:25:32.595310 systemd[1]: initrd-setup-root.service: Deactivated successfully. Apr 12 18:25:32.595353 systemd[1]: Stopped initrd-setup-root.service. Apr 12 18:25:32.607255 systemd[1]: Stopping network-cleanup.service... Apr 12 18:25:32.615368 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Apr 12 18:25:32.615459 systemd[1]: Stopped parse-ip-for-networkd.service. Apr 12 18:25:32.624135 systemd[1]: systemd-sysctl.service: Deactivated successfully. Apr 12 18:25:32.624184 systemd[1]: Stopped systemd-sysctl.service. Apr 12 18:25:32.636819 systemd[1]: systemd-modules-load.service: Deactivated successfully. Apr 12 18:25:32.636861 systemd[1]: Stopped systemd-modules-load.service. Apr 12 18:25:32.641667 systemd[1]: Stopping systemd-udevd.service... Apr 12 18:25:32.650797 systemd[1]: sysroot-tmp-_translate\x2dfilesystem\x2d0\x2dign1.mount: Deactivated successfully. Apr 12 18:25:32.650885 systemd[1]: sysroot-boot.mount: Deactivated successfully. Apr 12 18:25:32.650936 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Apr 12 18:25:32.664225 systemd[1]: systemd-udevd.service: Deactivated successfully. Apr 12 18:25:32.664356 systemd[1]: Stopped systemd-udevd.service. Apr 12 18:25:32.675177 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Apr 12 18:25:32.675211 systemd[1]: Closed systemd-udevd-control.socket. Apr 12 18:25:32.679825 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Apr 12 18:25:32.679853 systemd[1]: Closed systemd-udevd-kernel.socket. Apr 12 18:25:32.696423 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Apr 12 18:25:32.696468 systemd[1]: Stopped dracut-pre-udev.service. Apr 12 18:25:32.713282 systemd[1]: dracut-cmdline.service: Deactivated successfully. Apr 12 18:25:32.713330 systemd[1]: Stopped dracut-cmdline.service. Apr 12 18:25:32.731564 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Apr 12 18:25:32.731622 systemd[1]: Stopped dracut-cmdline-ask.service. Apr 12 18:25:32.740394 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Apr 12 18:25:32.755527 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Apr 12 18:25:32.755598 systemd[1]: Stopped systemd-vconsole-setup.service. Apr 12 18:25:32.764840 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Apr 12 18:25:32.764954 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Apr 12 18:25:32.792451 systemd[1]: network-cleanup.service: Deactivated successfully. Apr 12 18:25:32.792591 systemd[1]: Stopped network-cleanup.service. Apr 12 18:25:32.801463 systemd[1]: Reached target initrd-switch-root.target. Apr 12 18:25:32.811154 systemd[1]: Starting initrd-switch-root.service... Apr 12 18:25:32.831714 systemd[1]: Switching root. Apr 12 18:25:32.875474 systemd-journald[236]: Journal stopped Apr 12 18:25:58.122185 kernel: SELinux: Class mctp_socket not defined in policy. Apr 12 18:25:58.122205 kernel: SELinux: Class anon_inode not defined in policy. Apr 12 18:25:58.122215 kernel: SELinux: the above unknown classes and permissions will be allowed Apr 12 18:25:58.122225 kernel: SELinux: policy capability network_peer_controls=1 Apr 12 18:25:58.122233 kernel: SELinux: policy capability open_perms=1 Apr 12 18:25:58.122241 kernel: SELinux: policy capability extended_socket_class=1 Apr 12 18:25:58.122250 kernel: SELinux: policy capability always_check_network=0 Apr 12 18:25:58.122258 kernel: SELinux: policy capability cgroup_seclabel=1 Apr 12 18:25:58.122265 kernel: SELinux: policy capability nnp_nosuid_transition=1 Apr 12 18:25:58.122273 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Apr 12 18:25:58.122283 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Apr 12 18:25:58.122292 systemd[1]: Successfully loaded SELinux policy in 329.144ms. Apr 12 18:25:58.122302 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 26.497ms. Apr 12 18:25:58.122312 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) Apr 12 18:25:58.122324 systemd[1]: Detected virtualization microsoft. Apr 12 18:25:58.122334 systemd[1]: Detected architecture arm64. Apr 12 18:25:58.122343 systemd[1]: Detected first boot. Apr 12 18:25:58.122352 systemd[1]: Hostname set to . Apr 12 18:25:58.122361 systemd[1]: Initializing machine ID from random generator. Apr 12 18:25:58.122370 kernel: kauditd_printk_skb: 36 callbacks suppressed Apr 12 18:25:58.122379 kernel: audit: type=1400 audit(1712946335.885:80): 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 Apr 12 18:25:58.122388 kernel: audit: type=1400 audit(1712946335.885:81): 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 Apr 12 18:25:58.122398 kernel: audit: type=1334 audit(1712946335.891:82): prog-id=10 op=LOAD Apr 12 18:25:58.122407 kernel: audit: type=1334 audit(1712946335.891:83): prog-id=10 op=UNLOAD Apr 12 18:25:58.122415 kernel: audit: type=1334 audit(1712946335.907:84): prog-id=11 op=LOAD Apr 12 18:25:58.122423 kernel: audit: type=1334 audit(1712946335.907:85): prog-id=11 op=UNLOAD Apr 12 18:25:58.122432 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Apr 12 18:25:58.122442 kernel: audit: type=1400 audit(1712946337.354:86): avc: denied { associate } for pid=1053 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" Apr 12 18:25:58.122453 kernel: audit: type=1300 audit(1712946337.354:86): arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1036 pid=1053 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) Apr 12 18:25:58.122463 kernel: audit: type=1327 audit(1712946337.354:86): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Apr 12 18:25:58.122472 kernel: audit: type=1400 audit(1712946337.363:87): avc: denied { associate } for pid=1053 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Apr 12 18:25:58.122481 systemd[1]: Populated /etc with preset unit settings. Apr 12 18:25:58.122490 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Apr 12 18:25:58.122500 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Apr 12 18:25:58.122522 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. Apr 12 18:25:58.122534 kernel: kauditd_printk_skb: 5 callbacks suppressed Apr 12 18:25:58.122542 kernel: audit: type=1334 audit(1712946357.353:88): prog-id=12 op=LOAD Apr 12 18:25:58.122551 kernel: audit: type=1334 audit(1712946357.353:89): prog-id=3 op=UNLOAD Apr 12 18:25:58.122559 kernel: audit: type=1334 audit(1712946357.358:90): prog-id=13 op=LOAD Apr 12 18:25:58.122568 kernel: audit: type=1334 audit(1712946357.364:91): prog-id=14 op=LOAD Apr 12 18:25:58.122580 kernel: audit: type=1334 audit(1712946357.364:92): prog-id=4 op=UNLOAD Apr 12 18:25:58.122589 kernel: audit: type=1334 audit(1712946357.364:93): prog-id=5 op=UNLOAD Apr 12 18:25:58.122597 kernel: audit: type=1334 audit(1712946357.370:94): prog-id=15 op=LOAD Apr 12 18:25:58.122607 kernel: audit: type=1334 audit(1712946357.370:95): prog-id=12 op=UNLOAD Apr 12 18:25:58.122616 systemd[1]: iscsiuio.service: Deactivated successfully. Apr 12 18:25:58.122624 kernel: audit: type=1334 audit(1712946357.375:96): prog-id=16 op=LOAD Apr 12 18:25:58.122633 systemd[1]: Stopped iscsiuio.service. Apr 12 18:25:58.122642 kernel: audit: type=1334 audit(1712946357.381:97): prog-id=17 op=LOAD Apr 12 18:25:58.122651 systemd[1]: iscsid.service: Deactivated successfully. Apr 12 18:25:58.122660 systemd[1]: Stopped iscsid.service. Apr 12 18:25:58.122669 systemd[1]: initrd-switch-root.service: Deactivated successfully. Apr 12 18:25:58.122680 systemd[1]: Stopped initrd-switch-root.service. Apr 12 18:25:58.122689 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Apr 12 18:25:58.122698 systemd[1]: Created slice system-addon\x2dconfig.slice. Apr 12 18:25:58.122708 systemd[1]: Created slice system-addon\x2drun.slice. Apr 12 18:25:58.122717 systemd[1]: Created slice system-getty.slice. Apr 12 18:25:58.122726 systemd[1]: Created slice system-modprobe.slice. Apr 12 18:25:58.122735 systemd[1]: Created slice system-serial\x2dgetty.slice. Apr 12 18:25:58.122745 systemd[1]: Created slice system-system\x2dcloudinit.slice. Apr 12 18:25:58.122754 systemd[1]: Created slice system-systemd\x2dfsck.slice. Apr 12 18:25:58.122764 systemd[1]: Created slice user.slice. Apr 12 18:25:58.122774 systemd[1]: Started systemd-ask-password-console.path. Apr 12 18:25:58.122784 systemd[1]: Started systemd-ask-password-wall.path. Apr 12 18:25:58.122793 systemd[1]: Set up automount boot.automount. Apr 12 18:25:58.122802 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Apr 12 18:25:58.122812 systemd[1]: Stopped target initrd-switch-root.target. Apr 12 18:25:58.122821 systemd[1]: Stopped target initrd-fs.target. Apr 12 18:25:58.122832 systemd[1]: Stopped target initrd-root-fs.target. Apr 12 18:25:58.122841 systemd[1]: Reached target integritysetup.target. Apr 12 18:25:58.122851 systemd[1]: Reached target remote-cryptsetup.target. Apr 12 18:25:58.122860 systemd[1]: Reached target remote-fs.target. Apr 12 18:25:58.122869 systemd[1]: Reached target slices.target. Apr 12 18:25:58.122878 systemd[1]: Reached target swap.target. Apr 12 18:25:58.122887 systemd[1]: Reached target torcx.target. Apr 12 18:25:58.122898 systemd[1]: Reached target veritysetup.target. Apr 12 18:25:58.122907 systemd[1]: Listening on systemd-coredump.socket. Apr 12 18:25:58.122916 systemd[1]: Listening on systemd-initctl.socket. Apr 12 18:25:58.122926 systemd[1]: Listening on systemd-networkd.socket. Apr 12 18:25:58.122935 systemd[1]: Listening on systemd-udevd-control.socket. Apr 12 18:25:58.122944 systemd[1]: Listening on systemd-udevd-kernel.socket. Apr 12 18:25:58.122953 systemd[1]: Listening on systemd-userdbd.socket. Apr 12 18:25:58.122964 systemd[1]: Mounting dev-hugepages.mount... Apr 12 18:25:58.122974 systemd[1]: Mounting dev-mqueue.mount... Apr 12 18:25:58.122984 systemd[1]: Mounting media.mount... Apr 12 18:25:58.122993 systemd[1]: Mounting sys-kernel-debug.mount... Apr 12 18:25:58.123002 systemd[1]: Mounting sys-kernel-tracing.mount... Apr 12 18:25:58.123012 systemd[1]: tmp.mount: Directory /tmp to mount over is not empty, mounting anyway. Apr 12 18:25:58.123021 systemd[1]: Mounting tmp.mount... Apr 12 18:25:58.123030 systemd[1]: Starting flatcar-tmpfiles.service... Apr 12 18:25:58.123040 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Apr 12 18:25:58.123050 systemd[1]: Starting kmod-static-nodes.service... Apr 12 18:25:58.123059 systemd[1]: Starting modprobe@configfs.service... Apr 12 18:25:58.123069 systemd[1]: Starting modprobe@dm_mod.service... Apr 12 18:25:58.123078 systemd[1]: Starting modprobe@drm.service... Apr 12 18:25:58.123088 systemd[1]: Starting modprobe@efi_pstore.service... Apr 12 18:25:58.123097 systemd[1]: Starting modprobe@fuse.service... Apr 12 18:25:58.123106 systemd[1]: Starting modprobe@loop.service... Apr 12 18:25:58.123116 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Apr 12 18:25:58.123125 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Apr 12 18:25:58.123136 systemd[1]: Stopped systemd-fsck-root.service. Apr 12 18:25:58.123145 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Apr 12 18:25:58.123155 systemd[1]: Stopped systemd-fsck-usr.service. Apr 12 18:25:58.123164 systemd[1]: Stopped systemd-journald.service. Apr 12 18:25:58.123174 kernel: fuse: init (API version 7.34) Apr 12 18:25:58.123184 systemd[1]: systemd-journald.service: Consumed 2.567s CPU time. Apr 12 18:25:58.123193 systemd[1]: Starting systemd-journald.service... Apr 12 18:25:58.123202 kernel: loop: module loaded Apr 12 18:25:58.123211 systemd[1]: Starting systemd-modules-load.service... Apr 12 18:25:58.123221 systemd[1]: Starting systemd-network-generator.service... Apr 12 18:25:58.123231 systemd[1]: Starting systemd-remount-fs.service... Apr 12 18:25:58.123240 systemd[1]: Starting systemd-udev-trigger.service... Apr 12 18:25:58.123249 systemd[1]: verity-setup.service: Deactivated successfully. Apr 12 18:25:58.123258 systemd[1]: Stopped verity-setup.service. Apr 12 18:25:58.123270 systemd[1]: Mounted dev-hugepages.mount. Apr 12 18:25:58.123281 systemd[1]: Mounted dev-mqueue.mount. Apr 12 18:25:58.123290 systemd[1]: Mounted media.mount. Apr 12 18:25:58.123300 systemd[1]: Mounted sys-kernel-debug.mount. Apr 12 18:25:58.123310 systemd[1]: Mounted sys-kernel-tracing.mount. Apr 12 18:25:58.123322 systemd[1]: Mounted tmp.mount. Apr 12 18:25:58.123333 systemd[1]: Finished flatcar-tmpfiles.service. Apr 12 18:25:58.123343 systemd[1]: Finished kmod-static-nodes.service. Apr 12 18:25:58.123356 systemd-journald[1159]: Journal started Apr 12 18:25:58.123390 systemd-journald[1159]: Runtime Journal (/run/log/journal/54336a16ef3148b3b6b4c75bde0ea143) is 8.0M, max 78.6M, 70.6M free. Apr 12 18:25:35.091000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Apr 12 18:25:35.885000 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 Apr 12 18:25:35.885000 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 Apr 12 18:25:35.891000 audit: BPF prog-id=10 op=LOAD Apr 12 18:25:35.891000 audit: BPF prog-id=10 op=UNLOAD Apr 12 18:25:35.907000 audit: BPF prog-id=11 op=LOAD Apr 12 18:25:35.907000 audit: BPF prog-id=11 op=UNLOAD Apr 12 18:25:37.354000 audit[1053]: AVC avc: denied { associate } for pid=1053 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" Apr 12 18:25:37.354000 audit[1053]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=400014589c a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1036 pid=1053 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) Apr 12 18:25:37.354000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Apr 12 18:25:37.363000 audit[1053]: AVC avc: denied { associate } for pid=1053 comm="torcx-generator" name="bin" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Apr 12 18:25:37.363000 audit[1053]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145979 a2=1ed a3=0 items=2 ppid=1036 pid=1053 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) Apr 12 18:25:37.363000 audit: CWD cwd="/" Apr 12 18:25:37.363000 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 Apr 12 18:25:37.363000 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 Apr 12 18:25:37.363000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Apr 12 18:25:57.353000 audit: BPF prog-id=12 op=LOAD Apr 12 18:25:57.353000 audit: BPF prog-id=3 op=UNLOAD Apr 12 18:25:57.358000 audit: BPF prog-id=13 op=LOAD Apr 12 18:25:57.364000 audit: BPF prog-id=14 op=LOAD Apr 12 18:25:57.364000 audit: BPF prog-id=4 op=UNLOAD Apr 12 18:25:57.364000 audit: BPF prog-id=5 op=UNLOAD Apr 12 18:25:57.370000 audit: BPF prog-id=15 op=LOAD Apr 12 18:25:57.370000 audit: BPF prog-id=12 op=UNLOAD Apr 12 18:25:57.375000 audit: BPF prog-id=16 op=LOAD Apr 12 18:25:57.381000 audit: BPF prog-id=17 op=LOAD Apr 12 18:25:57.381000 audit: BPF prog-id=13 op=UNLOAD Apr 12 18:25:57.381000 audit: BPF prog-id=14 op=UNLOAD Apr 12 18:25:57.386000 audit: BPF prog-id=18 op=LOAD Apr 12 18:25:57.386000 audit: BPF prog-id=15 op=UNLOAD Apr 12 18:25:57.392000 audit: BPF prog-id=19 op=LOAD Apr 12 18:25:57.398000 audit: BPF prog-id=20 op=LOAD Apr 12 18:25:57.398000 audit: BPF prog-id=16 op=UNLOAD Apr 12 18:25:57.398000 audit: BPF prog-id=17 op=UNLOAD Apr 12 18:25:57.399000 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' Apr 12 18:25:57.420000 audit: BPF prog-id=18 op=UNLOAD Apr 12 18:25:57.429000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:57.442000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:25:57.457000 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' Apr 12 18:25:57.457000 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' Apr 12 18:25:57.936000 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' Apr 12 18:25:57.951000 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' Apr 12 18:25:57.963000 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' Apr 12 18:25:57.963000 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' Apr 12 18:25:57.971000 audit: BPF prog-id=21 op=LOAD Apr 12 18:25:57.971000 audit: BPF prog-id=22 op=LOAD Apr 12 18:25:57.971000 audit: BPF prog-id=23 op=LOAD Apr 12 18:25:57.971000 audit: BPF prog-id=19 op=UNLOAD Apr 12 18:25:57.971000 audit: BPF prog-id=20 op=UNLOAD Apr 12 18:25:58.054000 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' Apr 12 18:25:58.115000 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' Apr 12 18:25:58.119000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Apr 12 18:25:58.119000 audit[1159]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffde98a740 a2=4000 a3=1 items=0 ppid=1 pid=1159 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) Apr 12 18:25:58.119000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Apr 12 18:25:37.261583 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" 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.3 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.3 /var/lib/torcx/store]" Apr 12 18:25:58.124000 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' Apr 12 18:25:57.352582 systemd[1]: Queued start job for default target multi-user.target. Apr 12 18:25:37.282243 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Apr 12 18:25:57.399218 systemd[1]: systemd-journald.service: Deactivated successfully. Apr 12 18:25:37.282261 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Apr 12 18:25:57.399632 systemd[1]: systemd-journald.service: Consumed 2.567s CPU time. Apr 12 18:25:37.282296 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Apr 12 18:25:37.282306 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="skipped missing lower profile" missing profile=oem Apr 12 18:25:37.282336 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Apr 12 18:25:37.282349 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Apr 12 18:25:37.282558 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Apr 12 18:25:37.282592 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Apr 12 18:25:37.282603 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Apr 12 18:25:37.336940 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" 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 Apr 12 18:25:37.337023 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" 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 Apr 12 18:25:37.337052 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.3: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.3 Apr 12 18:25:37.337068 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Apr 12 18:25:37.337088 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.3: no such file or directory" path=/var/lib/torcx/store/3510.3.3 Apr 12 18:25:37.337101 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:37Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Apr 12 18:25:55.601572 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Apr 12 18:25:55.601828 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" 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 Apr 12 18:25:55.601918 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" 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 Apr 12 18:25:55.602074 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" 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 Apr 12 18:25:55.602121 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Apr 12 18:25:55.602173 /usr/lib/systemd/system-generators/torcx-generator[1053]: time="2024-04-12T18:25:55Z" 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 Apr 12 18:25:58.132982 systemd[1]: Started systemd-journald.service. Apr 12 18:25:58.132000 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' Apr 12 18:25:58.133817 systemd[1]: modprobe@configfs.service: Deactivated successfully. Apr 12 18:25:58.133943 systemd[1]: Finished modprobe@configfs.service. Apr 12 18:25:58.138000 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' Apr 12 18:25:58.138000 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' Apr 12 18:25:58.139182 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Apr 12 18:25:58.139318 systemd[1]: Finished modprobe@dm_mod.service. Apr 12 18:25:58.143000 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' Apr 12 18:25:58.143000 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' Apr 12 18:25:58.143932 systemd[1]: modprobe@drm.service: Deactivated successfully. Apr 12 18:25:58.144053 systemd[1]: Finished modprobe@drm.service. Apr 12 18:25:58.147000 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' Apr 12 18:25:58.147000 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' Apr 12 18:25:58.148432 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Apr 12 18:25:58.148728 systemd[1]: Finished modprobe@efi_pstore.service. Apr 12 18:25:58.153000 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' Apr 12 18:25:58.153000 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' Apr 12 18:25:58.153684 systemd[1]: modprobe@fuse.service: Deactivated successfully. Apr 12 18:25:58.153803 systemd[1]: Finished modprobe@fuse.service. Apr 12 18:25:58.157000 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' Apr 12 18:25:58.157000 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' Apr 12 18:25:58.158187 systemd[1]: modprobe@loop.service: Deactivated successfully. Apr 12 18:25:58.158303 systemd[1]: Finished modprobe@loop.service. Apr 12 18:25:58.162000 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' Apr 12 18:25:58.162000 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' Apr 12 18:25:58.162928 systemd[1]: Finished systemd-network-generator.service. Apr 12 18:25:58.167000 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' Apr 12 18:25:58.168121 systemd[1]: Finished systemd-remount-fs.service. Apr 12 18:25:58.172000 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' Apr 12 18:25:58.173389 systemd[1]: Reached target network-pre.target. Apr 12 18:25:58.178944 systemd[1]: Mounting sys-fs-fuse-connections.mount... Apr 12 18:25:58.184815 systemd[1]: Mounting sys-kernel-config.mount... Apr 12 18:25:58.188946 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Apr 12 18:25:58.190682 systemd[1]: Starting systemd-hwdb-update.service... Apr 12 18:25:58.195924 systemd[1]: Starting systemd-journal-flush.service... Apr 12 18:25:58.200127 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Apr 12 18:25:58.201082 systemd[1]: Starting systemd-random-seed.service... Apr 12 18:25:58.205329 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Apr 12 18:25:58.206405 systemd[1]: Starting systemd-sysusers.service... Apr 12 18:25:58.212132 systemd[1]: Finished systemd-modules-load.service. Apr 12 18:25:58.216000 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' Apr 12 18:25:58.217435 systemd[1]: Mounted sys-fs-fuse-connections.mount. Apr 12 18:25:58.222409 systemd[1]: Mounted sys-kernel-config.mount. Apr 12 18:25:58.227921 systemd[1]: Starting systemd-sysctl.service... Apr 12 18:25:58.258079 systemd[1]: Finished systemd-random-seed.service. Apr 12 18:25:58.262000 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' Apr 12 18:25:58.263031 systemd[1]: Reached target first-boot-complete.target. Apr 12 18:25:58.272108 systemd-journald[1159]: Time spent on flushing to /var/log/journal/54336a16ef3148b3b6b4c75bde0ea143 is 13.796ms for 1082 entries. Apr 12 18:25:58.272108 systemd-journald[1159]: System Journal (/var/log/journal/54336a16ef3148b3b6b4c75bde0ea143) is 8.0M, max 2.6G, 2.6G free. Apr 12 18:25:58.366051 systemd-journald[1159]: Received client request to flush runtime journal. Apr 12 18:25:58.288000 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' Apr 12 18:25:58.347000 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' Apr 12 18:25:58.279875 systemd[1]: Finished systemd-udev-trigger.service. Apr 12 18:25:58.289869 systemd[1]: Starting systemd-udev-settle.service... Apr 12 18:25:58.367016 udevadm[1173]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in. Apr 12 18:25:58.343654 systemd[1]: Finished systemd-sysctl.service. Apr 12 18:25:58.367007 systemd[1]: Finished systemd-journal-flush.service. Apr 12 18:25:58.371000 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' Apr 12 18:25:58.900616 systemd[1]: Finished systemd-sysusers.service. Apr 12 18:25:58.905000 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' Apr 12 18:25:59.580814 systemd[1]: Finished systemd-hwdb-update.service. Apr 12 18:25:59.584000 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' Apr 12 18:25:59.585000 audit: BPF prog-id=24 op=LOAD Apr 12 18:25:59.585000 audit: BPF prog-id=25 op=LOAD Apr 12 18:25:59.585000 audit: BPF prog-id=7 op=UNLOAD Apr 12 18:25:59.585000 audit: BPF prog-id=8 op=UNLOAD Apr 12 18:25:59.586783 systemd[1]: Starting systemd-udevd.service... Apr 12 18:25:59.604114 systemd-udevd[1176]: Using default interface naming scheme 'v252'. Apr 12 18:25:59.892705 systemd[1]: Started systemd-udevd.service. Apr 12 18:25:59.900000 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' Apr 12 18:25:59.909764 systemd[1]: Starting systemd-networkd.service... Apr 12 18:25:59.908000 audit: BPF prog-id=26 op=LOAD Apr 12 18:25:59.930955 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Apr 12 18:25:59.980229 systemd[1]: Starting systemd-userdbd.service... Apr 12 18:25:59.978000 audit: BPF prog-id=27 op=LOAD Apr 12 18:25:59.979000 audit: BPF prog-id=28 op=LOAD Apr 12 18:25:59.979000 audit: BPF prog-id=29 op=LOAD Apr 12 18:26:00.053000 audit[1196]: AVC avc: denied { confidentiality } for pid=1196 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 Apr 12 18:26:00.069114 kernel: hv_vmbus: registering driver hyperv_fb Apr 12 18:26:00.069221 kernel: hv_vmbus: registering driver hv_balloon Apr 12 18:26:00.069257 kernel: mousedev: PS/2 mouse device common for all mice Apr 12 18:26:00.069281 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Apr 12 18:26:00.078683 kernel: hv_balloon: Memory hot add disabled on ARM64 Apr 12 18:26:00.090666 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Apr 12 18:26:00.090728 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Apr 12 18:26:00.090020 systemd[1]: Started systemd-userdbd.service. Apr 12 18:26:00.099452 kernel: Console: switching to colour dummy device 80x25 Apr 12 18:26:00.102000 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' Apr 12 18:26:00.108929 kernel: Console: switching to colour frame buffer device 128x48 Apr 12 18:26:00.053000 audit[1196]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaaf90ad840 a1=aa2c a2=ffff869924b0 a3=aaaaf9004010 items=12 ppid=1176 pid=1196 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) Apr 12 18:26:00.053000 audit: CWD cwd="/" Apr 12 18:26:00.053000 audit: PATH item=0 name=(null) inode=6700 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 Apr 12 18:26:00.053000 audit: PATH item=1 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=2 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=3 name=(null) inode=11383 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 Apr 12 18:26:00.053000 audit: PATH item=4 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=5 name=(null) inode=11384 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 Apr 12 18:26:00.053000 audit: PATH item=6 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=7 name=(null) inode=11385 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 Apr 12 18:26:00.053000 audit: PATH item=8 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=9 name=(null) inode=11386 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 Apr 12 18:26:00.053000 audit: PATH item=10 name=(null) inode=11382 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 Apr 12 18:26:00.053000 audit: PATH item=11 name=(null) inode=11387 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 Apr 12 18:26:00.053000 audit: PROCTITLE proctitle="(udev-worker)" Apr 12 18:26:00.122545 kernel: hv_utils: Registering HyperV Utility Driver Apr 12 18:26:00.122774 kernel: hv_vmbus: registering driver hv_utils Apr 12 18:26:00.126345 kernel: hv_utils: Heartbeat IC version 3.0 Apr 12 18:26:00.126424 kernel: hv_utils: Shutdown IC version 3.2 Apr 12 18:26:00.118545 kernel: hv_utils: TimeSync IC version 4.0 Apr 12 18:26:00.193312 systemd-journald[1159]: Time jumped backwards, rotating. Apr 12 18:26:00.351083 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1186) Apr 12 18:26:00.367588 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Apr 12 18:26:00.375458 systemd[1]: Finished systemd-udev-settle.service. Apr 12 18:26:00.379000 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' Apr 12 18:26:00.381384 systemd[1]: Starting lvm2-activation-early.service... Apr 12 18:26:00.589396 systemd-networkd[1197]: lo: Link UP Apr 12 18:26:00.589666 systemd-networkd[1197]: lo: Gained carrier Apr 12 18:26:00.590209 systemd-networkd[1197]: Enumeration completed Apr 12 18:26:00.590375 systemd[1]: Started systemd-networkd.service. Apr 12 18:26:00.594000 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' Apr 12 18:26:00.596533 systemd[1]: Starting systemd-networkd-wait-online.service... Apr 12 18:26:00.646086 systemd-networkd[1197]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 12 18:26:00.696021 kernel: mlx5_core 33c9:00:02.0 enP13257s1: Link up Apr 12 18:26:00.721704 systemd-networkd[1197]: enP13257s1: Link UP Apr 12 18:26:00.721831 systemd-networkd[1197]: eth0: Link UP Apr 12 18:26:00.721835 systemd-networkd[1197]: eth0: Gained carrier Apr 12 18:26:00.722013 kernel: hv_netvsc 000d3a01-5fc7-000d-3a01-5fc7000d3a01 eth0: Data path switched to VF: enP13257s1 Apr 12 18:26:00.730206 systemd-networkd[1197]: enP13257s1: Gained carrier Apr 12 18:26:00.738118 systemd-networkd[1197]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Apr 12 18:26:00.747394 lvm[1253]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 12 18:26:00.788934 systemd[1]: Finished lvm2-activation-early.service. Apr 12 18:26:00.792000 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' Apr 12 18:26:00.794092 systemd[1]: Reached target cryptsetup.target. Apr 12 18:26:00.799662 systemd[1]: Starting lvm2-activation.service... Apr 12 18:26:00.804067 lvm[1256]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Apr 12 18:26:00.824851 systemd[1]: Finished lvm2-activation.service. Apr 12 18:26:00.828000 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' Apr 12 18:26:00.829547 systemd[1]: Reached target local-fs-pre.target. Apr 12 18:26:00.834216 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Apr 12 18:26:00.834242 systemd[1]: Reached target local-fs.target. Apr 12 18:26:00.838361 systemd[1]: Reached target machines.target. Apr 12 18:26:00.843761 systemd[1]: Starting ldconfig.service... Apr 12 18:26:00.847525 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Apr 12 18:26:00.847587 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). Apr 12 18:26:00.848637 systemd[1]: Starting systemd-boot-update.service... Apr 12 18:26:00.853677 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Apr 12 18:26:00.860095 systemd[1]: Starting systemd-machine-id-commit.service... Apr 12 18:26:00.864647 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Apr 12 18:26:00.864697 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Apr 12 18:26:00.865685 systemd[1]: Starting systemd-tmpfiles-setup.service... Apr 12 18:26:01.056472 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1258 (bootctl) Apr 12 18:26:01.058447 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Apr 12 18:26:01.532241 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Apr 12 18:26:01.533315 systemd[1]: Finished systemd-machine-id-commit.service. Apr 12 18:26:01.537000 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' Apr 12 18:26:01.542263 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Apr 12 18:26:01.547000 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' Apr 12 18:26:01.680563 systemd-tmpfiles[1261]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Apr 12 18:26:01.998044 systemd-fsck[1266]: fsck.fat 4.2 (2021-01-31) Apr 12 18:26:01.998044 systemd-fsck[1266]: /dev/sda1: 236 files, 117047/258078 clusters Apr 12 18:26:02.000124 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Apr 12 18:26:02.005000 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' Apr 12 18:26:02.007352 systemd[1]: Mounting boot.mount... Apr 12 18:26:02.156659 systemd[1]: Mounted boot.mount. Apr 12 18:26:02.166432 systemd[1]: Finished systemd-boot-update.service. Apr 12 18:26:02.170000 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' Apr 12 18:26:02.376742 systemd-tmpfiles[1261]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Apr 12 18:26:02.550132 systemd-networkd[1197]: eth0: Gained IPv6LL Apr 12 18:26:02.552852 systemd[1]: Finished systemd-networkd-wait-online.service. Apr 12 18:26:02.557000 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' Apr 12 18:26:02.561868 kernel: kauditd_printk_skb: 85 callbacks suppressed Apr 12 18:26:02.561911 kernel: audit: type=1130 audit(1712946362.557:166): 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' Apr 12 18:26:02.742399 systemd-tmpfiles[1261]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Apr 12 18:26:05.271969 systemd[1]: Finished systemd-tmpfiles-setup.service. Apr 12 18:26:05.276000 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' Apr 12 18:26:05.284324 systemd[1]: Starting audit-rules.service... Apr 12 18:26:05.296690 kernel: audit: type=1130 audit(1712946365.276:167): 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' Apr 12 18:26:05.298349 systemd[1]: Starting clean-ca-certificates.service... Apr 12 18:26:05.305237 systemd[1]: Starting systemd-journal-catalog-update.service... Apr 12 18:26:05.310000 audit: BPF prog-id=30 op=LOAD Apr 12 18:26:05.313254 systemd[1]: Starting systemd-resolved.service... Apr 12 18:26:05.322047 kernel: audit: type=1334 audit(1712946365.310:168): prog-id=30 op=LOAD Apr 12 18:26:05.321000 audit: BPF prog-id=31 op=LOAD Apr 12 18:26:05.327951 systemd[1]: Starting systemd-timesyncd.service... Apr 12 18:26:05.330015 kernel: audit: type=1334 audit(1712946365.321:169): prog-id=31 op=LOAD Apr 12 18:26:05.333162 systemd[1]: Starting systemd-update-utmp.service... Apr 12 18:26:05.583000 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' Apr 12 18:26:05.579072 systemd[1]: Started systemd-timesyncd.service. Apr 12 18:26:05.585143 systemd[1]: Reached target time-set.target. Apr 12 18:26:05.619647 kernel: audit: type=1130 audit(1712946365.583:170): 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' Apr 12 18:26:05.619723 kernel: audit: type=1127 audit(1712946365.602:171): pid=1278 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' Apr 12 18:26:05.602000 audit[1278]: SYSTEM_BOOT pid=1278 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' Apr 12 18:26:05.626769 systemd[1]: Finished systemd-update-utmp.service. Apr 12 18:26:05.630000 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' Apr 12 18:26:05.649024 kernel: audit: type=1130 audit(1712946365.630:172): 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' Apr 12 18:26:05.989854 systemd[1]: Finished clean-ca-certificates.service. Apr 12 18:26:05.993000 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' Apr 12 18:26:05.994894 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Apr 12 18:26:06.013034 kernel: audit: type=1130 audit(1712946365.993:173): 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' Apr 12 18:26:06.029768 systemd-resolved[1276]: Positive Trust Anchors: Apr 12 18:26:06.029784 systemd-resolved[1276]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Apr 12 18:26:06.029811 systemd-resolved[1276]: 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 Apr 12 18:26:06.033850 systemd-resolved[1276]: Using system hostname 'ci-3510.3.3-a-4700c11e05'. Apr 12 18:26:06.034960 systemd[1]: Finished systemd-journal-catalog-update.service. Apr 12 18:26:06.039000 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' Apr 12 18:26:06.040404 systemd[1]: Started systemd-resolved.service. Apr 12 18:26:06.063654 kernel: audit: type=1130 audit(1712946366.039:174): 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' Apr 12 18:26:06.063000 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' Apr 12 18:26:06.064351 systemd[1]: Reached target network.target. Apr 12 18:26:06.085857 kernel: audit: type=1130 audit(1712946366.063:175): 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' Apr 12 18:26:06.086224 systemd[1]: Reached target network-online.target. Apr 12 18:26:06.091119 systemd[1]: Reached target nss-lookup.target. Apr 12 18:26:06.497912 systemd-timesyncd[1277]: Contacted time server 154.16.245.246:123 (0.flatcar.pool.ntp.org). Apr 12 18:26:06.498028 systemd-timesyncd[1277]: Initial clock synchronization to Fri 2024-04-12 18:26:06.489596 UTC. Apr 12 18:26:07.909677 augenrules[1293]: No rules Apr 12 18:26:07.908000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Apr 12 18:26:07.911659 systemd[1]: Finished audit-rules.service. Apr 12 18:26:07.908000 audit[1293]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff6fd3e50 a2=420 a3=0 items=0 ppid=1272 pid=1293 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) Apr 12 18:26:07.949744 kernel: audit: type=1305 audit(1712946367.908:176): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Apr 12 18:26:07.949828 kernel: audit: type=1300 audit(1712946367.908:176): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffff6fd3e50 a2=420 a3=0 items=0 ppid=1272 pid=1293 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) Apr 12 18:26:07.949857 kernel: audit: type=1327 audit(1712946367.908:176): proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Apr 12 18:26:07.908000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Apr 12 18:26:23.065953 ldconfig[1257]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Apr 12 18:26:23.082203 systemd[1]: Finished ldconfig.service. Apr 12 18:26:23.087824 systemd[1]: Starting systemd-update-done.service... Apr 12 18:26:23.119684 systemd[1]: Finished systemd-update-done.service. Apr 12 18:26:23.124507 systemd[1]: Reached target sysinit.target. Apr 12 18:26:23.128790 systemd[1]: Started motdgen.path. Apr 12 18:26:23.132428 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Apr 12 18:26:23.138464 systemd[1]: Started logrotate.timer. Apr 12 18:26:23.142302 systemd[1]: Started mdadm.timer. Apr 12 18:26:23.146515 systemd[1]: Started systemd-tmpfiles-clean.timer. Apr 12 18:26:23.151049 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Apr 12 18:26:23.151088 systemd[1]: Reached target paths.target. Apr 12 18:26:23.155095 systemd[1]: Reached target timers.target. Apr 12 18:26:23.159659 systemd[1]: Listening on dbus.socket. Apr 12 18:26:23.164481 systemd[1]: Starting docker.socket... Apr 12 18:26:23.185616 systemd[1]: Listening on sshd.socket. Apr 12 18:26:23.189520 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). Apr 12 18:26:23.189931 systemd[1]: Listening on docker.socket. Apr 12 18:26:23.194069 systemd[1]: Reached target sockets.target. Apr 12 18:26:23.198181 systemd[1]: Reached target basic.target. Apr 12 18:26:23.202225 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Apr 12 18:26:23.202254 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Apr 12 18:26:23.203201 systemd[1]: Starting containerd.service... Apr 12 18:26:23.207620 systemd[1]: Starting dbus.service... Apr 12 18:26:23.211589 systemd[1]: Starting enable-oem-cloudinit.service... Apr 12 18:26:23.216622 systemd[1]: Starting extend-filesystems.service... Apr 12 18:26:23.223223 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Apr 12 18:26:23.224286 systemd[1]: Starting motdgen.service... Apr 12 18:26:23.228648 systemd[1]: Started nvidia.service. Apr 12 18:26:23.233444 systemd[1]: Starting ssh-key-proc-cmdline.service... Apr 12 18:26:23.240772 systemd[1]: Starting sshd-keygen.service... Apr 12 18:26:23.246842 systemd[1]: Starting systemd-logind.service... Apr 12 18:26:23.250823 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Apr 12 18:26:23.250881 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Apr 12 18:26:23.251246 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Apr 12 18:26:23.251833 systemd[1]: Starting update-engine.service... Apr 12 18:26:23.256510 systemd[1]: Starting update-ssh-keys-after-ignition.service... Apr 12 18:26:23.263729 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Apr 12 18:26:23.263896 systemd[1]: Finished ssh-key-proc-cmdline.service. Apr 12 18:26:23.297800 jq[1303]: false Apr 12 18:26:23.298054 jq[1319]: true Apr 12 18:26:23.328532 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Apr 12 18:26:23.328700 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Apr 12 18:26:23.333161 extend-filesystems[1304]: Found sda Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda1 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda2 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda3 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found usr Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda4 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda6 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda7 Apr 12 18:26:23.336846 extend-filesystems[1304]: Found sda9 Apr 12 18:26:23.336846 extend-filesystems[1304]: Checking size of /dev/sda9 Apr 12 18:26:23.377233 jq[1322]: true Apr 12 18:26:23.384783 systemd[1]: Finished update-ssh-keys-after-ignition.service. Apr 12 18:26:23.398133 systemd[1]: motdgen.service: Deactivated successfully. Apr 12 18:26:23.398300 systemd[1]: Finished motdgen.service. Apr 12 18:26:23.412266 systemd-logind[1315]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) Apr 12 18:26:23.414119 systemd-logind[1315]: New seat seat0. Apr 12 18:26:23.467517 env[1345]: time="2024-04-12T18:26:23.467459765Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Apr 12 18:26:23.492410 extend-filesystems[1304]: Old size kept for /dev/sda9 Apr 12 18:26:23.498925 extend-filesystems[1304]: Found sr0 Apr 12 18:26:23.493052 systemd[1]: extend-filesystems.service: Deactivated successfully. Apr 12 18:26:23.493218 systemd[1]: Finished extend-filesystems.service. Apr 12 18:26:23.529370 env[1345]: time="2024-04-12T18:26:23.529326804Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Apr 12 18:26:23.529628 env[1345]: time="2024-04-12T18:26:23.529606474Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.531033 env[1345]: time="2024-04-12T18:26:23.530975505Z" 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.154-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Apr 12 18:26:23.531136 env[1345]: time="2024-04-12T18:26:23.531120519Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.531396 env[1345]: time="2024-04-12T18:26:23.531374192Z" 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 Apr 12 18:26:23.531474 env[1345]: time="2024-04-12T18:26:23.531460177Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.531543 env[1345]: time="2024-04-12T18:26:23.531528604Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Apr 12 18:26:23.531599 env[1345]: time="2024-04-12T18:26:23.531585394Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.531726 env[1345]: time="2024-04-12T18:26:23.531708012Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.532047 env[1345]: time="2024-04-12T18:26:23.531987801Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Apr 12 18:26:23.532270 env[1345]: time="2024-04-12T18:26:23.532248114Z" 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 Apr 12 18:26:23.532338 env[1345]: time="2024-04-12T18:26:23.532325100Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Apr 12 18:26:23.532456 env[1345]: time="2024-04-12T18:26:23.532437679Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Apr 12 18:26:23.532517 env[1345]: time="2024-04-12T18:26:23.532503427Z" level=info msg="metadata content store policy set" policy=shared Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551841914Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551884226Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551897944Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551928498Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551942615Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551958093Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.551970370Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552336304Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552359500Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552372337Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552389094Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552402092Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552523910Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Apr 12 18:26:23.554028 env[1345]: time="2024-04-12T18:26:23.552594137Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552813617Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552837533Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552851490Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552892683Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552906080Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552918798Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552929476Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552941234Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552954272Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552965150Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552976708Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.552989705Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.553135239Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.553152636Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554352 env[1345]: time="2024-04-12T18:26:23.553165193Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554612 env[1345]: time="2024-04-12T18:26:23.553177231Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Apr 12 18:26:23.554612 env[1345]: time="2024-04-12T18:26:23.553192628Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Apr 12 18:26:23.554612 env[1345]: time="2024-04-12T18:26:23.553202986Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Apr 12 18:26:23.554612 env[1345]: time="2024-04-12T18:26:23.553220023Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Apr 12 18:26:23.554612 env[1345]: time="2024-04-12T18:26:23.553253017Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Apr 12 18:26:23.554705 env[1345]: time="2024-04-12T18:26:23.553442903Z" 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}" Apr 12 18:26:23.554705 env[1345]: time="2024-04-12T18:26:23.553493694Z" level=info msg="Connect containerd service" Apr 12 18:26:23.554705 env[1345]: time="2024-04-12T18:26:23.553528887Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.555064728Z" 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" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.555300645Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.555336999Z" level=info msg=serving... address=/run/containerd/containerd.sock Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.555391989Z" level=info msg="containerd successfully booted in 0.088665s" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.561579425Z" level=info msg="Start subscribing containerd event" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.561724198Z" level=info msg="Start recovering state" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.562115527Z" level=info msg="Start event monitor" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.562142442Z" level=info msg="Start snapshots syncer" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.562242904Z" level=info msg="Start cni network conf syncer for default" Apr 12 18:26:23.572066 env[1345]: time="2024-04-12T18:26:23.562256702Z" level=info msg="Start streaming server" Apr 12 18:26:23.555454 systemd[1]: Started containerd.service. Apr 12 18:26:23.625716 systemd[1]: nvidia.service: Deactivated successfully. Apr 12 18:26:23.651186 dbus-daemon[1302]: [system] SELinux support is enabled Apr 12 18:26:23.651340 systemd[1]: Started dbus.service. Apr 12 18:26:23.656632 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). Apr 12 18:26:23.656661 systemd[1]: Reached target system-config.target. Apr 12 18:26:23.657366 dbus-daemon[1302]: [system] Successfully activated service 'org.freedesktop.systemd1' Apr 12 18:26:23.663374 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Apr 12 18:26:23.663401 systemd[1]: Reached target user-config.target. Apr 12 18:26:23.668002 systemd[1]: Started systemd-logind.service. Apr 12 18:26:24.192906 update_engine[1318]: I0412 18:26:24.177459 1318 main.cc:92] Flatcar Update Engine starting Apr 12 18:26:24.248028 systemd[1]: Started update-engine.service. Apr 12 18:26:24.252288 update_engine[1318]: I0412 18:26:24.248050 1318 update_check_scheduler.cc:74] Next update check in 3m10s Apr 12 18:26:24.256833 systemd[1]: Started locksmithd.service. Apr 12 18:26:25.806735 locksmithd[1398]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Apr 12 18:26:26.794897 sshd_keygen[1317]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Apr 12 18:26:26.817857 systemd[1]: Finished sshd-keygen.service. Apr 12 18:26:26.824874 systemd[1]: Starting issuegen.service... Apr 12 18:26:26.830180 systemd[1]: Started waagent.service. Apr 12 18:26:26.834634 systemd[1]: issuegen.service: Deactivated successfully. Apr 12 18:26:26.834802 systemd[1]: Finished issuegen.service. Apr 12 18:26:26.840050 systemd[1]: Starting systemd-user-sessions.service... Apr 12 18:26:26.865915 systemd[1]: Finished systemd-user-sessions.service. Apr 12 18:26:26.872822 systemd[1]: Started getty@tty1.service. Apr 12 18:26:26.878104 systemd[1]: Started serial-getty@ttyAMA0.service. Apr 12 18:26:26.882858 systemd[1]: Reached target getty.target. Apr 12 18:26:26.886877 systemd[1]: Reached target multi-user.target. Apr 12 18:26:26.892384 systemd[1]: Starting systemd-update-utmp-runlevel.service... Apr 12 18:26:26.903421 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Apr 12 18:26:26.903569 systemd[1]: Finished systemd-update-utmp-runlevel.service. Apr 12 18:26:26.914612 systemd[1]: Startup finished in 725ms (kernel) + 13.906s (initrd) + 52.386s (userspace) = 1min 7.019s. Apr 12 18:26:27.694761 login[1416]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 12 18:26:27.695950 login[1417]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Apr 12 18:26:27.747505 systemd[1]: Created slice user-500.slice. Apr 12 18:26:27.748505 systemd[1]: Starting user-runtime-dir@500.service... Apr 12 18:26:27.751335 systemd-logind[1315]: New session 1 of user core. Apr 12 18:26:27.754065 systemd-logind[1315]: New session 2 of user core. Apr 12 18:26:27.795784 systemd[1]: Finished user-runtime-dir@500.service. Apr 12 18:26:27.797161 systemd[1]: Starting user@500.service... Apr 12 18:26:27.830257 (systemd)[1420]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:26:28.128690 systemd[1420]: Queued start job for default target default.target. Apr 12 18:26:28.129545 systemd[1420]: Reached target paths.target. Apr 12 18:26:28.129571 systemd[1420]: Reached target sockets.target. Apr 12 18:26:28.129583 systemd[1420]: Reached target timers.target. Apr 12 18:26:28.129593 systemd[1420]: Reached target basic.target. Apr 12 18:26:28.129635 systemd[1420]: Reached target default.target. Apr 12 18:26:28.129659 systemd[1420]: Startup finished in 293ms. Apr 12 18:26:28.129712 systemd[1]: Started user@500.service. Apr 12 18:26:28.130687 systemd[1]: Started session-1.scope. Apr 12 18:26:28.131246 systemd[1]: Started session-2.scope. Apr 12 18:26:33.434915 waagent[1414]: 2024-04-12T18:26:33.434812Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Apr 12 18:26:33.441659 waagent[1414]: 2024-04-12T18:26:33.441592Z INFO Daemon Daemon OS: flatcar 3510.3.3 Apr 12 18:26:33.446626 waagent[1414]: 2024-04-12T18:26:33.446565Z INFO Daemon Daemon Python: 3.9.16 Apr 12 18:26:33.451230 waagent[1414]: 2024-04-12T18:26:33.451139Z INFO Daemon Daemon Run daemon Apr 12 18:26:33.455485 waagent[1414]: 2024-04-12T18:26:33.455429Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.3' Apr 12 18:26:33.472177 waagent[1414]: 2024-04-12T18:26:33.472072Z 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. Apr 12 18:26:33.486531 waagent[1414]: 2024-04-12T18:26:33.486427Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Apr 12 18:26:33.496107 waagent[1414]: 2024-04-12T18:26:33.496047Z INFO Daemon Daemon cloud-init is enabled: False Apr 12 18:26:33.501118 waagent[1414]: 2024-04-12T18:26:33.501057Z INFO Daemon Daemon Using waagent for provisioning Apr 12 18:26:33.506793 waagent[1414]: 2024-04-12T18:26:33.506735Z INFO Daemon Daemon Activate resource disk Apr 12 18:26:33.512024 waagent[1414]: 2024-04-12T18:26:33.511955Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Apr 12 18:26:33.526052 waagent[1414]: 2024-04-12T18:26:33.525972Z INFO Daemon Daemon Found device: None Apr 12 18:26:33.530571 waagent[1414]: 2024-04-12T18:26:33.530512Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Apr 12 18:26:33.538831 waagent[1414]: 2024-04-12T18:26:33.538774Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Apr 12 18:26:33.550744 waagent[1414]: 2024-04-12T18:26:33.550683Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 12 18:26:33.556612 waagent[1414]: 2024-04-12T18:26:33.556552Z INFO Daemon Daemon Running default provisioning handler Apr 12 18:26:33.569239 waagent[1414]: 2024-04-12T18:26:33.569124Z 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. Apr 12 18:26:33.583764 waagent[1414]: 2024-04-12T18:26:33.583652Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Apr 12 18:26:33.593442 waagent[1414]: 2024-04-12T18:26:33.593379Z INFO Daemon Daemon cloud-init is enabled: False Apr 12 18:26:33.598577 waagent[1414]: 2024-04-12T18:26:33.598513Z INFO Daemon Daemon Copying ovf-env.xml Apr 12 18:26:33.697960 waagent[1414]: 2024-04-12T18:26:33.697767Z INFO Daemon Daemon Successfully mounted dvd Apr 12 18:26:33.834124 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Apr 12 18:26:33.893986 waagent[1414]: 2024-04-12T18:26:33.893838Z INFO Daemon Daemon Detect protocol endpoint Apr 12 18:26:33.899279 waagent[1414]: 2024-04-12T18:26:33.899191Z INFO Daemon Daemon Clean protocol and wireserver endpoint Apr 12 18:26:33.905236 waagent[1414]: 2024-04-12T18:26:33.905156Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Apr 12 18:26:33.911797 waagent[1414]: 2024-04-12T18:26:33.911736Z INFO Daemon Daemon Test for route to 168.63.129.16 Apr 12 18:26:33.917274 waagent[1414]: 2024-04-12T18:26:33.917218Z INFO Daemon Daemon Route to 168.63.129.16 exists Apr 12 18:26:33.922557 waagent[1414]: 2024-04-12T18:26:33.922497Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Apr 12 18:26:34.022112 waagent[1414]: 2024-04-12T18:26:34.021970Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Apr 12 18:26:34.029338 waagent[1414]: 2024-04-12T18:26:34.029292Z INFO Daemon Daemon Wire protocol version:2012-11-30 Apr 12 18:26:34.034861 waagent[1414]: 2024-04-12T18:26:34.034799Z INFO Daemon Daemon Server preferred version:2015-04-05 Apr 12 18:26:34.586187 waagent[1414]: 2024-04-12T18:26:34.586027Z INFO Daemon Daemon Initializing goal state during protocol detection Apr 12 18:26:34.601460 waagent[1414]: 2024-04-12T18:26:34.601390Z INFO Daemon Daemon Forcing an update of the goal state.. Apr 12 18:26:34.607201 waagent[1414]: 2024-04-12T18:26:34.607141Z INFO Daemon Daemon Fetching goal state [incarnation 1] Apr 12 18:26:34.676501 waagent[1414]: 2024-04-12T18:26:34.676377Z INFO Daemon Daemon Found private key matching thumbprint 66A3A40EDABB4C56C34B9CD7B32D9842F7EDE1AF Apr 12 18:26:34.684838 waagent[1414]: 2024-04-12T18:26:34.684767Z INFO Daemon Daemon Certificate with thumbprint 1001F45314AA573ADC096C220484EE90F717F4ED has no matching private key. Apr 12 18:26:34.694244 waagent[1414]: 2024-04-12T18:26:34.694179Z INFO Daemon Daemon Fetch goal state completed Apr 12 18:26:34.732800 waagent[1414]: 2024-04-12T18:26:34.732747Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 02bedd26-2eb9-4ca5-b3bd-8795d1e7d9f5 New eTag: 8796866611733185183] Apr 12 18:26:34.743177 waagent[1414]: 2024-04-12T18:26:34.743116Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Apr 12 18:26:34.757846 waagent[1414]: 2024-04-12T18:26:34.757771Z INFO Daemon Daemon Starting provisioning Apr 12 18:26:34.762894 waagent[1414]: 2024-04-12T18:26:34.762832Z INFO Daemon Daemon Handle ovf-env.xml. Apr 12 18:26:34.767525 waagent[1414]: 2024-04-12T18:26:34.767466Z INFO Daemon Daemon Set hostname [ci-3510.3.3-a-4700c11e05] Apr 12 18:26:34.813284 waagent[1414]: 2024-04-12T18:26:34.813175Z INFO Daemon Daemon Publish hostname [ci-3510.3.3-a-4700c11e05] Apr 12 18:26:34.819782 waagent[1414]: 2024-04-12T18:26:34.819719Z INFO Daemon Daemon Examine /proc/net/route for primary interface Apr 12 18:26:34.826197 waagent[1414]: 2024-04-12T18:26:34.826139Z INFO Daemon Daemon Primary interface is [eth0] Apr 12 18:26:34.841797 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Apr 12 18:26:34.841971 systemd[1]: Stopped systemd-networkd-wait-online.service. Apr 12 18:26:34.842039 systemd[1]: Stopping systemd-networkd-wait-online.service... Apr 12 18:26:34.842279 systemd[1]: Stopping systemd-networkd.service... Apr 12 18:26:34.847055 systemd-networkd[1197]: eth0: DHCPv6 lease lost Apr 12 18:26:34.848389 systemd[1]: systemd-networkd.service: Deactivated successfully. Apr 12 18:26:34.848552 systemd[1]: Stopped systemd-networkd.service. Apr 12 18:26:34.850541 systemd[1]: Starting systemd-networkd.service... Apr 12 18:26:34.877443 systemd-networkd[1464]: enP13257s1: Link UP Apr 12 18:26:34.877454 systemd-networkd[1464]: enP13257s1: Gained carrier Apr 12 18:26:34.878353 systemd-networkd[1464]: eth0: Link UP Apr 12 18:26:34.878363 systemd-networkd[1464]: eth0: Gained carrier Apr 12 18:26:34.878677 systemd-networkd[1464]: lo: Link UP Apr 12 18:26:34.878686 systemd-networkd[1464]: lo: Gained carrier Apr 12 18:26:34.878914 systemd-networkd[1464]: eth0: Gained IPv6LL Apr 12 18:26:34.879196 systemd-networkd[1464]: Enumeration completed Apr 12 18:26:34.879277 systemd[1]: Started systemd-networkd.service. Apr 12 18:26:34.880760 systemd[1]: Starting systemd-networkd-wait-online.service... Apr 12 18:26:34.881655 systemd-networkd[1464]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Apr 12 18:26:34.883263 waagent[1414]: 2024-04-12T18:26:34.881957Z INFO Daemon Daemon Create user account if not exists Apr 12 18:26:34.890742 waagent[1414]: 2024-04-12T18:26:34.888923Z INFO Daemon Daemon User core already exists, skip useradd Apr 12 18:26:34.894582 waagent[1414]: 2024-04-12T18:26:34.894519Z INFO Daemon Daemon Configure sudoer Apr 12 18:26:34.899776 waagent[1414]: 2024-04-12T18:26:34.899710Z INFO Daemon Daemon Configure sshd Apr 12 18:26:34.904048 waagent[1414]: 2024-04-12T18:26:34.903970Z INFO Daemon Daemon Deploy ssh public key. Apr 12 18:26:34.912075 systemd-networkd[1464]: eth0: DHCPv4 address 10.200.20.31/24, gateway 10.200.20.1 acquired from 168.63.129.16 Apr 12 18:26:34.914359 systemd[1]: Finished systemd-networkd-wait-online.service. Apr 12 18:26:36.193899 waagent[1414]: 2024-04-12T18:26:36.193805Z INFO Daemon Daemon Provisioning complete Apr 12 18:26:36.210755 waagent[1414]: 2024-04-12T18:26:36.210691Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Apr 12 18:26:36.217056 waagent[1414]: 2024-04-12T18:26:36.216979Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Apr 12 18:26:36.227743 waagent[1414]: 2024-04-12T18:26:36.227680Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Apr 12 18:26:36.519897 waagent[1473]: 2024-04-12T18:26:36.519755Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Apr 12 18:26:36.520967 waagent[1473]: 2024-04-12T18:26:36.520909Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:36.521229 waagent[1473]: 2024-04-12T18:26:36.521179Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:36.533527 waagent[1473]: 2024-04-12T18:26:36.533461Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Apr 12 18:26:36.533797 waagent[1473]: 2024-04-12T18:26:36.533747Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Apr 12 18:26:36.597648 waagent[1473]: 2024-04-12T18:26:36.597530Z INFO ExtHandler ExtHandler Found private key matching thumbprint 66A3A40EDABB4C56C34B9CD7B32D9842F7EDE1AF Apr 12 18:26:36.598030 waagent[1473]: 2024-04-12T18:26:36.597941Z INFO ExtHandler ExtHandler Certificate with thumbprint 1001F45314AA573ADC096C220484EE90F717F4ED has no matching private key. Apr 12 18:26:36.598364 waagent[1473]: 2024-04-12T18:26:36.598311Z INFO ExtHandler ExtHandler Fetch goal state completed Apr 12 18:26:36.613750 waagent[1473]: 2024-04-12T18:26:36.613703Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: db52014e-4736-41cf-8712-0e79c3820f12 New eTag: 8796866611733185183] Apr 12 18:26:36.614381 waagent[1473]: 2024-04-12T18:26:36.614324Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Apr 12 18:26:36.712607 waagent[1473]: 2024-04-12T18:26:36.712476Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.3; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Apr 12 18:26:36.722699 waagent[1473]: 2024-04-12T18:26:36.722630Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1473 Apr 12 18:26:36.726505 waagent[1473]: 2024-04-12T18:26:36.726444Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.3', '', 'Flatcar Container Linux by Kinvolk'] Apr 12 18:26:36.727907 waagent[1473]: 2024-04-12T18:26:36.727850Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Apr 12 18:26:36.891075 waagent[1473]: 2024-04-12T18:26:36.890947Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Apr 12 18:26:36.891604 waagent[1473]: 2024-04-12T18:26:36.891547Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Apr 12 18:26:36.899377 waagent[1473]: 2024-04-12T18:26:36.899329Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Apr 12 18:26:36.899938 waagent[1473]: 2024-04-12T18:26:36.899884Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Apr 12 18:26:36.901185 waagent[1473]: 2024-04-12T18:26:36.901121Z 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] Apr 12 18:26:36.902596 waagent[1473]: 2024-04-12T18:26:36.902529Z INFO ExtHandler ExtHandler Starting env monitor service. Apr 12 18:26:36.902961 waagent[1473]: 2024-04-12T18:26:36.902889Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:36.903442 waagent[1473]: 2024-04-12T18:26:36.903374Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:36.904025 waagent[1473]: 2024-04-12T18:26:36.903934Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Apr 12 18:26:36.904340 waagent[1473]: 2024-04-12T18:26:36.904279Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Apr 12 18:26:36.904340 waagent[1473]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Apr 12 18:26:36.904340 waagent[1473]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Apr 12 18:26:36.904340 waagent[1473]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Apr 12 18:26:36.904340 waagent[1473]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:36.904340 waagent[1473]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:36.904340 waagent[1473]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:36.906370 waagent[1473]: 2024-04-12T18:26:36.906210Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Apr 12 18:26:36.907265 waagent[1473]: 2024-04-12T18:26:36.907197Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:36.907540 waagent[1473]: 2024-04-12T18:26:36.907489Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:36.908203 waagent[1473]: 2024-04-12T18:26:36.908136Z INFO EnvHandler ExtHandler Configure routes Apr 12 18:26:36.908333 waagent[1473]: 2024-04-12T18:26:36.908264Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Apr 12 18:26:36.908671 waagent[1473]: 2024-04-12T18:26:36.908599Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Apr 12 18:26:36.908935 waagent[1473]: 2024-04-12T18:26:36.908875Z INFO EnvHandler ExtHandler Gateway:None Apr 12 18:26:36.909408 waagent[1473]: 2024-04-12T18:26:36.909345Z INFO EnvHandler ExtHandler Routes:None Apr 12 18:26:36.910473 waagent[1473]: 2024-04-12T18:26:36.910408Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Apr 12 18:26:36.910546 waagent[1473]: 2024-04-12T18:26:36.910494Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Apr 12 18:26:36.910916 waagent[1473]: 2024-04-12T18:26:36.910851Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Apr 12 18:26:36.920597 waagent[1473]: 2024-04-12T18:26:36.920528Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Apr 12 18:26:36.921307 waagent[1473]: 2024-04-12T18:26:36.921244Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Apr 12 18:26:36.924058 waagent[1473]: 2024-04-12T18:26:36.923965Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Apr 12 18:26:36.951866 waagent[1473]: 2024-04-12T18:26:36.951762Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1464' Apr 12 18:26:36.967548 waagent[1473]: 2024-04-12T18:26:36.967482Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Apr 12 18:26:37.039434 waagent[1473]: 2024-04-12T18:26:37.039311Z INFO MonitorHandler ExtHandler Network interfaces: Apr 12 18:26:37.039434 waagent[1473]: Executing ['ip', '-a', '-o', 'link']: Apr 12 18:26:37.039434 waagent[1473]: 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 Apr 12 18:26:37.039434 waagent[1473]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:01:5f:c7 brd ff:ff:ff:ff:ff:ff Apr 12 18:26:37.039434 waagent[1473]: 3: enP13257s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:01:5f:c7 brd ff:ff:ff:ff:ff:ff\ altname enP13257p0s2 Apr 12 18:26:37.039434 waagent[1473]: Executing ['ip', '-4', '-a', '-o', 'address']: Apr 12 18:26:37.039434 waagent[1473]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Apr 12 18:26:37.039434 waagent[1473]: 2: eth0 inet 10.200.20.31/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Apr 12 18:26:37.039434 waagent[1473]: Executing ['ip', '-6', '-a', '-o', 'address']: Apr 12 18:26:37.039434 waagent[1473]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Apr 12 18:26:37.039434 waagent[1473]: 2: eth0 inet6 fe80::20d:3aff:fe01:5fc7/64 scope link \ valid_lft forever preferred_lft forever Apr 12 18:26:37.149750 waagent[1473]: 2024-04-12T18:26:37.149647Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.10.0.8 -- exiting Apr 12 18:26:37.231043 waagent[1414]: 2024-04-12T18:26:37.230906Z 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 Apr 12 18:26:37.235163 waagent[1414]: 2024-04-12T18:26:37.235100Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.10.0.8 to be the latest agent Apr 12 18:26:38.393694 waagent[1502]: 2024-04-12T18:26:38.393596Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.10.0.8) Apr 12 18:26:38.394393 waagent[1502]: 2024-04-12T18:26:38.394334Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.3 Apr 12 18:26:38.394526 waagent[1502]: 2024-04-12T18:26:38.394479Z INFO ExtHandler ExtHandler Python: 3.9.16 Apr 12 18:26:38.394651 waagent[1502]: 2024-04-12T18:26:38.394607Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Apr 12 18:26:38.402463 waagent[1502]: 2024-04-12T18:26:38.402353Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.3; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Apr 12 18:26:38.402844 waagent[1502]: 2024-04-12T18:26:38.402787Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:38.402987 waagent[1502]: 2024-04-12T18:26:38.402942Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:38.416055 waagent[1502]: 2024-04-12T18:26:38.415974Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Apr 12 18:26:38.427429 waagent[1502]: 2024-04-12T18:26:38.427370Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.149 Apr 12 18:26:38.428451 waagent[1502]: 2024-04-12T18:26:38.428390Z INFO ExtHandler Apr 12 18:26:38.428600 waagent[1502]: 2024-04-12T18:26:38.428552Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: b9f330d4-8d99-4a4c-abe2-6975ed7d6c64 eTag: 8796866611733185183 source: Fabric] Apr 12 18:26:38.429348 waagent[1502]: 2024-04-12T18:26:38.429290Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Apr 12 18:26:38.430599 waagent[1502]: 2024-04-12T18:26:38.430537Z INFO ExtHandler Apr 12 18:26:38.430734 waagent[1502]: 2024-04-12T18:26:38.430687Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Apr 12 18:26:38.436932 waagent[1502]: 2024-04-12T18:26:38.436883Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Apr 12 18:26:38.437409 waagent[1502]: 2024-04-12T18:26:38.437358Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Apr 12 18:26:38.457487 waagent[1502]: 2024-04-12T18:26:38.457430Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Apr 12 18:26:38.526395 waagent[1502]: 2024-04-12T18:26:38.526261Z INFO ExtHandler Downloaded certificate {'thumbprint': '66A3A40EDABB4C56C34B9CD7B32D9842F7EDE1AF', 'hasPrivateKey': True} Apr 12 18:26:38.527436 waagent[1502]: 2024-04-12T18:26:38.527376Z INFO ExtHandler Downloaded certificate {'thumbprint': '1001F45314AA573ADC096C220484EE90F717F4ED', 'hasPrivateKey': False} Apr 12 18:26:38.528488 waagent[1502]: 2024-04-12T18:26:38.528427Z INFO ExtHandler Fetch goal state completed Apr 12 18:26:38.550451 waagent[1502]: 2024-04-12T18:26:38.550350Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.7 1 Nov 2022 (Library: OpenSSL 3.0.7 1 Nov 2022) Apr 12 18:26:38.562267 waagent[1502]: 2024-04-12T18:26:38.562180Z INFO ExtHandler ExtHandler WALinuxAgent-2.10.0.8 running as process 1502 Apr 12 18:26:38.565925 waagent[1502]: 2024-04-12T18:26:38.565860Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.3', '', 'Flatcar Container Linux by Kinvolk'] Apr 12 18:26:38.567436 waagent[1502]: 2024-04-12T18:26:38.567372Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Apr 12 18:26:38.572159 waagent[1502]: 2024-04-12T18:26:38.572097Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Apr 12 18:26:38.572545 waagent[1502]: 2024-04-12T18:26:38.572487Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Apr 12 18:26:38.580056 waagent[1502]: 2024-04-12T18:26:38.579973Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Apr 12 18:26:38.580536 waagent[1502]: 2024-04-12T18:26:38.580474Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Apr 12 18:26:38.586322 waagent[1502]: 2024-04-12T18:26:38.586213Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Apr 12 18:26:38.587350 waagent[1502]: 2024-04-12T18:26:38.587285Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True] Apr 12 18:26:38.588913 waagent[1502]: 2024-04-12T18:26:38.588837Z INFO ExtHandler ExtHandler Starting env monitor service. Apr 12 18:26:38.589578 waagent[1502]: 2024-04-12T18:26:38.589516Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:38.589828 waagent[1502]: 2024-04-12T18:26:38.589779Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:38.590555 waagent[1502]: 2024-04-12T18:26:38.590497Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Apr 12 18:26:38.590946 waagent[1502]: 2024-04-12T18:26:38.590891Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Apr 12 18:26:38.590946 waagent[1502]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Apr 12 18:26:38.590946 waagent[1502]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Apr 12 18:26:38.590946 waagent[1502]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Apr 12 18:26:38.590946 waagent[1502]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:38.590946 waagent[1502]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:38.590946 waagent[1502]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Apr 12 18:26:38.593528 waagent[1502]: 2024-04-12T18:26:38.593412Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Apr 12 18:26:38.596077 waagent[1502]: 2024-04-12T18:26:38.595870Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Apr 12 18:26:38.596398 waagent[1502]: 2024-04-12T18:26:38.596334Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Apr 12 18:26:38.597214 waagent[1502]: 2024-04-12T18:26:38.597143Z INFO EnvHandler ExtHandler Configure routes Apr 12 18:26:38.597373 waagent[1502]: 2024-04-12T18:26:38.597325Z INFO EnvHandler ExtHandler Gateway:None Apr 12 18:26:38.597493 waagent[1502]: 2024-04-12T18:26:38.597450Z INFO EnvHandler ExtHandler Routes:None Apr 12 18:26:38.598409 waagent[1502]: 2024-04-12T18:26:38.598349Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Apr 12 18:26:38.598705 waagent[1502]: 2024-04-12T18:26:38.598642Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Apr 12 18:26:38.599247 waagent[1502]: 2024-04-12T18:26:38.599171Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Apr 12 18:26:38.599363 waagent[1502]: 2024-04-12T18:26:38.599296Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Apr 12 18:26:38.600762 waagent[1502]: 2024-04-12T18:26:38.600694Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Apr 12 18:26:38.613310 waagent[1502]: 2024-04-12T18:26:38.613228Z INFO ExtHandler ExtHandler Downloading agent manifest Apr 12 18:26:38.627663 waagent[1502]: 2024-04-12T18:26:38.627574Z INFO ExtHandler ExtHandler Apr 12 18:26:38.627925 waagent[1502]: 2024-04-12T18:26:38.627863Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 18c2d736-8603-49a1-a077-477a95d53b4f correlation f4425443-1ceb-4cda-95c6-f5e24c8a2164 created: 2024-04-12T18:24:28.828468Z] Apr 12 18:26:38.630737 waagent[1502]: 2024-04-12T18:26:38.630665Z INFO MonitorHandler ExtHandler Network interfaces: Apr 12 18:26:38.630737 waagent[1502]: Executing ['ip', '-a', '-o', 'link']: Apr 12 18:26:38.630737 waagent[1502]: 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 Apr 12 18:26:38.630737 waagent[1502]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:01:5f:c7 brd ff:ff:ff:ff:ff:ff Apr 12 18:26:38.630737 waagent[1502]: 3: enP13257s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:01:5f:c7 brd ff:ff:ff:ff:ff:ff\ altname enP13257p0s2 Apr 12 18:26:38.630737 waagent[1502]: Executing ['ip', '-4', '-a', '-o', 'address']: Apr 12 18:26:38.630737 waagent[1502]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Apr 12 18:26:38.630737 waagent[1502]: 2: eth0 inet 10.200.20.31/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Apr 12 18:26:38.630737 waagent[1502]: Executing ['ip', '-6', '-a', '-o', 'address']: Apr 12 18:26:38.630737 waagent[1502]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Apr 12 18:26:38.630737 waagent[1502]: 2: eth0 inet6 fe80::20d:3aff:fe01:5fc7/64 scope link \ valid_lft forever preferred_lft forever Apr 12 18:26:38.636367 waagent[1502]: 2024-04-12T18:26:38.636269Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Apr 12 18:26:38.645939 waagent[1502]: 2024-04-12T18:26:38.645814Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 18 ms] Apr 12 18:26:38.668521 waagent[1502]: 2024-04-12T18:26:38.668462Z INFO ExtHandler ExtHandler Looking for existing remote access users. Apr 12 18:26:38.711574 waagent[1502]: 2024-04-12T18:26:38.711499Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.10.0.8 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 25D031AF-03C9-4727-9858-7781B5F4CC70;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1] Apr 12 18:26:38.858034 waagent[1502]: 2024-04-12T18:26:38.857884Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules: Apr 12 18:26:38.858034 waagent[1502]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.858034 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.858034 waagent[1502]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.858034 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.858034 waagent[1502]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.858034 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.858034 waagent[1502]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 12 18:26:38.858034 waagent[1502]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 12 18:26:38.858034 waagent[1502]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 12 18:26:38.865407 waagent[1502]: 2024-04-12T18:26:38.865294Z INFO EnvHandler ExtHandler Current Firewall rules: Apr 12 18:26:38.865407 waagent[1502]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.865407 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.865407 waagent[1502]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.865407 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.865407 waagent[1502]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Apr 12 18:26:38.865407 waagent[1502]: pkts bytes target prot opt in out source destination Apr 12 18:26:38.865407 waagent[1502]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Apr 12 18:26:38.865407 waagent[1502]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Apr 12 18:26:38.865407 waagent[1502]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Apr 12 18:26:38.866222 waagent[1502]: 2024-04-12T18:26:38.866172Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Apr 12 18:26:48.230349 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Apr 12 18:27:09.902076 update_engine[1318]: I0412 18:27:09.902035 1318 update_attempter.cc:509] Updating boot flags... Apr 12 18:27:50.973741 systemd[1]: Created slice system-sshd.slice. Apr 12 18:27:50.974786 systemd[1]: Started sshd@0-10.200.20.31:22-10.200.12.6:53098.service. Apr 12 18:27:51.722801 sshd[1623]: Accepted publickey for core from 10.200.12.6 port 53098 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:51.763249 sshd[1623]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:51.767721 systemd[1]: Started session-3.scope. Apr 12 18:27:51.768728 systemd-logind[1315]: New session 3 of user core. Apr 12 18:27:52.107210 systemd[1]: Started sshd@1-10.200.20.31:22-10.200.12.6:53100.service. Apr 12 18:27:52.515053 sshd[1628]: Accepted publickey for core from 10.200.12.6 port 53100 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:52.516680 sshd[1628]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:52.520040 systemd-logind[1315]: New session 4 of user core. Apr 12 18:27:52.520593 systemd[1]: Started session-4.scope. Apr 12 18:27:52.825903 sshd[1628]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:52.828187 systemd[1]: sshd@1-10.200.20.31:22-10.200.12.6:53100.service: Deactivated successfully. Apr 12 18:27:52.828830 systemd[1]: session-4.scope: Deactivated successfully. Apr 12 18:27:52.829422 systemd-logind[1315]: Session 4 logged out. Waiting for processes to exit. Apr 12 18:27:52.830347 systemd-logind[1315]: Removed session 4. Apr 12 18:27:52.894552 systemd[1]: Started sshd@2-10.200.20.31:22-10.200.12.6:53114.service. Apr 12 18:27:53.294234 sshd[1634]: Accepted publickey for core from 10.200.12.6 port 53114 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:53.295765 sshd[1634]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:53.299709 systemd[1]: Started session-5.scope. Apr 12 18:27:53.300973 systemd-logind[1315]: New session 5 of user core. Apr 12 18:27:53.600814 sshd[1634]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:53.603387 systemd[1]: sshd@2-10.200.20.31:22-10.200.12.6:53114.service: Deactivated successfully. Apr 12 18:27:53.604046 systemd[1]: session-5.scope: Deactivated successfully. Apr 12 18:27:53.604539 systemd-logind[1315]: Session 5 logged out. Waiting for processes to exit. Apr 12 18:27:53.605309 systemd-logind[1315]: Removed session 5. Apr 12 18:27:53.668574 systemd[1]: Started sshd@3-10.200.20.31:22-10.200.12.6:53130.service. Apr 12 18:27:54.076642 sshd[1640]: Accepted publickey for core from 10.200.12.6 port 53130 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:54.077847 sshd[1640]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:54.081559 systemd-logind[1315]: New session 6 of user core. Apr 12 18:27:54.081965 systemd[1]: Started session-6.scope. Apr 12 18:27:54.387302 sshd[1640]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:54.389909 systemd[1]: sshd@3-10.200.20.31:22-10.200.12.6:53130.service: Deactivated successfully. Apr 12 18:27:54.390560 systemd[1]: session-6.scope: Deactivated successfully. Apr 12 18:27:54.391098 systemd-logind[1315]: Session 6 logged out. Waiting for processes to exit. Apr 12 18:27:54.391896 systemd-logind[1315]: Removed session 6. Apr 12 18:27:54.454172 systemd[1]: Started sshd@4-10.200.20.31:22-10.200.12.6:53136.service. Apr 12 18:27:54.858902 sshd[1646]: Accepted publickey for core from 10.200.12.6 port 53136 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:54.860401 sshd[1646]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:54.864348 systemd[1]: Started session-7.scope. Apr 12 18:27:54.864849 systemd-logind[1315]: New session 7 of user core. Apr 12 18:27:55.407425 sudo[1649]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Apr 12 18:27:55.407942 sudo[1649]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Apr 12 18:27:55.467128 dbus-daemon[1302]: avc: received setenforce notice (enforcing=1) Apr 12 18:27:55.468062 sudo[1649]: pam_unix(sudo:session): session closed for user root Apr 12 18:27:55.564857 sshd[1646]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:55.567808 systemd[1]: sshd@4-10.200.20.31:22-10.200.12.6:53136.service: Deactivated successfully. Apr 12 18:27:55.568554 systemd[1]: session-7.scope: Deactivated successfully. Apr 12 18:27:55.569343 systemd-logind[1315]: Session 7 logged out. Waiting for processes to exit. Apr 12 18:27:55.570294 systemd-logind[1315]: Removed session 7. Apr 12 18:27:55.639870 systemd[1]: Started sshd@5-10.200.20.31:22-10.200.12.6:59998.service. Apr 12 18:27:56.052458 sshd[1653]: Accepted publickey for core from 10.200.12.6 port 59998 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:56.054125 sshd[1653]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:56.057979 systemd-logind[1315]: New session 8 of user core. Apr 12 18:27:56.058461 systemd[1]: Started session-8.scope. Apr 12 18:27:56.285460 sudo[1657]: 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 Apr 12 18:27:56.285972 sudo[1657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Apr 12 18:27:56.288542 sudo[1657]: pam_unix(sudo:session): session closed for user root Apr 12 18:27:56.292673 sudo[1656]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Apr 12 18:27:56.292864 sudo[1656]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Apr 12 18:27:56.300864 systemd[1]: Stopping audit-rules.service... Apr 12 18:27:56.301000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Apr 12 18:27:56.301000 audit[1660]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe3eeac10 a2=420 a3=0 items=0 ppid=1 pid=1660 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) Apr 12 18:27:56.312986 auditctl[1660]: No rules Apr 12 18:27:56.313496 systemd[1]: audit-rules.service: Deactivated successfully. Apr 12 18:27:56.313654 systemd[1]: Stopped audit-rules.service. Apr 12 18:27:56.315193 systemd[1]: Starting audit-rules.service... Apr 12 18:27:56.335184 kernel: audit: type=1305 audit(1712946476.301:177): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Apr 12 18:27:56.335275 kernel: audit: type=1300 audit(1712946476.301:177): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe3eeac10 a2=420 a3=0 items=0 ppid=1 pid=1660 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) Apr 12 18:27:56.301000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Apr 12 18:27:56.342718 kernel: audit: type=1327 audit(1712946476.301:177): proctitle=2F7362696E2F617564697463746C002D44 Apr 12 18:27:56.311000 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' Apr 12 18:27:56.358116 kernel: audit: type=1131 audit(1712946476.311:178): 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' Apr 12 18:27:56.365395 augenrules[1677]: No rules Apr 12 18:27:56.366340 systemd[1]: Finished audit-rules.service. Apr 12 18:27:56.367150 sudo[1656]: pam_unix(sudo:session): session closed for user root Apr 12 18:27:56.365000 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' Apr 12 18:27:56.366000 audit[1656]: USER_END pid=1656 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' Apr 12 18:27:56.401012 kernel: audit: type=1130 audit(1712946476.365:179): 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' Apr 12 18:27:56.401136 kernel: audit: type=1106 audit(1712946476.366:180): pid=1656 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' Apr 12 18:27:56.401170 kernel: audit: type=1104 audit(1712946476.366:181): pid=1656 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' Apr 12 18:27:56.366000 audit[1656]: CRED_DISP pid=1656 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' Apr 12 18:27:56.447826 sshd[1653]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:56.447000 audit[1653]: USER_END pid=1653 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.471270 systemd[1]: sshd@5-10.200.20.31:22-10.200.12.6:59998.service: Deactivated successfully. Apr 12 18:27:56.447000 audit[1653]: CRED_DISP pid=1653 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.488659 kernel: audit: type=1106 audit(1712946476.447:182): pid=1653 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.488724 kernel: audit: type=1104 audit(1712946476.447:183): pid=1653 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.471966 systemd[1]: session-8.scope: Deactivated successfully. Apr 12 18:27:56.470000 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.31:22-10.200.12.6:59998 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:56.489297 systemd-logind[1315]: Session 8 logged out. Waiting for processes to exit. Apr 12 18:27:56.506602 kernel: audit: type=1131 audit(1712946476.470:184): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.31:22-10.200.12.6:59998 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:56.507140 systemd-logind[1315]: Removed session 8. Apr 12 18:27:56.537798 systemd[1]: Started sshd@6-10.200.20.31:22-10.200.12.6:60014.service. Apr 12 18:27:56.536000 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.31:22-10.200.12.6:60014 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:56.944000 audit[1683]: USER_ACCT pid=1683 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.945678 sshd[1683]: Accepted publickey for core from 10.200.12.6 port 60014 ssh2: RSA SHA256:FwI9mp8Uipvmjkr+VYh+76kYXjtYhCPwjtuEb1G3LpI Apr 12 18:27:56.945000 audit[1683]: CRED_ACQ pid=1683 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.945000 audit[1683]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd5559870 a2=3 a3=1 items=0 ppid=1 pid=1683 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) Apr 12 18:27:56.945000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Apr 12 18:27:56.947225 sshd[1683]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Apr 12 18:27:56.951350 systemd[1]: Started session-9.scope. Apr 12 18:27:56.952056 systemd-logind[1315]: New session 9 of user core. Apr 12 18:27:56.954000 audit[1683]: USER_START pid=1683 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:56.955000 audit[1685]: CRED_ACQ pid=1685 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:57.179000 audit[1688]: USER_ACCT pid=1688 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' Apr 12 18:27:57.179000 audit[1688]: CRED_REFR pid=1688 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' Apr 12 18:27:57.180127 sudo[1688]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/tee /etc/flatcar/update.conf Apr 12 18:27:57.180324 sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Apr 12 18:27:57.180000 audit[1688]: USER_START pid=1688 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' Apr 12 18:27:57.221000 audit[1688]: USER_END pid=1688 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' Apr 12 18:27:57.221000 audit[1688]: CRED_DISP pid=1688 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' Apr 12 18:27:57.222356 sudo[1688]: pam_unix(sudo:session): session closed for user root Apr 12 18:27:57.225500 systemd[1]: Starting motdgen.service... Apr 12 18:27:57.232961 systemd[1]: motdgen.service: Deactivated successfully. Apr 12 18:27:57.233116 systemd[1]: Finished motdgen.service. Apr 12 18:27:57.232000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=motdgen comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:57.232000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=motdgen comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:57.285052 sshd[1683]: pam_unix(sshd:session): session closed for user core Apr 12 18:27:57.285000 audit[1683]: USER_END pid=1683 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:57.285000 audit[1683]: CRED_DISP 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.12.6 addr=10.200.12.6 terminal=ssh res=success' Apr 12 18:27:57.287337 systemd[1]: sshd@6-10.200.20.31:22-10.200.12.6:60014.service: Deactivated successfully. Apr 12 18:27:57.286000 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.31:22-10.200.12.6:60014 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Apr 12 18:27:57.287977 systemd[1]: session-9.scope: Deactivated successfully. Apr 12 18:27:57.288496 systemd-logind[1315]: Session 9 logged out. Waiting for processes to exit. Apr 12 18:27:57.289278 systemd-logind[1315]: Removed session 9.