BdsDxe: failed to load Boot0001 "UEFI Misc Device" from PciRoot(0x0)/Pci(0x1,0x2)/Pci(0x0,0x0): Not Found >>Start PXE over IPv4. Station IP address is 192.168.24.15 Server IP address is 192.168.24.1 NBP filename is snponly.efi NBP filesize is 218624 Bytes Downloading NBP file... NBP file downloaded successfully. BdsDxe: loading Boot0002 "UEFI PXEv4 (MAC:525400123458)" from VenHw(837DCA9E-E874-4D82-B29A-23FE0E23D1E2,003E000A00000000)/MAC(525400123458,0x1)/IPv4(0.0.0.0,0x0,DHCP,0.0.0.0,0.0.0.0,0.0.0.0) BdsDxe: starting Boot0002 "UEFI PXEv4 (MAC:525400123458)" from VenHw(837DCA9E-E874-4D82-B29A-23FE0E23D1E2,003E000A00000000)/MAC(525400123458,0x1)/IPv4(0.0.0.0,0x0,DHCP,0.0.0.0,0.0.0.0,0.0.0.0) iPXE initialising devices...ok iPXE 1.21.1+ (g98dd) -- Open Source Network Boot Firmware -- https://ipxe.org Features: DNS HTTP iSCSI TFTP VLAN AoE EFI Menu net0: 52:54:00:12:34:58 using SNP on SNP-0x23f01ac18 (Ethernet) [open] [Link:up, TX:0 TXE:1 RX:0 RXE:0] [TXE: 1 x "Network unreachable (https://ipxe.org/28086090)"] Configuring (net0 52:54:00:12:34:58)...... ok net0: 192.168.24.15/255.255.255.0 gw 192.168.24.1 net0: fe80::5054:ff:fe12:3458/64 Next server: 192.168.24.1 Filename: http://192.168.24.1:8088/boot.ipxe http://192.168.24.1:8088/boot.ipxe... ok boot.ipxe : 758 bytes [script] Attempting to boot from MAC 52-54-00-12-34-58 pxelinux.cfg/52-54-00-12-34-58... ok http://192.168.24.1:8088/8d5ed008-efea-4df7-a190-aab8274a3bd5/deploy_kernel... ok http://192.168.24.1:8088/8d5ed008-efea-4df7-a190-aab8274a3bd5/deploy_ramdisk... ok EFI stub: Booting Linux Kernel... EFI stub: Loaded initrd from LINUX_EFI_INITRD_MEDIA_GUID device path EFI stub: Generating empty DTB EFI stub: Exiting boot services... [ 0.000000] Booting Linux on physical CPU 0x0000000000 [0x411fd070] [ 0.000000] Linux version 5.14.0-386.el9.aarch64 (mockbuild@aarch64-01.stream.rdu2.redhat.com) (gcc (GCC) 11.4.1 20230605 (Red Hat 11.4.1-2), GNU ld version 2.35.2-42.el9) #1 SMP PREEMPT_DYNAMIC Wed Nov 15 11:33:32 UTC 2023 [ 0.000000] The list of certified hardware and cloud instances for Red Hat Enterprise Linux 9 can be viewed at the Red Hat Ecosystem Catalog, https://catalog.redhat.com. [ 0.000000] efi: EFI v2.7 by EDK II [ 0.000000] efi: SMBIOS 3.0=0x23fed0000 MEMATTR=0x23f010018 ACPI 2.0=0x23c740018 INITRD=0x23cb43c18 RNG=0x23c74fe18 MEMRESERVE=0x23cb43c98 [ 0.000000] ACPI: Early table checksum verification disabled [ 0.000000] ACPI: RSDP 0x000000023C740018 000024 (v02 BOCHS ) [ 0.000000] ACPI: XSDT 0x000000023C74FE98 00006C (v01 BOCHS BXPC 00000001 01000013) [ 0.000000] ACPI: FACP 0x000000023C74FA98 000114 (v06 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DSDT 0x000000023C747518 001516 (v02 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: APIC 0x000000023C74D898 0002DC (v04 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: PPTT 0x000000023C74E718 000164 (v02 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: GTDT 0x000000023C74FC18 000060 (v02 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: MCFG 0x000000023C74FF98 00003C (v01 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: SPCR 0x000000023C74FD18 000050 (v02 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: DBG2 0x000000023C74FD98 000057 (v00 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: IORT 0x000000023C74DC98 000080 (v03 BOCHS BXPC 00000001 BXPC 00000001) [ 0.000000] ACPI: BGRT 0x000000023C74E698 000038 (v01 INTEL EDK2 00000002 01000013) [ 0.000000] ACPI: SPCR: console: pl011,mmio,0x9000000,9600 [ 0.000000] NUMA: Failed to initialise from firmware [ 0.000000] NUMA: Faking a node at [mem 0x0000000040000000-0x000000023fffffff] [ 0.000000] NUMA: NODE_DATA [mem 0x23ec29f80-0x23ec36fff] [ 0.000000] Zone ranges: [ 0.000000] DMA [mem 0x0000000040000000-0x00000000ffffffff] [ 0.000000] DMA32 empty [ 0.000000] Normal [mem 0x0000000100000000-0x000000023fffffff] [ 0.000000] Device empty [ 0.000000] Movable zone start for each node [ 0.000000] Early memory node ranges [ 0.000000] node 0: [mem 0x0000000040000000-0x000000023c76ffff] [ 0.000000] node 0: [mem 0x000000023c770000-0x000000023cb3ffff] [ 0.000000] node 0: [mem 0x000000023cb40000-0x000000023fe1ffff] [ 0.000000] node 0: [mem 0x000000023fe20000-0x000000023feaffff] [ 0.000000] node 0: [mem 0x000000023feb0000-0x000000023febffff] [ 0.000000] node 0: [mem 0x000000023fec0000-0x000000023ffdffff] [ 0.000000] node 0: [mem 0x000000023ffe0000-0x000000023fffffff] [ 0.000000] Initmem setup node 0 [mem 0x0000000040000000-0x000000023fffffff] [ 0.000000] psci: probing for conduit method from ACPI. [ 0.000000] psci: PSCIv1.1 detected in firmware. [ 0.000000] psci: Using standard PSCI v0.2 function IDs [ 0.000000] psci: Trusted OS migration not required [ 0.000000] psci: SMC Calling Convention v1.0 [ 0.000000] percpu: Embedded 32 pages/cpu s93160 r8192 d29720 u131072 [ 0.000000] Detected PIPT I-cache on CPU0 [ 0.000000] CPU features: detected: Spectre-v3a [ 0.000000] CPU features: detected: Spectre-v4 [ 0.000000] CPU features: detected: Spectre-BHB [ 0.000000] CPU features: kernel page table isolation forced ON by KASLR [ 0.000000] CPU features: detected: Kernel page table isolation (KPTI) [ 0.000000] CPU features: detected: ARM erratum 834220 [ 0.000000] CPU features: detected: ARM erratum 832075 [ 0.000000] CPU features: detected: ARM errata 1165522, 1319367, or 1530923 [ 0.000000] alternatives: applying boot alternatives [ 0.000000] Fallback order for Node 0: 0 [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 2064384 [ 0.000000] Policy zone: Normal [ 0.000000] Kernel command line: deploy_kernel selinux=0 troubleshoot=0 text nofb nomodeset vga=normal ipa-debug=1 ipa-api-url=http://192.168.24.1:6385 ipa-global-request-id=req-f843aae4-963b-42aa-8165-31fed61bf55e BOOTIF=52:54:00:12:34:58 initrd=deploy_ramdisk [ 0.000000] Booted with the nomodeset parameter. Only the system framebuffer will be available [ 0.000000] Unknown kernel command line parameters "deploy_kernel text nofb troubleshoot=0 vga=normal ipa-debug=1 ipa-api-url=http://192.168.24.1:6385 ipa-global-request-id=req-f843aae4-963b-42aa-8165-31fed61bf55e BOOTIF=52:54:00:12:34:58", will be passed to user space. [ 0.000000] Dentry cache hash table entries: 1048576 (order: 11, 8388608 bytes, linear) [ 0.000000] Inode-cache hash table entries: 524288 (order: 10, 4194304 bytes, linear) [ 0.000000] mem auto-init: stack:off, heap alloc:off, heap free:off [ 0.000000] software IO TLB: area num 8. [ 0.000000] software IO TLB: mapped [mem 0x00000000fbfff000-0x00000000fffff000] (64MB) [ 0.000000] Memory: 7657556K/8388608K available (13056K kernel code, 5464K rwdata, 10880K rodata, 5888K init, 11408K bss, 731052K reserved, 0K cma-reserved) [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1 [ 0.000000] ftrace: allocating 47261 entries in 185 pages [ 0.000000] ftrace: allocated 185 pages with 5 groups [ 0.000000] trace event string verifier disabled [ 0.000000] Dynamic Preempt: voluntary [ 0.000000] rcu: Preemptible hierarchical RCU implementation. [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=4096 to nr_cpu_ids=8. [ 0.000000] Trampoline variant of Tasks RCU enabled. [ 0.000000] Rude variant of Tasks RCU enabled. [ 0.000000] Tracing variant of Tasks RCU enabled. [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 10 jiffies. [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=8 [ 0.000000] NR_IRQS: 64, nr_irqs: 64, preallocated irqs: 0 [ 0.000000] Root IRQ handler: gic_handle_irq [ 0.000000] GICv2m: ACPI overriding V2M MSI_TYPER (base:80, num:64) [ 0.000000] GICv2m: range[mem 0x08020000-0x08020fff], SPI[80:143] [ 0.000000] rcu: srcu_init: Setting srcu_struct sizes based on contention. [ 0.000000] arch_timer: cp15 timer(s) running at 24.00MHz (virt). [ 0.000000] clocksource: arch_sys_counter: mask: 0xffffffffffffff max_cycles: 0x588fe9dc0, max_idle_ns: 440795202592 ns [ 0.000000] sched_clock: 56 bits at 24MHz, resolution 41ns, wraps every 4398046511097ns [ 0.000169] kfence: initialized - using 2097152 bytes for 255 objects at 0x(____ptrval____)-0x(____ptrval____) [ 0.000233] Console: colour dummy device 80x25 [ 0.000282] ACPI: Core revision 20221020 [ 0.000423] Calibrating delay loop (skipped), value calculated using timer frequency.. 48.00 BogoMIPS (lpj=240000) [ 0.000424] pid_max: default: 32768 minimum: 301 [ 0.000639] LSM: initializing lsm=lockdown,capability,yama,integrity,bpf [ 0.000662] Yama: becoming mindful. [ 0.000681] LSM support for eBPF active [ 0.000856] Mount-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.000890] Mountpoint-cache hash table entries: 16384 (order: 5, 131072 bytes, linear) [ 0.001713] cblist_init_generic: Setting adjustable number of callback queues. [ 0.001714] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.001726] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.001761] cblist_init_generic: Setting shift to 3 and lim to 1. [ 0.001800] rcu: Hierarchical SRCU implementation. [ 0.001801] rcu: Max phase no-delay instances is 1000. [ 0.002196] Remapping and enabling EFI services. [ 0.002498] smp: Bringing up secondary CPUs ... [ 0.002837] Detected PIPT I-cache on CPU1 [ 0.002906] CPU1: Booted secondary processor 0x0000000001 [0x411fd070] [ 0.003245] Detected PIPT I-cache on CPU2 [ 0.003328] CPU2: Booted secondary processor 0x0000000002 [0x411fd070] [ 0.003650] Detected PIPT I-cache on CPU3 [ 0.003747] CPU3: Booted secondary processor 0x0000000003 [0x411fd070] [ 0.004112] Detected PIPT I-cache on CPU4 [ 0.004227] CPU4: Booted secondary processor 0x0000000004 [0x411fd070] [ 0.004530] Detected PIPT I-cache on CPU5 [ 0.004659] CPU5: Booted secondary processor 0x0000000005 [0x411fd070] [ 0.004924] Detected PIPT I-cache on CPU6 [ 0.005068] CPU6: Booted secondary processor 0x0000000006 [0x411fd070] [ 0.005349] Detected PIPT I-cache on CPU7 [ 0.005509] CPU7: Booted secondary processor 0x0000000007 [0x411fd070] [ 0.005580] smp: Brought up 1 node, 8 CPUs [ 0.005581] SMP: Total of 8 processors activated. [ 0.005582] CPU features: detected: Data cache clean to the PoU not required for I/D coherence [ 0.005582] CPU features: detected: CRC32 instructions [ 0.005583] CPU features: detected: Data independent timing control (DIT) [ 0.005583] CPU features: detected: RAS Extension Support [ 0.014100] CPU: All CPU(s) started at EL1 [ 0.014111] alternatives: applying system-wide alternatives [ 0.019141] devtmpfs: initialized [ 0.019653] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604462750000 ns [ 0.019655] futex hash table entries: 2048 (order: 5, 131072 bytes, linear) [ 0.019719] pinctrl core: initialized pinctrl subsystem [ 0.019785] SMBIOS 3.0.0 present. [ 0.019787] DMI: QEMU QEMU Virtual Machine, BIOS edk2-stable202302-for-qemu 03/01/2023 [ 0.020210] NET: Registered PF_NETLINK/PF_ROUTE protocol family [ 0.020860] DMA: preallocated 1024 KiB GFP_KERNEL pool for atomic allocations [ 0.021181] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA pool for atomic allocations [ 0.021448] DMA: preallocated 1024 KiB GFP_KERNEL|GFP_DMA32 pool for atomic allocations [ 0.021473] audit: initializing netlink subsys (disabled) [ 0.021595] audit: type=2000 audit(0.020:1): state=initialized audit_enabled=0 res=1 [ 0.021632] thermal_sys: Registered thermal governor 'fair_share' [ 0.021632] thermal_sys: Registered thermal governor 'step_wise' [ 0.021632] thermal_sys: Registered thermal governor 'user_space' [ 0.021643] cpuidle: using governor menu [ 0.021668] hw-breakpoint: found 6 breakpoint and 4 watchpoint registers. [ 0.022132] ASID allocator initialised with 128 entries [ 0.022155] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 [ 0.022195] Serial: AMBA PL011 UART driver [ 0.022294] KASLR enabled [ 0.024282] HugeTLB: registered 1.00 GiB page size, pre-allocated 0 pages [ 0.024284] HugeTLB: 0 KiB vmemmap can be freed for a 1.00 GiB page [ 0.024284] HugeTLB: registered 32.0 MiB page size, pre-allocated 0 pages [ 0.024285] HugeTLB: 0 KiB vmemmap can be freed for a 32.0 MiB page [ 0.024285] HugeTLB: registered 2.00 MiB page size, pre-allocated 0 pages [ 0.024285] HugeTLB: 0 KiB vmemmap can be freed for a 2.00 MiB page [ 0.024285] HugeTLB: registered 64.0 KiB page size, pre-allocated 0 pages [ 0.024286] HugeTLB: 0 KiB vmemmap can be freed for a 64.0 KiB page [ 0.025223] cryptd: max_cpu_qlen set to 1000 [ 0.025727] ACPI: Added _OSI(Module Device) [ 0.025728] ACPI: Added _OSI(Processor Device) [ 0.025728] ACPI: Added _OSI(3.0 _SCP Extensions) [ 0.025729] ACPI: Added _OSI(Processor Aggregator Device) [ 0.025729] ACPI: Added _OSI(Linux-Dell-Video) [ 0.025729] ACPI: Added _OSI(Linux-Lenovo-NV-HDMI-Audio) [ 0.025730] ACPI: Added _OSI(Linux-HPI-Hybrid-Graphics) [ 0.026020] ACPI: 1 ACPI AML tables successfully acquired and loaded [ 0.026166] ACPI: Interpreter enabled [ 0.026167] ACPI: Using GIC for interrupt routing [ 0.026188] ACPI: MCFG table detected, 1 entries [ 0.026931] ARMH0011:00: ttyAMA0 at MMIO 0x9000000 (irq = 12, base_baud = 0) is a SBSA [ 0.026999] printk: console [ttyAMA0] enabled [ 0.027078] printk: console [ttyAMA0] printing thread started [ 0.027569] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) [ 0.027590] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI EDR HPX-Type3] [ 0.027611] acpi PNP0A08:00: _OSC: platform does not support [LTR DPC] [ 0.027634] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability] [ 0.027688] acpi PNP0A08:00: ECAM area [mem 0x4010000000-0x401fffffff] reserved by PNP0C02:00 [ 0.027694] acpi PNP0A08:00: ECAM at [mem 0x4010000000-0x401fffffff] for [bus 00-ff] [ 0.027699] ACPI: Remapped I/O 0x000000003eff0000 to [io 0x0000-0xffff window] [ 0.027721] PCI host bridge to bus 0000:00 [ 0.027721] pci_bus 0000:00: root bus resource [mem 0x10000000-0x3efeffff window] [ 0.027722] pci_bus 0000:00: root bus resource [io 0x0000-0xffff window] [ 0.027723] pci_bus 0000:00: root bus resource [mem 0x8000000000-0xffffffffff window] [ 0.027723] pci_bus 0000:00: root bus resource [bus 00-ff] [ 0.027740] pci 0000:00:00.0: [1b36:0008] type 00 class 0x060000 [ 0.028040] pci 0000:00:01.0: [1b36:000c] type 01 class 0x060400 [ 0.029140] pci 0000:00:01.0: reg 0x10: [mem 0x1200e000-0x1200efff] [ 0.043022] pci 0000:00:01.1: [1b36:000c] type 01 class 0x060400 [ 0.044132] pci 0000:00:01.1: reg 0x10: [mem 0x1200d000-0x1200dfff] [ 0.046612] pci 0000:00:01.2: [1b36:000c] type 01 class 0x060400 [ 0.047725] pci 0000:00:01.2: reg 0x10: [mem 0x1200c000-0x1200cfff] [ 0.050074] pci 0000:00:01.3: [1b36:000c] type 01 class 0x060400 [ 0.051074] pci 0000:00:01.3: reg 0x10: [mem 0x1200b000-0x1200bfff] [ 0.056318] pci 0000:00:01.4: [1b36:000c] type 01 class 0x060400 [ 0.057300] pci 0000:00:01.4: reg 0x10: [mem 0x1200a000-0x1200afff] [ 0.060047] pci 0000:00:01.5: [1b36:000c] type 01 class 0x060400 [ 0.061029] pci 0000:00:01.5: reg 0x10: [mem 0x12009000-0x12009fff] [ 0.063773] pci 0000:00:01.6: [1b36:000c] type 01 class 0x060400 [ 0.064800] pci 0000:00:01.6: reg 0x10: [mem 0x12008000-0x12008fff] [ 0.067277] pci 0000:00:01.7: [1b36:000c] type 01 class 0x060400 [ 0.068420] pci 0000:00:01.7: reg 0x10: [mem 0x12007000-0x12007fff] [ 0.071424] pci 0000:00:02.0: [1b36:000c] type 01 class 0x060400 [ 0.072484] pci 0000:00:02.0: reg 0x10: [mem 0x12006000-0x12006fff] [ 0.075385] pci 0000:00:02.1: [1b36:000c] type 01 class 0x060400 [ 0.076506] pci 0000:00:02.1: reg 0x10: [mem 0x12005000-0x12005fff] [ 0.079118] pci 0000:00:02.2: [1b36:000c] type 01 class 0x060400 [ 0.096194] pci 0000:00:02.2: reg 0x10: [mem 0x12004000-0x12004fff] [ 0.098736] pci 0000:00:02.3: [1b36:000c] type 01 class 0x060400 [ 0.099802] pci 0000:00:02.3: reg 0x10: [mem 0x12003000-0x12003fff] [ 0.102165] pci 0000:00:02.4: [1b36:000c] type 01 class 0x060400 [ 0.103100] pci 0000:00:02.4: reg 0x10: [mem 0x12002000-0x12002fff] [ 0.105513] pci 0000:00:02.5: [1b36:000c] type 01 class 0x060400 [ 0.106530] pci 0000:00:02.5: reg 0x10: [mem 0x12001000-0x12001fff] [ 0.108949] pci 0000:00:02.6: [1b36:000c] type 01 class 0x060400 [ 0.109961] pci 0000:00:02.6: reg 0x10: [mem 0x12000000-0x12000fff] [ 0.114243] pci 0000:01:00.0: [1b36:000d] type 00 class 0x0c0330 [ 0.114330] pci 0000:01:00.0: reg 0x10: [mem 0x11e00000-0x11e03fff 64bit] [ 0.115637] pci 0000:02:00.0: [1af4:1043] type 00 class 0x078000 [ 0.117086] pci 0000:02:00.0: reg 0x14: [mem 0x11c00000-0x11c00fff] [ 0.119092] pci 0000:02:00.0: reg 0x20: [mem 0x8000000000-0x8000003fff 64bit pref] [ 0.126210] pci 0000:03:00.0: [1af4:1042] type 00 class 0x010000 [ 0.127496] pci 0000:03:00.0: reg 0x14: [mem 0x11a00000-0x11a00fff] [ 0.129396] pci 0000:03:00.0: reg 0x20: [mem 0x8000100000-0x8000103fff 64bit pref] [ 0.131479] pci 0000:04:00.0: [1af4:1045] type 00 class 0x00ff00 [ 0.133337] pci 0000:04:00.0: reg 0x20: [mem 0x8000200000-0x8000203fff 64bit pref] [ 0.135568] pci 0000:05:00.0: [1af4:1044] type 00 class 0x00ff00 [ 0.138118] pci 0000:05:00.0: reg 0x14: [mem 0x11600000-0x11600fff] [ 0.139905] pci 0000:05:00.0: reg 0x20: [mem 0x8000300000-0x8000303fff 64bit pref] [ 0.142489] pci 0000:06:00.0: [1af4:1050] type 00 class 0x038000 [ 0.143812] pci 0000:06:00.0: reg 0x14: [mem 0x11400000-0x11400fff] [ 0.145439] pci 0000:06:00.0: reg 0x20: [mem 0x8000400000-0x8000403fff 64bit pref] [ 0.147306] pci 0000:07:00.0: [1b36:000e] type 01 class 0x060400 [ 0.148377] pci 0000:07:00.0: reg 0x10: [mem 0x11200000-0x112000ff 64bit] [ 0.150341] pci_bus 0000:08: extended config space not accessible [ 0.150401] pci 0000:08:01.0: [1000:0012] type 00 class 0x010000 [ 0.151369] pci 0000:08:01.0: reg 0x10: [io 0x8000-0x80ff] [ 0.152241] pci 0000:08:01.0: reg 0x14: [mem 0x11002000-0x110023ff] [ 0.153187] pci 0000:08:01.0: reg 0x18: [mem 0x11000000-0x11001fff] [ 0.166226] pci 0000:00:01.0: bridge window [io 0x1000-0x0fff] to [bus 01] add_size 1000 [ 0.166229] pci 0000:00:01.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 01] add_size 200000 add_align 100000 [ 0.166230] pci 0000:00:01.0: bridge window [mem 0x00100000-0x001fffff] to [bus 01] add_size 100000 add_align 100000 [ 0.166232] pci 0000:00:01.1: bridge window [io 0x1000-0x0fff] to [bus 02] add_size 1000 [ 0.166232] pci 0000:00:01.1: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 02] add_size 100000 add_align 100000 [ 0.166233] pci 0000:00:01.1: bridge window [mem 0x00100000-0x001fffff] to [bus 02] add_size 100000 add_align 100000 [ 0.166234] pci 0000:00:01.2: bridge window [io 0x1000-0x0fff] to [bus 03] add_size 1000 [ 0.166235] pci 0000:00:01.2: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 03] add_size 100000 add_align 100000 [ 0.166236] pci 0000:00:01.2: bridge window [mem 0x00100000-0x001fffff] to [bus 03] add_size 100000 add_align 100000 [ 0.166237] pci 0000:00:01.3: bridge window [io 0x1000-0x0fff] to [bus 04] add_size 1000 [ 0.166237] pci 0000:00:01.3: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 04] add_size 100000 add_align 100000 [ 0.166238] pci 0000:00:01.3: bridge window [mem 0x00100000-0x000fffff] to [bus 04] add_size 200000 add_align 100000 [ 0.166242] pci 0000:00:01.4: bridge window [io 0x1000-0x0fff] to [bus 05] add_size 1000 [ 0.166248] pci 0000:00:01.4: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 05] add_size 100000 add_align 100000 [ 0.166249] pci 0000:00:01.4: bridge window [mem 0x00100000-0x001fffff] to [bus 05] add_size 100000 add_align 100000 [ 0.166249] pci 0000:00:01.5: bridge window [io 0x1000-0x0fff] to [bus 06] add_size 1000 [ 0.166250] pci 0000:00:01.5: bridge window [mem 0x00100000-0x001fffff 64bit pref] to [bus 06] add_size 100000 add_align 100000 [ 0.166251] pci 0000:00:01.5: bridge window [mem 0x00100000-0x001fffff] to [bus 06] add_size 100000 add_align 100000 [ 0.166252] pci 0000:00:01.6: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 07-08] add_size 200000 add_align 100000 [ 0.166253] pci 0000:00:01.7: bridge window [io 0x1000-0x0fff] to [bus 09] add_size 1000 [ 0.166254] pci 0000:00:01.7: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 09] add_size 200000 add_align 100000 [ 0.166255] pci 0000:00:01.7: bridge window [mem 0x00100000-0x000fffff] to [bus 09] add_size 200000 add_align 100000 [ 0.166255] pci 0000:00:02.0: bridge window [io 0x1000-0x0fff] to [bus 0a] add_size 1000 [ 0.166256] pci 0000:00:02.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0a] add_size 200000 add_align 100000 [ 0.166257] pci 0000:00:02.0: bridge window [mem 0x00100000-0x000fffff] to [bus 0a] add_size 200000 add_align 100000 [ 0.166257] pci 0000:00:02.1: bridge window [io 0x1000-0x0fff] to [bus 0b] add_size 1000 [ 0.166258] pci 0000:00:02.1: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0b] add_size 200000 add_align 100000 [ 0.166258] pci 0000:00:02.1: bridge window [mem 0x00100000-0x000fffff] to [bus 0b] add_size 200000 add_align 100000 [ 0.166262] pci 0000:00:02.2: bridge window [io 0x1000-0x0fff] to [bus 0c] add_size 1000 [ 0.166263] pci 0000:00:02.2: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0c] add_size 200000 add_align 100000 [ 0.166263] pci 0000:00:02.2: bridge window [mem 0x00100000-0x000fffff] to [bus 0c] add_size 200000 add_align 100000 [ 0.166264] pci 0000:00:02.3: bridge window [io 0x1000-0x0fff] to [bus 0d] add_size 1000 [ 0.166265] pci 0000:00:02.3: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0d] add_size 200000 add_align 100000 [ 0.166265] pci 0000:00:02.3: bridge window [mem 0x00100000-0x000fffff] to [bus 0d] add_size 200000 add_align 100000 [ 0.166266] pci 0000:00:02.4: bridge window [io 0x1000-0x0fff] to [bus 0e] add_size 1000 [ 0.166267] pci 0000:00:02.4: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0e] add_size 200000 add_align 100000 [ 0.166267] pci 0000:00:02.4: bridge window [mem 0x00100000-0x000fffff] to [bus 0e] add_size 200000 add_align 100000 [ 0.166268] pci 0000:00:02.5: bridge window [io 0x1000-0x0fff] to [bus 0f] add_size 1000 [ 0.166269] pci 0000:00:02.5: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 0f] add_size 200000 add_align 100000 [ 0.166269] pci 0000:00:02.5: bridge window [mem 0x00100000-0x000fffff] to [bus 0f] add_size 200000 add_align 100000 [ 0.166270] pci 0000:00:02.6: bridge window [io 0x1000-0x0fff] to [bus 10] add_size 1000 [ 0.166271] pci 0000:00:02.6: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 10] add_size 200000 add_align 100000 [ 0.166271] pci 0000:00:02.6: bridge window [mem 0x00100000-0x000fffff] to [bus 10] add_size 200000 add_align 100000 [ 0.166293] pci 0000:00:01.0: BAR 14: assigned [mem 0x10000000-0x101fffff] [ 0.166294] pci 0000:00:01.0: BAR 15: assigned [mem 0x8000000000-0x80001fffff 64bit pref] [ 0.166295] pci 0000:00:01.1: BAR 14: assigned [mem 0x10200000-0x103fffff] [ 0.166296] pci 0000:00:01.1: BAR 15: assigned [mem 0x8000200000-0x80003fffff 64bit pref] [ 0.166296] pci 0000:00:01.2: BAR 14: assigned [mem 0x10400000-0x105fffff] [ 0.166297] pci 0000:00:01.2: BAR 15: assigned [mem 0x8000400000-0x80005fffff 64bit pref] [ 0.166298] pci 0000:00:01.3: BAR 14: assigned [mem 0x10600000-0x107fffff] [ 0.166298] pci 0000:00:01.3: BAR 15: assigned [mem 0x8000600000-0x80007fffff 64bit pref] [ 0.166299] pci 0000:00:01.4: BAR 14: assigned [mem 0x10800000-0x109fffff] [ 0.166299] pci 0000:00:01.4: BAR 15: assigned [mem 0x8000800000-0x80009fffff 64bit pref] [ 0.166300] pci 0000:00:01.5: BAR 14: assigned [mem 0x10a00000-0x10bfffff] [ 0.166300] pci 0000:00:01.5: BAR 15: assigned [mem 0x8000a00000-0x8000bfffff 64bit pref] [ 0.166301] pci 0000:00:01.6: BAR 14: assigned [mem 0x10c00000-0x10dfffff] [ 0.166302] pci 0000:00:01.6: BAR 15: assigned [mem 0x8000c00000-0x8000dfffff 64bit pref] [ 0.166302] pci 0000:00:01.7: BAR 14: assigned [mem 0x10e00000-0x10ffffff] [ 0.166303] pci 0000:00:01.7: BAR 15: assigned [mem 0x8000e00000-0x8000ffffff 64bit pref] [ 0.166303] pci 0000:00:02.0: BAR 14: assigned [mem 0x11000000-0x111fffff] [ 0.166304] pci 0000:00:02.0: BAR 15: assigned [mem 0x8001000000-0x80011fffff 64bit pref] [ 0.166304] pci 0000:00:02.1: BAR 14: assigned [mem 0x11200000-0x113fffff] [ 0.166305] pci 0000:00:02.1: BAR 15: assigned [mem 0x8001200000-0x80013fffff 64bit pref] [ 0.166305] pci 0000:00:02.2: BAR 14: assigned [mem 0x11400000-0x115fffff] [ 0.166306] pci 0000:00:02.2: BAR 15: assigned [mem 0x8001400000-0x80015fffff 64bit pref] [ 0.166307] pci 0000:00:02.3: BAR 14: assigned [mem 0x11600000-0x117fffff] [ 0.166307] pci 0000:00:02.3: BAR 15: assigned [mem 0x8001600000-0x80017fffff 64bit pref] [ 0.166308] pci 0000:00:02.4: BAR 14: assigned [mem 0x11800000-0x119fffff] [ 0.166308] pci 0000:00:02.4: BAR 15: assigned [mem 0x8001800000-0x80019fffff 64bit pref] [ 0.166309] pci 0000:00:02.5: BAR 14: assigned [mem 0x11a00000-0x11bfffff] [ 0.166309] pci 0000:00:02.5: BAR 15: assigned [mem 0x8001a00000-0x8001bfffff 64bit pref] [ 0.166310] pci 0000:00:02.6: BAR 14: assigned [mem 0x11c00000-0x11dfffff] [ 0.166311] pci 0000:00:02.6: BAR 15: assigned [mem 0x8001c00000-0x8001dfffff 64bit pref] [ 0.166311] pci 0000:00:01.0: BAR 0: assigned [mem 0x11e00000-0x11e00fff] [ 0.166699] pci 0000:00:01.0: BAR 13: assigned [io 0x1000-0x1fff] [ 0.166700] pci 0000:00:01.1: BAR 0: assigned [mem 0x11e01000-0x11e01fff] [ 0.167066] pci 0000:00:01.1: BAR 13: assigned [io 0x2000-0x2fff] [ 0.167067] pci 0000:00:01.2: BAR 0: assigned [mem 0x11e02000-0x11e02fff] [ 0.167392] pci 0000:00:01.2: BAR 13: assigned [io 0x3000-0x3fff] [ 0.167392] pci 0000:00:01.3: BAR 0: assigned [mem 0x11e03000-0x11e03fff] [ 0.167735] pci 0000:00:01.3: BAR 13: assigned [io 0x4000-0x4fff] [ 0.167736] pci 0000:00:01.4: BAR 0: assigned [mem 0x11e04000-0x11e04fff] [ 0.168054] pci 0000:00:01.4: BAR 13: assigned [io 0x5000-0x5fff] [ 0.168055] pci 0000:00:01.5: BAR 0: assigned [mem 0x11e05000-0x11e05fff] [ 0.168391] pci 0000:00:01.5: BAR 13: assigned [io 0x6000-0x6fff] [ 0.168392] pci 0000:00:01.6: BAR 0: assigned [mem 0x11e06000-0x11e06fff] [ 0.168729] pci 0000:00:01.6: BAR 13: assigned [io 0x7000-0x7fff] [ 0.168730] pci 0000:00:01.7: BAR 0: assigned [mem 0x11e07000-0x11e07fff] [ 0.169063] pci 0000:00:01.7: BAR 13: assigned [io 0x8000-0x8fff] [ 0.169064] pci 0000:00:02.0: BAR 0: assigned [mem 0x11e08000-0x11e08fff] [ 0.169413] pci 0000:00:02.0: BAR 13: assigned [io 0x9000-0x9fff] [ 0.169413] pci 0000:00:02.1: BAR 0: assigned [mem 0x11e09000-0x11e09fff] [ 0.169754] pci 0000:00:02.1: BAR 13: assigned [io 0xa000-0xafff] [ 0.169754] pci 0000:00:02.2: BAR 0: assigned [mem 0x11e0a000-0x11e0afff] [ 0.170091] pci 0000:00:02.2: BAR 13: assigned [io 0xb000-0xbfff] [ 0.170092] pci 0000:00:02.3: BAR 0: assigned [mem 0x11e0b000-0x11e0bfff] [ 0.170436] pci 0000:00:02.3: BAR 13: assigned [io 0xc000-0xcfff] [ 0.170437] pci 0000:00:02.4: BAR 0: assigned [mem 0x11e0c000-0x11e0cfff] [ 0.170781] pci 0000:00:02.4: BAR 13: assigned [io 0xd000-0xdfff] [ 0.170782] pci 0000:00:02.5: BAR 0: assigned [mem 0x11e0d000-0x11e0dfff] [ 0.171127] pci 0000:00:02.5: BAR 13: assigned [io 0xe000-0xefff] [ 0.171128] pci 0000:00:02.6: BAR 0: assigned [mem 0x11e0e000-0x11e0efff] [ 0.171474] pci 0000:00:02.6: BAR 13: assigned [io 0xf000-0xffff] [ 0.171482] pci 0000:01:00.0: BAR 0: assigned [mem 0x10000000-0x10003fff 64bit] [ 0.171720] pci 0000:00:01.0: PCI bridge to [bus 01] [ 0.171723] pci 0000:00:01.0: bridge window [io 0x1000-0x1fff] [ 0.172346] pci 0000:00:01.0: bridge window [mem 0x10000000-0x101fffff] [ 0.172735] pci 0000:00:01.0: bridge window [mem 0x8000000000-0x80001fffff 64bit pref] [ 0.173472] pci 0000:02:00.0: BAR 4: assigned [mem 0x8000200000-0x8000203fff 64bit pref] [ 0.174175] pci 0000:02:00.0: BAR 1: assigned [mem 0x10200000-0x10200fff] [ 0.174500] pci 0000:00:01.1: PCI bridge to [bus 02] [ 0.174503] pci 0000:00:01.1: bridge window [io 0x2000-0x2fff] [ 0.175033] pci 0000:00:01.1: bridge window [mem 0x10200000-0x103fffff] [ 0.175401] pci 0000:00:01.1: bridge window [mem 0x8000200000-0x80003fffff 64bit pref] [ 0.176107] pci 0000:03:00.0: BAR 4: assigned [mem 0x8000400000-0x8000403fff 64bit pref] [ 0.176745] pci 0000:03:00.0: BAR 1: assigned [mem 0x10400000-0x10400fff] [ 0.177042] pci 0000:00:01.2: PCI bridge to [bus 03] [ 0.177045] pci 0000:00:01.2: bridge window [io 0x3000-0x3fff] [ 0.177539] pci 0000:00:01.2: bridge window [mem 0x10400000-0x105fffff] [ 0.177874] pci 0000:00:01.2: bridge window [mem 0x8000400000-0x80005fffff 64bit pref] [ 0.178551] pci 0000:04:00.0: BAR 4: assigned [mem 0x8000600000-0x8000603fff 64bit pref] [ 0.178878] pci 0000:00:01.3: PCI bridge to [bus 04] [ 0.178881] pci 0000:00:01.3: bridge window [io 0x4000-0x4fff] [ 0.179384] pci 0000:00:01.3: bridge window [mem 0x10600000-0x107fffff] [ 0.179715] pci 0000:00:01.3: bridge window [mem 0x8000600000-0x80007fffff 64bit pref] [ 0.180563] pci 0000:05:00.0: BAR 4: assigned [mem 0x8000800000-0x8000803fff 64bit pref] [ 0.181250] pci 0000:05:00.0: BAR 1: assigned [mem 0x10800000-0x10800fff] [ 0.181573] pci 0000:00:01.4: PCI bridge to [bus 05] [ 0.181576] pci 0000:00:01.4: bridge window [io 0x5000-0x5fff] [ 0.201647] pci 0000:00:01.4: bridge window [mem 0x10800000-0x109fffff] [ 0.202019] pci 0000:00:01.4: bridge window [mem 0x8000800000-0x80009fffff 64bit pref] [ 0.202635] pci 0000:06:00.0: BAR 4: assigned [mem 0x8000a00000-0x8000a03fff 64bit pref] [ 0.203203] pci 0000:06:00.0: BAR 1: assigned [mem 0x10a00000-0x10a00fff] [ 0.203491] pci 0000:00:01.5: PCI bridge to [bus 06] [ 0.203492] pci 0000:00:01.5: bridge window [io 0x6000-0x6fff] [ 0.203981] pci 0000:00:01.5: bridge window [mem 0x10a00000-0x10bfffff] [ 0.204322] pci 0000:00:01.5: bridge window [mem 0x8000a00000-0x8000bfffff 64bit pref] [ 0.204964] pci 0000:07:00.0: BAR 14: assigned [mem 0x10c00000-0x10cfffff] [ 0.204965] pci 0000:07:00.0: BAR 13: assigned [io 0x7000-0x7fff] [ 0.204966] pci 0000:07:00.0: BAR 0: assigned [mem 0x10d00000-0x10d000ff 64bit] [ 0.205661] pci 0000:08:01.0: BAR 2: assigned [mem 0x10c00000-0x10c01fff] [ 0.205985] pci 0000:08:01.0: BAR 1: assigned [mem 0x10c02000-0x10c023ff] [ 0.206305] pci 0000:08:01.0: BAR 0: assigned [io 0x7000-0x70ff] [ 0.206601] pci 0000:07:00.0: PCI bridge to [bus 08] [ 0.206603] pci 0000:07:00.0: bridge window [io 0x7000-0x7fff] [ 0.207106] pci 0000:07:00.0: bridge window [mem 0x10c00000-0x10cfffff] [ 0.208057] pci 0000:00:01.6: PCI bridge to [bus 07-08] [ 0.208058] pci 0000:00:01.6: bridge window [io 0x7000-0x7fff] [ 0.208562] pci 0000:00:01.6: bridge window [mem 0x10c00000-0x10dfffff] [ 0.208913] pci 0000:00:01.6: bridge window [mem 0x8000c00000-0x8000dfffff 64bit pref] [ 0.209601] pci 0000:00:01.7: PCI bridge to [bus 09] [ 0.209603] pci 0000:00:01.7: bridge window [io 0x8000-0x8fff] [ 0.210125] pci 0000:00:01.7: bridge window [mem 0x10e00000-0x10ffffff] [ 0.210496] pci 0000:00:01.7: bridge window [mem 0x8000e00000-0x8000ffffff 64bit pref] [ 0.211222] pci 0000:00:02.0: PCI bridge to [bus 0a] [ 0.211226] pci 0000:00:02.0: bridge window [io 0x9000-0x9fff] [ 0.211724] pci 0000:00:02.0: bridge window [mem 0x11000000-0x111fffff] [ 0.212048] pci 0000:00:02.0: bridge window [mem 0x8001000000-0x80011fffff 64bit pref] [ 0.212726] pci 0000:00:02.1: PCI bridge to [bus 0b] [ 0.212728] pci 0000:00:02.1: bridge window [io 0xa000-0xafff] [ 0.213233] pci 0000:00:02.1: bridge window [mem 0x11200000-0x113fffff] [ 0.213573] pci 0000:00:02.1: bridge window [mem 0x8001200000-0x80013fffff 64bit pref] [ 0.214265] pci 0000:00:02.2: PCI bridge to [bus 0c] [ 0.214266] pci 0000:00:02.2: bridge window [io 0xb000-0xbfff] [ 0.214814] pci 0000:00:02.2: bridge window [mem 0x11400000-0x115fffff] [ 0.215177] pci 0000:00:02.2: bridge window [mem 0x8001400000-0x80015fffff 64bit pref] [ 0.215905] pci 0000:00:02.3: PCI bridge to [bus 0d] [ 0.215907] pci 0000:00:02.3: bridge window [io 0xc000-0xcfff] [ 0.216481] pci 0000:00:02.3: bridge window [mem 0x11600000-0x117fffff] [ 0.216849] pci 0000:00:02.3: bridge window [mem 0x8001600000-0x80017fffff 64bit pref] [ 0.217685] pci 0000:00:02.4: PCI bridge to [bus 0e] [ 0.217689] pci 0000:00:02.4: bridge window [io 0xd000-0xdfff] [ 0.218182] pci 0000:00:02.4: bridge window [mem 0x11800000-0x119fffff] [ 0.218492] pci 0000:00:02.4: bridge window [mem 0x8001800000-0x80019fffff 64bit pref] [ 0.219108] pci 0000:00:02.5: PCI bridge to [bus 0f] [ 0.219110] pci 0000:00:02.5: bridge window [io 0xe000-0xefff] [ 0.219570] pci 0000:00:02.5: bridge window [mem 0x11a00000-0x11bfffff] [ 0.219880] pci 0000:00:02.5: bridge window [mem 0x8001a00000-0x8001bfffff 64bit pref] [ 0.220532] pci 0000:00:02.6: PCI bridge to [bus 10] [ 0.220534] pci 0000:00:02.6: bridge window [io 0xf000-0xffff] [ 0.221060] pci 0000:00:02.6: bridge window [mem 0x11c00000-0x11dfffff] [ 0.221407] pci 0000:00:02.6: bridge window [mem 0x8001c00000-0x8001dfffff 64bit pref] [ 0.222252] pci_bus 0000:00: resource 4 [mem 0x10000000-0x3efeffff window] [ 0.222254] pci_bus 0000:00: resource 5 [io 0x0000-0xffff window] [ 0.222255] pci_bus 0000:00: resource 6 [mem 0x8000000000-0xffffffffff window] [ 0.222256] pci_bus 0000:01: resource 0 [io 0x1000-0x1fff] [ 0.222256] pci_bus 0000:01: resource 1 [mem 0x10000000-0x101fffff] [ 0.222257] pci_bus 0000:01: resource 2 [mem 0x8000000000-0x80001fffff 64bit pref] [ 0.222257] pci_bus 0000:02: resource 0 [io 0x2000-0x2fff] [ 0.222258] pci_bus 0000:02: resource 1 [mem 0x10200000-0x103fffff] [ 0.222258] pci_bus 0000:02: resource 2 [mem 0x8000200000-0x80003fffff 64bit pref] [ 0.222259] pci_bus 0000:03: resource 0 [io 0x3000-0x3fff] [ 0.222259] pci_bus 0000:03: resource 1 [mem 0x10400000-0x105fffff] [ 0.222260] pci_bus 0000:03: resource 2 [mem 0x8000400000-0x80005fffff 64bit pref] [ 0.222260] pci_bus 0000:04: resource 0 [io 0x4000-0x4fff] [ 0.222261] pci_bus 0000:04: resource 1 [mem 0x10600000-0x107fffff] [ 0.222261] pci_bus 0000:04: resource 2 [mem 0x8000600000-0x80007fffff 64bit pref] [ 0.222262] pci_bus 0000:05: resource 0 [io 0x5000-0x5fff] [ 0.222262] pci_bus 0000:05: resource 1 [mem 0x10800000-0x109fffff] [ 0.222263] pci_bus 0000:05: resource 2 [mem 0x8000800000-0x80009fffff 64bit pref] [ 0.222264] pci_bus 0000:06: resource 0 [io 0x6000-0x6fff] [ 0.222264] pci_bus 0000:06: resource 1 [mem 0x10a00000-0x10bfffff] [ 0.222264] pci_bus 0000:06: resource 2 [mem 0x8000a00000-0x8000bfffff 64bit pref] [ 0.222265] pci_bus 0000:07: resource 0 [io 0x7000-0x7fff] [ 0.222266] pci_bus 0000:07: resource 1 [mem 0x10c00000-0x10dfffff] [ 0.222266] pci_bus 0000:07: resource 2 [mem 0x8000c00000-0x8000dfffff 64bit pref] [ 0.222267] pci_bus 0000:08: resource 0 [io 0x7000-0x7fff] [ 0.222267] pci_bus 0000:08: resource 1 [mem 0x10c00000-0x10cfffff] [ 0.222268] pci_bus 0000:09: resource 0 [io 0x8000-0x8fff] [ 0.222268] pci_bus 0000:09: resource 1 [mem 0x10e00000-0x10ffffff] [ 0.222269] pci_bus 0000:09: resource 2 [mem 0x8000e00000-0x8000ffffff 64bit pref] [ 0.222269] pci_bus 0000:0a: resource 0 [io 0x9000-0x9fff] [ 0.222270] pci_bus 0000:0a: resource 1 [mem 0x11000000-0x111fffff] [ 0.222270] pci_bus 0000:0a: resource 2 [mem 0x8001000000-0x80011fffff 64bit pref] [ 0.222276] pci_bus 0000:0b: resource 0 [io 0xa000-0xafff] [ 0.222277] pci_bus 0000:0b: resource 1 [mem 0x11200000-0x113fffff] [ 0.222277] pci_bus 0000:0b: resource 2 [mem 0x8001200000-0x80013fffff 64bit pref] [ 0.222278] pci_bus 0000:0c: resource 0 [io 0xb000-0xbfff] [ 0.222278] pci_bus 0000:0c: resource 1 [mem 0x11400000-0x115fffff] [ 0.222278] pci_bus 0000:0c: resource 2 [mem 0x8001400000-0x80015fffff 64bit pref] [ 0.222282] pci_bus 0000:0d: resource 0 [io 0xc000-0xcfff] [ 0.222283] pci_bus 0000:0d: resource 1 [mem 0x11600000-0x117fffff] [ 0.222283] pci_bus 0000:0d: resource 2 [mem 0x8001600000-0x80017fffff 64bit pref] [ 0.222284] pci_bus 0000:0e: resource 0 [io 0xd000-0xdfff] [ 0.222284] pci_bus 0000:0e: resource 1 [mem 0x11800000-0x119fffff] [ 0.222284] pci_bus 0000:0e: resource 2 [mem 0x8001800000-0x80019fffff 64bit pref] [ 0.222285] pci_bus 0000:0f: resource 0 [io 0xe000-0xefff] [ 0.222286] pci_bus 0000:0f: resource 1 [mem 0x11a00000-0x11bfffff] [ 0.222286] pci_bus 0000:0f: resource 2 [mem 0x8001a00000-0x8001bfffff 64bit pref] [ 0.222287] pci_bus 0000:10: resource 0 [io 0xf000-0xffff] [ 0.222287] pci_bus 0000:10: resource 1 [mem 0x11c00000-0x11dfffff] [ 0.222287] pci_bus 0000:10: resource 2 [mem 0x8001c00000-0x8001dfffff 64bit pref] [ 0.222458] ACPI: PCI: Interrupt link GSI0 configured for IRQ 35 [ 0.222462] ACPI: PCI: Interrupt link GSI1 configured for IRQ 36 [ 0.222467] ACPI: PCI: Interrupt link GSI2 configured for IRQ 37 [ 0.222470] ACPI: PCI: Interrupt link GSI3 configured for IRQ 38 [ 0.222935] iommu: Default domain type: Translated [ 0.222937] iommu: DMA domain TLB invalidation policy: lazy mode [ 0.223095] SCSI subsystem initialized [ 0.223455] ACPI: bus type USB registered [ 0.223479] usbcore: registered new interface driver usbfs [ 0.223484] usbcore: registered new interface driver hub [ 0.223486] usbcore: registered new device driver usb [ 0.223517] pps_core: LinuxPPS API ver. 1 registered [ 0.223518] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti [ 0.223519] PTP clock support registered [ 0.223953] EDAC MC: Ver: 3.0.0 [ 0.225134] Registered efivars operations [ 0.225404] NetLabel: Initializing [ 0.225404] NetLabel: domain hash size = 128 [ 0.225404] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO [ 0.225411] NetLabel: unlabeled traffic allowed by default [ 0.225942] vgaarb: loaded [ 0.231645] clocksource: Switched to clocksource arch_sys_counter [ 0.231968] VFS: Disk quotas dquot_6.6.0 [ 0.231976] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) [ 0.232040] pnp: PnP ACPI init [ 0.232084] system 00:00: [mem 0x4010000000-0x401fffffff window] could not be reserved [ 0.232089] pnp: PnP ACPI: found 1 devices [ 0.268370] NET: Registered PF_INET protocol family [ 0.268738] IP idents hash table entries: 131072 (order: 8, 1048576 bytes, linear) [ 0.269846] tcp_listen_portaddr_hash hash table entries: 4096 (order: 4, 65536 bytes, linear) [ 0.269869] Table-perturb hash table entries: 65536 (order: 6, 262144 bytes, linear) [ 0.269874] TCP established hash table entries: 65536 (order: 7, 524288 bytes, linear) [ 0.270065] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes, linear) [ 0.270391] TCP: Hash tables configured (established 65536 bind 65536) [ 0.270535] MPTCP token hash table entries: 8192 (order: 5, 196608 bytes, linear) [ 0.270546] UDP hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 0.270590] UDP-Lite hash table entries: 4096 (order: 5, 131072 bytes, linear) [ 0.270662] NET: Registered PF_UNIX/PF_LOCAL protocol family [ 0.270665] NET: Registered PF_XDP protocol family [ 0.270863] pci 0000:01:00.0: enabling device (0000 -> 0002) [ 0.271316] PCI: CLS 0 bytes, default 64 [ 0.271336] ACPI: bus type thunderbolt registered [ 0.271490] Trying to unpack rootfs image as initramfs... [ 0.273896] kvm [1]: HYP mode not available [ 0.274433] Initialise system trusted keyrings [ 0.274442] Key type blacklist registered [ 0.274780] workingset: timestamp_bits=38 max_order=21 bucket_order=0 [ 0.275648] zbud: loaded [ 0.276515] integrity: Platform Keyring initialized [ 0.276518] integrity: Machine keyring initialized [ 0.286629] NET: Registered PF_ALG protocol family [ 0.286632] xor: measuring software checksum speed [ 0.286926] 8regs : 34281 MB/sec [ 0.287284] 32regs : 27730 MB/sec [ 0.287469] arm64_neon : 53901 MB/sec [ 0.287470] xor: using function: arm64_neon (53901 MB/sec) [ 0.287472] Key type asymmetric registered [ 0.287472] Asymmetric key parser 'x509' registered [ 0.287473] Running certificate verification selftests [ 0.327478] Loaded X.509 cert 'Certificate verification self-testing key: f58703bb33ce1b73ee02eccdee5b8817518fe3db' [ 0.327933] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 246) [ 0.328287] io scheduler mq-deadline registered [ 0.328288] io scheduler kyber registered [ 0.328318] io scheduler bfq registered [ 0.333533] atomic64_test: passed [ 0.334051] ACPI: \_SB_.PCI0.GSI1: Enabled at IRQ 36 [ 0.334488] pcieport 0000:00:01.0: PME: Signaling with IRQ 50 [ 0.334802] pcieport 0000:00:01.0: AER: enabled with IRQ 50 [ 0.334877] pcieport 0000:00:01.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.336421] pcieport 0000:00:01.1: PME: Signaling with IRQ 51 [ 0.336857] pcieport 0000:00:01.1: AER: enabled with IRQ 51 [ 0.336889] pcieport 0000:00:01.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.338302] pcieport 0000:00:01.2: PME: Signaling with IRQ 52 [ 0.338711] pcieport 0000:00:01.2: AER: enabled with IRQ 52 [ 0.338752] pcieport 0000:00:01.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.340574] pcieport 0000:00:01.3: PME: Signaling with IRQ 53 [ 0.341273] pcieport 0000:00:01.3: AER: enabled with IRQ 53 [ 0.341302] pcieport 0000:00:01.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.343536] pcieport 0000:00:01.4: PME: Signaling with IRQ 54 [ 0.343925] pcieport 0000:00:01.4: AER: enabled with IRQ 54 [ 0.343943] pcieport 0000:00:01.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.345682] pcieport 0000:00:01.5: PME: Signaling with IRQ 55 [ 0.346094] pcieport 0000:00:01.5: AER: enabled with IRQ 55 [ 0.346155] pcieport 0000:00:01.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.347971] pcieport 0000:00:01.6: PME: Signaling with IRQ 56 [ 0.348857] pcieport 0000:00:01.6: AER: enabled with IRQ 56 [ 0.349015] pcieport 0000:00:01.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.350696] pcieport 0000:00:01.7: PME: Signaling with IRQ 57 [ 0.351332] pcieport 0000:00:01.7: AER: enabled with IRQ 57 [ 0.351451] pcieport 0000:00:01.7: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.352534] ACPI: \_SB_.PCI0.GSI2: Enabled at IRQ 37 [ 0.353457] pcieport 0000:00:02.0: PME: Signaling with IRQ 58 [ 0.353782] pcieport 0000:00:02.0: AER: enabled with IRQ 58 [ 0.353825] pcieport 0000:00:02.0: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.355119] pcieport 0000:00:02.1: PME: Signaling with IRQ 59 [ 0.355374] pcieport 0000:00:02.1: AER: enabled with IRQ 59 [ 0.355459] pcieport 0000:00:02.1: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.357695] pcieport 0000:00:02.2: PME: Signaling with IRQ 60 [ 0.357966] pcieport 0000:00:02.2: AER: enabled with IRQ 60 [ 0.358027] pcieport 0000:00:02.2: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.360667] pcieport 0000:00:02.3: PME: Signaling with IRQ 61 [ 0.361200] pcieport 0000:00:02.3: AER: enabled with IRQ 61 [ 0.361223] pcieport 0000:00:02.3: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.363630] pcieport 0000:00:02.4: PME: Signaling with IRQ 62 [ 0.363965] pcieport 0000:00:02.4: AER: enabled with IRQ 62 [ 0.364035] pcieport 0000:00:02.4: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.365136] pcieport 0000:00:02.5: PME: Signaling with IRQ 63 [ 0.365376] pcieport 0000:00:02.5: AER: enabled with IRQ 63 [ 0.365460] pcieport 0000:00:02.5: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.366302] pcieport 0000:00:02.6: PME: Signaling with IRQ 64 [ 0.366668] pcieport 0000:00:02.6: AER: enabled with IRQ 64 [ 0.366847] pcieport 0000:00:02.6: pciehp: Slot #0 AttnBtn+ PwrCtrl+ MRL- AttnInd+ PwrInd+ HotPlug+ Surprise+ Interlock+ NoCompl- IbPresDis- LLActRep+ [ 0.368113] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input0 [ 0.368122] ACPI: button: Power Button [PWRB] [ 0.373823] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled [ 0.377828] random: crng init done [ 0.381922] rdac: device handler registered [ 0.382656] hp_sw: device handler registered [ 0.382657] emc: device handler registered [ 0.383111] alua: device handler registered [ 0.383905] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 0.384131] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 1 [ 0.384429] xhci_hcd 0000:01:00.0: hcc params 0x00087001 hci version 0x100 quirks 0x0000000000000010 [ 0.386367] xhci_hcd 0000:01:00.0: xHCI Host Controller [ 0.386566] xhci_hcd 0000:01:00.0: new USB bus registered, assigned bus number 2 [ 0.386569] xhci_hcd 0000:01:00.0: Host supports USB 3.0 SuperSpeed [ 0.386601] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002, bcdDevice= 5.14 [ 0.386602] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.386602] usb usb1: Product: xHCI Host Controller [ 0.386603] usb usb1: Manufacturer: Linux 5.14.0-386.el9.aarch64 xhci-hcd [ 0.386603] usb usb1: SerialNumber: 0000:01:00.0 [ 0.387067] hub 1-0:1.0: USB hub found [ 0.387086] hub 1-0:1.0: 15 ports detected [ 0.387710] usb usb2: We don't know the algorithms for LPM for this host, disabling LPM. [ 0.387722] usb usb2: New USB device found, idVendor=1d6b, idProduct=0003, bcdDevice= 5.14 [ 0.387723] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1 [ 0.387724] usb usb2: Product: xHCI Host Controller [ 0.387725] usb usb2: Manufacturer: Linux 5.14.0-386.el9.aarch64 xhci-hcd [ 0.387725] usb usb2: SerialNumber: 0000:01:00.0 [ 0.388166] hub 2-0:1.0: USB hub found [ 0.388340] hub 2-0:1.0: 15 ports detected [ 0.400532] usbcore: registered new interface driver usbserial_generic [ 0.400534] usbserial: USB Serial support registered for generic [ 0.400965] mousedev: PS/2 mouse device common for all mice [ 0.402854] rtc-efi rtc-efi.0: registered as rtc0 [ 0.403038] rtc-efi rtc-efi.0: setting system clock to 2023-12-11T17:58:55 UTC (1702317535) [ 0.403102] ghes_edac: GHES probing device list is empty [ 0.408127] hid: raw HID events driver (C) Jiri Kosina [ 0.408144] usbcore: registered new interface driver usbhid [ 0.408144] usbhid: USB HID core driver [ 0.410801] hw perfevents: enabled with armv8_pmuv3_0 PMU driver, 7 counters available [ 0.410891] drop_monitor: Initializing network drop monitor service [ 0.418087] Initializing XFRM netlink socket [ 0.418194] NET: Registered PF_INET6 protocol family [ 0.715415] usb 1-1: new high-speed USB device number 2 using xhci_hcd [ 0.984605] usb 1-1: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 [ 0.984608] usb 1-1: New USB device strings: Mfr=1, Product=3, SerialNumber=10 [ 0.984609] usb 1-1: Product: QEMU USB Tablet [ 0.984609] usb 1-1: Manufacturer: QEMU [ 0.984610] usb 1-1: SerialNumber: 28754-0000:00:01.0:00.0-1 [ 0.986272] input: QEMU QEMU USB Tablet as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/usb1/1-1/1-1:1.0/0003:0627:0001.0001/input/input1 [ 0.986672] hid-generic 0003:0627:0001.0001: input,hidraw0: USB HID v0.01 Mouse [QEMU QEMU USB Tablet] on usb-0000:01:00.0-1/input0 [ 1.167609] usb 1-2: new high-speed USB device number 3 using xhci_hcd [ 1.373597] usb 1-2: New USB device found, idVendor=0627, idProduct=0001, bcdDevice= 0.00 [ 1.373600] usb 1-2: New USB device strings: Mfr=1, Product=4, SerialNumber=11 [ 1.373601] usb 1-2: Product: QEMU USB Keyboard [ 1.373602] usb 1-2: Manufacturer: QEMU [ 1.373605] usb 1-2: SerialNumber: 68284-0000:00:01.0:00.0-2 [ 1.375406] input: QEMU QEMU USB Keyboard as /devices/pci0000:00/0000:00:01.0/0000:01:00.0/usb1/1-2/1-2:1.0/0003:0627:0001.0002/input/input2 [ 1.442717] hid-generic 0003:0627:0001.0002: input,hidraw1: USB HID v1.11 Keyboard [QEMU QEMU USB Keyboard] on usb-0000:01:00.0-2/input0 [ 3.145403] Freeing initrd memory: 448996K [ 3.153357] Segment Routing with IPv6 [ 3.153376] NET: Registered PF_PACKET protocol family [ 3.153591] mpls_gso: MPLS GSO support [ 3.153792] registered taskstats version 1 [ 3.155417] Loading compiled-in X.509 certificates [ 3.156618] Loaded X.509 cert 'The CentOS Project: CentOS Stream kernel signing key: e37d4e7421df551c55f489f3eb3766ee78bb932b' [ 3.156852] Loaded X.509 cert 'Red Hat Enterprise Linux Driver Update Program (key 3): bf57f3e87362bc7229d9f465321773dfd1f77a80' [ 3.157066] Loaded X.509 cert 'Red Hat Enterprise Linux kpatch signing key: 4d38fd864ebe18c5f0b72e3852e2014c3a676fc8' [ 3.157171] Loaded X.509 cert 'RH-IMA-CA: Red Hat IMA CA: fb31825dd0e073685b264e3038963673f753959a' [ 3.157618] zswap: loaded using pool lzo/zbud [ 3.157740] page_owner is disabled [ 3.157749] Key type big_key registered [ 3.158172] Key type encrypted registered [ 3.159427] ima: secureboot mode disabled [ 3.159429] ima: No TPM chip found, activating TPM-bypass! [ 3.159432] Loading compiled-in module X.509 certificates [ 3.159662] Loaded X.509 cert 'The CentOS Project: CentOS Stream kernel signing key: e37d4e7421df551c55f489f3eb3766ee78bb932b' [ 3.159663] ima: Allocated hash algorithm: sha256 [ 3.159669] ima: No architecture policies found [ 3.159676] evm: Initialising EVM extended attributes: [ 3.159677] evm: security.selinux [ 3.159677] evm: security.SMACK64 (disabled) [ 3.159677] evm: security.SMACK64EXEC (disabled) [ 3.159677] evm: security.SMACK64TRANSMUTE (disabled) [ 3.159678] evm: security.SMACK64MMAP (disabled) [ 3.159678] evm: security.apparmor (disabled) [ 3.159678] evm: security.ima [ 3.159678] evm: security.capability [ 3.159679] evm: HMAC attrs: 0x1 [ 3.272991] Freeing unused kernel memory: 5888K [ 3.404465] Checked W+X mappings: passed, no W+X pages found [ 3.404480] Run /init as init process Welcome to CentOS Stream 9! [ 3.418241] systemd[1]: systemd 252-18.el9 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) [ 3.418305] systemd[1]: Detected virtualization qemu. [ 3.418307] systemd[1]: Detected architecture arm64. [ 3.418325] systemd[1]: Detected first boot. [ 3.418775] systemd[1]: No hostname configured, using default hostname. [ 3.418798] systemd[1]: Hostname set to . [ 3.418872] systemd[1]: Initializing machine ID from VM UUID. [ 3.426422] systemd-rc-local-generator[239]: /etc/rc.d/rc.local is not marked executable, skipping. [ 3.429304] systemd-sysv-generator[242]: SysV service '/etc/rc.d/init.d/network' lacks a native systemd unit file. Automatically generating a unit file for compatibility. Please update package to include a native systemd unit file, in order to make it more safe and robust. [ 3.432101] systemd-gpt-auto-generator[235]: EFI loader partition unknown, exiting. [ 3.432103] systemd-gpt-auto-generator[235]: (The boot loader did not set EFI variable LoaderDevicePartUUID.) [ 3.527411] systemd[1]: Populated /etc with preset unit settings. [ OK ] Created slice Slice /system/getty. [ OK ] Created slice Slice /system/modprobe. [ OK ] Created slice Slice /system/serial-getty. [ OK ] Created slice Slice /system/sshd-keygen. [ OK ] Created slice User and Session Slice. [ OK ] Started Dispatch Password …ts to Console Directory Watch. [ OK ] Started Forward Password R…uests to Wall Directory Watch. [ OK ] Set up automount Arbitrary…s File System Automount Point. [ OK ] Reached target Local Encrypted Volumes. [ OK ] Reached target Local Integrity Protected Volumes. [ OK ] Reached target Slice Units. [ OK ] Reached target Swaps.[ 3.552464] systemd[1]: Queued start job for default target Multi-User System. [ 3.553098] systemd[1]: Created slice Slice /system/getty. [ 3.553863] systemd[1]: Created slice Slice /system/modprobe. [ 3.554826] systemd[1]: Created slice Slice /system/serial-getty. [ 3.555709] systemd[1]: Created slice Slice /system/sshd-keygen. [ 3.556467] systemd[1]: Created slice User and Session Slice. [ 3.557231] systemd[1]: Started Dispatch Password Requests to Console Directory Watch. [ OK ] Reached target Local Verity Protected Volumes.[ 3.558227] systemd[1]: Started Forward Password Requests to Wall Directory Watch. [ 3.559192] systemd[1]: Set up automount Arbitrary Executable File Formats File System Automount Point. [ 3.559752] systemd[1]: Reached target Local Encrypted Volumes. [ 3.560141] systemd[1]: Reached target Local Integrity Protected Volumes. [ 3.560968] systemd[1]: Reached target Slice Units. [ 3.561618] systemd[1]: Reached target Swaps. [ 3.564392] systemd[1]: Reached target Local Verity Protected Volumes. [ 3.570621] systemd[1]: multipathd control socket was skipped because of an unmet condition check (ConditionPathExists=/etc/multipath.conf). [ 3.573605] systemd[1]: Listening on RPCbind Server Activation Socket. [ OK ] Listening on RPCbind Server Activation Socket. [ OK ] Reached target RPC Port Mapper. [ OK ] Listening on Process Core Dump Socket. [ OK ] Listening on initctl Compatibility Named Pipe. [ OK ] Listening on Journal Socket (/dev/log). [ OK ] Listening on Journal Socket. [ OK ] Listening on udev Control Socket.[ 3.577626] systemd[1]: Reached target RPC Port Mapper. [ 3.578870] systemd[1]: Listening on Process Core Dump Socket. [ 3.579613] systemd[1]: Listening on initctl Compatibility Named Pipe. [ 3.580365] systemd[1]: Listening on Journal Socket (/dev/log). [ 3.581142] systemd[1]: Listening on Journal Socket. [ OK ] Listening on udev Kernel Socket.[ 3.581741] systemd[1]: Listening on udev Control Socket. [ 3.583931] systemd[1]: Listening on udev Kernel Socket. Mounting Huge Pages File System... Mounting POSIX Message Queue File System... Mounting Kernel Debug File System... Mounting Kernel Trace File System...[ 3.587920] systemd[1]: Mounting Huge Pages File System... [ 3.588892] systemd[1]: Mounting POSIX Message Queue File System... [ 3.589912] systemd[1]: Mounting Kernel Debug File System... [ 3.590965] systemd[1]: Mounting Kernel Trace File System... Starting Create List of Static Device Nodes... Starting Load Kernel Module configfs... Starting Load Kernel Module drm... Starting Load Kernel Module efi_pstore... Starting Load Kernel Module fuse... Starting Read and set NIS …from /etc/sysconfig/network... [ 3.594649] systemd[1]: Kernel Module supporting RPCSEC_GSS was skipped because of an unmet condition check (ConditionPathExists=/etc/krb5.keytab). [ 3.595195] systemd[1]: Starting Create List of Static Device Nodes... [ 3.595974] systemd[1]: Load legacy module configuration was skipped because no trigger condition checks were met. [ 3.596512] systemd[1]: Starting Load Kernel Module configfs... [ 3.597614] systemd[1]: Starting Load Kernel Module drm... [ 3.598403] systemd[1]: Starting Load Kernel Module efi_pstore... [ 3.599545] systemd[1]: Starting Load Kernel Module fuse... [ 3.601317] systemd[1]: Starting Read and set NIS domainname from /etc/sysconfig/network... Starting Journal Service... [ 3.609383] systemd[1]: Starting Journal Service... Starting Load Kernel Modules... Starting Generate network …ts from Kernel command line... Starting Remount Root and Kernel File Systems... Starting Coldplug All udev Devices...[ 3.610927] fuse: init (API version 7.36) [ 3.612891] systemd[1]: Starting Load Kernel Modules... [ 3.615820] systemd[1]: Starting Generate network units from Kernel command line... [ 3.617869] systemd[1]: Starting Remount Root and Kernel File Systems... [ 3.618520] systemd[1]: Repartition Root Disk was skipped because no trigger condition checks were met. [ 3.621201] ACPI: bus type drm_connector registered [ 3.622078] systemd[1]: Starting Coldplug All udev Devices... [ 3.626346] Loading iSCSI transport class v2.0-870. [ 3.628593] systemd[1]: Mounted Huge Pages File System. [ OK ] Mounted Huge Pages File System. [ OK ] Mounted POSIX Message Queue File System. [ OK ] Mounted Kernel Debug File System. [ 3.632525] systemd[1]: Mounted POSIX Message Queue File System. [ 3.635374] systemd[1]: Mounted Kernel Debug File System. [ OK ] Mounted Kernel Trace File System. [ 3.649270] systemd[1]: Mounted Kernel Trace File System. [ OK ] Finished Create List of Static Device Nodes. [ OK ] Finished Load Kernel Module configfs. [ OK ] Finished Load Kernel Module drm. [ 3.654043] systemd[1]: Finished Create List of Static Device Nodes. [ OK ] Finished Load Kernel Module efi_pstore. [ OK ] Finished Load Kernel Module fuse. [ OK ] Finished Read and set NIS …e from /etc/sysconfig/network. [ OK ] Finished Generate network units from Kernel command line. [ OK ] Finished Remount Root and Kernel File Systems. [ OK ] Reached target Preparation for Network. Mounting FUSE Control File System...[ 3.657735] systemd[1]: modprobe@configfs.service: Deactivated successfully. [ 3.657836] systemd[1]: Finished Load Kernel Module configfs. [ 3.660335] systemd[1]: modprobe@drm.service: Deactivated successfully. [ 3.661000] systemd[1]: Finished Load Kernel Module drm. [ 3.663165] systemd[1]: modprobe@efi_pstore.service: Deactivated successfully. [ 3.663267] systemd[1]: Finished Load Kernel Module efi_pstore. Mounting Kernel Configuration File System... Starting First Boot Wizard... Starting Rebuild Hardware Database... Starting Load/Save Random Seed... [ OK ] Mounted FUSE Control File System.[ 3.665111] systemd[1]: modprobe@fuse.service: Deactivated successfully. [ 3.665276] systemd[1]: Finished Load Kernel Module fuse. [ 3.666833] systemd[1]: Finished Read and set NIS domainname from /etc/sysconfig/network. [ 3.667776] systemd[1]: Finished Generate network units from Kernel command line. [ OK ] Started Journal Service.[ 3.669312] systemd[1]: Finished Remount Root and Kernel File Systems. [ 3.669776] systemd[1]: Reached target Preparation for Network. [ 3.671966] systemd[1]: Mounting FUSE Control File System... [ 3.674494] iscsi: registered transport (tcp) [ 3.677279] systemd[1]: Mounting Kernel Configuration File System... [ 3.677896] systemd[1]: Special handling of early boot iSCSI sessions was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/class/iscsi_session). [ OK ] Mounted Kernel Configuration File System. [ OK ] Finished Load/Save Random Seed. Starting Flush Journal to Persistent Storage... [ OK ] Finished Flush Journal to Persistent Storage.[ 3.678788] systemd[1]: Starting First Boot Wizard... [ 3.679763] systemd[1]: Starting Rebuild Hardware Database... [ 3.679998] systemd[1]: Platform Persistent Storage Archival was skipped because of an unmet condition check (ConditionDirectoryNotEmpty=/sys/fs/pstore). [ 3.680577] systemd[1]: Starting Load/Save Random Seed... [ 3.681821] systemd[1]: Mounted FUSE Control File System. [ 3.683834] systemd[1]: Started Journal Service. [ 3.691796] iscsi: registered transport (qla4xxx) [ 3.691819] QLogic iSCSI HBA Driver [ 3.699950] systemd-journald[261]: Received client request to flush runtime journal. [ OK ] Finished Coldplug All udev Devices.[ 3.700191] libcxgbi:libcxgbi_init_module: Chelsio iSCSI driver library libcxgbi v0.9.1-ko (Apr. 2015) [ OK ] Finished First Boot Wizard. [ OK ] Reached target First Boot Complete. Starting Create System Users... Starting Wait for udev To …plete Device Initialization... [ OK ] Finished Create System Users. Starting Create Static Device Nodes in /dev... [ OK ] Finished Create Static Device Nodes in /dev. [ 3.738837] Chelsio T4-T6 iSCSI Driver cxgb4i v0.9.5-ko (Apr. 2015) [ 3.738851] iscsi: registered transport (cxgb4i) [ 3.745743] iscsi: registered transport (be2iscsi) [ 3.745744] In beiscsi_module_init, tt=00000000cd0044c3 [ OK ] Finished Load Kernel Modules. Starting Apply Kernel Variables... [ OK ] Finished Apply Kernel Variables.[ 3.762248] Rounding down aligned max_sectors from 4294967295 to 4294967288 [ OK ] Finished Rebuild Hardware Database. Starting Rule-based Manage…for Device Events and Files... [ OK ] Started Rule-based Manager for Device Events and Files. [ OK ] Found device /dev/ttyAMA0. [ 4.129099] virtio_blk virtio1: 8/0/0 default/read/poll queues [ 4.184775] virtio_blk virtio1: [vda] 104857600 512-byte logical blocks (53.7 GB/50.0 GiB) Starting Load Kernel Module efi_pstore... [ OK ] Finished Load Kernel Module efi_pstore. [ OK ] Created slice Slice /system/dhcp-interface. Starting Load Kernel Module efi_pstore... [ OK ] Finished Load Kernel Module efi_pstore. [ 4.292778] virtio_gpu: probe of virtio4 failed with error -22 [ OK ] Finished Wait for udev To Complete Device Initialization. [ OK ] Reached target Preparation for Local File Systems. [ OK ] Reached target Local File Systems. Starting Rebuild Dynamic Linker Cache... Starting Mark the need to relabel after reboot... Starting Automatic Boot Loader Update... Starting Commit a transient machine-id on disk... Starting Create Volatile Files and Directories... [ OK ] Finished Mark the need to relabel after reboot. [ OK ] Finished Automatic Boot Loader Update. [ OK ] Finished Commit a transient machine-id on disk. [ OK ] Finished Rebuild Dynamic Linker Cache. [ OK ] Finished Create Volatile Files and Directories. Starting Security Auditing Service... Starting RPC Bind... Starting Rebuild Journal Catalog... [FAILED] Failed to start Security Auditing Service. See 'systemctl status auditd.service' for details. Starting Record System Boot/Shutdown in UTMP... [ OK ] Finished Rebuild Journal Catalog. Starting Update is Completed... [ OK ] Started RPC Bind. [ OK ] Finished Record System Boot/Shutdown in UTMP. [ OK ] Finished Update is Completed. [ OK ] Reached target System Initialization. [ OK ] Started Ironic user rescue - notify path existence. [ OK ] Started dnf makecache --timer. [ OK ] Started Daily rotation of log files. [ OK ] Started Daily Cleanup of Temporary Directories. [ OK ] Reached target Path Units. [ OK ] Reached target Timer Units. [ OK ] Listening on D-Bus System Message Bus Socket. [ OK ] Listening on Open-iSCSI iscsid Socket. [ OK ] Listening on Open-iSCSI iscsiuio Socket. [ OK ] Listening on SSSD Kerberos…ache Manager responder socket. [ OK ] Reached target Socket Units. Starting D-Bus System Message Bus... Mounting RPC Pipe File System... [ OK ] Started D-Bus System Message Bus. [ OK ] Reached target Basic System. Starting Network Manager... Starting NTP client/server... Starting Restore /run/initramfs on shutdown... [ OK ] Started irqbalance daemon. [ OK ] Started QEMU Guest Agent. [ OK ] Started Hardware RNG Entropy Gatherer Daemon. Starting OpenSSH ecdsa Server Key Generation... Starting OpenSSH ed25519 Server Key Generation... Starting OpenSSH rsa Server Key Generation... [ OK ] Reached target User and Group Name Lookups. Starting User Login Management... [ OK ] Finished Restore /run/initramfs on shutdown. [ OK ] Mounted RPC Pipe File System. [ OK ] Finished OpenSSH ecdsa Server Key Generation. [ OK ] Finished OpenSSH ed25519 Server Key Generation. [ OK ] Reached target rpc_pipefs.target.[ 4.674100] RPC: Registered named UNIX socket transport module. [ 4.674102] RPC: Registered udp transport module. [ 4.674102] RPC: Registered tcp transport module. [ 4.674102] RPC: Registered tcp NFSv4.1 backchannel transport module. [ OK ] Started NTP client/server. Starting Hostname Service... [ OK ] Stopped Security Auditing Service. Starting Security Auditing Service... [ OK ] Started Hostname Service. [ OK ] Started User Login Management. [FAILED] Failed to start Security Auditing Service. See 'systemctl status auditd.service' for details. [ OK ] Listening on Load/Save RF …itch Status /dev/rfkill Watch. Starting Network Manager Script Dispatcher Service... [ OK ] Started Network Manager. [ OK ] Reached target Network. Starting Network Manager Wait Online... Starting DHCP interface eth0... Starting Dynamic Login... Starting GSSAPI Proxy Daemon... Starting Initialize iBFT network interfaces... [ OK ] Finished Dynamic Login. [ OK ] Started GSSAPI Proxy Daemon. [ OK ] Started Network Manager Script Dispatcher Service. [ OK ] Reached target NFS client services. [ OK ] Finished Initialize iBFT network interfaces. [ OK ] Finished Network Manager Wait Online. [ OK ] Finished DHCP interface eth0. [ OK ] Reached target Network is Online. [ OK ] Reached target Preparation for Remote File Systems. [ OK ] Reached target Remote Encrypted Volumes. [ OK ] Reached target Remote File Systems. [ OK ] Started Ironic Python Agent. Starting Notify NFS peers of a restart... Starting System Logging Service... Starting Permit User Sessions... [ OK ] Started Notify NFS peers of a restart. [ OK ] Finished Permit User Sessions. [ OK ] Started Command Scheduler. [ OK ] Started Getty on tty1. [ OK ] Started Serial Getty on ttyAMA0. [ OK ] Reached target Login Prompts. [ OK ] Stopped Security Auditing Service. Starting Security Auditing Service... [FAILED] Failed to start Security Auditing Service. See 'systemctl status auditd.service' for details. [ OK ] Started System Logging Service. [ OK ] Stopped Security Auditing Service. Starting Security Auditing Service... [FAILED] Failed to start Security Auditing Service. See 'systemctl status auditd.service' for details. [ 5.281761] IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready [ OK ] Finished OpenSSH rsa Server Key Generation. [ OK ] Reached target sshd-keygen.target. [ OK ] Stopped Security Auditing Service. Starting Security Auditing Service... Starting OpenSSH server daemon... [ OK ] Started OpenSSH server daemon. [ OK ] Reached target Multi-User System. Starting Record Runlevel Change in UTMP... [FAILED] Failed to start Security Auditing Service. See 'systemctl status auditd.service' for details. [ OK ] Finished Record Runlevel Change in UTMP. ironic-python-agent: Did not identify any virtual media candidates devices. ironic-python-agent: No virtual media device detected 2023-12-11 12:59:01.117 503 DEBUG ironic_python_agent.cmd.agent [-] Configuration: run /usr/lib/python3.9/site-packages/ironic_python_agent/cmd/agent.py:47 2023-12-11 12:59:01.118 503 DEBUG ironic_python_agent.cmd.agent [-] ******************************************************************************** log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2593 2023-12-11 12:59:01.121 503 DEBUG ironic_python_agent.cmd.agent [-] Configuration options gathered from: log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2594 2023-12-11 12:59:01.123 503 DEBUG ironic_python_agent.cmd.agent [-] command line args: ['--config-dir', '/etc/ironic-python-agent'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2595 2023-12-11 12:59:01.128 503 DEBUG ironic_python_agent.cmd.agent [-] config files: [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2596 2023-12-11 12:59:01.132 503 DEBUG ironic_python_agent.cmd.agent [-] ================================================================================ log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2598 2023-12-11 12:59:01.136 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_host = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.138 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_port = 9999 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.144 503 DEBUG ironic_python_agent.cmd.agent [-] advertise_protocol = http log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.149 503 DEBUG ironic_python_agent.cmd.agent [-] agent_token = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.152 503 DEBUG ironic_python_agent.cmd.agent [-] agent_token_required = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.157 503 DEBUG ironic_python_agent.cmd.agent [-] api_url = http://192.168.24.1:6385 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.162 503 DEBUG ironic_python_agent.cmd.agent [-] auto_tls_allowed_clock_skew = 3600 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.165 503 DEBUG ironic_python_agent.cmd.agent [-] cafile = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.167 503 DEBUG ironic_python_agent.cmd.agent [-] certfile = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.171 503 DEBUG ironic_python_agent.cmd.agent [-] collect_lldp = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.174 503 DEBUG ironic_python_agent.cmd.agent [-] config_dir = ['/etc/ironic-python-agent'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.178 503 DEBUG ironic_python_agent.cmd.agent [-] config_file = [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.182 503 DEBUG ironic_python_agent.cmd.agent [-] config_source = [] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.185 503 DEBUG ironic_python_agent.cmd.agent [-] debug = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.189 503 DEBUG ironic_python_agent.cmd.agent [-] default_log_levels = ['amqp=WARN', 'amqplib=WARN', 'boto=WARN', 'qpid=WARN', 'sqlalchemy=WARN', 'suds=INFO', 'oslo.messaging=INFO', 'oslo_messaging=INFO', 'iso8601=WARN', 'requests.packages.urllib3.connectionpool=WARN', 'urllib3.connectionpool=WARN', 'websocket=WARN', 'requests.packages.urllib3.util.retry=WARN', 'urllib3.util.retry=WARN', 'keystonemiddleware=WARN', 'routes.middleware=WARN', 'stevedore=WARN', 'taskflow=WARN', 'keystoneauth=WARN', 'oslo.cache=INFO', 'oslo_policy=INFO', 'dogpile.core.dogpile=INFO'] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.197 503 DEBUG ironic_python_agent.cmd.agent [-] disk_wait_attempts = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.201 503 DEBUG ironic_python_agent.cmd.agent [-] disk_wait_delay = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.204 503 DEBUG ironic_python_agent.cmd.agent [-] enable_auto_tls = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.206 503 DEBUG ironic_python_agent.cmd.agent [-] enable_vlan_interfaces = log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.211 503 DEBUG ironic_python_agent.cmd.agent [-] fail_if_clock_not_set = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.215 503 DEBUG ironic_python_agent.cmd.agent [-] global_request_id = req-f843aae4-963b-42aa-8165-31fed61bf55e log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.221 503 DEBUG ironic_python_agent.cmd.agent [-] hardware_initialization_delay = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.223 503 DEBUG ironic_python_agent.cmd.agent [-] ignore_bootloader_failure = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.227 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_retries = 9 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.231 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_retry_interval = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.235 503 DEBUG ironic_python_agent.cmd.agent [-] image_download_connection_timeout = 60 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.239 503 DEBUG ironic_python_agent.cmd.agent [-] inject_files_priority = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.241 503 DEBUG ironic_python_agent.cmd.agent [-] insecure = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.244 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_callback_url = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.247 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_collectors = default,logs log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.252 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_dhcp_all_interfaces = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.256 503 DEBUG ironic_python_agent.cmd.agent [-] inspection_dhcp_wait_timeout = 60 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.260 503 DEBUG ironic_python_agent.cmd.agent [-] instance_format = [instance: %(uuid)s] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.264 503 DEBUG ironic_python_agent.cmd.agent [-] instance_uuid_format = [instance: %(uuid)s] log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.268 503 DEBUG ironic_python_agent.cmd.agent [-] introspection_daemon = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.270 503 DEBUG ironic_python_agent.cmd.agent [-] introspection_daemon_post_interval = 300 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.273 503 DEBUG ironic_python_agent.cmd.agent [-] ip_lookup_attempts = 6 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.276 503 DEBUG ironic_python_agent.cmd.agent [-] ip_lookup_sleep = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.280 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_api_version = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.283 503 DEBUG ironic_python_agent.cmd.agent [-] keyfile = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.285 503 DEBUG ironic_python_agent.cmd.agent [-] listen_host = :: log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.286 503 DEBUG ironic_python_agent.cmd.agent [-] listen_port = 9999 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.288 503 DEBUG ironic_python_agent.cmd.agent [-] listen_tls = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.292 503 DEBUG ironic_python_agent.cmd.agent [-] lldp_timeout = 30.0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.296 503 DEBUG ironic_python_agent.cmd.agent [-] log_config_append = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.299 503 DEBUG ironic_python_agent.cmd.agent [-] log_date_format = %Y-%m-%d %H:%M:%S log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.302 503 DEBUG ironic_python_agent.cmd.agent [-] log_dir = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.304 503 DEBUG ironic_python_agent.cmd.agent [-] log_file = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.306 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotate_interval = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.309 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotate_interval_type = days log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.313 503 DEBUG ironic_python_agent.cmd.agent [-] log_rotation_type = none log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.316 503 DEBUG ironic_python_agent.cmd.agent [-] logging_context_format_string = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [%(request_id)s %(user_identity)s] %(instance)s%(message)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.324 503 DEBUG ironic_python_agent.cmd.agent [-] logging_debug_format_suffix = %(funcName)s %(pathname)s:%(lineno)d log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.330 503 DEBUG ironic_python_agent.cmd.agent [-] logging_default_format_string = %(asctime)s.%(msecs)03d %(process)d %(levelname)s %(name)s [-] %(instance)s%(message)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.334 503 DEBUG ironic_python_agent.cmd.agent [-] logging_exception_prefix = %(asctime)s.%(msecs)03d %(process)d ERROR %(name)s %(instance)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.339 503 DEBUG ironic_python_agent.cmd.agent [-] logging_user_identity_format = %(user)s %(tenant)s %(domain)s %(user_domain)s %(project_domain)s log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.342 503 DEBUG ironic_python_agent.cmd.agent [-] lookup_interval = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.345 503 DEBUG ironic_python_agent.cmd.agent [-] lookup_timeout = 300 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.348 503 DEBUG ironic_python_agent.cmd.agent [-] max_logfile_count = 30 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.352 503 DEBUG ironic_python_agent.cmd.agent [-] max_logfile_size_mb = 200 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.357 503 DEBUG ironic_python_agent.cmd.agent [-] network_interface = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.361 503 DEBUG ironic_python_agent.cmd.agent [-] ntp_server = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.364 503 DEBUG ironic_python_agent.cmd.agent [-] publish_errors = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.365 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_burst = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.369 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_except_level = CRITICAL log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.373 503 DEBUG ironic_python_agent.cmd.agent [-] rate_limit_interval = 0 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.376 503 DEBUG ironic_python_agent.cmd.agent [-] syslog_log_facility = LOG_USER log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.381 503 DEBUG ironic_python_agent.cmd.agent [-] use_eventlog = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.385 503 DEBUG ironic_python_agent.cmd.agent [-] use_journal = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.388 503 DEBUG ironic_python_agent.cmd.agent [-] use_json = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.389 503 DEBUG ironic_python_agent.cmd.agent [-] use_stderr = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.390 503 DEBUG ironic_python_agent.cmd.agent [-] use_syslog = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.393 503 DEBUG ironic_python_agent.cmd.agent [-] watch_log_file = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2606 2023-12-11 12:59:01.395 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_lib.fatal_exception_format_errors = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.398 503 DEBUG ironic_python_agent.cmd.agent [-] ironic_lib.root_helper = sudo ironic-rootwrap /etc/ironic/rootwrap.conf log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.406 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.interfaces = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.409 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.lookup_attempts = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.413 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.params = {} log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.417 503 DEBUG ironic_python_agent.cmd.agent [-] mdns.registration_attempts = 5 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.422 503 DEBUG ironic_python_agent.cmd.agent [-] metrics_statsd.statsd_host = localhost log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.423 503 DEBUG ironic_python_agent.cmd.agent [-] metrics_statsd.statsd_port = 8125 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.427 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.backend = noop log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.432 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.global_prefix = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.435 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.prepend_host = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.439 503 DEBUG ironic_python_agent.cmd.agent [-] metrics.prepend_host_reverse = True log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.443 503 DEBUG ironic_python_agent.cmd.agent [-] oslo_concurrency.disable_process_locking = False log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.447 503 DEBUG ironic_python_agent.cmd.agent [-] oslo_concurrency.lock_path = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.451 503 DEBUG ironic_python_agent.cmd.agent [-] disk_partitioner.check_device_interval = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.455 503 DEBUG ironic_python_agent.cmd.agent [-] disk_partitioner.check_device_max_retries = 20 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.459 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.bios_boot_partition_size = 1 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.463 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.dd_block_size = 1M log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.466 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.efi_system_partition_size = 200 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.471 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.image_convert_attempts = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.475 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.image_convert_memory_limit = 2048 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.477 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.partition_detection_attempts = 3 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.480 503 DEBUG ironic_python_agent.cmd.agent [-] disk_utils.partprobe_attempts = 10 log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.484 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.ca_file = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.488 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.cert_file = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.492 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.ciphers = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.495 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.key_file = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.499 503 DEBUG ironic_python_agent.cmd.agent [-] ssl.version = None log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2613 2023-12-11 12:59:01.502 503 DEBUG ironic_python_agent.cmd.agent [-] ******************************************************************************** log_opt_values /usr/lib/python3.9/site-packages/oslo_config/cfg.py:2617 2023-12-11 12:59:01.507 503 INFO ironic_python_agent.utils [-] Early logging: Did not identify any virtual media candidates devices. 2023-12-11 12:59:01.515 503 INFO ironic_python_agent.utils [-] Early logging: No virtual media device detected 2023-12-11 12:59:01.561 503 INFO ironic_python_agent.agent [-] Starting ironic-python-agent version: 7.1.1.dev6 2023-12-11 12:59:01.566 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.575 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.577 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.583 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.585 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.590 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. Command: readlink -v /sys/class/net/lo/device/driver/module Exit code: 1 Stdout: '' Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40 2023-12-11 12:59:01.596 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.605 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.610 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.614 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.616 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87 CentOS Stream 9 Kernel 5.14.0-386.el9.aarch64 on an aarch64 Activate the web console with: systemctl enable --now cockpit.socket 2023-12-11 12:59:01.621 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 host-192-168-24-15 login: 2023-12-11 12:59:01.626 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.631 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.635 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.639 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.641 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. Command: iscsistart -f Exit code: 21 Stdout: 'Could not get list of targets from firmware.\n' Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171 2023-12-11 12:59:01.648 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.657 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.009s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.664 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.668 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.671 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly mdadm: no recogniseable superblock on /dev/vda mdadm: No arrays found in config file or automatically " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.675 503 INFO root [-] No new RAID devices assembled during start-up 2023-12-11 12:59:01.677 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): /usr/sbin/mpathconf --enable --find_multipaths yes --with_module y --with_multipathd y execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 [ 6.616897] device-mapper: core: CONFIG_IMA_DISABLE_HTABLE is disabled. Duplicate IMA measurements will not be recorded in the IMA log. [ 6.616913] device-mapper: uevent: version 1.0.3 [ 6.617162] device-mapper: ioctl: 4.48.0-ioctl (2023-03-01) initialised: dm-devel@redhat.com 2023-12-11 12:59:01.739 503 DEBUG oslo_concurrency.processutils [-] CMD "/usr/sbin/mpathconf --enable --find_multipaths yes --with_module y --with_multipathd y" returned: 0 in 0.061s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.741 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.742 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.743 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipathd execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.752 503 DEBUG oslo_concurrency.processutils [-] CMD "multipathd" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.754 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.757 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.760 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -ll execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.768 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -ll" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.774 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.778 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.785 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.796 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.010s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.801 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.803 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.804 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.809 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.812 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.814 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.819 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.823 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.827 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.830 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.832 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.834 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command. Command: multipath -c /dev/vda Exit code: 1 Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n' Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254 2023-12-11 12:59:01.837 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' 2023-12-11 12:59:01.841 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None 2023-12-11 12:59:01.842 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.847 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.850 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.852 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.854 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.855 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. Command: iscsistart -f Exit code: 21 Stdout: 'Could not get list of targets from firmware.\n' Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171 2023-12-11 12:59:01.860 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.866 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.868 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.870 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.871 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly mdadm: no recogniseable superblock on /dev/vda mdadm: No arrays found in config file or automatically " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.873 503 INFO root [-] No new RAID devices assembled during start-up 2023-12-11 12:59:01.874 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.881 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.883 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.885 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.886 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.892 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.894 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.896 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.901 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.906 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.910 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.914 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.916 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.919 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command. Command: multipath -c /dev/vda Exit code: 1 Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n' Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254 2023-12-11 12:59:01.923 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' 2023-12-11 12:59:01.925 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None 2023-12-11 12:59:01.926 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor' 2023-12-11 12:59:01.929 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84 2023-12-11 12:59:01.932 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.937 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.940 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.943 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.945 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.947 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. Command: readlink -v /sys/class/net/lo/device/driver/module Exit code: 1 Stdout: '' Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40 2023-12-11 12:59:01.953 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.958 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.961 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.964 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.966 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87 2023-12-11 12:59:01.969 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor' 2023-12-11 12:59:01.971 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84 2023-12-11 12:59:01.973 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.977 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:01.980 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:01.984 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:01.986 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:01.987 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. Command: iscsistart -f Exit code: 21 Stdout: 'Could not get list of targets from firmware.\n' Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171 2023-12-11 12:59:01.991 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:01.999 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.002 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:02.005 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.006 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly mdadm: no recogniseable superblock on /dev/vda mdadm: No arrays found in config file or automatically " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.011 503 INFO root [-] No new RAID devices assembled during start-up 2023-12-11 12:59:02.012 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.019 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.022 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.024 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.025 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.031 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.034 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.037 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.041 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.046 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.050 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:02.051 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.053 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.054 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command. Command: multipath -c /dev/vda Exit code: 1 Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n' Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254 2023-12-11 12:59:02.058 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' 2023-12-11 12:59:02.060 503 INFO root [-] Picked root device /dev/vda for node None based on root device hints None 2023-12-11 12:59:02.062 503 INFO root [-] Hardware manager found: ironic_python_agent.hardware:GenericHardwareManager 2023-12-11 12:59:02.064 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.068 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.070 503 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:02.072 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.073 503 DEBUG ironic_lib.utils [-] Command stderr is: "readlink: /sys/class/net/lo/device/driver/module: No such file or directory " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.076 503 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. Command: readlink -v /sys/class/net/lo/device/driver/module Exit code: 1 Stdout: '' Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:40 2023-12-11 12:59:02.080 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/eth0/device/driver/module execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.084 503 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/eth0/device/driver/module" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.088 503 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:02.091 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:02.093 503 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/cna.py:87 2023-12-11 12:59:02.095 503 WARNING root [-] Can't find field vendor fordevice lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor' 2023-12-11 12:59:02.098 503 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /usr/lib/python3.9/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84 2023-12-11 12:59:02.101 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth0 execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.105 503 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:02.108 503 DEBUG ironic_lib.utils [-] Command not found: "biosdevname -i eth0" execute /usr/lib/python3.9/site-packages/ironic_lib/utils.py:106 2023-12-11 12:59:02.110 503 WARNING root [-] Executable 'biosdevname' not found: FileNotFoundError: [Errno 2] No such file or directory: 'biosdevname' 2023-12-11 12:59:02.112 503 INFO root [-] Collecting full inventory 2023-12-11 12:59:02.112 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i eth0 execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.116 503 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i eth0' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:02.118 503 DEBUG ironic_lib.utils [-] Command not found: "biosdevname -i eth0" execute /usr/lib/python3.9/site-packages/ironic_lib/utils.py:106 2023-12-11 12:59:02.121 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lscpu execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:02.124 503 DEBUG oslo_concurrency.processutils [-] CMD "lscpu" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:02.127 503 DEBUG ironic_lib.utils [-] Command stdout is: "Architecture: aarch64 CPU op-mode(s): 64-bit Byte Order: Little Endian CPU(s): 8 On-line CPU(s) list: 0-7 Vendor ID: ARM BIOS Vendor ID: QEMU Model name: Cortex-A57 BIOS Model name: virt-8.1 Model: 0 Thread(s) per core: 1 Core(s) per socket: 1 Socket(s): 8 Stepping: r1p0 BogoMIPS: 48.00 Flags: fp asimd evtstrm aes pmull sha1 sha2 crc32 fphp asimdhp cpuid dit NUMA node(s): 1 NUMA node0 CPU(s): 0-7 Vulnerability Gather data sampling: Not affected Vulnerability Itlb multihit: Not affected Vulnerability L1tf: Not affected Vulnerability Mds: Not affected Vulnerability Meltdown: Not affected Vulnerability Mmio stale [removed] 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]} get_deploy_steps /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:40 2023-12-11 12:59:03.927 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:03.932 503 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:03.936 503 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:03.939 503 DEBUG ironic_lib.utils [-] Command stdout is: "Could not get list of targets from firmware. " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:03.941 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:03.943 503 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. Command: iscsistart -f Exit code: 21 Stdout: 'Could not get list of targets from firmware.\n' Stderr: '' _check_for_iscsi /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:171 2023-12-11 12:59:03.946 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:03.952 503 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:03.955 503 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:03.957 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:03.959 503 DEBUG ironic_lib.utils [-] Command stderr is: "mdadm: looking for devices for further assembly mdadm: no recogniseable superblock on /dev/vda mdadm: No arrays found in config file or automatically " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:03.963 503 INFO root [-] No new RAID devices assembled during start-up 2023-12-11 12:59:03.964 503 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:1354 2023-12-11 12:59:03.966 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:03.972 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:03.975 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:03.977 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:03.980 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:03.985 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:03.989 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:03.992 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:03.995 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:04.001 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:04.004 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:04.007 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:04.009 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:04.010 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command. Command: multipath -c /dev/vda Exit code: 1 Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n' Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254 2023-12-11 12:59:04.014 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' 2023-12-11 12:59:04.015 503 INFO root [-] Picked root device /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 based on root device hints None 2023-12-11 12:59:04.016 503 DEBUG root [-] Returning deploy steps: defaultdict(, {'GenericHardwareManager': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]}) get_deploy_steps /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:42 2023-12-11 12:59:04.031 503 INFO root [-] Synchronous command get_deploy_steps completed: {'deploy_steps': {'GenericHardwareManager': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}]}, 'hardware_manager_version': {'generic_hardware_manager': '1.1'}} 2023-12-11 12:59:04.048 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=true&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200 len: 6266 time: 0.2470188 2023-12-11 12:59:04.063 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "GET /v1/commands/ HTTP/1.1" status: 200 len: 6282 time: 0.0002947 2023-12-11 12:59:04.117 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 55364, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992 2023-12-11 12:59:04.122 503 DEBUG root [-] Executing command: deploy.execute_deploy_step with args: {'step': {'interface': 'deploy', 'step': 'write_image', 'args': {'image_info': {'id': 'overcloud-full.raw', 'urls': ['http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5'], 'disk_format': 'raw', 'container_format': None, 'stream_raw_images': True, 'os_hash_algo': 'sha256', 'os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'node_uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5'}, 'configdrive': '<...>'}}, 'node': {'id': 1, 'uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5', 'name': 'controller', 'chassis_id': None, 'instance_uuid': '7570d05e-785b-41c0-9fc0-ad0f8c3710b5', 'driver': 'ipmi', 'driver_info': {'ipmi_username': 'admin', 'ipmi_password': '******', 'ipmi_address': '10.0.2.2', 'deploy_kernel': 'file:///var/lib/ironic/httpboot/arm64/agent.kernel', 'deploy_ramdisk': 'file:///var/lib/ironic/httpboot/arm64/agent.ramdisk', 'rescue_ramdisk': 'file:///var/lib/ironic/httpboot/arm64/agent.ramdisk', 'rescue_kernel': 'file:///var/lib/ironic/httpboot/arm64/agent.kernel'}, 'driver_internal_info': {'last_power_state_change': '2023-12-11T17:58:23.429968', 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'write_image', 'priority': 80, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'prepare_instance_boot', 'priority': 60, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'tear_down_agent', 'priority': 40, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'switch_to_tenant_network', 'priority': 30, 'argsinfo': None, 'interface': 'deploy'}, {'step': 'boot_instance', 'priority': 20, 'argsinfo': None, 'interface': 'deploy'}], 'agent_version': '7.1.1.dev6', 'agent_last_heartbeat': '2023-12-11T17:59:03.580135', 'hardware_manager_version': {'generic_hardware_manager': '1.1'}, 'agent_cached_deploy_steps_refreshed': '2023-12-11 17:59:03.853433', 'clean_steps': None, 'agent_erase_devices_iterations': 1, 'agent_erase_devices_zeroize': True, 'agent_continue_if_secure_erase_failed': False, 'agent_continue_if_ata_erase_failed': False, 'agent_enable_nvme_secure_erase': True, 'agent_enable_ata_secure_erase': True, 'disk_erasure_concurrency': 1, 'agent_erase_skip_read_only': False, 'agent_cached_clean_steps_refreshed': '2023-12-11 17:43:40.216462', 'is_whole_disk_image': True, 'deploy_step_index': 1, 'agent_secret_token': '******', 'agent_url': 'https://192.168.24.15:9999', 'agent_verify_ca': '/var/lib/ironic/certificates/8d5ed008-efea-4df7-a190-aab8274a3bd5.crt', 'agent_cached_deploy_steps': {'deploy': [{'step': 'erase_devices_metadata', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'write_image', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False}, {'step': 'inject_files', 'priority': 0, 'interface': 'deploy', 'reboot_requested': False, 'argsinfo': {'files': {'description': "Files to inject, a list of file structures with keys: 'path' (path to the file), 'partition' (partition specifier), 'content' (base64 encoded string), 'mode' (new file mode) and 'dirmode' (mode for the leaf directory, if created). Merged with the values from node.properties[inject_files].", 'required': False}, 'verify_ca': {'description': 'Whether to verify TLS certificates. Global agent options are used by default.', 'required': False}}}], 'raid': [{'step': 'apply_configuration', 'priority': 0, 'interface': 'raid', 'reboot_requested': False, 'argsinfo': {'raid_config': {'description': 'The RAID configuration to apply.', 'required': True}, 'delete_existing': {'description': "Setting this to 'True' indicates to delete existing RAID configuration prior to creating the new configuration. Default value is 'True'.", 'required': False}}}]}, 'steps_validated': True}, 'clean_step': {}, 'deploy_step': {'step': 'write_image', 'priority': 80, 'argsinfo': None, 'interface': 'deploy'}, 'raid_config': {}, 'target_raid_config': {}, 'properties': {'capabilities': 'boot_mode:uefi,profile:controller,disk_label:gpt', 'vendor': 'unknown', 'local_gb': '49', 'cpus': '8', 'cpu_arch': 'aarch64', 'memory_mb': '8192'}, 'reservation': 'undercloud.localdomain', 'conductor_affinity': 1, 'conductor_group': '', 'power_state': 'power on', 'target_power_state': None, 'provision_state': 'deploying', 'provision_updated_at': '2023-12-11T17:59:03.000000', 'target_provision_state': 'active', 'maintenance': False, 'maintenance_reason': None, 'fault': None, 'console_enabled': False, 'last_error': None, 'resource_class': 'baremetal', 'inspection_finished_at': None, 'inspection_started_at': '2023-12-11T13:30:45.000000', 'extra': {'metalsmith_attached_ports': ['b5cb64b0-c130-44bd-ae85-dbe69fc21f53'], 'metalsmith_created_ports': ['b5cb64b0-c130-44bd-ae85-dbe69fc21f53']}, 'automated_clean': None, 'protected': False, 'protected_reason': None, 'allocation_id': 17, 'bios_interface': 'no-bios', 'boot_interface': 'ipxe', 'console_interface': 'ipmitool-socat', 'deploy_interface': 'direct', 'inspect_interface': 'inspector', 'management_interface': 'ipmitool', 'network_interface': 'flat', 'power_interface': 'ipmitool', 'raid_interface': 'no-raid', 'rescue_interface': 'agent', 'storage_interface': 'noop', 'vendor_interface': 'ipmitool', 'traits': {'objects': []}, 'owner': None, 'lessee': None, 'description': None, 'retired': False, 'retired_reason': None, 'network_data': {}, 'created_at': '2023-12-11T13:30:33.000000', 'updated_at': '2023-12-11T17:59:03.910029', 'instance_info': {'traits': [], 'capabilities': {'profile': 'controller'}, 'display_name': 'overcloud-controllernoceph-0', 'image_source': 'file:///var/lib/ironic/images/aarch64/overcloud-full.raw', 'root_gb': 48, 'image_disk_format': 'raw', 'image_checksum': None, 'image_os_hash_algo': 'sha256', 'image_os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'image_url': 'http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5', 'image_type': 'whole-disk-image', 'configdrive': '<...>'}}, 'ports': [{'id': 1, 'uuid': '6e6afff9-41a1-48d8-a2a2-e1eb358bbbb6', 'node_id': 1, 'address': '52:54:00:12:34:58', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'tenant_vif_port_id': 'b5cb64b0-c130-44bd-ae85-dbe69fc21f53'}, 'physical_network': 'ctlplane', 'is_smartnic': False, 'name': None, 'created_at': '2023-12-11T13:30:33.000000', 'updated_at': '2023-12-11T17:58:10.000000'}], 'deploy_version': {'generic_hardware_manager': '1.1'}} execute_command /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py:253 2023-12-11 12:59:04.194 503 DEBUG root [-] Executing deploy step {'interface': 'deploy', 'step': 'write_image', 'args': {'image_info': {'id': 'overcloud-full.raw', 'urls': ['http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5'], 'disk_format': 'raw', 'container_format': None, 'stream_raw_images': True, 'os_hash_algo': 'sha256', 'os_hash_value': 'e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2', 'node_uuid': '8d5ed008-efea-4df7-a190-aab8274a3bd5'}, 'configdrive': 'H4sICLJNd2UC/3RtcGRfMzNwMm0wAO3ca1Mj2XkA4Na4ylmvtzbJ7nqdrNeJzM664mJhdOUysVMRQgzyAAIJZoAkhXVpBg1CAiSYga394CpXJX8q+QX+7l/h8k9IuiWYy+4YbM84s+N9HiH15bzndEs0/XYfNR0EAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAECiOJ9KpRNB8hrNXne3/WAi8/vKL9v77+cGVyw3+omfwVtvBT8dzfrp3z0t/vv4ZSx4fzT1fvBWPHgr+Pyd7/7t7beDG5f1k6/Zcmm9sFRbLq8vvp7lb1aq1XKtMpGezE/mkplUJj2ZSk9mU5PpfCr6tX6WXCrPReVzG9WVUczlnIXaM9NPi5NcJfqAs+lMOp2ezs+kow84+MqM1Jd8dcbr32h5vSQdvjZuXOT/IHnNoxn0gm6wG7SDB8FEkPlK+Zfy/6e3Sn9w/l8fzVr/h6fF/zjK/x+Npj76ffn/2rX+cz8OgjAYBPWgE/Sj8XY0vvf/uvzNoBJUo0c5qEVjE0E6mAzy0TMXlWWCKEEN56SGr9loOBmN5aNhajjvsyhqKao7d1F/LtiI2lp5rp0vxyxEr0/Lk2/A49UFvZr8D/C18L+X+R8A+Mb41bV97LXVv0r8z++C1c2biYNCHFI4SDw5h3/e+sJHiXcv6j8/KJY+TnwwCvrgMvq3l4NfXLcOiZdb+GnwvVHI917c/nd6h2G3P6g3919uOSvL7yaCJ23ZtgD4+ipVf5t4e/0/E9VqeXUnPTubLqwvlpLVSvFuslqev1NKllfWS9XiYmElGl+tVtYrxcpSPHKvPF+qJWsbq6uV6npyoVJNrlZq5c3kQnmplKxt1dZLy8laabmwsl4u1laXSoVaKVmsrKwXiuvJ+XKtmFzdmFsq1xZL1WHl2mqpWF4oFwvr5cpKslbZqBZLk8mohdIzgdEio+YWyvHoSrQS5eVCdSt5r7K0sVxKRqtTjN7EemXU4OWyyivR1PKw2Um/bgAY+sU158aJlzzx/3Of258HH45CPnxx+29nUunMTmpmJ50KXvbs/q8TQdzaRGpmImrtOPj+qMr3X7zkb3fqg7A/eOmlfjcRXLQEAK8u/1+dP18+/19zfPGS+f9XwY9GIT/KfysefCt/UTBqJvE3B+GgvtOqD+qTD/u97j+nh+/n5i/j8l/e/FPy8fuJ4Pk2g/8KxkbVxn4zbCnxm+fX4f1uOHjUO95/djVedi0+TARfafU8+GRU8ZNf34gHN379/Hq8c9IPj0fhr+BzeDcRPGnP3xHAm5f/rz6L/frn/5ujkJuvM/9/Oqr26evO/z8eVfyx/A/A1RLD/vl4x/+dYaZ+9gK2t4d9AzeCp13o3x4eLdzQIQ0Ab3j+D+Lv5hNx/h+epSeezf9B/L3AjWfz/7Cn4Ib8DwBvsuvvsXNtRCIX/GAU8YMXR7wX9ILDIAy6QX94s5xm4J/jAOD15v+rc3fi+ojEVPDxKOLjF0d8MLwVXTp6nYiGM8Pbx8W3ovvhqNYPX1zrnaATHSsMouOG+KgBAHiV+f/q3J24PiLxL9dcg//xk9vk7gStYU6vR9n/YZTX41sKz19z9fxYFBPXfhRFHwf7L2wjd82V7+8FJ1FkGNV/WhsAvsn5/+qz8MT1EVH+v/oa/Ovy/9VXz/9h+f/qK9/lfwB4VmL47X6ceN8b9vNHY1/5sv6DYQ9AlFhf0I3/zvDoICrTWQ8Ab1D+D+Ir+xJx/g/i7/gTL8r/Qdz7f+PF+X/YM3BD/gcAAICvr8/H2vF/+3eb4cTg7DAcu50cK/a6g+NepxMer/SK4eHe2GfJscOTRqfd3NkPz/pxSL+/N3HcrycLkbnsynm9mD5rZkrx5HxhrTAXz36wVty/v9nLhb3ZhweNuUcLqfL4Rnaxdv/szvbgYXo82ziZm07tFsuFwWl1bnVjdqs7/nB9vHq2VMhnG7Ob6dLG9p3ZjeN8qVVdmA57e4tTy63s8b36WmZ8c/1ge3uwXr87v1Y5mLnXqT/YXTjp//zO4OeZ6fHiWnM/Nb6wvvxg+1Z/Zfkwd/5o/N7+4uZKMXw4PX++PLW2mlpYmT7emjlqL57ey57vLx63p9fnNh6lws3MQe9RuvTwcLa/Vtk+v7V38rhxOJPPNmePtrbSlXsby0etavdsvp/Z6OwWzxYypdRSr7DQz5wUu7nN/NpWeqk8qDXu9jp79w6m53sbZxsbjwena9V06l7q8XbYmNoe3y+HzburG4vbx4sn9d2lpcGtheXC/sPt8blbM9PtzbmzbmkwWJye6z8+qrRPHh8tbYSFW527YXN+MN6ojt/Knm8cPmju11rjja1CdnVz5fFa42z58dHRWljozG8tzJ8+2s893FqsbZ+MHy4Wbt2fmbpzuHo0aE8vPyi0u9m9mQcrqdXqbuVe8SibDcOj87nNR+WFW4PZh7mwVjstNrOl+c3l/fGVw+XMYuHk/spRo1Gvdh+t1nNLSwuVzc2j7PRpJny8sRumF2e27i6M57brm2fju4tzu7e2po7v35mvb6+e1zZ+ljzu9Qb/etJthcfNTu+kNdnpNeudVu+g3u7+ezfesE5O2q14i5pp5cNWKjUzEe6G9Ylca3d6op6eTU3U642ZzHSunm208nF8t34w3EibTzbSeO5erz+4LOmdXixs4mlMt9eMNuSJVBzbqZ90m3s77WilHkfxqWhW/bTe7tQb7U57cLZz3usO24ljd9udMN7g/+0/oon4ro/R+OdffGG3AfxF5P9Ou7s/3MdFhwLDXXEj32xM5RqpiWY6m5rI5RqtiXo4k59oNcKp2d1mJr2bz8Y7x8vjhcO9s3jyYHASTaVzs1PRRDjYi/a64WDnoN7cqbdax2F/eOCQz9zO526nUrfTmdvZ3O38zNgX8a714la2z61Gc9A57NS74XCnfXmv24uimfrM9Ew+yhNTuzNRssiFE40wOoLJ7danpprN+mwjkxvu6qO39se8o/bhs+uans1MpqdmJjO5yXT+YiUO6v1hi5l8fvLymXr2w2gfnuZ2WnvNw3jmce9k8DR/9MPj03YzvHiTlxVa3X4c+uxyU5OpycxkNvpovvhT69mwAbjSJ09OlXbbD97+fCy+pftFprk8oRoctw87YW+i3jpoD8/ZHkR57XAYNPZoLww7Y8MsddLqxdGFpaWf/VP08pPkSmW1UKvdn78dTcXV+v29nfrJYK933D4PW/oS9CW8yr4EBz0Af+T5v/5/OVv/v/5/4JuX//X/6/8H4JtG/7++BP3/AAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAAMCb4P8AiEKWpwDoBQA='}} execute_deploy_step /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py:65 2023-12-11 12:59:04.194 503 INFO root [-] Asynchronous command execute_deploy_step started execution 2023-12-11 12:59:04.224 503 DEBUG ironic_python_agent.extensions.standby [-] Preparing image overcloud-full.raw prepare_image /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py:681 2023-12-11 12:59:04.225 503 INFO root [-] Asynchronous command prepare_image started execution 2023-12-11 12:59:04.227 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=false&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200 len: 14135 time: 0.1055114 2023-12-11 12:59:04.230 503 INFO root [-] Agent is requesting to perform an explicit node cache update. This is to pickup any changes in the cache before deployment. 2023-12-11 12:59:04.234 503 DEBUG ironic_python_agent.ironic_api_client [-] Looking up node with addresses '52:54:00:12:34:58' and UUID 8d5ed008-efea-4df7-a190-aab8274a3bd5 at http://192.168.24.1:6385 _do_lookup /usr/lib/python3.9/site-packages/ironic_python_agent/ironic_api_client.py:205 2023-12-11 12:59:04.262 503 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:1354 2023-12-11 12:59:04.264 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:04.271 503 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:04.274 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:04.276 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:04.279 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:04.286 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:04.288 503 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="vda" MODEL="" SIZE="53687091200" ROTA="1" TYPE="disk" UUID="" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:04.291 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:04.295 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -c /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:04.300 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -c /dev/vda" returned: 1 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:04.303 503 DEBUG oslo_concurrency.processutils [-] 'multipath -c /dev/vda' failed. Not Retrying. execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:473 2023-12-11 12:59:04.306 503 DEBUG ironic_lib.utils [-] Command stdout is: "DM_MULTIPATH_DEVICE_PATH="0" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:04.309 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:04.311 503 DEBUG root [-] Checked device /dev/vda and determined it was not a multipath device. Unexpected error while running command. Command: multipath -c /dev/vda Exit code: 1 Stdout: 'DM_MULTIPATH_DEVICE_PATH="0"\n' Stderr: '' _get_multipath_parent_device /usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py:254 2023-12-11 12:59:04.317 503 WARNING root [-] Could not find the SCSI address (HCTL) for device /dev/vda. Skipping: FileNotFoundError: [Errno 2] No such file or directory: '/sys/block/vda/device/scsi_device' 2023-12-11 12:59:04.320 503 INFO root [-] Picked root device /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 based on root device hints None 2023-12-11 12:59:04.322 503 INFO ironic_python_agent.extensions.standby [-] Attempting to download image from http://192.168.24.1:8088/agent_images/8d5ed008-efea-4df7-a190-aab8274a3bd5 2023-12-11 12:59:15.191 503 INFO ironic_python_agent.extensions.standby [-] Image streamed onto device /dev/vda in 10.868741989135742 seconds 2023-12-11 12:59:15.195 503 DEBUG ironic_python_agent.extensions.standby [-] Verifying image at /dev/vda against sha256 checksum e29bd827409aed9336741f3943650bc8918e8ac50dbb76971719da66ebde1fd2 verify_image /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py:385 2023-12-11 12:59:15.199 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.629 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.431s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.632 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk) Disk /dev/vda: 53.7GB Sector size (logical/physical): 512B/512B Partition Table: loop Disk Flags: Number Start End Size File system Flags 1 0.00B 53.7GB 53.7GB xfs " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.636 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.638 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda 2023-12-11 12:59:15.640 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk /dev/vda --pairs --bytes --ascii --nodeps --output UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.647 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk /dev/vda --pairs --bytes --ascii --nodeps --output UUID,PARTUUID" returned: 0 in 0.007s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.650 503 DEBUG ironic_lib.utils [-] Command stdout is: "UUID="c2a96066-f0e8-42f8-a3aa-7d4672858571" PARTUUID="" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.651 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.652 503 INFO ironic_python_agent.extensions.standby [-] /dev/vda UUID is now c2a96066-f0e8-42f8-a3aa-7d4672858571 2023-12-11 12:59:15.665 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): partprobe /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.683 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe /dev/vda" returned: 0 in 0.018s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.686 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.690 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.691 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted -s -m /dev/vda unit MiB print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.703 503 DEBUG oslo_concurrency.processutils [-] CMD "parted -s -m /dev/vda unit MiB print" returned: 0 in 0.013s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.706 503 DEBUG ironic_lib.utils [-] Command stdout is: "BYT; /dev/vda:51200MiB:virtblk:512:512:loop:Virtio Block Device:; 1:0.00MiB:51200MiB:51200MiB:xfs::; " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.711 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.714 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): partprobe /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.721 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe /dev/vda" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.724 503 DEBUG ironic_lib.utils [-] Command stdout is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.727 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.730 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Po name,label /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.735 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Po name,label /dev/vda" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.736 503 DEBUG ironic_lib.utils [-] Command stdout is: "NAME="vda" LABEL="img-rootfs" " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.738 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.742 503 DEBUG ironic_lib.disk_utils [-] Adding config drive partition 1 MiB to device: /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5 create_config_drive_partition /usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py:1113 2023-12-11 12:59:15.744 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.751 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.754 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk) Disk /dev/vda: 53.7GB Sector size (logical/physical): 512B/512B Partition Table: loop Disk Flags: Number Start End Size File system Flags 1 0.00B 53.7GB 53.7GB xfs " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.759 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.760 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda 2023-12-11 12:59:15.762 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted --script /dev/vda -- print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.769 503 DEBUG oslo_concurrency.processutils [-] CMD "parted --script /dev/vda -- print" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.772 503 DEBUG ironic_lib.utils [-] Command stdout is: "Model: Virtio Block Device (virtblk) Disk /dev/vda: 53.7GB Sector size (logical/physical): 512B/512B Partition Table: loop Disk Flags: Number Start End Size File system Flags 1 0.00B 53.7GB 53.7GB xfs " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.778 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.780 503 WARNING ironic_lib.disk_utils [-] Unable to get partition table type for device /dev/vda 2023-12-11 12:59:15.780 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted -s -m /dev/vda unit MiB print execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.787 503 DEBUG oslo_concurrency.processutils [-] CMD "parted -s -m /dev/vda unit MiB print" returned: 0 in 0.006s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.791 503 DEBUG ironic_lib.utils [-] Command stdout is: "BYT; /dev/vda:51200MiB:virtblk:512:512:loop:Virtio Block Device:; 1:0.00MiB:51200MiB:51200MiB:xfs::; " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.794 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.796 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): partprobe -d -s /dev/vda execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:15.803 503 DEBUG oslo_concurrency.processutils [-] CMD "partprobe -d -s /dev/vda" returned: 0 in 0.008s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:15.806 503 DEBUG ironic_lib.utils [-] Command stdout is: "/dev/vda: loop partitions 1 " _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:99 2023-12-11 12:59:15.809 503 DEBUG ironic_lib.utils [-] Command stderr is: "" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:15.811 503 ERROR root [-] Command failed: prepare_image, error: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_lib.exception.InstanceDeployFailure: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.811 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.811 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 1141, in create_config_drive_partition 2023-12-11 12:59:15.811 503 ERROR root pp_count, lp_count = count_mbr_partitions(device) 2023-12-11 12:59:15.811 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 153, in count_mbr_partitions 2023-12-11 12:59:15.811 503 ERROR root raise ValueError('The device %s does not have a valid MBR ' 2023-12-11 12:59:15.811 503 ERROR root ValueError: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.811 503 ERROR root 2023-12-11 12:59:15.811 503 ERROR root During handling of the above exception, another exception occurred: 2023-12-11 12:59:15.811 503 ERROR root 2023-12-11 12:59:15.811 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.811 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 174, in run 2023-12-11 12:59:15.811 503 ERROR root result = self.execute_method(**self.command_params) 2023-12-11 12:59:15.811 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/standby.py", line 718, in prepare_image 2023-12-11 12:59:15.811 503 ERROR root disk_utils.create_config_drive_partition(node_uuid, 2023-12-11 12:59:15.811 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_lib/disk_utils.py", line 1143, in create_config_drive_partition 2023-12-11 12:59:15.811 503 ERROR root raise exception.InstanceDeployFailure( 2023-12-11 12:59:15.811 503 ERROR root ironic_lib.exception.InstanceDeployFailure: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.811 503 ERROR root 2023-12-11 12:59:15.824 503 ERROR root [-] Unexpected error dispatching write_image to manager : Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.824 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.824 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers 2023-12-11 12:59:15.824 503 ERROR root return getattr(manager, method)(*args, **kwargs) 2023-12-11 12:59:15.824 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image 2023-12-11 12:59:15.824 503 ERROR root return cmd.wait() 2023-12-11 12:59:15.824 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait 2023-12-11 12:59:15.824 503 ERROR root raise self.command_error 2023-12-11 12:59:15.824 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.824 503 ERROR root 2023-12-11 12:59:15.834 503 ERROR root [-] Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.834 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.834 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 76, in execute_deploy_step 2023-12-11 12:59:15.834 503 ERROR root result = hardware.dispatch_to_managers(step['step'], node, ports, 2023-12-11 12:59:15.834 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers 2023-12-11 12:59:15.834 503 ERROR root return getattr(manager, method)(*args, **kwargs) 2023-12-11 12:59:15.834 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image 2023-12-11 12:59:15.834 503 ERROR root return cmd.wait() 2023-12-11 12:59:15.834 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait 2023-12-11 12:59:15.834 503 ERROR root raise self.command_error 2023-12-11 12:59:15.834 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.834 503 ERROR root 2023-12-11 12:59:15.845 503 ERROR root [-] Command failed: execute_deploy_step, error: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table: ironic_python_agent.errors.DeploymentError: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.845 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 76, in execute_deploy_step 2023-12-11 12:59:15.845 503 ERROR root result = hardware.dispatch_to_managers(step['step'], node, ports, 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2617, in dispatch_to_managers 2023-12-11 12:59:15.845 503 ERROR root return getattr(manager, method)(*args, **kwargs) 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/hardware.py", line 2487, in write_image 2023-12-11 12:59:15.845 503 ERROR root return cmd.wait() 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 89, in wait 2023-12-11 12:59:15.845 503 ERROR root raise self.command_error 2023-12-11 12:59:15.845 503 ERROR root ironic_python_agent.errors.CommandExecutionError: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.845 503 ERROR root 2023-12-11 12:59:15.845 503 ERROR root During handling of the above exception, another exception occurred: 2023-12-11 12:59:15.845 503 ERROR root 2023-12-11 12:59:15.845 503 ERROR root Traceback (most recent call last): 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py", line 174, in run 2023-12-11 12:59:15.845 503 ERROR root result = self.execute_method(**self.command_params) 2023-12-11 12:59:15.845 503 ERROR root File "/usr/lib/python3.9/site-packages/ironic_python_agent/extensions/deploy.py", line 82, in execute_deploy_step 2023-12-11 12:59:15.845 503 ERROR root raise errors.DeploymentError(msg) 2023-12-11 12:59:15.845 503 ERROR root ironic_python_agent.errors.DeploymentError: Deploy step failed: Error performing deploy_step write_image: Command execution failed: Failed to check the number of primary partitions present on /dev/vda for node 8d5ed008-efea-4df7-a190-aab8274a3bd5. Error: The device /dev/vda does not have a valid MBR partition table 2023-12-11 12:59:15.845 503 ERROR root 2023-12-11 12:59:18.849 503 DEBUG ironic_python_agent.ironic_api_client [-] Heartbeat: announcing callback URL https://192.168.24.15:9999, API version is 1.68 heartbeat /usr/lib/python3.9/site-packages/ironic_python_agent/ironic_api_client.py:164 2023-12-11 12:59:18.903 503 INFO ironic_python_agent.agent [-] heartbeat successful 2023-12-11 12:59:18.903 503 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 125.57153486729219 2023-12-11 12:59:18.924 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 52252, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992 2023-12-11 12:59:18.949 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "GET /v1/commands/ HTTP/1.1" status: 200 len: 20620 time: 0.0130451 2023-12-11 12:59:18.953 503 DEBUG eventlet.wsgi.server [-] (503) accepted ('::ffff:192.168.24.1', 52254, 0, 0) server /usr/lib/python3.9/site-packages/eventlet/wsgi.py:992 2023-12-11 12:59:18.958 503 DEBUG root [-] Executing command: log.collect_system_logs with args: {} execute_command /usr/lib/python3.9/site-packages/ironic_python_agent/extensions/base.py:253 2023-12-11 12:59:18.959 503 INFO ironic_python_agent.utils [-] Collecting system logs and debugging information 2023-12-11 12:59:18.965 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): journalctl --full --no-pager -b execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:18.982 503 DEBUG oslo_concurrency.processutils [-] CMD "journalctl --full --no-pager -b" returned: 0 in 0.017s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:18.984 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:18.986 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ps au execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:18.997 503 DEBUG oslo_concurrency.processutils [-] CMD "ps au" returned: 0 in 0.011s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:18.999 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.001 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): df -a execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.013 503 DEBUG oslo_concurrency.processutils [-] CMD "df -a" returned: 0 in 0.013s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.015 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.017 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iptables -L execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.041 503 DEBUG oslo_concurrency.processutils [-] CMD "iptables -L" returned: 0 in 0.024s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.043 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.044 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ip addr execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.060 503 DEBUG oslo_concurrency.processutils [-] CMD "ip addr" returned: 0 in 0.016s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.061 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.062 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.240 503 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 0.177s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.241 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.242 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.246 503 DEBUG oslo_concurrency.processutils [-] CMD "lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.004s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.249 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.250 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): cat /proc/mdstat execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.253 503 DEBUG oslo_concurrency.processutils [-] CMD "cat /proc/mdstat" returned: 0 in 0.003s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.254 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.254 503 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): multipath -ll execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:384 2023-12-11 12:59:19.259 503 DEBUG oslo_concurrency.processutils [-] CMD "multipath -ll" returned: 0 in 0.005s execute /usr/lib/python3.9/site-packages/oslo_concurrency/processutils.py:422 2023-12-11 12:59:19.260 503 DEBUG ironic_lib.utils [-] Command stderr is: "b''" _log /usr/lib/python3.9/site-packages/ironic_lib/utils.py:100 2023-12-11 12:59:19.270 503 INFO root [-] Synchronous command collect_system_logs completed: {'system_logs': '<...>'} 2023-12-11 12:59:19.271 503 INFO eventlet.wsgi.server [-] ::ffff:192.168.24.1 "POST /v1/commands/?wait=true&agent_token=0_wU51Y9lpnHrUNgrtxboO1vn09RqBpi0bjJ6C0WdoU HTTP/1.1" status: 200 len: 52895 time: 0.3135779