Oct 2 20:33:39.718692 kernel: Booting Linux on physical CPU 0x0000000000 [0x413fd0c1]
Oct 2 20:33:39.718710 kernel: Linux version 5.15.132-flatcar (build@pony-truck.infra.kinvolk.io) (aarch64-cros-linux-gnu-gcc (Gentoo Hardened 11.3.1_p20221209 p3) 11.3.1 20221209, GNU ld (Gentoo 2.39 p5) 2.39.0) #1 SMP PREEMPT Mon Oct 2 17:55:37 -00 2023
Oct 2 20:33:39.719960 kernel: earlycon: pl11 at MMIO 0x00000000effec000 (options '')
Oct 2 20:33:39.719979 kernel: printk: bootconsole [pl11] enabled
Oct 2 20:33:39.719985 kernel: efi: EFI v2.70 by EDK II
Oct 2 20:33:39.719991 kernel: efi: ACPI 2.0=0x3fd8d018 SMBIOS=0x3fd6a000 SMBIOS 3.0=0x3fd68000 MEMATTR=0x3ef3f098 RNG=0x3fd8d998 MEMRESERVE=0x37eb7f98
Oct 2 20:33:39.719998 kernel: random: crng init done
Oct 2 20:33:39.720004 kernel: ACPI: Early table checksum verification disabled
Oct 2 20:33:39.720010 kernel: ACPI: RSDP 0x000000003FD8D018 000024 (v02 VRTUAL)
Oct 2 20:33:39.720015 kernel: ACPI: XSDT 0x000000003FD8DF18 00006C (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720021 kernel: ACPI: FACP 0x000000003FD8DC18 000114 (v06 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720027 kernel: ACPI: DSDT 0x000000003EBD6018 01DEC0 (v02 MSFTVM DSDT01 00000001 MSFT 05000000)
Oct 2 20:33:39.720033 kernel: ACPI: DBG2 0x000000003FD8DB18 000072 (v00 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720038 kernel: ACPI: GTDT 0x000000003FD8DD98 000060 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720045 kernel: ACPI: OEM0 0x000000003FD8D098 000064 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720051 kernel: ACPI: SPCR 0x000000003FD8DA98 000050 (v02 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720058 kernel: ACPI: APIC 0x000000003FD8D818 0000FC (v04 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720065 kernel: ACPI: SRAT 0x000000003FD8D198 000234 (v03 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720071 kernel: ACPI: PPTT 0x000000003FD8D418 000120 (v01 VRTUAL MICROSFT 00000000 MSFT 00000000)
Oct 2 20:33:39.720077 kernel: ACPI: BGRT 0x000000003FD8DE98 000038 (v01 VRTUAL MICROSFT 00000001 MSFT 00000001)
Oct 2 20:33:39.720083 kernel: ACPI: SPCR: console: pl011,mmio32,0xeffec000,115200
Oct 2 20:33:39.720089 kernel: NUMA: Failed to initialise from firmware
Oct 2 20:33:39.720095 kernel: NUMA: Faking a node at [mem 0x0000000000000000-0x00000001bfffffff]
Oct 2 20:33:39.720100 kernel: NUMA: NODE_DATA [mem 0x1bf7f2900-0x1bf7f7fff]
Oct 2 20:33:39.720106 kernel: Zone ranges:
Oct 2 20:33:39.720112 kernel: DMA [mem 0x0000000000000000-0x00000000ffffffff]
Oct 2 20:33:39.720118 kernel: DMA32 empty
Oct 2 20:33:39.720124 kernel: Normal [mem 0x0000000100000000-0x00000001bfffffff]
Oct 2 20:33:39.720130 kernel: Movable zone start for each node
Oct 2 20:33:39.720136 kernel: Early memory node ranges
Oct 2 20:33:39.720141 kernel: node 0: [mem 0x0000000000000000-0x00000000007fffff]
Oct 2 20:33:39.720147 kernel: node 0: [mem 0x0000000000824000-0x000000003ec84fff]
Oct 2 20:33:39.720153 kernel: node 0: [mem 0x000000003ec85000-0x000000003ecadfff]
Oct 2 20:33:39.720158 kernel: node 0: [mem 0x000000003ecae000-0x000000003fd2dfff]
Oct 2 20:33:39.720164 kernel: node 0: [mem 0x000000003fd2e000-0x000000003fd81fff]
Oct 2 20:33:39.720170 kernel: node 0: [mem 0x000000003fd82000-0x000000003fd8dfff]
Oct 2 20:33:39.720175 kernel: node 0: [mem 0x000000003fd8e000-0x000000003fd91fff]
Oct 2 20:33:39.720181 kernel: node 0: [mem 0x000000003fd92000-0x000000003fffffff]
Oct 2 20:33:39.720187 kernel: node 0: [mem 0x0000000100000000-0x00000001bfffffff]
Oct 2 20:33:39.720194 kernel: Initmem setup node 0 [mem 0x0000000000000000-0x00000001bfffffff]
Oct 2 20:33:39.720202 kernel: On node 0, zone DMA: 36 pages in unavailable ranges
Oct 2 20:33:39.720208 kernel: psci: probing for conduit method from ACPI.
Oct 2 20:33:39.720214 kernel: psci: PSCIv1.1 detected in firmware.
Oct 2 20:33:39.720221 kernel: psci: Using standard PSCI v0.2 function IDs
Oct 2 20:33:39.720228 kernel: psci: MIGRATE_INFO_TYPE not supported.
Oct 2 20:33:39.720234 kernel: psci: SMC Calling Convention v1.4
Oct 2 20:33:39.720240 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x0 -> Node -1
Oct 2 20:33:39.720246 kernel: ACPI: NUMA: SRAT: PXM 0 -> MPIDR 0x1 -> Node -1
Oct 2 20:33:39.720252 kernel: percpu: Embedded 29 pages/cpu s79960 r8192 d30632 u118784
Oct 2 20:33:39.720258 kernel: pcpu-alloc: s79960 r8192 d30632 u118784 alloc=29*4096
Oct 2 20:33:39.720265 kernel: pcpu-alloc: [0] 0 [0] 1
Oct 2 20:33:39.720271 kernel: Detected PIPT I-cache on CPU0
Oct 2 20:33:39.720277 kernel: CPU features: detected: GIC system register CPU interface
Oct 2 20:33:39.720283 kernel: CPU features: detected: Hardware dirty bit management
Oct 2 20:33:39.720289 kernel: CPU features: detected: Spectre-BHB
Oct 2 20:33:39.720295 kernel: CPU features: kernel page table isolation forced ON by KASLR
Oct 2 20:33:39.720303 kernel: CPU features: detected: Kernel page table isolation (KPTI)
Oct 2 20:33:39.720309 kernel: CPU features: detected: ARM erratum 1418040
Oct 2 20:33:39.720315 kernel: CPU features: detected: ARM erratum 1542419 (kernel portion)
Oct 2 20:33:39.720321 kernel: Built 1 zonelists, mobility grouping on. Total pages: 1032156
Oct 2 20:33:39.720327 kernel: Policy zone: Normal
Oct 2 20:33:39.720334 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 quiet verity.usrhash=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca
Oct 2 20:33:39.720341 kernel: Unknown kernel command line parameters "BOOT_IMAGE=/flatcar/vmlinuz-a", will be passed to user space.
Oct 2 20:33:39.720347 kernel: Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes, linear)
Oct 2 20:33:39.720353 kernel: Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes, linear)
Oct 2 20:33:39.720359 kernel: mem auto-init: stack:off, heap alloc:off, heap free:off
Oct 2 20:33:39.720367 kernel: software IO TLB: mapped [mem 0x000000003abd6000-0x000000003ebd6000] (64MB)
Oct 2 20:33:39.720373 kernel: Memory: 3992064K/4194160K available (9792K kernel code, 2092K rwdata, 7548K rodata, 34560K init, 779K bss, 202096K reserved, 0K cma-reserved)
Oct 2 20:33:39.720379 kernel: SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
Oct 2 20:33:39.720385 kernel: trace event string verifier disabled
Oct 2 20:33:39.720391 kernel: rcu: Preemptible hierarchical RCU implementation.
Oct 2 20:33:39.720398 kernel: rcu: RCU event tracing is enabled.
Oct 2 20:33:39.720404 kernel: rcu: RCU restricting CPUs from NR_CPUS=512 to nr_cpu_ids=2.
Oct 2 20:33:39.720410 kernel: Trampoline variant of Tasks RCU enabled.
Oct 2 20:33:39.720417 kernel: Tracing variant of Tasks RCU enabled.
Oct 2 20:33:39.720423 kernel: rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies.
Oct 2 20:33:39.720429 kernel: rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
Oct 2 20:33:39.720436 kernel: NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0
Oct 2 20:33:39.720442 kernel: GICv3: 960 SPIs implemented
Oct 2 20:33:39.720448 kernel: GICv3: 0 Extended SPIs implemented
Oct 2 20:33:39.720454 kernel: GICv3: Distributor has no Range Selector support
Oct 2 20:33:39.720460 kernel: Root IRQ handler: gic_handle_irq
Oct 2 20:33:39.720466 kernel: GICv3: 16 PPIs implemented
Oct 2 20:33:39.720472 kernel: GICv3: CPU0: found redistributor 0 region 0:0x00000000effee000
Oct 2 20:33:39.720478 kernel: ITS: No ITS available, not enabling LPIs
Oct 2 20:33:39.720484 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040
Oct 2 20:33:39.720491 kernel: arch_timer: cp15 timer(s) running at 25.00MHz (virt).
Oct 2 20:33:39.720497 kernel: clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x5c40939b5, max_idle_ns: 440795202646 ns
Oct 2 20:33:39.720503 kernel: sched_clock: 56 bits at 25MHz, resolution 40ns, wraps every 4398046511100ns
Oct 2 20:33:39.720510 kernel: Console: colour dummy device 80x25
Oct 2 20:33:39.720517 kernel: printk: console [tty1] enabled
Oct 2 20:33:39.720523 kernel: ACPI: Core revision 20210730
Oct 2 20:33:39.720530 kernel: Calibrating delay loop (skipped), value calculated using timer frequency.. 50.00 BogoMIPS (lpj=25000)
Oct 2 20:33:39.720537 kernel: pid_max: default: 32768 minimum: 301
Oct 2 20:33:39.720543 kernel: LSM: Security Framework initializing
Oct 2 20:33:39.720549 kernel: SELinux: Initializing.
Oct 2 20:33:39.720555 kernel: Mount-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Oct 2 20:33:39.720562 kernel: Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes, linear)
Oct 2 20:33:39.720569 kernel: Hyper-V: privilege flags low 0x2e7f, high 0x3a8030, hints 0xe, misc 0x31e1
Oct 2 20:33:39.720575 kernel: Hyper-V: Host Build 10.0.22477.1341-1-0
Oct 2 20:33:39.720581 kernel: rcu: Hierarchical SRCU implementation.
Oct 2 20:33:39.720588 kernel: Remapping and enabling EFI services.
Oct 2 20:33:39.720594 kernel: smp: Bringing up secondary CPUs ...
Oct 2 20:33:39.720600 kernel: Detected PIPT I-cache on CPU1
Oct 2 20:33:39.720606 kernel: GICv3: CPU1: found redistributor 1 region 1:0x00000000f000e000
Oct 2 20:33:39.720613 kernel: arch_timer: Enabling local workaround for ARM erratum 1418040
Oct 2 20:33:39.720619 kernel: CPU1: Booted secondary processor 0x0000000001 [0x413fd0c1]
Oct 2 20:33:39.720625 kernel: smp: Brought up 1 node, 2 CPUs
Oct 2 20:33:39.720633 kernel: SMP: Total of 2 processors activated.
Oct 2 20:33:39.720639 kernel: CPU features: detected: 32-bit EL0 Support
Oct 2 20:33:39.720645 kernel: CPU features: detected: Instruction cache invalidation not required for I/D coherence
Oct 2 20:33:39.720652 kernel: CPU features: detected: Data cache clean to the PoU not required for I/D coherence
Oct 2 20:33:39.720658 kernel: CPU features: detected: CRC32 instructions
Oct 2 20:33:39.720664 kernel: CPU features: detected: RCpc load-acquire (LDAPR)
Oct 2 20:33:39.720671 kernel: CPU features: detected: LSE atomic instructions
Oct 2 20:33:39.720677 kernel: CPU features: detected: Privileged Access Never
Oct 2 20:33:39.720684 kernel: CPU: All CPU(s) started at EL1
Oct 2 20:33:39.720691 kernel: alternatives: patching kernel code
Oct 2 20:33:39.720702 kernel: devtmpfs: initialized
Oct 2 20:33:39.720709 kernel: KASLR enabled
Oct 2 20:33:39.720717 kernel: clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns
Oct 2 20:33:39.720755 kernel: futex hash table entries: 512 (order: 3, 32768 bytes, linear)
Oct 2 20:33:39.720762 kernel: pinctrl core: initialized pinctrl subsystem
Oct 2 20:33:39.720768 kernel: SMBIOS 3.1.0 present.
Oct 2 20:33:39.720775 kernel: DMI: Microsoft Corporation Virtual Machine/Virtual Machine, BIOS Hyper-V UEFI Release v4.1 05/16/2022
Oct 2 20:33:39.720782 kernel: NET: Registered PF_NETLINK/PF_ROUTE protocol family
Oct 2 20:33:39.720800 kernel: DMA: preallocated 512 KiB GFP_KERNEL pool for atomic allocations
Oct 2 20:33:39.720807 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations
Oct 2 20:33:39.720814 kernel: DMA: preallocated 512 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations
Oct 2 20:33:39.720821 kernel: audit: initializing netlink subsys (disabled)
Oct 2 20:33:39.720828 kernel: audit: type=2000 audit(0.044:1): state=initialized audit_enabled=0 res=1
Oct 2 20:33:39.720834 kernel: thermal_sys: Registered thermal governor 'step_wise'
Oct 2 20:33:39.720841 kernel: cpuidle: using governor menu
Oct 2 20:33:39.720849 kernel: hw-breakpoint: found 6 breakpoint and 4 watchpoint registers.
Oct 2 20:33:39.720856 kernel: ASID allocator initialised with 32768 entries
Oct 2 20:33:39.720862 kernel: ACPI: bus type PCI registered
Oct 2 20:33:39.720869 kernel: acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
Oct 2 20:33:39.720875 kernel: Serial: AMBA PL011 UART driver
Oct 2 20:33:39.720882 kernel: HugeTLB registered 1.00 GiB page size, pre-allocated 0 pages
Oct 2 20:33:39.720889 kernel: HugeTLB registered 32.0 MiB page size, pre-allocated 0 pages
Oct 2 20:33:39.720895 kernel: HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages
Oct 2 20:33:39.720909 kernel: HugeTLB registered 64.0 KiB page size, pre-allocated 0 pages
Oct 2 20:33:39.720918 kernel: cryptd: max_cpu_qlen set to 1000
Oct 2 20:33:39.720925 kernel: alg: No test for fips(ansi_cprng) (fips_ansi_cprng)
Oct 2 20:33:39.720931 kernel: ACPI: Added _OSI(Module Device)
Oct 2 20:33:39.720938 kernel: ACPI: Added _OSI(Processor Device)
Oct 2 20:33:39.720944 kernel: ACPI: Added _OSI(3.0 _SCP Extensions)
Oct 2 20:33:39.720951 kernel: ACPI: Added _OSI(Processor Aggregator Device)
Oct 2 20:33:39.720957 kernel: ACPI: Added _OSI(Linux-Dell-Video)
Oct 2 20:33:39.720964 kernel: ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio)
Oct 2 20:33:39.720971 kernel: ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics)
Oct 2 20:33:39.720977 kernel: ACPI: 1 ACPI AML tables successfully acquired and loaded
Oct 2 20:33:39.720992 kernel: ACPI: Interpreter enabled
Oct 2 20:33:39.720999 kernel: ACPI: Using GIC for interrupt routing
Oct 2 20:33:39.721005 kernel: ARMH0011:00: ttyAMA0 at MMIO 0xeffec000 (irq = 12, base_baud = 0) is a SBSA
Oct 2 20:33:39.721012 kernel: printk: console [ttyAMA0] enabled
Oct 2 20:33:39.721018 kernel: printk: bootconsole [pl11] disabled
Oct 2 20:33:39.721025 kernel: ARMH0011:01: ttyAMA1 at MMIO 0xeffeb000 (irq = 13, base_baud = 0) is a SBSA
Oct 2 20:33:39.721032 kernel: iommu: Default domain type: Translated
Oct 2 20:33:39.721038 kernel: iommu: DMA domain TLB invalidation policy: strict mode
Oct 2 20:33:39.721045 kernel: vgaarb: loaded
Oct 2 20:33:39.721053 kernel: pps_core: LinuxPPS API ver. 1 registered
Oct 2 20:33:39.721060 kernel: pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
Oct 2 20:33:39.721066 kernel: PTP clock support registered
Oct 2 20:33:39.721073 kernel: Registered efivars operations
Oct 2 20:33:39.721079 kernel: No ACPI PMU IRQ for CPU0
Oct 2 20:33:39.721086 kernel: No ACPI PMU IRQ for CPU1
Oct 2 20:33:39.721092 kernel: clocksource: Switched to clocksource arch_sys_counter
Oct 2 20:33:39.721099 kernel: VFS: Disk quotas dquot_6.6.0
Oct 2 20:33:39.721105 kernel: VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
Oct 2 20:33:39.721113 kernel: pnp: PnP ACPI init
Oct 2 20:33:39.721120 kernel: pnp: PnP ACPI: found 0 devices
Oct 2 20:33:39.721126 kernel: NET: Registered PF_INET protocol family
Oct 2 20:33:39.721133 kernel: IP idents hash table entries: 65536 (order: 7, 524288 bytes, linear)
Oct 2 20:33:39.721140 kernel: tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes, linear)
Oct 2 20:33:39.721147 kernel: Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear)
Oct 2 20:33:39.721154 kernel: TCP established hash table entries: 32768 (order: 6, 262144 bytes, linear)
Oct 2 20:33:39.721160 kernel: TCP bind hash table entries: 32768 (order: 7, 524288 bytes, linear)
Oct 2 20:33:39.721167 kernel: TCP: Hash tables configured (established 32768 bind 32768)
Oct 2 20:33:39.721175 kernel: UDP hash table entries: 2048 (order: 4, 65536 bytes, linear)
Oct 2 20:33:39.721182 kernel: UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes, linear)
Oct 2 20:33:39.721188 kernel: NET: Registered PF_UNIX/PF_LOCAL protocol family
Oct 2 20:33:39.721195 kernel: PCI: CLS 0 bytes, default 64
Oct 2 20:33:39.721201 kernel: hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 1 counters available
Oct 2 20:33:39.721208 kernel: kvm [1]: HYP mode not available
Oct 2 20:33:39.721215 kernel: Initialise system trusted keyrings
Oct 2 20:33:39.721221 kernel: workingset: timestamp_bits=39 max_order=20 bucket_order=0
Oct 2 20:33:39.721228 kernel: Key type asymmetric registered
Oct 2 20:33:39.721236 kernel: Asymmetric key parser 'x509' registered
Oct 2 20:33:39.721243 kernel: Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
Oct 2 20:33:39.721249 kernel: io scheduler mq-deadline registered
Oct 2 20:33:39.721256 kernel: io scheduler kyber registered
Oct 2 20:33:39.721262 kernel: io scheduler bfq registered
Oct 2 20:33:39.721269 kernel: Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
Oct 2 20:33:39.721280 kernel: thunder_xcv, ver 1.0
Oct 2 20:33:39.721287 kernel: thunder_bgx, ver 1.0
Oct 2 20:33:39.721293 kernel: nicpf, ver 1.0
Oct 2 20:33:39.721302 kernel: nicvf, ver 1.0
Oct 2 20:33:39.721453 kernel: rtc-efi rtc-efi.0: registered as rtc0
Oct 2 20:33:39.721515 kernel: rtc-efi rtc-efi.0: setting system clock to 2023-10-02T20:33:39 UTC (1696278819)
Oct 2 20:33:39.721525 kernel: efifb: probing for efifb
Oct 2 20:33:39.721531 kernel: efifb: framebuffer at 0x40000000, using 3072k, total 3072k
Oct 2 20:33:39.721538 kernel: efifb: mode is 1024x768x32, linelength=4096, pages=1
Oct 2 20:33:39.721545 kernel: efifb: scrolling: redraw
Oct 2 20:33:39.721551 kernel: efifb: Truecolor: size=8:8:8:8, shift=24:16:8:0
Oct 2 20:33:39.721560 kernel: Console: switching to colour frame buffer device 128x48
Oct 2 20:33:39.721567 kernel: fb0: EFI VGA frame buffer device
Oct 2 20:33:39.721574 kernel: SMCCC: SOC_ID: ARCH_SOC_ID not implemented, skipping ....
Oct 2 20:33:39.721580 kernel: hid: raw HID events driver (C) Jiri Kosina
Oct 2 20:33:39.721587 kernel: NET: Registered PF_INET6 protocol family
Oct 2 20:33:39.721593 kernel: Segment Routing with IPv6
Oct 2 20:33:39.721600 kernel: In-situ OAM (IOAM) with IPv6
Oct 2 20:33:39.721606 kernel: NET: Registered PF_PACKET protocol family
Oct 2 20:33:39.721613 kernel: Key type dns_resolver registered
Oct 2 20:33:39.721621 kernel: registered taskstats version 1
Oct 2 20:33:39.721627 kernel: Loading compiled-in X.509 certificates
Oct 2 20:33:39.721634 kernel: Loaded X.509 cert 'Kinvolk GmbH: Module signing key for 5.15.132-flatcar: 3a2a38edc68cb70dc60ec0223a6460557b3bb28d'
Oct 2 20:33:39.721641 kernel: Key type .fscrypt registered
Oct 2 20:33:39.721647 kernel: Key type fscrypt-provisioning registered
Oct 2 20:33:39.721654 kernel: ima: No TPM chip found, activating TPM-bypass!
Oct 2 20:33:39.721661 kernel: ima: Allocated hash algorithm: sha1
Oct 2 20:33:39.721667 kernel: ima: No architecture policies found
Oct 2 20:33:39.721674 kernel: Freeing unused kernel memory: 34560K
Oct 2 20:33:39.721681 kernel: Run /init as init process
Oct 2 20:33:39.721688 kernel: with arguments:
Oct 2 20:33:39.721694 kernel: /init
Oct 2 20:33:39.721701 kernel: with environment:
Oct 2 20:33:39.721707 kernel: HOME=/
Oct 2 20:33:39.721713 kernel: TERM=linux
Oct 2 20:33:39.721736 kernel: BOOT_IMAGE=/flatcar/vmlinuz-a
Oct 2 20:33:39.721746 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)
Oct 2 20:33:39.721757 systemd[1]: Detected virtualization microsoft.
Oct 2 20:33:39.721764 systemd[1]: Detected architecture arm64.
Oct 2 20:33:39.721770 systemd[1]: Running in initrd.
Oct 2 20:33:39.721777 systemd[1]: No hostname configured, using default hostname.
Oct 2 20:33:39.721784 systemd[1]: Hostname set to <localhost>.
Oct 2 20:33:39.721791 systemd[1]: Initializing machine ID from random generator.
Oct 2 20:33:39.721798 systemd[1]: Queued start job for default target initrd.target.
Oct 2 20:33:39.721805 systemd[1]: Started systemd-ask-password-console.path.
Oct 2 20:33:39.721813 systemd[1]: Reached target cryptsetup.target.
Oct 2 20:33:39.721820 systemd[1]: Reached target paths.target.
Oct 2 20:33:39.721827 systemd[1]: Reached target slices.target.
Oct 2 20:33:39.721834 systemd[1]: Reached target swap.target.
Oct 2 20:33:39.721841 systemd[1]: Reached target timers.target.
Oct 2 20:33:39.721849 systemd[1]: Listening on iscsid.socket.
Oct 2 20:33:39.721856 systemd[1]: Listening on iscsiuio.socket.
Oct 2 20:33:39.721864 systemd[1]: Listening on systemd-journald-audit.socket.
Oct 2 20:33:39.721871 systemd[1]: Listening on systemd-journald-dev-log.socket.
Oct 2 20:33:39.721878 systemd[1]: Listening on systemd-journald.socket.
Oct 2 20:33:39.721885 systemd[1]: Listening on systemd-networkd.socket.
Oct 2 20:33:39.721892 systemd[1]: Listening on systemd-udevd-control.socket.
Oct 2 20:33:39.721899 systemd[1]: Listening on systemd-udevd-kernel.socket.
Oct 2 20:33:39.721906 systemd[1]: Reached target sockets.target.
Oct 2 20:33:39.721913 systemd[1]: Starting kmod-static-nodes.service...
Oct 2 20:33:39.721920 systemd[1]: Finished network-cleanup.service.
Oct 2 20:33:39.721929 systemd[1]: Starting systemd-fsck-usr.service...
Oct 2 20:33:39.721936 systemd[1]: Starting systemd-journald.service...
Oct 2 20:33:39.721943 systemd[1]: Starting systemd-modules-load.service...
Oct 2 20:33:39.721950 systemd[1]: Starting systemd-resolved.service...
Oct 2 20:33:39.721957 systemd[1]: Starting systemd-vconsole-setup.service...
Oct 2 20:33:39.721967 systemd-journald[275]: Journal started
Oct 2 20:33:39.722006 systemd-journald[275]: Runtime Journal (/run/log/journal/bd8336e396f94a70a50d9471f587837c) is 8.0M, max 78.6M, 70.6M free.
Oct 2 20:33:39.723743 systemd[1]: Finished kmod-static-nodes.service.
Oct 2 20:33:39.723772 kernel: audit: type=1130 audit(1696278819.721:2): 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'
Oct 2 20:33:39.723783 systemd[1]: Started systemd-journald.service.
Oct 2 20:33:39.723792 kernel: audit: type=1130 audit(1696278819.721:3): 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'
Oct 2 20:33:39.723800 kernel: audit: type=1130 audit(1696278819.722:4): 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'
Oct 2 20:33:39.723809 kernel: audit: type=1130 audit(1696278819.722:5): 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'
Oct 2 20:33:39.721000 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'
Oct 2 20:33:39.721000 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'
Oct 2 20:33:39.722000 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'
Oct 2 20:33:39.722000 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'
Oct 2 20:33:39.722798 systemd[1]: Finished systemd-fsck-usr.service.
Oct 2 20:33:39.722947 systemd[1]: Finished systemd-vconsole-setup.service.
Oct 2 20:33:39.723601 systemd[1]: Starting dracut-cmdline-ask.service...
Oct 2 20:33:39.728402 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Oct 2 20:33:39.737775 systemd-modules-load[276]: Inserted module 'overlay'
Oct 2 20:33:39.755000 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'
Oct 2 20:33:39.756003 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Oct 2 20:33:39.760848 kernel: audit: type=1130 audit(1696278819.755:6): 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'
Oct 2 20:33:39.773197 systemd-resolved[277]: Positive Trust Anchors:
Oct 2 20:33:39.777454 kernel: bridge: filtering via arp/ip/ip6tables is no longer available by default. Update your scripts to load br_netfilter if you need this.
Oct 2 20:33:39.777473 kernel: Bridge firewalling registered
Oct 2 20:33:39.777505 systemd-resolved[277]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 2 20:33:39.790263 kernel: audit: type=1130 audit(1696278819.788:7): 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'
Oct 2 20:33:39.790287 kernel: audit: type=1130 audit(1696278819.788: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'
Oct 2 20:33:39.788000 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'
Oct 2 20:33:39.788000 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'
Oct 2 20:33:39.777539 systemd-resolved[277]: 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
Oct 2 20:33:39.822949 kernel: SCSI subsystem initialized
Oct 2 20:33:39.780543 systemd-modules-load[276]: Inserted module 'br_netfilter'
Oct 2 20:33:39.787963 systemd-resolved[277]: Defaulting to hostname 'linux'.
Oct 2 20:33:39.788843 systemd[1]: Started systemd-resolved.service.
Oct 2 20:33:39.838056 kernel: device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log.
Oct 2 20:33:39.838078 kernel: device-mapper: uevent: version 1.0.3
Oct 2 20:33:39.838087 kernel: device-mapper: ioctl: 4.45.0-ioctl (2021-03-22) initialised: dm-devel@redhat.com
Oct 2 20:33:39.788926 systemd[1]: Reached target nss-lookup.target.
Oct 2 20:33:39.843000 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'
Oct 2 20:33:39.789381 systemd[1]: Finished dracut-cmdline-ask.service.
Oct 2 20:33:39.851351 kernel: audit: type=1130 audit(1696278819.843:9): 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'
Oct 2 20:33:39.790142 systemd[1]: Starting dracut-cmdline.service...
Oct 2 20:33:39.858365 dracut-cmdline[293]: dracut-dracut-053
Oct 2 20:33:39.862000 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'
Oct 2 20:33:39.842392 systemd-modules-load[276]: Inserted module 'dm_multipath'
Oct 2 20:33:39.868622 kernel: audit: type=1130 audit(1696278819.862:10): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-sysctl comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:33:39.868642 dracut-cmdline[293]: 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 quiet verity.usrhash=684fe6a2259d7fb96810743ab87aaaa03d9f185b113bd6990a64d1079e5672ca
Oct 2 20:33:39.843939 systemd[1]: Finished systemd-modules-load.service.
Oct 2 20:33:39.844727 systemd[1]: Starting systemd-sysctl.service...
Oct 2 20:33:39.862203 systemd[1]: Finished systemd-sysctl.service.
Oct 2 20:33:39.974746 kernel: Loading iSCSI transport class v2.0-870.
Oct 2 20:33:39.982750 kernel: iscsi: registered transport (tcp)
Oct 2 20:33:39.995746 kernel: iscsi: registered transport (qla4xxx)
Oct 2 20:33:39.995796 kernel: QLogic iSCSI HBA Driver
Oct 2 20:33:40.069796 systemd[1]: Finished dracut-cmdline.service.
Oct 2 20:33:40.069000 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'
Oct 2 20:33:40.070670 systemd[1]: Starting dracut-pre-udev.service...
Oct 2 20:33:40.124752 kernel: raid6: neonx8 gen() 13823 MB/s
Oct 2 20:33:40.140740 kernel: raid6: neonx8 xor() 10845 MB/s
Oct 2 20:33:40.157732 kernel: raid6: neonx4 gen() 13548 MB/s
Oct 2 20:33:40.174731 kernel: raid6: neonx4 xor() 11275 MB/s
Oct 2 20:33:40.191733 kernel: raid6: neonx2 gen() 13108 MB/s
Oct 2 20:33:40.208731 kernel: raid6: neonx2 xor() 10264 MB/s
Oct 2 20:33:40.225733 kernel: raid6: neonx1 gen() 10513 MB/s
Oct 2 20:33:40.242735 kernel: raid6: neonx1 xor() 8764 MB/s
Oct 2 20:33:40.259733 kernel: raid6: int64x8 gen() 6298 MB/s
Oct 2 20:33:40.276735 kernel: raid6: int64x8 xor() 3551 MB/s
Oct 2 20:33:40.293730 kernel: raid6: int64x4 gen() 7315 MB/s
Oct 2 20:33:40.310731 kernel: raid6: int64x4 xor() 3856 MB/s
Oct 2 20:33:40.327730 kernel: raid6: int64x2 gen() 6156 MB/s
Oct 2 20:33:40.344735 kernel: raid6: int64x2 xor() 3323 MB/s
Oct 2 20:33:40.361733 kernel: raid6: int64x1 gen() 5046 MB/s
Oct 2 20:33:40.378746 kernel: raid6: int64x1 xor() 2648 MB/s
Oct 2 20:33:40.378756 kernel: raid6: using algorithm neonx8 gen() 13823 MB/s
Oct 2 20:33:40.378768 kernel: raid6: .... xor() 10845 MB/s, rmw enabled
Oct 2 20:33:40.378776 kernel: raid6: using neon recovery algorithm
Oct 2 20:33:40.386733 kernel: xor: measuring software checksum speed
Oct 2 20:33:40.387732 kernel: 8regs : 17286 MB/sec
Oct 2 20:33:40.387744 kernel: 32regs : 20749 MB/sec
Oct 2 20:33:40.388734 kernel: arm64_neon : 27741 MB/sec
Oct 2 20:33:40.388744 kernel: xor: using function: arm64_neon (27741 MB/sec)
Oct 2 20:33:40.436743 kernel: Btrfs loaded, crc32c=crc32c-generic, zoned=no, fsverity=no
Oct 2 20:33:40.455007 systemd[1]: Finished dracut-pre-udev.service.
Oct 2 20:33:40.454000 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'
Oct 2 20:33:40.454000 audit: BPF prog-id=7 op=LOAD
Oct 2 20:33:40.454000 audit: BPF prog-id=8 op=LOAD
Oct 2 20:33:40.455927 systemd[1]: Starting systemd-udevd.service...
Oct 2 20:33:40.476136 systemd-udevd[475]: Using default interface naming scheme 'v252'.
Oct 2 20:33:40.478000 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'
Oct 2 20:33:40.479503 systemd[1]: Started systemd-udevd.service.
Oct 2 20:33:40.480370 systemd[1]: Starting dracut-pre-trigger.service...
Oct 2 20:33:40.512318 dracut-pre-trigger[476]: rd.md=0: removing MD RAID activation
Oct 2 20:33:40.568045 systemd[1]: Finished dracut-pre-trigger.service.
Oct 2 20:33:40.567000 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'
Oct 2 20:33:40.568911 systemd[1]: Starting systemd-udev-trigger.service...
Oct 2 20:33:40.608991 systemd[1]: Finished systemd-udev-trigger.service.
Oct 2 20:33:40.608000 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'
Oct 2 20:33:40.664760 kernel: hv_vmbus: Vmbus version:5.3
Oct 2 20:33:40.664807 kernel: hv_vmbus: registering driver hid_hyperv
Oct 2 20:33:40.668742 kernel: input: Microsoft Vmbus HID-compliant Mouse as /devices/0006:045E:0621.0001/input/input0
Oct 2 20:33:40.668778 kernel: hid-generic 0006:045E:0621.0001: input: VIRTUAL HID v0.01 Mouse [Microsoft Vmbus HID-compliant Mouse] on
Oct 2 20:33:40.668919 kernel: hv_vmbus: registering driver hyperv_keyboard
Oct 2 20:33:40.669740 kernel: input: AT Translated Set 2 keyboard as /devices/LNXSYSTM:00/LNXSYBUS:00/ACPI0004:00/VMBUS:00/d34b2567-b9b6-42b9-8778-0a4ec0b955bf/serio0/input/input1
Oct 2 20:33:40.675743 kernel: hv_vmbus: registering driver hv_storvsc
Oct 2 20:33:40.675778 kernel: hv_vmbus: registering driver hv_netvsc
Oct 2 20:33:40.678236 kernel: scsi host0: storvsc_host_t
Oct 2 20:33:40.678409 kernel: scsi 0:0:0:0: Direct-Access Msft Virtual Disk 1.0 PQ: 0 ANSI: 5
Oct 2 20:33:40.678433 kernel: scsi 0:0:0:2: CD-ROM Msft Virtual DVD-ROM 1.0 PQ: 0 ANSI: 0
Oct 2 20:33:40.678448 kernel: scsi host1: storvsc_host_t
Oct 2 20:33:40.692747 kernel: sr 0:0:0:2: [sr0] scsi-1 drive
Oct 2 20:33:40.692916 kernel: cdrom: Uniform CD-ROM driver Revision: 3.20
Oct 2 20:33:40.693741 kernel: sr 0:0:0:2: Attached scsi CD-ROM sr0
Oct 2 20:33:40.701821 kernel: sd 0:0:0:0: [sda] 63737856 512-byte logical blocks: (32.6 GB/30.4 GiB)
Oct 2 20:33:40.701992 kernel: sd 0:0:0:0: [sda] 4096-byte physical blocks
Oct 2 20:33:40.702078 kernel: sd 0:0:0:0: [sda] Write Protect is off
Oct 2 20:33:40.702154 kernel: sd 0:0:0:0: [sda] Mode Sense: 0f 00 10 00
Oct 2 20:33:40.702229 kernel: sd 0:0:0:0: [sda] Write cache: disabled, read cache: enabled, supports DPO and FUA
Oct 2 20:33:40.703739 kernel: sda: sda1 sda2 sda3 sda4 sda6 sda7 sda9
Oct 2 20:33:40.704741 kernel: sd 0:0:0:0: [sda] Attached SCSI disk
Oct 2 20:33:40.740747 kernel: BTRFS: device label OEM devid 1 transid 16 /dev/sda6 scanned by (udev-worker) (545)
Oct 2 20:33:40.753329 systemd[1]: Found device dev-disk-by\x2dpartlabel-USR\x2dA.device.
Oct 2 20:33:40.753364 systemd[1]: Found device dev-disk-by\x2dpartuuid-7130c94a\x2d213a\x2d4e5a\x2d8e26\x2d6cce9662f132.device.
Oct 2 20:33:40.775000 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'
Oct 2 20:33:40.775000 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'
Oct 2 20:33:40.758906 systemd[1]: Starting disk-uuid.service...
Oct 2 20:33:40.775960 systemd[1]: disk-uuid.service: Deactivated successfully.
Oct 2 20:33:40.776045 systemd[1]: Finished disk-uuid.service.
Oct 2 20:33:40.780353 systemd[1]: Found device dev-disk-by\x2dlabel-ROOT.device.
Oct 2 20:33:40.804994 kernel: hv_netvsc 000d3a6e-6de4-000d-3a6e-6de4000d3a6e eth0: VF slot 1 added
Oct 2 20:33:40.783839 systemd[1]: Starting verity-setup.service...
Oct 2 20:33:40.806819 systemd[1]: Found device dev-disk-by\x2dlabel-EFI\x2dSYSTEM.device.
Oct 2 20:33:40.818907 kernel: hv_vmbus: registering driver hv_pci
Oct 2 20:33:40.820748 kernel: hv_pci b8ae2ea7-d4e7-48e8-b127-c14798c34138: PCI VMBus probing: Using version 0x10004
Oct 2 20:33:40.824081 kernel: device-mapper: verity: sha256 using implementation "sha256-ce"
Oct 2 20:33:40.830761 kernel: hv_pci b8ae2ea7-d4e7-48e8-b127-c14798c34138: PCI host bridge to bus d4e7:00
Oct 2 20:33:40.830898 kernel: pci_bus d4e7:00: root bus resource [mem 0xfc0000000-0xfc00fffff window]
Oct 2 20:33:40.830991 kernel: pci_bus d4e7:00: No busn resource found for root bus, will use [bus 00-ff]
Oct 2 20:33:40.833312 kernel: pci d4e7:00:02.0: [15b3:1018] type 00 class 0x020000
Oct 2 20:33:40.850650 kernel: pci d4e7:00:02.0: reg 0x10: [mem 0xfc0000000-0xfc00fffff 64bit pref]
Oct 2 20:33:40.856953 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Oct 2 20:33:40.875744 systemd[1]: Found device dev-mapper-usr.device.
Oct 2 20:33:40.883794 systemd[1]: Mounting sysusr-usr.mount...
Oct 2 20:33:41.101128 kernel: pci d4e7:00:02.0: enabling Extended Tags
Oct 2 20:33:41.102223 systemd[1]: Finished verity-setup.service.
Oct 2 20:33:41.106290 kernel: pci d4e7:00:02.0: 0.000 Gb/s available PCIe bandwidth, limited by Unknown x0 link at d4e7:00:02.0 (capable of 126.016 Gb/s with 8.0 GT/s PCIe x16 link)
Oct 2 20:33:41.106442 kernel: pci_bus d4e7:00: busn_res: [bus 00-ff] end is updated to 00
Oct 2 20:33:41.106525 kernel: pci d4e7:00:02.0: BAR 0: assigned [mem 0xfc0000000-0xfc00fffff 64bit pref]
Oct 2 20:33:41.101000 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'
Oct 2 20:33:41.140750 kernel: mlx5_core d4e7:00:02.0: firmware version: 16.31.2424
Oct 2 20:33:41.146737 kernel: EXT4-fs (dm-0): mounted filesystem without journal. Opts: norecovery. Quota mode: none.
Oct 2 20:33:41.146809 systemd[1]: Mounted sysusr-usr.mount.
Oct 2 20:33:41.146925 systemd[1]: afterburn-network-kargs.service was skipped because no trigger condition checks were met.
Oct 2 20:33:41.150146 systemd[1]: Starting ignition-setup.service...
Oct 2 20:33:41.181801 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 20:33:41.181828 kernel: BTRFS info (device sda6): using free space tree
Oct 2 20:33:41.181838 kernel: BTRFS info (device sda6): has skinny extents
Oct 2 20:33:41.150992 systemd[1]: Starting parse-ip-for-networkd.service...
Oct 2 20:33:41.218407 systemd[1]: mnt-oem.mount: Deactivated successfully.
Oct 2 20:33:41.243135 systemd[1]: Finished ignition-setup.service.
Oct 2 20:33:41.242000 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'
Oct 2 20:33:41.247005 systemd[1]: Starting ignition-fetch-offline.service...
Oct 2 20:33:41.322047 ignition[692]: Ignition 2.14.0
Oct 2 20:33:41.322056 ignition[692]: Stage: fetch-offline
Oct 2 20:33:41.322105 ignition[692]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:41.322127 ignition[692]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:41.345439 ignition[692]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:41.351000 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'
Oct 2 20:33:41.352507 systemd[1]: Finished ignition-fetch-offline.service.
Oct 2 20:33:41.357968 kernel: mlx5_core d4e7:00:02.0: MLX5E: StrdRq(1) RqSz(8) StrdSz(2048) RxCqeCmprss(0)
Oct 2 20:33:41.345614 ignition[692]: parsed url from cmdline: ""
Oct 2 20:33:41.345618 ignition[692]: no config URL provided
Oct 2 20:33:41.345623 ignition[692]: reading system config file "/usr/lib/ignition/user.ign"
Oct 2 20:33:41.345632 ignition[692]: no config at "/usr/lib/ignition/user.ign"
Oct 2 20:33:41.345641 ignition[692]: failed to fetch config: resource requires networking
Oct 2 20:33:41.345795 ignition[692]: Ignition finished successfully
Oct 2 20:33:41.399000 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'
Oct 2 20:33:41.399000 audit: BPF prog-id=9 op=LOAD
Oct 2 20:33:41.399904 systemd[1]: Finished parse-ip-for-networkd.service.
Oct 2 20:33:41.401063 systemd[1]: Starting systemd-networkd.service...
Oct 2 20:33:41.428450 systemd-networkd[773]: lo: Link UP
Oct 2 20:33:41.440572 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Oct 2 20:33:41.432000 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'
Oct 2 20:33:41.428461 systemd-networkd[773]: lo: Gained carrier
Oct 2 20:33:41.444000 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'
Oct 2 20:33:41.428826 systemd-networkd[773]: Enumeration completed
Oct 2 20:33:41.453000 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'
Oct 2 20:33:41.448687 ignition[775]: Ignition 2.14.0
Oct 2 20:33:41.429070 systemd-networkd[773]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 2 20:33:41.448693 ignition[775]: Stage: fetch
Oct 2 20:33:41.472565 iscsid[783]: iscsid: can't open InitiatorName configuration file /etc/iscsi/initiatorname.iscsi
Oct 2 20:33:41.472565 iscsid[783]: 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
Oct 2 20:33:41.472565 iscsid[783]: 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].
Oct 2 20:33:41.472565 iscsid[783]: Example: InitiatorName=iqn.2001-04.com.redhat:fc6.
Oct 2 20:33:41.472565 iscsid[783]: If using hardware iscsi like qla4xxx this message can be ignored.
Oct 2 20:33:41.472565 iscsid[783]: iscsid: can't open InitiatorAlias configuration file /etc/iscsi/initiatorname.iscsi
Oct 2 20:33:41.472565 iscsid[783]: iscsid: can't open iscsid.safe_logout configuration file /etc/iscsi/iscsid.conf
Oct 2 20:33:41.472000 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'
Oct 2 20:33:41.498000 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'
Oct 2 20:33:41.430263 systemd-networkd[773]: eth0: Link UP
Oct 2 20:33:41.448807 ignition[775]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:41.430351 systemd-networkd[773]: eth0: Gained carrier
Oct 2 20:33:41.448826 ignition[775]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:41.432687 systemd[1]: Started systemd-networkd.service.
Oct 2 20:33:41.451424 ignition[775]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:41.432818 systemd[1]: Reached target network.target.
Oct 2 20:33:41.451531 ignition[775]: parsed url from cmdline: ""
Oct 2 20:33:41.433568 systemd[1]: Starting ignition-fetch.service...
Oct 2 20:33:41.451534 ignition[775]: no config URL provided
Oct 2 20:33:41.434193 systemd[1]: Starting iscsiuio.service...
Oct 2 20:33:41.451538 ignition[775]: reading system config file "/usr/lib/ignition/user.ign"
Oct 2 20:33:41.445557 systemd[1]: Started iscsiuio.service.
Oct 2 20:33:41.451546 ignition[775]: no config at "/usr/lib/ignition/user.ign"
Oct 2 20:33:41.446459 systemd[1]: Starting iscsid.service...
Oct 2 20:33:41.451570 ignition[775]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #1
Oct 2 20:33:41.455999 systemd[1]: Started iscsid.service.
Oct 2 20:33:41.453128 ignition[775]: GET error: Get "http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text": dial tcp 169.254.169.254:80: connect: network is unreachable
Oct 2 20:33:41.456840 systemd[1]: Starting dracut-initqueue.service...
Oct 2 20:33:41.468642 systemd-networkd[773]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16
Oct 2 20:33:41.476462 systemd[1]: Finished dracut-initqueue.service.
Oct 2 20:33:41.476576 systemd[1]: Reached target remote-fs-pre.target.
Oct 2 20:33:41.639950 kernel: hv_netvsc 000d3a6e-6de4-000d-3a6e-6de4000d3a6e eth0: VF registering: eth1
Oct 2 20:33:41.640463 kernel: mlx5_core d4e7:00:02.0 eth1: joined to eth0
Oct 2 20:33:41.476593 systemd[1]: Reached target remote-cryptsetup.target.
Oct 2 20:33:41.476606 systemd[1]: Reached target remote-fs.target.
Oct 2 20:33:41.480393 systemd[1]: Starting dracut-pre-mount.service...
Oct 2 20:33:41.656106 kernel: mlx5_core d4e7:00:02.0 enP54503s1: renamed from eth1
Oct 2 20:33:41.499222 systemd[1]: Finished dracut-pre-mount.service.
Oct 2 20:33:41.654926 ignition[775]: GET http://169.254.169.254/metadata/instance/compute/userData?api-version=2021-01-01&format=text: attempt #2
Oct 2 20:33:41.661415 systemd-networkd[773]: eth1: Interface name change detected, renamed to enP54503s1.
Oct 2 20:33:41.679063 unknown[775]: fetched base config from "system"
Oct 2 20:33:41.685000 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'
Oct 2 20:33:41.677278 ignition[775]: GET result: OK
Oct 2 20:33:41.679071 unknown[775]: fetched base config from "system"
Oct 2 20:33:41.677330 ignition[775]: config has been read from IMDS userdata
Oct 2 20:33:41.679077 unknown[775]: fetched user config from "azure"
Oct 2 20:33:41.677349 ignition[775]: parsing config with SHA512: 315dfc85723e6a49332935bb339a91941bc551464f4c9ca84259f489dbf0f003c343671e4e0280daefc744fc2cc6e4b717f8705d415c74afec10e9f85625580b
Oct 2 20:33:41.685628 systemd[1]: Finished ignition-fetch.service.
Oct 2 20:33:41.680754 ignition[775]: fetch: fetch complete
Oct 2 20:33:41.687902 systemd[1]: Starting ignition-kargs.service...
Oct 2 20:33:41.680759 ignition[775]: fetch: fetch passed
Oct 2 20:33:41.680820 ignition[775]: Ignition finished successfully
Oct 2 20:33:41.706568 ignition[799]: Ignition 2.14.0
Oct 2 20:33:41.706574 ignition[799]: Stage: kargs
Oct 2 20:33:41.706675 ignition[799]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:41.706693 ignition[799]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:41.709624 ignition[799]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:41.717242 ignition[799]: kargs: op(1): [started] updating kernel arguments
Oct 2 20:33:41.749584 kernel: BTRFS: device label OEM devid 1 transid 17 /dev/sda6 scanned by mount (807)
Oct 2 20:33:41.749606 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 20:33:41.749615 kernel: BTRFS info (device sda6): using free space tree
Oct 2 20:33:41.749624 kernel: BTRFS info (device sda6): has skinny extents
Oct 2 20:33:41.717251 ignition[799]: kargs: op(1): executing: "ignition-kargs-helper" "--should-exist" "quiet"
Oct 2 20:33:42.141932 ignition[799]: kargs: op(1): [finished] updating kernel arguments
Oct 2 20:33:42.149679 ignition[799]: kargs: kargs passed
Oct 2 20:33:42.149768 ignition[799]: Ignition finished successfully
Oct 2 20:33:42.164000 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'
Oct 2 20:33:42.165574 systemd[1]: Finished ignition-kargs.service.
Oct 2 20:33:42.166428 systemd[1]: Starting ignition-disks.service...
Oct 2 20:33:42.183597 systemd-networkd[773]: enP54503s1: Link UP
Oct 2 20:33:42.189081 kernel: mlx5_core d4e7:00:02.0 enP54503s1: Link up
Oct 2 20:33:42.186240 ignition[836]: Ignition 2.14.0
Oct 2 20:33:42.186245 ignition[836]: Stage: disks
Oct 2 20:33:42.186363 ignition[836]: reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:42.207000 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'
Oct 2 20:33:42.207865 systemd[1]: Finished ignition-disks.service.
Oct 2 20:33:42.186423 ignition[836]: parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:42.208010 systemd[1]: Reached target initrd-root-device.target.
Oct 2 20:33:42.194079 ignition[836]: no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:42.208028 systemd[1]: Reached target local-fs-pre.target.
Oct 2 20:33:42.199269 ignition[836]: disks: disks passed
Oct 2 20:33:42.208044 systemd[1]: Reached target local-fs.target.
Oct 2 20:33:42.199313 ignition[836]: Ignition finished successfully
Oct 2 20:33:42.208060 systemd[1]: Reached target sysinit.target.
Oct 2 20:33:42.208073 systemd[1]: Reached target basic.target.
Oct 2 20:33:42.208877 systemd[1]: Starting systemd-fsck-root.service...
Oct 2 20:33:42.262806 kernel: hv_netvsc 000d3a6e-6de4-000d-3a6e-6de4000d3a6e eth0: Data path switched to VF: enP54503s1
Oct 2 20:33:42.312210 systemd-fsck[845]: ROOT: clean, 603/7326000 files, 481067/7359488 blocks
Oct 2 20:33:42.351000 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'
Oct 2 20:33:42.351761 systemd[1]: Finished systemd-fsck-root.service.
Oct 2 20:33:42.352532 systemd[1]: Mounting sysroot.mount...
Oct 2 20:33:42.370735 kernel: EXT4-fs (sda9): mounted filesystem with ordered data mode. Opts: (null). Quota mode: none.
Oct 2 20:33:42.370985 systemd[1]: Mounted sysroot.mount.
Oct 2 20:33:42.371097 systemd[1]: Reached target initrd-root-fs.target.
Oct 2 20:33:42.400587 systemd[1]: Mounting sysroot-usr.mount...
Oct 2 20:33:42.401558 systemd[1]: Starting flatcar-metadata-hostname.service...
Oct 2 20:33:42.401615 systemd[1]: ignition-remount-sysroot.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/sysroot).
Oct 2 20:33:42.401651 systemd[1]: Reached target ignition-diskful.target.
Oct 2 20:33:42.413066 systemd[1]: Mounted sysroot-usr.mount.
Oct 2 20:33:42.440782 systemd-networkd[773]: enP54503s1: Gained carrier
Oct 2 20:33:42.442323 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Oct 2 20:33:42.443188 systemd[1]: Starting initrd-setup-root.service...
Oct 2 20:33:42.473740 kernel: BTRFS: device label OEM devid 1 transid 19 /dev/sda6 scanned by mount (855)
Oct 2 20:33:42.474768 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 20:33:42.474978 kernel: BTRFS info (device sda6): using free space tree
Oct 2 20:33:42.474992 kernel: BTRFS info (device sda6): has skinny extents
Oct 2 20:33:42.475507 initrd-setup-root[860]: cut: /sysroot/etc/passwd: No such file or directory
Oct 2 20:33:42.490335 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Oct 2 20:33:42.505901 initrd-setup-root[886]: cut: /sysroot/etc/group: No such file or directory
Oct 2 20:33:42.524116 initrd-setup-root[894]: cut: /sysroot/etc/shadow: No such file or directory
Oct 2 20:33:42.543094 initrd-setup-root[902]: cut: /sysroot/etc/gshadow: No such file or directory
Oct 2 20:33:42.853699 systemd[1]: Finished initrd-setup-root.service.
Oct 2 20:33:42.853000 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'
Oct 2 20:33:42.854572 systemd[1]: Starting ignition-mount.service...
Oct 2 20:33:42.855308 systemd[1]: Starting sysroot-boot.service...
Oct 2 20:33:42.880570 systemd[1]: sysusr-usr-share-oem.mount: Deactivated successfully.
Oct 2 20:33:42.880695 systemd[1]: sysroot-usr-share-oem.mount: Deactivated successfully.
Oct 2 20:33:42.899341 systemd[1]: Finished sysroot-boot.service.
Oct 2 20:33:42.898000 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'
Oct 2 20:33:42.918536 ignition[924]: INFO : Ignition 2.14.0
Oct 2 20:33:42.924746 ignition[924]: INFO : Stage: mount
Oct 2 20:33:42.924746 ignition[924]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:42.924746 ignition[924]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:42.951824 ignition[924]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:42.959014 ignition[924]: INFO : mount: mount passed
Oct 2 20:33:42.959014 ignition[924]: INFO : Ignition finished successfully
Oct 2 20:33:42.958000 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'
Oct 2 20:33:42.958976 systemd[1]: Finished ignition-mount.service.
Oct 2 20:33:43.206563 coreos-metadata[854]: Oct 02 20:33:43.206 INFO Fetching http://168.63.129.16/?comp=versions: Attempt #1
Oct 2 20:33:43.216318 coreos-metadata[854]: Oct 02 20:33:43.216 INFO Fetch successful
Oct 2 20:33:43.249654 coreos-metadata[854]: Oct 02 20:33:43.249 INFO Fetching http://169.254.169.254/metadata/instance/compute/name?api-version=2017-08-01&format=text: Attempt #1
Oct 2 20:33:43.272362 coreos-metadata[854]: Oct 02 20:33:43.272 INFO Fetch successful
Oct 2 20:33:43.279000 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'
Oct 2 20:33:43.281208 coreos-metadata[854]: Oct 02 20:33:43.277 INFO wrote hostname ci-3510.3.0-a-42e9e120c2 to /sysroot/etc/hostname
Oct 2 20:33:43.280176 systemd[1]: Finished flatcar-metadata-hostname.service.
Oct 2 20:33:43.281021 systemd[1]: Starting ignition-files.service...
Oct 2 20:33:43.297172 systemd[1]: Mounting sysroot-usr-share-oem.mount...
Oct 2 20:33:43.300955 systemd-networkd[773]: eth0: Gained IPv6LL
Oct 2 20:33:43.312730 kernel: BTRFS: device label OEM devid 1 transid 20 /dev/sda6 scanned by mount (933)
Oct 2 20:33:43.312757 kernel: BTRFS info (device sda6): using crc32c (crc32c-generic) checksum algorithm
Oct 2 20:33:43.312767 kernel: BTRFS info (device sda6): using free space tree
Oct 2 20:33:43.312775 kernel: BTRFS info (device sda6): has skinny extents
Oct 2 20:33:43.316199 systemd[1]: Mounted sysroot-usr-share-oem.mount.
Oct 2 20:33:43.330970 ignition[952]: INFO : Ignition 2.14.0
Oct 2 20:33:43.335011 ignition[952]: INFO : Stage: files
Oct 2 20:33:43.335011 ignition[952]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:43.335011 ignition[952]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:43.358345 ignition[952]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:43.358345 ignition[952]: DEBUG : files: compiled without relabeling support, skipping
Oct 2 20:33:43.371682 ignition[952]: INFO : files: ensureUsers: op(1): [started] creating or modifying user "core"
Oct 2 20:33:43.371682 ignition[952]: DEBUG : files: ensureUsers: op(1): executing: "usermod" "--root" "/sysroot" "core"
Oct 2 20:33:43.399471 ignition[952]: INFO : files: ensureUsers: op(1): [finished] creating or modifying user "core"
Oct 2 20:33:43.407262 ignition[952]: INFO : files: ensureUsers: op(2): [started] adding ssh keys to user "core"
Oct 2 20:33:43.416952 unknown[952]: wrote ssh authorized keys file for user: core
Oct 2 20:33:43.422238 ignition[952]: INFO : files: ensureUsers: op(2): [finished] adding ssh keys to user "core"
Oct 2 20:33:43.430647 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): [started] writing file "/sysroot/etc/systemd/system/waagent.service"
Oct 2 20:33:43.443299 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 20:33:43.458745 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(4): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2383902007"
Oct 2 20:33:43.472125 kernel: BTRFS info: devid 1 device path /dev/sda6 changed to /dev/disk/by-label/OEM scanned by ignition (952)
Oct 2 20:33:43.472145 ignition[952]: CRITICAL : files: createFilesystemsFiles: createFiles: op(3): op(4): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2383902007": device or resource busy
Oct 2 20:33:43.472145 ignition[952]: ERROR : files: createFilesystemsFiles: createFiles: op(3): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem2383902007", trying btrfs: device or resource busy
Oct 2 20:33:43.472145 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(5): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2383902007"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(5): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem2383902007"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(6): [started] unmounting "/mnt/oem2383902007"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): op(6): [finished] unmounting "/mnt/oem2383902007"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(3): [finished] writing file "/sysroot/etc/systemd/system/waagent.service"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): [started] writing file "/sysroot/etc/systemd/system/nvidia.service"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): oem config not found in "/usr/share/oem", looking on oem partition
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(8): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1647609959"
Oct 2 20:33:43.515930 ignition[952]: CRITICAL : files: createFilesystemsFiles: createFiles: op(7): op(8): [failed] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1647609959": device or resource busy
Oct 2 20:33:43.515930 ignition[952]: ERROR : files: createFilesystemsFiles: createFiles: op(7): failed to mount ext4 device "/dev/disk/by-label/OEM" at "/mnt/oem1647609959", trying btrfs: device or resource busy
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [started] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1647609959"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(9): [finished] mounting "/dev/disk/by-label/OEM" at "/mnt/oem1647609959"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [started] unmounting "/mnt/oem1647609959"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): op(a): [finished] unmounting "/mnt/oem1647609959"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: createFilesystemsFiles: createFiles: op(7): [finished] writing file "/sysroot/etc/systemd/system/nvidia.service"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: op(b): [started] processing unit "waagent.service"
Oct 2 20:33:43.515930 ignition[952]: INFO : files: op(b): [finished] processing unit "waagent.service"
Oct 2 20:33:43.515000 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'
Oct 2 20:33:43.540000 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'
Oct 2 20:33:43.540000 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'
Oct 2 20:33:43.576000 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'
Oct 2 20:33:43.603000 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'
Oct 2 20:33:43.603000 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'
Oct 2 20:33:43.622000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.636000 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'
Oct 2 20:33:43.647000 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'
Oct 2 20:33:43.648000 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'
Oct 2 20:33:43.657000 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'
Oct 2 20:33:43.662000 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'
Oct 2 20:33:43.662000 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'
Oct 2 20:33:43.669000 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'
Oct 2 20:33:43.680000 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'
Oct 2 20:33:43.680000 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'
Oct 2 20:33:43.680000 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'
Oct 2 20:33:43.680000 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'
Oct 2 20:33:43.680000 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'
Oct 2 20:33:43.691000 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'
Oct 2 20:33:43.488570 systemd[1]: mnt-oem2383902007.mount: Deactivated successfully.
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(c): [started] processing unit "nvidia.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(c): [finished] processing unit "nvidia.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(d): [started] setting preset to enabled for "nvidia.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(d): [finished] setting preset to enabled for "nvidia.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(e): [started] setting preset to enabled for "waagent.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: op(e): [finished] setting preset to enabled for "waagent.service"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: createResultFile: createFiles: op(f): [started] writing file "/sysroot/etc/.ignition-result.json"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: createResultFile: createFiles: op(f): [finished] writing file "/sysroot/etc/.ignition-result.json"
Oct 2 20:33:43.711582 ignition[952]: INFO : files: files passed
Oct 2 20:33:43.711582 ignition[952]: INFO : Ignition finished successfully
Oct 2 20:33:43.710000 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'
Oct 2 20:33:43.710000 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'
Oct 2 20:33:43.710000 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'
Oct 2 20:33:43.718000 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'
Oct 2 20:33:43.726000 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'
Oct 2 20:33:43.726000 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'
Oct 2 20:33:43.726000 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'
Oct 2 20:33:43.755000 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'
Oct 2 20:33:43.755000 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'
Oct 2 20:33:43.755000 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'
Oct 2 20:33:43.755000 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'
Oct 2 20:33:43.755000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:33:43.763000 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'
Oct 2 20:33:43.763000 audit[1]: SERVICE_STOP pid=1 uid=0 auid=4294967295 ses=4294967295 subj=kernel msg='unit=systemd-vconsole-setup comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:33:43.763000 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'
Oct 2 20:33:43.763000 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'
Oct 2 20:33:43.791731 iscsid[783]: iscsid shutting down.
Oct 2 20:33:43.518075 systemd[1]: Finished ignition-files.service.
Oct 2 20:33:43.798000 audit: BPF prog-id=6 op=UNLOAD
Oct 2 20:33:43.521007 systemd[1]: Starting initrd-setup-root-after-ignition.service...
Oct 2 20:33:43.521056 systemd[1]: torcx-profile-populate.service was skipped because of an unmet condition check (ConditionPathExists=/sysroot/etc/torcx/next-profile).
Oct 2 20:33:43.815491 initrd-setup-root-after-ignition[978]: grep: /sysroot/etc/flatcar/enabled-sysext.conf: No such file or directory
Oct 2 20:33:43.823856 kernel: hv_netvsc 000d3a6e-6de4-000d-3a6e-6de4000d3a6e eth0: Data path switched from VF: enP54503s1
Oct 2 20:33:43.521771 systemd[1]: Starting ignition-quench.service...
Oct 2 20:33:43.541596 systemd[1]: ignition-quench.service: Deactivated successfully.
Oct 2 20:33:43.541700 systemd[1]: Finished ignition-quench.service.
Oct 2 20:33:43.578859 systemd[1]: Finished initrd-setup-root-after-ignition.service.
Oct 2 20:33:43.579003 systemd[1]: Reached target ignition-complete.target.
Oct 2 20:33:43.579823 systemd[1]: Starting initrd-parse-etc.service...
Oct 2 20:33:43.605206 systemd[1]: initrd-parse-etc.service: Deactivated successfully.
Oct 2 20:33:43.857107 ignition[991]: INFO : Ignition 2.14.0
Oct 2 20:33:43.857107 ignition[991]: INFO : Stage: umount
Oct 2 20:33:43.857107 ignition[991]: INFO : reading system config file "/usr/lib/ignition/base.d/base.ign"
Oct 2 20:33:43.857107 ignition[991]: DEBUG : parsing config with SHA512: 4824fd4a4e57848da530dc2b56e2d3e9f5f19634d1c84ef29f8fc49255520728d0377a861a375d7c8cb5301ed861ff4ede4b440b074b1d6a86e23be9cefc2f63
Oct 2 20:33:43.857107 ignition[991]: INFO : no config dir at "/usr/lib/ignition/base.platform.d/azure"
Oct 2 20:33:43.857107 ignition[991]: INFO : umount: umount passed
Oct 2 20:33:43.857107 ignition[991]: INFO : Ignition finished successfully
Oct 2 20:33:43.605324 systemd[1]: Finished initrd-parse-etc.service.
Oct 2 20:33:43.605480 systemd[1]: Reached target initrd-fs.target.
Oct 2 20:33:43.605511 systemd[1]: Reached target initrd.target.
Oct 2 20:33:43.605576 systemd[1]: dracut-mount.service was skipped because no trigger condition checks were met.
Oct 2 20:33:43.606347 systemd[1]: Starting dracut-pre-pivot.service...
Oct 2 20:33:43.625376 systemd[1]: Finished dracut-pre-pivot.service.
Oct 2 20:33:43.626411 systemd[1]: Starting initrd-cleanup.service...
Oct 2 20:33:43.638758 systemd[1]: Stopped target nss-lookup.target.
Oct 2 20:33:43.638831 systemd[1]: Stopped target remote-cryptsetup.target.
Oct 2 20:33:43.638910 systemd[1]: Stopped target timers.target.
Oct 2 20:33:43.638977 systemd[1]: dracut-pre-pivot.service: Deactivated successfully.
Oct 2 20:33:43.639070 systemd[1]: Stopped dracut-pre-pivot.service.
Oct 2 20:33:43.639238 systemd[1]: Stopped target initrd.target.
Oct 2 20:33:43.953000 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'
Oct 2 20:33:43.639304 systemd[1]: Stopped target basic.target.
Oct 2 20:33:43.639368 systemd[1]: Stopped target ignition-complete.target.
Oct 2 20:33:43.639429 systemd[1]: Stopped target ignition-diskful.target.
Oct 2 20:33:43.639487 systemd[1]: Stopped target initrd-root-device.target.
Oct 2 20:33:43.639549 systemd[1]: Stopped target remote-fs.target.
Oct 2 20:33:43.639607 systemd[1]: Stopped target remote-fs-pre.target.
Oct 2 20:33:43.639672 systemd[1]: Stopped target sysinit.target.
Oct 2 20:33:43.639749 systemd[1]: Stopped target local-fs.target.
Oct 2 20:33:43.639811 systemd[1]: Stopped target local-fs-pre.target.
Oct 2 20:33:43.639871 systemd[1]: Stopped target swap.target.
Oct 2 20:33:43.639910 systemd[1]: dracut-pre-mount.service: Deactivated successfully.
Oct 2 20:33:44.003816 systemd-journald[275]: Received SIGTERM from PID 1 (n/a).
Oct 2 20:33:43.639988 systemd[1]: Stopped dracut-pre-mount.service.
Oct 2 20:33:43.640097 systemd[1]: Stopped target cryptsetup.target.
Oct 2 20:33:43.640137 systemd[1]: dracut-initqueue.service: Deactivated successfully.
Oct 2 20:33:43.640204 systemd[1]: Stopped dracut-initqueue.service.
Oct 2 20:33:43.640321 systemd[1]: initrd-setup-root-after-ignition.service: Deactivated successfully.
Oct 2 20:33:43.640403 systemd[1]: Stopped initrd-setup-root-after-ignition.service.
Oct 2 20:33:43.640486 systemd[1]: ignition-files.service: Deactivated successfully.
Oct 2 20:33:43.640553 systemd[1]: Stopped ignition-files.service.
Oct 2 20:33:43.640634 systemd[1]: flatcar-metadata-hostname.service: Deactivated successfully.
Oct 2 20:33:43.640707 systemd[1]: Stopped flatcar-metadata-hostname.service.
Oct 2 20:33:43.642304 systemd[1]: Stopping ignition-mount.service...
Oct 2 20:33:43.644715 systemd[1]: Stopping iscsid.service...
Oct 2 20:33:43.647940 systemd[1]: Stopping sysroot-boot.service...
Oct 2 20:33:43.647983 systemd[1]: systemd-udev-trigger.service: Deactivated successfully.
Oct 2 20:33:43.648130 systemd[1]: Stopped systemd-udev-trigger.service.
Oct 2 20:33:43.648328 systemd[1]: dracut-pre-trigger.service: Deactivated successfully.
Oct 2 20:33:43.648831 systemd[1]: Stopped dracut-pre-trigger.service.
Oct 2 20:33:43.658574 systemd[1]: iscsid.service: Deactivated successfully.
Oct 2 20:33:43.658696 systemd[1]: Stopped iscsid.service.
Oct 2 20:33:43.663099 systemd[1]: initrd-cleanup.service: Deactivated successfully.
Oct 2 20:33:43.663182 systemd[1]: Finished initrd-cleanup.service.
Oct 2 20:33:43.663353 systemd[1]: Stopping iscsiuio.service...
Oct 2 20:33:43.670168 systemd[1]: iscsiuio.service: Deactivated successfully.
Oct 2 20:33:43.670253 systemd[1]: Stopped iscsiuio.service.
Oct 2 20:33:43.680958 systemd[1]: ignition-mount.service: Deactivated successfully.
Oct 2 20:33:43.681051 systemd[1]: Stopped ignition-mount.service.
Oct 2 20:33:43.681154 systemd[1]: ignition-disks.service: Deactivated successfully.
Oct 2 20:33:43.681188 systemd[1]: Stopped ignition-disks.service.
Oct 2 20:33:43.681232 systemd[1]: ignition-kargs.service: Deactivated successfully.
Oct 2 20:33:43.681262 systemd[1]: Stopped ignition-kargs.service.
Oct 2 20:33:43.681292 systemd[1]: ignition-fetch.service: Deactivated successfully.
Oct 2 20:33:43.681315 systemd[1]: Stopped ignition-fetch.service.
Oct 2 20:33:43.681344 systemd[1]: Stopped target network.target.
Oct 2 20:33:43.681361 systemd[1]: ignition-fetch-offline.service: Deactivated successfully.
Oct 2 20:33:43.681392 systemd[1]: Stopped ignition-fetch-offline.service.
Oct 2 20:33:43.681434 systemd[1]: Stopped target paths.target.
Oct 2 20:33:43.681453 systemd[1]: systemd-ask-password-console.path: Deactivated successfully.
Oct 2 20:33:43.691786 systemd[1]: Stopped systemd-ask-password-console.path.
Oct 2 20:33:43.691825 systemd[1]: Stopped target slices.target.
Oct 2 20:33:43.691839 systemd[1]: Stopped target sockets.target.
Oct 2 20:33:43.691887 systemd[1]: iscsid.socket: Deactivated successfully.
Oct 2 20:33:43.691926 systemd[1]: Closed iscsid.socket.
Oct 2 20:33:43.691965 systemd[1]: iscsiuio.socket: Deactivated successfully.
Oct 2 20:33:43.691993 systemd[1]: Closed iscsiuio.socket.
Oct 2 20:33:43.692023 systemd[1]: ignition-setup.service: Deactivated successfully.
Oct 2 20:33:43.692063 systemd[1]: Stopped ignition-setup.service.
Oct 2 20:33:43.692222 systemd[1]: Stopping systemd-networkd.service...
Oct 2 20:33:43.692284 systemd[1]: Stopping systemd-resolved.service...
Oct 2 20:33:43.705790 systemd-networkd[773]: eth0: DHCPv6 lease lost
Oct 2 20:33:44.008000 audit: BPF prog-id=9 op=UNLOAD
Oct 2 20:33:43.711526 systemd[1]: systemd-networkd.service: Deactivated successfully.
Oct 2 20:33:43.711612 systemd[1]: Stopped systemd-networkd.service.
Oct 2 20:33:43.711821 systemd[1]: systemd-networkd.socket: Deactivated successfully.
Oct 2 20:33:43.711859 systemd[1]: Closed systemd-networkd.socket.
Oct 2 20:33:43.712563 systemd[1]: Stopping network-cleanup.service...
Oct 2 20:33:43.712591 systemd[1]: parse-ip-for-networkd.service: Deactivated successfully.
Oct 2 20:33:43.712648 systemd[1]: Stopped parse-ip-for-networkd.service.
Oct 2 20:33:43.712761 systemd[1]: systemd-sysctl.service: Deactivated successfully.
Oct 2 20:33:43.712798 systemd[1]: Stopped systemd-sysctl.service.
Oct 2 20:33:43.718380 systemd[1]: systemd-modules-load.service: Deactivated successfully.
Oct 2 20:33:43.718427 systemd[1]: Stopped systemd-modules-load.service.
Oct 2 20:33:43.721564 systemd[1]: Stopping systemd-udevd.service...
Oct 2 20:33:43.727503 systemd[1]: sysroot-boot.service: Deactivated successfully.
Oct 2 20:33:43.727623 systemd[1]: Stopped sysroot-boot.service.
Oct 2 20:33:43.727846 systemd[1]: initrd-setup-root.service: Deactivated successfully.
Oct 2 20:33:43.727889 systemd[1]: Stopped initrd-setup-root.service.
Oct 2 20:33:43.730028 systemd[1]: systemd-resolved.service: Deactivated successfully.
Oct 2 20:33:43.730184 systemd[1]: Stopped systemd-resolved.service.
Oct 2 20:33:43.755940 systemd[1]: systemd-udevd.service: Deactivated successfully.
Oct 2 20:33:43.756079 systemd[1]: Stopped systemd-udevd.service.
Oct 2 20:33:43.756269 systemd[1]: systemd-udevd-control.socket: Deactivated successfully.
Oct 2 20:33:43.756307 systemd[1]: Closed systemd-udevd-control.socket.
Oct 2 20:33:43.756345 systemd[1]: systemd-udevd-kernel.socket: Deactivated successfully.
Oct 2 20:33:43.756373 systemd[1]: Closed systemd-udevd-kernel.socket.
Oct 2 20:33:43.756398 systemd[1]: dracut-pre-udev.service: Deactivated successfully.
Oct 2 20:33:43.756432 systemd[1]: Stopped dracut-pre-udev.service.
Oct 2 20:33:43.756479 systemd[1]: dracut-cmdline.service: Deactivated successfully.
Oct 2 20:33:43.756509 systemd[1]: Stopped dracut-cmdline.service.
Oct 2 20:33:43.756556 systemd[1]: dracut-cmdline-ask.service: Deactivated successfully.
Oct 2 20:33:43.756585 systemd[1]: Stopped dracut-cmdline-ask.service.
Oct 2 20:33:43.757436 systemd[1]: Starting initrd-udevadm-cleanup-db.service...
Oct 2 20:33:43.757467 systemd[1]: systemd-tmpfiles-setup-dev.service: Deactivated successfully.
Oct 2 20:33:43.757528 systemd[1]: Stopped systemd-tmpfiles-setup-dev.service.
Oct 2 20:33:43.767596 systemd[1]: kmod-static-nodes.service: Deactivated successfully.
Oct 2 20:33:43.767658 systemd[1]: Stopped kmod-static-nodes.service.
Oct 2 20:33:43.767747 systemd[1]: systemd-vconsole-setup.service: Deactivated successfully.
Oct 2 20:33:43.767784 systemd[1]: Stopped systemd-vconsole-setup.service.
Oct 2 20:33:43.768118 systemd[1]: initrd-udevadm-cleanup-db.service: Deactivated successfully.
Oct 2 20:33:43.768208 systemd[1]: Finished initrd-udevadm-cleanup-db.service.
Oct 2 20:33:43.953683 systemd[1]: network-cleanup.service: Deactivated successfully.
Oct 2 20:33:43.953823 systemd[1]: Stopped network-cleanup.service.
Oct 2 20:33:43.954028 systemd[1]: Reached target initrd-switch-root.target.
Oct 2 20:33:43.954841 systemd[1]: Starting initrd-switch-root.service...
Oct 2 20:33:43.965778 systemd[1]: Switching root.
Oct 2 20:33:44.010136 systemd-journald[275]: Journal stopped
Oct 2 20:33:57.688216 kernel: SELinux: Class mctp_socket not defined in policy.
Oct 2 20:33:57.688245 kernel: SELinux: Class anon_inode not defined in policy.
Oct 2 20:33:57.688256 kernel: SELinux: the above unknown classes and permissions will be allowed
Oct 2 20:33:57.688268 kernel: SELinux: policy capability network_peer_controls=1
Oct 2 20:33:57.688276 kernel: SELinux: policy capability open_perms=1
Oct 2 20:33:57.688285 kernel: SELinux: policy capability extended_socket_class=1
Oct 2 20:33:57.688294 kernel: SELinux: policy capability always_check_network=0
Oct 2 20:33:57.688302 kernel: SELinux: policy capability cgroup_seclabel=1
Oct 2 20:33:57.688310 kernel: SELinux: policy capability nnp_nosuid_transition=1
Oct 2 20:33:57.688318 kernel: SELinux: policy capability genfs_seclabel_symlinks=0
Oct 2 20:33:57.688327 kernel: SELinux: policy capability ioctl_skip_cloexec=0
Oct 2 20:33:57.688334 kernel: kauditd_printk_skb: 71 callbacks suppressed
Oct 2 20:33:57.688343 kernel: audit: type=1403 audit(1696278824.638:82): auid=4294967295 ses=4294967295 lsm=selinux res=1
Oct 2 20:33:57.688352 systemd[1]: Successfully loaded SELinux policy in 152.370ms.
Oct 2 20:33:57.688363 systemd[1]: Relabelled /dev, /dev/shm, /run, /sys/fs/cgroup in 19.463ms.
Oct 2 20:33:57.688375 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)
Oct 2 20:33:57.688385 systemd[1]: Detected virtualization microsoft.
Oct 2 20:33:57.688394 systemd[1]: Detected architecture arm64.
Oct 2 20:33:57.688402 systemd[1]: Detected first boot.
Oct 2 20:33:57.688412 systemd[1]: Hostname set to <ci-3510.3.0-a-42e9e120c2>.
Oct 2 20:33:57.688421 systemd[1]: Initializing machine ID from random generator.
Oct 2 20:33:57.688430 kernel: audit: type=1400 audit(1696278825.119:83): avc: denied { bpf } for pid=1 comm="systemd" capability=39 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1
Oct 2 20:33:57.688441 kernel: audit: type=1400 audit(1696278825.119:84): avc: denied { perfmon } for pid=1 comm="systemd" capability=38 scontext=system_u:system_r:kernel_t:s0 tcontext=system_u:system_r:kernel_t:s0 tclass=capability2 permissive=1
Oct 2 20:33:57.688488 kernel: audit: type=1334 audit(1696278825.119:85): prog-id=10 op=LOAD
Oct 2 20:33:57.688500 kernel: audit: type=1334 audit(1696278825.119:86): prog-id=10 op=UNLOAD
Oct 2 20:33:57.688508 kernel: audit: type=1334 audit(1696278825.119:87): prog-id=11 op=LOAD
Oct 2 20:33:57.688517 kernel: audit: type=1334 audit(1696278825.119:88): prog-id=11 op=UNLOAD
Oct 2 20:33:57.689186 kernel: mlx5_core d4e7:00:02.0: poll_health:739:(pid 0): device's health compromised - reached miss count
Oct 2 20:33:57.689211 systemd[1]: Populated /etc with preset unit settings.
Oct 2 20:33:57.689221 systemd[1]: /usr/lib/systemd/system/locksmithd.service:8: Unit uses CPUShares=; please use CPUWeight= instead. Support for CPUShares= will be removed soon.
Oct 2 20:33:57.689232 systemd[1]: /usr/lib/systemd/system/locksmithd.service:9: Unit uses MemoryLimit=; please use MemoryMax= instead. Support for MemoryLimit= will be removed soon.
Oct 2 20:33:57.689244 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.
Oct 2 20:33:57.689253 kernel: audit: type=1334 audit(1696278837.564:89): prog-id=12 op=LOAD
Oct 2 20:33:57.689261 kernel: audit: type=1334 audit(1696278837.564:90): prog-id=3 op=UNLOAD
Oct 2 20:33:57.689269 kernel: audit: type=1334 audit(1696278837.564:91): prog-id=13 op=LOAD
Oct 2 20:33:57.689279 kernel: audit: type=1334 audit(1696278837.564:92): prog-id=14 op=LOAD
Oct 2 20:33:57.689288 kernel: audit: type=1334 audit(1696278837.564:93): prog-id=4 op=UNLOAD
Oct 2 20:33:57.689296 kernel: audit: type=1334 audit(1696278837.564:94): prog-id=5 op=UNLOAD
Oct 2 20:33:57.689305 kernel: audit: type=1334 audit(1696278837.565:95): prog-id=15 op=LOAD
Oct 2 20:33:57.689313 kernel: audit: type=1334 audit(1696278837.565:96): prog-id=12 op=UNLOAD
Oct 2 20:33:57.689324 kernel: audit: type=1334 audit(1696278837.565:97): prog-id=16 op=LOAD
Oct 2 20:33:57.689332 kernel: audit: type=1334 audit(1696278837.565:98): prog-id=17 op=LOAD
Oct 2 20:33:57.689341 systemd[1]: initrd-switch-root.service: Deactivated successfully.
Oct 2 20:33:57.689350 systemd[1]: Stopped initrd-switch-root.service.
Oct 2 20:33:57.689361 systemd[1]: systemd-journald.service: Scheduled restart job, restart counter is at 1.
Oct 2 20:33:57.689371 systemd[1]: Created slice system-addon\x2dconfig.slice.
Oct 2 20:33:57.689380 systemd[1]: Created slice system-addon\x2drun.slice.
Oct 2 20:33:57.689389 systemd[1]: Created slice system-getty.slice.
Oct 2 20:33:57.689399 systemd[1]: Created slice system-modprobe.slice.
Oct 2 20:33:57.689408 systemd[1]: Created slice system-serial\x2dgetty.slice.
Oct 2 20:33:57.689417 systemd[1]: Created slice system-system\x2dcloudinit.slice.
Oct 2 20:33:57.689427 systemd[1]: Created slice system-systemd\x2dfsck.slice.
Oct 2 20:33:57.689437 systemd[1]: Created slice user.slice.
Oct 2 20:33:57.689446 systemd[1]: Started systemd-ask-password-console.path.
Oct 2 20:33:57.689455 systemd[1]: Started systemd-ask-password-wall.path.
Oct 2 20:33:57.689465 systemd[1]: Set up automount boot.automount.
Oct 2 20:33:57.689475 systemd[1]: Set up automount proc-sys-fs-binfmt_misc.automount.
Oct 2 20:33:57.689484 systemd[1]: Stopped target initrd-switch-root.target.
Oct 2 20:33:57.689493 systemd[1]: Stopped target initrd-fs.target.
Oct 2 20:33:57.689502 systemd[1]: Stopped target initrd-root-fs.target.
Oct 2 20:33:57.689511 systemd[1]: Reached target integritysetup.target.
Oct 2 20:33:57.689521 systemd[1]: Reached target remote-cryptsetup.target.
Oct 2 20:33:57.689531 systemd[1]: Reached target remote-fs.target.
Oct 2 20:33:57.689540 systemd[1]: Reached target slices.target.
Oct 2 20:33:57.689549 systemd[1]: Reached target swap.target.
Oct 2 20:33:57.689559 systemd[1]: Reached target torcx.target.
Oct 2 20:33:57.689568 systemd[1]: Reached target veritysetup.target.
Oct 2 20:33:57.689577 systemd[1]: Listening on systemd-coredump.socket.
Oct 2 20:33:57.689587 systemd[1]: Listening on systemd-initctl.socket.
Oct 2 20:33:57.689597 systemd[1]: Listening on systemd-networkd.socket.
Oct 2 20:33:57.689607 systemd[1]: Listening on systemd-udevd-control.socket.
Oct 2 20:33:57.689616 systemd[1]: Listening on systemd-udevd-kernel.socket.
Oct 2 20:33:57.689626 systemd[1]: Listening on systemd-userdbd.socket.
Oct 2 20:33:57.689636 systemd[1]: Mounting dev-hugepages.mount...
Oct 2 20:33:57.689646 systemd[1]: Mounting dev-mqueue.mount...
Oct 2 20:33:57.689655 systemd[1]: Mounting media.mount...
Oct 2 20:33:57.689664 systemd[1]: Mounting sys-kernel-debug.mount...
Oct 2 20:33:57.689674 systemd[1]: Mounting sys-kernel-tracing.mount...
Oct 2 20:33:57.689684 systemd[1]: Mounting tmp.mount...
Oct 2 20:33:57.689693 systemd[1]: Starting flatcar-tmpfiles.service...
Oct 2 20:33:57.689702 systemd[1]: ignition-delete-config.service was skipped because no trigger condition checks were met.
Oct 2 20:33:57.689711 systemd[1]: Starting kmod-static-nodes.service...
Oct 2 20:33:57.690270 systemd[1]: Starting modprobe@configfs.service...
Oct 2 20:33:57.690287 systemd[1]: Starting modprobe@dm_mod.service...
Oct 2 20:33:57.690297 systemd[1]: Starting modprobe@drm.service...
Oct 2 20:33:57.690306 systemd[1]: Starting modprobe@efi_pstore.service...
Oct 2 20:33:57.690317 systemd[1]: Starting modprobe@fuse.service...
Oct 2 20:33:57.690327 systemd[1]: Starting modprobe@loop.service...
Oct 2 20:33:57.690337 systemd[1]: setup-nsswitch.service was skipped because of an unmet condition check (ConditionPathExists=!/etc/nsswitch.conf).
Oct 2 20:33:57.690346 systemd[1]: systemd-fsck-root.service: Deactivated successfully.
Oct 2 20:33:57.690355 systemd[1]: Stopped systemd-fsck-root.service.
Oct 2 20:33:57.690367 systemd[1]: systemd-fsck-usr.service: Deactivated successfully.
Oct 2 20:33:57.690377 systemd[1]: Stopped systemd-fsck-usr.service.
Oct 2 20:33:57.690386 systemd[1]: Stopped systemd-journald.service.
Oct 2 20:33:57.690396 systemd[1]: systemd-journald.service: Consumed 1.687s CPU time.
Oct 2 20:33:57.690405 systemd[1]: Starting systemd-journald.service...
Oct 2 20:33:57.690414 systemd[1]: Starting systemd-modules-load.service...
Oct 2 20:33:57.690423 systemd[1]: Starting systemd-network-generator.service...
Oct 2 20:33:57.690433 systemd[1]: Starting systemd-remount-fs.service...
Oct 2 20:33:57.690443 systemd[1]: Starting systemd-udev-trigger.service...
Oct 2 20:33:57.690453 systemd[1]: verity-setup.service: Deactivated successfully.
Oct 2 20:33:57.690462 systemd[1]: Stopped verity-setup.service.
Oct 2 20:33:57.690472 systemd[1]: Mounted dev-hugepages.mount.
Oct 2 20:33:57.690481 systemd[1]: Mounted dev-mqueue.mount.
Oct 2 20:33:57.690490 systemd[1]: Mounted sys-kernel-debug.mount.
Oct 2 20:33:57.690500 systemd[1]: Mounted sys-kernel-tracing.mount.
Oct 2 20:33:57.690510 systemd[1]: Mounted media.mount.
Oct 2 20:33:57.690519 systemd[1]: Mounted tmp.mount.
Oct 2 20:33:57.690528 systemd[1]: modprobe@dm_mod.service: Deactivated successfully.
Oct 2 20:33:57.690539 systemd[1]: Finished modprobe@dm_mod.service.
Oct 2 20:33:57.690548 systemd[1]: Finished kmod-static-nodes.service.
Oct 2 20:33:57.690557 systemd[1]: modprobe@drm.service: Deactivated successfully.
Oct 2 20:33:57.690567 systemd[1]: Finished modprobe@drm.service.
Oct 2 20:33:57.690576 systemd[1]: modprobe@efi_pstore.service: Deactivated successfully.
Oct 2 20:33:57.690585 systemd[1]: Finished modprobe@efi_pstore.service.
Oct 2 20:33:57.690595 systemd[1]: Finished systemd-network-generator.service.
Oct 2 20:33:57.690604 systemd[1]: Finished systemd-remount-fs.service.
Oct 2 20:33:57.690615 systemd[1]: Reached target network-pre.target.
Oct 2 20:33:57.690628 systemd[1]: remount-root.service was skipped because of an unmet condition check (ConditionPathIsReadWrite=!/).
Oct 2 20:33:57.690638 systemd[1]: Starting systemd-hwdb-update.service...
Oct 2 20:33:57.690648 systemd[1]: systemd-pstore.service was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore).
Oct 2 20:33:57.690658 systemd[1]: Starting systemd-random-seed.service...
Oct 2 20:33:57.690668 systemd[1]: modprobe@configfs.service: Deactivated successfully.
Oct 2 20:33:57.690698 systemd[1]: Finished modprobe@configfs.service.
Oct 2 20:33:57.690710 systemd[1]: Mounting sys-kernel-config.mount...
Oct 2 20:33:57.698914 kernel: fuse: init (API version 7.34)
Oct 2 20:33:57.698944 systemd[1]: Mounted sys-kernel-config.mount.
Oct 2 20:33:57.698956 systemd[1]: modprobe@fuse.service: Deactivated successfully.
Oct 2 20:33:57.698971 systemd[1]: Finished modprobe@fuse.service.
Oct 2 20:33:57.698981 systemd[1]: Mounting sys-fs-fuse-connections.mount...
Oct 2 20:33:57.698991 systemd[1]: Mounted sys-fs-fuse-connections.mount.
Oct 2 20:33:57.699004 systemd-journald[1106]: Journal started
Oct 2 20:33:57.699057 systemd-journald[1106]: Runtime Journal (/run/log/journal/6a3aef02d6dd40dc891871be79484352) is 8.0M, max 78.6M, 70.6M free.
Oct 2 20:33:44.638000 audit: MAC_POLICY_LOAD auid=4294967295 ses=4294967295 lsm=selinux res=1
Oct 2 20:33:45.119000 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
Oct 2 20:33:45.119000 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
Oct 2 20:33:45.119000 audit: BPF prog-id=10 op=LOAD
Oct 2 20:33:45.119000 audit: BPF prog-id=10 op=UNLOAD
Oct 2 20:33:45.119000 audit: BPF prog-id=11 op=LOAD
Oct 2 20:33:45.119000 audit: BPF prog-id=11 op=UNLOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=12 op=LOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=3 op=UNLOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=13 op=LOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=14 op=LOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=4 op=UNLOAD
Oct 2 20:33:57.564000 audit: BPF prog-id=5 op=UNLOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=15 op=LOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=12 op=UNLOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=16 op=LOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=17 op=LOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=13 op=UNLOAD
Oct 2 20:33:57.565000 audit: BPF prog-id=14 op=UNLOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=18 op=LOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=15 op=UNLOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=19 op=LOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=20 op=LOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=16 op=UNLOAD
Oct 2 20:33:57.566000 audit: BPF prog-id=17 op=UNLOAD
Oct 2 20:33:57.566000 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'
Oct 2 20:33:57.567000 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'
Oct 2 20:33:57.567000 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'
Oct 2 20:33:57.576000 audit: BPF prog-id=18 op=UNLOAD
Oct 2 20:33:57.596000 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'
Oct 2 20:33:57.596000 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'
Oct 2 20:33:57.596000 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'
Oct 2 20:33:57.596000 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'
Oct 2 20:33:57.597000 audit: BPF prog-id=21 op=LOAD
Oct 2 20:33:57.597000 audit: BPF prog-id=22 op=LOAD
Oct 2 20:33:57.597000 audit: BPF prog-id=23 op=LOAD
Oct 2 20:33:57.597000 audit: BPF prog-id=19 op=UNLOAD
Oct 2 20:33:57.700347 systemd[1]: Started systemd-journald.service.
Oct 2 20:33:57.597000 audit: BPF prog-id=20 op=UNLOAD
Oct 2 20:33:57.622000 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'
Oct 2 20:33:57.635000 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'
Oct 2 20:33:57.635000 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'
Oct 2 20:33:57.644000 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'
Oct 2 20:33:57.645000 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'
Oct 2 20:33:57.645000 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'
Oct 2 20:33:57.645000 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'
Oct 2 20:33:57.645000 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'
Oct 2 20:33:57.656000 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'
Oct 2 20:33:57.656000 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'
Oct 2 20:33:57.675000 audit: CONFIG_CHANGE op=set audit_enabled=1 old=1 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 res=1
Oct 2 20:33:57.675000 audit[1106]: SYSCALL arch=c00000b7 syscall=211 success=yes exit=60 a0=6 a1=ffffe256ea20 a2=4000 a3=1 items=0 ppid=1 pid=1106 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)
Oct 2 20:33:57.675000 audit: PROCTITLE proctitle="/usr/lib/systemd/systemd-journald"
Oct 2 20:33:57.678000 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'
Oct 2 20:33:57.678000 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'
Oct 2 20:33:57.689000 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'
Oct 2 20:33:57.689000 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'
Oct 2 20:33:57.698000 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'
Oct 2 20:33:46.666401 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" 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.0 /usr/share/oem/torcx/store /var/lib/torcx/store/3510.3.0 /var/lib/torcx/store]"
Oct 2 20:33:57.563768 systemd[1]: Queued start job for default target multi-user.target.
Oct 2 20:33:46.717906 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Oct 2 20:33:57.567156 systemd[1]: systemd-journald.service: Deactivated successfully.
Oct 2 20:33:46.717927 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Oct 2 20:33:57.567493 systemd[1]: systemd-journald.service: Consumed 1.687s CPU time.
Oct 2 20:33:46.717965 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=info msg="no vendor profile selected by /etc/flatcar/docker-1.12"
Oct 2 20:33:57.700261 systemd[1]: Starting systemd-journal-flush.service...
Oct 2 20:33:46.717975 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="skipped missing lower profile" missing profile=oem
Oct 2 20:33:46.718013 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=warning msg="no next profile: unable to read profile file: open /etc/torcx/next-profile: no such file or directory"
Oct 2 20:33:46.718025 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="apply configuration parsed" lower profiles (vendor/oem)="[vendor]" upper profile (user)=
Oct 2 20:33:46.718219 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="mounted tmpfs" target=/run/torcx/unpack
Oct 2 20:33:46.718248 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="profile found" name=docker-1.12-no path=/usr/share/torcx/profiles/docker-1.12-no.json
Oct 2 20:33:46.718259 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=debug msg="profile found" name=vendor path=/usr/share/torcx/profiles/vendor.json
Oct 2 20:33:46.718615 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" 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
Oct 2 20:33:46.718648 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" 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
Oct 2 20:33:46.718666 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store/3510.3.0: no such file or directory" path=/usr/share/oem/torcx/store/3510.3.0
Oct 2 20:33:46.718679 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=info msg="store skipped" err="open /usr/share/oem/torcx/store: no such file or directory" path=/usr/share/oem/torcx/store
Oct 2 20:33:46.718695 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=info msg="store skipped" err="open /var/lib/torcx/store/3510.3.0: no such file or directory" path=/var/lib/torcx/store/3510.3.0
Oct 2 20:33:46.718708 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:46Z" level=info msg="store skipped" err="open /var/lib/torcx/store: no such file or directory" path=/var/lib/torcx/store
Oct 2 20:33:56.685221 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" level=debug msg="image unpacked" image=docker path=/run/torcx/unpack/docker reference=com.coreos.cl
Oct 2 20:33:56.685486 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" 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
Oct 2 20:33:56.685573 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" 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
Oct 2 20:33:56.685738 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" 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
Oct 2 20:33:56.685788 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" level=debug msg="profile applied" sealed profile=/run/torcx/profile.json upper profile=
Oct 2 20:33:56.685841 /usr/lib/systemd/system-generators/torcx-generator[1025]: time="2023-10-02T20:33:56Z" 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
Oct 2 20:33:57.723387 systemd[1]: modprobe@loop.service: Deactivated successfully.
Oct 2 20:33:57.723535 systemd[1]: Finished modprobe@loop.service.
Oct 2 20:33:57.722000 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'
Oct 2 20:33:57.722000 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'
Oct 2 20:33:57.723780 kernel: loop: module loaded
Oct 2 20:33:57.723760 systemd[1]: systemd-repart.service was skipped because no trigger condition checks were met.
Oct 2 20:33:57.725686 systemd[1]: Finished systemd-modules-load.service.
Oct 2 20:33:57.724000 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'
Oct 2 20:33:57.726821 systemd[1]: Starting systemd-sysctl.service...
Oct 2 20:33:57.728000 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'
Oct 2 20:33:57.729189 systemd[1]: Finished systemd-random-seed.service.
Oct 2 20:33:57.729331 systemd[1]: Reached target first-boot-complete.target.
Oct 2 20:33:57.734866 systemd[1]: Finished flatcar-tmpfiles.service.
Oct 2 20:33:57.734000 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'
Oct 2 20:33:57.735942 systemd[1]: Starting systemd-sysusers.service...
Oct 2 20:33:57.752067 systemd-journald[1106]: Time spent on flushing to /var/log/journal/6a3aef02d6dd40dc891871be79484352 is 16.264ms for 1051 entries.
Oct 2 20:33:57.752067 systemd-journald[1106]: System Journal (/var/log/journal/6a3aef02d6dd40dc891871be79484352) is 8.0M, max 2.6G, 2.6G free.
Oct 2 20:33:57.815240 systemd-journald[1106]: Received client request to flush runtime journal.
Oct 2 20:33:57.757000 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'
Oct 2 20:33:57.758035 systemd[1]: Finished systemd-udev-trigger.service.
Oct 2 20:33:57.759139 systemd[1]: Starting systemd-udev-settle.service...
Oct 2 20:33:57.815619 udevadm[1148]: systemd-udev-settle.service is deprecated. Please fix lvm2-activation.service, lvm2-activation-early.service not to pull it in.
Oct 2 20:33:57.816641 systemd[1]: Finished systemd-journal-flush.service.
Oct 2 20:33:57.815000 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'
Oct 2 20:33:57.832000 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'
Oct 2 20:33:57.833090 systemd[1]: Finished systemd-sysctl.service.
Oct 2 20:33:58.162667 systemd[1]: Finished systemd-sysusers.service.
Oct 2 20:33:58.161000 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'
Oct 2 20:33:58.163869 systemd[1]: Starting systemd-tmpfiles-setup-dev.service...
Oct 2 20:33:58.383000 audit[1]: SERVICE_START pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=systemd-tmpfiles-setup-dev comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:33:58.384374 systemd[1]: Finished systemd-tmpfiles-setup-dev.service.
Oct 2 20:33:58.557593 systemd[1]: Finished systemd-hwdb-update.service.
Oct 2 20:33:58.556000 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'
Oct 2 20:33:58.557000 audit: BPF prog-id=24 op=LOAD
Oct 2 20:33:58.557000 audit: BPF prog-id=25 op=LOAD
Oct 2 20:33:58.557000 audit: BPF prog-id=7 op=UNLOAD
Oct 2 20:33:58.557000 audit: BPF prog-id=8 op=UNLOAD
Oct 2 20:33:58.558892 systemd[1]: Starting systemd-udevd.service...
Oct 2 20:33:58.578108 systemd-udevd[1153]: Using default interface naming scheme 'v252'.
Oct 2 20:33:58.663000 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'
Oct 2 20:33:58.664000 audit: BPF prog-id=26 op=LOAD
Oct 2 20:33:58.664330 systemd[1]: Started systemd-udevd.service.
Oct 2 20:33:58.665958 systemd[1]: Starting systemd-networkd.service...
Oct 2 20:33:58.709789 systemd[1]: Condition check resulted in dev-ttyAMA0.device being skipped.
Oct 2 20:33:58.723000 audit: BPF prog-id=27 op=LOAD
Oct 2 20:33:58.723000 audit: BPF prog-id=28 op=LOAD
Oct 2 20:33:58.723000 audit: BPF prog-id=29 op=LOAD
Oct 2 20:33:58.724926 systemd[1]: Starting systemd-userdbd.service...
Oct 2 20:33:58.781000 audit[1154]: AVC avc: denied { confidentiality } for pid=1154 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
Oct 2 20:33:58.784773 kernel: hv_vmbus: registering driver hv_balloon
Oct 2 20:33:58.786737 kernel: hv_balloon: Using Dynamic Memory protocol version 2.0
Oct 2 20:33:58.786818 kernel: hv_balloon: Memory hot add disabled on ARM64
Oct 2 20:33:58.781000 audit[1154]: SYSCALL arch=c00000b7 syscall=105 success=yes exit=0 a0=aaab1e1f2920 a1=aa2c a2=ffffae0424b0 a3=aaab1e154010 items=10 ppid=1153 pid=1154 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)
Oct 2 20:33:58.781000 audit: CWD cwd="/"
Oct 2 20:33:58.781000 audit: PATH item=0 name=(null) inode=10795 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
Oct 2 20:33:58.781000 audit: PATH item=1 name=(null) inode=10796 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
Oct 2 20:33:58.781000 audit: PATH item=2 name=(null) inode=10795 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
Oct 2 20:33:58.781000 audit: PATH item=3 name=(null) inode=10797 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
Oct 2 20:33:58.781000 audit: PATH item=4 name=(null) inode=10795 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
Oct 2 20:33:58.781000 audit: PATH item=5 name=(null) inode=10798 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
Oct 2 20:33:58.781000 audit: PATH item=6 name=(null) inode=10795 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
Oct 2 20:33:58.781000 audit: PATH item=7 name=(null) inode=10799 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
Oct 2 20:33:58.781000 audit: PATH item=8 name=(null) inode=10795 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
Oct 2 20:33:58.781000 audit: PATH item=9 name=(null) inode=10800 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
Oct 2 20:33:58.781000 audit: PROCTITLE proctitle="(udev-worker)"
Oct 2 20:33:58.792834 kernel: hv_vmbus: registering driver hyperv_fb
Oct 2 20:33:58.793823 kernel: hyperv_fb: Synthvid Version major 3, minor 5
Oct 2 20:33:58.793904 kernel: hyperv_fb: Screen resolution: 1024x768, Color depth: 32, Frame buffer size: 8388608
Oct 2 20:33:58.793933 kernel: Console: switching to colour dummy device 80x25
Oct 2 20:33:58.796449 kernel: Console: switching to colour frame buffer device 128x48
Oct 2 20:33:58.801000 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'
Oct 2 20:33:58.802463 systemd[1]: Started systemd-userdbd.service.
Oct 2 20:33:58.805767 kernel: hv_utils: Registering HyperV Utility Driver
Oct 2 20:33:58.805882 kernel: hv_vmbus: registering driver hv_utils
Oct 2 20:33:58.805921 kernel: hv_utils: Heartbeat IC version 3.0
Oct 2 20:33:58.805954 kernel: hv_utils: Shutdown IC version 3.2
Oct 2 20:33:58.805973 kernel: hv_utils: TimeSync IC version 4.0
Oct 2 20:33:58.754096 kernel: mousedev: PS/2 mouse device common for all mice
Oct 2 20:33:58.801942 systemd-journald[1106]: Time jumped backwards, rotating.
Oct 2 20:33:58.931002 systemd-networkd[1159]: lo: Link UP
Oct 2 20:33:58.931280 systemd-networkd[1159]: lo: Gained carrier
Oct 2 20:33:58.931724 systemd-networkd[1159]: Enumeration completed
Oct 2 20:33:58.930000 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'
Oct 2 20:33:58.931867 systemd[1]: Started systemd-networkd.service.
Oct 2 20:33:58.933148 systemd[1]: Starting systemd-networkd-wait-online.service...
Oct 2 20:33:58.952375 systemd-networkd[1159]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 2 20:33:59.002095 kernel: mlx5_core d4e7:00:02.0 enP54503s1: Link up
Oct 2 20:33:59.024114 kernel: BTRFS info: devid 1 device path /dev/disk/by-label/OEM changed to /dev/sda6 scanned by (udev-worker) (1166)
Oct 2 20:33:59.039094 kernel: hv_netvsc 000d3a6e-6de4-000d-3a6e-6de4000d3a6e eth0: Data path switched to VF: enP54503s1
Oct 2 20:33:59.040207 systemd-networkd[1159]: enP54503s1: Link UP
Oct 2 20:33:59.040293 systemd-networkd[1159]: eth0: Link UP
Oct 2 20:33:59.040296 systemd-networkd[1159]: eth0: Gained carrier
Oct 2 20:33:59.045332 systemd-networkd[1159]: enP54503s1: Gained carrier
Oct 2 20:33:59.049198 systemd-networkd[1159]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16
Oct 2 20:33:59.068873 systemd[1]: Found device dev-disk-by\x2dlabel-OEM.device.
Oct 2 20:33:59.071000 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'
Oct 2 20:33:59.072437 systemd[1]: Finished systemd-udev-settle.service.
Oct 2 20:33:59.073881 systemd[1]: Starting lvm2-activation-early.service...
Oct 2 20:33:59.267719 lvm[1232]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Oct 2 20:33:59.303248 systemd[1]: Finished lvm2-activation-early.service.
Oct 2 20:33:59.302000 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'
Oct 2 20:33:59.303391 systemd[1]: Reached target cryptsetup.target.
Oct 2 20:33:59.304787 systemd[1]: Starting lvm2-activation.service...
Oct 2 20:33:59.310204 lvm[1233]: WARNING: Failed to connect to lvmetad. Falling back to device scanning.
Oct 2 20:33:59.337153 systemd[1]: Finished lvm2-activation.service.
Oct 2 20:33:59.336000 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'
Oct 2 20:33:59.337294 systemd[1]: Reached target local-fs-pre.target.
Oct 2 20:33:59.337331 systemd[1]: var-lib-machines.mount was skipped because of an unmet condition check (ConditionPathExists=/var/lib/machines.raw).
Oct 2 20:33:59.337351 systemd[1]: Reached target local-fs.target.
Oct 2 20:33:59.337375 systemd[1]: Reached target machines.target.
Oct 2 20:33:59.338789 systemd[1]: Starting ldconfig.service...
Oct 2 20:33:59.339124 systemd[1]: systemd-binfmt.service was skipped because no trigger condition checks were met.
Oct 2 20:33:59.339186 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).
Oct 2 20:33:59.340316 systemd[1]: Starting systemd-boot-update.service...
Oct 2 20:33:59.341247 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-OEM.service...
Oct 2 20:33:59.342334 systemd[1]: Starting systemd-machine-id-commit.service...
Oct 2 20:33:59.342446 systemd[1]: systemd-sysext.service was skipped because no trigger condition checks were met.
Oct 2 20:33:59.342489 systemd[1]: ensure-sysext.service was skipped because no trigger condition checks were met.
Oct 2 20:33:59.345519 systemd[1]: Starting systemd-tmpfiles-setup.service...
Oct 2 20:33:59.368851 systemd[1]: boot.automount: Got automount request for /boot, triggered by 1235 (bootctl)
Oct 2 20:33:59.370348 systemd[1]: Starting systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service...
Oct 2 20:33:59.372831 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/legacy.conf:13: Duplicate line for path "/run/lock", ignoring.
Oct 2 20:33:59.384936 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/provision.conf:20: Duplicate line for path "/root", ignoring.
Oct 2 20:33:59.400018 systemd-tmpfiles[1238]: /usr/lib/tmpfiles.d/systemd.conf:29: Duplicate line for path "/var/lib/systemd", ignoring.
Oct 2 20:33:59.402054 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-OEM.service.
Oct 2 20:33:59.401000 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'
Oct 2 20:33:59.413568 systemd[1]: etc-machine\x2did.mount: Deactivated successfully.
Oct 2 20:33:59.414907 systemd[1]: Finished systemd-machine-id-commit.service.
Oct 2 20:33:59.413000 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'
Oct 2 20:33:59.604144 systemd-fsck[1243]: fsck.fat 4.2 (2021-01-31)
Oct 2 20:33:59.604144 systemd-fsck[1243]: /dev/sda1: 236 files, 113463/258078 clusters
Oct 2 20:33:59.606271 systemd[1]: Finished systemd-fsck@dev-disk-by\x2dlabel-EFI\x2dSYSTEM.service.
Oct 2 20:33:59.605000 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'
Oct 2 20:33:59.607461 systemd[1]: Mounting boot.mount...
Oct 2 20:33:59.621371 systemd[1]: Mounted boot.mount.
Oct 2 20:33:59.628528 systemd[1]: Finished systemd-boot-update.service.
Oct 2 20:33:59.627000 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'
Oct 2 20:33:59.772447 systemd[1]: Finished systemd-tmpfiles-setup.service.
Oct 2 20:33:59.771000 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'
Oct 2 20:33:59.773694 systemd[1]: Starting audit-rules.service...
Oct 2 20:33:59.774670 systemd[1]: Starting clean-ca-certificates.service...
Oct 2 20:33:59.776868 systemd[1]: Starting systemd-journal-catalog-update.service...
Oct 2 20:33:59.776000 audit: BPF prog-id=30 op=LOAD
Oct 2 20:33:59.778000 audit: BPF prog-id=31 op=LOAD
Oct 2 20:33:59.778675 systemd[1]: Starting systemd-resolved.service...
Oct 2 20:33:59.780591 systemd[1]: Starting systemd-timesyncd.service...
Oct 2 20:33:59.782051 systemd[1]: Starting systemd-update-utmp.service...
Oct 2 20:33:59.813000 audit[1254]: SYSTEM_BOOT pid=1254 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'
Oct 2 20:33:59.817414 systemd[1]: Finished systemd-update-utmp.service.
Oct 2 20:33:59.816000 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'
Oct 2 20:33:59.821164 systemd[1]: Finished clean-ca-certificates.service.
Oct 2 20:33:59.820000 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'
Oct 2 20:33:59.821265 systemd[1]: update-ca-certificates.service was skipped because of an unmet condition check (ConditionPathIsSymbolicLink=!/etc/ssl/certs/ca-certificates.crt).
Oct 2 20:33:59.860000 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'
Oct 2 20:33:59.861801 systemd[1]: Started systemd-timesyncd.service.
Oct 2 20:33:59.861907 systemd[1]: Reached target time-set.target.
Oct 2 20:33:59.871866 systemd[1]: Finished systemd-journal-catalog-update.service.
Oct 2 20:33:59.870000 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'
Oct 2 20:33:59.916804 systemd-resolved[1252]: Positive Trust Anchors:
Oct 2 20:33:59.916818 systemd-resolved[1252]: . IN DS 20326 8 2 e06d44b80b8f1d39a95c0b0d7c65d08458e880409bbc683457104237c7f8ec8d
Oct 2 20:33:59.916848 systemd-resolved[1252]: 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
Oct 2 20:33:59.966145 systemd-resolved[1252]: Using system hostname 'ci-3510.3.0-a-42e9e120c2'.
Oct 2 20:33:59.967880 systemd[1]: Started systemd-resolved.service.
Oct 2 20:33:59.966000 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'
Oct 2 20:33:59.967982 systemd[1]: Reached target network.target.
Oct 2 20:33:59.968000 systemd[1]: Reached target nss-lookup.target.
Oct 2 20:34:00.048000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=add_rule key=(null) list=5 res=1
Oct 2 20:34:00.048000 audit[1270]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffcf6f1b90 a2=420 a3=0 items=0 ppid=1249 pid=1270 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)
Oct 2 20:34:00.048000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D52002F6574632F61756469742F61756469742E72756C6573
Oct 2 20:34:00.061525 augenrules[1270]: No rules
Oct 2 20:34:00.062569 systemd[1]: Finished audit-rules.service.
Oct 2 20:34:00.295537 systemd-timesyncd[1253]: Contacted time server 154.16.245.246:123 (0.flatcar.pool.ntp.org).
Oct 2 20:34:00.295706 systemd-timesyncd[1253]: Initial clock synchronization to Mon 2023-10-02 20:34:00.241162 UTC.
Oct 2 20:34:00.382206 systemd-networkd[1159]: eth0: Gained IPv6LL
Oct 2 20:34:00.384432 systemd[1]: Finished systemd-networkd-wait-online.service.
Oct 2 20:34:00.384538 systemd[1]: Reached target network-online.target.
Oct 2 20:34:06.778220 ldconfig[1234]: /sbin/ldconfig: /lib/ld.so.conf is not an ELF file - it has the wrong magic bytes at the start.
Oct 2 20:34:06.821047 systemd[1]: Finished ldconfig.service.
Oct 2 20:34:06.822356 systemd[1]: Starting systemd-update-done.service...
Oct 2 20:34:06.992045 systemd[1]: Finished systemd-update-done.service.
Oct 2 20:34:06.992190 systemd[1]: Reached target sysinit.target.
Oct 2 20:34:06.992284 systemd[1]: Started motdgen.path.
Oct 2 20:34:06.992332 systemd[1]: Started user-cloudinit@var-lib-flatcar\x2dinstall-user_data.path.
Oct 2 20:34:06.992490 systemd[1]: Started logrotate.timer.
Oct 2 20:34:06.992587 systemd[1]: Started mdadm.timer.
Oct 2 20:34:06.992606 systemd[1]: Started systemd-tmpfiles-clean.timer.
Oct 2 20:34:06.992621 systemd[1]: update-engine-stub.timer was skipped because of an unmet condition check (ConditionPathExists=/usr/.noupdate).
Oct 2 20:34:06.992638 systemd[1]: Reached target paths.target.
Oct 2 20:34:06.992651 systemd[1]: Reached target timers.target.
Oct 2 20:34:06.992920 systemd[1]: Listening on dbus.socket.
Oct 2 20:34:06.993824 systemd[1]: Starting docker.socket...
Oct 2 20:34:06.996870 systemd[1]: Listening on sshd.socket.
Oct 2 20:34:06.996993 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).
Oct 2 20:34:06.997430 systemd[1]: Listening on docker.socket.
Oct 2 20:34:06.997509 systemd[1]: Reached target sockets.target.
Oct 2 20:34:06.997530 systemd[1]: Reached target basic.target.
Oct 2 20:34:06.997584 systemd[1]: addon-config@usr-share-oem.service was skipped because no trigger condition checks were met.
Oct 2 20:34:06.997602 systemd[1]: addon-run@usr-share-oem.service was skipped because no trigger condition checks were met.
Oct 2 20:34:06.998711 systemd[1]: Starting containerd.service...
Oct 2 20:34:06.999708 systemd[1]: Starting dbus.service...
Oct 2 20:34:07.001585 systemd[1]: Starting enable-oem-cloudinit.service...
Oct 2 20:34:07.003557 systemd[1]: Starting extend-filesystems.service...
Oct 2 20:34:07.003630 systemd[1]: flatcar-setup-environment.service was skipped because of an unmet condition check (ConditionPathExists=/usr/share/oem/bin/flatcar-setup-environment).
Oct 2 20:34:07.005766 systemd[1]: Starting motdgen.service...
Oct 2 20:34:07.007242 systemd[1]: Started nvidia.service.
Oct 2 20:34:07.008241 systemd[1]: Starting ssh-key-proc-cmdline.service...
Oct 2 20:34:07.009677 systemd[1]: Starting sshd-keygen.service...
Oct 2 20:34:07.012591 systemd[1]: Starting systemd-logind.service...
Oct 2 20:34:07.012640 systemd[1]: systemd-pcrphase.service was skipped because of an unmet condition check (ConditionPathExists=/sys/firmware/efi/efivars/StubPcrKernelImage-4a67b082-0a4c-41cf-b6c7-440b29bb8c4f).
Oct 2 20:34:07.012688 systemd[1]: tcsd.service was skipped because of an unmet condition check (ConditionPathExists=/dev/tpm0).
Oct 2 20:34:07.013035 systemd[1]: cgroup compatibility translation between legacy and unified hierarchy settings activated. See cgroup-compat debug messages for details.
Oct 2 20:34:07.014180 systemd[1]: Starting update-engine.service...
Oct 2 20:34:07.015572 systemd[1]: Starting update-ssh-keys-after-ignition.service...
Oct 2 20:34:07.028707 systemd[1]: ssh-key-proc-cmdline.service: Deactivated successfully.
Oct 2 20:34:07.028852 systemd[1]: Finished ssh-key-proc-cmdline.service.
Oct 2 20:34:07.275965 extend-filesystems[1281]: Found sda
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda1
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda2
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda3
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found usr
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda4
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda6
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda7
Oct 2 20:34:07.279783 extend-filesystems[1281]: Found sda9
Oct 2 20:34:07.279783 extend-filesystems[1281]: Checking size of /dev/sda9
Oct 2 20:34:07.351353 systemd[1]: motdgen.service: Deactivated successfully.
Oct 2 20:34:07.351515 systemd[1]: Finished motdgen.service.
Oct 2 20:34:07.385223 systemd-logind[1288]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard)
Oct 2 20:34:07.386210 systemd-logind[1288]: New seat seat0.
Oct 2 20:34:07.511873 jq[1280]: false
Oct 2 20:34:07.512187 jq[1290]: true
Oct 2 20:34:07.549671 env[1300]: time="2023-10-02T20:34:07.549571572Z" level=info msg="starting containerd" revision=92b3a9d6f1b3bcc6dc74875cfdea653fe39f09c2 version=1.6.16
Oct 2 20:34:07.565561 env[1300]: time="2023-10-02T20:34:07.565513082Z" level=info msg="loading plugin \"io.containerd.content.v1.content\"..." type=io.containerd.content.v1
Oct 2 20:34:07.565691 env[1300]: time="2023-10-02T20:34:07.565668568Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.aufs\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.567546 env[1300]: time="2023-10-02T20:34:07.567509390Z" 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.132-flatcar\\n\"): skip plugin" type=io.containerd.snapshotter.v1
Oct 2 20:34:07.567546 env[1300]: time="2023-10-02T20:34:07.567541253Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.btrfs\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.567769 env[1300]: time="2023-10-02T20:34:07.567741482Z" 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
Oct 2 20:34:07.567769 env[1300]: time="2023-10-02T20:34:07.567766685Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.devmapper\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.567826 env[1300]: time="2023-10-02T20:34:07.567781081Z" level=warning msg="failed to load plugin io.containerd.snapshotter.v1.devmapper" error="devmapper not configured"
Oct 2 20:34:07.567826 env[1300]: time="2023-10-02T20:34:07.567790452Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.native\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.567881 env[1300]: time="2023-10-02T20:34:07.567860877Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.overlayfs\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.568149 env[1300]: time="2023-10-02T20:34:07.568125430Z" level=info msg="loading plugin \"io.containerd.snapshotter.v1.zfs\"..." type=io.containerd.snapshotter.v1
Oct 2 20:34:07.568277 env[1300]: time="2023-10-02T20:34:07.568253120Z" 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
Oct 2 20:34:07.568277 env[1300]: time="2023-10-02T20:34:07.568274336Z" level=info msg="loading plugin \"io.containerd.metadata.v1.bolt\"..." type=io.containerd.metadata.v1
Oct 2 20:34:07.568351 env[1300]: time="2023-10-02T20:34:07.568329647Z" level=warning msg="could not use snapshotter devmapper in metadata plugin" error="devmapper not configured"
Oct 2 20:34:07.568351 env[1300]: time="2023-10-02T20:34:07.568348230Z" level=info msg="metadata content store policy set" policy=shared
Oct 2 20:34:07.823660 systemd[1]: enable-oem-cloudinit.service: Skipped due to 'exec-condition'.
Oct 2 20:34:07.823844 systemd[1]: Condition check resulted in enable-oem-cloudinit.service being skipped.
Oct 2 20:34:07.931844 jq[1330]: true
Oct 2 20:34:07.993020 extend-filesystems[1281]: Old size kept for /dev/sda9
Oct 2 20:34:08.016839 extend-filesystems[1281]: Found sr0
Oct 2 20:34:07.993693 systemd[1]: extend-filesystems.service: Deactivated successfully.
Oct 2 20:34:07.993875 systemd[1]: Finished extend-filesystems.service.
Oct 2 20:34:08.521328 env[1300]: time="2023-10-02T20:34:08.521257509Z" level=info msg="loading plugin \"io.containerd.differ.v1.walking\"..." type=io.containerd.differ.v1
Oct 2 20:34:08.521328 env[1300]: time="2023-10-02T20:34:08.521306817Z" level=info msg="loading plugin \"io.containerd.event.v1.exchange\"..." type=io.containerd.event.v1
Oct 2 20:34:08.521328 env[1300]: time="2023-10-02T20:34:08.521320381Z" level=info msg="loading plugin \"io.containerd.gc.v1.scheduler\"..." type=io.containerd.gc.v1
Oct 2 20:34:08.521533 env[1300]: time="2023-10-02T20:34:08.521356485Z" level=info msg="loading plugin \"io.containerd.service.v1.introspection-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521533 env[1300]: time="2023-10-02T20:34:08.521374756Z" level=info msg="loading plugin \"io.containerd.service.v1.containers-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521533 env[1300]: time="2023-10-02T20:34:08.521388519Z" level=info msg="loading plugin \"io.containerd.service.v1.content-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521533 env[1300]: time="2023-10-02T20:34:08.521402362Z" level=info msg="loading plugin \"io.containerd.service.v1.diff-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521744 env[1300]: time="2023-10-02T20:34:08.521710938Z" level=info msg="loading plugin \"io.containerd.service.v1.images-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521744 env[1300]: time="2023-10-02T20:34:08.521737827Z" level=info msg="loading plugin \"io.containerd.service.v1.leases-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521794 env[1300]: time="2023-10-02T20:34:08.521753305Z" level=info msg="loading plugin \"io.containerd.service.v1.namespaces-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521794 env[1300]: time="2023-10-02T20:34:08.521766151Z" level=info msg="loading plugin \"io.containerd.service.v1.snapshots-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.521794 env[1300]: time="2023-10-02T20:34:08.521779236Z" level=info msg="loading plugin \"io.containerd.runtime.v1.linux\"..." type=io.containerd.runtime.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.521933345Z" level=info msg="loading plugin \"io.containerd.runtime.v2.task\"..." type=io.containerd.runtime.v2
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522015725Z" level=info msg="loading plugin \"io.containerd.monitor.v1.cgroups\"..." type=io.containerd.monitor.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522273397Z" level=info msg="loading plugin \"io.containerd.service.v1.tasks-service\"..." type=io.containerd.service.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522301203Z" level=info msg="loading plugin \"io.containerd.grpc.v1.introspection\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522314328Z" level=info msg="loading plugin \"io.containerd.internal.v1.restart\"..." type=io.containerd.internal.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522383782Z" level=info msg="loading plugin \"io.containerd.grpc.v1.containers\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522396668Z" level=info msg="loading plugin \"io.containerd.grpc.v1.content\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522408277Z" level=info msg="loading plugin \"io.containerd.grpc.v1.diff\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522419168Z" level=info msg="loading plugin \"io.containerd.grpc.v1.events\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522430897Z" level=info msg="loading plugin \"io.containerd.grpc.v1.healthcheck\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522443663Z" level=info msg="loading plugin \"io.containerd.grpc.v1.images\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522456229Z" level=info msg="loading plugin \"io.containerd.grpc.v1.leases\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522467200Z" level=info msg="loading plugin \"io.containerd.grpc.v1.namespaces\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522481003Z" level=info msg="loading plugin \"io.containerd.internal.v1.opt\"..." type=io.containerd.internal.v1
Oct 2 20:34:08.523515 env[1300]: time="2023-10-02T20:34:08.522607505Z" level=info msg="loading plugin \"io.containerd.grpc.v1.snapshots\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522623303Z" level=info msg="loading plugin \"io.containerd.grpc.v1.tasks\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522635590Z" level=info msg="loading plugin \"io.containerd.grpc.v1.version\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522649593Z" level=info msg="loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." type=io.containerd.tracing.processor.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522663875Z" level=info msg="skip loading plugin \"io.containerd.tracing.processor.v1.otlp\"..." error="no OpenTelemetry endpoint: skip plugin" type=io.containerd.tracing.processor.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522674008Z" level=info msg="loading plugin \"io.containerd.internal.v1.tracing\"..." type=io.containerd.internal.v1
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522690763Z" level=error msg="failed to initialize a tracing processor \"otlp\"" error="no OpenTelemetry endpoint: skip plugin"
Oct 2 20:34:08.523819 env[1300]: time="2023-10-02T20:34:08.522723715Z" level=info msg="loading plugin \"io.containerd.grpc.v1.cri\"..." type=io.containerd.grpc.v1
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.522935589Z" 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}"
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.522993635Z" level=info msg="Connect containerd service"
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.523030616Z" level=info msg="Get image filesystem path \"/var/lib/containerd/io.containerd.snapshotter.v1.overlayfs\""
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.523602130Z" 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"
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.523915215Z" level=info msg=serving... address=/run/containerd/containerd.sock.ttrpc
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.523950281Z" level=info msg=serving... address=/run/containerd/containerd.sock
Oct 2 20:34:08.523974 env[1300]: time="2023-10-02T20:34:08.523998632Z" level=info msg="containerd successfully booted in 0.976293s"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525024294Z" level=info msg="Start subscribing containerd event"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525237126Z" level=info msg="Start recovering state"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525311727Z" level=info msg="Start event monitor"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525383894Z" level=info msg="Start snapshots syncer"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525398017Z" level=info msg="Start cni network conf syncer for default"
Oct 2 20:34:08.577458 env[1300]: time="2023-10-02T20:34:08.525461088Z" level=info msg="Start streaming server"
Oct 2 20:34:08.524090 systemd[1]: Started containerd.service.
Oct 2 20:34:08.645784 systemd[1]: nvidia.service: Deactivated successfully.
Oct 2 20:34:09.125695 bash[1345]: Updated "/home/core/.ssh/authorized_keys"
Oct 2 20:34:09.126495 systemd[1]: Finished update-ssh-keys-after-ignition.service.
Oct 2 20:34:09.131607 dbus-daemon[1279]: [system] SELinux support is enabled
Oct 2 20:34:09.131761 systemd[1]: Started dbus.service.
Oct 2 20:34:09.133329 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).
Oct 2 20:34:09.133351 systemd[1]: Reached target system-config.target.
Oct 2 20:34:09.133399 systemd[1]: user-cloudinit-proc-cmdline.service was skipped because of an unmet condition check (ConditionKernelCommandLine=cloud-config-url).
Oct 2 20:34:09.133409 systemd[1]: Reached target user-config.target.
Oct 2 20:34:09.136793 systemd[1]: Started systemd-logind.service.
Oct 2 20:34:09.667247 update_engine[1289]: I1002 20:34:09.527837 1289 main.cc:92] Flatcar Update Engine starting
Oct 2 20:34:09.705594 systemd[1]: Started update-engine.service.
Oct 2 20:34:09.707399 systemd[1]: Started locksmithd.service.
Oct 2 20:34:09.708413 update_engine[1289]: I1002 20:34:09.708390 1289 update_check_scheduler.cc:74] Next update check in 10m24s
Oct 2 20:34:10.785056 sshd_keygen[1295]: ssh-keygen: generating new host keys: RSA ECDSA ED25519
Oct 2 20:34:10.811249 systemd[1]: Finished sshd-keygen.service.
Oct 2 20:34:10.812675 systemd[1]: Starting issuegen.service...
Oct 2 20:34:10.813692 systemd[1]: Started waagent.service.
Oct 2 20:34:10.822761 systemd[1]: issuegen.service: Deactivated successfully.
Oct 2 20:34:10.822921 systemd[1]: Finished issuegen.service.
Oct 2 20:34:10.824258 systemd[1]: Starting systemd-user-sessions.service...
Oct 2 20:34:10.876728 systemd[1]: Finished systemd-user-sessions.service.
Oct 2 20:34:10.878215 systemd[1]: Started getty@tty1.service.
Oct 2 20:34:10.879350 systemd[1]: Started serial-getty@ttyAMA0.service.
Oct 2 20:34:10.879513 systemd[1]: Reached target getty.target.
Oct 2 20:34:10.879588 systemd[1]: Reached target multi-user.target.
Oct 2 20:34:10.880755 systemd[1]: Starting systemd-update-utmp-runlevel.service...
Oct 2 20:34:10.891009 systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully.
Oct 2 20:34:10.891185 systemd[1]: Finished systemd-update-utmp-runlevel.service.
Oct 2 20:34:10.891301 systemd[1]: Startup finished in 436ms (kernel) + 4.818s (initrd) + 26.596s (userspace) = 31.852s.
Oct 2 20:34:11.584959 locksmithd[1378]: locksmithd starting currentOperation="UPDATE_STATUS_IDLE" strategy="reboot"
Oct 2 20:34:12.190460 login[1396]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Oct 2 20:34:12.191861 login[1397]: pam_unix(login:session): session opened for user core(uid=500) by LOGIN(uid=0)
Oct 2 20:34:12.239330 systemd[1]: Created slice user-500.slice.
Oct 2 20:34:12.240669 systemd[1]: Starting user-runtime-dir@500.service...
Oct 2 20:34:12.243000 systemd-logind[1288]: New session 2 of user core.
Oct 2 20:34:12.245677 systemd-logind[1288]: New session 1 of user core.
Oct 2 20:34:12.292637 systemd[1]: Finished user-runtime-dir@500.service.
Oct 2 20:34:12.294117 systemd[1]: Starting user@500.service...
Oct 2 20:34:12.617426 (systemd)[1400]: pam_unix(systemd-user:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:12.801159 systemd[1400]: Queued start job for default target default.target.
Oct 2 20:34:12.801642 systemd[1400]: Reached target paths.target.
Oct 2 20:34:12.801662 systemd[1400]: Reached target sockets.target.
Oct 2 20:34:12.801673 systemd[1400]: Reached target timers.target.
Oct 2 20:34:12.801683 systemd[1400]: Reached target basic.target.
Oct 2 20:34:12.801780 systemd[1]: Started user@500.service.
Oct 2 20:34:12.802614 systemd[1]: Started session-1.scope.
Oct 2 20:34:12.803152 systemd[1]: Started session-2.scope.
Oct 2 20:34:12.803811 systemd[1400]: Reached target default.target.
Oct 2 20:34:12.803959 systemd[1400]: Startup finished in 177ms.
Oct 2 20:34:17.380601 waagent[1391]: 2023-10-02T20:34:17.380495Z INFO Daemon Daemon Azure Linux Agent Version:2.6.0.2
Oct 2 20:34:17.387449 waagent[1391]: 2023-10-02T20:34:17.387372Z INFO Daemon Daemon OS: flatcar 3510.3.0
Oct 2 20:34:17.392432 waagent[1391]: 2023-10-02T20:34:17.392365Z INFO Daemon Daemon Python: 3.9.16
Oct 2 20:34:17.397008 waagent[1391]: 2023-10-02T20:34:17.396937Z INFO Daemon Daemon Run daemon
Oct 2 20:34:17.401861 waagent[1391]: 2023-10-02T20:34:17.401797Z INFO Daemon Daemon No RDMA handler exists for distro='Flatcar Container Linux by Kinvolk' version='3510.3.0'
Oct 2 20:34:17.422608 waagent[1391]: 2023-10-02T20:34:17.422473Z 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.
Oct 2 20:34:17.438676 waagent[1391]: 2023-10-02T20:34:17.438538Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Oct 2 20:34:17.449530 waagent[1391]: 2023-10-02T20:34:17.449444Z INFO Daemon Daemon cloud-init is enabled: False
Oct 2 20:34:17.455039 waagent[1391]: 2023-10-02T20:34:17.454962Z INFO Daemon Daemon Using waagent for provisioning
Oct 2 20:34:17.461270 waagent[1391]: 2023-10-02T20:34:17.461196Z INFO Daemon Daemon Activate resource disk
Oct 2 20:34:17.466860 waagent[1391]: 2023-10-02T20:34:17.466791Z INFO Daemon Daemon Searching gen1 prefix 00000000-0001 or gen2 f8b3781a-1e82-4818-a1c3-63d806ec15bb
Oct 2 20:34:17.482196 waagent[1391]: 2023-10-02T20:34:17.482121Z INFO Daemon Daemon Found device: None
Oct 2 20:34:17.487369 waagent[1391]: 2023-10-02T20:34:17.487299Z ERROR Daemon Daemon Failed to mount resource disk [ResourceDiskError] unable to detect disk topology
Oct 2 20:34:17.496829 waagent[1391]: 2023-10-02T20:34:17.496760Z ERROR Daemon Daemon Event: name=WALinuxAgent, op=ActivateResourceDisk, message=[ResourceDiskError] unable to detect disk topology, duration=0
Oct 2 20:34:17.510160 waagent[1391]: 2023-10-02T20:34:17.510090Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Oct 2 20:34:17.516488 waagent[1391]: 2023-10-02T20:34:17.516423Z INFO Daemon Daemon Running default provisioning handler
Oct 2 20:34:17.533494 waagent[1391]: 2023-10-02T20:34:17.533351Z 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.
Oct 2 20:34:17.550561 waagent[1391]: 2023-10-02T20:34:17.550427Z INFO Daemon Daemon Unable to get cloud-init enabled status from service: [Errno 2] No such file or directory: 'service'
Oct 2 20:34:17.561553 waagent[1391]: 2023-10-02T20:34:17.561471Z INFO Daemon Daemon cloud-init is enabled: False
Oct 2 20:34:17.567428 waagent[1391]: 2023-10-02T20:34:17.567340Z INFO Daemon Daemon Copying ovf-env.xml
Oct 2 20:34:17.636283 waagent[1391]: 2023-10-02T20:34:17.636060Z INFO Daemon Daemon Successfully mounted dvd
Oct 2 20:34:17.792658 systemd[1]: mnt-cdrom-secure.mount: Deactivated successfully.
Oct 2 20:34:17.821854 waagent[1391]: 2023-10-02T20:34:17.821718Z INFO Daemon Daemon Detect protocol endpoint
Oct 2 20:34:17.826986 waagent[1391]: 2023-10-02T20:34:17.826902Z INFO Daemon Daemon Clean protocol and wireserver endpoint
Oct 2 20:34:17.833230 waagent[1391]: 2023-10-02T20:34:17.833152Z INFO Daemon Daemon WireServer endpoint is not found. Rerun dhcp handler
Oct 2 20:34:17.839758 waagent[1391]: 2023-10-02T20:34:17.839692Z INFO Daemon Daemon Test for route to 168.63.129.16
Oct 2 20:34:17.845219 waagent[1391]: 2023-10-02T20:34:17.845159Z INFO Daemon Daemon Route to 168.63.129.16 exists
Oct 2 20:34:17.850635 waagent[1391]: 2023-10-02T20:34:17.850571Z INFO Daemon Daemon Wire server endpoint:168.63.129.16
Oct 2 20:34:17.970690 waagent[1391]: 2023-10-02T20:34:17.970567Z INFO Daemon Daemon Fabric preferred wire protocol version:2015-04-05
Oct 2 20:34:17.977533 waagent[1391]: 2023-10-02T20:34:17.977492Z INFO Daemon Daemon Wire protocol version:2012-11-30
Oct 2 20:34:17.982654 waagent[1391]: 2023-10-02T20:34:17.982599Z INFO Daemon Daemon Server preferred version:2015-04-05
Oct 2 20:34:18.719967 waagent[1391]: 2023-10-02T20:34:18.719808Z INFO Daemon Daemon Initializing goal state during protocol detection
Oct 2 20:34:18.734573 waagent[1391]: 2023-10-02T20:34:18.734498Z INFO Daemon Daemon Forcing an update of the goal state..
Oct 2 20:34:18.740368 waagent[1391]: 2023-10-02T20:34:18.740307Z INFO Daemon Daemon Fetching goal state [incarnation 1]
Oct 2 20:34:18.822409 waagent[1391]: 2023-10-02T20:34:18.822276Z INFO Daemon Daemon Found private key matching thumbprint 7A2AD7AFD3CF1F3E86F596CB61E9F9A0610DD078
Oct 2 20:34:18.830947 waagent[1391]: 2023-10-02T20:34:18.830862Z INFO Daemon Daemon Certificate with thumbprint 2D1122B0449236FAD9E0FBB4D320A3E919074055 has no matching private key.
Oct 2 20:34:18.840582 waagent[1391]: 2023-10-02T20:34:18.840509Z INFO Daemon Daemon Fetch goal state completed
Oct 2 20:34:18.910382 waagent[1391]: 2023-10-02T20:34:18.910323Z INFO Daemon Daemon Fetched new vmSettings [correlation ID: 0c644fe4-5fc4-4f73-9641-8c86c49d01e0 New eTag: 4594207848204105010]
Oct 2 20:34:18.921563 waagent[1391]: 2023-10-02T20:34:18.921482Z INFO Daemon Daemon Status Blob type 'None' is not valid, assuming BlockBlob
Oct 2 20:34:18.936897 waagent[1391]: 2023-10-02T20:34:18.936831Z INFO Daemon Daemon Starting provisioning
Oct 2 20:34:18.941911 waagent[1391]: 2023-10-02T20:34:18.941845Z INFO Daemon Daemon Handle ovf-env.xml.
Oct 2 20:34:18.946589 waagent[1391]: 2023-10-02T20:34:18.946529Z INFO Daemon Daemon Set hostname [ci-3510.3.0-a-42e9e120c2]
Oct 2 20:34:18.989276 waagent[1391]: 2023-10-02T20:34:18.989146Z INFO Daemon Daemon Publish hostname [ci-3510.3.0-a-42e9e120c2]
Oct 2 20:34:18.996503 waagent[1391]: 2023-10-02T20:34:18.996422Z INFO Daemon Daemon Examine /proc/net/route for primary interface
Oct 2 20:34:19.003617 waagent[1391]: 2023-10-02T20:34:19.003554Z INFO Daemon Daemon Primary interface is [eth0]
Oct 2 20:34:19.022931 systemd[1]: systemd-networkd-wait-online.service: Deactivated successfully.
Oct 2 20:34:19.023124 systemd[1]: Stopped systemd-networkd-wait-online.service.
Oct 2 20:34:19.023182 systemd[1]: Stopping systemd-networkd-wait-online.service...
Oct 2 20:34:19.023414 systemd[1]: Stopping systemd-networkd.service...
Oct 2 20:34:19.029129 systemd-networkd[1159]: eth0: DHCPv6 lease lost
Oct 2 20:34:19.031117 systemd[1]: systemd-networkd.service: Deactivated successfully.
Oct 2 20:34:19.031290 systemd[1]: Stopped systemd-networkd.service.
Oct 2 20:34:19.033813 systemd[1]: Starting systemd-networkd.service...
Oct 2 20:34:19.064938 systemd-networkd[1447]: enP54503s1: Link UP
Oct 2 20:34:19.064950 systemd-networkd[1447]: enP54503s1: Gained carrier
Oct 2 20:34:19.065957 systemd-networkd[1447]: eth0: Link UP
Oct 2 20:34:19.065968 systemd-networkd[1447]: eth0: Gained carrier
Oct 2 20:34:19.066285 systemd-networkd[1447]: lo: Link UP
Oct 2 20:34:19.066294 systemd-networkd[1447]: lo: Gained carrier
Oct 2 20:34:19.066516 systemd-networkd[1447]: eth0: Gained IPv6LL
Oct 2 20:34:19.067652 systemd-networkd[1447]: Enumeration completed
Oct 2 20:34:19.067757 systemd[1]: Started systemd-networkd.service.
Oct 2 20:34:19.069467 systemd-networkd[1447]: eth0: Configuring with /usr/lib/systemd/network/zz-default.network.
Oct 2 20:34:19.069496 systemd[1]: Starting systemd-networkd-wait-online.service...
Oct 2 20:34:19.076098 waagent[1391]: 2023-10-02T20:34:19.073288Z INFO Daemon Daemon Create user account if not exists
Oct 2 20:34:19.080436 waagent[1391]: 2023-10-02T20:34:19.080349Z INFO Daemon Daemon User core already exists, skip useradd
Oct 2 20:34:19.087294 waagent[1391]: 2023-10-02T20:34:19.087206Z INFO Daemon Daemon Configure sudoer
Oct 2 20:34:19.092881 waagent[1391]: 2023-10-02T20:34:19.092803Z INFO Daemon Daemon Configure sshd
Oct 2 20:34:19.098214 waagent[1391]: 2023-10-02T20:34:19.098147Z INFO Daemon Daemon Deploy ssh public key.
Oct 2 20:34:19.099166 systemd-networkd[1447]: eth0: DHCPv4 address 10.200.20.17/24, gateway 10.200.20.1 acquired from 168.63.129.16
Oct 2 20:34:19.104056 systemd[1]: Finished systemd-networkd-wait-online.service.
Oct 2 20:34:20.363174 waagent[1391]: 2023-10-02T20:34:20.363091Z INFO Daemon Daemon Provisioning complete
Oct 2 20:34:20.381903 waagent[1391]: 2023-10-02T20:34:20.381833Z INFO Daemon Daemon RDMA capabilities are not enabled, skipping
Oct 2 20:34:20.388393 waagent[1391]: 2023-10-02T20:34:20.388314Z INFO Daemon Daemon End of log to /dev/console. The agent will now check for updates and then will process extensions.
Oct 2 20:34:20.400770 waagent[1391]: 2023-10-02T20:34:20.400687Z INFO Daemon Daemon Installed Agent WALinuxAgent-2.6.0.2 is the most current agent
Oct 2 20:34:20.712780 waagent[1456]: 2023-10-02T20:34:20.712619Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 is running as the goal state agent
Oct 2 20:34:20.713889 waagent[1456]: 2023-10-02T20:34:20.713829Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:20.714184 waagent[1456]: 2023-10-02T20:34:20.714128Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:20.727330 waagent[1456]: 2023-10-02T20:34:20.727235Z INFO ExtHandler ExtHandler Forcing an update of the goal state..
Oct 2 20:34:20.727689 waagent[1456]: 2023-10-02T20:34:20.727636Z INFO ExtHandler ExtHandler Fetching goal state [incarnation 1]
Oct 2 20:34:20.809621 waagent[1456]: 2023-10-02T20:34:20.809478Z INFO ExtHandler ExtHandler Found private key matching thumbprint 7A2AD7AFD3CF1F3E86F596CB61E9F9A0610DD078
Oct 2 20:34:20.809990 waagent[1456]: 2023-10-02T20:34:20.809937Z INFO ExtHandler ExtHandler Certificate with thumbprint 2D1122B0449236FAD9E0FBB4D320A3E919074055 has no matching private key.
Oct 2 20:34:20.810382 waagent[1456]: 2023-10-02T20:34:20.810295Z INFO ExtHandler ExtHandler Fetch goal state completed
Oct 2 20:34:20.824581 waagent[1456]: 2023-10-02T20:34:20.824518Z INFO ExtHandler ExtHandler Fetched new vmSettings [correlation ID: bfc3577f-a088-4974-b249-fe0ce8b595e6 New eTag: 4594207848204105010]
Oct 2 20:34:20.825402 waagent[1456]: 2023-10-02T20:34:20.825338Z INFO ExtHandler ExtHandler Status Blob type 'None' is not valid, assuming BlockBlob
Oct 2 20:34:20.888995 waagent[1456]: 2023-10-02T20:34:20.888845Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.0; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1;
Oct 2 20:34:20.899615 waagent[1456]: 2023-10-02T20:34:20.899529Z INFO ExtHandler ExtHandler WALinuxAgent-2.6.0.2 running as process 1456
Oct 2 20:34:20.903594 waagent[1456]: 2023-10-02T20:34:20.903523Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.0', '', 'Flatcar Container Linux by Kinvolk']
Oct 2 20:34:20.905110 waagent[1456]: 2023-10-02T20:34:20.905034Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Oct 2 20:34:20.970702 waagent[1456]: 2023-10-02T20:34:20.970586Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Oct 2 20:34:20.971315 waagent[1456]: 2023-10-02T20:34:20.971252Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Oct 2 20:34:20.982322 waagent[1456]: 2023-10-02T20:34:20.982263Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Oct 2 20:34:20.983010 waagent[1456]: 2023-10-02T20:34:20.982953Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
Oct 2 20:34:20.984368 waagent[1456]: 2023-10-02T20:34:20.984299Z 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]
Oct 2 20:34:20.985958 waagent[1456]: 2023-10-02T20:34:20.985884Z INFO ExtHandler ExtHandler Starting env monitor service.
Oct 2 20:34:20.986285 waagent[1456]: 2023-10-02T20:34:20.986209Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:20.986867 waagent[1456]: 2023-10-02T20:34:20.986806Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:20.987445 waagent[1456]: 2023-10-02T20:34:20.987386Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Oct 2 20:34:20.987751 waagent[1456]: 2023-10-02T20:34:20.987696Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Oct 2 20:34:20.987751 waagent[1456]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT
Oct 2 20:34:20.987751 waagent[1456]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0
Oct 2 20:34:20.987751 waagent[1456]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0
Oct 2 20:34:20.987751 waagent[1456]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:20.987751 waagent[1456]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:20.987751 waagent[1456]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:20.990055 waagent[1456]: 2023-10-02T20:34:20.989897Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Oct 2 20:34:20.990316 waagent[1456]: 2023-10-02T20:34:20.990244Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:20.991030 waagent[1456]: 2023-10-02T20:34:20.990965Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:20.991649 waagent[1456]: 2023-10-02T20:34:20.991576Z INFO EnvHandler ExtHandler Configure routes
Oct 2 20:34:20.991822 waagent[1456]: 2023-10-02T20:34:20.991768Z INFO EnvHandler ExtHandler Gateway:None
Oct 2 20:34:20.991942 waagent[1456]: 2023-10-02T20:34:20.991898Z INFO EnvHandler ExtHandler Routes:None
Oct 2 20:34:20.992541 waagent[1456]: 2023-10-02T20:34:20.992453Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Oct 2 20:34:20.992882 waagent[1456]: 2023-10-02T20:34:20.992816Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Oct 2 20:34:20.993932 waagent[1456]: 2023-10-02T20:34:20.993854Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Oct 2 20:34:20.994210 waagent[1456]: 2023-10-02T20:34:20.994128Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Oct 2 20:34:20.994505 waagent[1456]: 2023-10-02T20:34:20.994440Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Oct 2 20:34:21.006636 waagent[1456]: 2023-10-02T20:34:21.006559Z INFO ExtHandler ExtHandler Checking for agent updates (family: Prod)
Oct 2 20:34:21.007448 waagent[1456]: 2023-10-02T20:34:21.007396Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
Oct 2 20:34:21.008576 waagent[1456]: 2023-10-02T20:34:21.008505Z INFO ExtHandler ExtHandler [PERIODIC] Request failed using the direct channel. Error: 'NoneType' object has no attribute 'getheaders'
Oct 2 20:34:21.032591 waagent[1456]: 2023-10-02T20:34:21.032486Z ERROR EnvHandler ExtHandler Failed to get the PID of the DHCP client: invalid literal for int() with base 10: 'MainPID=1447'
Oct 2 20:34:21.061897 waagent[1456]: 2023-10-02T20:34:21.061821Z INFO ExtHandler ExtHandler Default channel changed to HostGA channel.
Oct 2 20:34:21.114710 waagent[1456]: 2023-10-02T20:34:21.114552Z INFO MonitorHandler ExtHandler Network interfaces:
Oct 2 20:34:21.114710 waagent[1456]: Executing ['ip', '-a', '-o', 'link']:
Oct 2 20:34:21.114710 waagent[1456]: 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
Oct 2 20:34:21.114710 waagent[1456]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6e:6d:e4 brd ff:ff:ff:ff:ff:ff
Oct 2 20:34:21.114710 waagent[1456]: 3: enP54503s1: <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:6e:6d:e4 brd ff:ff:ff:ff:ff:ff\ altname enP54503p0s2
Oct 2 20:34:21.114710 waagent[1456]: Executing ['ip', '-4', '-a', '-o', 'address']:
Oct 2 20:34:21.114710 waagent[1456]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever
Oct 2 20:34:21.114710 waagent[1456]: 2: eth0 inet 10.200.20.17/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever
Oct 2 20:34:21.114710 waagent[1456]: Executing ['ip', '-6', '-a', '-o', 'address']:
Oct 2 20:34:21.114710 waagent[1456]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever
Oct 2 20:34:21.114710 waagent[1456]: 2: eth0 inet6 fe80::20d:3aff:fe6e:6de4/64 scope link \ valid_lft forever preferred_lft forever
Oct 2 20:34:21.210866 waagent[1456]: 2023-10-02T20:34:21.210786Z INFO ExtHandler ExtHandler Agent WALinuxAgent-2.6.0.2 discovered update WALinuxAgent-2.9.1.1 -- exiting
Oct 2 20:34:21.404755 waagent[1391]: 2023-10-02T20:34:21.404576Z 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
Oct 2 20:34:21.408372 waagent[1391]: 2023-10-02T20:34:21.408313Z INFO Daemon Daemon Determined Agent WALinuxAgent-2.9.1.1 to be the latest agent
Oct 2 20:34:22.557065 waagent[1485]: 2023-10-02T20:34:22.556961Z INFO ExtHandler ExtHandler Azure Linux Agent (Goal State Agent version 2.9.1.1)
Oct 2 20:34:22.558148 waagent[1485]: 2023-10-02T20:34:22.558067Z INFO ExtHandler ExtHandler OS: flatcar 3510.3.0
Oct 2 20:34:22.558407 waagent[1485]: 2023-10-02T20:34:22.558356Z INFO ExtHandler ExtHandler Python: 3.9.16
Oct 2 20:34:22.568372 waagent[1485]: 2023-10-02T20:34:22.568257Z INFO ExtHandler ExtHandler Distro: flatcar-3510.3.0; OSUtil: CoreOSUtil; AgentService: waagent; Python: 3.9.16; systemd: True; LISDrivers: Absent; logrotate: logrotate 3.20.1;
Oct 2 20:34:22.568915 waagent[1485]: 2023-10-02T20:34:22.568861Z INFO ExtHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:22.569207 waagent[1485]: 2023-10-02T20:34:22.569151Z INFO ExtHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:22.615319 waagent[1485]: 2023-10-02T20:34:22.615220Z INFO ExtHandler Fetched a new incarnation for the WireServer goal state [incarnation 1]
Oct 2 20:34:22.624930 waagent[1485]: 2023-10-02T20:34:22.624859Z INFO ExtHandler ExtHandler HostGAPlugin version: 1.0.8.143
Oct 2 20:34:22.626243 waagent[1485]: 2023-10-02T20:34:22.626181Z INFO ExtHandler
Oct 2 20:34:22.626515 waagent[1485]: 2023-10-02T20:34:22.626461Z INFO ExtHandler Fetched new vmSettings [HostGAPlugin correlation ID: 78b8b8db-8ea5-48a2-9d21-a59a1afdcd4d eTag: 4594207848204105010 source: Fabric]
Oct 2 20:34:22.627391 waagent[1485]: 2023-10-02T20:34:22.627329Z INFO ExtHandler The vmSettings originated via Fabric; will ignore them.
Oct 2 20:34:22.628827 waagent[1485]: 2023-10-02T20:34:22.628760Z INFO ExtHandler
Oct 2 20:34:22.629103 waagent[1485]: 2023-10-02T20:34:22.629024Z INFO ExtHandler Fetching full goal state from the WireServer [incarnation 1]
Oct 2 20:34:22.635672 waagent[1485]: 2023-10-02T20:34:22.635616Z INFO ExtHandler ExtHandler Downloading artifacts profile blob
Oct 2 20:34:22.636318 waagent[1485]: 2023-10-02T20:34:22.636264Z WARNING ExtHandler ExtHandler Fetch failed: [HttpError] HTTPS is unavailable and required
Oct 2 20:34:22.658279 waagent[1485]: 2023-10-02T20:34:22.658211Z INFO ExtHandler ExtHandler Default channel changed to HostGAPlugin channel.
Oct 2 20:34:22.742778 waagent[1485]: 2023-10-02T20:34:22.742637Z INFO ExtHandler Downloaded certificate {'thumbprint': '7A2AD7AFD3CF1F3E86F596CB61E9F9A0610DD078', 'hasPrivateKey': True}
Oct 2 20:34:22.744123 waagent[1485]: 2023-10-02T20:34:22.744041Z INFO ExtHandler Downloaded certificate {'thumbprint': '2D1122B0449236FAD9E0FBB4D320A3E919074055', 'hasPrivateKey': False}
Oct 2 20:34:22.745345 waagent[1485]: 2023-10-02T20:34:22.745280Z INFO ExtHandler Fetch goal state completed
Oct 2 20:34:22.767529 waagent[1485]: 2023-10-02T20:34:22.767454Z INFO ExtHandler ExtHandler WALinuxAgent-2.9.1.1 running as process 1485
Oct 2 20:34:22.771277 waagent[1485]: 2023-10-02T20:34:22.771211Z INFO ExtHandler ExtHandler Cgroup monitoring is not supported on ['flatcar', '3510.3.0', '', 'Flatcar Container Linux by Kinvolk']
Oct 2 20:34:22.772946 waagent[1485]: 2023-10-02T20:34:22.772879Z INFO ExtHandler ExtHandler Starting setup for Persistent firewall rules
Oct 2 20:34:22.779473 waagent[1485]: 2023-10-02T20:34:22.779421Z INFO ExtHandler ExtHandler Firewalld service not running/unavailable, trying to set up waagent-network-setup.service
Oct 2 20:34:22.779991 waagent[1485]: 2023-10-02T20:34:22.779932Z INFO ExtHandler ExtHandler Successfully updated the Binary file /var/lib/waagent/waagent-network-setup.py for firewall setup
Oct 2 20:34:22.791055 waagent[1485]: 2023-10-02T20:34:22.790993Z INFO ExtHandler ExtHandler Service: waagent-network-setup.service not enabled. Adding it now
Oct 2 20:34:22.791752 waagent[1485]: 2023-10-02T20:34:22.791690Z ERROR ExtHandler ExtHandler Unable to setup the persistent firewall rules: [Errno 30] Read-only file system: '/lib/systemd/system/waagent-network-setup.service'
Oct 2 20:34:22.799993 waagent[1485]: 2023-10-02T20:34:22.799886Z INFO ExtHandler ExtHandler DROP rule is not available which implies no firewall rules are set yet. Environment thread will set it up.
Oct 2 20:34:22.803902 waagent[1485]: 2023-10-02T20:34:22.803837Z 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]
Oct 2 20:34:22.805683 waagent[1485]: 2023-10-02T20:34:22.805607Z INFO ExtHandler ExtHandler Starting env monitor service.
Oct 2 20:34:22.805971 waagent[1485]: 2023-10-02T20:34:22.805892Z INFO MonitorHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:22.806577 waagent[1485]: 2023-10-02T20:34:22.806510Z INFO MonitorHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:22.807191 waagent[1485]: 2023-10-02T20:34:22.807094Z INFO MonitorHandler ExtHandler Monitor.NetworkConfigurationChanges is disabled.
Oct 2 20:34:22.807844 waagent[1485]: 2023-10-02T20:34:22.807777Z INFO ExtHandler ExtHandler Start SendTelemetryHandler service.
Oct 2 20:34:22.808222 waagent[1485]: 2023-10-02T20:34:22.808155Z INFO MonitorHandler ExtHandler Routing table from /proc/net/route:
Oct 2 20:34:22.808222 waagent[1485]: Iface Destination Gateway Flags RefCnt Use Metric Mask MTU Window IRTT
Oct 2 20:34:22.808222 waagent[1485]: eth0 00000000 0114C80A 0003 0 0 1024 00000000 0 0 0
Oct 2 20:34:22.808222 waagent[1485]: eth0 0014C80A 00000000 0001 0 0 1024 00FFFFFF 0 0 0
Oct 2 20:34:22.808222 waagent[1485]: eth0 0114C80A 00000000 0005 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:22.808222 waagent[1485]: eth0 10813FA8 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:22.808222 waagent[1485]: eth0 FEA9FEA9 0114C80A 0007 0 0 1024 FFFFFFFF 0 0 0
Oct 2 20:34:22.809200 waagent[1485]: 2023-10-02T20:34:22.809106Z INFO SendTelemetryHandler ExtHandler Successfully started the SendTelemetryHandler thread
Oct 2 20:34:22.809460 waagent[1485]: 2023-10-02T20:34:22.809395Z INFO EnvHandler ExtHandler WireServer endpoint 168.63.129.16 read from file
Oct 2 20:34:22.809615 waagent[1485]: 2023-10-02T20:34:22.809556Z INFO ExtHandler ExtHandler Start Extension Telemetry service.
Oct 2 20:34:22.811983 waagent[1485]: 2023-10-02T20:34:22.811842Z INFO EnvHandler ExtHandler Wire server endpoint:168.63.129.16
Oct 2 20:34:22.812710 waagent[1485]: 2023-10-02T20:34:22.812626Z INFO TelemetryEventsCollector ExtHandler Extension Telemetry pipeline enabled: True
Oct 2 20:34:22.813288 waagent[1485]: 2023-10-02T20:34:22.813206Z INFO ExtHandler ExtHandler Goal State Period: 6 sec. This indicates how often the agent checks for new goal states and reports status.
Oct 2 20:34:22.813423 waagent[1485]: 2023-10-02T20:34:22.813361Z INFO TelemetryEventsCollector ExtHandler Successfully started the TelemetryEventsCollector thread
Oct 2 20:34:22.814545 waagent[1485]: 2023-10-02T20:34:22.814464Z INFO EnvHandler ExtHandler Configure routes
Oct 2 20:34:22.818452 waagent[1485]: 2023-10-02T20:34:22.818371Z INFO EnvHandler ExtHandler Gateway:None
Oct 2 20:34:22.820415 waagent[1485]: 2023-10-02T20:34:22.820261Z INFO EnvHandler ExtHandler Routes:None
Oct 2 20:34:22.834187 waagent[1485]: 2023-10-02T20:34:22.834107Z INFO ExtHandler ExtHandler No requested version specified, checking for all versions for agent update (family: Prod)
Oct 2 20:34:22.835354 waagent[1485]: 2023-10-02T20:34:22.835286Z INFO ExtHandler ExtHandler Downloading manifest
Oct 2 20:34:22.849816 waagent[1485]: 2023-10-02T20:34:22.849732Z INFO MonitorHandler ExtHandler Network interfaces:
Oct 2 20:34:22.849816 waagent[1485]: Executing ['ip', '-a', '-o', 'link']:
Oct 2 20:34:22.849816 waagent[1485]: 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
Oct 2 20:34:22.849816 waagent[1485]: 2: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP mode DEFAULT group default qlen 1000\ link/ether 00:0d:3a:6e:6d:e4 brd ff:ff:ff:ff:ff:ff
Oct 2 20:34:22.849816 waagent[1485]: 3: enP54503s1: <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:6e:6d:e4 brd ff:ff:ff:ff:ff:ff\ altname enP54503p0s2
Oct 2 20:34:22.849816 waagent[1485]: Executing ['ip', '-4', '-a', '-o', 'address']:
Oct 2 20:34:22.849816 waagent[1485]: 1: lo inet 127.0.0.1/8 scope host lo\ valid_lft forever preferred_lft forever
Oct 2 20:34:22.849816 waagent[1485]: 2: eth0 inet 10.200.20.17/24 metric 1024 brd 10.200.20.255 scope global eth0\ valid_lft forever preferred_lft forever
Oct 2 20:34:22.849816 waagent[1485]: Executing ['ip', '-6', '-a', '-o', 'address']:
Oct 2 20:34:22.849816 waagent[1485]: 1: lo inet6 ::1/128 scope host \ valid_lft forever preferred_lft forever
Oct 2 20:34:22.849816 waagent[1485]: 2: eth0 inet6 fe80::20d:3aff:fe6e:6de4/64 scope link \ valid_lft forever preferred_lft forever
Oct 2 20:34:22.872893 waagent[1485]: 2023-10-02T20:34:22.872825Z INFO ExtHandler ExtHandler
Oct 2 20:34:22.873262 waagent[1485]: 2023-10-02T20:34:22.873198Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState started [incarnation_1 channel: WireServer source: Fabric activity: c334d168-b277-4f99-975c-b37a6972b852 correlation 5f3405de-87d4-44df-9066-96c2f197e8f0 created: 2023-10-02T20:32:47.645149Z]
Oct 2 20:34:22.874610 waagent[1485]: 2023-10-02T20:34:22.874538Z INFO ExtHandler ExtHandler No extension handlers found, not processing anything.
Oct 2 20:34:22.876719 waagent[1485]: 2023-10-02T20:34:22.876655Z INFO ExtHandler ExtHandler ProcessExtensionsGoalState completed [incarnation_1 3 ms]
Oct 2 20:34:22.899987 waagent[1485]: 2023-10-02T20:34:22.899892Z INFO ExtHandler ExtHandler Looking for existing remote access users.
Oct 2 20:34:22.926943 waagent[1485]: 2023-10-02T20:34:22.926861Z INFO ExtHandler ExtHandler [HEARTBEAT] Agent WALinuxAgent-2.9.1.1 is running as the goal state agent [DEBUG HeartbeatCounter: 0;HeartbeatId: 2FED3D93-56E9-4534-BFAA-E94BC4B33A99;DroppedPackets: 0;UpdateGSErrors: 0;AutoUpdate: 1]
Oct 2 20:34:23.075566 waagent[1485]: 2023-10-02T20:34:23.075377Z INFO EnvHandler ExtHandler Successfully added Azure fabric firewall rules. Current Firewall rules:
Oct 2 20:34:23.075566 waagent[1485]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.075566 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.075566 waagent[1485]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.075566 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.075566 waagent[1485]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.075566 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.075566 waagent[1485]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53
Oct 2 20:34:23.075566 waagent[1485]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0
Oct 2 20:34:23.075566 waagent[1485]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW
Oct 2 20:34:23.085531 waagent[1485]: 2023-10-02T20:34:23.085411Z INFO EnvHandler ExtHandler Current Firewall rules:
Oct 2 20:34:23.085531 waagent[1485]: Chain INPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.085531 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.085531 waagent[1485]: Chain FORWARD (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.085531 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.085531 waagent[1485]: Chain OUTPUT (policy ACCEPT 0 packets, 0 bytes)
Oct 2 20:34:23.085531 waagent[1485]: pkts bytes target prot opt in out source destination
Oct 2 20:34:23.085531 waagent[1485]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 tcp dpt:53
Oct 2 20:34:23.085531 waagent[1485]: 0 0 ACCEPT tcp -- * * 0.0.0.0/0 168.63.129.16 owner UID match 0
Oct 2 20:34:23.085531 waagent[1485]: 0 0 DROP tcp -- * * 0.0.0.0/0 168.63.129.16 ctstate INVALID,NEW
Oct 2 20:34:23.086421 waagent[1485]: 2023-10-02T20:34:23.086367Z INFO EnvHandler ExtHandler Set block dev timeout: sda with timeout: 300
Oct 2 20:34:46.834046 kernel: hv_balloon: Max. dynamic memory size: 4096 MB
Oct 2 20:34:54.908661 systemd[1]: Created slice system-sshd.slice.
Oct 2 20:34:54.910031 systemd[1]: Started sshd@0-10.200.20.17:22-10.200.12.6:40700.service.
Oct 2 20:34:55.040197 update_engine[1289]: I1002 20:34:55.040148 1289 update_attempter.cc:505] Updating boot flags...
Oct 2 20:34:55.464566 sshd[1536]: Accepted publickey for core from 10.200.12.6 port 40700 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:55.478227 sshd[1536]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:55.482889 systemd[1]: Started session-3.scope.
Oct 2 20:34:55.483340 systemd-logind[1288]: New session 3 of user core.
Oct 2 20:34:55.817004 systemd[1]: Started sshd@1-10.200.20.17:22-10.200.12.6:40710.service.
Oct 2 20:34:56.229573 sshd[1607]: Accepted publickey for core from 10.200.12.6 port 40710 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:56.231372 sshd[1607]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:56.235443 systemd[1]: Started session-4.scope.
Oct 2 20:34:56.235949 systemd-logind[1288]: New session 4 of user core.
Oct 2 20:34:56.524555 sshd[1607]: pam_unix(sshd:session): session closed for user core
Oct 2 20:34:56.527369 systemd-logind[1288]: Session 4 logged out. Waiting for processes to exit.
Oct 2 20:34:56.527450 systemd[1]: session-4.scope: Deactivated successfully.
Oct 2 20:34:56.528303 systemd[1]: sshd@1-10.200.20.17:22-10.200.12.6:40710.service: Deactivated successfully.
Oct 2 20:34:56.529271 systemd-logind[1288]: Removed session 4.
Oct 2 20:34:56.591375 systemd[1]: Started sshd@2-10.200.20.17:22-10.200.12.6:40726.service.
Oct 2 20:34:56.993318 sshd[1613]: Accepted publickey for core from 10.200.12.6 port 40726 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:56.994839 sshd[1613]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:56.998660 systemd-logind[1288]: New session 5 of user core.
Oct 2 20:34:56.999057 systemd[1]: Started session-5.scope.
Oct 2 20:34:57.282420 sshd[1613]: pam_unix(sshd:session): session closed for user core
Oct 2 20:34:57.285111 systemd[1]: sshd@2-10.200.20.17:22-10.200.12.6:40726.service: Deactivated successfully.
Oct 2 20:34:57.285793 systemd[1]: session-5.scope: Deactivated successfully.
Oct 2 20:34:57.286337 systemd-logind[1288]: Session 5 logged out. Waiting for processes to exit.
Oct 2 20:34:57.287161 systemd-logind[1288]: Removed session 5.
Oct 2 20:34:57.348613 systemd[1]: Started sshd@3-10.200.20.17:22-10.200.12.6:40732.service.
Oct 2 20:34:57.746832 sshd[1619]: Accepted publickey for core from 10.200.12.6 port 40732 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:57.748429 sshd[1619]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:57.752239 systemd-logind[1288]: New session 6 of user core.
Oct 2 20:34:57.752649 systemd[1]: Started session-6.scope.
Oct 2 20:34:58.040375 sshd[1619]: pam_unix(sshd:session): session closed for user core
Oct 2 20:34:58.043273 systemd-logind[1288]: Session 6 logged out. Waiting for processes to exit.
Oct 2 20:34:58.043351 systemd[1]: session-6.scope: Deactivated successfully.
Oct 2 20:34:58.044277 systemd[1]: sshd@3-10.200.20.17:22-10.200.12.6:40732.service: Deactivated successfully.
Oct 2 20:34:58.044978 systemd-logind[1288]: Removed session 6.
Oct 2 20:34:58.106946 systemd[1]: Started sshd@4-10.200.20.17:22-10.200.12.6:60624.service.
Oct 2 20:34:58.511254 sshd[1625]: Accepted publickey for core from 10.200.12.6 port 60624 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:58.513038 sshd[1625]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:58.516700 systemd-logind[1288]: New session 7 of user core.
Oct 2 20:34:58.517128 systemd[1]: Started session-7.scope.
Oct 2 20:34:58.947336 sudo[1631]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/sbin/setenforce 1
Oct 2 20:34:58.947538 sudo[1631]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 20:34:58.981216 dbus-daemon[1279]: avc: received setenforce notice (enforcing=1)
Oct 2 20:34:58.982253 sudo[1631]: pam_unix(sudo:session): session closed for user root
Oct 2 20:34:59.056940 sshd[1625]: pam_unix(sshd:session): session closed for user core
Oct 2 20:34:59.060322 systemd-logind[1288]: Session 7 logged out. Waiting for processes to exit.
Oct 2 20:34:59.060365 systemd[1]: session-7.scope: Deactivated successfully.
Oct 2 20:34:59.060927 systemd[1]: sshd@4-10.200.20.17:22-10.200.12.6:60624.service: Deactivated successfully.
Oct 2 20:34:59.061907 systemd-logind[1288]: Removed session 7.
Oct 2 20:34:59.123016 systemd[1]: Started sshd@5-10.200.20.17:22-10.200.12.6:60640.service.
Oct 2 20:34:59.521533 sshd[1635]: Accepted publickey for core from 10.200.12.6 port 60640 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:34:59.523487 sshd[1635]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:34:59.527647 systemd[1]: Started session-8.scope.
Oct 2 20:34:59.528245 systemd-logind[1288]: New session 8 of user core.
Oct 2 20:34:59.752779 sudo[1639]: 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
Oct 2 20:34:59.753285 sudo[1639]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 20:34:59.756793 sudo[1639]: pam_unix(sudo:session): session closed for user root
Oct 2 20:34:59.762786 sudo[1638]: core : PWD=/home/core ; USER=root ; COMMAND=/usr/bin/systemctl restart audit-rules
Oct 2 20:34:59.762971 sudo[1638]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=500)
Oct 2 20:34:59.774214 systemd[1]: Stopping audit-rules.service...
Oct 2 20:34:59.775000 audit: CONFIG_CHANGE auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
Oct 2 20:34:59.775000 audit[1642]: SYSCALL arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd24bdd80 a2=420 a3=0 items=0 ppid=1 pid=1642 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)
Oct 2 20:34:59.775000 audit: PROCTITLE proctitle=2F7362696E2F617564697463746C002D44
Oct 2 20:34:59.776424 auditctl[1642]: No rules
Oct 2 20:34:59.778528 kernel: kauditd_printk_skb: 94 callbacks suppressed
Oct 2 20:34:59.778597 kernel: audit: type=1305 audit(1696278899.775:176): auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 op=remove_rule key=(null) list=5 res=1
Oct 2 20:34:59.778619 kernel: audit: type=1300 audit(1696278899.775:176): arch=c00000b7 syscall=206 success=yes exit=1056 a0=3 a1=ffffd24bdd80 a2=420 a3=0 items=0 ppid=1 pid=1642 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)
Oct 2 20:34:59.778635 kernel: audit: type=1327 audit(1696278899.775:176): proctitle=2F7362696E2F617564697463746C002D44
Oct 2 20:34:59.778653 kernel: audit: type=1131 audit(1696278899.775:177): 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'
Oct 2 20:34:59.775000 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'
Oct 2 20:34:59.776816 systemd[1]: audit-rules.service: Deactivated successfully.
Oct 2 20:34:59.776966 systemd[1]: Stopped audit-rules.service.
Oct 2 20:34:59.778365 systemd[1]: Starting audit-rules.service...
Oct 2 20:34:59.808034 augenrules[1659]: No rules
Oct 2 20:34:59.808960 systemd[1]: Finished audit-rules.service.
Oct 2 20:34:59.809000 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'
Oct 2 20:34:59.810136 kernel: audit: type=1130 audit(1696278899.809: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'
Oct 2 20:34:59.811106 kernel: audit: type=1106 audit(1696278899.810:179): pid=1638 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'
Oct 2 20:34:59.811168 kernel: audit: type=1104 audit(1696278899.810:180): pid=1638 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'
Oct 2 20:34:59.810000 audit[1638]: USER_END pid=1638 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'
Oct 2 20:34:59.810000 audit[1638]: CRED_DISP pid=1638 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'
Oct 2 20:34:59.810333 sudo[1638]: pam_unix(sudo:session): session closed for user root
Oct 2 20:34:59.872055 sshd[1635]: pam_unix(sshd:session): session closed for user core
Oct 2 20:34:59.872000 audit[1635]: USER_END pid=1635 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'
Oct 2 20:34:59.873000 audit[1635]: CRED_DISP pid=1635 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'
Oct 2 20:34:59.874890 kernel: audit: type=1106 audit(1696278899.872:181): pid=1635 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'
Oct 2 20:34:59.874960 kernel: audit: type=1104 audit(1696278899.873:182): pid=1635 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'
Oct 2 20:34:59.874752 systemd[1]: sshd@5-10.200.20.17:22-10.200.12.6:60640.service: Deactivated successfully.
Oct 2 20:34:59.874000 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.17:22-10.200.12.6:60640 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:34:59.875434 systemd[1]: session-8.scope: Deactivated successfully.
Oct 2 20:34:59.876101 kernel: audit: type=1131 audit(1696278899.874:183): pid=1 uid=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:kernel_t:s0 msg='unit=sshd@5-10.200.20.17:22-10.200.12.6:60640 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:34:59.875929 systemd-logind[1288]: Session 8 logged out. Waiting for processes to exit.
Oct 2 20:34:59.876675 systemd-logind[1288]: Removed session 8.
Oct 2 20:34:59.938773 systemd[1]: Started sshd@6-10.200.20.17:22-10.200.12.6:60644.service.
Oct 2 20:34:59.938000 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.17:22-10.200.12.6:60644 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:35:00.336000 audit[1665]: USER_ACCT pid=1665 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'
Oct 2 20:35:00.337621 sshd[1665]: Accepted publickey for core from 10.200.12.6 port 60644 ssh2: RSA SHA256:pOhi17uv1dMw9wbwzof49dIVAjOqWAX9EZnbvXjLyxI
Oct 2 20:35:00.338000 audit[1665]: CRED_ACQ pid=1665 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'
Oct 2 20:35:00.338000 audit[1665]: SYSCALL arch=c00000b7 syscall=64 success=yes exit=3 a0=5 a1=ffffd082a750 a2=3 a3=1 items=0 ppid=1 pid=1665 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)
Oct 2 20:35:00.338000 audit: PROCTITLE proctitle=737368643A20636F7265205B707269765D
Oct 2 20:35:00.339459 sshd[1665]: pam_unix(sshd:session): session opened for user core(uid=500) by (uid=0)
Oct 2 20:35:00.343667 systemd[1]: Started session-9.scope.
Oct 2 20:35:00.344209 systemd-logind[1288]: New session 9 of user core.
Oct 2 20:35:00.347000 audit[1665]: USER_START pid=1665 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'
Oct 2 20:35:00.349000 audit[1667]: CRED_ACQ pid=1667 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'
Oct 2 20:35:00.632979 sshd[1665]: pam_unix(sshd:session): session closed for user core
Oct 2 20:35:00.633000 audit[1665]: USER_END pid=1665 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'
Oct 2 20:35:00.633000 audit[1665]: CRED_DISP pid=1665 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'
Oct 2 20:35:00.636224 systemd-logind[1288]: Session 9 logged out. Waiting for processes to exit.
Oct 2 20:35:00.636392 systemd[1]: sshd@6-10.200.20.17:22-10.200.12.6:60644.service: Deactivated successfully.
Oct 2 20:35:00.635000 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.17:22-10.200.12.6:60644 comm="systemd" exe="/usr/lib/systemd/systemd" hostname=? addr=? terminal=? res=success'
Oct 2 20:35:00.637013 systemd[1]: session-9.scope: Deactivated successfully.
Oct 2 20:35:00.637680 systemd-logind[1288]: Removed session 9.