Feb 9 09:54:03.079152 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]
Feb 9 09:54:03.079173 kernel: Linux version 5.15.148-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Fri Feb 9 08:56:26 -00 2024
Feb 9 09:54:03.079181 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '')
Feb 9 09:54:03.079189 kernel: printk: bootconsole [pl11] enabled
Feb 9 09:54:03.079193 kernel: efi: EFI v2.70 by EDK II
Feb 9 09:54:03.079199 kernel: efi: ACPI 2.0=0x3fd89018 SMBIOS=0x3fd66000 SMBIOS 3.0=0x3fd64000 MEMATTR=0x3ef2e698 RNG=0x3fd89998 MEMRESERVE=0x37e73f98
Feb 9 09:54:03.079205 kernel: random: crng init done
Feb 9 09:54:03.079211 kernel: ACPI: Early table checksum verification disabled
Feb 9 09:54:03.079216 kernel: ACPI: RSDP 0x000000003FD89018 000024 (v02 VRTUAL)
Feb 9 09:54:03.079222 kernel: ACPI: XSDT 0x000000003FD89F18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079227 kernel: ACPI: FACP 0x000000003FD89C18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079234 kernel: ACPI: DSDT 0x000000003EBD2018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000)
Feb 9 09:54:03.079239 kernel: ACPI: DBG2 0x000000003FD89B18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079245 kernel: ACPI: GTDT 0x000000003FD89D98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079251 kernel: ACPI: OEM0 0x000000003FD89098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079257 kernel: ACPI: SPCR 0x000000003FD89A98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079263 kernel: ACPI: APIC 0x000000003FD89818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079270 kernel: ACPI: SRAT 0x000000003FD89198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079275 kernel: ACPI: PPTT 0x000000003FD89418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000)
Feb 9 09:54:03.079281 kernel: ACPI: BGRT 0x000000003FD89E98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Feb 9 09:54:03.079287 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200
Feb 9 09:54:03.079292 kernel: NUMA: Failed to initialise from firmware
Feb 9 09:54:03.079298 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff]
Feb 9 09:54:03.079304 kernel: NUMA: NODE_DATA [mem 0x1bf7f1900-0x1bf7f6fff]
Feb 9 09:54:03.079310 kernel: Zone ranges:
Feb 9 09:54:03.079316 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff]
Feb 9 09:54:03.079321 kernel: DMA32 empty
Feb 9 09:54:03.079328 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff]
Feb 9 09:54:03.079334 kernel: Movable zone start for each node
Feb 9 09:54:03.079340 kernel: Early memory node ranges
Feb 9 09:54:03.079345 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff]
Feb 9 09:54:03.079351 kernel: node 0: [mem 0x0000000000824000-0x000000003ec80fff]
Feb 9 09:54:03.079357 kernel: node 0: [mem 0x000000003ec81000-0x000000003eca9fff]
Feb 9 09:54:03.079362 kernel: node 0: [mem 0x000000003ecaa000-0x000000003fd29fff]
Feb 9 09:54:03.079368 kernel: node 0: [mem 0x000000003fd2a000-0x000000003fd7dfff]
Feb 9 09:54:03.079374 kernel: node 0: [mem 0x000000003fd7e000-0x000000003fd89fff]
Feb 9 09:54:03.079379 kernel: node 0: [mem 0x000000003fd8a000-0x000000003fd8dfff]
Feb 9 09:54:03.079385 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fffffff]
Feb 9 09:54:03.079391 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff]
Feb 9 09:54:03.079398 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff]
Feb 9 09:54:03.079406 kernel: On node 0, zone DMA: 36 pages in unavailable ranges
Feb 9 09:54:03.079412 kernel: psci: probing for conduit method from ACPI.
Feb 9 09:54:03.079418 kernel: psci: PSCIv1.1 detected in firmware.
Feb 9 09:54:03.079424 kernel: psci: Using standard PSCI v0.2 function IDs
Feb 9 09:54:03.079431 kernel: psci: MIGRATE_INFO_TYPE not supported.
Feb 9 09:54:03.079437 kernel: psci: SMC Calling Convention v1.4
Feb 9 09:54:03.079444 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1
Feb 9 09:54:03.079450 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1
Feb 9 09:54:03.079456 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784
Feb 9 09:54:03.079462 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096
Feb 9 09:54:03.079469 kernel: pcpu-alloc: [0] 0 [0] 1
Feb 9 09:54:03.079475 kernel: Detected PIPT I-cache on CPU0
Feb 9 09:54:03.079481 kernel: CPU features: detected: GIC system register CPU interface
Feb 9 09:54:03.079487 kernel: CPU features: detected: Hardware dirty bit management
Feb 9 09:54:03.079493 kernel: CPU features: detected: Spectre-BHB
Feb 9 09:54:03.079499 kernel: CPU features: kernel page table isolation forced ON by KASLR
Feb 9 09:54:03.079506 kernel: CPU features: detected: Kernel page table isolation (KPTI)
Feb 9 09:54:03.079512 kernel: CPU features: detected: ARM erratum 1418040
Feb 9 09:54:03.079518 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion)
Feb 9 09:54:03.079524 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156
Feb 9 09:54:03.079530 kernel: Policy zone: Normal
Feb 9 09:54:03.079537 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=14ffd9340f674a8d04c9d43eed85484d8b2b7e2bcd8b36a975c9ac66063d537d
Feb 9 09:54:03.079544 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Feb 9 09:54:03.079550 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Feb 9 09:54:03.079556 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Feb 9 09:54:03.079563 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Feb 9 09:54:03.079570 kernel: software IO TLB: mapped [mem 0x000000003abd2000-0x000000003ebd2000] (64MB)
Feb 9 09:54:03.079576 kernel: Memory: 3991932K/4194160K available (9792K kernel code, 2092K rwdata, 7556K rodata, 34688K init, 778K bss, 202228K reserved, 0K cma-reserved)
Feb 9 09:54:03.079582 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Feb 9 09:54:03.079588 kernel: trace event string verifier disabled
Feb 9 09:54:03.079594 kernel: rcu: Preemptible hierarchical RCU implementation.
Feb 9 09:54:03.079601 kernel: rcu: RCU event tracing is enabled.
Feb 9 09:54:03.079607 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Feb 9 09:54:03.079613 kernel: Trampoline variant of Tasks RCU enabled.
Feb 9 09:54:03.079619 kernel: Tracing variant of Tasks RCU enabled.
Feb 9 09:54:03.079625 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Feb 9 09:54:03.079632 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Feb 9 09:54:03.079639 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Feb 9 09:54:03.079645 kernel: GICv3: 960 SPIs implemented
Feb 9 09:54:03.079651 kernel: GICv3: 0 Extended SPIs implemented
Feb 9 09:54:03.079657 kernel: GICv3: Distributor has no Range Selector support
Feb 9 09:54:03.079663 kernel: Root IRQ handler: gic_handle_irq
Feb 9 09:54:03.079669 kernel: GICv3: 16 PPIs implemented
Feb 9 09:54:03.079675 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000
Feb 9 09:54:03.079681 kernel: ITS: No ITS available, not enabling LPIs
Feb 9 09:54:03.079687 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040
Feb 9 09:54:03.079693 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt).
Feb 9 09:54:03.079700 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns
Feb 9 09:54:03.079706 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns
Feb 9 09:54:03.079714 kernel: Console: colour dummy device 80x25
Feb 9 09:54:03.079720 kernel: printk: console [tty1] enabled
Feb 9 09:54:03.079726 kernel: ACPI: Core revision 20210730
Feb 9 09:54:03.079733 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000)
Feb 9 09:54:03.079739 kernel: pid_max: default: 32768 minimum: 301
Feb 9 09:54:03.079745 kernel: LSM: Security Framework initializing
Feb 9 09:54:03.079751 kernel: SELinux: Initializing.
Feb 9 09:54:03.079758 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Feb 9 09:54:03.079764 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Feb 9 09:54:03.079772 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1
Feb 9 09:54:03.079778 kernel: Hyper-V: Host Build 10.0.22477.1369-1-0
Feb 9 09:54:03.079784 kernel: rcu: Hierarchical SRCU implementation.
Feb 9 09:54:03.079790 kernel: Remapping and enabling EFI services.
Feb 9 09:54:03.079797 kernel: smp: Bringing up secondary CPUs ...
Feb 9 09:54:03.079803 kernel: Detected PIPT I-cache on CPU1
Feb 9 09:54:03.079809 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000
Feb 9 09:54:03.079816 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040
Feb 9 09:54:03.079822 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1]
Feb 9 09:54:03.079829 kernel: smp: Brought up 1 node, 2 CPUs
Feb 9 09:54:03.079836 kernel: SMP: Total of 2 processors activated.
Feb 9 09:54:03.079842 kernel: CPU features: detected: 32-bit EL0 Support
Feb 9 09:54:03.079848 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence
Feb 9 09:54:03.079855 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence
Feb 9 09:54:03.079861 kernel: CPU features: detected: CRC32 instructions
Feb 9 09:54:03.079867 kernel: CPU features: detected: RCpc load-acquire (LDAPR)
Feb 9 09:54:03.079874 kernel: CPU features: detected: LSE atomic instructions
Feb 9 09:54:03.079880 kernel: CPU features: detected: Privileged Access Never
Feb 9 09:54:03.079887 kernel: CPU: All CPU(s) started at EL1
Feb 9 09:54:03.079893 kernel: alternatives: patching kernel code
Feb 9 09:54:03.079904 kernel: devtmpfs: initialized
Feb 9 09:54:03.079912 kernel: KASLR enabled
Feb 9 09:54:03.079919 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Feb 9 09:54:03.079926 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Feb 9 09:54:03.079932 kernel: pinctrl core: initialized pinctrl subsystem
Feb 9 09:54:03.079939 kernel: SMBIOS 3.1.0 present.
Feb 9 09:54:03.079946 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 07/12/2023
Feb 9 09:54:03.079952 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Feb 9 09:54:03.079960 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
Feb 9 09:54:03.079967 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Feb 9 09:54:03.079974 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Feb 9 09:54:03.079980 kernel: audit: initializing netlink subsys (disabled)
Feb 9 09:54:03.079987 kernel: audit: type=2000 audit(0.091:1): state=initialized audit_enabled=0 res=1
Feb 9 09:54:03.079993 kernel: thermal_sys: Registered thermal governor 'step_wise'
Feb 9 09:54:03.080000 kernel: cpuidle: using governor menu
Feb 9 09:54:03.080008 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Feb 9 09:54:03.080015 kernel: ASID allocator initialised with 32768 entries
Feb 9 09:54:03.080022 kernel: ACPI: bus type PCI registered
Feb 9 09:54:03.080028 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Feb 9 09:54:03.080035 kernel: Serial: AMBA PL011 UART driver
Feb 9 09:54:03.080042 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
Feb 9 09:54:03.080048 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
Feb 9 09:54:03.080055 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Feb 9 09:54:03.080083 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
Feb 9 09:54:03.080093 kernel: cryptd: max_cpu_qlen set to 1000
Feb 9 09:54:03.080099 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
Feb 9 09:54:03.080106 kernel: ACPI: Added _OSI(Module Device)
Feb 9 09:54:03.080112 kernel: ACPI: Added _OSI(Processor Device)
Feb 9 09:54:03.080119 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Feb 9 09:54:03.080126 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Feb 9 09:54:03.080132 kernel: ACPI: Added _OSI(Linux-Dell-Video)
Feb 9 09:54:03.080139 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Feb 9 09:54:03.080145 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Feb 9 09:54:03.080153 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Feb 9 09:54:03.080160 kernel: ACPI: Interpreter enabled
Feb 9 09:54:03.080166 kernel: ACPI: Using GIC for interrupt routing
Feb 9 09:54:03.080173 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA
Feb 9 09:54:03.080179 kernel: printk: console [ttyAMA0] enabled
Feb 9 09:54:03.080186 kernel: printk: bootconsole [pl11] disabled
Feb 9 09:54:03.080192 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA
Feb 9 09:54:03.080199 kernel: iommu: Default domain type: Translated
Feb 9 09:54:03.080206 kernel: iommu: DMA domain TLB invalidation policy: strict mode
Feb 9 09:54:03.080213 kernel: vgaarb: loaded
Feb 9 09:54:03.080220 kernel: pps_core: LinuxPPS API ver. 1 registered
Feb 9 09:54:03.080227 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Feb 9 09:54:03.080233 kernel: PTP clock support registered
Feb 9 09:54:03.080240 kernel: Registered efivars operations
Feb 9 09:54:03.080246 kernel: No ACPI PMU IRQ for CPU0
Feb 9 09:54:03.080252 kernel: No ACPI PMU IRQ for CPU1
Feb 9 09:54:03.080259 kernel: clocksource: Switched to clocksource arch_sys_counter
Feb 9 09:54:03.080265 kernel: VFS: Disk quotas dquot_6.6.0
Feb 9 09:54:03.080273 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Feb 9 09:54:03.080280 kernel: pnp: PnP ACPI init
Feb 9 09:54:03.080286 kernel: pnp: PnP ACPI: found 0 devices
Feb 9 09:54:03.080293 kernel: NET: Registered PF_INET protocol family
Feb 9 09:54:03.080300 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Feb 9 09:54:03.080306 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Feb 9 09:54:03.080313 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Feb 9 09:54:03.080319 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Feb 9 09:54:03.080326 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
Feb 9 09:54:03.080334 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Feb 9 09:54:03.080341 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Feb 9 09:54:03.080347 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Feb 9 09:54:03.080354 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Feb 9 09:54:03.080361 kernel: PCI: CLS 0 bytes, default 64
Feb 9 09:54:03.080367 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available
Feb 9 09:54:03.080374 kernel: kvm [1]: HYP mode not available
Feb 9 09:54:03.080380 kernel: Initialise system trusted keyrings
Feb 9 09:54:03.080387 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Feb 9 09:54:03.080395 kernel: Key type asymmetric registered
Feb 9 09:54:03.080402 kernel: Asymmetric key parser 'x509' registered
Feb 9 09:54:03.080408 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Feb 9 09:54:03.080415 kernel: io scheduler mq-deadline registered
Feb 9 09:54:03.080421 kernel: io scheduler kyber registered
Feb 9 09:54:03.080428 kernel: io scheduler bfq registered
Feb 9 09:54:03.080434 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Feb 9 09:54:03.080441 kernel: thunder_xcv, ver 1.0
Feb 9 09:54:03.080447 kernel: thunder_bgx, ver 1.0
Feb 9 09:54:03.080455 kernel: nicpf, ver 1.0
Feb 9 09:54:03.080462 kernel: nicvf, ver 1.0
Feb 9 09:54:03.080567 kernel: rtc-efi rtc-efi.0: registered as rtc0
Feb 9 09:54:03.080631 kernel: rtc-efi rtc-efi.0: setting system clock to 2024-02-09T09:54:02 UTC (1707472442)
Feb 9 09:54:03.080640 kernel: efifb: probing for efifb
Feb 9 09:54:03.080647 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k
Feb 9 09:54:03.080654 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1
Feb 9 09:54:03.080660 kernel: efifb: scrolling: redraw
Feb 9 09:54:03.080669 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Feb 9 09:54:03.080676 kernel: Console: switching to colour frame buffer device 128x48
Feb 9 09:54:03.080682 kernel: fb0: EFI VGA frame buffer device
Feb 9 09:54:03.080689 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
Feb 9 09:54:03.080696 kernel: hid: raw HID events driver (C) Jiri Kosina
Feb 9 09:54:03.080702 kernel: NET: Registered PF_INET6 protocol family
Feb 9 09:54:03.080709 kernel: Segment Routing with IPv6
Feb 9 09:54:03.080715 kernel: In-situ OAM (IOAM) with IPv6
Feb 9 09:54:03.080722 kernel: NET: Registered PF_PACKET protocol family
Feb 9 09:54:03.080730 kernel: Key type dns_resolver registered
Feb 9 09:54:03.080736 kernel: registered taskstats version 1
Feb 9 09:54:03.080743 kernel: Loading compiled-in X.509 certificates
Feb 9 09:54:03.080750 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.148-flatcar: ca91574208414224935c9cea513398977daf917d'
Feb 9 09:54:03.080756 kernel: Key type .fscrypt registered
Feb 9 09:54:03.080763 kernel: Key type fscrypt-provisioning registered
Feb 9 09:54:03.080769 kernel: ima: No TPM chip found, activating TPM-bypass!
Feb 9 09:54:03.080776 kernel: ima: Allocated hash algorithm: sha1
Feb 9 09:54:03.080782 kernel: ima: No architecture policies found
Feb 9 09:54:03.080790 kernel: Freeing unused kernel memory: 34688K
Feb 9 09:54:03.080797 kernel: Run /init as init process
Feb 9 09:54:03.080803 kernel: with arguments:
Feb 9 09:54:03.080810 kernel: /init
Feb 9 09:54:03.080816 kernel: with environment:
Feb 9 09:54:03.080822 kernel: HOME=/
Feb 9 09:54:03.080828 kernel: TERM=linux
Feb 9 09:54:03.080835 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a
Feb 9 09:54:03.080844 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
Feb 9 09:54:03.080854 systemd[1]: Detected virtualization microsoft.
Feb 9 09:54:03.080861 systemd[1]: Detected architecture arm64.
Feb 9 09:54:03.080868 systemd[1]: Running in initrd.
Feb 9 09:54:03.080875 systemd[1]: No hostname configured, using default hostname.
Feb 9 09:54:03.080882 systemd[1]: Hostname set to <localhost>.
Feb 9 09:54:03.080889 systemd[1]: Initializing machine ID from random generator.
Feb 9 09:54:03.080896 systemd[1]: Queued start job for default target initrd.target.
Feb 9 09:54:03.080904 systemd[1]: Started systemd-ask-password-console.path.
Feb 9 09:54:03.080911 systemd[1]: Reached target cryptsetup.target.
Feb 9 09:54:03.080918 systemd[1]: Reached target paths.target.
Feb 9 09:54:03.080925 systemd[1]: Reached target slices.target.
Feb 9 09:54:03.080932 systemd[1]: Reached target swap.target.
Feb 9 09:54:03.080939 systemd[1]: Reached target timers.target.
Feb 9 09:54:03.080946 systemd[1]: Listening on iscsid.socket.
Feb 9 09:54:03.080953 systemd[1]: Listening on iscsiuio.socket.
Feb 9 09:54:03.080962 systemd[1]: Listening on systemd-journald-audit.socket.
Feb 9 09:54:03.080969 systemd[1]: Listening on systemd-journald-dev-log.socket.
Feb 9 09:54:03.080976 systemd[1]: Listening on systemd-journald.socket.
Feb 9 09:54:03.080983 systemd[1]: Listening on systemd-networkd.socket.
Feb 9 09:54:03.080990 systemd[1]: Listening on systemd-udevd-control.socket.
Feb 9 09:54:03.080997 systemd[1]: Listening on systemd-udevd-kernel.socket.
Feb 9 09:54:03.081004 systemd[1]: Reached target sockets.target.
Feb 9 09:54:03.081011 systemd[1]: Starting kmod-static-nodes.service...
Feb 9 09:54:03.081018 systemd[1]: Finished network-cleanup.service.
Feb 9 09:54:03.081026 systemd[1]: Starting systemd-fsck-usr.service...
Feb 9 09:54:03.081033 systemd[1]: Starting systemd-journald.service...
Feb 9 09:54:03.081040 systemd[1]: Starting systemd-modules-load.service...
Feb 9 09:54:03.081047 systemd[1]: Starting systemd-resolved.service...
Feb 9 09:54:03.081057 systemd-journald[276]: Journal started
Feb 9 09:54:03.081103 systemd-journald[276]: Runtime Journal (/run/log/journal/09f501e1dc6f451c8cfb6d3eddec6861) is 8.0M, max 78.6M, 70.6M free.
Feb 9 09:54:03.065975 systemd-modules-load[277]: Inserted module 'overlay'
Feb 9 09:54:03.114860 systemd[1]: Starting systemd-vconsole-setup.service...
Feb 9 09:54:03.114910 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Feb 9 09:54:03.126875 systemd-resolved[278]: Positive Trust Anchors:
Feb 9 09:54:03.133299 systemd[1]: Started systemd-journald.service.
Feb 9 09:54:03.133319 kernel: Bridge firewalling registered
Feb 9 09:54:03.126893 systemd-resolved[278]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Feb 9 09:54:03.208481 kernel: audit: type=1130 audit(1707472443.138:2): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.208506 kernel: audit: type=1130 audit(1707472443.170:3): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.208517 kernel: SCSI subsystem initialized
Feb 9 09:54:03.138000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.170000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.126923 systemd-resolved[278]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
Feb 9 09:54:03.244000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.128953 systemd-resolved[278]: Defaulting to hostname 'linux'.
Feb 9 09:54:03.329774 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Feb 9 09:54:03.329801 kernel: audit: type=1130 audit(1707472443.244:4): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.329812 kernel: device-mapper: uevent: version 1.0.3
Feb 9 09:54:03.329820 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Feb 9 09:54:03.329829 kernel: audit: type=1130 audit(1707472443.298:5): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.298000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.138499 systemd-modules-load[277]: Inserted module 'br_netfilter'
Feb 9 09:54:03.139002 systemd[1]: Started systemd-resolved.service.
Feb 9 09:54:03.335000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.171273 systemd[1]: Finished kmod-static-nodes.service.
Feb 9 09:54:03.364000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.245202 systemd[1]: Finished systemd-fsck-usr.service.
Feb 9 09:54:03.398771 kernel: audit: type=1130 audit(1707472443.335:6): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.398791 kernel: audit: type=1130 audit(1707472443.364:7): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.298816 systemd[1]: Finished systemd-vconsole-setup.service.
Feb 9 09:54:03.329809 systemd-modules-load[277]: Inserted module 'dm_multipath'
Feb 9 09:54:03.336426 systemd[1]: Finished systemd-modules-load.service.
Feb 9 09:54:03.364952 systemd[1]: Reached target nss-lookup.target.
Feb 9 09:54:03.398371 systemd[1]: Starting dracut-cmdline-ask.service...
Feb 9 09:54:03.405178 systemd[1]: Starting systemd-sysctl.service...
Feb 9 09:54:03.453000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.426525 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Feb 9 09:54:03.438566 systemd[1]: Finished dracut-cmdline-ask.service.
Feb 9 09:54:03.517143 kernel: audit: type=1130 audit(1707472443.453:8): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.517164 kernel: audit: type=1130 audit(1707472443.488:9): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.488000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.478165 systemd[1]: Finished systemd-sysctl.service.
Feb 9 09:54:03.545513 kernel: audit: type=1130 audit(1707472443.517: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'
Feb 9 09:54:03.517000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.488732 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Feb 9 09:54:03.538356 systemd[1]: Starting dracut-cmdline.service...
Feb 9 09:54:03.559693 dracut-cmdline[298]: dracut-dracut-053
Feb 9 09:54:03.564918 dracut-cmdline[298]: Using kernel command line parameters: rd.driver.pre=btrfs BOOT_IMAGE=/flatcar/vmlinuz-a mount.usr=/dev/mapper/usr verity.usr=PARTUUID=7130c94a-213a-4e5a-8e26-6cce9662f132 rootflags=rw mount.usrflags=ro consoleblank=0 root=LABEL=ROOT console=tty1 console=ttyAMA0,115200n8 earlycon=pl011,0xeffec000 flatcar.first_boot=detected acpi=force flatcar.oem.id=azure flatcar.autologin verity.usrhash=14ffd9340f674a8d04c9d43eed85484d8b2b7e2bcd8b36a975c9ac66063d537d
Feb 9 09:54:03.662095 kernel: Loading iSCSI transport class v2.0-870.
Feb 9 09:54:03.674096 kernel: iscsi: registered transport (tcp)
Feb 9 09:54:03.694321 kernel: iscsi: registered transport (qla4xxx)
Feb 9 09:54:03.694348 kernel: QLogic iSCSI HBA Driver
Feb 9 09:54:03.731007 systemd[1]: Finished dracut-cmdline.service.
Feb 9 09:54:03.736000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:03.737357 systemd[1]: Starting dracut-pre-udev.service...
Feb 9 09:54:03.795093 kernel: raid6: neonx8 gen() 13814 MB/s
Feb 9 09:54:03.815077 kernel: raid6: neonx8 xor() 10818 MB/s
Feb 9 09:54:03.836076 kernel: raid6: neonx4 gen() 13535 MB/s
Feb 9 09:54:03.858102 kernel: raid6: neonx4 xor() 11109 MB/s
Feb 9 09:54:03.879109 kernel: raid6: neonx2 gen() 12960 MB/s
Feb 9 09:54:03.900122 kernel: raid6: neonx2 xor() 10233 MB/s
Feb 9 09:54:03.922079 kernel: raid6: neonx1 gen() 10491 MB/s
Feb 9 09:54:03.943102 kernel: raid6: neonx1 xor() 8792 MB/s
Feb 9 09:54:03.964076 kernel: raid6: int64x8 gen() 6297 MB/s
Feb 9 09:54:03.986077 kernel: raid6: int64x8 xor() 3548 MB/s
Feb 9 09:54:04.007073 kernel: raid6: int64x4 gen() 7270 MB/s
Feb 9 09:54:04.028080 kernel: raid6: int64x4 xor() 3855 MB/s
Feb 9 09:54:04.050078 kernel: raid6: int64x2 gen() 6152 MB/s
Feb 9 09:54:04.071074 kernel: raid6: int64x2 xor() 3322 MB/s
Feb 9 09:54:04.092083 kernel: raid6: int64x1 gen() 5046 MB/s
Feb 9 09:54:04.118896 kernel: raid6: int64x1 xor() 2646 MB/s
Feb 9 09:54:04.118947 kernel: raid6: using algorithm neonx8 gen() 13814 MB/s
Feb 9 09:54:04.118957 kernel: raid6: .... xor() 10818 MB/s, rmw enabled
Feb 9 09:54:04.123833 kernel: raid6: using neon recovery algorithm
Feb 9 09:54:04.147384 kernel: xor: measuring software checksum speed
Feb 9 09:54:04.147399 kernel: 8regs : 17286 MB/sec
Feb 9 09:54:04.152440 kernel: 32regs : 20755 MB/sec
Feb 9 09:54:04.159135 kernel: arm64_neon : 27959 MB/sec
Feb 9 09:54:04.159146 kernel: xor: using function: arm64_neon (27959 MB/sec)
Feb 9 09:54:04.221077 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no
Feb 9 09:54:04.230660 systemd[1]: Finished dracut-pre-udev.service.
Feb 9 09:54:04.236000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:04.240000 audit: BPF prog-id=7 op=LOAD
Feb 9 09:54:04.240000 audit: BPF prog-id=8 op=LOAD
Feb 9 09:54:04.241391 systemd[1]: Starting systemd-udevd.service...
Feb 9 09:54:04.260900 systemd-udevd[475]: Using default interface naming scheme 'v252'.
Feb 9 09:54:04.275000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:04.269838 systemd[1]: Started systemd-udevd.service.
Feb 9 09:54:04.276746 systemd[1]: Starting dracut-pre-trigger.service...
Feb 9 09:54:04.301672 dracut-pre-trigger[496]: rd.md=0: removing MD RAID activation
Feb 9 09:54:04.333898 systemd[1]: Finished dracut-pre-trigger.service.
Feb 9 09:54:04.339000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:04.340299 systemd[1]: Starting systemd-udev-trigger.service...
Feb 9 09:54:04.376335 systemd[1]: Finished systemd-udev-trigger.service.
Feb 9 09:54:04.388000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:04.427085 kernel: hv_vmbus: Vmbus version:5.3
Feb 9 09:54:04.429083 kernel: hv_vmbus: registering driver hyperv_keyboard
Feb 9 09:54:04.446192 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input0
Feb 9 09:54:04.446235 kernel: hv_vmbus: registering driver hv_storvsc
Feb 9 09:54:04.446251 kernel: hv_vmbus: registering driver hid_hyperv
Feb 9 09:54:04.465151 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input1
Feb 9 09:54:04.465235 kernel: scsi host1: storvsc_host_t
Feb 9 09:54:04.465366 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on
Feb 9 09:54:04.465440 kernel: hv_vmbus: registering driver hv_netvsc
Feb 9 09:54:04.465450 kernel: scsi host0: storvsc_host_t
Feb 9 09:54:04.479348 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
Feb 9 09:54:04.508242 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0
Feb 9 09:54:04.538240 kernel: sr 0:0:0:2: [sr0] scsi-1 drive
Feb 9 09:54:04.538481 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Feb 9 09:54:04.546250 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0
Feb 9 09:54:04.546406 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB)
Feb 9 09:54:04.546496 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
Feb 9 09:54:04.555640 kernel: sd 0:0:0:0: [sda] Write Protect is off
Feb 9 09:54:04.555759 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00
Feb 9 09:54:04.555855 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA
Feb 9 09:54:04.570096 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Feb 9 09:54:04.575083 kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Feb 9 09:54:04.612079 kernel: hv_netvsc 000d3af6-1390-000d-3af6-1390000d3af6 eth0: VF slot 1 added
Feb 9 09:54:04.621086 kernel: hv_vmbus: registering driver hv_pci
Feb 9 09:54:04.630083 kernel: hv_pci 158d306e-d298-4789-a7bf-ef02ae5c71f6: PCI VMBus probing: Using version 0x10004
Feb 9 09:54:04.650025 kernel: hv_pci 158d306e-d298-4789-a7bf-ef02ae5c71f6: PCI host bridge to bus d298:00
Feb 9 09:54:04.650194 kernel: pci_bus d298:00: root bus resource [mem 0xfc0000000-0xfc00fffff window]
Feb 9 09:54:04.650288 kernel: pci_bus d298:00: No busn resource found for root bus, will use [bus 00-ff]
Feb 9 09:54:04.666280 kernel: pci d298:00:02.0: [15b3:1018] type 00 class 0x020000
Feb 9 09:54:04.679904 kernel: pci d298:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref]
Feb 9 09:54:04.702173 kernel: pci d298:00:02.0: enabling Extended Tags
Feb 9 09:54:04.725098 kernel: pci d298:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at d298:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link)
Feb 9 09:54:04.738560 kernel: pci_bus d298:00: busn_res: [bus 00-ff] end is updated to 00
Feb 9 09:54:04.738713 kernel: pci d298:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref]
Feb 9 09:54:04.780096 kernel: mlx5_core d298:00:02.0: firmware version: 16.30.1284
Feb 9 09:54:04.940079 kernel: mlx5_core d298:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0)
Feb 9 09:54:05.001786 kernel: hv_netvsc 000d3af6-1390-000d-3af6-1390000d3af6 eth0: VF registering: eth1
Feb 9 09:54:05.001969 kernel: mlx5_core d298:00:02.0 eth1: joined to eth0
Feb 9 09:54:05.016083 kernel: mlx5_core d298:00:02.0 enP53912s1: renamed from eth1
Feb 9 09:54:05.138605 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device.
Feb 9 09:54:05.190087 kernel: BTRFS: device label OEM devid 1 transid 12 /dev/sda6 scanned by (udev-worker) (543)
Feb 9 09:54:05.202953 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Feb 9 09:54:05.341263 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device.
Feb 9 09:54:05.364638 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device.
Feb 9 09:54:05.371622 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device.
Feb 9 09:54:05.387681 systemd[1]: Starting disk-uuid.service...
Feb 9 09:54:06.419629 disk-uuid[601]: The operation has completed successfully.
Feb 9 09:54:06.425599 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Feb 9 09:54:06.467822 systemd[1]: disk-uuid.service: Deactivated successfully.
Feb 9 09:54:06.472000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:06.472000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=disk-uuid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:06.467913 systemd[1]: Finished disk-uuid.service.
Feb 9 09:54:06.485471 systemd[1]: Starting verity-setup.service...
Feb 9 09:54:06.525875 kernel: device-mapper: verity: sha256 using implementation "sha256-ce"
Feb 9 09:54:06.758179 systemd[1]: Found device dev-mapper-usr.device.
Feb 9 09:54:06.768929 systemd[1]: Finished verity-setup.service.
Feb 9 09:54:06.773000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:06.774703 systemd[1]: Mounting sysusr-usr.mount...
Feb 9 09:54:06.833740 systemd[1]: Mounted sysusr-usr.mount.
Feb 9 09:54:06.838175 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none.
Feb 9 09:54:06.843154 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met.
Feb 9 09:54:06.843909 systemd[1]: Starting ignition-setup.service...
Feb 9 09:54:06.857727 systemd[1]: Starting parse-ip-for-networkd.service...
Feb 9 09:54:06.891748 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Feb 9 09:54:06.891803 kernel: BTRFS info (device sda6): using free space tree
Feb 9 09:54:06.897562 kernel: BTRFS info (device sda6): has skinny extents
Feb 9 09:54:06.960398 systemd[1]: Finished parse-ip-for-networkd.service.
Feb 9 09:54:06.966000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:06.971000 audit: BPF prog-id=9 op=LOAD
Feb 9 09:54:06.972084 systemd[1]: Starting systemd-networkd.service...
Feb 9 09:54:06.996961 systemd[1]: mnt-oem.mount: Deactivated successfully.
Feb 9 09:54:07.005986 systemd-networkd[871]: lo: Link UP
Feb 9 09:54:07.059765 kernel: kauditd_printk_skb: 12 callbacks suppressed
Feb 9 09:54:07.059792 kernel: audit: type=1130 audit(1707472447.015:23): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.015000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.005999 systemd-networkd[871]: lo: Gained carrier
Feb 9 09:54:07.006704 systemd-networkd[871]: Enumeration completed
Feb 9 09:54:07.009888 systemd[1]: Started systemd-networkd.service.
Feb 9 09:54:07.010563 systemd-networkd[871]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Feb 9 09:54:07.092000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.021224 systemd[1]: Reached target network.target.
Feb 9 09:54:07.132704 kernel: audit: type=1130 audit(1707472447.092:24): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.132732 iscsid[880]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi
Feb 9 09:54:07.132732 iscsid[880]: iscsid: Warning: InitiatorName file /etc/iscsi/initiatorname.iscsi does not exist or does not contain a properly formatted InitiatorName. If using software iscsi (iscsi_tcp or ib_iser) or partial offload (bnx2i or cxgbi iscsi), you may not be able to log
Feb 9 09:54:07.132732 iscsid[880]: into or discover targets. Please create a file /etc/iscsi/initiatorname.iscsi that contains a sting with the format: InitiatorName=iqn.yyyy-mm.<reversed domain name>[:identifier].
Feb 9 09:54:07.132732 iscsid[880]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6.
Feb 9 09:54:07.132732 iscsid[880]: If using hardware iscsi like qla4xxx this message can be ignored.
Feb 9 09:54:07.132732 iscsid[880]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi
Feb 9 09:54:07.132732 iscsid[880]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf
Feb 9 09:54:07.296851 kernel: audit: type=1130 audit(1707472447.135:25): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.296876 kernel: audit: type=1130 audit(1707472447.228:26): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.296886 kernel: mlx5_core d298:00:02.0 enP53912s1: Link up
Feb 9 09:54:07.135000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.228000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.070744 systemd[1]: Starting iscsiuio.service...
Feb 9 09:54:07.082001 systemd[1]: Started iscsiuio.service.
Feb 9 09:54:07.307000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.093363 systemd[1]: Starting iscsid.service...
Feb 9 09:54:07.349931 kernel: audit: type=1130 audit(1707472447.307:27): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.349953 kernel: hv_netvsc 000d3af6-1390-000d-3af6-1390000d3af6 eth0: Data path switched to VF: enP53912s1
Feb 9 09:54:07.350101 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Feb 9 09:54:07.127989 systemd[1]: Started iscsid.service.
Feb 9 09:54:07.377957 kernel: audit: type=1130 audit(1707472447.355:28): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.355000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:07.158571 systemd[1]: Starting dracut-initqueue.service...
Feb 9 09:54:07.223421 systemd[1]: Finished dracut-initqueue.service.
Feb 9 09:54:07.228969 systemd[1]: Reached target remote-fs-pre.target.
Feb 9 09:54:07.257277 systemd[1]: Reached target remote-cryptsetup.target.
Feb 9 09:54:07.277349 systemd[1]: Reached target remote-fs.target.
Feb 9 09:54:07.292287 systemd[1]: Starting dracut-pre-mount.service...
Feb 9 09:54:07.300083 systemd[1]: Finished ignition-setup.service.
Feb 9 09:54:07.340480 systemd[1]: Finished dracut-pre-mount.service.
Feb 9 09:54:07.355194 systemd-networkd[871]: enP53912s1: Link UP
Feb 9 09:54:07.355276 systemd-networkd[871]: eth0: Link UP
Feb 9 09:54:07.355400 systemd-networkd[871]: eth0: Gained carrier
Feb 9 09:54:07.379235 systemd[1]: Starting ignition-fetch-offline.service...
Feb 9 09:54:07.381266 systemd-networkd[871]: enP53912s1: Gained carrier
Feb 9 09:54:07.397138 systemd-networkd[871]: eth0: DHCPv4 address 10.200.20.10/24, gateway 10.200.20.1 acquired from 168.63.129.16
Feb 9 09:54:08.572242 systemd-networkd[871]: eth0: Gained IPv6LL
Feb 9 09:54:10.512041 ignition[895]: Ignition 2.14.0
Feb 9 09:54:10.512052 ignition[895]: Stage: fetch-offline
Feb 9 09:54:10.512122 ignition[895]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:10.512145 ignition[895]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:10.606100 ignition[895]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:10.606271 ignition[895]: parsed url from cmdline: ""
Feb 9 09:54:10.606275 ignition[895]: no config URL provided
Feb 9 09:54:10.606280 ignition[895]: reading system config file "/usr/lib/ignition/user.ign"
Feb 9 09:54:10.667345 kernel: audit: type=1130 audit(1707472450.634:29): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.634000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.623729 systemd[1]: Finished ignition-fetch-offline.service.
Feb 9 09:54:10.606289 ignition[895]: no config at "/usr/lib/ignition/user.ign"
Feb 9 09:54:10.635865 systemd[1]: Starting ignition-fetch.service...
Feb 9 09:54:10.606294 ignition[895]: failed to fetch config: resource requires networking
Feb 9 09:54:10.606508 ignition[895]: Ignition finished successfully
Feb 9 09:54:10.648892 ignition[901]: Ignition 2.14.0
Feb 9 09:54:10.648898 ignition[901]: Stage: fetch
Feb 9 09:54:10.648997 ignition[901]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:10.649014 ignition[901]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:10.651658 ignition[901]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:10.651794 ignition[901]: parsed url from cmdline: ""
Feb 9 09:54:10.651798 ignition[901]: no config URL provided
Feb 9 09:54:10.651803 ignition[901]: reading system config file "/usr/lib/ignition/user.ign"
Feb 9 09:54:10.651811 ignition[901]: no config at "/usr/lib/ignition/user.ign"
Feb 9 09:54:10.651838 ignition[901]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1
Feb 9 09:54:10.752136 ignition[901]: GET result: OK
Feb 9 09:54:10.752231 ignition[901]: config has been read from IMDS userdata
Feb 9 09:54:10.752268 ignition[901]: parsing config with SHA512: e450512d55f6e2c86824dc99d674986015341dac27e2ba948d74be7d1c0c369fe8393cb1024bb2602ea414c9fcc100bc4ca860b2cc31a90591d503d0275de5e0
Feb 9 09:54:10.765469 unknown[901]: fetched base config from "system"
Feb 9 09:54:10.765885 ignition[901]: fetch: fetch complete
Feb 9 09:54:10.765476 unknown[901]: fetched base config from "system"
Feb 9 09:54:10.776000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.765890 ignition[901]: fetch: fetch passed
Feb 9 09:54:10.765481 unknown[901]: fetched user config from "azure"
Feb 9 09:54:10.765932 ignition[901]: Ignition finished successfully
Feb 9 09:54:10.825246 kernel: audit: type=1130 audit(1707472450.776:30): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.771170 systemd[1]: Finished ignition-fetch.service.
Feb 9 09:54:10.831000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.817910 ignition[907]: Ignition 2.14.0
Feb 9 09:54:10.866835 kernel: audit: type=1130 audit(1707472450.831:31): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.777259 systemd[1]: Starting ignition-kargs.service...
Feb 9 09:54:10.817917 ignition[907]: Stage: kargs
Feb 9 09:54:10.825312 systemd[1]: Finished ignition-kargs.service.
Feb 9 09:54:10.818025 ignition[907]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:10.889000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.855095 systemd[1]: Starting ignition-disks.service...
Feb 9 09:54:10.930021 kernel: audit: type=1130 audit(1707472450.889:32): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:10.818046 ignition[907]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:10.879467 systemd[1]: Finished ignition-disks.service.
Feb 9 09:54:10.821146 ignition[907]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:10.889815 systemd[1]: Reached target initrd-root-device.target.
Feb 9 09:54:10.822921 ignition[907]: kargs: kargs passed
Feb 9 09:54:10.924950 systemd[1]: Reached target local-fs-pre.target.
Feb 9 09:54:10.822988 ignition[907]: Ignition finished successfully
Feb 9 09:54:10.935549 systemd[1]: Reached target local-fs.target.
Feb 9 09:54:10.866335 ignition[913]: Ignition 2.14.0
Feb 9 09:54:10.945716 systemd[1]: Reached target sysinit.target.
Feb 9 09:54:10.866341 ignition[913]: Stage: disks
Feb 9 09:54:10.958109 systemd[1]: Reached target basic.target.
Feb 9 09:54:10.866436 ignition[913]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:10.972484 systemd[1]: Starting systemd-fsck-root.service...
Feb 9 09:54:10.866472 ignition[913]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:10.877318 ignition[913]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:10.878321 ignition[913]: disks: disks passed
Feb 9 09:54:10.878382 ignition[913]: Ignition finished successfully
Feb 9 09:54:11.042887 systemd-fsck[921]: ROOT: clean, 602/7326000 files, 481069/7359488 blocks
Feb 9 09:54:11.048182 systemd[1]: Finished systemd-fsck-root.service.
Feb 9 09:54:11.066000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:11.070396 systemd[1]: Mounting sysroot.mount...
Feb 9 09:54:11.103496 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Feb 9 09:54:11.092753 systemd[1]: Mounted sysroot.mount.
Feb 9 09:54:11.097858 systemd[1]: Reached target initrd-root-fs.target.
Feb 9 09:54:11.134603 systemd[1]: Mounting sysroot-usr.mount...
Feb 9 09:54:11.140318 systemd[1]: Starting flatcar-metadata-hostname.service...
Feb 9 09:54:11.151006 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Feb 9 09:54:11.151039 systemd[1]: Reached target ignition-diskful.target.
Feb 9 09:54:11.158423 systemd[1]: Mounted sysroot-usr.mount.
Feb 9 09:54:11.200432 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Feb 9 09:54:11.206870 systemd[1]: Starting initrd-setup-root.service...
Feb 9 09:54:11.235393 initrd-setup-root[937]: cut: /sysroot/etc/passwd: No such file or directory
Feb 9 09:54:11.244256 kernel: BTRFS: device label OEM devid 1 transid 14 /dev/sda6 scanned by mount (932)
Feb 9 09:54:11.258951 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Feb 9 09:54:11.258989 kernel: BTRFS info (device sda6): using free space tree
Feb 9 09:54:11.267943 kernel: BTRFS info (device sda6): has skinny extents
Feb 9 09:54:11.272060 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Feb 9 09:54:11.285005 initrd-setup-root[963]: cut: /sysroot/etc/group: No such file or directory
Feb 9 09:54:11.295521 initrd-setup-root[971]: cut: /sysroot/etc/shadow: No such file or directory
Feb 9 09:54:11.306092 initrd-setup-root[979]: cut: /sysroot/etc/gshadow: No such file or directory
Feb 9 09:54:11.796084 systemd[1]: Finished initrd-setup-root.service.
Feb 9 09:54:11.801000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:11.802431 systemd[1]: Starting ignition-mount.service...
Feb 9 09:54:11.817853 systemd[1]: Starting sysroot-boot.service...
Feb 9 09:54:11.828126 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully.
Feb 9 09:54:11.828281 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully.
Feb 9 09:54:11.856705 ignition[999]: INFO : Ignition 2.14.0
Feb 9 09:54:11.867162 ignition[999]: INFO : Stage: mount
Feb 9 09:54:11.867162 ignition[999]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:11.867162 ignition[999]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:11.867162 ignition[999]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:11.867000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:11.901000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:11.862618 systemd[1]: Finished sysroot-boot.service.
Feb 9 09:54:11.916041 ignition[999]: INFO : mount: mount passed
Feb 9 09:54:11.916041 ignition[999]: INFO : Ignition finished successfully
Feb 9 09:54:11.896444 systemd[1]: Finished ignition-mount.service.
Feb 9 09:54:12.813107 coreos-metadata[931]: Feb 09 09:54:12.813 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Feb 9 09:54:12.823258 coreos-metadata[931]: Feb 09 09:54:12.823 INFO Fetch successful
Feb 9 09:54:12.850748 coreos-metadata[931]: Feb 09 09:54:12.850 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1
Feb 9 09:54:12.875298 coreos-metadata[931]: Feb 09 09:54:12.875 INFO Fetch successful
Feb 9 09:54:12.893095 coreos-metadata[931]: Feb 09 09:54:12.893 INFO wrote hostname ci-3510.3.2-a-9a5ebba439 to /sysroot/etc/hostname
Feb 9 09:54:12.903682 systemd[1]: Finished flatcar-metadata-hostname.service.
Feb 9 09:54:12.909000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:12.925726 kernel: kauditd_printk_skb: 4 callbacks suppressed
Feb 9 09:54:12.925770 kernel: audit: type=1130 audit(1707472452.909: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'
Feb 9 09:54:12.921475 systemd[1]: Starting ignition-files.service...
Feb 9 09:54:12.950677 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Feb 9 09:54:12.978090 kernel: BTRFS: device label OEM devid 1 transid 15 /dev/sda6 scanned by mount (1010)
Feb 9 09:54:12.992658 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Feb 9 09:54:12.992713 kernel: BTRFS info (device sda6): using free space tree
Feb 9 09:54:12.998497 kernel: BTRFS info (device sda6): has skinny extents
Feb 9 09:54:13.003565 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Feb 9 09:54:13.021688 ignition[1029]: INFO : Ignition 2.14.0
Feb 9 09:54:13.021688 ignition[1029]: INFO : Stage: files
Feb 9 09:54:13.034184 ignition[1029]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:13.034184 ignition[1029]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:13.034184 ignition[1029]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:13.034184 ignition[1029]: DEBUG : files: compiled without relabeling support, skipping
Feb 9 09:54:13.074238 ignition[1029]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core"
Feb 9 09:54:13.074238 ignition[1029]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Feb 9 09:54:13.164389 ignition[1029]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Feb 9 09:54:13.173399 ignition[1029]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core"
Feb 9 09:54:13.189881 unknown[1029]: wrote ssh authorized keys file for user: core
Feb 9 09:54:13.196302 ignition[1029]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Feb 9 09:54:13.207677 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/var/resource/data"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/var/resource/data"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(4): [started] writing file "/sysroot/var/resource/http"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET http://10.200.20.12/http: attempt #1
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(4): GET result: OK
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(4): [finished] writing file "/sysroot/var/resource/http"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(5): [started] writing file "/sysroot/var/resource/tftp"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(5): [finished] writing file "/sysroot/var/resource/tftp"
Feb 9 09:54:13.219101 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(6): [started] writing file "/sysroot/etc/flatcar/update.conf"
Feb 9 09:54:13.318999 kernel: BTRFS info: devid 1 device path /dev/sda6 changed to /dev/disk/by-label/OEM scanned by ignition (1032)
Feb 9 09:54:13.294358 systemd[1]: mnt-oem3564288835.mount: Deactivated successfully.
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(6): [finished] writing file "/sysroot/etc/flatcar/update.conf"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/waagent.service"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): oem config not found in "/usr/share/oem", looking on oem partition
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3564288835"
Feb 9 09:54:13.324977 ignition[1029]: CRITICAL : files: createFilesystemsFiles: createFiles: op(7): op(8): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3564288835": device or resource busy
Feb 9 09:54:13.324977 ignition[1029]: ERROR : files: createFilesystemsFiles: createFiles: op(7): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem3564288835", trying btrfs: device or resource busy
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3564288835"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem3564288835"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [started] unmounting "/mnt/oem3564288835"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [finished] unmounting "/mnt/oem3564288835"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/waagent.service"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): [started] writing file "/sysroot/etc/systemd/system/nvidia.service"
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): oem config not found in "/usr/share/oem", looking on oem partition
Feb 9 09:54:13.324977 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(c): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2880831468"
Feb 9 09:54:13.324977 ignition[1029]: CRITICAL : files: createFilesystemsFiles: createFiles: op(b): op(c): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2880831468": device or resource busy
Feb 9 09:54:13.686590 kernel: audit: type=1130 audit(1707472453.378:38): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686616 kernel: audit: type=1130 audit(1707472453.459:39): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686627 kernel: audit: type=1130 audit(1707472453.507:40): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686645 kernel: audit: type=1131 audit(1707472453.507:41): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686656 kernel: audit: type=1130 audit(1707472453.615:42): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686665 kernel: audit: type=1131 audit(1707472453.615:43): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.378000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.459000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.507000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.507000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-quench comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.615000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.615000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-parse-etc comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.686820 ignition[1029]: ERROR : files: createFilesystemsFiles: createFiles: op(b): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2880831468", trying btrfs: device or resource busy
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(d): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2880831468"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(d): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2880831468"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(e): [started] unmounting "/mnt/oem2880831468"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): op(e): [finished] unmounting "/mnt/oem2880831468"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createFilesystemsFiles: createFiles: op(b): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(f): [started] processing unit "waagent.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(f): [finished] processing unit "waagent.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(10): [started] processing unit "nvidia.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(10): [finished] processing unit "nvidia.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(11): [started] setting preset to enabled for "nvidia.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(11): [finished] setting preset to enabled for "nvidia.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(12): [started] setting preset to enabled for "waagent.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: op(12): [finished] setting preset to enabled for "waagent.service"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createResultFile: createFiles: op(13): [started] writing file "/sysroot/etc/.ignition-result.json"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: createResultFile: createFiles: op(13): [finished] writing file "/sysroot/etc/.ignition-result.json"
Feb 9 09:54:13.686820 ignition[1029]: INFO : files: files passed
Feb 9 09:54:13.686820 ignition[1029]: INFO : Ignition finished successfully
Feb 9 09:54:13.990560 kernel: audit: type=1130 audit(1707472453.767:44): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.990590 kernel: audit: type=1130 audit(1707472453.824:45): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.990600 kernel: audit: type=1131 audit(1707472453.824:46): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.767000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.824000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.824000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.915000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-pivot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.329617 systemd[1]: mnt-oem2880831468.mount: Deactivated successfully.
Feb 9 09:54:13.364317 systemd[1]: Finished ignition-files.service.
Feb 9 09:54:14.010785 initrd-setup-root-after-ignition[1054]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Feb 9 09:54:13.411919 systemd[1]: Starting initrd-setup-root-after-ignition.service...
Feb 9 09:54:13.418459 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile).
Feb 9 09:54:13.419269 systemd[1]: Starting ignition-quench.service...
Feb 9 09:54:13.442211 systemd[1]: Finished initrd-setup-root-after-ignition.service.
Feb 9 09:54:14.068000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.491969 systemd[1]: ignition-quench.service: Deactivated successfully.
Feb 9 09:54:13.492054 systemd[1]: Finished ignition-quench.service.
Feb 9 09:54:14.089000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-initqueue comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.507637 systemd[1]: Reached target ignition-complete.target.
Feb 9 09:54:14.100000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root-after-ignition comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.571081 systemd[1]: Starting initrd-parse-etc.service...
Feb 9 09:54:14.110000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-files comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.608842 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Feb 9 09:54:14.120000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=flatcar-metadata-hostname comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.608935 systemd[1]: Finished initrd-parse-etc.service.
Feb 9 09:54:14.143000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.149863 iscsid[880]: iscsid shutting down.
Feb 9 09:54:13.615383 systemd[1]: Reached target initrd-fs.target.
Feb 9 09:54:14.164032 ignition[1067]: INFO : Ignition 2.14.0
Feb 9 09:54:14.164032 ignition[1067]: INFO : Stage: umount
Feb 9 09:54:14.164032 ignition[1067]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Feb 9 09:54:14.164032 ignition[1067]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Feb 9 09:54:14.164032 ignition[1067]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Feb 9 09:54:14.164032 ignition[1067]: INFO : umount: umount passed
Feb 9 09:54:14.164032 ignition[1067]: INFO : Ignition finished successfully
Feb 9 09:54:14.180000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.186000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.199000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsid comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.210000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-mount comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.224000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-disks comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.237000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-kargs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.672419 systemd[1]: Reached target initrd.target.
Feb 9 09:54:14.253000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.691309 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met.
Feb 9 09:54:14.263000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-fetch-offline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.706364 systemd[1]: Starting dracut-pre-pivot.service...
Feb 9 09:54:13.762165 systemd[1]: Finished dracut-pre-pivot.service.
Feb 9 09:54:13.768695 systemd[1]: Starting initrd-cleanup.service...
Feb 9 09:54:13.813700 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Feb 9 09:54:13.813801 systemd[1]: Finished initrd-cleanup.service.
Feb 9 09:54:13.825201 systemd[1]: Stopped target nss-lookup.target.
Feb 9 09:54:13.875454 systemd[1]: Stopped target remote-cryptsetup.target.
Feb 9 09:54:13.888825 systemd[1]: Stopped target timers.target.
Feb 9 09:54:14.333000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=ignition-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.901825 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Feb 9 09:54:13.901889 systemd[1]: Stopped dracut-pre-pivot.service.
Feb 9 09:54:14.353000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=iscsiuio comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.915201 systemd[1]: Stopped target initrd.target.
Feb 9 09:54:14.361000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=sysroot-boot comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.929338 systemd[1]: Stopped target basic.target.
Feb 9 09:54:13.942295 systemd[1]: Stopped target ignition-complete.target.
Feb 9 09:54:13.958297 systemd[1]: Stopped target ignition-diskful.target.
Feb 9 09:54:14.389000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-setup-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:13.974848 systemd[1]: Stopped target initrd-root-device.target.
Feb 9 09:54:13.985145 systemd[1]: Stopped target remote-fs.target.
Feb 9 09:54:13.995369 systemd[1]: Stopped target remote-fs-pre.target.
Feb 9 09:54:14.006437 systemd[1]: Stopped target sysinit.target.
Feb 9 09:54:14.425000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.015851 systemd[1]: Stopped target local-fs.target.
Feb 9 09:54:14.436000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.436000 audit: BPF prog-id=6 op=UNLOAD
Feb 9 09:54:14.030240 systemd[1]: Stopped target local-fs-pre.target.
Feb 9 09:54:14.041444 systemd[1]: Stopped target swap.target.
Feb 9 09:54:14.460000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=parse-ip-for-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.058153 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Feb 9 09:54:14.469000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.058227 systemd[1]: Stopped dracut-pre-mount.service.
Feb 9 09:54:14.480000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.068323 systemd[1]: Stopped target cryptsetup.target.
Feb 9 09:54:14.079037 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Feb 9 09:54:14.500000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.079103 systemd[1]: Stopped dracut-initqueue.service.
Feb 9 09:54:14.089914 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Feb 9 09:54:14.526000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-pre-udev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.089958 systemd[1]: Stopped initrd-setup-root-after-ignition.service.
Feb 9 09:54:14.538000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.100715 systemd[1]: ignition-files.service: Deactivated successfully.
Feb 9 09:54:14.549000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=dracut-cmdline-ask comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.100751 systemd[1]: Stopped ignition-files.service.
Feb 9 09:54:14.110325 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
Feb 9 09:54:14.110360 systemd[1]: Stopped flatcar-metadata-hostname.service.
Feb 9 09:54:14.121813 systemd[1]: Stopping ignition-mount.service...
Feb 9 09:54:14.597393 kernel: hv_netvsc 000d3af6-1390-000d-3af6-1390000d3af6 eth0: Data path switched from VF: enP53912s1
Feb 9 09:54:14.592000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.133603 systemd[1]: Stopping iscsid.service...
Feb 9 09:54:14.603000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.603000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=initrd-udevadm-cleanup-db comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.137547 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Feb 9 09:54:14.137614 systemd[1]: Stopped kmod-static-nodes.service.
Feb 9 09:54:14.144001 systemd[1]: Stopping sysroot-boot.service...
Feb 9 09:54:14.164542 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Feb 9 09:54:14.164606 systemd[1]: Stopped systemd-udev-trigger.service.
Feb 9 09:54:14.180326 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Feb 9 09:54:14.180376 systemd[1]: Stopped dracut-pre-trigger.service.
Feb 9 09:54:14.194489 systemd[1]: iscsid.service: Deactivated successfully.
Feb 9 09:54:14.194594 systemd[1]: Stopped iscsid.service.
Feb 9 09:54:14.199482 systemd[1]: ignition-mount.service: Deactivated successfully.
Feb 9 09:54:14.199560 systemd[1]: Stopped ignition-mount.service.
Feb 9 09:54:14.211277 systemd[1]: sysroot-boot.mount: Deactivated successfully.
Feb 9 09:54:14.211682 systemd[1]: ignition-disks.service: Deactivated successfully.
Feb 9 09:54:14.211723 systemd[1]: Stopped ignition-disks.service.
Feb 9 09:54:14.225099 systemd[1]: ignition-kargs.service: Deactivated successfully.
Feb 9 09:54:14.225148 systemd[1]: Stopped ignition-kargs.service.
Feb 9 09:54:14.693000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=network-cleanup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:14.237993 systemd[1]: ignition-fetch.service: Deactivated successfully.
Feb 9 09:54:14.238033 systemd[1]: Stopped ignition-fetch.service.
Feb 9 09:54:14.253543 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Feb 9 09:54:14.253596 systemd[1]: Stopped ignition-fetch-offline.service.
Feb 9 09:54:14.263241 systemd[1]: Stopped target paths.target.
Feb 9 09:54:14.275558 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Feb 9 09:54:14.285114 systemd[1]: Stopped systemd-ask-password-console.path.
Feb 9 09:54:14.295764 systemd[1]: Stopped target slices.target.
Feb 9 09:54:14.306320 systemd[1]: Stopped target sockets.target.
Feb 9 09:54:14.315502 systemd[1]: iscsid.socket: Deactivated successfully.
Feb 9 09:54:14.315553 systemd[1]: Closed iscsid.socket.
Feb 9 09:54:14.324007 systemd[1]: ignition-setup.service: Deactivated successfully.
Feb 9 09:54:14.324048 systemd[1]: Stopped ignition-setup.service.
Feb 9 09:54:14.334491 systemd[1]: Stopping iscsiuio.service...
Feb 9 09:54:14.343188 systemd[1]: iscsiuio.service: Deactivated successfully.
Feb 9 09:54:14.343283 systemd[1]: Stopped iscsiuio.service.
Feb 9 09:54:14.353336 systemd[1]: sysroot-boot.service: Deactivated successfully.
Feb 9 09:54:14.353431 systemd[1]: Stopped sysroot-boot.service.
Feb 9 09:54:14.362287 systemd[1]: Stopped target network.target.
Feb 9 09:54:14.371267 systemd[1]: iscsiuio.socket: Deactivated successfully.
Feb 9 09:54:14.371300 systemd[1]: Closed iscsiuio.socket.
Feb 9 09:54:14.379929 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Feb 9 09:54:14.379974 systemd[1]: Stopped initrd-setup-root.service.
Feb 9 09:54:14.390169 systemd[1]: Stopping systemd-networkd.service...
Feb 9 09:54:14.399700 systemd[1]: Stopping systemd-resolved.service...
Feb 9 09:54:14.413403 systemd-networkd[871]: eth0: DHCPv6 lease lost
Feb 9 09:54:14.769000 audit: BPF prog-id=9 op=UNLOAD
Feb 9 09:54:14.414672 systemd[1]: systemd-networkd.service: Deactivated successfully.
Feb 9 09:54:14.414761 systemd[1]: Stopped systemd-networkd.service.
Feb 9 09:54:14.425999 systemd[1]: systemd-resolved.service: Deactivated successfully.
Feb 9 09:54:14.426159 systemd[1]: Stopped systemd-resolved.service.
Feb 9 09:54:14.436783 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Feb 9 09:54:14.436821 systemd[1]: Closed systemd-networkd.socket.
Feb 9 09:54:14.446358 systemd[1]: Stopping network-cleanup.service...
Feb 9 09:54:14.454933 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Feb 9 09:54:14.454989 systemd[1]: Stopped parse-ip-for-networkd.service.
Feb 9 09:54:14.460590 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Feb 9 09:54:14.460641 systemd[1]: Stopped systemd-sysctl.service.
Feb 9 09:54:14.475439 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Feb 9 09:54:14.475485 systemd[1]: Stopped systemd-modules-load.service.
Feb 9 09:54:14.481378 systemd[1]: Stopping systemd-udevd.service...
Feb 9 09:54:14.492112 systemd[1]: run-credentials-systemd\x2dsysctl.service.mount: Deactivated successfully.
Feb 9 09:54:14.492604 systemd[1]: systemd-udevd.service: Deactivated successfully.
Feb 9 09:54:14.492714 systemd[1]: Stopped systemd-udevd.service.
Feb 9 09:54:14.501288 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Feb 9 09:54:14.770092 systemd-journald[276]: Received SIGTERM from PID 1 (n/a).
Feb 9 09:54:14.501338 systemd[1]: Closed systemd-udevd-control.socket.
Feb 9 09:54:14.511998 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Feb 9 09:54:14.512030 systemd[1]: Closed systemd-udevd-kernel.socket.
Feb 9 09:54:14.517240 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Feb 9 09:54:14.517286 systemd[1]: Stopped dracut-pre-udev.service.
Feb 9 09:54:14.526761 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Feb 9 09:54:14.526800 systemd[1]: Stopped dracut-cmdline.service.
Feb 9 09:54:14.538617 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Feb 9 09:54:14.538655 systemd[1]: Stopped dracut-cmdline-ask.service.
Feb 9 09:54:14.549918 systemd[1]: Starting initrd-udevadm-cleanup-db.service...
Feb 9 09:54:14.581861 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Feb 9 09:54:14.581938 systemd[1]: Stopped systemd-vconsole-setup.service.
Feb 9 09:54:14.593192 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Feb 9 09:54:14.593285 systemd[1]: Finished initrd-udevadm-cleanup-db.service.
Feb 9 09:54:14.682771 systemd[1]: network-cleanup.service: Deactivated successfully.
Feb 9 09:54:14.682895 systemd[1]: Stopped network-cleanup.service.
Feb 9 09:54:14.694231 systemd[1]: Reached target initrd-switch-root.target.
Feb 9 09:54:14.706100 systemd[1]: Starting initrd-switch-root.service...
Feb 9 09:54:14.726469 systemd[1]: Switching root.
Feb 9 09:54:14.770572 systemd-journald[276]: Journal stopped
Feb 9 09:54:26.866296 kernel: SELinux: Class mctp_socket not defined in policy.
Feb 9 09:54:26.866318 kernel: SELinux: Class anon_inode not defined in policy.
Feb 9 09:54:26.866328 kernel: SELinux: the above unknown classes and permissions will be allowed
Feb 9 09:54:26.866338 kernel: SELinux: policy capability network_peer_controls=1
Feb 9 09:54:26.866346 kernel: SELinux: policy capability open_perms=1
Feb 9 09:54:26.866354 kernel: SELinux: policy capability extended_socket_class=1
Feb 9 09:54:26.866363 kernel: SELinux: policy capability always_check_network=0
Feb 9 09:54:26.866371 kernel: SELinux: policy capability cgroup_seclabel=1
Feb 9 09:54:26.866379 kernel: SELinux: policy capability nnp_nosuid_transition=1
Feb 9 09:54:26.866387 kernel: SELinux: policy capability genfs_seclabel_symlinks=0
Feb 9 09:54:26.866397 kernel: SELinux: policy capability ioctl_skip_cloexec=0
Feb 9 09:54:26.866406 systemd[1]: Successfully loaded SELinux policy in 319.263ms.
Feb 9 09:54:26.866416 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 26.003ms.
Feb 9 09:54:26.866426 systemd[1]: systemd 252 running in system mode (+PAM +AUDIT +SELINUX -APPARMOR +IMA +SMACK +SECCOMP +GCRYPT -GNUTLS +OPENSSL -ACL +BLKID +CURL -ELFUTILS -FIDO2 +IDN2 -IDN +IPTC +KMOD +LIBCRYPTSETUP +LIBFDISK +PCRE2 -PWQUALITY -P11KIT -QRENCODE -TPM2 +BZIP2 +LZ4 +XZ +ZLIB +ZSTD -BPF_FRAMEWORK -XKBCOMMON +UTMP +SYSVINIT default-hierarchy=unified)
Feb 9 09:54:26.866437 systemd[1]: Detected virtualization microsoft.
Feb 9 09:54:26.866447 systemd[1]: Detected architecture arm64.
Feb 9 09:54:26.866456 systemd[1]: Detected first boot.
Feb 9 09:54:26.866465 systemd[1]: Hostname set to <ci-3510.3.2-a-9a5ebba439>.
Feb 9 09:54:26.866473 systemd[1]: Initializing machine ID from random generator.
Feb 9 09:54:26.866482 kernel: SELinux: Context system_u:object_r:container_file_t:s0:c1022,c1023 is not valid (left unmapped).
Feb 9 09:54:26.866491 kernel: kauditd_printk_skb: 41 callbacks suppressed
Feb 9 09:54:26.866500 kernel: audit: type=1400 audit(1707472459.321:88): avc: denied { associate } for pid=1100 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023"
Feb 9 09:54:26.866511 kernel: audit: type=1300 audit(1707472459.321:88): arch=c00000b7 syscall=5 success=yes exit=0 a0=40001458a2 a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1083 pid=1100 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:26.866522 kernel: audit: type=1327 audit(1707472459.321:88): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Feb 9 09:54:26.866531 kernel: audit: type=1400 audit(1707472459.331:89): avc: denied { associate } for pid=1100 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1
Feb 9 09:54:26.866540 kernel: audit: type=1300 audit(1707472459.331:89): arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145979 a2=1ed a3=0 items=2 ppid=1083 pid=1100 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:26.866549 kernel: audit: type=1307 audit(1707472459.331:89): cwd="/"
Feb 9 09:54:26.866559 kernel: audit: type=1302 audit(1707472459.331:89): item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:26.866568 kernel: audit: type=1302 audit(1707472459.331:89): item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:26.866577 kernel: audit: type=1327 audit(1707472459.331:89): proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Feb 9 09:54:26.866586 systemd[1]: Populated /etc with preset unit settings.
Feb 9 09:54:26.866596 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon.
Feb 9 09:54:26.866605 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon.
Feb 9 09:54:26.866615 systemd[1]: /run/systemd/system/docker.socket:8: ListenStream= references a path below legacy directory /var/run/, updating /var/run/docker.sock → /run/docker.sock; please update the unit file accordingly.
Feb 9 09:54:26.866625 kernel: audit: type=1334 audit(1707472466.062:90): prog-id=12 op=LOAD
Feb 9 09:54:26.866635 kernel: audit: type=1334 audit(1707472466.062:91): prog-id=3 op=UNLOAD
Feb 9 09:54:26.866644 kernel: audit: type=1334 audit(1707472466.062:92): prog-id=13 op=LOAD
Feb 9 09:54:26.866652 kernel: audit: type=1334 audit(1707472466.062:93): prog-id=14 op=LOAD
Feb 9 09:54:26.866660 kernel: audit: type=1334 audit(1707472466.062:94): prog-id=4 op=UNLOAD
Feb 9 09:54:26.866669 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Feb 9 09:54:26.866680 kernel: audit: type=1334 audit(1707472466.062:95): prog-id=5 op=UNLOAD
Feb 9 09:54:26.866689 systemd[1]: Stopped initrd-switch-root.service.
Feb 9 09:54:26.866700 kernel: audit: type=1334 audit(1707472466.062:96): prog-id=15 op=LOAD
Feb 9 09:54:26.866708 kernel: audit: type=1334 audit(1707472466.062:97): prog-id=12 op=UNLOAD
Feb 9 09:54:26.866717 kernel: audit: type=1334 audit(1707472466.062:98): prog-id=16 op=LOAD
Feb 9 09:54:26.866725 kernel: audit: type=1334 audit(1707472466.062:99): prog-id=17 op=LOAD
Feb 9 09:54:26.866734 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Feb 9 09:54:26.866744 systemd[1]: Created slice system-addon\x2dconfig.slice.
Feb 9 09:54:26.866753 systemd[1]: Created slice system-addon\x2drun.slice.
Feb 9 09:54:26.866763 systemd[1]: Created slice system-getty.slice.
Feb 9 09:54:26.866773 systemd[1]: Created slice system-modprobe.slice.
Feb 9 09:54:26.866782 systemd[1]: Created slice system-serial\x2dgetty.slice.
Feb 9 09:54:26.866792 systemd[1]: Created slice system-system\x2dcloudinit.slice.
Feb 9 09:54:26.866801 systemd[1]: Created slice system-systemd\x2dfsck.slice.
Feb 9 09:54:26.866810 systemd[1]: Created slice user.slice.
Feb 9 09:54:26.866820 systemd[1]: Started systemd-ask-password-console.path.
Feb 9 09:54:26.866830 systemd[1]: Started systemd-ask-password-wall.path.
Feb 9 09:54:26.866839 systemd[1]: Set up automount boot.automount.
Feb 9 09:54:26.866849 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
Feb 9 09:54:26.866858 systemd[1]: Stopped target initrd-switch-root.target.
Feb 9 09:54:26.866867 systemd[1]: Stopped target initrd-fs.target.
Feb 9 09:54:26.866876 systemd[1]: Stopped target initrd-root-fs.target.
Feb 9 09:54:26.866885 systemd[1]: Reached target integritysetup.target.
Feb 9 09:54:26.866895 systemd[1]: Reached target remote-cryptsetup.target.
Feb 9 09:54:26.866904 systemd[1]: Reached target remote-fs.target.
Feb 9 09:54:26.866913 systemd[1]: Reached target slices.target.
Feb 9 09:54:26.866923 systemd[1]: Reached target swap.target.
Feb 9 09:54:26.866933 systemd[1]: Reached target torcx.target.
Feb 9 09:54:26.866942 systemd[1]: Reached target veritysetup.target.
Feb 9 09:54:26.866951 systemd[1]: Listening on systemd-coredump.socket.
Feb 9 09:54:26.866960 systemd[1]: Listening on systemd-initctl.socket.
Feb 9 09:54:26.866970 systemd[1]: Listening on systemd-networkd.socket.
Feb 9 09:54:26.866980 systemd[1]: Listening on systemd-udevd-control.socket.
Feb 9 09:54:26.866990 systemd[1]: Listening on systemd-udevd-kernel.socket.
Feb 9 09:54:26.866999 systemd[1]: Listening on systemd-userdbd.socket.
Feb 9 09:54:26.867008 systemd[1]: Mounting dev-hugepages.mount...
Feb 9 09:54:26.867017 systemd[1]: Mounting dev-mqueue.mount...
Feb 9 09:54:26.867027 systemd[1]: Mounting media.mount...
Feb 9 09:54:26.867037 systemd[1]: Mounting sys-kernel-debug.mount...
Feb 9 09:54:26.867046 systemd[1]: Mounting sys-kernel-tracing.mount...
Feb 9 09:54:26.867056 systemd[1]: Mounting tmp.mount...
Feb 9 09:54:26.867075 systemd[1]: Starting flatcar-tmpfiles.service...
Feb 9 09:54:26.867085 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
Feb 9 09:54:26.867094 systemd[1]: Starting kmod-static-nodes.service...
Feb 9 09:54:26.867104 systemd[1]: Starting modprobe@configfs.service...
Feb 9 09:54:26.867113 systemd[1]: Starting modprobe@dm_mod.service...
Feb 9 09:54:26.867122 systemd[1]: Starting modprobe@drm.service...
Feb 9 09:54:26.867132 systemd[1]: Starting modprobe@efi_pstore.service...
Feb 9 09:54:26.867141 systemd[1]: Starting modprobe@fuse.service...
Feb 9 09:54:26.867152 systemd[1]: Starting modprobe@loop.service...
Feb 9 09:54:26.867162 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Feb 9 09:54:26.867172 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Feb 9 09:54:26.867181 systemd[1]: Stopped systemd-fsck-root.service.
Feb 9 09:54:26.867191 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Feb 9 09:54:26.867200 systemd[1]: Stopped systemd-fsck-usr.service.
Feb 9 09:54:26.867209 kernel: loop: module loaded
Feb 9 09:54:26.867218 systemd[1]: Stopped systemd-journald.service.
Feb 9 09:54:26.867229 systemd[1]: systemd-journald.service: Consumed 3.167s CPU time.
Feb 9 09:54:26.867239 kernel: fuse: init (API version 7.34)
Feb 9 09:54:26.867248 systemd[1]: Starting systemd-journald.service...
Feb 9 09:54:26.867257 systemd[1]: Starting systemd-modules-load.service...
Feb 9 09:54:26.867267 systemd[1]: Starting systemd-network-generator.service...
Feb 9 09:54:26.867276 systemd[1]: Starting systemd-remount-fs.service...
Feb 9 09:54:26.867285 systemd[1]: Starting systemd-udev-trigger.service...
Feb 9 09:54:26.867294 systemd[1]: verity-setup.service: Deactivated successfully.
Feb 9 09:54:26.867304 systemd[1]: Stopped verity-setup.service.
Feb 9 09:54:26.867314 systemd[1]: Mounted dev-hugepages.mount.
Feb 9 09:54:26.867323 systemd[1]: Mounted dev-mqueue.mount.
Feb 9 09:54:26.867333 systemd[1]: Mounted media.mount.
Feb 9 09:54:26.867342 systemd[1]: Mounted sys-kernel-debug.mount.
Feb 9 09:54:26.867351 systemd[1]: Mounted sys-kernel-tracing.mount.
Feb 9 09:54:26.867361 systemd[1]: Mounted tmp.mount.
Feb 9 09:54:26.867370 systemd[1]: Finished flatcar-tmpfiles.service.
Feb 9 09:54:26.867383 systemd-journald[1206]: Journal started
Feb 9 09:54:26.867423 systemd-journald[1206]: Runtime Journal (/run/log/journal/765f3756ee1f457b97b1358c6a18e91e) is 8.0M, max 78.6M, 70.6M free.
Feb 9 09:54:17.160000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
Feb 9 09:54:17.906000 audit[1]: AVC avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1
Feb 9 09:54:17.906000 audit[1]: AVC avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1
Feb 9 09:54:17.906000 audit: BPF prog-id=10 op=LOAD
Feb 9 09:54:17.906000 audit: BPF prog-id=10 op=UNLOAD
Feb 9 09:54:17.906000 audit: BPF prog-id=11 op=LOAD
Feb 9 09:54:17.906000 audit: BPF prog-id=11 op=UNLOAD
Feb 9 09:54:19.321000 audit[1100]: AVC avc: denied { associate } for pid=1100 comm="torcx-generator" name="docker" dev="tmpfs" ino=2 scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1 srawcon="system_u:object_r:container_file_t:s0:c1022,c1023"
Feb 9 09:54:19.321000 audit[1100]: SYSCALL arch=c00000b7 syscall=5 success=yes exit=0 a0=40001458a2 a1=40000c6df8 a2=40000cd0c0 a3=32 items=0 ppid=1083 pid=1100 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:19.321000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Feb 9 09:54:19.331000 audit[1100]: AVC avc: denied { associate } for pid=1100 comm="torcx-generator" name="usr" scontext=system_u:object_r:unlabeled_t:s0 tcontext=system_u:object_r:tmpfs_t:s0 tclass=filesystem permissive=1
Feb 9 09:54:19.331000 audit[1100]: SYSCALL arch=c00000b7 syscall=34 success=yes exit=0 a0=ffffffffffffff9c a1=4000145979 a2=1ed a3=0 items=2 ppid=1083 pid=1100 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="torcx-generator" exe="/usr/lib/systemd/system-generators/torcx-generator" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:19.331000 audit: CWD cwd="/"
Feb 9 09:54:19.331000 audit: PATH item=0 name=(null) inode=2 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:19.331000 audit: PATH item=1 name=(null) inode=3 dev=00:1c mode=040755 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:unlabeled_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:19.331000 audit: PROCTITLE proctitle=2F7573722F6C69622F73797374656D642F73797374656D2D67656E657261746F72732F746F7263782D67656E657261746F72002F72756E2F73797374656D642F67656E657261746F72002F72756E2F73797374656D642F67656E657261746F722E6561726C79002F72756E2F73797374656D642F67656E657261746F722E6C61
Feb 9 09:54:26.062000 audit: BPF prog-id=12 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=3 op=UNLOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=13 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=14 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=4 op=UNLOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=5 op=UNLOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=15 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=12 op=UNLOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=16 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=17 op=LOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=13 op=UNLOAD
Feb 9 09:54:26.062000 audit: BPF prog-id=14 op=UNLOAD
Feb 9 09:54:26.077000 audit: BPF prog-id=18 op=LOAD
Feb 9 09:54:26.077000 audit: BPF prog-id=15 op=UNLOAD
Feb 9 09:54:26.083000 audit: BPF prog-id=19 op=LOAD
Feb 9 09:54:26.092000 audit: BPF prog-id=20 op=LOAD
Feb 9 09:54:26.092000 audit: BPF prog-id=16 op=UNLOAD
Feb 9 09:54:26.092000 audit: BPF prog-id=17 op=UNLOAD
Feb 9 09:54:26.092000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.119000 audit: BPF prog-id=18 op=UNLOAD
Feb 9 09:54:26.132000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.132000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=initrd-switch-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.688000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-root comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.709000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck-usr comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.719000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.719000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.728000 audit: BPF prog-id=21 op=LOAD
Feb 9 09:54:26.728000 audit: BPF prog-id=22 op=LOAD
Feb 9 09:54:26.728000 audit: BPF prog-id=23 op=LOAD
Feb 9 09:54:26.728000 audit: BPF prog-id=19 op=UNLOAD
Feb 9 09:54:26.728000 audit: BPF prog-id=20 op=UNLOAD
Feb 9 09:54:26.806000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=verity-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.864000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
Feb 9 09:54:19.263655 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="common configuration parsed" base_dir=/var/lib/torcx/ conf_dir=/etc/torcx/ run_dir=/run/torcx/ store_paths="[/usr/share/torcx/store /usr/share/oem/torcx/store/3510.3.2 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.2 /var/lib/torcx/store]"
Feb 9 09:54:26.060447 systemd[1]: Queued start job for default target multi-user.target.
Feb 9 09:54:19.304967 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Feb 9 09:54:26.092825 systemd[1]: systemd-journald.service: Deactivated successfully.
Feb 9 09:54:19.304987 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Feb 9 09:54:26.093256 systemd[1]: systemd-journald.service: Consumed 3.167s CPU time.
Feb 9 09:54:19.305025 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
Feb 9 09:54:19.305036 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="skipped missing lower profile" missing profile=oem
Feb 9 09:54:19.305089 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
Feb 9 09:54:19.305102 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
Feb 9 09:54:19.305306 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
Feb 9 09:54:19.305339 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Feb 9 09:54:19.305350 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Feb 9 09:54:26.864000 audit[1206]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=3 a1=ffffdea067e0 a2=4000 a3=1 items=0 ppid=1 pid=1206 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="systemd-journal" exe="/usr/lib/systemd/systemd-journald" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:26.864000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
Feb 9 09:54:19.305756 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:20.10.torcx.tgz" reference=20.10
Feb 9 09:54:19.305790 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=debug msg="new archive/reference added to cache" format=tgz name=docker path="/usr/share/torcx/store/docker:com.coreos.cl.torcx.tgz" reference=com.coreos.cl
Feb 9 09:54:19.305821 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.2: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.2
Feb 9 09:54:19.305836 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store
Feb 9 09:54:19.305852 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.2: no such file or directory" path=/var/lib/torcx/store/3510.3.2
Feb 9 09:54:19.305865 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:19Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
Feb 9 09:54:25.051037 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Feb 9 09:54:25.051327 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="binaries propagated" assets="[/bin/containerd /bin/containerd-shim /bin/ctr /bin/docker /bin/docker-containerd /bin/docker-containerd-shim /bin/docker-init /bin/docker-proxy /bin/docker-runc /bin/dockerd /bin/runc /bin/tini]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Feb 9 09:54:25.051417 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="networkd units propagated" assets="[/lib/systemd/network/50-docker.network /lib/systemd/network/90-docker-veth.network]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Feb 9 09:54:25.051571 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="systemd units propagated" assets="[/lib/systemd/system/containerd.service /lib/systemd/system/docker.service /lib/systemd/system/docker.socket /lib/systemd/system/sockets.target.wants /lib/systemd/system/multi-user.target.wants]" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Feb 9 09:54:25.051619 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
Feb 9 09:54:25.051674 /usr/lib/systemd/system-generators/torcx-generator[1100]: time="2024-02-09T09:54:25Z" level=debug msg="system state sealed" content="[TORCX_LOWER_PROFILES=\"vendor\" TORCX_UPPER_PROFILE=\"\" TORCX_PROFILE_PATH=\"/run/torcx/profile.json\" TORCX_BINDIR=\"/run/torcx/bin\" TORCX_UNPACKDIR=\"/run/torcx/unpack\"]" path=/run/metadata/torcx
Feb 9 09:54:26.874000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=flatcar-tmpfiles comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.884198 systemd[1]: Started systemd-journald.service.
Feb 9 09:54:26.884000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journald comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.885166 systemd[1]: Finished kmod-static-nodes.service.
Feb 9 09:54:26.890000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=kmod-static-nodes comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.890802 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Feb 9 09:54:26.890923 systemd[1]: Finished modprobe@configfs.service.
Feb 9 09:54:26.896000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.896000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@configfs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.896602 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Feb 9 09:54:26.896724 systemd[1]: Finished modprobe@dm_mod.service.
Feb 9 09:54:26.901000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.901000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@dm_mod comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.902039 systemd[1]: modprobe@drm.service: Deactivated successfully.
Feb 9 09:54:26.902213 systemd[1]: Finished modprobe@drm.service.
Feb 9 09:54:26.907000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.907000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@drm comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.907359 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Feb 9 09:54:26.907659 systemd[1]: Finished modprobe@efi_pstore.service.
Feb 9 09:54:26.913000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.913000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@efi_pstore comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.913349 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Feb 9 09:54:26.913469 systemd[1]: Finished modprobe@fuse.service.
Feb 9 09:54:26.918000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.918000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@fuse comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.918870 systemd[1]: modprobe@loop.service: Deactivated successfully.
Feb 9 09:54:26.919018 systemd[1]: Finished modprobe@loop.service.
Feb 9 09:54:26.923000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.923000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=modprobe@loop comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.924423 systemd[1]: Finished systemd-network-generator.service.
Feb 9 09:54:26.930000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-network-generator comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.930699 systemd[1]: Finished systemd-remount-fs.service.
Feb 9 09:54:26.935000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-remount-fs comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.936420 systemd[1]: Finished systemd-modules-load.service.
Feb 9 09:54:26.941000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-modules-load comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.942033 systemd[1]: Finished systemd-udev-trigger.service.
Feb 9 09:54:26.947000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-trigger comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:26.947950 systemd[1]: Reached target network-pre.target.
Feb 9 09:54:26.954216 systemd[1]: Mounting sys-fs-fuse-connections.mount...
Feb 9 09:54:26.959918 systemd[1]: Mounting sys-kernel-config.mount...
Feb 9 09:54:26.964330 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Feb 9 09:54:26.980949 systemd[1]: Starting systemd-hwdb-update.service...
Feb 9 09:54:26.986748 systemd[1]: Starting systemd-journal-flush.service...
Feb 9 09:54:26.991578 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Feb 9 09:54:26.992619 systemd[1]: Starting systemd-random-seed.service...
Feb 9 09:54:26.997267 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
Feb 9 09:54:26.998298 systemd[1]: Starting systemd-sysctl.service...
Feb 9 09:54:27.003912 systemd[1]: Starting systemd-sysusers.service...
Feb 9 09:54:27.009473 systemd[1]: Starting systemd-udev-settle.service...
Feb 9 09:54:27.018410 systemd[1]: Mounted sys-fs-fuse-connections.mount.
Feb 9 09:54:27.024363 systemd[1]: Mounted sys-kernel-config.mount.
Feb 9 09:54:27.029924 systemd-journald[1206]: Time spent on flushing to /var/log/journal/765f3756ee1f457b97b1358c6a18e91e is 13.459ms for 1086 entries.
Feb 9 09:54:27.029924 systemd-journald[1206]: System Journal (/var/log/journal/765f3756ee1f457b97b1358c6a18e91e) is 8.0M, max 2.6G, 2.6G free.
Feb 9 09:54:27.124227 systemd-journald[1206]: Received client request to flush runtime journal.
Feb 9 09:54:27.070000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-random-seed comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:27.113000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:27.064958 systemd[1]: Finished systemd-random-seed.service.
Feb 9 09:54:27.124486 udevadm[1220]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation-early.service, lvm2-activation.service not to pull it in.
Feb 9 09:54:27.070546 systemd[1]: Reached target first-boot-complete.target.
Feb 9 09:54:27.102993 systemd[1]: Finished systemd-sysctl.service.
Feb 9 09:54:27.125151 systemd[1]: Finished systemd-journal-flush.service.
Feb 9 09:54:27.131000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-flush comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:27.557919 systemd[1]: Finished systemd-sysusers.service.
Feb 9 09:54:27.563000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-sysusers comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.213118 systemd[1]: Finished systemd-hwdb-update.service.
Feb 9 09:54:28.218000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-hwdb-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.219000 audit: BPF prog-id=24 op=LOAD
Feb 9 09:54:28.219000 audit: BPF prog-id=25 op=LOAD
Feb 9 09:54:28.219000 audit: BPF prog-id=7 op=UNLOAD
Feb 9 09:54:28.219000 audit: BPF prog-id=8 op=UNLOAD
Feb 9 09:54:28.220037 systemd[1]: Starting systemd-udevd.service...
Feb 9 09:54:28.238671 systemd-udevd[1223]: Using default interface naming scheme 'v252'.
Feb 9 09:54:28.424096 systemd[1]: Started systemd-udevd.service.
Feb 9 09:54:28.434000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udevd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.437000 audit: BPF prog-id=26 op=LOAD
Feb 9 09:54:28.437917 systemd[1]: Starting systemd-networkd.service...
Feb 9 09:54:28.465219 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped.
Feb 9 09:54:28.515924 systemd[1]: Starting systemd-userdbd.service...
Feb 9 09:54:28.515000 audit: BPF prog-id=27 op=LOAD
Feb 9 09:54:28.515000 audit: BPF prog-id=28 op=LOAD
Feb 9 09:54:28.515000 audit: BPF prog-id=29 op=LOAD
Feb 9 09:54:28.538110 kernel: mousedev: PS/2 mouse device common for all mice
Feb 9 09:54:28.601709 systemd[1]: Started systemd-userdbd.service.
Feb 9 09:54:28.606000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-userdbd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.684086 kernel: hv_vmbus: registering driver hyperv_fb
Feb 9 09:54:28.684166 kernel: hyperv_fb: Synthvid Version major 3, minor 5
Feb 9 09:54:28.703690 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
Feb 9 09:54:28.703770 kernel: hv_utils: Registering HyperV Utility Driver
Feb 9 09:54:28.703785 kernel: Console: switching to colour dummy device 80x25
Feb 9 09:54:28.690000 audit[1235]: AVC avc: denied { confidentiality } for pid=1235 comm="(udev-worker)" lockdown_reason="use of tracefs" scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=lockdown permissive=1
Feb 9 09:54:28.709078 kernel: hv_vmbus: registering driver hv_utils
Feb 9 09:54:28.709110 kernel: hv_utils: Heartbeat IC version 3.0
Feb 9 09:54:28.709126 kernel: hv_utils: Shutdown IC version 3.2
Feb 9 09:54:28.710092 kernel: hv_vmbus: registering driver hv_balloon
Feb 9 09:54:28.717700 kernel: hv_utils: TimeSync IC version 4.0
Feb 9 09:54:28.717756 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
Feb 9 09:54:28.717785 kernel: hv_balloon: Memory hot add disabled on ARM64
Feb 9 09:54:28.723555 kernel: Console: switching to colour frame buffer device 128x48
Feb 9 09:54:28.690000 audit[1235]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaaae50fad30 a1=aa2c a2=ffffadca24b0 a3=aaaae5057010 items=12 ppid=1223 pid=1235 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="(udev-worker)" exe="/usr/bin/udevadm" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:28.690000 audit: CWD cwd="/"
Feb 9 09:54:28.690000 audit: PATH item=0 name=(null) inode=7190 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=1 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=2 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=3 name=(null) inode=9930 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=4 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=5 name=(null) inode=9931 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=6 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=7 name=(null) inode=9932 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=8 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=9 name=(null) inode=9933 dev=00:0a mode=0100640 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=10 name=(null) inode=9929 dev=00:0a mode=040750 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=PARENT cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PATH item=11 name=(null) inode=9934 dev=00:0a mode=0100440 ouid=0 ogid=0 rdev=00:00 obj=system_u:object_r:tracefs_t:s0 nametype=CREATE cap_fp=0 cap_fi=0 cap_fe=0 cap_fver=0 cap_frootid=0
Feb 9 09:54:28.690000 audit: PROCTITLE proctitle="(udev-worker)"
Feb 9 09:54:28.824640 systemd-networkd[1244]: lo: Link UP
Feb 9 09:54:28.824649 systemd-networkd[1244]: lo: Gained carrier
Feb 9 09:54:28.825080 systemd-networkd[1244]: Enumeration completed
Feb 9 09:54:28.825196 systemd[1]: Started systemd-networkd.service.
Feb 9 09:54:28.830000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.831803 systemd[1]: Starting systemd-networkd-wait-online.service...
Feb 9 09:54:28.872561 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1241)
Feb 9 09:54:28.872956 systemd-networkd[1244]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Feb 9 09:54:28.890985 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Feb 9 09:54:28.900658 systemd[1]: Finished systemd-udev-settle.service.
Feb 9 09:54:28.906000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-udev-settle comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:28.908069 systemd[1]: Starting lvm2-activation-early.service...
Feb 9 09:54:28.945545 kernel: mlx5_core d298:00:02.0 enP53912s1: Link up
Feb 9 09:54:28.976324 systemd-networkd[1244]: enP53912s1: Link UP
Feb 9 09:54:28.976558 kernel: hv_netvsc 000d3af6-1390-000d-3af6-1390000d3af6 eth0: Data path switched to VF: enP53912s1
Feb 9 09:54:28.976781 systemd-networkd[1244]: eth0: Link UP
Feb 9 09:54:28.976838 systemd-networkd[1244]: eth0: Gained carrier
Feb 9 09:54:28.985838 systemd-networkd[1244]: enP53912s1: Gained carrier
Feb 9 09:54:28.995679 systemd-networkd[1244]: eth0: DHCPv4 address 10.200.20.10/24, gateway 10.200.20.1 acquired from 168.63.129.16
Feb 9 09:54:29.196872 lvm[1302]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Feb 9 09:54:29.236537 systemd[1]: Finished lvm2-activation-early.service.
Feb 9 09:54:29.241000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation-early comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.242226 systemd[1]: Reached target cryptsetup.target.
Feb 9 09:54:29.248568 systemd[1]: Starting lvm2-activation.service...
Feb 9 09:54:29.252110 lvm[1303]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Feb 9 09:54:29.276445 systemd[1]: Finished lvm2-activation.service.
Feb 9 09:54:29.281000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=lvm2-activation comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.281674 systemd[1]: Reached target local-fs-pre.target.
Feb 9 09:54:29.287045 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Feb 9 09:54:29.287072 systemd[1]: Reached target local-fs.target.
Feb 9 09:54:29.291965 systemd[1]: Reached target machines.target.
Feb 9 09:54:29.298223 systemd[1]: Starting ldconfig.service...
Feb 9 09:54:29.303100 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
Feb 9 09:54:29.303172 systemd[1]: systemd-boot-system-token.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/LoaderFeatures-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Feb 9 09:54:29.304326 systemd[1]: Starting systemd-boot-update.service...
Feb 9 09:54:29.310161 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
Feb 9 09:54:29.318345 systemd[1]: Starting systemd-machine-id-commit.service...
Feb 9 09:54:29.323923 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
Feb 9 09:54:29.323987 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
Feb 9 09:54:29.325143 systemd[1]: Starting systemd-tmpfiles-setup.service...
Feb 9 09:54:29.351745 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1305 (bootctl)
Feb 9 09:54:29.352947 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
Feb 9 09:54:29.381290 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
Feb 9 09:54:29.387000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-OEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.456771 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Feb 9 09:54:29.457663 systemd[1]: Finished systemd-machine-id-commit.service.
Feb 9 09:54:29.463000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-machine-id-commit comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.503448 systemd-tmpfiles[1308]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
Feb 9 09:54:29.650139 systemd-tmpfiles[1308]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Feb 9 09:54:29.657832 systemd-fsck[1313]: fsck.fat 4.2 (2021-01-31)
Feb 9 09:54:29.657832 systemd-fsck[1313]: /dev/sda1: 236 files, 113719/258078 clusters
Feb 9 09:54:29.659736 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
Feb 9 09:54:29.667000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.670654 systemd[1]: Mounting boot.mount...
Feb 9 09:54:29.680998 systemd[1]: Mounted boot.mount.
Feb 9 09:54:29.692265 systemd[1]: Finished systemd-boot-update.service.
Feb 9 09:54:29.697000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-boot-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:29.742206 systemd-tmpfiles[1308]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Feb 9 09:54:30.055685 systemd-networkd[1244]: eth0: Gained IPv6LL
Feb 9 09:54:30.062379 systemd[1]: Finished systemd-networkd-wait-online.service.
Feb 9 09:54:30.068000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-networkd-wait-online comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.294379 systemd[1]: Finished systemd-tmpfiles-setup.service.
Feb 9 09:54:30.299000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.301772 systemd[1]: Starting audit-rules.service...
Feb 9 09:54:30.307675 systemd[1]: Starting clean-ca-certificates.service...
Feb 9 09:54:30.315732 systemd[1]: Starting systemd-journal-catalog-update.service...
Feb 9 09:54:30.322000 audit: BPF prog-id=30 op=LOAD
Feb 9 09:54:30.323489 systemd[1]: Starting systemd-resolved.service...
Feb 9 09:54:30.330000 audit: BPF prog-id=31 op=LOAD
Feb 9 09:54:30.332078 systemd[1]: Starting systemd-timesyncd.service...
Feb 9 09:54:30.338454 systemd[1]: Starting systemd-update-utmp.service...
Feb 9 09:54:30.394055 systemd[1]: Finished clean-ca-certificates.service.
Feb 9 09:54:30.395000 audit[1325]: SYSTEM_BOOT pid=1325 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg=' comm="systemd-update-utmp" exe="/usr/lib/systemd/systemd-update-utmp" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.399000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=clean-ca-certificates comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.402002 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Feb 9 09:54:30.403150 systemd[1]: Finished systemd-update-utmp.service.
Feb 9 09:54:30.408000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-update-utmp comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.428261 systemd[1]: Started systemd-timesyncd.service.
Feb 9 09:54:30.433000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-timesyncd comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.434595 systemd[1]: Reached target time-set.target.
Feb 9 09:54:30.483154 systemd-resolved[1322]: Positive Trust Anchors:
Feb 9 09:54:30.483165 systemd-resolved[1322]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Feb 9 09:54:30.483193 systemd-resolved[1322]: Negative trust anchors: home.arpa 10.in-addr.arpa 16.172.in-addr.arpa 17.172.in-addr.arpa 18.172.in-addr.arpa 19.172.in-addr.arpa 20.172.in-addr.arpa 21.172.in-addr.arpa 22.172.in-addr.arpa 23.172.in-addr.arpa 24.172.in-addr.arpa 25.172.in-addr.arpa 26.172.in-addr.arpa 27.172.in-addr.arpa 28.172.in-addr.arpa 29.172.in-addr.arpa 30.172.in-addr.arpa 31.172.in-addr.arpa 168.192.in-addr.arpa d.f.ip6.arpa corp home internal intranet lan local private test
Feb 9 09:54:30.554763 systemd-resolved[1322]: Using system hostname 'ci-3510.3.2-a-9a5ebba439'.
Feb 9 09:54:30.556342 systemd[1]: Started systemd-resolved.service.
Feb 9 09:54:30.562000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-resolved comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.562981 systemd[1]: Reached target network.target.
Feb 9 09:54:30.568623 systemd[1]: Reached target network-online.target.
Feb 9 09:54:30.574431 systemd[1]: Reached target nss-lookup.target.
Feb 9 09:54:30.602596 systemd[1]: Finished systemd-journal-catalog-update.service.
Feb 9 09:54:30.608000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-journal-catalog-update comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:54:30.770000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1
Feb 9 09:54:30.770000 audit[1340]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=fffffd0477c0 a2=420 a3=0 items=0 ppid=1319 pid=1340 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:54:30.770000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
Feb 9 09:54:30.785968 augenrules[1340]: No rules
Feb 9 09:54:30.786879 systemd[1]: Finished audit-rules.service.
Feb 9 09:54:30.798496 systemd-timesyncd[1324]: Contacted time server 198.211.103.209:123 (0.flatcar.pool.ntp.org).
Feb 9 09:54:30.798853 systemd-timesyncd[1324]: Initial clock synchronization to Fri 2024-02-09 09:54:30.796145 UTC.
Feb 9 09:54:37.190332 ldconfig[1304]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Feb 9 09:54:37.214217 systemd[1]: Finished ldconfig.service.
Feb 9 09:54:37.220947 systemd[1]: Starting systemd-update-done.service...
Feb 9 09:54:37.256048 systemd[1]: Finished systemd-update-done.service.
Feb 9 09:54:37.261658 systemd[1]: Reached target sysinit.target.
Feb 9 09:54:37.266904 systemd[1]: Started motdgen.path.
Feb 9 09:54:37.271185 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
Feb 9 09:54:37.278512 systemd[1]: Started logrotate.timer.
Feb 9 09:54:37.283058 systemd[1]: Started mdadm.timer.
Feb 9 09:54:37.287270 systemd[1]: Started systemd-tmpfiles-clean.timer.
Feb 9 09:54:37.292737 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Feb 9 09:54:37.292769 systemd[1]: Reached target paths.target.
Feb 9 09:54:37.297680 systemd[1]: Reached target timers.target.
Feb 9 09:54:37.303379 systemd[1]: Listening on dbus.socket.
Feb 9 09:54:37.309025 systemd[1]: Starting docker.socket...
Feb 9 09:54:37.315490 systemd[1]: Listening on sshd.socket.
Feb 9 09:54:37.320239 systemd[1]: systemd-pcrphase-sysinit.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Feb 9 09:54:37.320731 systemd[1]: Listening on docker.socket.
Feb 9 09:54:37.325471 systemd[1]: Reached target sockets.target.
Feb 9 09:54:37.330649 systemd[1]: Reached target basic.target.
Feb 9 09:54:37.335389 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
Feb 9 09:54:37.335417 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
Feb 9 09:54:37.336558 systemd[1]: Starting containerd.service...
Feb 9 09:54:37.341623 systemd[1]: Starting dbus.service...
Feb 9 09:54:37.346519 systemd[1]: Starting enable-oem-cloudinit.service...
Feb 9 09:54:37.352448 systemd[1]: Starting extend-filesystems.service...
Feb 9 09:54:37.357341 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
Feb 9 09:54:37.358393 systemd[1]: Starting motdgen.service...
Feb 9 09:54:37.363418 systemd[1]: Started nvidia.service.
Feb 9 09:54:37.371502 systemd[1]: Starting ssh-key-proc-cmdline.service...
Feb 9 09:54:37.378952 systemd[1]: Starting sshd-keygen.service...
Feb 9 09:54:37.385355 systemd[1]: Starting systemd-logind.service...
Feb 9 09:54:37.390120 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Feb 9 09:54:37.390174 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Feb 9 09:54:37.390631 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Feb 9 09:54:37.391298 systemd[1]: Starting update-engine.service...
Feb 9 09:54:37.396907 systemd[1]: Starting update-ssh-keys-after-ignition.service...
Feb 9 09:54:37.404080 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Feb 9 09:54:37.404935 systemd[1]: Finished ssh-key-proc-cmdline.service.
Feb 9 09:54:37.461188 jq[1366]: true
Feb 9 09:54:37.463628 jq[1350]: false
Feb 9 09:54:37.461719 systemd[1]: motdgen.service: Deactivated successfully.
Feb 9 09:54:37.461891 systemd[1]: Finished motdgen.service.
Feb 9 09:54:37.483290 extend-filesystems[1351]: Found sda
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda1
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda2
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda3
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found usr
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda4
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda6
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda7
Feb 9 09:54:37.488026 extend-filesystems[1351]: Found sda9
Feb 9 09:54:37.488026 extend-filesystems[1351]: Checking size of /dev/sda9
Feb 9 09:54:37.557426 env[1369]: time="2024-02-09T09:54:37.548196407Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
Feb 9 09:54:37.492719 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Feb 9 09:54:37.557887 jq[1375]: true
Feb 9 09:54:37.492902 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
Feb 9 09:54:37.503987 systemd-logind[1361]: Watching system buttons on /dev/input/event0 (AT Translated Set 2 keyboard)
Feb 9 09:54:37.511301 systemd-logind[1361]: New seat seat0.
Feb 9 09:54:37.585476 env[1369]: time="2024-02-09T09:54:37.585436935Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Feb 9 09:54:37.585778 env[1369]: time="2024-02-09T09:54:37.585759784Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.586800 env[1369]: time="2024-02-09T09:54:37.586771088Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.aufs\"..." error="aufs is not supported (modprobe aufs failed: exit status 1 \"modprobe: FATAL: Module aufs not found in directory /lib/modules/5.15.148-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Feb 9 09:54:37.586896 env[1369]: time="2024-02-09T09:54:37.586882877Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.587154 env[1369]: time="2024-02-09T09:54:37.587134173Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.btrfs (ext4) must be a btrfs filesystem to be used with the btrfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 9 09:54:37.587231 env[1369]: time="2024-02-09T09:54:37.587217925Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.587349 env[1369]: time="2024-02-09T09:54:37.587332754Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Feb 9 09:54:37.587591 env[1369]: time="2024-02-09T09:54:37.587571851Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.588100 env[1369]: time="2024-02-09T09:54:37.588081763Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.588824 env[1369]: time="2024-02-09T09:54:37.588803294Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Feb 9 09:54:37.589159 env[1369]: time="2024-02-09T09:54:37.589137342Z" level=info msg="skip loading plugin \"io.containerd.snapshotter.v1.zfs\"..." error="path /var/lib/containerd/io.containerd.snapshotter.v1.zfs must be a zfs filesystem to be used with the zfs snapshotter: skip plugin" type=io.containerd.snapshotter.v1
Feb 9 09:54:37.589247 env[1369]: time="2024-02-09T09:54:37.589232893Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Feb 9 09:54:37.589352 env[1369]: time="2024-02-09T09:54:37.589337243Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Feb 9 09:54:37.589408 env[1369]: time="2024-02-09T09:54:37.589396037Z" level=info msg="metadata content store policy set" policy=shared
Feb 9 09:54:37.621548 extend-filesystems[1351]: Old size kept for /dev/sda9
Feb 9 09:54:37.621548 extend-filesystems[1351]: Found sr0
Feb 9 09:54:37.619699 systemd[1]: extend-filesystems.service: Deactivated successfully.
Feb 9 09:54:37.664262 dbus-daemon[1349]: [system] SELinux support is enabled
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636654410Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636696206Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636711364Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636751641Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636766999Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636780878Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.636793876Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637153962Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637174800Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637187879Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637200278Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637213476Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637348344Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Feb 9 09:54:37.694307 env[1369]: time="2024-02-09T09:54:37.637416217Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Feb 9 09:54:37.619881 systemd[1]: Finished extend-filesystems.service.
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.639723877Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.639766273Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.639781791Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640116960Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640137198Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640150116Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640221910Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640238108Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640249747Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640260546Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640273545Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640287543Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640426250Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640442808Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.694697 env[1369]: time="2024-02-09T09:54:37.640455687Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.641932 systemd[1]: Started containerd.service.
Feb 9 09:54:37.695069 env[1369]: time="2024-02-09T09:54:37.640468806Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Feb 9 09:54:37.695069 env[1369]: time="2024-02-09T09:54:37.640483405Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Feb 9 09:54:37.695069 env[1369]: time="2024-02-09T09:54:37.640494084Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Feb 9 09:54:37.695069 env[1369]: time="2024-02-09T09:54:37.640512362Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Feb 9 09:54:37.695069 env[1369]: time="2024-02-09T09:54:37.640558437Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Feb 9 09:54:37.669911 systemd[1]: Started dbus.service.
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.640756059Z" level=info msg="Start cri plugin with config {PluginConfig:{ContainerdConfig:{Snapshotter:overlayfs DefaultRuntimeName:runc DefaultRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} UntrustedWorkloadRuntime:{Type: Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0} Runtimes:map[runc:{Type:io.containerd.runc.v2 Path: Engine: PodAnnotations:[] ContainerAnnotations:[] Root: Options:map[SystemdCgroup:true] PrivilegedWithoutHostDevices:false BaseRuntimeSpec: NetworkPluginConfDir: NetworkPluginMaxConfNum:0}] NoPivot:false DisableSnapshotAnnotations:true DiscardUnpackedLayers:false IgnoreRdtNotEnabledErrors:false} CniConfig:{NetworkPluginBinDir:/opt/cni/bin NetworkPluginConfDir:/etc/cni/net.d NetworkPluginMaxConfNum:1 NetworkPluginConfTemplate: IPPreference:} Registry:{ConfigPath: Mirrors:map[] Configs:map[] Auths:map[] Headers:map[]} ImageDecryption:{KeyModel:node} DisableTCPService:true StreamServerAddress:127.0.0.1 StreamServerPort:0 StreamIdleTimeout:4h0m0s EnableSelinux:true SelinuxCategoryRange:1024 SandboxImage:registry.k8s.io/pause:3.6 StatsCollectPeriod:10 SystemdCgroup:false EnableTLSStreaming:false X509KeyPairStreaming:{TLSCertFile: TLSKeyFile:} MaxContainerLogLineSize:16384 DisableCgroup:false DisableApparmor:false RestrictOOMScoreAdj:false MaxConcurrentDownloads:3 DisableProcMount:false UnsetSeccompProfile: TolerateMissingHugetlbController:true DisableHugetlbController:true DeviceOwnershipFromSecurityContext:false IgnoreImageDefinedVolumes:false NetNSMountsUnderStateDir:false EnableUnprivilegedPorts:false EnableUnprivilegedICMP:false} ContainerdRootDir:/var/lib/containerd ContainerdEndpoint:/run/containerd/containerd.sock RootDir:/var/lib/containerd/io.containerd.grpc.v1.cri StateDir:/run/containerd/io.containerd.grpc.v1.cri}"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.640810053Z" level=info msg="Connect containerd service"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.640840171Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.641515746Z" level=error msg="failed to load cni during init, please check CRI plugin status before setting up network for pods" error="cni config load failed: no network config found in /etc/cni/net.d: cni plugin not initialized: failed to load cni config"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.641770162Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.641806318Z" level=info msg=serving... address=/run/containerd/containerd.sock
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.641857234Z" level=info msg="containerd successfully booted in 0.103113s"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642215239Z" level=info msg="Start subscribing containerd event"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642252476Z" level=info msg="Start recovering state"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642310710Z" level=info msg="Start event monitor"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642328469Z" level=info msg="Start snapshots syncer"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642337588Z" level=info msg="Start cni network conf syncer for default"
Feb 9 09:54:37.695220 env[1369]: time="2024-02-09T09:54:37.642344587Z" level=info msg="Start streaming server"
Feb 9 09:54:37.708652 systemd[1]: Started systemd-logind.service.
Feb 9 09:54:37.715883 bash[1409]: Updated "/home/core/.ssh/authorized_keys"
Feb 9 09:54:37.705175 dbus-daemon[1349]: [system] Successfully activated service 'org.freedesktop.systemd1'
Feb 9 09:54:37.716049 systemd[1]: Finished update-ssh-keys-after-ignition.service.
Feb 9 09:54:37.728782 systemd[1]: system-cloudinit@usr-share-oem-cloud\x2dconfig.yml.service was skipped because of an unmet condition check (ConditionFileNotEmpty=/usr/share/oem/cloud-config.yml).
Feb 9 09:54:37.728930 systemd[1]: Reached target system-config.target.
Feb 9 09:54:37.737467 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Feb 9 09:54:37.737600 systemd[1]: Reached target user-config.target.
Feb 9 09:54:37.773386 systemd[1]: nvidia.service: Deactivated successfully.
Feb 9 09:54:38.180232 update_engine[1365]: I0209 09:54:38.151232 1365 main.cc:92] Flatcar Update Engine starting
Feb 9 09:54:38.230342 systemd[1]: Started update-engine.service.
Feb 9 09:54:38.230777 update_engine[1365]: I0209 09:54:38.230402 1365 update_check_scheduler.cc:74] Next update check in 5m57s
Feb 9 09:54:38.239789 systemd[1]: Started locksmithd.service.
Feb 9 09:54:38.670653 sshd_keygen[1364]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Feb 9 09:54:38.687027 systemd[1]: Finished sshd-keygen.service.
Feb 9 09:54:38.693464 systemd[1]: Starting issuegen.service...
Feb 9 09:54:38.698892 systemd[1]: Started waagent.service.
Feb 9 09:54:38.704240 systemd[1]: issuegen.service: Deactivated successfully.
Feb 9 09:54:38.704397 systemd[1]: Finished issuegen.service.
Feb 9 09:54:38.710567 systemd[1]: Starting systemd-user-sessions.service...
Feb 9 09:54:38.734365 systemd[1]: Finished systemd-user-sessions.service.
Feb 9 09:54:38.741549 systemd[1]: Started getty@tty1.service.
Feb 9 09:54:38.747760 systemd[1]: Started serial-getty@ttyAMA0.service.
Feb 9 09:54:38.753440 systemd[1]: Reached target getty.target.
Feb 9 09:54:38.758260 systemd[1]: Reached target multi-user.target.
Feb 9 09:54:38.764677 systemd[1]: Starting systemd-update-utmp-runlevel.service...
Feb 9 09:54:38.773010 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Feb 9 09:54:38.773162 systemd[1]: Finished systemd-update-utmp-runlevel.service.
Feb 9 09:54:38.779512 systemd[1]: Startup finished in 777ms (kernel) + 14.031s (initrd) + 22.106s (userspace) = 36.914s.
Feb 9 09:54:39.453156 locksmithd[1444]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Feb 9 09:54:39.515020 login[1462]: pam_lastlog(login:session): file /var/log/lastlog is locked/write
Feb 9 09:54:39.533157 login[1463]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Feb 9 09:54:39.586406 systemd[1]: Created slice user-500.slice.
Feb 9 09:54:39.587496 systemd[1]: Starting user-runtime-dir@500.service...
Feb 9 09:54:39.590493 systemd-logind[1361]: New session 1 of user core.
Feb 9 09:54:39.624657 systemd[1]: Finished user-runtime-dir@500.service.
Feb 9 09:54:39.626095 systemd[1]: Starting user@500.service...
Feb 9 09:54:39.657446 (systemd)[1466]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:54:39.893090 systemd[1466]: Queued start job for default target default.target.
Feb 9 09:54:39.893610 systemd[1466]: Reached target paths.target.
Feb 9 09:54:39.893630 systemd[1466]: Reached target sockets.target.
Feb 9 09:54:39.893640 systemd[1466]: Reached target timers.target.
Feb 9 09:54:39.893650 systemd[1466]: Reached target basic.target.
Feb 9 09:54:39.893696 systemd[1466]: Reached target default.target.
Feb 9 09:54:39.893719 systemd[1466]: Startup finished in 230ms.
Feb 9 09:54:39.893767 systemd[1]: Started user@500.service.
Feb 9 09:54:39.894764 systemd[1]: Started session-1.scope.
Feb 9 09:54:40.515324 login[1462]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Feb 9 09:54:40.519123 systemd-logind[1361]: New session 2 of user core.
Feb 9 09:54:40.519514 systemd[1]: Started session-2.scope.
Feb 9 09:54:45.404715 waagent[1460]: 2024-02-09T09:54:45.404613Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2
Feb 9 09:54:45.426904 waagent[1460]: 2024-02-09T09:54:45.426813Z INFO Daemon Daemon OS: flatcar 3510.3.2
Feb 9 09:54:45.432626 waagent[1460]: 2024-02-09T09:54:45.432548Z INFO Daemon Daemon Python: 3.9.16
Feb 9 09:54:45.438143 waagent[1460]: 2024-02-09T09:54:45.438034Z INFO Daemon Daemon Run daemon
Feb 9 09:54:45.443312 waagent[1460]: 2024-02-09T09:54:45.443246Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.2'
Feb 9 09:54:45.462687 waagent[1460]: 2024-02-09T09:54:45.462565Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1.
Feb 9 09:54:45.480222 waagent[1460]: 2024-02-09T09:54:45.480093Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Feb 9 09:54:45.492218 waagent[1460]: 2024-02-09T09:54:45.492131Z INFO Daemon Daemon cloud-init is enabled: False
Feb 9 09:54:45.498999 waagent[1460]: 2024-02-09T09:54:45.498915Z INFO Daemon Daemon Using waagent for provisioning
Feb 9 09:54:45.505791 waagent[1460]: 2024-02-09T09:54:45.505726Z INFO Daemon Daemon Activate resource disk
Feb 9 09:54:45.511518 waagent[1460]: 2024-02-09T09:54:45.511457Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
Feb 9 09:54:45.527462 waagent[1460]: 2024-02-09T09:54:45.527381Z INFO Daemon Daemon Found device: None
Feb 9 09:54:45.533141 waagent[1460]: 2024-02-09T09:54:45.533066Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology
Feb 9 09:54:45.543522 waagent[1460]: 2024-02-09T09:54:45.543447Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0
Feb 9 09:54:45.557687 waagent[1460]: 2024-02-09T09:54:45.557622Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Feb 9 09:54:45.564750 waagent[1460]: 2024-02-09T09:54:45.564690Z INFO Daemon Daemon Running default provisioning handler
Feb 9 09:54:45.578866 waagent[1460]: 2024-02-09T09:54:45.578741Z INFO Daemon Daemon Unable to get cloud-init enabled status from systemctl: Command '['systemctl', 'is-enabled', 'cloud-init-local.service']' returned non-zero exit status 1.
Feb 9 09:54:45.596347 waagent[1460]: 2024-02-09T09:54:45.596227Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Feb 9 09:54:45.608114 waagent[1460]: 2024-02-09T09:54:45.608042Z INFO Daemon Daemon cloud-init is enabled: False
Feb 9 09:54:45.614275 waagent[1460]: 2024-02-09T09:54:45.614213Z INFO Daemon Daemon Copying ovf-env.xml
Feb 9 09:54:45.718111 waagent[1460]: 2024-02-09T09:54:45.717926Z INFO Daemon Daemon Successfully mounted dvd
Feb 9 09:54:45.849707 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
Feb 9 09:54:45.894355 waagent[1460]: 2024-02-09T09:54:45.894177Z INFO Daemon Daemon Detect protocol endpoint
Feb 9 09:54:45.900243 waagent[1460]: 2024-02-09T09:54:45.900165Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Feb 9 09:54:45.906985 waagent[1460]: 2024-02-09T09:54:45.906921Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
Feb 9 09:54:45.914544 waagent[1460]: 2024-02-09T09:54:45.914476Z INFO Daemon Daemon Test for route to 168.63.129.16
Feb 9 09:54:45.921074 waagent[1460]: 2024-02-09T09:54:45.921013Z INFO Daemon Daemon Route to 168.63.129.16 exists
Feb 9 09:54:45.927379 waagent[1460]: 2024-02-09T09:54:45.927324Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
Feb 9 09:54:46.034279 waagent[1460]: 2024-02-09T09:54:46.034217Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
Feb 9 09:54:46.042519 waagent[1460]: 2024-02-09T09:54:46.042474Z INFO Daemon Daemon Wire protocol version:2012-11-30
Feb 9 09:54:46.048833 waagent[1460]: 2024-02-09T09:54:46.048775Z INFO Daemon Daemon Server preferred version:2015-04-05
Feb 9 09:54:46.824696 waagent[1460]: 2024-02-09T09:54:46.824544Z INFO Daemon Daemon Initializing goal state during protocol detection
Feb 9 09:54:46.841470 waagent[1460]: 2024-02-09T09:54:46.841394Z INFO Daemon Daemon Forcing an update of the goal state..
Feb 9 09:54:46.848172 waagent[1460]: 2024-02-09T09:54:46.848110Z INFO Daemon Daemon Fetching goal state [incarnation 1]
Feb 9 09:54:46.937775 waagent[1460]: 2024-02-09T09:54:46.937633Z INFO Daemon Daemon Found private key matching thumbprint 575C47585D1459C00D5A0F07B441A63D325669A3
Feb 9 09:54:46.947631 waagent[1460]: 2024-02-09T09:54:46.947552Z INFO Daemon Daemon Certificate with thumbprint B8910F831702841F93B7EF734F4F2193722C3D6E has no matching private key.
Feb 9 09:54:46.958678 waagent[1460]: 2024-02-09T09:54:46.958607Z INFO Daemon Daemon Fetch goal state completed
Feb 9 09:54:47.040962 waagent[1460]: 2024-02-09T09:54:47.040892Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: f51d3481-311f-4226-aa92-d51744f37420 New eTag: 6594433563219663130]
Feb 9 09:54:47.053379 waagent[1460]: 2024-02-09T09:54:47.053297Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob
Feb 9 09:54:47.071223 waagent[1460]: 2024-02-09T09:54:47.071153Z INFO Daemon Daemon Starting provisioning
Feb 9 09:54:47.077250 waagent[1460]: 2024-02-09T09:54:47.077141Z INFO Daemon Daemon Handle ovf-env.xml.
Feb 9 09:54:47.082813 waagent[1460]: 2024-02-09T09:54:47.082749Z INFO Daemon Daemon Set hostname [ci-3510.3.2-a-9a5ebba439]
Feb 9 09:54:47.137575 waagent[1460]: 2024-02-09T09:54:47.137425Z INFO Daemon Daemon Publish hostname [ci-3510.3.2-a-9a5ebba439]
Feb 9 09:54:47.144882 waagent[1460]: 2024-02-09T09:54:47.144802Z INFO Daemon Daemon Examine /proc/net/route for primary interface
Feb 9 09:54:47.152354 waagent[1460]: 2024-02-09T09:54:47.152279Z INFO Daemon Daemon Primary interface is [eth0]
Feb 9 09:54:47.169931 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully.
Feb 9 09:54:47.170089 systemd[1]: Stopped systemd-networkd-wait-online.service.
Feb 9 09:54:47.170145 systemd[1]: Stopping systemd-networkd-wait-online.service...
Feb 9 09:54:47.170394 systemd[1]: Stopping systemd-networkd.service...
Feb 9 09:54:47.173564 systemd-networkd[1244]: eth0: DHCPv6 lease lost
Feb 9 09:54:47.175584 systemd[1]: systemd-networkd.service: Deactivated successfully.
Feb 9 09:54:47.175751 systemd[1]: Stopped systemd-networkd.service.
Feb 9 09:54:47.177892 systemd[1]: Starting systemd-networkd.service...
Feb 9 09:54:47.204709 systemd-networkd[1510]: enP53912s1: Link UP
Feb 9 09:54:47.204720 systemd-networkd[1510]: enP53912s1: Gained carrier
Feb 9 09:54:47.205830 systemd-networkd[1510]: eth0: Link UP
Feb 9 09:54:47.205839 systemd-networkd[1510]: eth0: Gained carrier
Feb 9 09:54:47.206147 systemd-networkd[1510]: lo: Link UP
Feb 9 09:54:47.206157 systemd-networkd[1510]: lo: Gained carrier
Feb 9 09:54:47.206376 systemd-networkd[1510]: eth0: Gained IPv6LL
Feb 9 09:54:47.207263 systemd-networkd[1510]: Enumeration completed
Feb 9 09:54:47.207379 systemd[1]: Started systemd-networkd.service.
Feb 9 09:54:47.209141 systemd[1]: Starting systemd-networkd-wait-online.service...
Feb 9 09:54:47.216775 waagent[1460]: 2024-02-09T09:54:47.209089Z INFO Daemon Daemon Create user account if not exists
Feb 9 09:54:47.217878 systemd-networkd[1510]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Feb 9 09:54:47.218703 waagent[1460]: 2024-02-09T09:54:47.218619Z INFO Daemon Daemon User core already exists, skip useradd
Feb 9 09:54:47.225567 waagent[1460]: 2024-02-09T09:54:47.225471Z INFO Daemon Daemon Configure sudoer
Feb 9 09:54:47.231399 waagent[1460]: 2024-02-09T09:54:47.231325Z INFO Daemon Daemon Configure sshd
Feb 9 09:54:47.237839 waagent[1460]: 2024-02-09T09:54:47.237748Z INFO Daemon Daemon Deploy ssh public key.
Feb 9 09:54:47.248650 systemd-networkd[1510]: eth0: DHCPv4 address 10.200.20.10/24, gateway 10.200.20.1 acquired from 168.63.129.16
Feb 9 09:54:47.250787 systemd[1]: Finished systemd-networkd-wait-online.service.
Feb 9 09:54:48.509284 waagent[1460]: 2024-02-09T09:54:48.509218Z INFO Daemon Daemon Provisioning complete
Feb 9 09:54:48.530651 waagent[1460]: 2024-02-09T09:54:48.530583Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
Feb 9 09:54:48.538628 waagent[1460]: 2024-02-09T09:54:48.538557Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
Feb 9 09:54:48.551015 waagent[1460]: 2024-02-09T09:54:48.550944Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent
Feb 9 09:54:48.851087 waagent[1519]: 2024-02-09T09:54:48.850992Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent
Feb 9 09:54:48.851886 waagent[1519]: 2024-02-09T09:54:48.851820Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:48.852022 waagent[1519]: 2024-02-09T09:54:48.851975Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:48.870641 waagent[1519]: 2024-02-09T09:54:48.870549Z INFO ExtHandler ExtHandler Forcing an update of the goal state..
Feb 9 09:54:48.870833 waagent[1519]: 2024-02-09T09:54:48.870780Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1]
Feb 9 09:54:48.939980 waagent[1519]: 2024-02-09T09:54:48.939830Z INFO ExtHandler ExtHandler Found private key matching thumbprint 575C47585D1459C00D5A0F07B441A63D325669A3
Feb 9 09:54:48.940197 waagent[1519]: 2024-02-09T09:54:48.940143Z INFO ExtHandler ExtHandler Certificate with thumbprint B8910F831702841F93B7EF734F4F2193722C3D6E has no matching private key.
Feb 9 09:54:48.940419 waagent[1519]: 2024-02-09T09:54:48.940372Z INFO ExtHandler ExtHandler Fetch goal state completed
Feb 9 09:54:48.956516 waagent[1519]: 2024-02-09T09:54:48.956458Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: c9f48c0c-c4f1-44dc-973a-2037e92cae47 New eTag: 6594433563219663130]
Feb 9 09:54:48.957142 waagent[1519]: 2024-02-09T09:54:48.957081Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob
Feb 9 09:54:49.052731 waagent[1519]: 2024-02-09T09:54:49.052581Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1;
Feb 9 09:54:49.062843 waagent[1519]: 2024-02-09T09:54:49.062758Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1519
Feb 9 09:54:49.066551 waagent[1519]: 2024-02-09T09:54:49.066475Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk']
Feb 9 09:54:49.067876 waagent[1519]: 2024-02-09T09:54:49.067819Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Feb 9 09:54:49.167338 waagent[1519]: 2024-02-09T09:54:49.167212Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Feb 9 09:54:49.167755 waagent[1519]: 2024-02-09T09:54:49.167690Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Feb 9 09:54:49.175384 waagent[1519]: 2024-02-09T09:54:49.175312Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Feb 9 09:54:49.175954 waagent[1519]: 2024-02-09T09:54:49.175898Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
Feb 9 09:54:49.177114 waagent[1519]: 2024-02-09T09:54:49.177049Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [False], cgroups enabled [False], python supported: [True]
Feb 9 09:54:49.178447 waagent[1519]: 2024-02-09T09:54:49.178375Z INFO ExtHandler ExtHandler Starting env monitor service.
Feb 9 09:54:49.179114 waagent[1519]: 2024-02-09T09:54:49.179054Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:49.179376 waagent[1519]: 2024-02-09T09:54:49.179324Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:49.180051 waagent[1519]: 2024-02-09T09:54:49.179996Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Feb 9 09:54:49.180424 waagent[1519]: 2024-02-09T09:54:49.180371Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Feb 9 09:54:49.180424 waagent[1519]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT
Feb 9 09:54:49.180424 waagent[1519]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0
Feb 9 09:54:49.180424 waagent[1519]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0
Feb 9 09:54:49.180424 waagent[1519]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:49.180424 waagent[1519]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:49.180424 waagent[1519]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:49.182970 waagent[1519]: 2024-02-09T09:54:49.182784Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Feb 9 09:54:49.183976 waagent[1519]: 2024-02-09T09:54:49.183913Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:49.184241 waagent[1519]: 2024-02-09T09:54:49.184190Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:49.184961 waagent[1519]: 2024-02-09T09:54:49.184901Z INFO EnvHandler ExtHandler Configure routes
Feb 9 09:54:49.185192 waagent[1519]: 2024-02-09T09:54:49.185145Z INFO EnvHandler ExtHandler Gateway:None
Feb 9 09:54:49.185391 waagent[1519]: 2024-02-09T09:54:49.185347Z INFO EnvHandler ExtHandler Routes:None
Feb 9 09:54:49.186352 waagent[1519]: 2024-02-09T09:54:49.186296Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Feb 9 09:54:49.186437 waagent[1519]: 2024-02-09T09:54:49.186375Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Feb 9 09:54:49.187357 waagent[1519]: 2024-02-09T09:54:49.187273Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Feb 9 09:54:49.187449 waagent[1519]: 2024-02-09T09:54:49.187381Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Feb 9 09:54:49.187806 waagent[1519]: 2024-02-09T09:54:49.187744Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Feb 9 09:54:49.199055 waagent[1519]: 2024-02-09T09:54:49.198974Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod)
Feb 9 09:54:49.200752 waagent[1519]: 2024-02-09T09:54:49.200701Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
Feb 9 09:54:49.201790 waagent[1519]: 2024-02-09T09:54:49.201734Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders'
Feb 9 09:54:49.227300 waagent[1519]: 2024-02-09T09:54:49.227219Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1510'
Feb 9 09:54:49.263747 waagent[1519]: 2024-02-09T09:54:49.263665Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel.
Feb 9 09:54:49.350137 waagent[1519]: 2024-02-09T09:54:49.350009Z INFO MonitorHandler ExtHandler Network interfaces:
Feb 9 09:54:49.350137 waagent[1519]: Executing ['ip', '-a', '-o', 'link']:
Feb 9 09:54:49.350137 waagent[1519]: 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
Feb 9 09:54:49.350137 waagent[1519]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f6:13:90 brd ff:ff:ff:ff:ff:ff
Feb 9 09:54:49.350137 waagent[1519]: 3: enP53912s1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f6:13:90 brd ff:ff:ff:ff:ff:ff\ altname enP53912p0s2
Feb 9 09:54:49.350137 waagent[1519]: Executing ['ip', '-4', '-a', '-o', 'address']:
Feb 9 09:54:49.350137 waagent[1519]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever
Feb 9 09:54:49.350137 waagent[1519]: 2: eth0 inet 10.200.20.10/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever
Feb 9 09:54:49.350137 waagent[1519]: Executing ['ip', '-6', '-a', '-o', 'address']:
Feb 9 09:54:49.350137 waagent[1519]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever
Feb 9 09:54:49.350137 waagent[1519]: 2: eth0 inet6 fe80::20d:3aff:fef6:1390/64 scope link \ valid_lft forever preferred_lft forever
Feb 9 09:54:49.464274 waagent[1519]: 2024-02-09T09:54:49.464150Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.9.1.1 -- exiting
Feb 9 09:54:49.554488 waagent[1460]: 2024-02-09T09:54:49.554376Z INFO Daemon Daemon Agent WALinuxAgent-2.6.0.2 launched with command '/usr/share/oem/python/bin/python -u /usr/share/oem/bin/waagent -run-exthandlers' is successfully running
Feb 9 09:54:49.558422 waagent[1460]: 2024-02-09T09:54:49.558369Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.9.1.1 to be the latest agent
Feb 9 09:54:50.699935 waagent[1548]: 2024-02-09T09:54:50.699828Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1)
Feb 9 09:54:50.700670 waagent[1548]: 2024-02-09T09:54:50.700605Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.2
Feb 9 09:54:50.700811 waagent[1548]: 2024-02-09T09:54:50.700763Z INFO ExtHandler ExtHandler Python: 3.9.16
Feb 9 09:54:50.708944 waagent[1548]: 2024-02-09T09:54:50.708818Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.2; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1;
Feb 9 09:54:50.709362 waagent[1548]: 2024-02-09T09:54:50.709304Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:50.709511 waagent[1548]: 2024-02-09T09:54:50.709462Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:50.722056 waagent[1548]: 2024-02-09T09:54:50.721984Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
Feb 9 09:54:50.732765 waagent[1548]: 2024-02-09T09:54:50.732707Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.143
Feb 9 09:54:50.733805 waagent[1548]: 2024-02-09T09:54:50.733746Z INFO ExtHandler
Feb 9 09:54:50.733954 waagent[1548]: 2024-02-09T09:54:50.733904Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: ce9e94da-e7b9-4a1a-81f9-7e4a370e45c5 eTag: 6594433563219663130 source: Fabric]
Feb 9 09:54:50.734707 waagent[1548]: 2024-02-09T09:54:50.734649Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
Feb 9 09:54:50.735922 waagent[1548]: 2024-02-09T09:54:50.735860Z INFO ExtHandler
Feb 9 09:54:50.736056 waagent[1548]: 2024-02-09T09:54:50.736008Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
Feb 9 09:54:50.749971 waagent[1548]: 2024-02-09T09:54:50.749916Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
Feb 9 09:54:50.750492 waagent[1548]: 2024-02-09T09:54:50.750441Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
Feb 9 09:54:50.774401 waagent[1548]: 2024-02-09T09:54:50.774341Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel.
Feb 9 09:54:50.851860 waagent[1548]: 2024-02-09T09:54:50.851718Z INFO ExtHandler Downloaded certificate {'thumbprint': 'B8910F831702841F93B7EF734F4F2193722C3D6E', 'hasPrivateKey': False}
Feb 9 09:54:50.852941 waagent[1548]: 2024-02-09T09:54:50.852876Z INFO ExtHandler Downloaded certificate {'thumbprint': '575C47585D1459C00D5A0F07B441A63D325669A3', 'hasPrivateKey': True}
Feb 9 09:54:50.854031 waagent[1548]: 2024-02-09T09:54:50.853970Z INFO ExtHandler Fetch goal state completed
Feb 9 09:54:50.876836 waagent[1548]: 2024-02-09T09:54:50.876757Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1548
Feb 9 09:54:50.880371 waagent[1548]: 2024-02-09T09:54:50.880304Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.2', '', 'Flatcar Container Linux by Kinvolk']
Feb 9 09:54:50.881888 waagent[1548]: 2024-02-09T09:54:50.881830Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Feb 9 09:54:50.887045 waagent[1548]: 2024-02-09T09:54:50.886981Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Feb 9 09:54:50.887457 waagent[1548]: 2024-02-09T09:54:50.887395Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Feb 9 09:54:50.895192 waagent[1548]: 2024-02-09T09:54:50.895117Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Feb 9 09:54:50.895798 waagent[1548]: 2024-02-09T09:54:50.895737Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
Feb 9 09:54:50.902449 waagent[1548]: 2024-02-09T09:54:50.902315Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up.
Feb 9 09:54:50.906255 waagent[1548]: 2024-02-09T09:54:50.906195Z INFO ExtHandler ExtHandler Checking if log collection is allowed at this time [False]. All three conditions must be met: configuration enabled [True], cgroups enabled [False], python supported: [True]
Feb 9 09:54:50.907843 waagent[1548]: 2024-02-09T09:54:50.907766Z INFO ExtHandler ExtHandler Starting env monitor service.
Feb 9 09:54:50.908666 waagent[1548]: 2024-02-09T09:54:50.908601Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:50.908941 waagent[1548]: 2024-02-09T09:54:50.908889Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:50.909642 waagent[1548]: 2024-02-09T09:54:50.909580Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Feb 9 09:54:50.910038 waagent[1548]: 2024-02-09T09:54:50.909985Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Feb 9 09:54:50.910038 waagent[1548]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT
Feb 9 09:54:50.910038 waagent[1548]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0
Feb 9 09:54:50.910038 waagent[1548]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0
Feb 9 09:54:50.910038 waagent[1548]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:50.910038 waagent[1548]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:50.910038 waagent[1548]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Feb 9 09:54:50.912883 waagent[1548]: 2024-02-09T09:54:50.912763Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Feb 9 09:54:50.915031 waagent[1548]: 2024-02-09T09:54:50.913470Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Feb 9 09:54:50.915660 waagent[1548]: 2024-02-09T09:54:50.915555Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Feb 9 09:54:50.916148 waagent[1548]: 2024-02-09T09:54:50.916071Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Feb 9 09:54:50.916280 waagent[1548]: 2024-02-09T09:54:50.916225Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Feb 9 09:54:50.917471 waagent[1548]: 2024-02-09T09:54:50.917396Z INFO EnvHandler ExtHandler Configure routes
Feb 9 09:54:50.919643 waagent[1548]: 2024-02-09T09:54:50.919412Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Feb 9 09:54:50.919842 waagent[1548]: 2024-02-09T09:54:50.919769Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Feb 9 09:54:50.920444 waagent[1548]: 2024-02-09T09:54:50.920379Z INFO EnvHandler ExtHandler Gateway:None
Feb 9 09:54:50.922031 waagent[1548]: 2024-02-09T09:54:50.921958Z INFO MonitorHandler ExtHandler Network interfaces:
Feb 9 09:54:50.922031 waagent[1548]: Executing ['ip', '-a', '-o', 'link']:
Feb 9 09:54:50.922031 waagent[1548]: 1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN mode DEFAULT group default qlen 1000\ link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
Feb 9 09:54:50.922031 waagent[1548]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f6:13:90 brd ff:ff:ff:ff:ff:ff
Feb 9 09:54:50.922031 waagent[1548]: 3: enP53912s1: <BROADCAST,MULTICAST,SLAVE,UP,LOWER_UP> mtu 1500 qdisc mq master eth0 state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:f6:13:90 brd ff:ff:ff:ff:ff:ff\ altname enP53912p0s2
Feb 9 09:54:50.922031 waagent[1548]: Executing ['ip', '-4', '-a', '-o', 'address']:
Feb 9 09:54:50.922031 waagent[1548]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever
Feb 9 09:54:50.922031 waagent[1548]: 2: eth0 inet 10.200.20.10/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever
Feb 9 09:54:50.922031 waagent[1548]: Executing ['ip', '-6', '-a', '-o', 'address']:
Feb 9 09:54:50.922031 waagent[1548]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever
Feb 9 09:54:50.922031 waagent[1548]: 2: eth0 inet6 fe80::20d:3aff:fef6:1390/64 scope link \ valid_lft forever preferred_lft forever
Feb 9 09:54:50.922554 waagent[1548]: 2024-02-09T09:54:50.922468Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Feb 9 09:54:50.923032 waagent[1548]: 2024-02-09T09:54:50.922964Z INFO EnvHandler ExtHandler Routes:None
Feb 9 09:54:50.939682 waagent[1548]: 2024-02-09T09:54:50.939590Z INFO ExtHandler ExtHandler No requested version specified, checking for all versions for agent update (family: Prod)
Feb 9 09:54:50.941503 waagent[1548]: 2024-02-09T09:54:50.941393Z INFO ExtHandler ExtHandler Downloading manifest
Feb 9 09:54:50.958455 waagent[1548]: 2024-02-09T09:54:50.958358Z INFO ExtHandler ExtHandler
Feb 9 09:54:50.958743 waagent[1548]: 2024-02-09T09:54:50.958690Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: 28977885-c282-4a9d-990b-8a02b84dcc9f correlation 28859cb6-0952-4a25-a3c3-59f4e2367f38 created: 2024-02-09T09:53:11.276543Z]
Feb 9 09:54:50.959710 waagent[1548]: 2024-02-09T09:54:50.959653Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
Feb 9 09:54:50.961633 waagent[1548]: 2024-02-09T09:54:50.961580Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms]
Feb 9 09:54:50.987681 waagent[1548]: 2024-02-09T09:54:50.987607Z INFO ExtHandler ExtHandler Looking for existing remote access users.
Feb 9 09:54:51.010343 waagent[1548]: 2024-02-09T09:54:51.010267Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 56A59AC3-5384-46C0-AD60-88C5FE5516AD;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1]
Feb 9 09:54:51.466105 waagent[1548]: 2024-02-09T09:54:51.465959Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules:
Feb 9 09:54:51.466105 waagent[1548]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.466105 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.466105 waagent[1548]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.466105 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.466105 waagent[1548]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.466105 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.466105 waagent[1548]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53
Feb 9 09:54:51.466105 waagent[1548]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0
Feb 9 09:54:51.466105 waagent[1548]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW
Feb 9 09:54:51.473751 waagent[1548]: 2024-02-09T09:54:51.473625Z INFO EnvHandler ExtHandler Current Firewall rules:
Feb 9 09:54:51.473751 waagent[1548]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.473751 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.473751 waagent[1548]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.473751 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.473751 waagent[1548]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Feb 9 09:54:51.473751 waagent[1548]: pkts bytes target prot opt in out source destination
Feb 9 09:54:51.473751 waagent[1548]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53
Feb 9 09:54:51.473751 waagent[1548]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0
Feb 9 09:54:51.473751 waagent[1548]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW
Feb 9 09:54:51.474284 waagent[1548]: 2024-02-09T09:54:51.474227Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300
Feb 9 09:55:16.813201 kernel: hv_balloon: Max. dynamic memory size: 4096 MB
Feb 9 09:55:17.988655 systemd[1]: Created slice system-sshd.slice.
Feb 9 09:55:17.989758 systemd[1]: Started sshd@0-10.200.20.10:22-10.200.12.6:35170.service.
Feb 9 09:55:18.626035 sshd[1599]: Accepted publickey for core from 10.200.12.6 port 35170 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:18.648300 sshd[1599]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:18.651963 systemd-logind[1361]: New session 3 of user core.
Feb 9 09:55:18.652748 systemd[1]: Started session-3.scope.
Feb 9 09:55:18.989870 systemd[1]: Started sshd@1-10.200.20.10:22-10.200.12.6:35182.service.
Feb 9 09:55:19.404665 sshd[1604]: Accepted publickey for core from 10.200.12.6 port 35182 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:19.405913 sshd[1604]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:19.409613 systemd-logind[1361]: New session 4 of user core.
Feb 9 09:55:19.410022 systemd[1]: Started session-4.scope.
Feb 9 09:55:19.705117 sshd[1604]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:19.707459 systemd[1]: sshd@1-10.200.20.10:22-10.200.12.6:35182.service: Deactivated successfully.
Feb 9 09:55:19.708163 systemd[1]: session-4.scope: Deactivated successfully.
Feb 9 09:55:19.708701 systemd-logind[1361]: Session 4 logged out. Waiting for processes to exit.
Feb 9 09:55:19.709626 systemd-logind[1361]: Removed session 4.
Feb 9 09:55:19.773408 systemd[1]: Started sshd@2-10.200.20.10:22-10.200.12.6:35196.service.
Feb 9 09:55:20.187972 sshd[1610]: Accepted publickey for core from 10.200.12.6 port 35196 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:20.190103 sshd[1610]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:20.193575 systemd-logind[1361]: New session 5 of user core.
Feb 9 09:55:20.194207 systemd[1]: Started session-5.scope.
Feb 9 09:55:20.484454 sshd[1610]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:20.487449 systemd-logind[1361]: Session 5 logged out. Waiting for processes to exit.
Feb 9 09:55:20.487952 systemd[1]: sshd@2-10.200.20.10:22-10.200.12.6:35196.service: Deactivated successfully.
Feb 9 09:55:20.488607 systemd[1]: session-5.scope: Deactivated successfully.
Feb 9 09:55:20.489243 systemd-logind[1361]: Removed session 5.
Feb 9 09:55:20.553132 systemd[1]: Started sshd@3-10.200.20.10:22-10.200.12.6:35198.service.
Feb 9 09:55:20.967454 sshd[1616]: Accepted publickey for core from 10.200.12.6 port 35198 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:20.968715 sshd[1616]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:20.972379 systemd-logind[1361]: New session 6 of user core.
Feb 9 09:55:20.972828 systemd[1]: Started session-6.scope.
Feb 9 09:55:21.267000 sshd[1616]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:21.269246 systemd[1]: sshd@3-10.200.20.10:22-10.200.12.6:35198.service: Deactivated successfully.
Feb 9 09:55:21.269901 systemd[1]: session-6.scope: Deactivated successfully.
Feb 9 09:55:21.270433 systemd-logind[1361]: Session 6 logged out. Waiting for processes to exit.
Feb 9 09:55:21.271296 systemd-logind[1361]: Removed session 6.
Feb 9 09:55:21.337158 systemd[1]: Started sshd@4-10.200.20.10:22-10.200.12.6:35206.service.
Feb 9 09:55:21.751318 sshd[1622]: Accepted publickey for core from 10.200.12.6 port 35206 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:21.752512 sshd[1622]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:21.756155 systemd-logind[1361]: New session 7 of user core.
Feb 9 09:55:21.756586 systemd[1]: Started session-7.scope.
Feb 9 09:55:22.241282 sudo[1625]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
Feb 9 09:55:22.241474 sudo[1625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:22.285665 dbus-daemon[1349]: avc: received setenforce notice (enforcing=1)
Feb 9 09:55:22.286558 sudo[1625]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:22.367231 sshd[1622]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:22.370340 systemd[1]: sshd@4-10.200.20.10:22-10.200.12.6:35206.service: Deactivated successfully.
Feb 9 09:55:22.370718 systemd-logind[1361]: Session 7 logged out. Waiting for processes to exit.
Feb 9 09:55:22.371092 systemd[1]: session-7.scope: Deactivated successfully.
Feb 9 09:55:22.371999 systemd-logind[1361]: Removed session 7.
Feb 9 09:55:22.439914 systemd[1]: Started sshd@5-10.200.20.10:22-10.200.12.6:35212.service.
Feb 9 09:55:22.867031 sshd[1629]: Accepted publickey for core from 10.200.12.6 port 35212 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:22.868313 sshd[1629]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:22.872009 systemd-logind[1361]: New session 8 of user core.
Feb 9 09:55:22.872407 systemd[1]: Started session-8.scope.
Feb 9 09:55:23.108381 sudo[1633]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/rm -rf /etc/audit/rules.d/80-selinux.rules /etc/audit/rules.d/99-default.rules
Feb 9 09:55:23.108889 sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:23.111401 sudo[1633]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:23.115604 sudo[1632]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules
Feb 9 09:55:23.115797 sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:23.123947 systemd[1]: Stopping audit-rules.service...
Feb 9 09:55:23.125115 auditctl[1636]: No rules
Feb 9 09:55:23.124000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
Feb 9 09:55:23.129719 kernel: kauditd_printk_skb: 96 callbacks suppressed
Feb 9 09:55:23.129806 kernel: audit: type=1305 audit(1707472523.124:177): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
Feb 9 09:55:23.131798 systemd[1]: audit-rules.service: Deactivated successfully.
Feb 9 09:55:23.124000 audit[1636]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffce1ab640 a2=420 a3=0 items=0 ppid=1 pid=1636 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:55:23.131974 systemd[1]: Stopped audit-rules.service.
Feb 9 09:55:23.143031 systemd[1]: Starting audit-rules.service...
Feb 9 09:55:23.168756 kernel: audit: type=1300 audit(1707472523.124:177): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffce1ab640 a2=420 a3=0 items=0 ppid=1 pid=1636 auid=4294967295 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=4294967295 comm="auditctl" exe="/usr/sbin/auditctl" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:55:23.124000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44
Feb 9 09:55:23.178956 kernel: audit: type=1327 audit(1707472523.124:177): proctitle=2F7362696E2F617564697463746C002D44
Feb 9 09:55:23.131000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.197974 kernel: audit: type=1131 audit(1707472523.131:178): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.198428 augenrules[1653]: No rules
Feb 9 09:55:23.199111 systemd[1]: Finished audit-rules.service.
Feb 9 09:55:23.198000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.200231 sudo[1632]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:23.199000 audit[1632]: USER_END pid=1632 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.241965 kernel: audit: type=1130 audit(1707472523.198:179): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=audit-rules comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.242042 kernel: audit: type=1106 audit(1707472523.199:180): pid=1632 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_limits,pam_env,pam_unix,pam_permit,pam_systemd acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.199000 audit[1632]: CRED_DISP pid=1632 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.262635 kernel: audit: type=1104 audit(1707472523.199:181): pid=1632 uid=500 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="root" exe="/usr/bin/sudo" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.268718 sshd[1629]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:23.269000 audit[1629]: USER_END pid=1629 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.297792 systemd[1]: sshd@5-10.200.20.10:22-10.200.12.6:35212.service: Deactivated successfully.
Feb 9 09:55:23.269000 audit[1629]: CRED_DISP pid=1629 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.319487 kernel: audit: type=1106 audit(1707472523.269:182): pid=1629 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.319595 kernel: audit: type=1104 audit(1707472523.269:183): pid=1629 uid=0 auid=500 ses=8 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.298481 systemd[1]: session-8.scope: Deactivated successfully.
Feb 9 09:55:23.297000 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.10:22-10.200.12.6:35212 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.319927 systemd-logind[1361]: Session 8 logged out. Waiting for processes to exit.
Feb 9 09:55:23.341019 kernel: audit: type=1131 audit(1707472523.297:184): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.10:22-10.200.12.6:35212 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.343944 systemd-logind[1361]: Removed session 8.
Feb 9 09:55:23.344842 systemd[1]: Started sshd@6-10.200.20.10:22-10.200.12.6:35222.service.
Feb 9 09:55:23.344000 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.10:22-10.200.12.6:35222 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:23.799000 audit[1659]: USER_ACCT pid=1659 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.800102 sshd[1659]: Accepted publickey for core from 10.200.12.6 port 35222 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:23.800000 audit[1659]: CRED_ACQ pid=1659 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.800000 audit[1659]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=fffffde5b8b0 a2=3 a3=1 items=0 ppid=1 pid=1659 auid=500 uid=0 gid=0 euid=0 suid=0 fsuid=0 egid=0 sgid=0 fsgid=0 tty=(none) ses=9 comm="sshd" exe="/usr/sbin/sshd" subj=system_u:system_r:kernel_t:s0 key=(null)
Feb 9 09:55:23.800000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
Feb 9 09:55:23.801640 sshd[1659]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:23.805811 systemd[1]: Started session-9.scope.
Feb 9 09:55:23.806205 systemd-logind[1361]: New session 9 of user core.
Feb 9 09:55:23.809000 audit[1659]: USER_START pid=1659 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_open grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.810000 audit[1661]: CRED_ACQ pid=1661 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:23.865553 update_engine[1365]: I0209 09:55:23.865262 1365 update_attempter.cc:509] Updating boot flags...
Feb 9 09:55:24.055000 audit[1701]: USER_ACCT pid=1701 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'
Feb 9 09:55:24.055000 audit[1701]: CRED_REFR pid=1701 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'
Feb 9 09:55:24.056058 sudo[1701]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/data
Feb 9 09:55:24.056255 sudo[1701]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:24.057000 audit[1701]: USER_START pid=1701 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'
Feb 9 09:55:24.058000 audit[1701]: USER_END pid=1701 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'
Feb 9 09:55:24.058000 audit[1701]: CRED_DISP pid=1701 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'
Feb 9 09:55:24.059102 sudo[1701]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:24.129952 sshd[1659]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:24.130000 audit[1659]: USER_END pid=1659 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:session_close grantors=pam_loginuid,pam_env,pam_lastlog,pam_limits,pam_env,pam_unix,pam_permit,pam_systemd,pam_mail acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.130000 audit[1659]: CRED_DISP pid=1659 uid=0 auid=500 ses=9 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.132607 systemd[1]: sshd@6-10.200.20.10:22-10.200.12.6:35222.service: Deactivated successfully.
Feb 9 09:55:24.132000 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.10:22-10.200.12.6:35222 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:24.133243 systemd[1]: session-9.scope: Deactivated successfully.
Feb 9 09:55:24.133798 systemd-logind[1361]: Session 9 logged out. Waiting for processes to exit.
Feb 9 09:55:24.134622 systemd-logind[1361]: Removed session 9.
Feb 9 09:55:24.209000 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.10:22-10.200.12.6:35226 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:24.210331 systemd[1]: Started sshd@7-10.200.20.10:22-10.200.12.6:35226.service.
Feb 9 09:55:24.623000 audit[1705]: USER_ACCT pid=1705 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.624511 sshd[1705]: Accepted publickey for core from 10.200.12.6 port 35226 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:24.625000 audit[1705]: CRED_ACQ pid=1705 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.625000 audit[1705]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffc77749b0 a2=3 a3=1 items=0 ppid=1 pid=1705 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)
Feb 9 09:55:24.625000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
Feb 9 09:55:24.626027 sshd[1705]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:24.630018 systemd[1]: Started session-10.scope.
Feb 9 09:55:24.631237 systemd-logind[1361]: New session 10 of user core.
Feb 9 09:55:24.634000 audit[1705]: USER_START pid=1705 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.635000 audit[1707]: CRED_ACQ pid=1707 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.859000 audit[1708]: USER_ACCT pid=1708 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'
Feb 9 09:55:24.860354 sudo[1708]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/http
Feb 9 09:55:24.859000 audit[1708]: CRED_REFR pid=1708 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'
Feb 9 09:55:24.860572 sudo[1708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:24.861000 audit[1708]: USER_START pid=1708 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'
Feb 9 09:55:24.862982 sudo[1708]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:24.862000 audit[1708]: USER_END pid=1708 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'
Feb 9 09:55:24.862000 audit[1708]: CRED_DISP pid=1708 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'
Feb 9 09:55:24.927072 sshd[1705]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:24.927000 audit[1705]: USER_END pid=1705 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.928000 audit[1705]: CRED_DISP pid=1705 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:24.929763 systemd[1]: sshd@7-10.200.20.10:22-10.200.12.6:35226.service: Deactivated successfully.
Feb 9 09:55:24.929000 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.10:22-10.200.12.6:35226 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:24.930403 systemd[1]: session-10.scope: Deactivated successfully.
Feb 9 09:55:24.930921 systemd-logind[1361]: Session 10 logged out. Waiting for processes to exit.
Feb 9 09:55:24.931824 systemd-logind[1361]: Removed session 10.
Feb 9 09:55:24.995000 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.10:22-10.200.12.6:35236 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:24.995657 systemd[1]: Started sshd@8-10.200.20.10:22-10.200.12.6:35236.service.
Feb 9 09:55:25.410000 audit[1712]: USER_ACCT pid=1712 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:accounting grantors=pam_access,pam_unix,pam_faillock,pam_permit acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.410985 sshd[1712]: Accepted publickey for core from 10.200.12.6 port 35236 ssh2: RSA SHA256:hRMUPydBxGv30tVhZeI0xMvD4t7pzUsgMKn9+EnuIn0
Feb 9 09:55:25.411000 audit[1712]: CRED_ACQ pid=1712 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='op=PAM:setcred grantors=pam_env,pam_faillock,pam_unix acct="core" exe="/usr/sbin/sshd" hostname=10.200.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.411000 audit[1712]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffcef097e0 a2=3 a3=1 items=0 ppid=1 pid=1712 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)
Feb 9 09:55:25.411000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
Feb 9 09:55:25.412480 sshd[1712]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Feb 9 09:55:25.416260 systemd-logind[1361]: New session 11 of user core.
Feb 9 09:55:25.416703 systemd[1]: Started session-11.scope.
Feb 9 09:55:25.420000 audit[1712]: USER_START pid=1712 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.421000 audit[1714]: CRED_ACQ pid=1714 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.645000 audit[1715]: USER_ACCT pid=1715 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'
Feb 9 09:55:25.645000 audit[1715]: CRED_REFR pid=1715 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'
Feb 9 09:55:25.646229 sudo[1715]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/cat /var/resource/tftp
Feb 9 09:55:25.646423 sudo[1715]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Feb 9 09:55:25.647000 audit[1715]: USER_START pid=1715 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'
Feb 9 09:55:25.648000 audit[1715]: USER_END pid=1715 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'
Feb 9 09:55:25.648000 audit[1715]: CRED_DISP pid=1715 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'
Feb 9 09:55:25.648961 sudo[1715]: pam_unix(sudo:session): session closed for user root
Feb 9 09:55:25.713064 sshd[1712]: pam_unix(sshd:session): session closed for user core
Feb 9 09:55:25.713000 audit[1712]: USER_END pid=1712 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.713000 audit[1712]: CRED_DISP pid=1712 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.12.6 addr=10.200.12.6 terminal=ssh res=success'
Feb 9 09:55:25.715742 systemd-logind[1361]: Session 11 logged out. Waiting for processes to exit.
Feb 9 09:55:25.715000 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.10:22-10.200.12.6:35236 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Feb 9 09:55:25.715983 systemd[1]: sshd@8-10.200.20.10:22-10.200.12.6:35236.service: Deactivated successfully.
Feb 9 09:55:25.716628 systemd[1]: session-11.scope: Deactivated successfully.
Feb 9 09:55:25.717260 systemd-logind[1361]: Removed session 11.