Mar 17 18:48:48.057684 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1] Mar 17 18:48:48.057702 kernel: Linux version 5.15.179-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Mon Mar 17 17:11:44 -00 2025 Mar 17 18:48:48.057710 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '') Mar 17 18:48:48.057717 kernel: printk: bootconsole [pl11] enabled Mar 17 18:48:48.057722 kernel: efi: EFI v2.70 by EDK II Mar 17 18:48:48.057727 kernel: efi: ACPI 2.0=0x3fd5f018 SMBIOS=0x3e580000 SMBIOS 3.0=0x3e560000 MEMATTR=0x3f214018 RNG=0x3fd5f998 MEMRESERVE=0x3763cf98 Mar 17 18:48:48.057734 kernel: random: crng init done Mar 17 18:48:48.057739 kernel: ACPI: Early table checksum verification disabled Mar 17 18:48:48.057745 kernel: ACPI: RSDP 0x000000003FD5F018 000024 (v02 VRTUAL) Mar 17 18:48:48.057750 kernel: ACPI: XSDT 0x000000003FD5FF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057755 kernel: ACPI: FACP 0x000000003FD5FC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057761 kernel: ACPI: DSDT 0x000000003FD41018 01DFCD (v02 MSFTVM DSDT01 00000001 INTL 20230628) Mar 17 18:48:48.057767 kernel: ACPI: DBG2 0x000000003FD5FB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057773 kernel: ACPI: GTDT 0x000000003FD5FD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057779 kernel: ACPI: OEM0 0x000000003FD5F098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057785 kernel: ACPI: SPCR 0x000000003FD5FA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057791 kernel: ACPI: APIC 0x000000003FD5F818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057798 kernel: ACPI: SRAT 0x000000003FD5F198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057804 kernel: ACPI: PPTT 0x000000003FD5F418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000) Mar 17 18:48:48.057809 kernel: ACPI: BGRT 0x000000003FD5FE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001) Mar 17 18:48:48.057815 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200 Mar 17 18:48:48.057821 kernel: NUMA: Failed to initialise from firmware Mar 17 18:48:48.057826 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff] Mar 17 18:48:48.057832 kernel: NUMA: NODE_DATA [mem 0x1bf7f3900-0x1bf7f8fff] Mar 17 18:48:48.057838 kernel: Zone ranges: Mar 17 18:48:48.057843 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff] Mar 17 18:48:48.057849 kernel: DMA32 empty Mar 17 18:48:48.057855 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 18:48:48.057862 kernel: Movable zone start for each node Mar 17 18:48:48.060922 kernel: Early memory node ranges Mar 17 18:48:48.060931 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff] Mar 17 18:48:48.060938 kernel: node 0: [mem 0x0000000000824000-0x000000003e54ffff] Mar 17 18:48:48.060944 kernel: node 0: [mem 0x000000003e550000-0x000000003e87ffff] Mar 17 18:48:48.060949 kernel: node 0: [mem 0x000000003e880000-0x000000003fc7ffff] Mar 17 18:48:48.060955 kernel: node 0: [mem 0x000000003fc80000-0x000000003fcfffff] Mar 17 18:48:48.060961 kernel: node 0: [mem 0x000000003fd00000-0x000000003fffffff] Mar 17 18:48:48.060967 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff] Mar 17 18:48:48.060973 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff] Mar 17 18:48:48.060978 kernel: On node 0, zone DMA: 36 pages in unavailable ranges Mar 17 18:48:48.060985 kernel: psci: probing for conduit method from ACPI. Mar 17 18:48:48.060997 kernel: psci: PSCIv1.1 detected in firmware. Mar 17 18:48:48.061003 kernel: psci: Using standard PSCI v0.2 function IDs Mar 17 18:48:48.061013 kernel: psci: MIGRATE_INFO_TYPE not supported. Mar 17 18:48:48.061020 kernel: psci: SMC Calling Convention v1.4 Mar 17 18:48:48.061026 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1 Mar 17 18:48:48.061034 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1 Mar 17 18:48:48.061040 kernel: percpu: Embedded 30 pages/cpu s83032 r8192 d31656 u122880 Mar 17 18:48:48.061046 kernel: pcpu-alloc: s83032 r8192 d31656 u122880 alloc=30*4096 Mar 17 18:48:48.061052 kernel: pcpu-alloc: [0] 0 [0] 1 Mar 17 18:48:48.061058 kernel: Detected PIPT I-cache on CPU0 Mar 17 18:48:48.061064 kernel: CPU features: detected: GIC system register CPU interface Mar 17 18:48:48.061070 kernel: CPU features: detected: Hardware dirty bit management Mar 17 18:48:48.061076 kernel: CPU features: detected: Spectre-BHB Mar 17 18:48:48.061083 kernel: CPU features: kernel page table isolation forced ON by KASLR Mar 17 18:48:48.061089 kernel: CPU features: detected: Kernel page table isolation (KPTI) Mar 17 18:48:48.061095 kernel: CPU features: detected: ARM erratum 1418040 Mar 17 18:48:48.061102 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion) Mar 17 18:48:48.061108 kernel: CPU features: detected: SSBS not fully self-synchronizing Mar 17 18:48:48.061114 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156 Mar 17 18:48:48.061120 kernel: Policy zone: Normal Mar 17 18:48:48.061128 kernel: Kernel command line: BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:48:48.061135 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space. Mar 17 18:48:48.061141 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) Mar 17 18:48:48.061147 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear) Mar 17 18:48:48.061153 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off Mar 17 18:48:48.061159 kernel: software IO TLB: mapped [mem 0x000000003a550000-0x000000003e550000] (64MB) Mar 17 18:48:48.061166 kernel: Memory: 3986944K/4194160K available (9792K kernel code, 2094K rwdata, 7584K rodata, 36416K init, 777K bss, 207216K reserved, 0K cma-reserved) Mar 17 18:48:48.061173 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 Mar 17 18:48:48.061179 kernel: trace event string verifier disabled Mar 17 18:48:48.061186 kernel: rcu: Preemptible hierarchical RCU implementation. Mar 17 18:48:48.061192 kernel: rcu: RCU event tracing is enabled. Mar 17 18:48:48.061198 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2. Mar 17 18:48:48.061205 kernel: Trampoline variant of Tasks RCU enabled. Mar 17 18:48:48.061211 kernel: Tracing variant of Tasks RCU enabled. Mar 17 18:48:48.061217 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. Mar 17 18:48:48.061223 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 Mar 17 18:48:48.061229 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 Mar 17 18:48:48.061235 kernel: GICv3: 960 SPIs implemented Mar 17 18:48:48.061242 kernel: GICv3: 0 Extended SPIs implemented Mar 17 18:48:48.061248 kernel: GICv3: Distributor has no Range Selector support Mar 17 18:48:48.061254 kernel: Root IRQ handler: gic_handle_irq Mar 17 18:48:48.061260 kernel: GICv3: 16 PPIs implemented Mar 17 18:48:48.061266 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000 Mar 17 18:48:48.061272 kernel: ITS: No ITS available, not enabling LPIs Mar 17 18:48:48.061279 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:48:48.061285 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt). Mar 17 18:48:48.061291 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns Mar 17 18:48:48.061297 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns Mar 17 18:48:48.061304 kernel: Console: colour dummy device 80x25 Mar 17 18:48:48.061311 kernel: printk: console [tty1] enabled Mar 17 18:48:48.061318 kernel: ACPI: Core revision 20210730 Mar 17 18:48:48.061324 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000) Mar 17 18:48:48.061331 kernel: pid_max: default: 32768 minimum: 301 Mar 17 18:48:48.061337 kernel: LSM: Security Framework initializing Mar 17 18:48:48.061343 kernel: SELinux: Initializing. Mar 17 18:48:48.061350 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:48:48.061356 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear) Mar 17 18:48:48.061362 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1 Mar 17 18:48:48.061370 kernel: Hyper-V: Host Build 10.0.22477.1619-1-0 Mar 17 18:48:48.061376 kernel: rcu: Hierarchical SRCU implementation. Mar 17 18:48:48.061382 kernel: Remapping and enabling EFI services. Mar 17 18:48:48.061389 kernel: smp: Bringing up secondary CPUs ... Mar 17 18:48:48.061395 kernel: Detected PIPT I-cache on CPU1 Mar 17 18:48:48.061401 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000 Mar 17 18:48:48.061408 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040 Mar 17 18:48:48.061414 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1] Mar 17 18:48:48.061420 kernel: smp: Brought up 1 node, 2 CPUs Mar 17 18:48:48.061426 kernel: SMP: Total of 2 processors activated. Mar 17 18:48:48.061434 kernel: CPU features: detected: 32-bit EL0 Support Mar 17 18:48:48.061441 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence Mar 17 18:48:48.061447 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence Mar 17 18:48:48.061454 kernel: CPU features: detected: CRC32 instructions Mar 17 18:48:48.061460 kernel: CPU features: detected: RCpc load-acquire (LDAPR) Mar 17 18:48:48.061466 kernel: CPU features: detected: LSE atomic instructions Mar 17 18:48:48.061472 kernel: CPU features: detected: Privileged Access Never Mar 17 18:48:48.061478 kernel: CPU: All CPU(s) started at EL1 Mar 17 18:48:48.061485 kernel: alternatives: patching kernel code Mar 17 18:48:48.061492 kernel: devtmpfs: initialized Mar 17 18:48:48.061503 kernel: KASLR enabled Mar 17 18:48:48.061509 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns Mar 17 18:48:48.061517 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear) Mar 17 18:48:48.061524 kernel: pinctrl core: initialized pinctrl subsystem Mar 17 18:48:48.061530 kernel: SMBIOS 3.1.0 present. Mar 17 18:48:48.061537 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 09/28/2024 Mar 17 18:48:48.061543 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family Mar 17 18:48:48.061550 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations Mar 17 18:48:48.061558 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations Mar 17 18:48:48.061565 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations Mar 17 18:48:48.061571 kernel: audit: initializing netlink subsys (disabled) Mar 17 18:48:48.061578 kernel: audit: type=2000 audit(0.086:1): state=initialized audit_enabled=0 res=1 Mar 17 18:48:48.061585 kernel: thermal_sys: Registered thermal governor 'step_wise' Mar 17 18:48:48.061591 kernel: cpuidle: using governor menu Mar 17 18:48:48.061598 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. Mar 17 18:48:48.061606 kernel: ASID allocator initialised with 32768 entries Mar 17 18:48:48.061612 kernel: ACPI: bus type PCI registered Mar 17 18:48:48.061619 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 Mar 17 18:48:48.061625 kernel: Serial: AMBA PL011 UART driver Mar 17 18:48:48.061632 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages Mar 17 18:48:48.061639 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages Mar 17 18:48:48.061645 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages Mar 17 18:48:48.061652 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages Mar 17 18:48:48.061658 kernel: cryptd: max_cpu_qlen set to 1000 Mar 17 18:48:48.061666 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng) Mar 17 18:48:48.061673 kernel: ACPI: Added _OSI(Module Device) Mar 17 18:48:48.061679 kernel: ACPI: Added _OSI(Processor Device) Mar 17 18:48:48.061686 kernel: ACPI: Added _OSI(3.0 _SCP Extensions) Mar 17 18:48:48.061693 kernel: ACPI: Added _OSI(Processor Aggregator Device) Mar 17 18:48:48.061699 kernel: ACPI: Added _OSI(Linux-Dell-Video) Mar 17 18:48:48.061706 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) Mar 17 18:48:48.061712 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) Mar 17 18:48:48.061719 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded Mar 17 18:48:48.061727 kernel: ACPI: Interpreter enabled Mar 17 18:48:48.061733 kernel: ACPI: Using GIC for interrupt routing Mar 17 18:48:48.061740 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA Mar 17 18:48:48.061746 kernel: printk: console [ttyAMA0] enabled Mar 17 18:48:48.061753 kernel: printk: bootconsole [pl11] disabled Mar 17 18:48:48.061759 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA Mar 17 18:48:48.061766 kernel: iommu: Default domain type: Translated Mar 17 18:48:48.061772 kernel: iommu: DMA domain TLB invalidation policy: strict mode Mar 17 18:48:48.061779 kernel: vgaarb: loaded Mar 17 18:48:48.061786 kernel: pps_core: LinuxPPS API ver. 1 registered Mar 17 18:48:48.061794 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti Mar 17 18:48:48.061801 kernel: PTP clock support registered Mar 17 18:48:48.061807 kernel: Registered efivars operations Mar 17 18:48:48.061813 kernel: No ACPI PMU IRQ for CPU0 Mar 17 18:48:48.061820 kernel: No ACPI PMU IRQ for CPU1 Mar 17 18:48:48.061826 kernel: clocksource: Switched to clocksource arch_sys_counter Mar 17 18:48:48.061833 kernel: VFS: Disk quotas dquot_6.6.0 Mar 17 18:48:48.061839 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) Mar 17 18:48:48.061847 kernel: pnp: PnP ACPI init Mar 17 18:48:48.061854 kernel: pnp: PnP ACPI: found 0 devices Mar 17 18:48:48.061860 kernel: NET: Registered PF_INET protocol family Mar 17 18:48:48.061878 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear) Mar 17 18:48:48.061886 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear) Mar 17 18:48:48.061893 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) Mar 17 18:48:48.061900 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear) Mar 17 18:48:48.061906 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear) Mar 17 18:48:48.061913 kernel: TCP: Hash tables configured (established 32768 bind 32768) Mar 17 18:48:48.061923 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:48:48.061929 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear) Mar 17 18:48:48.061936 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family Mar 17 18:48:48.061942 kernel: PCI: CLS 0 bytes, default 64 Mar 17 18:48:48.061949 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available Mar 17 18:48:48.061955 kernel: kvm [1]: HYP mode not available Mar 17 18:48:48.061962 kernel: Initialise system trusted keyrings Mar 17 18:48:48.061968 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0 Mar 17 18:48:48.061975 kernel: Key type asymmetric registered Mar 17 18:48:48.061983 kernel: Asymmetric key parser 'x509' registered Mar 17 18:48:48.061990 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249) Mar 17 18:48:48.061996 kernel: io scheduler mq-deadline registered Mar 17 18:48:48.062003 kernel: io scheduler kyber registered Mar 17 18:48:48.062009 kernel: io scheduler bfq registered Mar 17 18:48:48.062016 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled Mar 17 18:48:48.062022 kernel: thunder_xcv, ver 1.0 Mar 17 18:48:48.062029 kernel: thunder_bgx, ver 1.0 Mar 17 18:48:48.062035 kernel: nicpf, ver 1.0 Mar 17 18:48:48.062042 kernel: nicvf, ver 1.0 Mar 17 18:48:48.062169 kernel: rtc-efi rtc-efi.0: registered as rtc0 Mar 17 18:48:48.062228 kernel: rtc-efi rtc-efi.0: setting system clock to 2025-03-17T18:48:47 UTC (1742237327) Mar 17 18:48:48.062237 kernel: efifb: probing for efifb Mar 17 18:48:48.062244 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k Mar 17 18:48:48.062251 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1 Mar 17 18:48:48.062258 kernel: efifb: scrolling: redraw Mar 17 18:48:48.062264 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0 Mar 17 18:48:48.062273 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:48:48.062280 kernel: fb0: EFI VGA frame buffer device Mar 17 18:48:48.062286 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping .... Mar 17 18:48:48.062293 kernel: hid: raw HID events driver (C) Jiri Kosina Mar 17 18:48:48.062299 kernel: NET: Registered PF_INET6 protocol family Mar 17 18:48:48.062306 kernel: Segment Routing with IPv6 Mar 17 18:48:48.062312 kernel: In-situ OAM (IOAM) with IPv6 Mar 17 18:48:48.062319 kernel: NET: Registered PF_PACKET protocol family Mar 17 18:48:48.062325 kernel: Key type dns_resolver registered Mar 17 18:48:48.062332 kernel: registered taskstats version 1 Mar 17 18:48:48.062339 kernel: Loading compiled-in X.509 certificates Mar 17 18:48:48.062346 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.179-flatcar: c6f3fb83dc6bb7052b07ec5b1ef41d12f9b3f7e4' Mar 17 18:48:48.062353 kernel: Key type .fscrypt registered Mar 17 18:48:48.062359 kernel: Key type fscrypt-provisioning registered Mar 17 18:48:48.062366 kernel: ima: No TPM chip found, activating TPM-bypass! Mar 17 18:48:48.062372 kernel: ima: Allocated hash algorithm: sha1 Mar 17 18:48:48.062379 kernel: ima: No architecture policies found Mar 17 18:48:48.062385 kernel: clk: Disabling unused clocks Mar 17 18:48:48.062393 kernel: Freeing unused kernel memory: 36416K Mar 17 18:48:48.062400 kernel: Run /init as init process Mar 17 18:48:48.062406 kernel: with arguments: Mar 17 18:48:48.062413 kernel: /init Mar 17 18:48:48.062419 kernel: with environment: Mar 17 18:48:48.062425 kernel: HOME=/ Mar 17 18:48:48.062432 kernel: TERM=linux Mar 17 18:48:48.062438 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a Mar 17 18:48:48.062446 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Mar 17 18:48:48.062457 systemd[1]: Detected virtualization microsoft. Mar 17 18:48:48.062464 systemd[1]: Detected architecture arm64. Mar 17 18:48:48.062471 systemd[1]: Running in initrd. Mar 17 18:48:48.062478 systemd[1]: No hostname configured, using default hostname. Mar 17 18:48:48.062485 systemd[1]: Hostname set to . Mar 17 18:48:48.062492 systemd[1]: Initializing machine ID from random generator. Mar 17 18:48:48.062499 systemd[1]: Queued start job for default target initrd.target. Mar 17 18:48:48.062507 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:48:48.062514 systemd[1]: Reached target cryptsetup.target. Mar 17 18:48:48.062521 systemd[1]: Reached target paths.target. Mar 17 18:48:48.062528 systemd[1]: Reached target slices.target. Mar 17 18:48:48.062535 systemd[1]: Reached target swap.target. Mar 17 18:48:48.062542 systemd[1]: Reached target timers.target. Mar 17 18:48:48.062549 systemd[1]: Listening on iscsid.socket. Mar 17 18:48:48.062556 systemd[1]: Listening on iscsiuio.socket. Mar 17 18:48:48.062564 systemd[1]: Listening on systemd-journald-audit.socket. Mar 17 18:48:48.062572 systemd[1]: Listening on systemd-journald-dev-log.socket. Mar 17 18:48:48.062579 systemd[1]: Listening on systemd-journald.socket. Mar 17 18:48:48.062585 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:48:48.062593 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:48:48.062600 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:48:48.062606 systemd[1]: Reached target sockets.target. Mar 17 18:48:48.062614 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:48:48.062621 systemd[1]: Finished network-cleanup.service. Mar 17 18:48:48.062629 systemd[1]: Starting systemd-fsck-usr.service... Mar 17 18:48:48.062636 systemd[1]: Starting systemd-journald.service... Mar 17 18:48:48.062643 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:48:48.062650 systemd[1]: Starting systemd-resolved.service... Mar 17 18:48:48.062657 systemd[1]: Starting systemd-vconsole-setup.service... Mar 17 18:48:48.062667 systemd-journald[276]: Journal started Mar 17 18:48:48.062706 systemd-journald[276]: Runtime Journal (/run/log/journal/613b6efa2829456caabb41a9cffb0212) is 8.0M, max 78.5M, 70.5M free. Mar 17 18:48:48.048805 systemd-modules-load[277]: Inserted module 'overlay' Mar 17 18:48:48.094887 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this. Mar 17 18:48:48.107730 systemd[1]: Started systemd-journald.service. Mar 17 18:48:48.107790 kernel: Bridge firewalling registered Mar 17 18:48:48.107894 systemd-modules-load[277]: Inserted module 'br_netfilter' Mar 17 18:48:48.110000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.108849 systemd-resolved[278]: Positive Trust Anchors: Mar 17 18:48:48.165635 kernel: audit: type=1130 audit(1742237328.110:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.165660 kernel: SCSI subsystem initialized Mar 17 18:48:48.165670 kernel: audit: type=1130 audit(1742237328.134:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.134000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.108881 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:48:48.203404 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. Mar 17 18:48:48.203428 kernel: audit: type=1130 audit(1742237328.139:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.203439 kernel: device-mapper: uevent: version 1.0.3 Mar 17 18:48:48.203447 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com Mar 17 18:48:48.139000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.108915 systemd-resolved[278]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 17 18:48:48.111567 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:48:48.246000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.115926 systemd-resolved[278]: Defaulting to hostname 'linux'. Mar 17 18:48:48.134985 systemd[1]: Started systemd-resolved.service. Mar 17 18:48:48.146812 systemd[1]: Finished systemd-fsck-usr.service. Mar 17 18:48:48.307400 kernel: audit: type=1130 audit(1742237328.246:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.307421 kernel: audit: type=1130 audit(1742237328.276:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.276000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.242023 systemd-modules-load[277]: Inserted module 'dm_multipath' Mar 17 18:48:48.333505 kernel: audit: type=1130 audit(1742237328.302:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.302000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.267121 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:48:48.276832 systemd[1]: Finished systemd-vconsole-setup.service. Mar 17 18:48:48.302701 systemd[1]: Reached target nss-lookup.target. Mar 17 18:48:48.327583 systemd[1]: Starting dracut-cmdline-ask.service... Mar 17 18:48:48.338289 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:48:48.357773 systemd[1]: Starting systemd-tmpfiles-setup-dev.service... Mar 17 18:48:48.375291 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:48:48.406665 kernel: audit: type=1130 audit(1742237328.384:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.384000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.384703 systemd[1]: Finished dracut-cmdline-ask.service. Mar 17 18:48:48.452170 kernel: audit: type=1130 audit(1742237328.406: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' Mar 17 18:48:48.452194 kernel: audit: type=1130 audit(1742237328.431:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.406000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.431000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.422154 systemd[1]: Finished systemd-tmpfiles-setup-dev.service. Mar 17 18:48:48.449010 systemd[1]: Starting dracut-cmdline.service... Mar 17 18:48:48.468304 dracut-cmdline[299]: dracut-dracut-053 Mar 17 18:48:48.472980 dracut-cmdline[299]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=e034db32d58fe7496a3db6ba3879dd9052cea2cf1597d65edfc7b26afc92530d Mar 17 18:48:48.558893 kernel: Loading iSCSI transport class v2.0-870. Mar 17 18:48:48.574892 kernel: iscsi: registered transport (tcp) Mar 17 18:48:48.595518 kernel: iscsi: registered transport (qla4xxx) Mar 17 18:48:48.595541 kernel: QLogic iSCSI HBA Driver Mar 17 18:48:48.625549 systemd[1]: Finished dracut-cmdline.service. Mar 17 18:48:48.630000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:48.631643 systemd[1]: Starting dracut-pre-udev.service... Mar 17 18:48:48.687887 kernel: raid6: neonx8 gen() 13817 MB/s Mar 17 18:48:48.705878 kernel: raid6: neonx8 xor() 10838 MB/s Mar 17 18:48:48.726875 kernel: raid6: neonx4 gen() 13546 MB/s Mar 17 18:48:48.747876 kernel: raid6: neonx4 xor() 11315 MB/s Mar 17 18:48:48.767874 kernel: raid6: neonx2 gen() 12988 MB/s Mar 17 18:48:48.787873 kernel: raid6: neonx2 xor() 10543 MB/s Mar 17 18:48:48.808876 kernel: raid6: neonx1 gen() 10564 MB/s Mar 17 18:48:48.829874 kernel: raid6: neonx1 xor() 8786 MB/s Mar 17 18:48:48.850875 kernel: raid6: int64x8 gen() 6270 MB/s Mar 17 18:48:48.872879 kernel: raid6: int64x8 xor() 3544 MB/s Mar 17 18:48:48.893877 kernel: raid6: int64x4 gen() 7221 MB/s Mar 17 18:48:48.913876 kernel: raid6: int64x4 xor() 3857 MB/s Mar 17 18:48:48.935877 kernel: raid6: int64x2 gen() 6152 MB/s Mar 17 18:48:48.955873 kernel: raid6: int64x2 xor() 3324 MB/s Mar 17 18:48:48.975879 kernel: raid6: int64x1 gen() 5049 MB/s Mar 17 18:48:49.001491 kernel: raid6: int64x1 xor() 2646 MB/s Mar 17 18:48:49.001503 kernel: raid6: using algorithm neonx8 gen() 13817 MB/s Mar 17 18:48:49.001511 kernel: raid6: .... xor() 10838 MB/s, rmw enabled Mar 17 18:48:49.006149 kernel: raid6: using neon recovery algorithm Mar 17 18:48:49.027419 kernel: xor: measuring software checksum speed Mar 17 18:48:49.027432 kernel: 8regs : 17206 MB/sec Mar 17 18:48:49.031278 kernel: 32regs : 20691 MB/sec Mar 17 18:48:49.035218 kernel: arm64_neon : 27757 MB/sec Mar 17 18:48:49.035228 kernel: xor: using function: arm64_neon (27757 MB/sec) Mar 17 18:48:49.095888 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no Mar 17 18:48:49.105012 systemd[1]: Finished dracut-pre-udev.service. Mar 17 18:48:49.109000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:49.112000 audit: BPF prog-id=7 op=LOAD Mar 17 18:48:49.112000 audit: BPF prog-id=8 op=LOAD Mar 17 18:48:49.114181 systemd[1]: Starting systemd-udevd.service... Mar 17 18:48:49.132780 systemd-udevd[475]: Using default interface naming scheme 'v252'. Mar 17 18:48:49.145000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:49.139349 systemd[1]: Started systemd-udevd.service. Mar 17 18:48:49.150812 systemd[1]: Starting dracut-pre-trigger.service... Mar 17 18:48:49.166185 dracut-pre-trigger[496]: rd.md=0: removing MD RAID activation Mar 17 18:48:49.200816 systemd[1]: Finished dracut-pre-trigger.service. Mar 17 18:48:49.205000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:49.206556 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:48:49.246735 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:48:49.257000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:49.297886 kernel: hv_vmbus: Vmbus version:5.3 Mar 17 18:48:49.316886 kernel: hv_vmbus: registering driver hv_storvsc Mar 17 18:48:49.316931 kernel: hv_vmbus: registering driver hyperv_keyboard Mar 17 18:48:49.342815 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/MSFT1000:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0 Mar 17 18:48:49.342875 kernel: scsi host0: storvsc_host_t Mar 17 18:48:49.343017 kernel: scsi host1: storvsc_host_t Mar 17 18:48:49.343101 kernel: hv_vmbus: registering driver hid_hyperv Mar 17 18:48:49.343110 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5 Mar 17 18:48:49.358444 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1 Mar 17 18:48:49.358493 kernel: hv_vmbus: registering driver hv_netvsc Mar 17 18:48:49.371225 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on Mar 17 18:48:49.385902 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0 Mar 17 18:48:49.409051 kernel: sr 0:0:0:2: [sr0] scsi-1 drive Mar 17 18:48:49.421015 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20 Mar 17 18:48:49.421033 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB) Mar 17 18:48:49.442654 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0 Mar 17 18:48:49.442758 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks Mar 17 18:48:49.442836 kernel: sd 0:0:0:0: [sda] Write Protect is off Mar 17 18:48:49.442942 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00 Mar 17 18:48:49.443025 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA Mar 17 18:48:49.443107 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:48:49.443117 kernel: sd 0:0:0:0: [sda] Attached SCSI disk Mar 17 18:48:49.443191 kernel: hv_netvsc 0022487a-69f2-0022-487a-69f20022487a eth0: VF slot 1 added Mar 17 18:48:49.458882 kernel: hv_vmbus: registering driver hv_pci Mar 17 18:48:49.469722 kernel: hv_pci f6e2b717-fcdf-4900-b86c-98ae06966422: PCI VMBus probing: Using version 0x10004 Mar 17 18:48:49.589229 kernel: hv_pci f6e2b717-fcdf-4900-b86c-98ae06966422: PCI host bridge to bus fcdf:00 Mar 17 18:48:49.589334 kernel: pci_bus fcdf:00: root bus resource [mem 0xfc0000000-0xfc00fffff window] Mar 17 18:48:49.589431 kernel: pci_bus fcdf:00: No busn resource found for root bus, will use [bus 00-ff] Mar 17 18:48:49.589503 kernel: pci fcdf:00:02.0: [15b3:1018] type 00 class 0x020000 Mar 17 18:48:49.589612 kernel: pci fcdf:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 18:48:49.589690 kernel: pci fcdf:00:02.0: enabling Extended Tags Mar 17 18:48:49.589766 kernel: pci fcdf:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at fcdf:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link) Mar 17 18:48:49.589851 kernel: pci_bus fcdf:00: busn_res: [bus 00-ff] end is updated to 00 Mar 17 18:48:49.589958 kernel: pci fcdf:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref] Mar 17 18:48:49.626891 kernel: mlx5_core fcdf:00:02.0: firmware version: 16.30.1284 Mar 17 18:48:49.868812 kernel: mlx5_core fcdf:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0) Mar 17 18:48:49.868943 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (540) Mar 17 18:48:49.868954 kernel: hv_netvsc 0022487a-69f2-0022-487a-69f20022487a eth0: VF registering: eth1 Mar 17 18:48:49.869062 kernel: mlx5_core fcdf:00:02.0 eth1: joined to eth0 Mar 17 18:48:49.801911 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device. Mar 17 18:48:49.861826 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:48:49.885183 kernel: mlx5_core fcdf:00:02.0 enP64735s1: renamed from eth1 Mar 17 18:48:50.002773 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device. Mar 17 18:48:50.017565 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device. Mar 17 18:48:50.023800 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device. Mar 17 18:48:50.036699 systemd[1]: Starting disk-uuid.service... Mar 17 18:48:50.065354 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:48:50.077886 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:48:51.075886 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9 Mar 17 18:48:51.076214 disk-uuid[603]: The operation has completed successfully. Mar 17 18:48:51.133249 systemd[1]: disk-uuid.service: Deactivated successfully. Mar 17 18:48:51.137000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.137000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.133353 systemd[1]: Finished disk-uuid.service. Mar 17 18:48:51.142571 systemd[1]: Starting verity-setup.service... Mar 17 18:48:51.188919 kernel: device-mapper: verity: sha256 using implementation "sha256-ce" Mar 17 18:48:51.444388 systemd[1]: Found device dev-mapper-usr.device. Mar 17 18:48:51.450490 systemd[1]: Mounting sysusr-usr.mount... Mar 17 18:48:51.462418 systemd[1]: Finished verity-setup.service. Mar 17 18:48:51.466000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.519792 systemd[1]: Mounted sysusr-usr.mount. Mar 17 18:48:51.527793 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none. Mar 17 18:48:51.524454 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met. Mar 17 18:48:51.525229 systemd[1]: Starting ignition-setup.service... Mar 17 18:48:51.532237 systemd[1]: Starting parse-ip-for-networkd.service... Mar 17 18:48:51.577782 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:48:51.577811 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:48:51.577827 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:48:51.634836 systemd[1]: Finished parse-ip-for-networkd.service. Mar 17 18:48:51.640000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.643000 audit: BPF prog-id=9 op=LOAD Mar 17 18:48:51.645437 systemd[1]: Starting systemd-networkd.service... Mar 17 18:48:51.663770 systemd[1]: mnt-oem.mount: Deactivated successfully. Mar 17 18:48:51.671968 systemd-networkd[846]: lo: Link UP Mar 17 18:48:51.671978 systemd-networkd[846]: lo: Gained carrier Mar 17 18:48:51.680000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.672374 systemd-networkd[846]: Enumeration completed Mar 17 18:48:51.672457 systemd[1]: Started systemd-networkd.service. Mar 17 18:48:51.681030 systemd-networkd[846]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:48:51.681779 systemd[1]: Reached target network.target. Mar 17 18:48:51.716000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.690491 systemd[1]: Starting iscsiuio.service... Mar 17 18:48:51.701586 systemd[1]: Started iscsiuio.service. Mar 17 18:48:51.717750 systemd[1]: Starting iscsid.service... Mar 17 18:48:51.735000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.739556 iscsid[853]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:48:51.739556 iscsid[853]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log Mar 17 18:48:51.739556 iscsid[853]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.[:identifier]. Mar 17 18:48:51.739556 iscsid[853]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6. Mar 17 18:48:51.739556 iscsid[853]: If using hardware iscsi like qla4xxx this message can be ignored. Mar 17 18:48:51.739556 iscsid[853]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi Mar 17 18:48:51.739556 iscsid[853]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf Mar 17 18:48:51.775000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.728844 systemd[1]: Started iscsid.service. Mar 17 18:48:51.736917 systemd[1]: Starting dracut-initqueue.service... Mar 17 18:48:51.848000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.770100 systemd[1]: Finished dracut-initqueue.service. Mar 17 18:48:51.775257 systemd[1]: Reached target remote-fs-pre.target. Mar 17 18:48:51.862000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:51.785986 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:48:51.881702 kernel: mlx5_core fcdf:00:02.0 enP64735s1: Link up Mar 17 18:48:51.799671 systemd[1]: Reached target remote-fs.target. Mar 17 18:48:51.811859 systemd[1]: Starting dracut-pre-mount.service... Mar 17 18:48:51.840628 systemd[1]: Finished dracut-pre-mount.service. Mar 17 18:48:51.857886 systemd[1]: Finished ignition-setup.service. Mar 17 18:48:51.872440 systemd[1]: Starting ignition-fetch-offline.service... Mar 17 18:48:51.914882 kernel: hv_netvsc 0022487a-69f2-0022-487a-69f20022487a eth0: Data path switched to VF: enP64735s1 Mar 17 18:48:51.915039 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready Mar 17 18:48:51.922200 systemd-networkd[846]: enP64735s1: Link UP Mar 17 18:48:51.923070 systemd-networkd[846]: eth0: Link UP Mar 17 18:48:51.923496 systemd-networkd[846]: eth0: Gained carrier Mar 17 18:48:51.935355 systemd-networkd[846]: enP64735s1: Gained carrier Mar 17 18:48:51.960959 systemd-networkd[846]: eth0: DHCPv4 address 10.200.20.45/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:48:53.582166 systemd-networkd[846]: eth0: Gained IPv6LL Mar 17 18:48:54.458085 ignition[868]: Ignition 2.14.0 Mar 17 18:48:54.458099 ignition[868]: Stage: fetch-offline Mar 17 18:48:54.458167 ignition[868]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:54.458192 ignition[868]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:54.489270 ignition[868]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:54.495515 ignition[868]: parsed url from cmdline: "" Mar 17 18:48:54.495521 ignition[868]: no config URL provided Mar 17 18:48:54.495530 ignition[868]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:48:54.495553 ignition[868]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:48:54.495559 ignition[868]: failed to fetch config: resource requires networking Mar 17 18:48:54.526346 ignition[868]: Ignition finished successfully Mar 17 18:48:54.530816 systemd[1]: Finished ignition-fetch-offline.service. Mar 17 18:48:54.536000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.563141 kernel: kauditd_printk_skb: 18 callbacks suppressed Mar 17 18:48:54.563190 kernel: audit: type=1130 audit(1742237334.536:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.563349 systemd[1]: Starting ignition-fetch.service... Mar 17 18:48:54.569513 ignition[875]: Ignition 2.14.0 Mar 17 18:48:54.569526 ignition[875]: Stage: fetch Mar 17 18:48:54.569612 ignition[875]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:54.569632 ignition[875]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:54.572156 ignition[875]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:54.577659 ignition[875]: parsed url from cmdline: "" Mar 17 18:48:54.577664 ignition[875]: no config URL provided Mar 17 18:48:54.577679 ignition[875]: reading system config file "/usr/lib/ignition/user.ign" Mar 17 18:48:54.577689 ignition[875]: no config at "/usr/lib/ignition/user.ign" Mar 17 18:48:54.577716 ignition[875]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1 Mar 17 18:48:54.661589 ignition[875]: GET result: OK Mar 17 18:48:54.661660 ignition[875]: config has been read from IMDS userdata Mar 17 18:48:54.661689 ignition[875]: parsing config with SHA512: ebde25ec7f5b78dfcf499d4ca3fcca930686f4dae03a3882b23977c3a9f4bbb7311f2283d82d03ab060717a8828cf5728453fc9df7019df87ab5277eb9f42096 Mar 17 18:48:54.665862 unknown[875]: fetched base config from "system" Mar 17 18:48:54.668930 ignition[875]: fetch: fetch complete Mar 17 18:48:54.678000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.667939 unknown[875]: fetched base config from "system" Mar 17 18:48:54.707509 kernel: audit: type=1130 audit(1742237334.678:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.668935 ignition[875]: fetch: fetch passed Mar 17 18:48:54.667952 unknown[875]: fetched user config from "azure" Mar 17 18:48:54.669043 ignition[875]: Ignition finished successfully Mar 17 18:48:54.670173 systemd[1]: Finished ignition-fetch.service. Mar 17 18:48:54.714949 ignition[881]: Ignition 2.14.0 Mar 17 18:48:54.697361 systemd[1]: Starting ignition-kargs.service... Mar 17 18:48:54.714955 ignition[881]: Stage: kargs Mar 17 18:48:54.732786 systemd[1]: Finished ignition-kargs.service. Mar 17 18:48:54.739000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.715059 ignition[881]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:54.770471 kernel: audit: type=1130 audit(1742237334.739:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.760311 systemd[1]: Starting ignition-disks.service... Mar 17 18:48:54.715084 ignition[881]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:54.774736 systemd[1]: Finished ignition-disks.service. Mar 17 18:48:54.800927 kernel: audit: type=1130 audit(1742237334.778:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.778000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.724707 ignition[881]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:54.779482 systemd[1]: Reached target initrd-root-device.target. Mar 17 18:48:54.725548 ignition[881]: kargs: kargs passed Mar 17 18:48:54.802309 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:48:54.725587 ignition[881]: Ignition finished successfully Mar 17 18:48:54.813534 systemd[1]: Reached target local-fs.target. Mar 17 18:48:54.766724 ignition[887]: Ignition 2.14.0 Mar 17 18:48:54.822063 systemd[1]: Reached target sysinit.target. Mar 17 18:48:54.766730 ignition[887]: Stage: disks Mar 17 18:48:54.829877 systemd[1]: Reached target basic.target. Mar 17 18:48:54.766834 ignition[887]: reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:54.838935 systemd[1]: Starting systemd-fsck-root.service... Mar 17 18:48:54.766851 ignition[887]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:54.771154 ignition[887]: no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:54.772217 ignition[887]: disks: disks passed Mar 17 18:48:54.772258 ignition[887]: Ignition finished successfully Mar 17 18:48:54.902925 systemd-fsck[895]: ROOT: clean, 623/7326000 files, 481077/7359488 blocks Mar 17 18:48:54.943043 kernel: audit: type=1130 audit(1742237334.917:33): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.943066 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none. Mar 17 18:48:54.917000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:54.913574 systemd[1]: Finished systemd-fsck-root.service. Mar 17 18:48:54.919278 systemd[1]: Mounting sysroot.mount... Mar 17 18:48:54.951877 systemd[1]: Mounted sysroot.mount. Mar 17 18:48:54.960128 systemd[1]: Reached target initrd-root-fs.target. Mar 17 18:48:55.012078 systemd[1]: Mounting sysroot-usr.mount... Mar 17 18:48:55.016764 systemd[1]: Starting flatcar-metadata-hostname.service... Mar 17 18:48:55.024283 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot). Mar 17 18:48:55.024315 systemd[1]: Reached target ignition-diskful.target. Mar 17 18:48:55.030273 systemd[1]: Mounted sysroot-usr.mount. Mar 17 18:48:55.080378 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:48:55.089030 systemd[1]: Starting initrd-setup-root.service... Mar 17 18:48:55.105885 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (906) Mar 17 18:48:55.119466 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:48:55.119499 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:48:55.119688 initrd-setup-root[911]: cut: /sysroot/etc/passwd: No such file or directory Mar 17 18:48:55.130692 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:48:55.135016 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:48:55.145829 initrd-setup-root[937]: cut: /sysroot/etc/group: No such file or directory Mar 17 18:48:55.168669 initrd-setup-root[945]: cut: /sysroot/etc/shadow: No such file or directory Mar 17 18:48:55.178608 initrd-setup-root[953]: cut: /sysroot/etc/gshadow: No such file or directory Mar 17 18:48:55.567341 systemd[1]: Finished initrd-setup-root.service. Mar 17 18:48:55.571000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.572852 systemd[1]: Starting ignition-mount.service... Mar 17 18:48:55.604523 kernel: audit: type=1130 audit(1742237335.571:34): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.595229 systemd[1]: Starting sysroot-boot.service... Mar 17 18:48:55.606797 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully. Mar 17 18:48:55.606927 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully. Mar 17 18:48:55.630614 ignition[972]: INFO : Ignition 2.14.0 Mar 17 18:48:55.630614 ignition[972]: INFO : Stage: mount Mar 17 18:48:55.639257 ignition[972]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:55.639257 ignition[972]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:55.639257 ignition[972]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:55.639257 ignition[972]: INFO : mount: mount passed Mar 17 18:48:55.639257 ignition[972]: INFO : Ignition finished successfully Mar 17 18:48:55.717690 kernel: audit: type=1130 audit(1742237335.651:35): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.717715 kernel: audit: type=1130 audit(1742237335.692:36): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.651000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.692000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:55.639400 systemd[1]: Finished ignition-mount.service. Mar 17 18:48:55.684105 systemd[1]: Finished sysroot-boot.service. Mar 17 18:48:56.208153 coreos-metadata[905]: Mar 17 18:48:56.208 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1 Mar 17 18:48:56.218935 coreos-metadata[905]: Mar 17 18:48:56.218 INFO Fetch successful Mar 17 18:48:56.252380 coreos-metadata[905]: Mar 17 18:48:56.252 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1 Mar 17 18:48:56.263855 coreos-metadata[905]: Mar 17 18:48:56.263 INFO Fetch successful Mar 17 18:48:56.277126 coreos-metadata[905]: Mar 17 18:48:56.277 INFO wrote hostname ci-3510.3.7-a-006bc239d1 to /sysroot/etc/hostname Mar 17 18:48:56.286133 systemd[1]: Finished flatcar-metadata-hostname.service. Mar 17 18:48:56.291000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.316942 kernel: audit: type=1130 audit(1742237336.291:37): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.312329 systemd[1]: Starting ignition-files.service... Mar 17 18:48:56.323086 systemd[1]: Mounting sysroot-usr-share-oem.mount... Mar 17 18:48:56.347106 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (985) Mar 17 18:48:56.347152 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm Mar 17 18:48:56.347162 kernel: BTRFS info (device sda6): using free space tree Mar 17 18:48:56.357584 kernel: BTRFS info (device sda6): has skinny extents Mar 17 18:48:56.363402 systemd[1]: Mounted sysroot-usr-share-oem.mount. Mar 17 18:48:56.381906 ignition[1004]: INFO : Ignition 2.14.0 Mar 17 18:48:56.381906 ignition[1004]: INFO : Stage: files Mar 17 18:48:56.391400 ignition[1004]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:56.391400 ignition[1004]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:56.391400 ignition[1004]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:56.391400 ignition[1004]: DEBUG : files: compiled without relabeling support, skipping Mar 17 18:48:56.391400 ignition[1004]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core" Mar 17 18:48:56.391400 ignition[1004]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core" Mar 17 18:48:56.470214 ignition[1004]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core" Mar 17 18:48:56.478755 ignition[1004]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core" Mar 17 18:48:56.478755 ignition[1004]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core" Mar 17 18:48:56.478192 unknown[1004]: wrote ssh authorized keys file for user: core Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/var/resource/data" Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/var/resource/data" Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/var/resource/http" Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET http://10.200.20.32/http: attempt #1 Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET result: OK Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/var/resource/http" Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/var/resource/tftp" Mar 17 18:48:56.499993 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/var/resource/tftp" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/flatcar/update.conf" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3881425867" Mar 17 18:48:56.578030 ignition[1004]: CRITICAL : files: createFilesystemsFiles: createFiles: op(7): op(8): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3881425867": device or resource busy Mar 17 18:48:56.578030 ignition[1004]: ERROR : files: createFilesystemsFiles: createFiles: op(7): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3881425867", trying btrfs: device or resource busy Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3881425867" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3881425867" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [started] unmounting "/mnt/oem3881425867" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [finished] unmounting "/mnt/oem3881425867" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/waagent.service" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): [started] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): oem config not found in "/usr/share/oem", looking on oem partition Mar 17 18:48:56.578030 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(c): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3694870092" Mar 17 18:48:56.826860 kernel: audit: type=1130 audit(1742237336.603:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.603000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.682000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.682000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.703000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.762000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.762000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.542596 systemd[1]: mnt-oem3881425867.mount: Deactivated successfully. Mar 17 18:48:56.833674 ignition[1004]: CRITICAL : files: createFilesystemsFiles: createFiles: op(b): op(c): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3694870092": device or resource busy Mar 17 18:48:56.833674 ignition[1004]: ERROR : files: createFilesystemsFiles: createFiles: op(b): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3694870092", trying btrfs: device or resource busy Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(d): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3694870092" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(d): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3694870092" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(e): [started] unmounting "/mnt/oem3694870092" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(e): [finished] unmounting "/mnt/oem3694870092" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createFilesystemsFiles: createFiles: op(b): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(f): [started] processing unit "waagent.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(f): [finished] processing unit "waagent.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(10): [started] processing unit "nvidia.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(10): [finished] processing unit "nvidia.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(11): [started] setting preset to enabled for "nvidia.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(11): [finished] setting preset to enabled for "nvidia.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(12): [started] setting preset to enabled for "waagent.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: op(12): [finished] setting preset to enabled for "waagent.service" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createResultFile: createFiles: op(13): [started] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: createResultFile: createFiles: op(13): [finished] writing file "/sysroot/etc/.ignition-result.json" Mar 17 18:48:56.833674 ignition[1004]: INFO : files: files passed Mar 17 18:48:56.833674 ignition[1004]: INFO : Ignition finished successfully Mar 17 18:48:56.892000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.967000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.589693 systemd[1]: Finished ignition-files.service. Mar 17 18:48:56.606267 systemd[1]: Starting initrd-setup-root-after-ignition.service... Mar 17 18:48:57.085567 initrd-setup-root-after-ignition[1029]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory Mar 17 18:48:56.634150 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile). Mar 17 18:48:57.101000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.635074 systemd[1]: Starting ignition-quench.service... Mar 17 18:48:56.651178 systemd[1]: ignition-quench.service: Deactivated successfully. Mar 17 18:48:57.125000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.651574 systemd[1]: Finished ignition-quench.service. Mar 17 18:48:57.135000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.683390 systemd[1]: Finished initrd-setup-root-after-ignition.service. Mar 17 18:48:57.143000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.703786 systemd[1]: Reached target ignition-complete.target. Mar 17 18:48:57.154000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.721144 systemd[1]: Starting initrd-parse-etc.service... Mar 17 18:48:56.748161 systemd[1]: initrd-parse-etc.service: Deactivated successfully. Mar 17 18:48:57.177057 ignition[1042]: INFO : Ignition 2.14.0 Mar 17 18:48:57.177057 ignition[1042]: INFO : Stage: umount Mar 17 18:48:57.177057 ignition[1042]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign" Mar 17 18:48:57.177057 ignition[1042]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63 Mar 17 18:48:57.181000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.206000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.224000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.748282 systemd[1]: Finished initrd-parse-etc.service. Mar 17 18:48:57.229851 ignition[1042]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure" Mar 17 18:48:57.229851 ignition[1042]: INFO : umount: umount passed Mar 17 18:48:57.229851 ignition[1042]: INFO : Ignition finished successfully Mar 17 18:48:57.233000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.245000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.254000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.258000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.763020 systemd[1]: Reached target initrd-fs.target. Mar 17 18:48:56.793466 systemd[1]: Reached target initrd.target. Mar 17 18:48:57.276000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.811034 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met. Mar 17 18:48:56.811939 systemd[1]: Starting dracut-pre-pivot.service... Mar 17 18:48:56.874711 systemd[1]: Finished dracut-pre-pivot.service. Mar 17 18:48:56.893454 systemd[1]: Starting initrd-cleanup.service... Mar 17 18:48:56.919543 systemd[1]: Stopped target nss-lookup.target. Mar 17 18:48:56.933550 systemd[1]: Stopped target remote-cryptsetup.target. Mar 17 18:48:56.945790 systemd[1]: Stopped target timers.target. Mar 17 18:48:56.956681 systemd[1]: dracut-pre-pivot.service: Deactivated successfully. Mar 17 18:48:57.343000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.956789 systemd[1]: Stopped dracut-pre-pivot.service. Mar 17 18:48:56.967613 systemd[1]: Stopped target initrd.target. Mar 17 18:48:56.978215 systemd[1]: Stopped target basic.target. Mar 17 18:48:57.370000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.370000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:56.989585 systemd[1]: Stopped target ignition-complete.target. Mar 17 18:48:57.378000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.001459 systemd[1]: Stopped target ignition-diskful.target. Mar 17 18:48:57.388000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.013716 systemd[1]: Stopped target initrd-root-device.target. Mar 17 18:48:57.398000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.398000 audit: BPF prog-id=6 op=UNLOAD Mar 17 18:48:57.026073 systemd[1]: Stopped target remote-fs.target. Mar 17 18:48:57.040374 systemd[1]: Stopped target remote-fs-pre.target. Mar 17 18:48:57.415000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.054605 systemd[1]: Stopped target sysinit.target. Mar 17 18:48:57.428000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.062858 systemd[1]: Stopped target local-fs.target. Mar 17 18:48:57.436000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.072000 systemd[1]: Stopped target local-fs-pre.target. Mar 17 18:48:57.445000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.080284 systemd[1]: Stopped target swap.target. Mar 17 18:48:57.089453 systemd[1]: dracut-pre-mount.service: Deactivated successfully. Mar 17 18:48:57.463000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.089556 systemd[1]: Stopped dracut-pre-mount.service. Mar 17 18:48:57.102202 systemd[1]: Stopped target cryptsetup.target. Mar 17 18:48:57.487000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.116629 systemd[1]: dracut-initqueue.service: Deactivated successfully. Mar 17 18:48:57.496000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.116725 systemd[1]: Stopped dracut-initqueue.service. Mar 17 18:48:57.506000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.125248 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully. Mar 17 18:48:57.125344 systemd[1]: Stopped initrd-setup-root-after-ignition.service. Mar 17 18:48:57.527000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.135664 systemd[1]: ignition-files.service: Deactivated successfully. Mar 17 18:48:57.552474 kernel: hv_netvsc 0022487a-69f2-0022-487a-69f20022487a eth0: Data path switched from VF: enP64735s1 Mar 17 18:48:57.549000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.549000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.135749 systemd[1]: Stopped ignition-files.service. Mar 17 18:48:57.143952 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully. Mar 17 18:48:57.144037 systemd[1]: Stopped flatcar-metadata-hostname.service. Mar 17 18:48:57.155837 systemd[1]: Stopping ignition-mount.service... Mar 17 18:48:57.171685 systemd[1]: kmod-static-nodes.service: Deactivated successfully. Mar 17 18:48:57.173890 systemd[1]: Stopped kmod-static-nodes.service. Mar 17 18:48:57.185319 systemd[1]: Stopping sysroot-boot.service... Mar 17 18:48:57.200959 systemd[1]: systemd-udev-trigger.service: Deactivated successfully. Mar 17 18:48:57.201137 systemd[1]: Stopped systemd-udev-trigger.service. Mar 17 18:48:57.206716 systemd[1]: dracut-pre-trigger.service: Deactivated successfully. Mar 17 18:48:57.206854 systemd[1]: Stopped dracut-pre-trigger.service. Mar 17 18:48:57.226310 systemd[1]: ignition-mount.service: Deactivated successfully. Mar 17 18:48:57.226422 systemd[1]: Stopped ignition-mount.service. Mar 17 18:48:57.234484 systemd[1]: ignition-disks.service: Deactivated successfully. Mar 17 18:48:57.234592 systemd[1]: Stopped ignition-disks.service. Mar 17 18:48:57.246090 systemd[1]: ignition-kargs.service: Deactivated successfully. Mar 17 18:48:57.246133 systemd[1]: Stopped ignition-kargs.service. Mar 17 18:48:57.641000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:48:57.254274 systemd[1]: ignition-fetch.service: Deactivated successfully. Mar 17 18:48:57.254309 systemd[1]: Stopped ignition-fetch.service. Mar 17 18:48:57.258904 systemd[1]: Stopped target network.target. Mar 17 18:48:57.267896 systemd[1]: ignition-fetch-offline.service: Deactivated successfully. Mar 17 18:48:57.267941 systemd[1]: Stopped ignition-fetch-offline.service. Mar 17 18:48:57.276969 systemd[1]: Stopped target paths.target. Mar 17 18:48:57.285143 systemd[1]: systemd-ask-password-console.path: Deactivated successfully. Mar 17 18:48:57.288907 systemd[1]: Stopped systemd-ask-password-console.path. Mar 17 18:48:57.708087 iscsid[853]: iscsid shutting down. Mar 17 18:48:57.302372 systemd[1]: Stopped target slices.target. Mar 17 18:48:57.311594 systemd[1]: Stopped target sockets.target. Mar 17 18:48:57.319987 systemd[1]: iscsid.socket: Deactivated successfully. Mar 17 18:48:57.320024 systemd[1]: Closed iscsid.socket. Mar 17 18:48:57.327647 systemd[1]: iscsiuio.socket: Deactivated successfully. Mar 17 18:48:57.327676 systemd[1]: Closed iscsiuio.socket. Mar 17 18:48:57.335945 systemd[1]: ignition-setup.service: Deactivated successfully. Mar 17 18:48:57.335986 systemd[1]: Stopped ignition-setup.service. Mar 17 18:48:57.344252 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:48:57.353337 systemd[1]: Stopping systemd-resolved.service... Mar 17 18:48:57.361829 systemd[1]: sysroot-boot.mount: Deactivated successfully. Mar 17 18:48:57.362399 systemd[1]: initrd-cleanup.service: Deactivated successfully. Mar 17 18:48:57.362480 systemd[1]: Finished initrd-cleanup.service. Mar 17 18:48:57.364396 systemd-networkd[846]: eth0: DHCPv6 lease lost Mar 17 18:48:57.707000 audit: BPF prog-id=9 op=UNLOAD Mar 17 18:48:57.371046 systemd[1]: systemd-resolved.service: Deactivated successfully. Mar 17 18:48:57.371136 systemd[1]: Stopped systemd-resolved.service. Mar 17 18:48:57.379486 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:48:57.379585 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:48:57.389374 systemd[1]: sysroot-boot.service: Deactivated successfully. Mar 17 18:48:57.389478 systemd[1]: Stopped sysroot-boot.service. Mar 17 18:48:57.399309 systemd[1]: systemd-networkd.socket: Deactivated successfully. Mar 17 18:48:57.399347 systemd[1]: Closed systemd-networkd.socket. Mar 17 18:48:57.407060 systemd[1]: initrd-setup-root.service: Deactivated successfully. Mar 17 18:48:57.407104 systemd[1]: Stopped initrd-setup-root.service. Mar 17 18:48:57.416373 systemd[1]: Stopping network-cleanup.service... Mar 17 18:48:57.423723 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully. Mar 17 18:48:57.423776 systemd[1]: Stopped parse-ip-for-networkd.service. Mar 17 18:48:57.708899 systemd-journald[276]: Received SIGTERM from PID 1 (n/a). Mar 17 18:48:57.428732 systemd[1]: systemd-sysctl.service: Deactivated successfully. Mar 17 18:48:57.428782 systemd[1]: Stopped systemd-sysctl.service. Mar 17 18:48:57.440552 systemd[1]: systemd-modules-load.service: Deactivated successfully. Mar 17 18:48:57.440595 systemd[1]: Stopped systemd-modules-load.service. Mar 17 18:48:57.445844 systemd[1]: Stopping systemd-udevd.service... Mar 17 18:48:57.456387 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully. Mar 17 18:48:57.456921 systemd[1]: systemd-udevd.service: Deactivated successfully. Mar 17 18:48:57.457040 systemd[1]: Stopped systemd-udevd.service. Mar 17 18:48:57.464643 systemd[1]: systemd-udevd-control.socket: Deactivated successfully. Mar 17 18:48:57.464690 systemd[1]: Closed systemd-udevd-control.socket. Mar 17 18:48:57.474743 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully. Mar 17 18:48:57.474779 systemd[1]: Closed systemd-udevd-kernel.socket. Mar 17 18:48:57.479746 systemd[1]: dracut-pre-udev.service: Deactivated successfully. Mar 17 18:48:57.479792 systemd[1]: Stopped dracut-pre-udev.service. Mar 17 18:48:57.488235 systemd[1]: dracut-cmdline.service: Deactivated successfully. Mar 17 18:48:57.488280 systemd[1]: Stopped dracut-cmdline.service. Mar 17 18:48:57.497124 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully. Mar 17 18:48:57.497171 systemd[1]: Stopped dracut-cmdline-ask.service. Mar 17 18:48:57.507790 systemd[1]: Starting initrd-udevadm-cleanup-db.service... Mar 17 18:48:57.515854 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully. Mar 17 18:48:57.521954 systemd[1]: Stopped systemd-vconsole-setup.service. Mar 17 18:48:57.528334 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully. Mar 17 18:48:57.528442 systemd[1]: Finished initrd-udevadm-cleanup-db.service. Mar 17 18:48:57.631328 systemd[1]: network-cleanup.service: Deactivated successfully. Mar 17 18:48:57.631445 systemd[1]: Stopped network-cleanup.service. Mar 17 18:48:57.642192 systemd[1]: Reached target initrd-switch-root.target. Mar 17 18:48:57.654081 systemd[1]: Starting initrd-switch-root.service... Mar 17 18:48:57.675974 systemd[1]: Switching root. Mar 17 18:48:57.709573 systemd-journald[276]: Journal stopped Mar 17 18:49:08.475948 kernel: SELinux: Class mctp_socket not defined in policy. Mar 17 18:49:08.475969 kernel: SELinux: Class anon_inode not defined in policy. Mar 17 18:49:08.475979 kernel: SELinux: the above unknown classes and permissions will be allowed Mar 17 18:49:08.475989 kernel: SELinux: policy capability network_peer_controls=1 Mar 17 18:49:08.475997 kernel: SELinux: policy capability open_perms=1 Mar 17 18:49:08.476004 kernel: SELinux: policy capability extended_socket_class=1 Mar 17 18:49:08.476013 kernel: SELinux: policy capability always_check_network=0 Mar 17 18:49:08.476021 kernel: SELinux: policy capability cgroup_seclabel=1 Mar 17 18:49:08.476029 kernel: SELinux: policy capability nnp_nosuid_transition=1 Mar 17 18:49:08.476037 kernel: SELinux: policy capability genfs_seclabel_symlinks=0 Mar 17 18:49:08.476045 kernel: SELinux: policy capability ioctl_skip_cloexec=0 Mar 17 18:49:08.476054 kernel: kauditd_printk_skb: 40 callbacks suppressed Mar 17 18:49:08.476063 kernel: audit: type=1403 audit(1742237339.616:79): auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:49:08.476073 systemd[1]: Successfully loaded SELinux policy in 311.553ms. Mar 17 18:49:08.476084 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 21.202ms. Mar 17 18:49:08.476095 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified) Mar 17 18:49:08.476104 systemd[1]: Detected virtualization microsoft. Mar 17 18:49:08.476113 systemd[1]: Detected architecture arm64. Mar 17 18:49:08.476122 systemd[1]: Detected first boot. Mar 17 18:49:08.476131 systemd[1]: Hostname set to . Mar 17 18:49:08.476140 systemd[1]: Initializing machine ID from random generator. Mar 17 18:49:08.476149 kernel: audit: type=1400 audit(1742237340.313: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 Mar 17 18:49:08.476161 kernel: audit: type=1400 audit(1742237340.313: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 Mar 17 18:49:08.476169 kernel: audit: type=1334 audit(1742237340.328:82): prog-id=10 op=LOAD Mar 17 18:49:08.476177 kernel: audit: type=1334 audit(1742237340.328:83): prog-id=10 op=UNLOAD Mar 17 18:49:08.476186 kernel: audit: type=1334 audit(1742237340.346:84): prog-id=11 op=LOAD Mar 17 18:49:08.476194 kernel: audit: type=1334 audit(1742237340.346:85): prog-id=11 op=UNLOAD Mar 17 18:49:08.476203 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped). Mar 17 18:49:08.476213 kernel: audit: type=1400 audit(1742237341.644:86): avc: denied { associate } for pid=1075 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Mar 17 18:49:08.476223 kernel: audit: type=1300 audit(1742237341.644:86): arch=c00000b7 syscall=5 success=yes exit=0 a0=4000022802 a1=4000028ae0 a2=4000026d00 a3=32 items=0 ppid=1058 pid=1075 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:08.476233 kernel: audit: type=1327 audit(1742237341.644:86): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:49:08.476242 systemd[1]: Populated /etc with preset unit settings. Mar 17 18:49:08.476251 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon. Mar 17 18:49:08.476261 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon. Mar 17 18:49:08.476272 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly. Mar 17 18:49:08.476282 kernel: kauditd_printk_skb: 6 callbacks suppressed Mar 17 18:49:08.476291 kernel: audit: type=1334 audit(1742237347.707:88): prog-id=12 op=LOAD Mar 17 18:49:08.476300 kernel: audit: type=1334 audit(1742237347.707:89): prog-id=3 op=UNLOAD Mar 17 18:49:08.476310 systemd[1]: iscsiuio.service: Deactivated successfully. Mar 17 18:49:08.476319 kernel: audit: type=1334 audit(1742237347.713:90): prog-id=13 op=LOAD Mar 17 18:49:08.476329 systemd[1]: Stopped iscsiuio.service. Mar 17 18:49:08.476339 kernel: audit: type=1334 audit(1742237347.719:91): prog-id=14 op=LOAD Mar 17 18:49:08.476347 kernel: audit: type=1334 audit(1742237347.719:92): prog-id=4 op=UNLOAD Mar 17 18:49:08.476357 kernel: audit: type=1334 audit(1742237347.719:93): prog-id=5 op=UNLOAD Mar 17 18:49:08.476366 systemd[1]: iscsid.service: Deactivated successfully. Mar 17 18:49:08.476375 kernel: audit: type=1131 audit(1742237347.720:94): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.476384 systemd[1]: Stopped iscsid.service. Mar 17 18:49:08.476393 kernel: audit: type=1334 audit(1742237347.751:95): prog-id=12 op=UNLOAD Mar 17 18:49:08.476403 kernel: audit: type=1131 audit(1742237347.760:96): 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' Mar 17 18:49:08.476412 kernel: audit: type=1131 audit(1742237347.812:97): 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' Mar 17 18:49:08.476422 systemd[1]: initrd-switch-root.service: Deactivated successfully. Mar 17 18:49:08.476431 systemd[1]: Stopped initrd-switch-root.service. Mar 17 18:49:08.476441 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1. Mar 17 18:49:08.476450 systemd[1]: Created slice system-addon\x2dconfig.slice. Mar 17 18:49:08.476459 systemd[1]: Created slice system-addon\x2drun.slice. Mar 17 18:49:08.476470 systemd[1]: Created slice system-getty.slice. Mar 17 18:49:08.476479 systemd[1]: Created slice system-modprobe.slice. Mar 17 18:49:08.476488 systemd[1]: Created slice system-serial\x2dgetty.slice. Mar 17 18:49:08.476497 systemd[1]: Created slice system-system\x2dcloudinit.slice. Mar 17 18:49:08.476508 systemd[1]: Created slice system-systemd\x2dfsck.slice. Mar 17 18:49:08.476517 systemd[1]: Created slice user.slice. Mar 17 18:49:08.476532 systemd[1]: Started systemd-ask-password-console.path. Mar 17 18:49:08.476545 systemd[1]: Started systemd-ask-password-wall.path. Mar 17 18:49:08.476554 systemd[1]: Set up automount boot.automount. Mar 17 18:49:08.476563 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount. Mar 17 18:49:08.476572 systemd[1]: Stopped target initrd-switch-root.target. Mar 17 18:49:08.476581 systemd[1]: Stopped target initrd-fs.target. Mar 17 18:49:08.476596 systemd[1]: Stopped target initrd-root-fs.target. Mar 17 18:49:08.476607 systemd[1]: Reached target integritysetup.target. Mar 17 18:49:08.476616 systemd[1]: Reached target remote-cryptsetup.target. Mar 17 18:49:08.476625 systemd[1]: Reached target remote-fs.target. Mar 17 18:49:08.476634 systemd[1]: Reached target slices.target. Mar 17 18:49:08.476647 systemd[1]: Reached target swap.target. Mar 17 18:49:08.476657 systemd[1]: Reached target torcx.target. Mar 17 18:49:08.476669 systemd[1]: Reached target veritysetup.target. Mar 17 18:49:08.476678 systemd[1]: Listening on systemd-coredump.socket. Mar 17 18:49:08.476691 systemd[1]: Listening on systemd-initctl.socket. Mar 17 18:49:08.476703 systemd[1]: Listening on systemd-networkd.socket. Mar 17 18:49:08.476713 systemd[1]: Listening on systemd-udevd-control.socket. Mar 17 18:49:08.476722 systemd[1]: Listening on systemd-udevd-kernel.socket. Mar 17 18:49:08.476733 systemd[1]: Listening on systemd-userdbd.socket. Mar 17 18:49:08.476747 systemd[1]: Mounting dev-hugepages.mount... Mar 17 18:49:08.476757 systemd[1]: Mounting dev-mqueue.mount... Mar 17 18:49:08.476766 systemd[1]: Mounting media.mount... Mar 17 18:49:08.476775 systemd[1]: Mounting sys-kernel-debug.mount... Mar 17 18:49:08.476788 systemd[1]: Mounting sys-kernel-tracing.mount... Mar 17 18:49:08.476799 systemd[1]: Mounting tmp.mount... Mar 17 18:49:08.476808 systemd[1]: Starting flatcar-tmpfiles.service... Mar 17 18:49:08.476818 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met. Mar 17 18:49:08.476828 systemd[1]: Starting kmod-static-nodes.service... Mar 17 18:49:08.476841 systemd[1]: Starting modprobe@configfs.service... Mar 17 18:49:08.476850 systemd[1]: Starting modprobe@dm_mod.service... Mar 17 18:49:08.476859 systemd[1]: Starting modprobe@drm.service... Mar 17 18:49:08.476882 systemd[1]: Starting modprobe@efi_pstore.service... Mar 17 18:49:08.476894 systemd[1]: Starting modprobe@fuse.service... Mar 17 18:49:08.476903 systemd[1]: Starting modprobe@loop.service... Mar 17 18:49:08.476913 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf). Mar 17 18:49:08.476927 systemd[1]: systemd-fsck-root.service: Deactivated successfully. Mar 17 18:49:08.476938 systemd[1]: Stopped systemd-fsck-root.service. Mar 17 18:49:08.476948 systemd[1]: systemd-fsck-usr.service: Deactivated successfully. Mar 17 18:49:08.476957 systemd[1]: Stopped systemd-fsck-usr.service. Mar 17 18:49:08.476966 systemd[1]: Stopped systemd-journald.service. Mar 17 18:49:08.476980 systemd[1]: systemd-journald.service: Consumed 2.625s CPU time. Mar 17 18:49:08.476990 kernel: loop: module loaded Mar 17 18:49:08.476999 systemd[1]: Starting systemd-journald.service... Mar 17 18:49:08.477008 kernel: fuse: init (API version 7.34) Mar 17 18:49:08.477017 systemd[1]: Starting systemd-modules-load.service... Mar 17 18:49:08.477028 systemd[1]: Starting systemd-network-generator.service... Mar 17 18:49:08.477040 systemd[1]: Starting systemd-remount-fs.service... Mar 17 18:49:08.477052 systemd[1]: Starting systemd-udev-trigger.service... Mar 17 18:49:08.477062 systemd[1]: verity-setup.service: Deactivated successfully. Mar 17 18:49:08.477071 systemd[1]: Stopped verity-setup.service. Mar 17 18:49:08.477080 systemd[1]: Mounted dev-hugepages.mount. Mar 17 18:49:08.477093 systemd[1]: Mounted dev-mqueue.mount. Mar 17 18:49:08.477104 systemd[1]: Mounted media.mount. Mar 17 18:49:08.477114 systemd[1]: Mounted sys-kernel-debug.mount. Mar 17 18:49:08.477125 systemd[1]: Mounted sys-kernel-tracing.mount. Mar 17 18:49:08.477137 systemd[1]: Mounted tmp.mount. Mar 17 18:49:08.477157 systemd-journald[1181]: Journal started Mar 17 18:49:08.477201 systemd-journald[1181]: Runtime Journal (/run/log/journal/902bd80f78554fbe96304df861ded44a) is 8.0M, max 78.5M, 70.5M free. Mar 17 18:48:59.616000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1 Mar 17 18:49:00.313000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:49:00.313000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1 Mar 17 18:49:00.328000 audit: BPF prog-id=10 op=LOAD Mar 17 18:49:00.328000 audit: BPF prog-id=10 op=UNLOAD Mar 17 18:49:00.346000 audit: BPF prog-id=11 op=LOAD Mar 17 18:49:00.346000 audit: BPF prog-id=11 op=UNLOAD Mar 17 18:49:01.644000 audit[1075]: AVC avc: denied { associate } for pid=1075 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023" Mar 17 18:49:01.644000 audit[1075]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=4000022802 a1=4000028ae0 a2=4000026d00 a3=32 items=0 ppid=1058 pid=1075 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:01.644000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:49:01.653000 audit[1075]: AVC avc: denied { associate } for pid=1075 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 Mar 17 18:49:01.653000 audit[1075]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=40000228d9 a2=1ed a3=0 items=2 ppid=1058 pid=1075 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:01.653000 audit: CWD cwd="/" Mar 17 18:49:01.653000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:01.653000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:01.653000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61 Mar 17 18:49:07.707000 audit: BPF prog-id=12 op=LOAD Mar 17 18:49:07.707000 audit: BPF prog-id=3 op=UNLOAD Mar 17 18:49:07.713000 audit: BPF prog-id=13 op=LOAD Mar 17 18:49:07.719000 audit: BPF prog-id=14 op=LOAD Mar 17 18:49:07.719000 audit: BPF prog-id=4 op=UNLOAD Mar 17 18:49:07.719000 audit: BPF prog-id=5 op=UNLOAD Mar 17 18:49:07.720000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:07.751000 audit: BPF prog-id=12 op=UNLOAD Mar 17 18:49:07.760000 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' Mar 17 18:49:07.812000 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' Mar 17 18:49:07.843000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:07.843000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.302000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.317000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.326000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.326000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.336000 audit: BPF prog-id=15 op=LOAD Mar 17 18:49:08.336000 audit: BPF prog-id=16 op=LOAD Mar 17 18:49:08.336000 audit: BPF prog-id=17 op=LOAD Mar 17 18:49:08.336000 audit: BPF prog-id=13 op=UNLOAD Mar 17 18:49:08.336000 audit: BPF prog-id=14 op=UNLOAD Mar 17 18:49:08.429000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.472000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1 Mar 17 18:49:08.472000 audit[1181]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffcbefc910 a2=4000 a3=1 items=0 ppid=1 pid=1181 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:08.472000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald" Mar 17 18:49:07.706957 systemd[1]: Queued start job for default target multi-user.target. Mar 17 18:49:01.605560 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.7 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.7 /var/lib/torcx/store]" Mar 17 18:49:07.706969 systemd[1]: Unnecessary job was removed for dev-sda6.device. Mar 17 18:49:01.631725 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:49:07.721339 systemd[1]: systemd-journald.service: Deactivated successfully. Mar 17 18:49:01.631761 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:49:07.721670 systemd[1]: systemd-journald.service: Consumed 2.625s CPU time. Mar 17 18:49:01.631815 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12" Mar 17 18:49:01.631846 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="skipped missing lower profile" missing profile=oem Mar 17 18:49:01.631937 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory" Mar 17 18:49:01.631955 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)= Mar 17 18:49:01.632158 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack Mar 17 18:49:01.632189 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json Mar 17 18:49:01.632201 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json Mar 17 18:49:01.632552 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10 Mar 17 18:49:01.632587 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl Mar 17 18:49:01.632605 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.7: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.7 Mar 17 18:49:01.632618 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store Mar 17 18:49:01.632635 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.7: no such file or directory" path=/var/lib/torcx/store/3510.3.7 Mar 17 18:49:01.632648 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:01Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store Mar 17 18:49:06.807648 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:49:06.807941 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:49:06.808048 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:49:06.808209 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl Mar 17 18:49:06.808280 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile= Mar 17 18:49:06.808338 /usr/lib/systemd/system-generators/torcx-generator[1075]: time="2025-03-17T18:49:06Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx Mar 17 18:49:08.491070 systemd[1]: Started systemd-journald.service. Mar 17 18:49:08.491000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.496000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.492075 systemd[1]: Finished flatcar-tmpfiles.service. Mar 17 18:49:08.497085 systemd[1]: Finished kmod-static-nodes.service. Mar 17 18:49:08.501000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.502129 systemd[1]: modprobe@configfs.service: Deactivated successfully. Mar 17 18:49:08.502264 systemd[1]: Finished modprobe@configfs.service. Mar 17 18:49:08.506000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.506000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.507218 systemd[1]: modprobe@dm_mod.service: Deactivated successfully. Mar 17 18:49:08.507333 systemd[1]: Finished modprobe@dm_mod.service. Mar 17 18:49:08.511000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.511000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.512232 systemd[1]: modprobe@drm.service: Deactivated successfully. Mar 17 18:49:08.512348 systemd[1]: Finished modprobe@drm.service. Mar 17 18:49:08.516000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.516000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.516709 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. Mar 17 18:49:08.516873 systemd[1]: Finished modprobe@efi_pstore.service. Mar 17 18:49:08.521000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.521000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.521853 systemd[1]: modprobe@fuse.service: Deactivated successfully. Mar 17 18:49:08.521982 systemd[1]: Finished modprobe@fuse.service. Mar 17 18:49:08.526000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.526000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.526663 systemd[1]: modprobe@loop.service: Deactivated successfully. Mar 17 18:49:08.526780 systemd[1]: Finished modprobe@loop.service. Mar 17 18:49:08.530000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.530000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.531553 systemd[1]: Finished systemd-modules-load.service. Mar 17 18:49:08.536000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.536837 systemd[1]: Finished systemd-network-generator.service. Mar 17 18:49:08.541000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.542291 systemd[1]: Finished systemd-remount-fs.service. Mar 17 18:49:08.546000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.547030 systemd[1]: Finished systemd-udev-trigger.service. Mar 17 18:49:08.551000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.552697 systemd[1]: Reached target network-pre.target. Mar 17 18:49:08.558784 systemd[1]: Mounting sys-fs-fuse-connections.mount... Mar 17 18:49:08.564146 systemd[1]: Mounting sys-kernel-config.mount... Mar 17 18:49:08.568318 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/). Mar 17 18:49:08.582834 systemd[1]: Starting systemd-hwdb-update.service... Mar 17 18:49:08.587994 systemd[1]: Starting systemd-journal-flush.service... Mar 17 18:49:08.592333 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). Mar 17 18:49:08.593284 systemd[1]: Starting systemd-random-seed.service... Mar 17 18:49:08.598134 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met. Mar 17 18:49:08.599124 systemd[1]: Starting systemd-sysctl.service... Mar 17 18:49:08.604111 systemd[1]: Starting systemd-sysusers.service... Mar 17 18:49:08.609439 systemd[1]: Starting systemd-udev-settle.service... Mar 17 18:49:08.616490 systemd[1]: Mounted sys-fs-fuse-connections.mount. Mar 17 18:49:08.621414 systemd[1]: Mounted sys-kernel-config.mount. Mar 17 18:49:08.628917 udevadm[1195]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in. Mar 17 18:49:08.641782 systemd[1]: Finished systemd-random-seed.service. Mar 17 18:49:08.648000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.649438 systemd[1]: Reached target first-boot-complete.target. Mar 17 18:49:08.656477 systemd-journald[1181]: Time spent on flushing to /var/log/journal/902bd80f78554fbe96304df861ded44a is 13.526ms for 1068 entries. Mar 17 18:49:08.656477 systemd-journald[1181]: System Journal (/var/log/journal/902bd80f78554fbe96304df861ded44a) is 8.0M, max 2.6G, 2.6G free. Mar 17 18:49:08.731636 systemd-journald[1181]: Received client request to flush runtime journal. Mar 17 18:49:08.671000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.667297 systemd[1]: Finished systemd-sysctl.service. Mar 17 18:49:08.737000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:08.732582 systemd[1]: Finished systemd-journal-flush.service. Mar 17 18:49:09.084879 systemd[1]: Finished systemd-sysusers.service. Mar 17 18:49:09.088000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:09.561549 systemd[1]: Finished systemd-hwdb-update.service. Mar 17 18:49:09.565000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:09.566000 audit: BPF prog-id=18 op=LOAD Mar 17 18:49:09.566000 audit: BPF prog-id=19 op=LOAD Mar 17 18:49:09.566000 audit: BPF prog-id=7 op=UNLOAD Mar 17 18:49:09.566000 audit: BPF prog-id=8 op=UNLOAD Mar 17 18:49:09.567807 systemd[1]: Starting systemd-udevd.service... Mar 17 18:49:09.585799 systemd-udevd[1198]: Using default interface naming scheme 'v252'. Mar 17 18:49:09.830930 systemd[1]: Started systemd-udevd.service. Mar 17 18:49:09.839000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:09.848854 systemd[1]: Starting systemd-networkd.service... Mar 17 18:49:09.847000 audit: BPF prog-id=20 op=LOAD Mar 17 18:49:09.872353 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped. Mar 17 18:49:09.889574 systemd[1]: Starting systemd-userdbd.service... Mar 17 18:49:09.887000 audit: BPF prog-id=21 op=LOAD Mar 17 18:49:09.888000 audit: BPF prog-id=22 op=LOAD Mar 17 18:49:09.888000 audit: BPF prog-id=23 op=LOAD Mar 17 18:49:09.908890 kernel: mousedev: PS/2 mouse device common for all mice Mar 17 18:49:09.938265 systemd[1]: Started systemd-userdbd.service. Mar 17 18:49:09.942000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:09.965920 kernel: hv_vmbus: registering driver hyperv_fb Mar 17 18:49:09.983295 kernel: hyperv_fb: Synthvid Version major 3, minor 5 Mar 17 18:49:09.983394 kernel: hv_vmbus: registering driver hv_balloon Mar 17 18:49:09.983423 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608 Mar 17 18:49:09.965000 audit[1207]: AVC avc: denied { confidentiality } for pid=1207 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1 Mar 17 18:49:09.989920 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0 Mar 17 18:49:09.990005 kernel: hv_balloon: Memory hot add disabled on ARM64 Mar 17 18:49:09.997982 kernel: Console: switching to colour dummy device 80x25 Mar 17 18:49:10.005231 kernel: Console: switching to colour frame buffer device 128x48 Mar 17 18:49:09.965000 audit[1207]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaab211ab3e0 a1=aa2c a2=ffff972724b0 a3=aaab21109010 items=12 ppid=1198 pid=1207 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:09.965000 audit: CWD cwd="/" Mar 17 18:49:09.965000 audit: PATH item=0 name=(null) inode=5668 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=1 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=2 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=3 name=(null) inode=10817 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=4 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=5 name=(null) inode=10818 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=6 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=7 name=(null) inode=10819 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=8 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=9 name=(null) inode=10820 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=10 name=(null) inode=10816 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PATH item=11 name=(null) inode=10821 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0 Mar 17 18:49:09.965000 audit: PROCTITLE proctitle="(udev-worker)" Mar 17 18:49:10.035799 kernel: hv_utils: Registering HyperV Utility Driver Mar 17 18:49:10.035956 kernel: hv_vmbus: registering driver hv_utils Mar 17 18:49:10.040482 kernel: hv_utils: Heartbeat IC version 3.0 Mar 17 18:49:10.040571 kernel: hv_utils: Shutdown IC version 3.2 Mar 17 18:49:10.040592 kernel: hv_utils: TimeSync IC version 4.0 Mar 17 18:49:10.504229 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device. Mar 17 18:49:10.510437 systemd[1]: Finished systemd-udev-settle.service. Mar 17 18:49:10.514000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:10.516488 systemd[1]: Starting lvm2-activation-early.service... Mar 17 18:49:10.566025 systemd-networkd[1219]: lo: Link UP Mar 17 18:49:10.566036 systemd-networkd[1219]: lo: Gained carrier Mar 17 18:49:10.566417 systemd-networkd[1219]: Enumeration completed Mar 17 18:49:10.566530 systemd[1]: Started systemd-networkd.service. Mar 17 18:49:10.570000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:10.572382 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:49:10.582198 systemd-networkd[1219]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:49:10.630819 kernel: mlx5_core fcdf:00:02.0 enP64735s1: Link up Mar 17 18:49:10.655815 kernel: hv_netvsc 0022487a-69f2-0022-487a-69f20022487a eth0: Data path switched to VF: enP64735s1 Mar 17 18:49:10.657539 systemd-networkd[1219]: enP64735s1: Link UP Mar 17 18:49:10.657977 systemd-networkd[1219]: eth0: Link UP Mar 17 18:49:10.658057 systemd-networkd[1219]: eth0: Gained carrier Mar 17 18:49:10.666311 systemd-networkd[1219]: enP64735s1: Gained carrier Mar 17 18:49:10.679899 systemd-networkd[1219]: eth0: DHCPv4 address 10.200.20.45/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:49:10.756428 lvm[1274]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:49:10.797757 systemd[1]: Finished lvm2-activation-early.service. Mar 17 18:49:10.802000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:10.802846 systemd[1]: Reached target cryptsetup.target. Mar 17 18:49:10.808431 systemd[1]: Starting lvm2-activation.service... Mar 17 18:49:10.812480 lvm[1276]: WARNING: Failed to connect to lvmetad. Falling back to device scanning. Mar 17 18:49:10.832653 systemd[1]: Finished lvm2-activation.service. Mar 17 18:49:10.836000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:10.837404 systemd[1]: Reached target local-fs-pre.target. Mar 17 18:49:10.842221 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw). Mar 17 18:49:10.842252 systemd[1]: Reached target local-fs.target. Mar 17 18:49:10.846567 systemd[1]: Reached target machines.target. Mar 17 18:49:10.852095 systemd[1]: Starting ldconfig.service... Mar 17 18:49:10.856076 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met. Mar 17 18:49:10.856145 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:49:10.857222 systemd[1]: Starting systemd-boot-update.service... Mar 17 18:49:10.862423 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service... Mar 17 18:49:10.868984 systemd[1]: Starting systemd-machine-id-commit.service... Mar 17 18:49:10.873785 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met. Mar 17 18:49:10.873862 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met. Mar 17 18:49:10.874912 systemd[1]: Starting systemd-tmpfiles-setup.service... Mar 17 18:49:10.885903 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1278 (bootctl) Mar 17 18:49:10.886990 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service... Mar 17 18:49:10.950372 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service. Mar 17 18:49:10.956000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.009905 systemd[1]: etc-machine\x2did.mount: Deactivated successfully. Mar 17 18:49:11.010486 systemd[1]: Finished systemd-machine-id-commit.service. Mar 17 18:49:11.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.082829 systemd-fsck[1286]: fsck.fat 4.2 (2021-01-31) Mar 17 18:49:11.082829 systemd-fsck[1286]: /dev/sda1: 236 files, 117179/258078 clusters Mar 17 18:49:11.083753 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring. Mar 17 18:49:11.084423 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service. Mar 17 18:49:11.089000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.091648 systemd[1]: Mounting boot.mount... Mar 17 18:49:11.104301 systemd[1]: Mounted boot.mount. Mar 17 18:49:11.114083 systemd[1]: Finished systemd-boot-update.service. Mar 17 18:49:11.117000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.165432 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring. Mar 17 18:49:11.259669 systemd-tmpfiles[1281]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring. Mar 17 18:49:11.699745 systemd[1]: Finished systemd-tmpfiles-setup.service. Mar 17 18:49:11.704000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.706209 systemd[1]: Starting audit-rules.service... Mar 17 18:49:11.711586 systemd[1]: Starting clean-ca-certificates.service... Mar 17 18:49:11.716954 systemd[1]: Starting systemd-journal-catalog-update.service... Mar 17 18:49:11.721000 audit: BPF prog-id=24 op=LOAD Mar 17 18:49:11.723656 systemd[1]: Starting systemd-resolved.service... Mar 17 18:49:11.728000 audit: BPF prog-id=25 op=LOAD Mar 17 18:49:11.730309 systemd[1]: Starting systemd-timesyncd.service... Mar 17 18:49:11.735781 systemd[1]: Starting systemd-update-utmp.service... Mar 17 18:49:11.765000 audit[1298]: SYSTEM_BOOT pid=1298 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.768606 systemd[1]: Finished systemd-update-utmp.service. Mar 17 18:49:11.775000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.783000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.778598 systemd[1]: Finished clean-ca-certificates.service. Mar 17 18:49:11.783962 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt). Mar 17 18:49:11.817398 systemd[1]: Started systemd-timesyncd.service. Mar 17 18:49:11.821000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.822417 systemd[1]: Reached target time-set.target. Mar 17 18:49:11.859130 systemd-resolved[1295]: Positive Trust Anchors: Mar 17 18:49:11.859144 systemd-resolved[1295]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d Mar 17 18:49:11.859170 systemd-resolved[1295]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test Mar 17 18:49:11.870148 systemd[1]: Finished systemd-journal-catalog-update.service. Mar 17 18:49:11.875000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.917287 systemd-resolved[1295]: Using system hostname 'ci-3510.3.7-a-006bc239d1'. Mar 17 18:49:11.918866 systemd[1]: Started systemd-resolved.service. Mar 17 18:49:11.922000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:49:11.923701 systemd[1]: Reached target network.target. Mar 17 18:49:11.927949 systemd[1]: Reached target nss-lookup.target. Mar 17 18:49:12.053000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1 Mar 17 18:49:12.053000 audit[1313]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffc6cbdfe0 a2=420 a3=0 items=0 ppid=1292 pid=1313 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:49:12.053000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573 Mar 17 18:49:12.054979 augenrules[1313]: No rules Mar 17 18:49:12.055454 systemd[1]: Finished audit-rules.service. Mar 17 18:49:12.128522 systemd-timesyncd[1297]: Contacted time server 72.14.183.39:123 (0.flatcar.pool.ntp.org). Mar 17 18:49:12.128589 systemd-timesyncd[1297]: Initial clock synchronization to Mon 2025-03-17 18:49:12.128872 UTC. Mar 17 18:49:12.270974 systemd-networkd[1219]: eth0: Gained IPv6LL Mar 17 18:49:12.272549 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:49:12.278713 systemd[1]: Reached target network-online.target. Mar 17 18:49:17.653520 ldconfig[1277]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start. Mar 17 18:49:17.690783 systemd[1]: Finished ldconfig.service. Mar 17 18:49:17.696702 systemd[1]: Starting systemd-update-done.service... Mar 17 18:49:17.718743 systemd[1]: Finished systemd-update-done.service. Mar 17 18:49:17.723674 systemd[1]: Reached target sysinit.target. Mar 17 18:49:17.728148 systemd[1]: Started motdgen.path. Mar 17 18:49:17.731989 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path. Mar 17 18:49:17.737922 systemd[1]: Started logrotate.timer. Mar 17 18:49:17.741625 systemd[1]: Started mdadm.timer. Mar 17 18:49:17.744992 systemd[1]: Started systemd-tmpfiles-clean.timer. Mar 17 18:49:17.750115 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate). Mar 17 18:49:17.750145 systemd[1]: Reached target paths.target. Mar 17 18:49:17.753947 systemd[1]: Reached target timers.target. Mar 17 18:49:17.758437 systemd[1]: Listening on dbus.socket. Mar 17 18:49:17.763405 systemd[1]: Starting docker.socket... Mar 17 18:49:17.769140 systemd[1]: Listening on sshd.socket. Mar 17 18:49:17.773395 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:49:17.773857 systemd[1]: Listening on docker.socket. Mar 17 18:49:17.778324 systemd[1]: Reached target sockets.target. Mar 17 18:49:17.782751 systemd[1]: Reached target basic.target. Mar 17 18:49:17.787087 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:49:17.787114 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met. Mar 17 18:49:17.788068 systemd[1]: Starting containerd.service... Mar 17 18:49:17.792625 systemd[1]: Starting dbus.service... Mar 17 18:49:17.797051 systemd[1]: Starting enable-oem-cloudinit.service... Mar 17 18:49:17.802359 systemd[1]: Starting extend-filesystems.service... Mar 17 18:49:17.809344 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment). Mar 17 18:49:17.810310 systemd[1]: Starting motdgen.service... Mar 17 18:49:17.814713 systemd[1]: Started nvidia.service. Mar 17 18:49:17.819701 systemd[1]: Starting ssh-key-proc-cmdline.service... Mar 17 18:49:17.824855 systemd[1]: Starting sshd-keygen.service... Mar 17 18:49:17.830322 systemd[1]: Starting systemd-logind.service... Mar 17 18:49:17.834222 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f). Mar 17 18:49:17.834280 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0). Mar 17 18:49:17.834653 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details. Mar 17 18:49:17.835254 systemd[1]: Starting update-engine.service... Mar 17 18:49:17.842061 systemd[1]: Starting update-ssh-keys-after-ignition.service... Mar 17 18:49:17.848108 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully. Mar 17 18:49:17.848743 systemd[1]: Finished ssh-key-proc-cmdline.service. Mar 17 18:49:17.863374 jq[1339]: true Mar 17 18:49:17.866253 jq[1323]: false Mar 17 18:49:17.878089 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'. Mar 17 18:49:17.878266 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped. Mar 17 18:49:17.887719 systemd[1]: motdgen.service: Deactivated successfully. Mar 17 18:49:17.887949 systemd[1]: Finished motdgen.service. Mar 17 18:49:17.901232 systemd-logind[1332]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard) Mar 17 18:49:17.901409 systemd-logind[1332]: New seat seat0. Mar 17 18:49:17.911557 extend-filesystems[1324]: Found sda Mar 17 18:49:17.911557 extend-filesystems[1324]: Found sda1 Mar 17 18:49:17.911557 extend-filesystems[1324]: Found sda2 Mar 17 18:49:17.929287 extend-filesystems[1324]: Found sda3 Mar 17 18:49:17.929287 extend-filesystems[1324]: Found usr Mar 17 18:49:17.929287 extend-filesystems[1324]: Found sda4 Mar 17 18:49:17.929287 extend-filesystems[1324]: Found sda6 Mar 17 18:49:17.929287 extend-filesystems[1324]: Found sda7 Mar 17 18:49:17.929287 extend-filesystems[1324]: Found sda9 Mar 17 18:49:17.929287 extend-filesystems[1324]: Checking size of /dev/sda9 Mar 17 18:49:17.961165 jq[1341]: true Mar 17 18:49:17.970034 env[1343]: time="2025-03-17T18:49:17.969987641Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16 Mar 17 18:49:17.996299 env[1343]: time="2025-03-17T18:49:17.996253132Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1 Mar 17 18:49:17.996540 env[1343]: time="2025-03-17T18:49:17.996403615Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.997575 env[1343]: time="2025-03-17T18:49:17.997511081Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.179-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:49:17.997641 env[1343]: time="2025-03-17T18:49:17.997578003Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.997825 env[1343]: time="2025-03-17T18:49:17.997786448Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:49:17.997862 env[1343]: time="2025-03-17T18:49:17.997824969Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.997862 env[1343]: time="2025-03-17T18:49:17.997839649Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured" Mar 17 18:49:17.997862 env[1343]: time="2025-03-17T18:49:17.997849049Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.998223 env[1343]: time="2025-03-17T18:49:17.998198777Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.998481 env[1343]: time="2025-03-17T18:49:17.998461303Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1 Mar 17 18:49:17.998622 env[1343]: time="2025-03-17T18:49:17.998598587Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1 Mar 17 18:49:17.998652 env[1343]: time="2025-03-17T18:49:17.998618947Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1 Mar 17 18:49:17.998690 env[1343]: time="2025-03-17T18:49:17.998670948Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured" Mar 17 18:49:17.998725 env[1343]: time="2025-03-17T18:49:17.998689789Z" level=info msg="metadata content store policy set" policy=shared Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012650656Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012685337Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012703897Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012738258Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012752178Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012765659Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013060 env[1343]: time="2025-03-17T18:49:18.012777659Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013259349Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013285150Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013297790Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013309471Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013322671Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013423673Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013492355Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013756800Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013784801Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.013829 env[1343]: time="2025-03-17T18:49:18.013798041Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013880323Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013893283Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013904724Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013915684Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013972485Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013986605Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.013998126Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.014008846Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014067 env[1343]: time="2025-03-17T18:49:18.014021646Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014141489Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014161209Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014173049Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014183810Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014196930Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014207650Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1 Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014223650Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin" Mar 17 18:49:18.014277 env[1343]: time="2025-03-17T18:49:18.014256651Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1 Mar 17 18:49:18.014997 env[1343]: time="2025-03-17T18:49:18.014434415Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}" Mar 17 18:49:18.014997 env[1343]: time="2025-03-17T18:49:18.014495656Z" level=info msg="Connect containerd service" Mar 17 18:49:18.014997 env[1343]: time="2025-03-17T18:49:18.014524737Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\"" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.015068149Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.015323994Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.015359635Z" level=info msg=serving... address=/run/containerd/containerd.sock Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.015403556Z" level=info msg="containerd successfully booted in 0.046607s" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.016925269Z" level=info msg="Start subscribing containerd event" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.016977871Z" level=info msg="Start recovering state" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.017043312Z" level=info msg="Start event monitor" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.017062992Z" level=info msg="Start snapshots syncer" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.017112313Z" level=info msg="Start cni network conf syncer for default" Mar 17 18:49:18.032729 env[1343]: time="2025-03-17T18:49:18.017123634Z" level=info msg="Start streaming server" Mar 17 18:49:18.033144 extend-filesystems[1324]: Old size kept for /dev/sda9 Mar 17 18:49:18.033144 extend-filesystems[1324]: Found sr0 Mar 17 18:49:18.015478 systemd[1]: Started containerd.service. Mar 17 18:49:18.025167 systemd[1]: extend-filesystems.service: Deactivated successfully. Mar 17 18:49:18.025321 systemd[1]: Finished extend-filesystems.service. Mar 17 18:49:18.061546 bash[1376]: Updated "/home/core/.ssh/authorized_keys" Mar 17 18:49:18.062201 systemd[1]: Finished update-ssh-keys-after-ignition.service. Mar 17 18:49:18.104536 dbus-daemon[1322]: [system] SELinux support is enabled Mar 17 18:49:18.104684 systemd[1]: Started dbus.service. Mar 17 18:49:18.110318 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml). Mar 17 18:49:18.110340 systemd[1]: Reached target system-config.target. Mar 17 18:49:18.117991 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url). Mar 17 18:49:18.118009 systemd[1]: Reached target user-config.target. Mar 17 18:49:18.126492 systemd[1]: Started systemd-logind.service. Mar 17 18:49:18.154579 systemd[1]: nvidia.service: Deactivated successfully. Mar 17 18:49:18.512224 update_engine[1335]: I0317 18:49:18.493186 1335 main.cc:92] Flatcar Update Engine starting Mar 17 18:49:18.555221 systemd[1]: Started update-engine.service. Mar 17 18:49:18.566070 update_engine[1335]: I0317 18:49:18.555259 1335 update_check_scheduler.cc:74] Next update check in 9m11s Mar 17 18:49:18.561173 systemd[1]: Started locksmithd.service. Mar 17 18:49:19.633045 locksmithd[1418]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot" Mar 17 18:49:21.508432 sshd_keygen[1337]: ssh-keygen: generating new host keys: RSA ECDSA ED25519 Mar 17 18:49:21.524492 systemd[1]: Finished sshd-keygen.service. Mar 17 18:49:21.530986 systemd[1]: Starting issuegen.service... Mar 17 18:49:21.536162 systemd[1]: Started waagent.service. Mar 17 18:49:21.541077 systemd[1]: issuegen.service: Deactivated successfully. Mar 17 18:49:21.541235 systemd[1]: Finished issuegen.service. Mar 17 18:49:21.546944 systemd[1]: Starting systemd-user-sessions.service... Mar 17 18:49:21.566566 systemd[1]: Finished systemd-user-sessions.service. Mar 17 18:49:21.573651 systemd[1]: Started getty@tty1.service. Mar 17 18:49:21.579093 systemd[1]: Started serial-getty@ttyAMA0.service. Mar 17 18:49:21.583910 systemd[1]: Reached target getty.target. Mar 17 18:49:21.588394 systemd[1]: Reached target multi-user.target. Mar 17 18:49:21.593886 systemd[1]: Starting systemd-update-utmp-runlevel.service... Mar 17 18:49:21.605213 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Mar 17 18:49:21.605368 systemd[1]: Finished systemd-update-utmp-runlevel.service. Mar 17 18:49:21.610914 systemd[1]: Startup finished in 754ms (kernel) + 11.517s (initrd) + 22.187s (userspace) = 34.459s. Mar 17 18:49:22.185487 login[1437]: pam_lastlog(login:session): file /var/log/lastlog is locked/write Mar 17 18:49:22.185938 login[1436]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:49:22.234439 systemd[1]: Created slice user-500.slice. Mar 17 18:49:22.235470 systemd[1]: Starting user-runtime-dir@500.service... Mar 17 18:49:22.238144 systemd-logind[1332]: New session 1 of user core. Mar 17 18:49:22.256536 systemd[1]: Finished user-runtime-dir@500.service. Mar 17 18:49:22.257882 systemd[1]: Starting user@500.service... Mar 17 18:49:22.274576 (systemd)[1441]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:49:22.452503 systemd[1441]: Queued start job for default target default.target. Mar 17 18:49:22.453640 systemd[1441]: Reached target paths.target. Mar 17 18:49:22.453757 systemd[1441]: Reached target sockets.target. Mar 17 18:49:22.453852 systemd[1441]: Reached target timers.target. Mar 17 18:49:22.453923 systemd[1441]: Reached target basic.target. Mar 17 18:49:22.454071 systemd[1]: Started user@500.service. Mar 17 18:49:22.454873 systemd[1]: Started session-1.scope. Mar 17 18:49:22.455044 systemd[1441]: Reached target default.target. Mar 17 18:49:22.455477 systemd[1441]: Startup finished in 174ms. Mar 17 18:49:23.186938 login[1437]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0) Mar 17 18:49:23.191305 systemd[1]: Started session-2.scope. Mar 17 18:49:23.192631 systemd-logind[1332]: New session 2 of user core. Mar 17 18:49:27.944684 waagent[1434]: 2025-03-17T18:49:27.944564Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2 Mar 17 18:49:27.997285 waagent[1434]: 2025-03-17T18:49:27.997198Z INFO Daemon Daemon OS: flatcar 3510.3.7 Mar 17 18:49:28.002041 waagent[1434]: 2025-03-17T18:49:28.001976Z INFO Daemon Daemon Python: 3.9.16 Mar 17 18:49:28.007212 waagent[1434]: 2025-03-17T18:49:28.006884Z INFO Daemon Daemon Run daemon Mar 17 18:49:28.011707 waagent[1434]: 2025-03-17T18:49:28.011647Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.7' Mar 17 18:49:28.028738 waagent[1434]: 2025-03-17T18:49:28.028619Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Mar 17 18:49:28.043554 waagent[1434]: 2025-03-17T18:49:28.043432Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:49:28.053494 waagent[1434]: 2025-03-17T18:49:28.053426Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:49:28.058699 waagent[1434]: 2025-03-17T18:49:28.058638Z INFO Daemon Daemon Using waagent for provisioning Mar 17 18:49:28.065257 waagent[1434]: 2025-03-17T18:49:28.065193Z INFO Daemon Daemon Activate resource disk Mar 17 18:49:28.071684 waagent[1434]: 2025-03-17T18:49:28.071626Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb Mar 17 18:49:28.087422 waagent[1434]: 2025-03-17T18:49:28.087359Z INFO Daemon Daemon Found device: None Mar 17 18:49:28.091951 waagent[1434]: 2025-03-17T18:49:28.091892Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology Mar 17 18:49:28.101474 waagent[1434]: 2025-03-17T18:49:28.101416Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0 Mar 17 18:49:28.113468 waagent[1434]: 2025-03-17T18:49:28.113410Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:49:28.120144 waagent[1434]: 2025-03-17T18:49:28.120087Z INFO Daemon Daemon Running default provisioning handler Mar 17 18:49:28.133613 waagent[1434]: 2025-03-17T18:49:28.133477Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1. Mar 17 18:49:28.148921 waagent[1434]: 2025-03-17T18:49:28.148792Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service' Mar 17 18:49:28.159422 waagent[1434]: 2025-03-17T18:49:28.159360Z INFO Daemon Daemon cloud-init is enabled: False Mar 17 18:49:28.164733 waagent[1434]: 2025-03-17T18:49:28.164676Z INFO Daemon Daemon Copying ovf-env.xml Mar 17 18:49:28.275403 waagent[1434]: 2025-03-17T18:49:28.272432Z INFO Daemon Daemon Successfully mounted dvd Mar 17 18:49:28.417061 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully. Mar 17 18:49:28.482620 waagent[1434]: 2025-03-17T18:49:28.482475Z INFO Daemon Daemon Detect protocol endpoint Mar 17 18:49:28.487808 waagent[1434]: 2025-03-17T18:49:28.487740Z INFO Daemon Daemon Clean protocol and wireserver endpoint Mar 17 18:49:28.494033 waagent[1434]: 2025-03-17T18:49:28.493970Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler Mar 17 18:49:28.501281 waagent[1434]: 2025-03-17T18:49:28.501219Z INFO Daemon Daemon Test for route to 168.63.129.16 Mar 17 18:49:28.507434 waagent[1434]: 2025-03-17T18:49:28.507376Z INFO Daemon Daemon Route to 168.63.129.16 exists Mar 17 18:49:28.513247 waagent[1434]: 2025-03-17T18:49:28.513191Z INFO Daemon Daemon Wire server endpoint:168.63.129.16 Mar 17 18:49:28.619909 waagent[1434]: 2025-03-17T18:49:28.619841Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05 Mar 17 18:49:28.627275 waagent[1434]: 2025-03-17T18:49:28.627208Z INFO Daemon Daemon Wire protocol version:2012-11-30 Mar 17 18:49:28.632953 waagent[1434]: 2025-03-17T18:49:28.632890Z INFO Daemon Daemon Server preferred version:2015-04-05 Mar 17 18:49:29.270001 waagent[1434]: 2025-03-17T18:49:29.269855Z INFO Daemon Daemon Initializing goal state during protocol detection Mar 17 18:49:29.285825 waagent[1434]: 2025-03-17T18:49:29.285747Z INFO Daemon Daemon Forcing an update of the goal state.. Mar 17 18:49:29.291700 waagent[1434]: 2025-03-17T18:49:29.291642Z INFO Daemon Daemon Fetching goal state [incarnation 1] Mar 17 18:49:29.377157 waagent[1434]: 2025-03-17T18:49:29.377033Z INFO Daemon Daemon Found private key matching thumbprint 17C45693C802D6AFDA6978E258A7B7438237DAAC Mar 17 18:49:29.385566 waagent[1434]: 2025-03-17T18:49:29.385502Z INFO Daemon Daemon Certificate with thumbprint 0C36FF81DAEF83181DE3DB43C142D6432B395784 has no matching private key. Mar 17 18:49:29.395311 waagent[1434]: 2025-03-17T18:49:29.395251Z INFO Daemon Daemon Fetch goal state completed Mar 17 18:49:29.467594 waagent[1434]: 2025-03-17T18:49:29.467532Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: dec85515-fcda-43bf-b382-5849afd03fce New eTag: 9733745107888360561] Mar 17 18:49:29.478716 waagent[1434]: 2025-03-17T18:49:29.478636Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:49:29.495225 waagent[1434]: 2025-03-17T18:49:29.495143Z INFO Daemon Daemon Starting provisioning Mar 17 18:49:29.500873 waagent[1434]: 2025-03-17T18:49:29.500810Z INFO Daemon Daemon Handle ovf-env.xml. Mar 17 18:49:29.505975 waagent[1434]: 2025-03-17T18:49:29.505919Z INFO Daemon Daemon Set hostname [ci-3510.3.7-a-006bc239d1] Mar 17 18:49:29.543947 waagent[1434]: 2025-03-17T18:49:29.543756Z INFO Daemon Daemon Publish hostname [ci-3510.3.7-a-006bc239d1] Mar 17 18:49:29.551066 waagent[1434]: 2025-03-17T18:49:29.550971Z INFO Daemon Daemon Examine /proc/net/route for primary interface Mar 17 18:49:29.557679 waagent[1434]: 2025-03-17T18:49:29.557614Z INFO Daemon Daemon Primary interface is [eth0] Mar 17 18:49:29.574432 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully. Mar 17 18:49:29.574591 systemd[1]: Stopped systemd-networkd-wait-online.service. Mar 17 18:49:29.574645 systemd[1]: Stopping systemd-networkd-wait-online.service... Mar 17 18:49:29.574881 systemd[1]: Stopping systemd-networkd.service... Mar 17 18:49:29.583852 systemd-networkd[1219]: eth0: DHCPv6 lease lost Mar 17 18:49:29.585289 systemd[1]: systemd-networkd.service: Deactivated successfully. Mar 17 18:49:29.585470 systemd[1]: Stopped systemd-networkd.service. Mar 17 18:49:29.587491 systemd[1]: Starting systemd-networkd.service... Mar 17 18:49:29.613887 systemd-networkd[1485]: enP64735s1: Link UP Mar 17 18:49:29.613896 systemd-networkd[1485]: enP64735s1: Gained carrier Mar 17 18:49:29.614759 systemd-networkd[1485]: eth0: Link UP Mar 17 18:49:29.614770 systemd-networkd[1485]: eth0: Gained carrier Mar 17 18:49:29.615274 systemd-networkd[1485]: lo: Link UP Mar 17 18:49:29.615284 systemd-networkd[1485]: lo: Gained carrier Mar 17 18:49:29.615515 systemd-networkd[1485]: eth0: Gained IPv6LL Mar 17 18:49:29.616748 systemd-networkd[1485]: Enumeration completed Mar 17 18:49:29.616877 systemd[1]: Started systemd-networkd.service. Mar 17 18:49:29.618538 systemd[1]: Starting systemd-networkd-wait-online.service... Mar 17 18:49:29.625108 waagent[1434]: 2025-03-17T18:49:29.618845Z INFO Daemon Daemon Create user account if not exists Mar 17 18:49:29.626398 waagent[1434]: 2025-03-17T18:49:29.626307Z INFO Daemon Daemon User core already exists, skip useradd Mar 17 18:49:29.626567 systemd-networkd[1485]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network. Mar 17 18:49:29.633530 waagent[1434]: 2025-03-17T18:49:29.633424Z INFO Daemon Daemon Configure sudoer Mar 17 18:49:29.640249 waagent[1434]: 2025-03-17T18:49:29.640122Z INFO Daemon Daemon Configure sshd Mar 17 18:49:29.645523 waagent[1434]: 2025-03-17T18:49:29.645419Z INFO Daemon Daemon Deploy ssh public key. Mar 17 18:49:29.655908 systemd-networkd[1485]: eth0: DHCPv4 address 10.200.20.45/24, gateway 10.200.20.1 acquired from 168.63.129.16 Mar 17 18:49:29.658128 systemd[1]: Finished systemd-networkd-wait-online.service. Mar 17 18:49:30.827117 waagent[1434]: 2025-03-17T18:49:30.827023Z INFO Daemon Daemon Provisioning complete Mar 17 18:49:30.845696 waagent[1434]: 2025-03-17T18:49:30.845583Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping Mar 17 18:49:30.853632 waagent[1434]: 2025-03-17T18:49:30.853535Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions. Mar 17 18:49:30.865235 waagent[1434]: 2025-03-17T18:49:30.865139Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent Mar 17 18:49:31.183289 waagent[1494]: 2025-03-17T18:49:31.183115Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent Mar 17 18:49:31.184051 waagent[1494]: 2025-03-17T18:49:31.183983Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:31.184196 waagent[1494]: 2025-03-17T18:49:31.184145Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:31.197446 waagent[1494]: 2025-03-17T18:49:31.197339Z INFO ExtHandler ExtHandler Forcing an update of the goal state.. Mar 17 18:49:31.197664 waagent[1494]: 2025-03-17T18:49:31.197609Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1] Mar 17 18:49:31.277625 waagent[1494]: 2025-03-17T18:49:31.277456Z INFO ExtHandler ExtHandler Found private key matching thumbprint 17C45693C802D6AFDA6978E258A7B7438237DAAC Mar 17 18:49:31.277917 waagent[1494]: 2025-03-17T18:49:31.277857Z INFO ExtHandler ExtHandler Certificate with thumbprint 0C36FF81DAEF83181DE3DB43C142D6432B395784 has no matching private key. Mar 17 18:49:31.278177 waagent[1494]: 2025-03-17T18:49:31.278125Z INFO ExtHandler ExtHandler Fetch goal state completed Mar 17 18:49:31.297517 waagent[1494]: 2025-03-17T18:49:31.297443Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: e8bd7146-a143-4232-ae2d-f87611ae1268 New eTag: 9733745107888360561] Mar 17 18:49:31.298212 waagent[1494]: 2025-03-17T18:49:31.298142Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob Mar 17 18:49:31.351506 waagent[1494]: 2025-03-17T18:49:31.351340Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:49:31.362176 waagent[1494]: 2025-03-17T18:49:31.362079Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1494 Mar 17 18:49:31.366190 waagent[1494]: 2025-03-17T18:49:31.366101Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:49:31.367582 waagent[1494]: 2025-03-17T18:49:31.367508Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:49:31.511639 waagent[1494]: 2025-03-17T18:49:31.511507Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:49:31.512081 waagent[1494]: 2025-03-17T18:49:31.512017Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:49:31.520627 waagent[1494]: 2025-03-17T18:49:31.520551Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:49:31.521261 waagent[1494]: 2025-03-17T18:49:31.521190Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:49:31.522547 waagent[1494]: 2025-03-17T18:49:31.522473Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True] Mar 17 18:49:31.524077 waagent[1494]: 2025-03-17T18:49:31.523991Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:49:31.524775 waagent[1494]: 2025-03-17T18:49:31.524708Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:31.525064 waagent[1494]: 2025-03-17T18:49:31.525008Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:31.525767 waagent[1494]: 2025-03-17T18:49:31.525704Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:49:31.526229 waagent[1494]: 2025-03-17T18:49:31.526163Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:49:31.526229 waagent[1494]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:49:31.526229 waagent[1494]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:49:31.526229 waagent[1494]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:49:31.526229 waagent[1494]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:31.526229 waagent[1494]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:31.526229 waagent[1494]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:31.529129 waagent[1494]: 2025-03-17T18:49:31.528908Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:49:31.530303 waagent[1494]: 2025-03-17T18:49:31.530230Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:31.530591 waagent[1494]: 2025-03-17T18:49:31.530535Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:31.531063 waagent[1494]: 2025-03-17T18:49:31.530980Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:49:31.531649 waagent[1494]: 2025-03-17T18:49:31.531563Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:49:31.531754 waagent[1494]: 2025-03-17T18:49:31.531691Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:49:31.532413 waagent[1494]: 2025-03-17T18:49:31.532341Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:49:31.532830 waagent[1494]: 2025-03-17T18:49:31.532747Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:49:31.534002 waagent[1494]: 2025-03-17T18:49:31.533924Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:49:31.534118 waagent[1494]: 2025-03-17T18:49:31.534035Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:49:31.534706 waagent[1494]: 2025-03-17T18:49:31.534633Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:49:31.547151 waagent[1494]: 2025-03-17T18:49:31.547058Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod) Mar 17 18:49:31.547858 waagent[1494]: 2025-03-17T18:49:31.547764Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:49:31.549128 waagent[1494]: 2025-03-17T18:49:31.549048Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders' Mar 17 18:49:31.583675 waagent[1494]: 2025-03-17T18:49:31.583539Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1485' Mar 17 18:49:31.598590 waagent[1494]: 2025-03-17T18:49:31.598507Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel. Mar 17 18:49:31.701664 waagent[1494]: 2025-03-17T18:49:31.701443Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:49:31.701664 waagent[1494]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:49:31.701664 waagent[1494]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Mar 17 18:49:31.701664 waagent[1494]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:69:f2 brd ff:ff:ff:ff:ff:ff Mar 17 18:49:31.701664 waagent[1494]: 3: enP64735s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:69:f2 brd ff:ff:ff:ff:ff:ff\ altname enP64735p0s2 Mar 17 18:49:31.701664 waagent[1494]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:49:31.701664 waagent[1494]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:49:31.701664 waagent[1494]: 2: eth0 inet 10.200.20.45/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:49:31.701664 waagent[1494]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:49:31.701664 waagent[1494]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:49:31.701664 waagent[1494]: 2: eth0 inet6 fe80::222:48ff:fe7a:69f2/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:49:31.874096 waagent[1494]: 2025-03-17T18:49:31.874025Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.12.0.2 -- exiting Mar 17 18:49:32.870261 waagent[1434]: 2025-03-17T18:49:32.870114Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running Mar 17 18:49:32.875215 waagent[1434]: 2025-03-17T18:49:32.875151Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.12.0.2 to be the latest agent Mar 17 18:49:34.172516 waagent[1526]: 2025-03-17T18:49:34.172413Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.12.0.2) Mar 17 18:49:34.173287 waagent[1526]: 2025-03-17T18:49:34.173220Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.7 Mar 17 18:49:34.173420 waagent[1526]: 2025-03-17T18:49:34.173376Z INFO ExtHandler ExtHandler Python: 3.9.16 Mar 17 18:49:34.173544 waagent[1526]: 2025-03-17T18:49:34.173502Z INFO ExtHandler ExtHandler CPU Arch: aarch64 Mar 17 18:49:34.181763 waagent[1526]: 2025-03-17T18:49:34.181611Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.7; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; Arch: aarch64; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1; Mar 17 18:49:34.182250 waagent[1526]: 2025-03-17T18:49:34.182190Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:34.182399 waagent[1526]: 2025-03-17T18:49:34.182355Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:34.205785 waagent[1526]: 2025-03-17T18:49:34.205697Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1] Mar 17 18:49:34.219872 waagent[1526]: 2025-03-17T18:49:34.219793Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.164 Mar 17 18:49:34.220987 waagent[1526]: 2025-03-17T18:49:34.220927Z INFO ExtHandler Mar 17 18:49:34.221136 waagent[1526]: 2025-03-17T18:49:34.221091Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 60d2f7ef-9fc0-424f-8c89-3ff751edd6b7 eTag: 9733745107888360561 source: Fabric] Mar 17 18:49:34.221884 waagent[1526]: 2025-03-17T18:49:34.221829Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them. Mar 17 18:49:34.223106 waagent[1526]: 2025-03-17T18:49:34.223046Z INFO ExtHandler Mar 17 18:49:34.223242 waagent[1526]: 2025-03-17T18:49:34.223196Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1] Mar 17 18:49:34.230768 waagent[1526]: 2025-03-17T18:49:34.230713Z INFO ExtHandler ExtHandler Downloading artifacts profile blob Mar 17 18:49:34.231332 waagent[1526]: 2025-03-17T18:49:34.231281Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required Mar 17 18:49:34.253693 waagent[1526]: 2025-03-17T18:49:34.253631Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel. Mar 17 18:49:34.332375 waagent[1526]: 2025-03-17T18:49:34.332212Z INFO ExtHandler Downloaded certificate {'thumbprint': '17C45693C802D6AFDA6978E258A7B7438237DAAC', 'hasPrivateKey': True} Mar 17 18:49:34.333532 waagent[1526]: 2025-03-17T18:49:34.333467Z INFO ExtHandler Downloaded certificate {'thumbprint': '0C36FF81DAEF83181DE3DB43C142D6432B395784', 'hasPrivateKey': False} Mar 17 18:49:34.334607 waagent[1526]: 2025-03-17T18:49:34.334549Z INFO ExtHandler Fetch goal state completed Mar 17 18:49:34.353720 waagent[1526]: 2025-03-17T18:49:34.353571Z INFO ExtHandler ExtHandler OpenSSL version: OpenSSL 3.0.15 3 Sep 2024 (Library: OpenSSL 3.0.15 3 Sep 2024) Mar 17 18:49:34.366951 waagent[1526]: 2025-03-17T18:49:34.366834Z INFO ExtHandler ExtHandler WALinuxAgent-2.12.0.2 running as process 1526 Mar 17 18:49:34.370374 waagent[1526]: 2025-03-17T18:49:34.370293Z INFO ExtHandler ExtHandler [CGI] Cgroup monitoring is not supported on ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] Mar 17 18:49:34.371513 waagent[1526]: 2025-03-17T18:49:34.371452Z INFO ExtHandler ExtHandler [CGI] Agent will reset the quotas in case distro: ['flatcar', '3510.3.7', '', 'Flatcar Container Linux by Kinvolk'] went from supported to unsupported Mar 17 18:49:34.371821 waagent[1526]: 2025-03-17T18:49:34.371758Z INFO ExtHandler ExtHandler [CGI] Agent cgroups enabled: False Mar 17 18:49:34.373928 waagent[1526]: 2025-03-17T18:49:34.373870Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules Mar 17 18:49:34.379037 waagent[1526]: 2025-03-17T18:49:34.378970Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service Mar 17 18:49:34.379475 waagent[1526]: 2025-03-17T18:49:34.379415Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup Mar 17 18:49:34.387571 waagent[1526]: 2025-03-17T18:49:34.387499Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now Mar 17 18:49:34.388180 waagent[1526]: 2025-03-17T18:49:34.388117Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service' Mar 17 18:49:34.395507 waagent[1526]: 2025-03-17T18:49:34.395297Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up. Mar 17 18:49:34.397081 waagent[1526]: 2025-03-17T18:49:34.396999Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: 1. configuration enabled [True], 2. cgroups v1 enabled [False] OR cgroups v2 is in use and v2 resource limiting configuration enabled [False], 3. python supported: [True] Mar 17 18:49:34.398879 waagent[1526]: 2025-03-17T18:49:34.398733Z INFO ExtHandler ExtHandler Starting env monitor service. Mar 17 18:49:34.399512 waagent[1526]: 2025-03-17T18:49:34.399420Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:34.399874 waagent[1526]: 2025-03-17T18:49:34.399783Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:34.400606 waagent[1526]: 2025-03-17T18:49:34.400533Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service. Mar 17 18:49:34.401263 waagent[1526]: 2025-03-17T18:49:34.401193Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled. Mar 17 18:49:34.401612 waagent[1526]: 2025-03-17T18:49:34.401538Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file Mar 17 18:49:34.401851 waagent[1526]: 2025-03-17T18:49:34.401751Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread Mar 17 18:49:34.402162 waagent[1526]: 2025-03-17T18:49:34.402083Z INFO ExtHandler ExtHandler Start Extension Telemetry service. Mar 17 18:49:34.402669 waagent[1526]: 2025-03-17T18:49:34.402595Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16 Mar 17 18:49:34.403326 waagent[1526]: 2025-03-17T18:49:34.403255Z INFO EnvHandler ExtHandler Configure routes Mar 17 18:49:34.403661 waagent[1526]: 2025-03-17T18:49:34.403599Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route: Mar 17 18:49:34.403661 waagent[1526]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT Mar 17 18:49:34.403661 waagent[1526]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0 Mar 17 18:49:34.403661 waagent[1526]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0 Mar 17 18:49:34.403661 waagent[1526]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:34.403661 waagent[1526]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:34.403661 waagent[1526]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0 Mar 17 18:49:34.403861 waagent[1526]: 2025-03-17T18:49:34.403749Z INFO EnvHandler ExtHandler Gateway:None Mar 17 18:49:34.403959 waagent[1526]: 2025-03-17T18:49:34.403909Z INFO EnvHandler ExtHandler Routes:None Mar 17 18:49:34.406708 waagent[1526]: 2025-03-17T18:49:34.406505Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True Mar 17 18:49:34.406868 waagent[1526]: 2025-03-17T18:49:34.406769Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status. Mar 17 18:49:34.408052 waagent[1526]: 2025-03-17T18:49:34.407989Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread Mar 17 18:49:34.427524 waagent[1526]: 2025-03-17T18:49:34.427396Z INFO ExtHandler ExtHandler Downloading agent manifest Mar 17 18:49:34.452218 waagent[1526]: 2025-03-17T18:49:34.452150Z INFO MonitorHandler ExtHandler Network interfaces: Mar 17 18:49:34.452218 waagent[1526]: Executing ['ip', '-a', '-o', 'link']: Mar 17 18:49:34.452218 waagent[1526]: 1: lo: mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00 Mar 17 18:49:34.452218 waagent[1526]: 2: eth0: mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:69:f2 brd ff:ff:ff:ff:ff:ff Mar 17 18:49:34.452218 waagent[1526]: 3: enP64735s1: mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:22:48:7a:69:f2 brd ff:ff:ff:ff:ff:ff\ altname enP64735p0s2 Mar 17 18:49:34.452218 waagent[1526]: Executing ['ip', '-4', '-a', '-o', 'address']: Mar 17 18:49:34.452218 waagent[1526]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever Mar 17 18:49:34.452218 waagent[1526]: 2: eth0 inet 10.200.20.45/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever Mar 17 18:49:34.452218 waagent[1526]: Executing ['ip', '-6', '-a', '-o', 'address']: Mar 17 18:49:34.452218 waagent[1526]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever Mar 17 18:49:34.452218 waagent[1526]: 2: eth0 inet6 fe80::222:48ff:fe7a:69f2/64 scope link \ valid_lft forever preferred_lft forever Mar 17 18:49:34.497181 waagent[1526]: 2025-03-17T18:49:34.497090Z INFO ExtHandler ExtHandler Mar 17 18:49:34.497348 waagent[1526]: 2025-03-17T18:49:34.497292Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 51a5ab46-bb46-494d-948e-5b0118c76c33 correlation 6a7bc797-41d6-477d-9e6c-1e85096f4690 created: 2025-03-17T18:48:02.331390Z] Mar 17 18:49:34.498369 waagent[1526]: 2025-03-17T18:49:34.498302Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything. Mar 17 18:49:34.501130 waagent[1526]: 2025-03-17T18:49:34.501048Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms] Mar 17 18:49:34.530663 waagent[1526]: 2025-03-17T18:49:34.530572Z INFO ExtHandler ExtHandler Looking for existing remote access users. Mar 17 18:49:34.553108 waagent[1526]: 2025-03-17T18:49:34.553020Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.12.0.2 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: E7E3D7D1-50D2-4BB4-9BFA-9D8FACEB8DA0;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1;UpdateMode: SelfUpdate;] Mar 17 18:49:34.687742 waagent[1526]: 2025-03-17T18:49:34.687533Z INFO EnvHandler ExtHandler Created firewall rules for the Azure Fabric: Mar 17 18:49:34.687742 waagent[1526]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.687742 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.687742 waagent[1526]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.687742 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.687742 waagent[1526]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.687742 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.687742 waagent[1526]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:49:34.687742 waagent[1526]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:49:34.687742 waagent[1526]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:49:34.696497 waagent[1526]: 2025-03-17T18:49:34.696342Z INFO EnvHandler ExtHandler Current Firewall rules: Mar 17 18:49:34.696497 waagent[1526]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.696497 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.696497 waagent[1526]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.696497 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.696497 waagent[1526]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes) Mar 17 18:49:34.696497 waagent[1526]: pkts bytes target prot opt in out source destination Mar 17 18:49:34.696497 waagent[1526]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53 Mar 17 18:49:34.696497 waagent[1526]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0 Mar 17 18:49:34.696497 waagent[1526]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW Mar 17 18:49:34.697116 waagent[1526]: 2025-03-17T18:49:34.697057Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300 Mar 17 18:49:58.391038 kernel: hv_balloon: Max. dynamic memory size: 4096 MB Mar 17 18:50:04.186752 update_engine[1335]: I0317 18:50:04.186688 1335 update_attempter.cc:509] Updating boot flags... Mar 17 18:50:10.094602 systemd[1]: Created slice system-sshd.slice. Mar 17 18:50:10.095994 systemd[1]: Started sshd@0-10.200.20.45:22-10.200.16.10:50708.service. Mar 17 18:50:10.796363 sshd[1617]: Accepted publickey for core from 10.200.16.10 port 50708 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:10.815548 sshd[1617]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:10.820849 systemd[1]: Started session-3.scope. Mar 17 18:50:10.821928 systemd-logind[1332]: New session 3 of user core. Mar 17 18:50:11.178300 systemd[1]: Started sshd@1-10.200.20.45:22-10.200.16.10:50712.service. Mar 17 18:50:11.609557 sshd[1625]: Accepted publickey for core from 10.200.16.10 port 50712 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:11.611018 sshd[1625]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:11.614896 systemd-logind[1332]: New session 4 of user core. Mar 17 18:50:11.615715 systemd[1]: Started session-4.scope. Mar 17 18:50:11.943685 sshd[1625]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:11.946786 systemd[1]: sshd@1-10.200.20.45:22-10.200.16.10:50712.service: Deactivated successfully. Mar 17 18:50:11.947566 systemd[1]: session-4.scope: Deactivated successfully. Mar 17 18:50:11.948196 systemd-logind[1332]: Session 4 logged out. Waiting for processes to exit. Mar 17 18:50:11.949421 systemd-logind[1332]: Removed session 4. Mar 17 18:50:12.019554 systemd[1]: Started sshd@2-10.200.20.45:22-10.200.16.10:50728.service. Mar 17 18:50:12.461023 sshd[1631]: Accepted publickey for core from 10.200.16.10 port 50728 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:12.462385 sshd[1631]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:12.466466 systemd-logind[1332]: New session 5 of user core. Mar 17 18:50:12.467001 systemd[1]: Started session-5.scope. Mar 17 18:50:12.797053 sshd[1631]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:12.799609 systemd[1]: sshd@2-10.200.20.45:22-10.200.16.10:50728.service: Deactivated successfully. Mar 17 18:50:12.800333 systemd[1]: session-5.scope: Deactivated successfully. Mar 17 18:50:12.800903 systemd-logind[1332]: Session 5 logged out. Waiting for processes to exit. Mar 17 18:50:12.801746 systemd-logind[1332]: Removed session 5. Mar 17 18:50:12.871629 systemd[1]: Started sshd@3-10.200.20.45:22-10.200.16.10:50742.service. Mar 17 18:50:13.300842 sshd[1637]: Accepted publickey for core from 10.200.16.10 port 50742 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:13.302599 sshd[1637]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:13.307224 systemd[1]: Started session-6.scope. Mar 17 18:50:13.307892 systemd-logind[1332]: New session 6 of user core. Mar 17 18:50:13.618311 sshd[1637]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:13.621090 systemd[1]: sshd@3-10.200.20.45:22-10.200.16.10:50742.service: Deactivated successfully. Mar 17 18:50:13.621784 systemd[1]: session-6.scope: Deactivated successfully. Mar 17 18:50:13.622325 systemd-logind[1332]: Session 6 logged out. Waiting for processes to exit. Mar 17 18:50:13.623183 systemd-logind[1332]: Removed session 6. Mar 17 18:50:13.698039 systemd[1]: Started sshd@4-10.200.20.45:22-10.200.16.10:50748.service. Mar 17 18:50:14.169909 sshd[1643]: Accepted publickey for core from 10.200.16.10 port 50748 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:14.171267 sshd[1643]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:14.175755 systemd[1]: Started session-7.scope. Mar 17 18:50:14.176094 systemd-logind[1332]: New session 7 of user core. Mar 17 18:50:14.717355 sudo[1646]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1 Mar 17 18:50:14.718032 sudo[1646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:14.759028 dbus-daemon[1322]: avc: received setenforce notice (enforcing=1) Mar 17 18:50:14.760923 sudo[1646]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:14.887887 sshd[1643]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:14.891431 systemd[1]: sshd@4-10.200.20.45:22-10.200.16.10:50748.service: Deactivated successfully. Mar 17 18:50:14.891759 systemd-logind[1332]: Session 7 logged out. Waiting for processes to exit. Mar 17 18:50:14.892275 systemd[1]: session-7.scope: Deactivated successfully. Mar 17 18:50:14.893161 systemd-logind[1332]: Removed session 7. Mar 17 18:50:14.967540 systemd[1]: Started sshd@5-10.200.20.45:22-10.200.16.10:50762.service. Mar 17 18:50:15.435868 sshd[1650]: Accepted publickey for core from 10.200.16.10 port 50762 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:15.437603 sshd[1650]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:15.441495 systemd-logind[1332]: New session 8 of user core. Mar 17 18:50:15.442009 systemd[1]: Started session-8.scope. Mar 17 18:50:15.702357 sudo[1654]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules Mar 17 18:50:15.703111 sudo[1654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:15.706023 sudo[1654]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:15.710971 sudo[1653]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules Mar 17 18:50:15.711174 sudo[1653]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:15.720124 systemd[1]: Stopping audit-rules.service... Mar 17 18:50:15.725286 kernel: kauditd_printk_skb: 85 callbacks suppressed Mar 17 18:50:15.725413 kernel: audit: type=1305 audit(1742237415.719:164): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Mar 17 18:50:15.719000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1 Mar 17 18:50:15.725970 auditctl[1657]: No rules Mar 17 18:50:15.726452 systemd[1]: audit-rules.service: Deactivated successfully. Mar 17 18:50:15.726635 systemd[1]: Stopped audit-rules.service. Mar 17 18:50:15.736886 systemd[1]: Starting audit-rules.service... Mar 17 18:50:15.719000 audit[1657]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe9b7c9a0 a2=420 a3=0 items=0 ppid=1 pid=1657 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:50:15.761346 kernel: audit: type=1300 audit(1742237415.719:164): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffe9b7c9a0 a2=420 a3=0 items=0 ppid=1 pid=1657 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:50:15.719000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44 Mar 17 18:50:15.769343 kernel: audit: type=1327 audit(1742237415.719:164): proctitle=2F7362696E2F617564697463746C002D44 Mar 17 18:50:15.724000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.787892 augenrules[1674]: No rules Mar 17 18:50:15.789455 kernel: audit: type=1131 audit(1742237415.724:165): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.789543 kernel: audit: type=1130 audit(1742237415.786:166): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.786000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.788413 systemd[1]: Finished audit-rules.service. Mar 17 18:50:15.808506 sudo[1653]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:15.807000 audit[1653]: USER_END pid=1653 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.830674 kernel: audit: type=1106 audit(1742237415.807:167): pid=1653 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.807000 audit[1653]: CRED_DISP pid=1653 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.849626 kernel: audit: type=1104 audit(1742237415.807:168): pid=1653 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.904793 sshd[1650]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:15.903000 audit[1650]: USER_END pid=1650 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:15.930191 systemd[1]: sshd@5-10.200.20.45:22-10.200.16.10:50762.service: Deactivated successfully. Mar 17 18:50:15.930952 systemd[1]: session-8.scope: Deactivated successfully. Mar 17 18:50:15.904000 audit[1650]: CRED_DISP pid=1650 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:15.931505 systemd-logind[1332]: Session 8 logged out. Waiting for processes to exit. Mar 17 18:50:15.932057 kernel: audit: type=1106 audit(1742237415.903:169): pid=1650 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:15.932101 kernel: audit: type=1104 audit(1742237415.904:170): pid=1650 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:15.928000 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.45:22-10.200.16.10:50762 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.970823 kernel: audit: type=1131 audit(1742237415.928:171): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.45:22-10.200.16.10:50762 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.971112 systemd-logind[1332]: Removed session 8. Mar 17 18:50:15.981000 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.45:22-10.200.16.10:50768 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:15.983576 systemd[1]: Started sshd@6-10.200.20.45:22-10.200.16.10:50768.service. Mar 17 18:50:16.448000 audit[1680]: USER_ACCT pid=1680 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.450586 sshd[1680]: Accepted publickey for core from 10.200.16.10 port 50768 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:16.449000 audit[1680]: CRED_ACQ pid=1680 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.450000 audit[1680]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd5ccc1f0 a2=3 a3=1 items=0 ppid=1 pid=1680 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:50:16.450000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:50:16.452337 sshd[1680]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:16.457046 systemd[1]: Started session-9.scope. Mar 17 18:50:16.457461 systemd-logind[1332]: New session 9 of user core. Mar 17 18:50:16.460000 audit[1680]: USER_START pid=1680 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.461000 audit[1682]: CRED_ACQ pid=1682 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.714000 audit[1683]: USER_ACCT pid=1683 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.716164 sudo[1683]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/data Mar 17 18:50:16.714000 audit[1683]: CRED_REFR pid=1683 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.716699 sudo[1683]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:16.716000 audit[1683]: USER_START pid=1683 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.719481 sudo[1683]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:16.717000 audit[1683]: USER_END pid=1683 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.717000 audit[1683]: CRED_DISP pid=1683 uid=500 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.810144 sshd[1680]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:16.809000 audit[1680]: USER_END pid=1680 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.809000 audit[1680]: CRED_DISP pid=1680 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:16.812861 systemd[1]: sshd@6-10.200.20.45:22-10.200.16.10:50768.service: Deactivated successfully. Mar 17 18:50:16.811000 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.45:22-10.200.16.10:50768 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.813583 systemd[1]: session-9.scope: Deactivated successfully. Mar 17 18:50:16.814121 systemd-logind[1332]: Session 9 logged out. Waiting for processes to exit. Mar 17 18:50:16.815110 systemd-logind[1332]: Removed session 9. Mar 17 18:50:16.886000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.45:22-10.200.16.10:50772 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:16.887983 systemd[1]: Started sshd@7-10.200.20.45:22-10.200.16.10:50772.service. Mar 17 18:50:17.327000 audit[1687]: USER_ACCT pid=1687 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.328364 sshd[1687]: Accepted publickey for core from 10.200.16.10 port 50772 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:17.328000 audit[1687]: CRED_ACQ pid=1687 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.328000 audit[1687]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc8405510 a2=3 a3=1 items=0 ppid=1 pid=1687 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=10 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:50:17.328000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:50:17.330037 sshd[1687]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:17.334512 systemd[1]: Started session-10.scope. Mar 17 18:50:17.334855 systemd-logind[1332]: New session 10 of user core. Mar 17 18:50:17.338000 audit[1687]: USER_START pid=1687 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.339000 audit[1689]: CRED_ACQ pid=1689 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.580000 audit[1690]: USER_ACCT pid=1690 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.580000 audit[1690]: CRED_REFR pid=1690 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.581479 sudo[1690]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/http Mar 17 18:50:17.581690 sudo[1690]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:17.582000 audit[1690]: USER_START pid=1690 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.584458 sudo[1690]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:17.583000 audit[1690]: USER_END pid=1690 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.583000 audit[1690]: CRED_DISP pid=1690 uid=500 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.672033 sshd[1687]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:17.672000 audit[1687]: USER_END pid=1687 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.672000 audit[1687]: CRED_DISP pid=1687 uid=0 auid=500 ses=10 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:17.674513 systemd[1]: sshd@7-10.200.20.45:22-10.200.16.10:50772.service: Deactivated successfully. Mar 17 18:50:17.673000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@7-10.200.20.45:22-10.200.16.10:50772 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:17.675245 systemd[1]: session-10.scope: Deactivated successfully. Mar 17 18:50:17.675851 systemd-logind[1332]: Session 10 logged out. Waiting for processes to exit. Mar 17 18:50:17.676704 systemd-logind[1332]: Removed session 10. Mar 17 18:50:17.748884 systemd[1]: Started sshd@8-10.200.20.45:22-10.200.16.10:50778.service. Mar 17 18:50:17.748000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.45:22-10.200.16.10:50778 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.215000 audit[1694]: USER_ACCT pid=1694 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.216853 sshd[1694]: Accepted publickey for core from 10.200.16.10 port 50778 ssh2: RSA SHA256:paJy8VmUDtRyOvFhLDJavsN2rbrMSHSIk56mCEIjqlY Mar 17 18:50:18.217000 audit[1694]: CRED_ACQ pid=1694 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.217000 audit[1694]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc827b120 a2=3 a3=1 items=0 ppid=1 pid=1694 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=11 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null) Mar 17 18:50:18.217000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D Mar 17 18:50:18.218549 sshd[1694]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0) Mar 17 18:50:18.222564 systemd-logind[1332]: New session 11 of user core. Mar 17 18:50:18.223103 systemd[1]: Started session-11.scope. Mar 17 18:50:18.226000 audit[1694]: USER_START pid=1694 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.227000 audit[1696]: CRED_ACQ pid=1696 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.482000 audit[1697]: USER_ACCT pid=1697 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.482000 audit[1697]: CRED_REFR pid=1697 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.483261 sudo[1697]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/tftp Mar 17 18:50:18.484365 sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500) Mar 17 18:50:18.485000 audit[1697]: USER_START pid=1697 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.486000 audit[1697]: USER_END pid=1697 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.486000 audit[1697]: CRED_DISP pid=1697 uid=500 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.487247 sudo[1697]: pam_unix(sudo:session): session closed for user root Mar 17 18:50:18.561688 sshd[1694]: pam_unix(sshd:session): session closed for user core Mar 17 18:50:18.561000 audit[1694]: USER_END pid=1694 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.561000 audit[1694]: CRED_DISP pid=1694 uid=0 auid=500 ses=11 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.16.10 addr=10.200.16.10 terminal=ssh res=success' Mar 17 18:50:18.564689 systemd[1]: sshd@8-10.200.20.45:22-10.200.16.10:50778.service: Deactivated successfully. Mar 17 18:50:18.564000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@8-10.200.20.45:22-10.200.16.10:50778 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success' Mar 17 18:50:18.565457 systemd[1]: session-11.scope: Deactivated successfully. Mar 17 18:50:18.566016 systemd-logind[1332]: Session 11 logged out. Waiting for processes to exit. Mar 17 18:50:18.567098 systemd-logind[1332]: Removed session 11.