Created
May 25, 2020 13:32
This file contains bidirectional Unicode text that may be interpreted or compiled differently than what appears below. To review, open the file in an editor that reveals hidden Unicode characters.
Learn more about bidirectional Unicode characters
| [2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[2J[01;01H[=3h[2J[01;01H[0m[35m[40m[2J[01;01H[=3h[2J[01;01H[0m[37m[40m[2J[01;01H[=3h[2J[01;01H[0m[35m[40m[2J[01;01H[2J[01;01H[0m[37m[40m..Fetching Netboot Image | |
| [0m[30m[40m[2J[01;01H[0m[37m[40m[02;30HGNU GRUB version 2.02 | |
| [04;02H����������������������������������������������������������������������������Ŀ[05;02H�[05;79H�[06;02H�[06;79H�[07;02H�[07;79H�[08;02H�[08;79H�[09;02H�[09;79H�[10;02H�[10;79H�[11;02H�[11;79H�[12;02H�[12;79H�[13;02H�[13;79H�[14;02H�[14;79H�[15;02H�[15;79H�[16;02H�[16;79H�[17;02H�[17;79H�[18;02H������������������������������������������������������������������������������[19;02H[20;02H Use the and keys to select which entry is highlighted. | |
| Press enter to boot the selected OS, `e' to edit the commands | |
| before booting or `c' for a command-line. [05;80H [0m[30m[47m[05;03H*master [0m[37m[40m[05;78H[06;03H [06;78H[07;03H [07;78H[08;03H [08;78H[09;03H [09;78H[10;03H [10;78H[11;03H [11;78H[12;03H [12;78H[13;03H [13;78H[14;03H [14;78H[15;03H [15;78H[16;03H [16;78H[17;03H [17;78H[17;80H [05;78H[23;01H The highlighted entry will be executed automatically in 1s. [05;78H[23;01H The highlighted entry will be executed automatically in 0s. [05;78H[0m[30m[40m[2J[01;01H[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40m[02;30HGNU GRUB version 2.02 | |
| [04;02H����������������������������������������������������������������������������Ŀ[05;02H�[05;79H�[06;02H�[06;79H�[07;02H�[07;79H�[08;02H�[08;79H�[09;02H�[09;79H�[10;02H�[10;79H�[11;02H�[11;79H�[12;02H�[12;79H�[13;02H�[13;79H�[14;02H�[14;79H�[15;02H�[15;79H�[16;02H�[16;79H�[17;02H������������������������������������������������������������������������������[18;02H[19;02H Use the and keys to select which entry is highlighted. | |
| Press enter to boot the selected OS, `e' to edit the commands | |
| before booting or `c' for a command-line. ESC to return previous | |
| menu. [05;80H [0m[30m[47m[05;03H*deploy [0m[37m[40m[05;78H[06;03H boot_partition [06;78H[07;03H boot_ramdisk [07;78H[08;03H boot_whole_disk [08;78H[09;03H [09;78H[10;03H [10;78H[11;03H [11;78H[12;03H [12;78H[13;03H [13;78H[14;03H [14;78H[15;03H [15;78H[16;03H [16;78H[16;80H [05;78H[23;01H The highlighted entry will be executed automatically in 5s. [05;78H[23;01H The highlighted entry will be executed automatically in 4s. [05;78H[23;01H The highlighted entry will be executed automatically in 3s. [05;78H[23;01H The highlighted entry will be executed automatically in 2s. [05;78H[23;01H The highlighted entry will be executed automatically in 1s. [05;78H[23;01H The highlighted entry will be executed automatically in 0s. [05;78H[0m[30m[40m[2J[01;01H[0m[37m[40m[0m[30m[40m[2J[01;01H[0m[37m[40m[ 0.000000] Linux version 4.18.0-147.8.1.el8_1.x86_64 (mockbuild@kbuilder.bsys.centos.org) (gcc version 8.3.1 20190507 (Red Hat 8.3.1-4) (GCC)) #1 SMP Thu Apr 9 13:49:54 UTC 2020 | |
| [ 0.000000] Command line: BOOT_IMAGE=88833e40-aa2b-4b68-99fa-34c85532a049/deploy_kernel selinux=0 troubleshoot=0 text nofb nomodeset console=ttyS0,115200 systemd.journald.forward_to_console=yes ipa-insecure=1 ipa-debug=1 ipa-api-url=https://192.168.0.24/baremetal boot_server=192.168.0.24 | |
| [ 0.000000] x86/fpu: x87 FPU will use FXSAVE | |
| [ 0.000000] BIOS-provided physical RAM map: | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009efff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x000000000009f000-0x000000000009ffff] ACPI NVS | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000100000-0x00000000007fffff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000808000-0x000000000080ffff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS | |
| [ 0.000000] BIOS-e820: [mem 0x0000000000900000-0x00000000be9ebfff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x00000000be9ec000-0x00000000be9fffff] ACPI NVS | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bea00000-0x00000000bea1dfff] reserved | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bea1e000-0x00000000becbefff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x00000000becbf000-0x00000000bee40fff] reserved | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bee41000-0x00000000bfec0fff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bfec1000-0x00000000bff18fff] reserved | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bff19000-0x00000000bff20fff] ACPI data | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bff21000-0x00000000bff24fff] ACPI NVS | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bff25000-0x00000000bffcffff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bffd0000-0x00000000bffeffff] reserved | |
| [ 0.000000] BIOS-e820: [mem 0x00000000bfff0000-0x00000000bfffffff] usable | |
| [ 0.000000] BIOS-e820: [mem 0x00000000ffe00000-0x00000000ffffffff] reserved | |
| [ 0.000000] NX (Execute Disable) protection: active | |
| [ 0.000000] extended physical RAM map: | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000000000-0x000000000009efff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x000000000009f000-0x000000000009ffff] ACPI NVS | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000100000-0x00000000007fffff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000800000-0x0000000000807fff] ACPI NVS | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000808000-0x000000000080ffff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000810000-0x00000000008fffff] ACPI NVS | |
| [ 0.000000] reserve setup_data: [mem 0x0000000000900000-0x00000000be441017] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000be441018-0x00000000be48a857] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000be48a858-0x00000000be9ebfff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000be9ec000-0x00000000be9fffff] ACPI NVS | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bea00000-0x00000000bea1dfff] reserved | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bea1e000-0x00000000becbefff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000becbf000-0x00000000bee40fff] reserved | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bee41000-0x00000000bfec0fff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bfec1000-0x00000000bff18fff] reserved | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bff19000-0x00000000bff20fff] ACPI data | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bff21000-0x00000000bff24fff] ACPI NVS | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bff25000-0x00000000bffcffff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bffd0000-0x00000000bffeffff] reserved | |
| [ 0.000000] reserve setup_data: [mem 0x00000000bfff0000-0x00000000bfffffff] usable | |
| [ 0.000000] reserve setup_data: [mem 0x00000000ffe00000-0x00000000ffffffff] reserved | |
| [ 0.000000] efi: EFI v2.60 by EDK II | |
| [ 0.000000] efi: SMBIOS=0xbfef7000 ACPI=0xbff20000 ACPI 2.0=0xbff20014 MEMATTR=0xbf242898 | |
| [ 0.000000] secureboot: Secure boot disabled | |
| [ 0.000000] SMBIOS 2.8 present. | |
| [ 0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 0.0.0 02/06/2015 | |
| [ 0.000000] last_pfn = 0xc0000 max_arch_pfn = 0x400000000 | |
| [ 0.000000] x86/PAT: Configuration [0-7]: WB WC UC- UC WB WP UC- WT | |
| [ 0.000000] RAMDISK: [mem 0x2518b000-0x3d4a1fff] | |
| [ 0.000000] ACPI: Early table checksum verification disabled | |
| [ 0.000000] ACPI: RSDP 0x00000000BFF20014 000024 (v02 BOCHS ) | |
| [ 0.000000] ACPI: XSDT 0x00000000BFF1F0E8 00003C (v01 BOCHS BXPCFACP 00000001 01000013) | |
| [ 0.000000] ACPI: FACP 0x00000000BFF1C000 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001) | |
| [ 0.000000] ACPI: DSDT 0x00000000BFF1D000 001B86 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001) | |
| [ 0.000000] ACPI: FACS 0x00000000BFF23000 000040 | |
| [ 0.000000] ACPI: APIC 0x00000000BFF1B000 000080 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001) | |
| [ 0.000000] ACPI: HPET 0x00000000BFF1A000 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001) | |
| [ 0.000000] No NUMA configuration found | |
| [ 0.000000] Faking a node at [mem 0x0000000000000000-0x00000000bfffffff] | |
| [ 0.000000] NODE_DATA(0) allocated [mem 0xbf3b3000-0xbf3dcfff] | |
| [ 0.000000] tsc: Fast TSC calibration using PIT | |
| [ 0.000000] Zone ranges: | |
| [ 0.000000] DMA [mem 0x0000000000001000-0x0000000000ffffff] | |
| [ 0.000000] DMA32 [mem 0x0000000001000000-0x00000000bfffffff] | |
| [ 0.000000] Normal empty | |
| [ 0.000000] Device empty | |
| [ 0.000000] Movable zone start for each node | |
| [ 0.000000] Early memory node ranges | |
| [ 0.000000] node 0: [mem 0x0000000000001000-0x000000000009efff] | |
| [ 0.000000] node 0: [mem 0x0000000000100000-0x00000000007fffff] | |
| [ 0.000000] node 0: [mem 0x0000000000808000-0x000000000080ffff] | |
| [ 0.000000] node 0: [mem 0x0000000000900000-0x00000000be9ebfff] | |
| [ 0.000000] node 0: [mem 0x00000000bea1e000-0x00000000becbefff] | |
| [ 0.000000] node 0: [mem 0x00000000bee41000-0x00000000bfec0fff] | |
| [ 0.000000] node 0: [mem 0x00000000bff25000-0x00000000bffcffff] | |
| [ 0.000000] node 0: [mem 0x00000000bfff0000-0x00000000bfffffff] | |
| [ 0.000000] Zeroed struct page in unavailable ranges: 914 pages | |
| [ 0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000000bfffffff] | |
| [ 0.000000] ACPI: PM-Timer IO Port: 0xb008 | |
| [ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1]) | |
| [ 0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23 | |
| [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl) | |
| [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level) | |
| [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level) | |
| [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level) | |
| [ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level) | |
| [ 0.000000] Using ACPI (MADT) for SMP configuration information | |
| [ 0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000 | |
| [ 0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs | |
| [ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000fffff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0x00800000-0x00807fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0x00810000-0x008fffff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbe441000-0xbe441fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbe48a000-0xbe48afff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbe9ec000-0xbe9fffff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbea00000-0xbea1dfff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbecbf000-0xbee40fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbfec1000-0xbff18fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbff19000-0xbff20fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbff21000-0xbff24fff] | |
| [ 0.000000] PM: Registered nosave memory: [mem 0xbffd0000-0xbffeffff] | |
| [ 0.000000] [mem 0xc0000000-0xffdfffff] available for PCI devices | |
| [ 0.000000] Booting paravirtualized kernel on bare hardware | |
| [ 0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1910969940391419 ns | |
| [ 0.000000] Detected CPU family 6h model 6 | |
| [ 0.000000] Warning: AMD Processor - this hardware has not undergone upstream testing. Please consult http://wiki.centos.org/FAQ for more information | |
| [ 0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1 | |
| [ 0.000000] percpu: Embedded 47 pages/cpu s155648 r8192 d28672 u1048576 | |
| [ 0.000000] Built 1 zonelists, mobility grouping on. Total pages: 771422 | |
| [ 0.000000] Policy zone: DMA32 | |
| [ 0.000000] Kernel command line: BOOT_IMAGE=88833e40-aa2b-4b68-99fa-34c85532a049/deploy_kernel selinux=0 troubleshoot=0 text nofb nomodeset console=ttyS0,115200 systemd.journald.forward_to_console=yes ipa-insecure=1 ipa-debug=1 ipa-api-url=https://192.168.0.24/baremetal boot_server=192.168.0.24 | |
| [ 0.000000] Specific versions of hardware are certified with Red Hat Enterprise Linux 8. Please see the list of hardware certified with Red Hat Enterprise Linux 8 at https://access.redhat.com/ecosystem. | |
| [ 0.000000] Memory: 251460K/3142072K available (12292K kernel code, 2118K rwdata, 3780K rodata, 2368K init, 6348K bss, 504956K reserved, 0K cma-reserved) | |
| [ 0.000000] random: get_random_u64 called from cache_random_seq_create+0x7c/0x140 with crng_init=0 | |
| [ 0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1 | |
| [ 0.000000] ftrace: allocating 35731 entries in 140 pages | |
| [ 0.000000] rcu: Hierarchical RCU implementation. | |
| [ 0.000000] rcu: RCU restricting CPUs from NR_CPUS=8192 to nr_cpu_ids=2. | |
| [ 0.000000] rcu: RCU calculated value of scheduler-enlistment delay is 100 jiffies. | |
| [ 0.000000] rcu: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2 | |
| [ 0.000000] NR_IRQS: 524544, nr_irqs: 440, preallocated irqs: 16 | |
| [ 0.000000] Console: colour dummy device 80x25 | |
| [ 0.000000] console [ttyS0] enabled | |
| [ 0.000000] ACPI: Core revision 20180531 | |
| [ 0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 19112604467 ns | |
| [ 0.002000] APIC: Switch to symmetric I/O mode setup | |
| [ 0.006000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1 | |
| [ 0.011000] tsc: Fast TSC calibration using PIT | |
| [ 0.012000] tsc: Detected 2600.138 MHz processor | |
| [ 0.013000] tsc: Marking TSC unstable due to TSCs unsynchronized | |
| [ 0.014087] Calibrating delay loop (skipped), value calculated using timer frequency.. 5200.27 BogoMIPS (lpj=2600138) | |
| [ 0.014427] pid_max: default: 32768 minimum: 301 | |
| [ 0.028841] Security Framework initialized | |
| [ 0.029150] Yama: becoming mindful. | |
| [ 0.030312] SELinux: Disabled at boot. | |
| [ 0.044135] Dentry cache hash table entries: 524288 (order: 10, 4194304 bytes) | |
| [ 0.049884] Inode-cache hash table entries: 262144 (order: 9, 2097152 bytes) | |
| [ 0.050376] Mount-cache hash table entries: 8192 (order: 4, 65536 bytes) | |
| [ 0.050685] Mountpoint-cache hash table entries: 8192 (order: 4, 65536 bytes) | |
| [ 0.072294] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0 | |
| [ 0.072458] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0 | |
| [ 0.072695] FEATURE SPEC_CTRL Not Present | |
| [ 0.072832] FEATURE IBPB_SUPPORT Not Present | |
| [ 0.073093] Spectre V1 : Mitigation: usercopy/swapgs barriers and __user pointer sanitization | |
| [ 0.073423] Spectre V2 : Spectre mitigation: LFENCE not serializing, switching to generic retpoline | |
| [ 0.073604] Spectre V2 : Mitigation: Full generic retpoline | |
| [ 0.073748] Spectre V2 : Spectre v2 / SpectreRSB mitigation: Filling RSB on context switch | |
| [ 0.074081] Speculative Store Bypass: Vulnerable | |
| [ 0.078488] Freeing SMP alternatives memory: 32K | |
| [ 0.106000] APIC calibration not consistent with PM-Timer: 103ms instead of 100ms | |
| [ 0.106000] APIC delta adjusted to PM-Timer: 6249980 (6437766) | |
| [ 0.106761] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+ (family: 0x6, model: 0x6, stepping: 0x3) | |
| [ 0.115713] Performance Events: PMU not available due to virtualization, using software events only. | |
| [ 0.121530] rcu: Hierarchical SRCU implementation. | |
| [ 0.128513] NMI watchdog: Perf event create on CPU 0 failed with -2 | |
| [ 0.128676] NMI watchdog: Perf NMI watchdog permanently disabled | |
| [ 0.132186] smp: Bringing up secondary CPUs ... | |
| [ 0.137298] x86: Booting SMP configuration: | |
| [ 0.137531] .... node #0, CPUs: #1 | |
| [ 0.216239] smp: Brought up 1 node, 2 CPUs | |
| [ 0.216535] smpboot: Max logical packages: 2 | |
| [ 0.216741] smpboot: Total of 2 processors activated (16025.47 BogoMIPS) | |
| [ 0.231130] node 0 initialised, 596414 pages in 1ms | |
| [ 0.250165] devtmpfs: initialized | |
| [ 0.254452] x86/mm: Memory block size: 128MB | |
| [ 0.265402] PM: Registering ACPI NVS region [mem 0x0009f000-0x0009ffff] (4096 bytes) | |
| [ 0.265640] PM: Registering ACPI NVS region [mem 0x00800000-0x00807fff] (32768 bytes) | |
| [ 0.265921] PM: Registering ACPI NVS region [mem 0x00810000-0x008fffff] (983040 bytes) | |
| [ 0.266117] PM: Registering ACPI NVS region [mem 0xbe9ec000-0xbe9fffff] (81920 bytes) | |
| [ 0.266301] PM: Registering ACPI NVS region [mem 0xbff21000-0xbff24fff] (16384 bytes) | |
| [ 0.268083] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 1911260446275000 ns | |
| [ 0.268527] futex hash table entries: 512 (order: 3, 32768 bytes) | |
| [ 0.274111] pinctrl core: initialized pinctrl subsystem | |
| [ 0.283782] NET: Registered protocol family 16 | |
| [ 0.286962] audit: initializing netlink subsys (disabled) | |
| [ 0.289192] audit: type=2000 audit(1590410966.288:1): state=initialized audit_enabled=0 res=1 | |
| [ 0.298049] cpuidle: using governor menu | |
| [ 0.300548] ACPI: bus type PCI registered | |
| [ 0.300789] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5 | |
| [ 0.303938] PCI: Using configuration type 1 for base access | |
| [ 0.308475] mtrr: your CPUs had inconsistent fixed MTRR settings | |
| [ 0.308648] mtrr: your CPUs had inconsistent variable MTRR settings | |
| [ 0.308805] mtrr: your CPUs had inconsistent MTRRdefType settings | |
| [ 0.308966] mtrr: probably your BIOS does not setup all CPUs. | |
| [ 0.309033] mtrr: corrected configuration. | |
| [ 0.338143] HugeTLB registered 2.00 MiB page size, pre-allocated 0 pages | |
| [ 0.342246] cryptd: max_cpu_qlen set to 1000 | |
| [ 0.347564] ACPI: Added _OSI(Module Device) | |
| [ 0.347802] ACPI: Added _OSI(Processor Device) | |
| [ 0.347904] ACPI: Added _OSI(3.0 _SCP Extensions) | |
| [ 0.347996] ACPI: Added _OSI(Processor Aggregator Device) | |
| [ 0.348130] ACPI: Added _OSI(Linux-Dell-Video) | |
| [ 0.372295] ACPI: 1 ACPI AML tables successfully acquired and loaded | |
| [ 0.396495] ACPI: Interpreter enabled | |
| [ 0.397939] ACPI: (supports S0 S3 S4 S5) | |
| [ 0.398056] ACPI: Using IOAPIC for interrupt routing | |
| [ 0.398777] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug | |
| [ 0.401396] ACPI: Enabled 2 GPEs in block 00 to 0F | |
| [ 0.468496] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff]) | |
| [ 0.469335] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI] | |
| [ 0.470500] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge. | |
| [ 0.479220] acpiphp: Slot [3] registered | |
| [ 0.479581] acpiphp: Slot [4] registered | |
| [ 0.479876] acpiphp: Slot [5] registered | |
| [ 0.480181] acpiphp: Slot [6] registered | |
| [ 0.480432] acpiphp: Slot [7] registered | |
| [ 0.480768] acpiphp: Slot [8] registered | |
| [ 0.481053] acpiphp: Slot [9] registered | |
| [ 0.481338] acpiphp: Slot [10] registered | |
| [ 0.481586] acpiphp: Slot [11] registered | |
| [ 0.481958] acpiphp: Slot [12] registered | |
| [ 0.482186] acpiphp: Slot [13] registered | |
| [ 0.482420] acpiphp: Slot [14] registered | |
| [ 0.482727] acpiphp: Slot [15] registered | |
| [ 0.483089] acpiphp: Slot [16] registered | |
| [ 0.483343] acpiphp: Slot [17] registered | |
| [ 0.483607] acpiphp: Slot [18] registered | |
| [ 0.483922] acpiphp: Slot [19] registered | |
| [ 0.484153] acpiphp: Slot [20] registered | |
| [ 0.484390] acpiphp: Slot [21] registered | |
| [ 0.484672] acpiphp: Slot [22] registered | |
| [ 0.485060] acpiphp: Slot [23] registered | |
| [ 0.485315] acpiphp: Slot [24] registered | |
| [ 0.485541] acpiphp: Slot [25] registered | |
| [ 0.485858] acpiphp: Slot [26] registered | |
| [ 0.486155] acpiphp: Slot [27] registered | |
| [ 0.486376] acpiphp: Slot [28] registered | |
| [ 0.486693] acpiphp: Slot [29] registered | |
| [ 0.487135] acpiphp: Slot [30] registered | |
| [ 0.487381] acpiphp: Slot [31] registered | |
| [ 0.487842] PCI host bridge to bus 0000:00 | |
| [ 0.488185] pci_bus 0000:00: root bus resource [io 0x0000-0x0cf7 window] | |
| [ 0.488339] pci_bus 0000:00: root bus resource [io 0x0d00-0xffff window] | |
| [ 0.488560] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window] | |
| [ 0.488699] pci_bus 0000:00: root bus resource [mem 0xc0000000-0xfebfffff window] | |
| [ 0.488831] pci_bus 0000:00: root bus resource [mem 0x800000000-0x8000040ff window] | |
| [ 0.489056] pci_bus 0000:00: root bus resource [bus 00-ff] | |
| [ 0.501159] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io 0x01f0-0x01f7] | |
| [ 0.501374] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io 0x03f6] | |
| [ 0.502161] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io 0x0170-0x0177] | |
| [ 0.502324] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io 0x0376] | |
| [ 0.510914] pci 0000:00:01.3: quirk: [io 0xb000-0xb03f] claimed by PIIX4 ACPI | |
| [ 0.511083] pci 0000:00:01.3: quirk: [io 0xb100-0xb10f] claimed by PIIX4 SMB | |
| [ 0.523928] pci_bus 0000:01: extended config space not accessible | |
| [ 0.528791] acpiphp: Slot [0] registered | |
| [ 0.529106] acpiphp: Slot [1] registered | |
| [ 0.529380] acpiphp: Slot [2] registered | |
| [ 0.530299] acpiphp: Slot [3-2] registered | |
| [ 0.530647] acpiphp: Slot [4-2] registered | |
| [ 0.531254] acpiphp: Slot [5-2] registered | |
| [ 0.531546] acpiphp: Slot [6-2] registered | |
| [ 0.531856] acpiphp: Slot [7-2] registered | |
| [ 0.532197] acpiphp: Slot [8-2] registered | |
| [ 0.532629] acpiphp: Slot [9-2] registered | |
| [ 0.533119] acpiphp: Slot [10-2] registered | |
| [ 0.533442] acpiphp: Slot [11-2] registered | |
| [ 0.533788] acpiphp: Slot [12-2] registered | |
| [ 0.534184] acpiphp: Slot [13-2] registered | |
| [ 0.534448] acpiphp: Slot [14-2] registered | |
| [ 0.534760] acpiphp: Slot [15-2] registered | |
| [ 0.535113] acpiphp: Slot [16-2] registered | |
| [ 0.535414] acpiphp: Slot [17-2] registered | |
| [ 0.535789] acpiphp: Slot [18-2] registered | |
| [ 0.536165] acpiphp: Slot [19-2] registered | |
| [ 0.536449] acpiphp: Slot [20-2] registered | |
| [ 0.536803] acpiphp: Slot [21-2] registered | |
| [ 0.537126] acpiphp: Slot [22-2] registered | |
| [ 0.537440] acpiphp: Slot [23-2] registered | |
| [ 0.537862] acpiphp: Slot [24-2] registered | |
| [ 0.538211] acpiphp: Slot [25-2] registered | |
| [ 0.538519] acpiphp: Slot [26-2] registered | |
| [ 0.538856] acpiphp: Slot [27-2] registered | |
| [ 0.539172] acpiphp: Slot [28-2] registered | |
| [ 0.539485] acpiphp: Slot [29-2] registered | |
| [ 0.539825] acpiphp: Slot [30-2] registered | |
| [ 0.540184] acpiphp: Slot [31-2] registered | |
| [ 0.550173] pci 0000:00:02.0: PCI bridge to [bus 01] | |
| [ 0.560340] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 10 *11) | |
| [ 0.561841] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 10 *11) | |
| [ 0.563060] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 *10 11) | |
| [ 0.564158] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 *10 11) | |
| [ 0.564763] ACPI: PCI Interrupt Link [LNKS] (IRQs *9) | |
| [ 0.574309] vgaarb: loaded | |
| [ 0.577301] SCSI subsystem initialized | |
| [ 0.578297] ACPI: bus type USB registered | |
| [ 0.579085] usbcore: registered new interface driver usbfs | |
| [ 0.579614] usbcore: registered new interface driver hub | |
| [ 0.580397] usbcore: registered new device driver usb | |
| [ 0.581677] pps_core: LinuxPPS API ver. 1 registered | |
| [ 0.581820] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it> | |
| [ 0.582102] PTP clock support registered | |
| [ 0.584000] EDAC MC: Ver: 3.0.0 | |
| [ 0.586234] Registered efivars operations | |
| [ 0.599923] PCI: Using ACPI for IRQ routing | |
| [ 0.610327] NetLabel: Initializing | |
| [ 0.610433] NetLabel: domain hash size = 128 | |
| [ 0.610525] NetLabel: protocols = UNLABELED CIPSOv4 CALIPSO | |
| [ 0.612355] NetLabel: unlabeled traffic allowed by default | |
| [ 0.614834] HPET: 3 timers in total, 0 timers will be used for per-cpu timer | |
| [ 0.615295] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0 | |
| [ 0.615520] hpet0: 3 comparators, 64-bit 100.000000 MHz counter | |
| [ 0.623419] clocksource: Switched to clocksource hpet | |
| [ 0.846276] VFS: Disk quotas dquot_6.6.0 | |
| [ 0.846754] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes) | |
| [ 0.850839] pnp: PnP ACPI init | |
| [ 0.868752] pnp: PnP ACPI: found 6 devices | |
| [ 0.922908] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns | |
| [ 0.923301] pci 0000:01:01.0: can't claim BAR 6 [mem 0xfff80000-0xffffffff pref]: no compatible bridge window | |
| [ 0.925228] pci 0000:01:01.0: BAR 6: assigned [mem 0xc0180000-0xc01fffff pref] | |
| [ 0.925643] pci 0000:00:02.0: PCI bridge to [bus 01] | |
| [ 0.925860] pci 0000:00:02.0: bridge window [io 0xc000-0xcfff] | |
| [ 0.927424] pci 0000:00:02.0: bridge window [mem 0xc0100000-0xc01fffff] | |
| [ 0.928549] pci 0000:00:02.0: bridge window [mem 0xc0000000-0xc00fffff 64bit pref] | |
| [ 0.931916] NET: Registered protocol family 2 | |
| [ 0.940478] tcp_listen_portaddr_hash hash table entries: 2048 (order: 3, 32768 bytes) | |
| [ 0.940863] TCP established hash table entries: 32768 (order: 6, 262144 bytes) | |
| [ 0.941693] TCP bind hash table entries: 32768 (order: 7, 524288 bytes) | |
| [ 0.942472] TCP: Hash tables configured (established 32768 bind 32768) | |
| [ 0.944509] UDP hash table entries: 2048 (order: 4, 65536 bytes) | |
| [ 0.945167] UDP-Lite hash table entries: 2048 (order: 4, 65536 bytes) | |
| [ 0.950413] NET: Registered protocol family 1 | |
| [ 0.950882] NET: Registered protocol family 44 | |
| [ 0.951336] pci 0000:00:00.0: Limiting direct PCI/PCI transfers | |
| [ 0.951530] pci 0000:00:01.0: PIIX3: Enabling Passive Release | |
| [ 0.951807] pci 0000:00:01.0: Activating ISA DMA hang workarounds | |
| [ 1.161030] PCI Interrupt Link [LNKD] enabled at IRQ 10 | |
| [ 1.370900] pci 0000:00:01.2: quirk_usb_early_handoff+0x0/0x6c5 took 408998 usecs | |
| [ 1.375754] Unpacking initramfs... | |
| [ 42.936948] Freeing initrd memory: 396380K | |
| [ 43.134400] Initialise system trusted keyrings | |
| [ 43.135523] Key type blacklist registered | |
| [ 43.136917] workingset: timestamp_bits=36 max_order=20 bucket_order=0 | |
| [ 43.157617] zbud: loaded | |
| [ 43.162416] pstore: using deflate compression | |
| [ 43.164666] Platform Keyring initialized | |
| [ 46.478465] NET: Registered protocol family 38 | |
| [ 46.479505] Key type asymmetric registered | |
| [ 46.480247] Asymmetric key parser 'x509' registered | |
| [ 46.481424] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 245) | |
| [ 46.483977] io scheduler mq-deadline registered | |
| [ 46.485270] io scheduler kyber registered | |
| [ 46.486911] io scheduler bfq registered | |
| [ 46.495891] atomic64_test: passed for x86-64 platform with CX8 and with SSE | |
| [ 46.500736] shpchp 0000:00:02.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0.S10_) | |
| [ 46.502600] shpchp 0000:00:02.0: Requesting control of SHPC hotplug via OSHP (\_SB_.PCI0) | |
| [ 46.504125] shpchp 0000:00:02.0: Cannot get control of SHPC hotplug | |
| [ 46.505238] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4 | |
| [ 46.509392] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0 | |
| [ 46.512764] ACPI: Power Button [PWRF] | |
| [ 46.704457] PCI Interrupt Link [LNKC] enabled at IRQ 11 | |
| [ 46.889610] PCI Interrupt Link [LNKB] enabled at IRQ 11 | |
| [ 47.079937] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled | |
| [ 47.127456] 00:04: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A | |
| [ 47.172252] 00:05: ttyS1 at I/O 0x2f8 (irq = 3, base_baud = 115200) is a 16550A | |
| [ 47.182973] Non-volatile memory driver v1.3 | |
| [ 47.188475] rdac: device handler registered | |
| [ 47.189583] hp_sw: device handler registered | |
| [ 47.189714] emc: device handler registered | |
| [ 47.190755] alua: device handler registered | |
| [ 47.192454] libphy: Fixed MDIO Bus: probed | |
| [ 47.193857] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver | |
| [ 47.194222] ehci-pci: EHCI PCI platform driver | |
| [ 47.194544] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver | |
| [ 47.194763] ohci-pci: OHCI PCI platform driver | |
| [ 47.195087] uhci_hcd: USB Universal Host Controller Interface driver | |
| [ 47.393268] uhci_hcd 0000:00:01.2: UHCI Host Controller | |
| [ 47.395621] uhci_hcd 0000:00:01.2: new USB bus registered, assigned bus number 1 | |
| [ 47.396400] uhci_hcd 0000:00:01.2: detected 2 ports | |
| [ 47.397282] uhci_hcd 0000:00:01.2: irq 10, io base 0x0000d020 | |
| [ 47.402387] usb usb1: New USB device found, idVendor=1d6b, idProduct=0001, bcdDevice= 4.18 | |
| [ 47.402568] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1 | |
| [ 47.402726] usb usb1: Product: UHCI Host Controller | |
| [ 47.402841] usb usb1: Manufacturer: Linux 4.18.0-147.8.1.el8_1.x86_64 uhci_hcd | |
| [ 47.402983] usb usb1: SerialNumber: 0000:00:01.2 | |
| [ 47.407031] hub 1-0:1.0: USB hub found | |
| [ 47.407935] hub 1-0:1.0: 2 ports detected | |
| [ 47.414277] usbcore: registered new interface driver usbserial_generic | |
| [ 47.414704] usbserial: USB Serial support registered for generic | |
| [ 47.416460] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12 | |
| [ 47.424637] serio: i8042 KBD port at 0x60,0x64 irq 1 | |
| [ 47.424989] serio: i8042 AUX port at 0x60,0x64 irq 12 | |
| [ 47.427482] mousedev: PS/2 mouse device common for all mice | |
| [ 47.431914] rtc_cmos 00:00: RTC can wake from S4 | |
| [ 47.437673] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1 | |
| [ 47.439522] rtc_cmos 00:00: registered as rtc0 | |
| [ 47.441622] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs | |
| [ 47.450066] EFI Variables Facility v0.08 2004-May-17 | |
| [ 47.466490] hidraw: raw HID events driver (C) Jiri Kosina | |
| [ 47.467672] usbcore: registered new interface driver usbhid | |
| [ 47.467852] usbhid: USB HID core driver | |
| [ 47.469413] drop_monitor: Initializing network drop monitor service | |
| [ 47.470998] Initializing XFRM netlink socket | |
| [ 47.473249] NET: Registered protocol family 10 | |
| [ 47.482555] Segment Routing with IPv6 | |
| [ 47.483144] NET: Registered protocol family 17 | |
| [ 47.484156] mpls_gso: MPLS GSO support | |
| [ 47.487463] core: Using 10 MCE banks | |
| [ 47.491160] registered taskstats version 1 | |
| [ 47.491513] Loading compiled-in X.509 certificates | |
| [ 47.649746] random: fast init done | |
| [ 47.805650] Loaded X.509 cert 'CentOS Linux kernel signing key: fe454f8213ebfc41c8be4b4305d614b216a92a7e' | |
| [ 47.806476] Loaded X.509 cert 'CentOS Linux Driver update signing key: 29bd4c0d06d2e9911044b5dc973309139b51d6d5' | |
| [ 47.806919] Loaded X.509 cert 'CentOS Linux kpatch signing key: b49f086205909dc4da2cfa99376fb191d2f09e78' | |
| [ 47.808196] zswap: loaded using pool lzo/zbud | |
| [ 47.988356] Key type big_key registered | |
| [ 48.193938] Key type encrypted registered | |
| [ 48.195534] Couldn't get size: 0x800000000000000e | |
| [ 48.195740] MODSIGN: Couldn't get UEFI db list | |
| [ 48.196023] Couldn't get size: 0x800000000000000e | |
| [ 48.196455] Couldn't get UEFI MokListRT | |
| [ 48.196787] Couldn't get size: 0x800000000000000e | |
| [ 48.196913] Couldn't get UEFI dbx list | |
| [ 48.197668] ima: No TPM chip found, activating TPM-bypass! | |
| [ 48.197944] ima: Allocated hash algorithm: sha1 | |
| [ 48.222000] evm: Initialising EVM extended attributes: | |
| [ 48.222441] evm: security.selinux | |
| [ 48.222556] evm: security.ima | |
| [ 48.222624] evm: security.capability | |
| [ 48.222705] evm: HMAC attrs: 0x1 | |
| [ 48.227878] rtc_cmos 00:00: setting system clock to 2020-05-25 12:50:15 UTC (1590411015) | |
| [ 48.229463] Unstable clock detected, switching default tracing clock to "global" | |
| [ 48.229463] If you want to keep using the local clock, then add: | |
| [ 48.229463] "trace_clock=local" | |
| [ 48.229463] on the kernel command line | |
| [ 48.252042] Freeing unused decrypted memory: 2040K | |
| [ 48.302585] Freeing unused kernel memory: 2368K | |
| [ 48.305312] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3 | |
| [ 48.321472] Write protecting the kernel read-only data: 18432k | |
| [ 48.329123] Freeing unused kernel memory: 2020K | |
| [ 48.333378] Freeing unused kernel memory: 316K | |
| [ 48.723102] systemd[1]: systemd 239 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN2 -IDN +PCRE2 default-hierarchy=legacy) | |
| [ 48.725521] systemd[1]: Detected virtualization qemu. | |
| [ 48.725832] systemd[1]: Detected architecture x86-64. | |
| Welcome to [0;31mCentOS Linux 8 (Core)[0m! | |
| [ 48.795754] systemd[1]: No hostname configured. | |
| [ 48.796625] systemd[1]: Set hostname to <localhost>. | |
| [ 48.797812] random: systemd: uninitialized urandom read (16 bytes read) | |
| [ 48.798479] systemd[1]: Initializing machine ID from random generator. | |
| [ 51.213053] random: systemd: uninitialized urandom read (16 bytes read) | |
| [ 51.214428] systemd[1]: Reached target Swap. | |
| [[0;32m OK [0m] Reached target Swap. | |
| [ 51.219045] random: systemd: uninitialized urandom read (16 bytes read) | |
| [ 51.244685] systemd[1]: Created slice system-serial\x2dgetty.slice. | |
| [[0;32m OK [0m] Created slice system-serial\x2dgetty.slice. | |
| [ 51.246645] random: systemd: uninitialized urandom read (16 bytes read) | |
| [ 51.249817] systemd[1]: Created slice User and Session Slice. | |
| [[0;32m OK [0m] Created slice User and Session Slice. | |
| [ 51.256343] systemd[1]: Listening on Journal Socket. | |
| [[0;32m OK [0m] Listening on Journal Socket. | |
| Starting Load Kernel Modules... | |
| [[0;32m OK [0m] Reached target Slices. | |
| Starting Remount Root and Kernel File Systems... | |
| Mounting POSIX Message Queue File System... | |
| Starting Create list of required st…ce nodes for the current kernel... | |
| [[0;32m OK [0m] Listening on Journal Socket (/dev/log). | |
| Starting Journal Service... | |
| Mounting Kernel Debug File System... | |
| [[0;32m OK [0m] Created slice system-getty.slice. | |
| Starting Read and set NIS domainname from /etc/sysconfig/network... | |
| [[0;32m OK [0m] Listening on initctl Compatibility Named Pipe. | |
| [[0;32m OK [0m] Listening on Process Core Dump Socket. | |
| [[0;32m OK [0m] Listening on udev Kernel Socket. | |
| [[0;32m OK [0m] Started Dispatch Password Requests to Console Directory Watch. | |
| [ 52.957423] No iBFT detected. | |
| Mounting Huge Pages File System... | |
| [[0;32m OK [0m] Started Forward Password Requests to Wall Directory Watch. | |
| [[0;32m OK [0m] Reached target Local Encrypted Volumes. | |
| [[0;32m OK [0m] Listening on udev Control Socket. | |
| Starting udev Coldplug all Devices... | |
| [[0;32m OK [0m] Set up automount Arbitrary Executab…rmats File System Automount Point. | |
| [ 53.480168] Loading iSCSI transport class v2.0-870. | |
| [[0;32m OK [0m] Started Remount Root and Kernel File Systems. | |
| [ 53.759854] systemd[1]: Mounted POSIX Message Queue File System. | |
| [[0;32m OK [0m] Mounted POSIX Message Queue File System. | |
| [ 53.813954] systemd[1]: Started Create list of required static device nodes for the current kernel. | |
| [[0;32m OK [0m] Started Create list of required sta…vice nodes for the current kernel. | |
| [ 53.862853] systemd[1]: Mounted Kernel Debug File System. | |
| [[0;32m OK [0m] Mounted Kernel Debug File System. | |
| [ 53.913672] systemd[1]: Started Read and set NIS domainname from /etc/sysconfig/network. | |
| [[0;32m OK [0m] Started Read and set NIS domainname from /etc/sysconfig/network. | |
| [ 53.943181] systemd[1]: Mounted Huge Pages File System. | |
| [[0;32m OK [0m] Mounted Huge Pages File System. | |
| [ 54.052625] iscsi: registered transport (tcp) | |
| [ 54.118326] systemd[1]: Starting Create System Users... | |
| Starting Create System Users... | |
| [ 54.237851] systemd[1]: Starting Load/Save Random Seed... | |
| Starting Load/Save Random Seed... | |
| [ 54.342681] systemd[1]: Starting Rebuild Hardware Database... | |
| Starting Rebuild Hardware Database... | |
| [ 54.433264] systemd[1]: Started Load Kernel Modules. | |
| [[0;32m OK [0m] Started Load Kernel Modules. | |
| [ 54.612561] systemd[1]: Starting Apply Kernel Variables... | |
| Starting Apply Kernel Variables... | |
| Mounting Kernel Configuration File System... | |
| [[0;32m OK [0m] Started Load/Save Random Seed. | |
| [[0;32m OK [0m] Mounted Kernel Configuration File System. | |
| [[0;32m OK [0m] Started Create System Users. | |
| Starting Create Static Device Nodes in /dev... | |
| [[0;32m OK [0m] Started Apply Kernel Variables. | |
| [[0;32m OK [0m] Started Create Static Device Nodes in /dev. | |
| [[0;32m OK [0m] Reached target Local File Systems (Pre). | |
| [[0;32m OK [0m] Reached target Local File Systems. | |
| Starting Mark the need to relabel after reboot... | |
| Starting Rebuild Journal Catalog... | |
| Starting Rebuild Dynamic Linker Cache... | |
| Starting Restore /run/initramfs on shutdown... | |
| [[0;32m OK [0m] Started Mark the need to relabel after reboot. | |
| [[0;32m OK [0m] Started Restore /run/initramfs on shutdown. | |
| [ 59.170112] systemd[1]: Started Rebuild Journal Catalog. | |
| [[0;32m OK [0m] Started Rebuild Journal Catalog. | |
| [ 59.839981] systemd[1]: Started Rebuild Dynamic Linker Cache. | |
| [[0;32m OK [0m] Started Rebuild Dynamic Linker Cache. | |
| [ 60.946528] systemd[1]: Started udev Coldplug all Devices. | |
| [[0;32m OK [0m] Started udev Coldplug all Devices. | |
| [ 66.114764] systemd[1]: Started Rebuild Hardware Database. | |
| [[0;32m OK [0m] Started Rebuild Hardware Database. | |
| [ 66.180862] systemd[1]: Starting Update is Completed... | |
| Starting Update is Completed... | |
| [ 66.256942] systemd[1]: Starting udev Kernel Device Manager... | |
| Starting udev Kernel Device Manager... | |
| [ 66.916605] systemd[1]: Started Update is Completed. | |
| [[0;32m OK [0m] Started Update is Completed. | |
| [ 67.768623] systemd[1]: Started Journal Service. | |
| [[0;32m OK [0m] Started Journal Service. | |
| [ 67.805809] systemd-modules-load[170]: Inserted module 'iscsi_boot_sysfs' | |
| [ 67.850022] systemd-modules-load[170]: Inserted module 'iscsi_ibft' | |
| Starting Flush Journal to Persistent Storage... | |
| [ 67.877500] systemd-modules-load[170]: Inserted module 'iscsi_tcp' | |
| [ 67.895299] systemd-tmpfiles[197]: [/usr/lib/tmpfiles.d/mdadm.conf:1] Line references path below legacy directory /var/run/, updating /var/run/mdadm → /run/mdadm; please update the tmpfiles.d/ drop-in file accordingly. | |
| [ 67.935841] systemd[1]: Starting Flush Journal to Persistent Storage... | |
| [ 68.284958] systemd-journald[174]: Received request to flush runtime journal from PID 1 | |
| [[ 68.398709] systemd[1]: Started Flush Journal to Persistent Storage. | |
| [0;32m OK [0m] Started Flush Journal to Persistent Storage. | |
| [ 68.488068] systemd[1]: Starting Create Volatile Files and Directories... | |
| Starting Create Volatile Files and Directories... | |
| [ 68.877237] systemd-tmpfiles[219]: [/usr/lib/tmpfiles.d/mdadm.conf:1] Line references path below legacy directory /var/run/, updating /var/run/mdadm → /run/mdadm; please update the tmpfiles.d/ drop-in file accordingly. | |
| [[0;32m OK [0m] Started Create Volatile Files and Directories. | |
| [ 69.135895] systemd[1]: Started Create Volatile Files and Directories. | |
| Starting Security Auditing Service...[ 69.216640] systemd[1]: Starting Security Auditing Service... | |
| [ 69.690290] auditd[221]: Could not open dir /var/log/audit (No such file or directory) | |
| [ 69.708947] auditd[221]: The audit daemon is exiting. | |
| [ 69.731612] systemd[1]: auditd.service: Control process exited, code=exited status=6 | |
| [ 69.750481] systemd[1]: auditd.service: Failed with result 'exit-code'. | |
| [ 69.768634] systemd[1]: Failed to start Security Auditing Service. | |
| [[0;1;31mFAILED[0m] Failed to start Security Auditing Service. | |
| See 'systemctl status auditd.service' for details. | |
| Starting Update UTMP about System Boot/Shutdown... | |
| [ 69.879689] systemd[1]: Starting Update UTMP about System Boot/Shutdown... | |
| [ 70.357419] systemd[1]: Started Update UTMP about System Boot/Shutdown. | |
| [[0;32m OK [0m] Started Update UTMP about System Boot/Shutdown. | |
| [[0;32m OK [0m] Started udev Kernel Device Manager. | |
| [[0;32m OK [0m] Reached target System Initialization. | |
| [ 72.896811] systemd[1]: Started udev Kernel Device Manager. | |
| [ 72.908360] systemd[1]: Reached target System Initialization. | |
| [[0;32m OK [0m] Listening on D-Bus System Message Bus Socket. | |
| [ 72.916678] systemd[1]: Listening on D-Bus System Message Bus Socket. | |
| [ 72.937005] systemd[1]: Started dnf makecache --timer. | |
| [[0;32m OK [0m] Started dnf makecache --timer. | |
| [ 72.948907] systemd[1]: Started Daily Cleanup of Temporary Directories. | |
| [[0;32m OK [0m] Started Daily Cleanup of Temporary Directories. | |
| [ 72.961733] systemd[1]: Reached target Timers. | |
| [[0;32m OK [0m] Reached target Timers. | |
| [[0;32m OK [0m] Listening on Open-iSCSI iscsiuio Socket. | |
| [ 72.979608] systemd[1]: Listening on Open-iSCSI iscsiuio Socket. | |
| [ 72.991739] systemd[1]: Listening on Open-iSCSI iscsid Socket. | |
| [[0;32m OK [0m] Listening on Open-iSCSI iscsid Socket. | |
| [ 73.002517] systemd[1]: Reached target Sockets. | |
| [[0;32m OK [0m] Reached target Sockets. | |
| [ 73.020245] systemd[1]: Started Ironic user rescue - notify path existence. | |
| [[0;32m OK [0m] Started Ironic user rescue - notify path existence. | |
| [[0;32m OK [0m] Reached target Paths. | |
| [[0;32m OK [0m] Reached target Basic System. | |
| [ 73.031361] systemd[1]: Reached target Paths. | |
| [ 73.052607] systemd[1]: Reached target Basic System. | |
| [ 73.183685] systemd[1]: Starting NTP client/server... | |
| Starting NTP client/server... | |
| [[0;32m OK [0m] Started Hardware RNG Entropy Gatherer Daemon. | |
| [ 73.333626] systemd[1]: Started Hardware RNG Entropy Gatherer Daemon. | |
| [[0;32m OK [0m] Started D-Bus System Message Bus. | |
| [ 73.463355] systemd[1]: Started D-Bus System Message Bus. | |
| [ 73.838385] systemd[1]: Starting Login Service... | |
| Starting Login Service... | |
| Starting Network Manager... | |
| [ 74.075427] systemd[1]: Starting Network Manager... | |
| [ 74.395857] chronyd[239]: chronyd version 3.5 starting (+CMDMON +NTP +REFCLOCK +RTC +PRIVDROP +SCFILTER +SIGND +ASYNCDNS +SECHASH +IPV6 +DEBUG) | |
| [ 74.671488] rngd[232]: Initalizing available sources | |
| [ 74.707895] rngd[232]: Failed to init entropy source hwrng | |
| [ 74.755727] rngd[232]: Failed to init entropy source rdrand | |
| [ 74.794724] chronyd[239]: Using right/UTC timezone to obtain leap second data | |
| [ 78.465241] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0 | |
| [ 78.876933] input: PC Speaker as /devices/platform/pcspkr/input/input4 | |
| [[0m[0;31m* [0m] (1 of 4) A start job is running for Network Manager (29s / 1min 52s)[ 82.036520] scsi host0: ata_piix | |
| [ 82.045672] NetworkManager[237]: <info> [1590411049.0323] NetworkManager (version 1.20.0-5.el8_1) is starting... (for the first time) | |
| [ 82.139567] scsi host1: ata_piix | |
| [ 82.141944] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xd040 irq 14 | |
| [ 82.143200] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xd048 irq 15 | |
| [K[[0;32m OK [0m] Started NTP client/server. | |
| [ 82.381715] ata1.01: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100 | |
| [ 82.381947] ata1.01: 16777216 sectors, multi 16: LBA48 | |
| [ 82.521077] NetworkManager[237]: <info> [1590411049.2506] Read config: /etc/NetworkManager/NetworkManager.conf | |
| [ 82.750424] scsi 0:0:1:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5 | |
| [ 82.737036] systemd[1]: Started NTP client/server. | |
| [ 84.009793] NetworkManager[237]: <info> [1590411051.2483] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager" | |
| [[0;32m OK [0m] Found device /dev/ttyS0.[ 85.108670] | |
| systemd[1]: Found device /dev/ttyS0. | |
| [ 85.219883] [[0;32m OK [0msystemd[1]: Started Network Manager.] Started Network Manager. | |
| [ 85.664791] systemd[1]: Starting Network Manager Wait Online... | |
| Starting Network Manager Wait Online... | |
| [[0;32m OK [0m] Reached target Network. | |
| [ 85.793511] [[0;32m OK [0m] Started Logout off all iSCSI sessions on shutdown. | |
| systemd[1]: Reached target Network. | |
| [ 85.869945] systemd[1]: Started Logout off all iSCSI sessions on shutdown. | |
| [[0;32m OK [0m] Reached target Remote File Systems (Pre). | |
| [[0;32m OK [0m] Reached target Remote File Systems. | |
| [ 85.973645] systemd[1]: Reached target Remote File Systems (Pre). | |
| [ 86.047960] systemd[1]: Reached target Remote File Systems. | |
| Starting Permit User Sessions... | |
| [ 86.438287] systemd[1]: Starting Permit User Sessions... | |
| Starting Initialize iBFT network interfaces... | |
| [ 86.725173] systemd[1]: Starting Initialize iBFT network interfaces... | |
| [ 87.015452] NetworkManager[237]: <info> [1590411054.1280] manager[0x55b60bbf6070]: monitoring kernel firmware directory '/lib/firmware'. | |
| [ 87.846715] dbus-daemon[233]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.1' (uid=0 pid=237 comm="/usr/sbin/NetworkManager --no-daemon ") | |
| [ 89.115949] init-ibft-interfaces.sh[305]: + set -o pipefail | |
| [ 89.182003] init-ibft-interfaces.sh[305]: + iscsistart -v | |
| Starting Hostname Service... | |
| [ 89.742918] systemd[1]: Starting Hostname Service... | |
| [[0;32m OK [0m] Started Permit User Sessions. | |
| [ 90.239969] systemd[1]: Started Permit User Sessions. | |
| [[0;32m OK [0m] Started Serial Getty on ttyS0. | |
| [ 90.968645] systemd[1]: Started Serial Getty on ttyS0. | |
| [[0;32m OK [0m] Started Getty on tty1. | |
| [ 91.098057] systemd[1]: Started Getty on tty1. | |
| [[0;32m OK [0m] Reached target Login Prompts. | |
| [ 91.161979] systemd[1]: Reached target Login Prompts. | |
| [ 91.282741] init-ibft-interfaces.sh[305]: iscsistart version 6.2.0.877-0 | |
| [ 91.328949] init-ibft-interfaces.sh[305]: + iscsistart -f | |
| [ 91.640764] systemd[1]: Started Command Scheduler. | |
| [[0;32m OK [0m] Started Command Scheduler. | |
| [[0;32m OK [0m] Started Job spooling tools. | |
| [ 92.302948] systemd[1]: Started Job spooling tools. | |
| [ 93.655032] init-ibft-interfaces.sh[305]: Could not get list of targets from firmware. | |
| [ 94.009618] systemd-udevd[247]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. | |
| [ 94.099789] crond[335]: (CRON) STARTUP (1.5.2) | |
| [ 94.126587] crond[335]: (CRON) INFO (RANDOM_DELAY will be scaled with factor 56% if used.) | |
| [ 94.145559] init-ibft-interfaces.sh[305]: + echo 'No iBFT devices to configure, exiting' | |
| [ 94.220407] init-ibft-interfaces.sh[305]: No iBFT devices to configure, exiting | |
| [[0;32m OK [0m] Started Initialize iBFT network interfaces. | |
| [ 94.492820] systemd[1]: Started Initialize iBFT network interfaces. | |
| [ 94.856067] crond[335]: (CRON) INFO (running with inotify support) | |
| [ 97.995357] systemd-udevd[251]: link_config: autonegotiation is unset or enabled, the speed and duplex are not writable. | |
| [ 98.024605] virtio_net virtio1 ens1: renamed from eth0 | |
| [ 98.174761] scsi 0:0:1:0: Attached scsi generic sg0 type 0 | |
| CentOS Linux 8 (Core) | |
| Kernel 4.18.0-147.8.1.el8_1.x86_64 on an x86_64 | |
| localhost login: [ 98.614444] sd 0:0:1:0: [sda] 16777216 512-byte logical blocks: (8.59 GB/8.00 GiB) | |
| [ 98.625015] sd 0:0:1:0: [sda] Write Protect is off | |
| [ 98.636918] sd 0:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA | |
| [ 98.875363] sd 0:0:1:0: [sda] Attached SCSI disk | |
| [ 100.555716] systemd[1]: Created slice system-dhcp\x2dinterface.slice. | |
| [ 100.629587] systemd[1]: Starting DHCP interface ens1... | |
| [ 102.849694] dhcp-all-interfaces.sh[360]: Inspecting interface: ens1...rdisc6 not available, skipping IPv6 configuration. | |
| [ 103.100916] dhcp-all-interfaces.sh[360]: Configured ens1 | |
| [ 111.572713] systemd-logind[234]: Watching system buttons on /dev/input/event0 (Power Button) | |
| [ 111.604442] systemd-logind[234]: Watching system buttons on /dev/input/event1 (AT Translated Set 2 keyboard) | |
| [ 111.617904] systemd-logind[234]: New seat seat0. | |
| [ 111.908831] systemd[1]: Started Login Service. | |
| [ 112.267645] NetworkManager[237]: <info> [1590411079.5043] hostname: hostname: hostnamed not used as proxy creation failed with: Error calling StartServiceByName for org.freedesktop.hostname1: Timeout was reached | |
| [ 112.316523] dbus-daemon[233]: [system] Failed to activate service 'org.freedesktop.hostname1': timed out (service_start_timeout=25000ms) | |
| [ 112.344648] NetworkManager[237]: <info> [1590411079.6163] dns-mgr[0x55b60bbdb250]: init: dns=default,systemd-resolved rc-manager=symlink | |
| [ 112.780787] NetworkManager[237]: <info> [1590411080.0520] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file | |
| [ 112.936789] NetworkManager[237]: <info> [1590411080.0840] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file | |
| [ 112.958710] NetworkManager[237]: <info> [1590411080.0882] manager: Networking is enabled by state file | |
| [ 113.001536] dbus-daemon[233]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=237 comm="/usr/sbin/NetworkManager --no-daemon ") | |
| [ 113.022262] NetworkManager[237]: <info> [1590411080.2649] dhcp-init: Using DHCP client 'internal' | |
| [ 113.113607] NetworkManager[237]: <info> [1590411080.3825] settings: Loaded settings plugin: ifcfg-rh ("/usr/lib64/NetworkManager/1.20.0-5.el8_1/libnm-settings-plugin-ifcfg-rh.so") | |
| [ 113.126875] NetworkManager[237]: <info> [1590411080.3988] settings: Loaded settings plugin: keyfile (internal) | |
| [ 113.279309] systemd[1]: Starting Network Manager Script Dispatcher Service... | |
| [ 114.277988] NetworkManager[237]: <info> [1590411081.5276] device (lo): carrier: link connected | |
| [ 114.390416] NetworkManager[237]: <info> [1590411081.6609] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1) | |
| [ 114.579685] NetworkManager[237]: <info> [1590411081.8485] device (ens1): carrier: link connected | |
| [ 114.721948] NetworkManager[237]: <info> [1590411081.9935] manager: (ens1): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2) | |
| [ 115.110060] dbus-daemon[233]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher' | |
| [ 115.183577] systemd[1]: Started Network Manager Script Dispatcher Service. | |
| [ 115.998898] NetworkManager[237]: <info> [1590411083.2472] device (ens1): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external') | |
| [ 117.437712] NetworkManager[237]: <info> [1590411084.6939] device (ens1): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed') | |
| [ 117.752004] NetworkManager[237]: <info> [1590411085.0229] policy: auto-activating connection 'System ens1' (d18b6429-133f-4947-3b25-4482c7f9d5e7) | |
| [ 117.992721] NetworkManager[237]: <info> [1590411085.2597] device (ens1): Activation: starting connection 'System ens1' (d18b6429-133f-4947-3b25-4482c7f9d5e7) | |
| [ 118.023177] NetworkManager[237]: <info> [1590411085.2822] device (ens1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') | |
| [ 118.077960] NetworkManager[237]: <info> [1590411085.3486] manager: NetworkManager state is now CONNECTING | |
| [ 118.081289] random: crng init done | |
| [ 118.081289] random: 6 urandom warning(s) missed due to ratelimiting | |
| [ 118.615604] NetworkManager[237]: <info> [1590411085.8608] audit: op="connections-load" args="/etc/sysconfig/network-scripts/ifcfg-ens1" pid=370 uid=0 result="success" | |
| [ 118.659329] NetworkManager[237]: <info> [1590411085.8790] device (ens1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') | |
| [ 118.783658] NetworkManager[237]: <info> [1590411086.0551] device (ens1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') | |
| [ 118.874709] NetworkManager[237]: <info> [1590411086.1451] dhcp4 (ens1): activation: beginning transaction (timeout in 45 seconds) | |
| [ 119.655363] NetworkManager[237]: <info> [1590411086.8980] dhcp4 (ens1): state changed unknown -> bound | |
| [ 120.153549] NetworkManager[237]: <info> [1590411087.3495] device (ens1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') | |
| [ 120.482731] systemd[1]: NetworkManager-wait-online.service: Main process exited, code=exited, status=1/FAILURE | |
| [ 120.519650] systemd[1]: NetworkManager-wait-online.service: Failed with result 'exit-code'. | |
| [ 120.592708] systemd[1]: Failed to start Network Manager Wait Online. | |
| [ 120.872321] NetworkManager[237]: <info> [1590411088.1145] device (ens1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') | |
| [ 120.910610] NetworkManager[237]: <info> [1590411088.1734] device (ens1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') | |
| [ 121.043345] NetworkManager[237]: <info> [1590411088.3120] manager: NetworkManager state is now CONNECTED_LOCAL | |
| [ 121.565769] NetworkManager[237]: <info> [1590411088.8310] manager: NetworkManager state is now CONNECTED_SITE | |
| [ 121.594707] NetworkManager[237]: <info> [1590411088.8340] policy: set 'System ens1' (ens1) as default for IPv4 routing and DNS | |
| [ 121.783007] NetworkManager[237]: <info> [1590411088.8488] policy: set 'System ens1' (ens1) as default for IPv6 routing and DNS | |
| [ 121.796840] NetworkManager[237]: <info> [1590411088.8501] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 122.010476] NetworkManager[237]: <warn> [1590411088.8582] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 122.260378] dbus-daemon[233]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.1' (uid=0 pid=237 comm="/usr/sbin/NetworkManager --no-daemon ") | |
| [ 122.273392] NetworkManager[237]: <warn> [1590411088.8586] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 122.297036] dbus-daemon[233]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found. | |
| [ 122.413412] systemd[1]: Started Hostname Service. | |
| [ 122.417824] NetworkManager[237]: <info> [1590411089.2234] device (ens1): Activation: successful, device activated. | |
| [ 122.432006] NetworkManager[237]: <info> [1590411089.4027] manager: NetworkManager state is now CONNECTED_GLOBAL | |
| [ 122.447875] NetworkManager[237]: <info> [1590411089.5643] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 122.460015] NetworkManager[237]: <info> [1590411089.5661] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 122.492840] NetworkManager[237]: <warn> [1590411089.5663] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 122.506444] NetworkManager[237]: <warn> [1590411089.5665] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 122.518890] NetworkManager[237]: <info> [1590411089.7135] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 122.522060] NetworkManager[237]: <info> [1590411089.7232] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 122.531514] NetworkManager[237]: <warn> [1590411089.7235] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 122.541732] NetworkManager[237]: <warn> [1590411089.7248] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 122.558389] NetworkManager[237]: <info> [1590411089.7254] manager: startup complete | |
| [ 124.750660] systemd[1]: iscsi.service: Unit cannot be reloaded because it is inactive. | |
| [ 125.029474] NetworkManager[237]: <info> [1590411092.2612] agent-manager: req[0x55b60bc54bd0, :1.8/nmcli-connect/0]: agent registered | |
| [ 125.102562] NetworkManager[237]: <info> [1590411092.3743] device (ens1): state change: activated -> deactivating (reason 'new-activation', sys-iface-state: 'managed') | |
| [ 125.185813] NetworkManager[237]: <info> [1590411092.4571] manager: NetworkManager state is now DISCONNECTING | |
| [ 125.601689] NetworkManager[237]: <info> [1590411092.8713] device (ens1): disconnecting for new activation request. | |
| [ 125.619589] NetworkManager[237]: <info> [1590411092.8904] audit: op="connection-activate" uuid="d18b6429-133f-4947-3b25-4482c7f9d5e7" name="System ens1" pid=368 uid=0 result="success" | |
| [ 125.649957] NetworkManager[237]: <info> [1590411092.9206] device (ens1): state change: deactivating -> disconnected (reason 'new-activation', sys-iface-state: 'managed') | |
| [ 125.714708] NetworkManager[237]: <info> [1590411092.9861] dhcp4 (ens1): canceled DHCP transaction | |
| [ 125.720688] NetworkManager[237]: <info> [1590411092.9879] dhcp4 (ens1): state changed bound -> done | |
| [ 126.163649] NetworkManager[237]: <info> [1590411093.4000] manager: NetworkManager state is now DISCONNECTED | |
| [ 126.220422] NetworkManager[237]: <info> [1590411093.4911] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 126.246026] NetworkManager[237]: <info> [1590411093.5180] device (ens1): Activation: starting connection 'System ens1' (d18b6429-133f-4947-3b25-4482c7f9d5e7) | |
| [ 126.717483] NetworkManager[237]: <info> [1590411093.9864] device (ens1): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed') | |
| [ 126.823531] NetworkManager[237]: <info> [1590411094.0941] manager: NetworkManager state is now CONNECTING | |
| [ 126.865474] NetworkManager[237]: <info> [1590411094.1361] device (ens1): state change: prepare -> config (reason 'none', sys-iface-state: 'managed') | |
| [ 126.982938] NetworkManager[237]: <info> [1590411094.2530] device (ens1): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed') | |
| [ 127.050768] NetworkManager[237]: <info> [1590411094.3214] dhcp4 (ens1): activation: beginning transaction (timeout in 45 seconds) | |
| [ 127.329781] NetworkManager[237]: <info> [1590411094.5866] dhcp4 (ens1): state changed unknown -> bound | |
| [ 127.474001] NetworkManager[237]: <info> [1590411094.7436] device (ens1): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed') | |
| [ 127.792571] NetworkManager[237]: <info> [1590411095.0565] device (ens1): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed') | |
| [ 127.820667] NetworkManager[237]: <info> [1590411095.0919] device (ens1): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed') | |
| [ 127.880500] NetworkManager[237]: <info> [1590411095.1521] manager: NetworkManager state is now CONNECTED_LOCAL | |
| [ 127.953435] ifup[368]: Connection successfully activated (D-Bus active path: /org/freedesktop/NetworkManager/ActiveConnection/2) | |
| [ 128.031528] NetworkManager[237]: <info> [1590411095.3031] manager: NetworkManager state is now CONNECTED_SITE | |
| [ 128.065737] NetworkManager[237]: <info> [1590411095.3372] policy: set 'System ens1' (ens1) as default for IPv4 routing and DNS | |
| [ 128.099549] NetworkManager[237]: <info> [1590411095.3708] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 128.103422] NetworkManager[237]: <info> [1590411095.3714] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 128.105800] NetworkManager[237]: <warn> [1590411095.3716] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 128.119979] NetworkManager[237]: <warn> [1590411095.3732] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 128.143589] NetworkManager[237]: <info> [1590411095.4150] device (ens1): Activation: successful, device activated. | |
| [ 128.486618] NetworkManager[237]: <info> [1590411095.6965] manager: NetworkManager state is now CONNECTED_GLOBAL | |
| [ 128.660315] NetworkManager[237]: <info> [1590411095.9309] policy: set 'System ens1' (ens1) as default for IPv6 routing and DNS | |
| [ 128.668544] NetworkManager[237]: <info> [1590411095.9371] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 128.678834] NetworkManager[237]: <info> [1590411095.9394] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 128.695980] NetworkManager[237]: <warn> [1590411095.9550] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 128.701188] NetworkManager[237]: <warn> [1590411095.9552] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 129.357597] systemd[1]: Started DHCP interface ens1. | |
| [ 129.379800] systemd[1]: Reached target Network is Online. | |
| [ 129.475557] systemd[1]: Starting Ironic Python Agent... | |
| [ 129.766025] NetworkManager[237]: <info> [1590411097.0181] policy: set-hostname: current hostname was changed outside NetworkManager: 'localhost' | |
| [ 129.778469] NetworkManager[237]: <info> [1590411097.0298] policy: set-hostname: set hostname to 'host-10-1-0-8' (from DHCPv4) | |
| [ 129.787458] NetworkManager[237]: <warn> [1590411097.0303] policy: set-hostname: couldn't set the system hostname to 'host-10-1-0-8': (1) Operation not permitted | |
| [ 129.797851] NetworkManager[237]: <warn> [1590411097.0304] policy: set-hostname: you should use hostnamed when systemd hardening is in effect! | |
| [ 130.286824] rngd[232]: Enabling JITTER rng support | |
| [ 130.300716] rngd[232]: Initalizing entropy source jitter | |
| [ 131.038694] systemd[1]: Started Ironic Python Agent. | |
| [ 131.101988] systemd[1]: Reached target Multi-User System. | |
| [ 131.111682] systemd[1]: Reached target Graphical Interface. | |
| [ 131.238590] systemd[1]: Starting Update UTMP about System Runlevel Changes... | |
| [ 132.562280] systemd[1]: Started Update UTMP about System Runlevel Changes. | |
| [ 132.571507] systemd[1]: Startup finished in 48.483s (kernel) + 1min 24.075s (userspace) = 2min 12.559s. | |
| [ 136.216701] systemd[1]: iscsi.service: Unit cannot be reloaded because it is inactive. | |
| [ 191.003686] ironic-python-agent[408]: 2020-05-25 12:52:38.171 408 INFO ironic_python_agent.agent [-] Starting ironic-python-agent version: 6.2.0.dev14[00m | |
| [ 191.056574] ironic-python-agent[408]: 2020-05-25 12:52:38.322 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 191.185620] ironic-python-agent[408]: 2020-05-25 12:52:38.450 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.128s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 191.193410] ironic-python-agent[408]: 2020-05-25 12:52:38.461 408 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 191.202336] ironic-python-agent[408]: 2020-05-25 12:52:38.469 408 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. | |
| [ 191.206440] ironic-python-agent[408]: Command: readlink -v /sys/class/net/lo/device/driver/module | |
| [ 191.208995] ironic-python-agent[408]: Exit code: 1 | |
| [ 191.212632] ironic-python-agent[408]: Stdout: '' | |
| [ 191.215062] ironic-python-agent[408]: Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42[00m | |
| [ 191.221651] ironic-python-agent[408]: 2020-05-25 12:52:38.478 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ens1/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 191.316589] ironic-python-agent[408]: 2020-05-25 12:52:38.581 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ens1/device/driver/module" returned: 0 in 0.104s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 191.324366] ironic-python-agent[408]: 2020-05-25 12:52:38.590 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/ens1/device/driver/module" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 191.330200] ironic-python-agent[408]: 2020-05-25 12:52:38.596 408 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net | |
| [ 191.334609] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 191.338667] ironic-python-agent[408]: 2020-05-25 12:52:38.602 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 191.342802] ironic-python-agent[408]: 2020-05-25 12:52:38.610 408 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81[00m | |
| [ 191.350418] ironic-python-agent[408]: 2020-05-25 12:52:38.618 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 191.497762] ironic-python-agent[408]: 2020-05-25 12:52:38.763 408 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.144s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 191.506440] ironic-python-agent[408]: 2020-05-25 12:52:38.773 408 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 191.539355] ironic-python-agent[408]: 2020-05-25 12:52:38.782 408 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. | |
| [ 191.543372] ironic-python-agent[408]: Command: iscsistart -f | |
| [ 191.547385] ironic-python-agent[408]: Exit code: 21 | |
| [ 191.549718] ironic-python-agent[408]: Stdout: 'Could not get list of targets from firmware.\n' | |
| [ 191.553437] ironic-python-agent[408]: Stderr: '' _check_for_iscsi /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:126[00m | |
| [ 191.557834] ironic-python-agent[408]: 2020-05-25 12:52:38.815 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 191.742573] ironic-python-agent[408]: 2020-05-25 12:52:39.007 408 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.192s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 191.754480] ironic-python-agent[408]: 2020-05-25 12:52:39.020 408 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 191.760314] ironic-python-agent[408]: 2020-05-25 12:52:39.028 408 INFO root [-] No new RAID devices assembled during start-up[00m | |
| [ 191.770248] ironic-python-agent[408]: 2020-05-25 12:52:39.039 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 192.025665] ironic-python-agent[408]: 2020-05-25 12:52:39.274 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.234s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 192.033789] ironic-python-agent[408]: 2020-05-25 12:52:39.284 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 192.037795] ironic-python-agent[408]: 2020-05-25 12:52:39.292 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 192.041823] ironic-python-agent[408]: 2020-05-25 12:52:39.299 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 192.047718] ironic-python-agent[408]: 2020-05-25 12:52:39.311 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 192.205623] ironic-python-agent[408]: 2020-05-25 12:52:39.470 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.157s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 192.213389] ironic-python-agent[408]: 2020-05-25 12:52:39.479 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 192.219351] ironic-python-agent[408]: 2020-05-25 12:52:39.487 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 192.222966] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 192.226877] ironic-python-agent[408]: 2020-05-25 12:52:39.494 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 192.357838] ironic-python-agent[408]: 2020-05-25 12:52:39.624 408 INFO root [-] Picked root device /dev/sda for node None based on root device hints None[00m | |
| [ 192.367392] ironic-python-agent[408]: 2020-05-25 12:52:39.633 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 192.523683] ironic-python-agent[408]: 2020-05-25 12:52:39.789 408 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.155s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 192.532382] ironic-python-agent[408]: 2020-05-25 12:52:39.799 408 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 192.540354] ironic-python-agent[408]: 2020-05-25 12:52:39.806 408 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. | |
| [ 192.544458] ironic-python-agent[408]: Command: iscsistart -f | |
| [ 192.548670] ironic-python-agent[408]: Exit code: 21 | |
| [ 192.552341] ironic-python-agent[408]: Stdout: 'Could not get list of targets from firmware.\n' | |
| [ 192.554826] ironic-python-agent[408]: Stderr: '' _check_for_iscsi /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:126[00m | |
| [ 192.558841] ironic-python-agent[408]: 2020-05-25 12:52:39.815 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 192.706649] ironic-python-agent[408]: 2020-05-25 12:52:39.971 408 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.156s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 192.715377] ironic-python-agent[408]: 2020-05-25 12:52:39.982 408 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 192.722290] ironic-python-agent[408]: 2020-05-25 12:52:39.990 408 INFO root [-] No new RAID devices assembled during start-up[00m | |
| [ 192.730285] ironic-python-agent[408]: 2020-05-25 12:52:39.999 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 192.928507] ironic-python-agent[408]: 2020-05-25 12:52:40.194 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.194s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 192.937482] ironic-python-agent[408]: 2020-05-25 12:52:40.204 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 192.943303] ironic-python-agent[408]: 2020-05-25 12:52:40.209 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 192.948387] ironic-python-agent[408]: 2020-05-25 12:52:40.215 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 192.958596] ironic-python-agent[408]: 2020-05-25 12:52:40.227 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 193.111832] ironic-python-agent[408]: 2020-05-25 12:52:40.368 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.141s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 193.119356] ironic-python-agent[408]: 2020-05-25 12:52:40.377 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 193.123447] ironic-python-agent[408]: 2020-05-25 12:52:40.385 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 193.127781] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 193.131858] ironic-python-agent[408]: 2020-05-25 12:52:40.391 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 193.237803] ironic-python-agent[408]: 2020-05-25 12:52:40.503 408 INFO root [-] Picked root device /dev/sda for node None based on root device hints None[00m | |
| [ 193.248289] ironic-python-agent[408]: 2020-05-25 12:52:40.513 408 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'[00m | |
| [ 193.257436] ironic-python-agent[408]: 2020-05-25 12:52:40.525 408 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84[00m | |
| [ 193.265361] ironic-python-agent[408]: 2020-05-25 12:52:40.533 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 193.385675] ironic-python-agent[408]: 2020-05-25 12:52:40.638 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.104s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 193.388091] ironic-python-agent[408]: 2020-05-25 12:52:40.646 408 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 193.390004] ironic-python-agent[408]: 2020-05-25 12:52:40.650 408 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. | |
| [ 193.393981] ironic-python-agent[408]: Command: readlink -v /sys/class/net/lo/device/driver/module | |
| [ 193.398638] ironic-python-agent[408]: Exit code: 1 | |
| [ 193.404972] ironic-python-agent[408]: Stdout: '' | |
| [ 193.408860] ironic-python-agent[408]: Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42[00m | |
| [ 193.414583] ironic-python-agent[408]: 2020-05-25 12:52:40.655 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ens1/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 193.512819] ironic-python-agent[408]: 2020-05-25 12:52:40.777 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ens1/device/driver/module" returned: 0 in 0.121s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 193.521399] ironic-python-agent[408]: 2020-05-25 12:52:40.787 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/ens1/device/driver/module" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 193.527477] ironic-python-agent[408]: 2020-05-25 12:52:40.793 408 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net | |
| [ 193.531540] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 193.535468] ironic-python-agent[408]: 2020-05-25 12:52:40.799 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 193.541410] ironic-python-agent[408]: 2020-05-25 12:52:40.809 408 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81[00m | |
| [ 193.556224] ironic-python-agent[408]: 2020-05-25 12:52:40.819 408 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'[00m | |
| [ 193.560631] ironic-python-agent[408]: 2020-05-25 12:52:40.826 408 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84[00m | |
| [ 193.568472] ironic-python-agent[408]: 2020-05-25 12:52:40.836 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 193.730668] ironic-python-agent[408]: 2020-05-25 12:52:40.994 408 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.159s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 193.739490] ironic-python-agent[408]: 2020-05-25 12:52:41.004 408 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 193.747372] ironic-python-agent[408]: 2020-05-25 12:52:41.015 408 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. | |
| [ 193.751429] ironic-python-agent[408]: Command: iscsistart -f | |
| [ 193.753847] ironic-python-agent[408]: Exit code: 21 | |
| [ 193.757973] ironic-python-agent[408]: Stdout: 'Could not get list of targets from firmware.\n' | |
| [ 193.761902] ironic-python-agent[408]: Stderr: '' _check_for_iscsi /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:126[00m | |
| [ 193.767367] ironic-python-agent[408]: 2020-05-25 12:52:41.023 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 193.913604] ironic-python-agent[408]: 2020-05-25 12:52:41.178 408 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.154s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 193.921531] ironic-python-agent[408]: 2020-05-25 12:52:41.187 408 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 193.928374] ironic-python-agent[408]: 2020-05-25 12:52:41.197 408 INFO root [-] No new RAID devices assembled during start-up[00m | |
| [ 193.936369] ironic-python-agent[408]: 2020-05-25 12:52:41.205 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 194.150302] ironic-python-agent[408]: 2020-05-25 12:52:41.396 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.190s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 194.156950] ironic-python-agent[408]: 2020-05-25 12:52:41.414 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 194.162228] ironic-python-agent[408]: 2020-05-25 12:52:41.423 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 194.166252] ironic-python-agent[408]: 2020-05-25 12:52:41.429 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 194.173437] ironic-python-agent[408]: 2020-05-25 12:52:41.442 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 194.322628] ironic-python-agent[408]: 2020-05-25 12:52:41.588 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.145s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 194.330413] ironic-python-agent[408]: 2020-05-25 12:52:41.598 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 194.336359] ironic-python-agent[408]: 2020-05-25 12:52:41.604 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 194.339024] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 194.344439] ironic-python-agent[408]: 2020-05-25 12:52:41.609 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 194.445587] ironic-python-agent[408]: 2020-05-25 12:52:41.711 408 INFO root [-] Picked root device /dev/sda for node None based on root device hints None[00m | |
| [ 194.452320] ironic-python-agent[408]: 2020-05-25 12:52:41.719 408 INFO root [-] Hardware manager found: ironic_python_agent.hardware:GenericHardwareManager[00m | |
| [ 194.461565] ironic-python-agent[408]: 2020-05-25 12:52:41.729 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/lo/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 194.588680] ironic-python-agent[408]: 2020-05-25 12:52:41.849 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/lo/device/driver/module" returned: 1 in 0.120s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 194.599013] ironic-python-agent[408]: 2020-05-25 12:52:41.862 408 DEBUG oslo_concurrency.processutils [-] 'readlink -v /sys/class/net/lo/device/driver/module' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 194.610537] ironic-python-agent[408]: 2020-05-25 12:52:41.874 408 DEBUG root [-] Get driver for interface lo failed. Error: Unexpected error while running command. | |
| [ 194.617614] ironic-python-agent[408]: Command: readlink -v /sys/class/net/lo/device/driver/module | |
| [ 194.624034] ironic-python-agent[408]: Exit code: 1 | |
| [ 194.627332] ironic-python-agent[408]: Stdout: '' | |
| [ 194.629560] ironic-python-agent[408]: Stderr: 'readlink: /sys/class/net/lo/device/driver/module: No such file or directory\n' _detect_cna_card /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:42[00m | |
| [ 194.634586] ironic-python-agent[408]: 2020-05-25 12:52:41.886 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): readlink -v /sys/class/net/ens1/device/driver/module execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 194.759803] ironic-python-agent[408]: 2020-05-25 12:52:42.024 408 DEBUG oslo_concurrency.processutils [-] CMD "readlink -v /sys/class/net/ens1/device/driver/module" returned: 0 in 0.137s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 194.773873] ironic-python-agent[408]: 2020-05-25 12:52:42.036 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "readlink -v /sys/class/net/ens1/device/driver/module" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 194.781373] ironic-python-agent[408]: 2020-05-25 12:52:42.045 408 DEBUG ironic_lib.utils [-] Command stdout is: "../../../../module/virtio_net | |
| [ 194.785238] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 194.789899] ironic-python-agent[408]: 2020-05-25 12:52:42.053 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 194.797504] ironic-python-agent[408]: 2020-05-25 12:52:42.062 408 DEBUG root [-] No Intel CNA network card found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/cna.py:81[00m | |
| [ 194.806294] ironic-python-agent[408]: 2020-05-25 12:52:42.072 408 WARNING root [-] Can't find field vendor for device lo in device class net: FileNotFoundError: [Errno 2] No such file or directory: '/sys/class/net/lo/device/vendor'[00m | |
| [ 194.815265] ironic-python-agent[408]: 2020-05-25 12:52:42.084 408 DEBUG root [-] No Mellanox devices found evaluate_hardware_support /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware_managers/mlnx.py:84[00m | |
| [ 194.850370] ironic-python-agent[408]: 2020-05-25 12:52:42.118 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i ens1 execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 194.923501] ironic-python-agent[408]: 2020-05-25 12:52:42.188 408 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i ens1' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 194.933349] ironic-python-agent[408]: 2020-05-25 12:52:42.198 408 WARNING root [-] Executable 'biosdevname' not found: FileNotFoundError: [Errno 2] No such file or directory: 'biosdevname': 'biosdevname'[00m | |
| [ 194.954482] ironic-python-agent[408]: 2020-05-25 12:52:42.223 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): biosdevname -i ens1 execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 195.026744] ironic-python-agent[408]: 2020-05-25 12:52:42.291 408 DEBUG oslo_concurrency.processutils [-] 'biosdevname -i ens1' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 195.036335] ironic-python-agent[408]: 2020-05-25 12:52:42.304 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lscpu execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 195.221639] ironic-python-agent[408]: 2020-05-25 12:52:42.475 408 DEBUG oslo_concurrency.processutils [-] CMD "lscpu" returned: 0 in 0.170s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 195.227950] ironic-python-agent[408]: 2020-05-25 12:52:42.485 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lscpu" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 195.233741] ironic-python-agent[408]: 2020-05-25 12:52:42.493 408 DEBUG ironic_lib.utils [-] Command stdout is: "Architecture: x86_64 | |
| [ 195.237893] ironic-python-agent[408]: CPU op-mode(s): 32-bit, 64-bit | |
| [ 195.241633] ironic-python-agent[408]: Byte Order: Little Endian | |
| [ 195.245395] ironic-python-agent[408]: CPU(s): 2 | |
| [ 195.249828] ironic-python-agent[408]: On-line CPU(s) list: 0,1 | |
| [ 195.253588] ironic-python-agent[408]: Thread(s) per core: 1 | |
| [ 195.257414] ironic-python-agent[408]: Core(s) per socket: 1 | |
| [ 195.259962] ironic-python-agent[408]: Socket(s): 2 | |
| [ 195.265156] ironic-python-agent[408]: NUMA node(s): 1 | |
| [ 195.269184] ironic-python-agent[408]: Vendor ID: AuthenticAMD | |
| [ 195.271775] ironic-python-agent[408]: CPU family: 6 | |
| [ 195.275585] ironic-python-agent[408]: Model: 6 | |
| [ 195.280041] ironic-python-agent[408]: Model name: QEMU Virtual CPU version 2.5+ | |
| [ 195.283792] ironic-python-agent[408]: Stepping: 3 | |
| [ 195.287614] ironic-python-agent[408]: CPU MHz: 2600.138 | |
| [ 195.291371] ironic-python-agent[408]: BogoMIPS: 5200.27 | |
| [ 195.295513] ironic-python-agent[408]: Virtualization: AMD-V | |
| [ 195.298355] ironic-python-agent[408]: L1d cache: 64K | |
| [ 195.300864] ironic-python-agent[408]: L1i cache: 64K | |
| [ 195.304482] ironic-python-agent[408]: L2 cache: 512K | |
| [ 195.308619] ironic-python-agent[408]: L3 cache: 16384K | |
| [ 195.312304] ironic-python-agent[408]: NUMA node0 CPU(s): 0,1 | |
| [ 195.314744] ironic-python-agent[408]: Flags: fpu de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx lm nopl cpuid pni cx16 hypervisor lahf_lm svm 3dnowprefetch vmmcall | |
| [ 195.318913] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 195.324703] ironic-python-agent[408]: 2020-05-25 12:52:42.503 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 195.328977] ironic-python-agent[408]: 2020-05-25 12:52:42.517 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): grep -Em1 ^flags /proc/cpuinfo execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 195.428626] ironic-python-agent[408]: 2020-05-25 12:52:42.694 408 DEBUG oslo_concurrency.processutils [-] CMD "grep -Em1 ^flags /proc/cpuinfo" returned: 0 in 0.177s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 195.437388] ironic-python-agent[408]: 2020-05-25 12:52:42.704 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "grep -Em1 ^flags /proc/cpuinfo" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 195.444464] ironic-python-agent[408]: 2020-05-25 12:52:42.709 408 DEBUG ironic_lib.utils [-] Command stdout is: "flags : fpu de pse tsc msr pae mce cx8 apic sep mtrr pge mca cmov pat pse36 clflush mmx fxsr sse sse2 syscall nx lm nopl cpuid pni cx16 hypervisor lahf_lm svm 3dnowprefetch vmmcall | |
| [ 195.450313] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 195.454314] ironic-python-agent[408]: 2020-05-25 12:52:42.716 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 195.460421] ironic-python-agent[408]: 2020-05-25 12:52:42.726 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 195.663594] ironic-python-agent[408]: 2020-05-25 12:52:42.928 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.201s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 195.672416] ironic-python-agent[408]: 2020-05-25 12:52:42.939 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 195.678485] ironic-python-agent[408]: 2020-05-25 12:52:42.944 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 195.685395] ironic-python-agent[408]: 2020-05-25 12:52:42.952 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 195.694419] ironic-python-agent[408]: 2020-05-25 12:52:42.963 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 195.833792] ironic-python-agent[408]: 2020-05-25 12:52:43.099 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.135s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 195.843769] ironic-python-agent[408]: 2020-05-25 12:52:43.105 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 195.854358] ironic-python-agent[408]: 2020-05-25 12:52:43.112 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 195.858326] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 195.859701] ironic-python-agent[408]: 2020-05-25 12:52:43.116 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 195.961845] ironic-python-agent[408]: 2020-05-25 12:52:43.227 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 201.281901] ironic-python-agent[408]: 2020-05-25 12:52:48.535 408 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 5.307s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 201.289536] ironic-python-agent[408]: 2020-05-25 12:52:48.547 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lshw -quiet -json" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 201.293659] ironic-python-agent[408]: 2020-05-25 12:52:48.555 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 201.335478] ironic-python-agent[408]: 2020-05-25 12:52:48.603 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_msghandler execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 201.540687] IPMI message handler: version 39.2 | |
| [ 201.592943] ipmi device interface | |
| [ 201.674594] ironic-python-agent[408]: 2020-05-25 12:52:48.908 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_msghandler" returned: 0 in 0.297s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 201.700035] ironic-python-agent[408]: 2020-05-25 12:52:48.964 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "modprobe ipmi_msghandler" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 201.716533] ironic-python-agent[408]: 2020-05-25 12:52:48.968 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 201.733636] ironic-python-agent[408]: 2020-05-25 12:52:48.988 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 201.766491] ironic-python-agent[408]: 2020-05-25 12:52:49.031 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_devintf execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 201.908497] ironic-python-agent[408]: 2020-05-25 12:52:49.173 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_devintf" returned: 0 in 0.143s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 201.916350] ironic-python-agent[408]: 2020-05-25 12:52:49.182 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "modprobe ipmi_devintf" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 201.922600] ironic-python-agent[408]: 2020-05-25 12:52:49.188 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 201.928345] ironic-python-agent[408]: 2020-05-25 12:52:49.194 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 201.936315] ironic-python-agent[408]: 2020-05-25 12:52:49.204 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_si execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 202.159322] ipmi_si: IPMI System Interface driver | |
| [ 202.170346] ipmi_si: Unable to find any System Interface(s) | |
| [ 202.260670] ironic-python-agent[408]: 2020-05-25 12:52:49.512 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_si" returned: 1 in 0.306s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 202.270514] ironic-python-agent[408]: 2020-05-25 12:52:49.538 408 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_si' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 202.290494] ironic-python-agent[408]: 2020-05-25 12:52:49.546 408 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command. | |
| [ 202.294883] ironic-python-agent[408]: Command: modprobe ipmi_si | |
| [ 202.298521] ironic-python-agent[408]: Exit code: 1 | |
| [ 202.300873] ironic-python-agent[408]: Stdout: '' | |
| [ 202.304583] ironic-python-agent[408]: Stderr: "modprobe: ERROR: could not insert 'ipmi_si': No such device\n" try_execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/utils.py:104[00m | |
| [ 202.309078] ironic-python-agent[408]: 2020-05-25 12:52:49.555 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 202.586893] ironic-python-agent[408]: 2020-05-25 12:52:49.851 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.296s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 202.595454] ironic-python-agent[408]: 2020-05-25 12:52:49.861 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 1 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 202.601349] ironic-python-agent[408]: 2020-05-25 12:52:49.869 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 202.608343] ironic-python-agent[408]: 2020-05-25 12:52:49.876 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 202.612399] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 202.617369] ironic-python-agent[408]: 2020-05-25 12:52:49.884 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 202.625395] ironic-python-agent[408]: 2020-05-25 12:52:49.893 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 202.909557] ironic-python-agent[408]: 2020-05-25 12:52:50.174 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.281s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 202.918383] ironic-python-agent[408]: 2020-05-25 12:52:50.183 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 2 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 202.923610] ironic-python-agent[408]: 2020-05-25 12:52:50.190 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 202.929354] ironic-python-agent[408]: 2020-05-25 12:52:50.196 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 202.933587] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 202.939410] ironic-python-agent[408]: 2020-05-25 12:52:50.205 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 202.946892] ironic-python-agent[408]: 2020-05-25 12:52:50.216 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 203.246784] ironic-python-agent[408]: 2020-05-25 12:52:50.510 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.295s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 203.254997] ironic-python-agent[408]: 2020-05-25 12:52:50.521 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 3 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 203.261402] ironic-python-agent[408]: 2020-05-25 12:52:50.528 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 203.267413] ironic-python-agent[408]: 2020-05-25 12:52:50.533 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 203.271291] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 203.277376] ironic-python-agent[408]: 2020-05-25 12:52:50.543 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 203.293973] ironic-python-agent[408]: 2020-05-25 12:52:50.554 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 203.578861] ironic-python-agent[408]: 2020-05-25 12:52:50.843 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.289s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 203.588356] ironic-python-agent[408]: 2020-05-25 12:52:50.854 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 4 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 203.594407] ironic-python-agent[408]: 2020-05-25 12:52:50.860 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 203.600315] ironic-python-agent[408]: 2020-05-25 12:52:50.868 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 203.604356] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 203.609395] ironic-python-agent[408]: 2020-05-25 12:52:50.876 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 203.617415] ironic-python-agent[408]: 2020-05-25 12:52:50.885 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 203.905620] ironic-python-agent[408]: 2020-05-25 12:52:51.170 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.284s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 203.914381] ironic-python-agent[408]: 2020-05-25 12:52:51.179 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 5 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 203.920492] ironic-python-agent[408]: 2020-05-25 12:52:51.187 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 203.933609] ironic-python-agent[408]: 2020-05-25 12:52:51.192 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 203.937497] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 203.941349] ironic-python-agent[408]: 2020-05-25 12:52:51.200 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 203.945403] ironic-python-agent[408]: 2020-05-25 12:52:51.210 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 204.226099] ironic-python-agent[408]: 2020-05-25 12:52:51.490 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.280s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 204.236541] ironic-python-agent[408]: 2020-05-25 12:52:51.497 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 6 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 204.245491] ironic-python-agent[408]: 2020-05-25 12:52:51.508 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 204.250342] ironic-python-agent[408]: 2020-05-25 12:52:51.516 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 204.253773] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 204.258912] ironic-python-agent[408]: 2020-05-25 12:52:51.524 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 204.265416] ironic-python-agent[408]: 2020-05-25 12:52:51.532 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 204.566818] ironic-python-agent[408]: 2020-05-25 12:52:51.822 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.290s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 204.574297] ironic-python-agent[408]: 2020-05-25 12:52:51.834 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 7 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 204.578496] ironic-python-agent[408]: 2020-05-25 12:52:51.842 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 204.582887] ironic-python-agent[408]: 2020-05-25 12:52:51.849 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 204.586694] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 204.592368] ironic-python-agent[408]: 2020-05-25 12:52:51.858 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 204.599403] ironic-python-agent[408]: 2020-05-25 12:52:51.868 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 204.872861] ironic-python-agent[408]: 2020-05-25 12:52:52.137 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.268s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 204.880434] ironic-python-agent[408]: 2020-05-25 12:52:52.146 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 8 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 204.886554] ironic-python-agent[408]: 2020-05-25 12:52:52.152 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 204.892430] ironic-python-agent[408]: 2020-05-25 12:52:52.158 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 204.896604] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 204.901363] ironic-python-agent[408]: 2020-05-25 12:52:52.168 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 204.909373] ironic-python-agent[408]: 2020-05-25 12:52:52.177 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 205.190540] ironic-python-agent[408]: 2020-05-25 12:52:52.456 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.278s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 205.199337] ironic-python-agent[408]: 2020-05-25 12:52:52.464 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 9 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 205.205370] ironic-python-agent[408]: 2020-05-25 12:52:52.471 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 205.211366] ironic-python-agent[408]: 2020-05-25 12:52:52.477 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 205.215405] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 205.220675] ironic-python-agent[408]: 2020-05-25 12:52:52.487 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 205.227335] ironic-python-agent[408]: 2020-05-25 12:52:52.496 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 205.543907] ironic-python-agent[408]: 2020-05-25 12:52:52.807 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.311s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 205.552392] ironic-python-agent[408]: 2020-05-25 12:52:52.817 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 10 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 205.558290] ironic-python-agent[408]: 2020-05-25 12:52:52.824 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 205.572836] ironic-python-agent[408]: 2020-05-25 12:52:52.832 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 205.578541] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 205.582585] ironic-python-agent[408]: 2020-05-25 12:52:52.840 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 205.586799] ironic-python-agent[408]: 2020-05-25 12:52:52.850 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 205.877978] ironic-python-agent[408]: 2020-05-25 12:52:53.142 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}'" returned: 0 in 0.291s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 205.887434] ironic-python-agent[408]: 2020-05-25 12:52:53.151 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan print 11 | awk '/IP Address[ \t]*:/ {print $4}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 205.893377] ironic-python-agent[408]: 2020-05-25 12:52:53.159 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 205.899368] ironic-python-agent[408]: 2020-05-25 12:52:53.167 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 205.903318] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 205.909379] ironic-python-agent[408]: 2020-05-25 12:52:53.175 408 WARNING root [-] Invalid IP address : '' does not appear to be an IPv4 or IPv6 address[00m | |
| [ 205.917354] ironic-python-agent[408]: 2020-05-25 12:52:53.185 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_msghandler execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 206.049544] ironic-python-agent[408]: 2020-05-25 12:52:53.314 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_msghandler" returned: 0 in 0.128s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 206.058345] ironic-python-agent[408]: 2020-05-25 12:52:53.324 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "modprobe ipmi_msghandler" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 206.064339] ironic-python-agent[408]: 2020-05-25 12:52:53.330 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 206.071380] ironic-python-agent[408]: 2020-05-25 12:52:53.338 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 206.079356] ironic-python-agent[408]: 2020-05-25 12:52:53.347 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_devintf execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 206.222628] ironic-python-agent[408]: 2020-05-25 12:52:53.486 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_devintf" returned: 0 in 0.140s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 206.231394] ironic-python-agent[408]: 2020-05-25 12:52:53.496 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "modprobe ipmi_devintf" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 206.237342] ironic-python-agent[408]: 2020-05-25 12:52:53.503 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 206.243323] ironic-python-agent[408]: 2020-05-25 12:52:53.511 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 206.251344] ironic-python-agent[408]: 2020-05-25 12:52:53.519 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): modprobe ipmi_si execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 206.429558] ipmi_si: IPMI System Interface driver | |
| [ 206.433545] ipmi_si: Unable to find any System Interface(s) | |
| [ 206.529829] ironic-python-agent[408]: 2020-05-25 12:52:53.788 408 DEBUG oslo_concurrency.processutils [-] CMD "modprobe ipmi_si" returned: 1 in 0.267s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 206.543575] ironic-python-agent[408]: 2020-05-25 12:52:53.805 408 DEBUG oslo_concurrency.processutils [-] 'modprobe ipmi_si' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 206.550749] ironic-python-agent[408]: 2020-05-25 12:52:53.815 408 DEBUG ironic_python_agent.utils [-] Command failed: Unexpected error while running command. | |
| [ 206.554703] ironic-python-agent[408]: Command: modprobe ipmi_si | |
| [ 206.558860] ironic-python-agent[408]: Exit code: 1 | |
| [ 206.562558] ironic-python-agent[408]: Stdout: '' | |
| [ 206.574791] ironic-python-agent[408]: Stderr: "modprobe: ERROR: could not insert 'ipmi_si': No such device\n" try_execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/utils.py:104[00m | |
| [ 206.580493] ironic-python-agent[408]: 2020-05-25 12:52:53.840 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 206.866844] ironic-python-agent[408]: 2020-05-25 12:52:54.131 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.291s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 206.876333] ironic-python-agent[408]: 2020-05-25 12:52:54.143 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 1 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 206.881342] ironic-python-agent[408]: 2020-05-25 12:52:54.148 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 206.887358] ironic-python-agent[408]: 2020-05-25 12:52:54.155 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 206.891773] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 206.897366] ironic-python-agent[408]: 2020-05-25 12:52:54.163 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 207.197664] ironic-python-agent[408]: 2020-05-25 12:52:54.462 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.298s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 207.207384] ironic-python-agent[408]: 2020-05-25 12:52:54.473 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 2 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 207.213442] ironic-python-agent[408]: 2020-05-25 12:52:54.479 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 207.219331] ironic-python-agent[408]: 2020-05-25 12:52:54.485 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 207.223565] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 207.228539] ironic-python-agent[408]: 2020-05-25 12:52:54.495 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 207.538863] ironic-python-agent[408]: 2020-05-25 12:52:54.803 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.308s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 207.547374] ironic-python-agent[408]: 2020-05-25 12:52:54.814 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 3 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 207.553329] ironic-python-agent[408]: 2020-05-25 12:52:54.821 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 207.560342] ironic-python-agent[408]: 2020-05-25 12:52:54.827 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 207.574533] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 207.578598] ironic-python-agent[408]: 2020-05-25 12:52:54.834 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 207.863447] ironic-python-agent[408]: 2020-05-25 12:52:55.124 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.289s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 207.871541] ironic-python-agent[408]: 2020-05-25 12:52:55.135 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 4 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 207.879316] ironic-python-agent[408]: 2020-05-25 12:52:55.143 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 207.885318] ironic-python-agent[408]: 2020-05-25 12:52:55.153 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 207.889335] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 207.894411] ironic-python-agent[408]: 2020-05-25 12:52:55.161 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 208.185661] ironic-python-agent[408]: 2020-05-25 12:52:55.450 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.289s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 208.195364] ironic-python-agent[408]: 2020-05-25 12:52:55.461 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 5 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 208.202314] ironic-python-agent[408]: 2020-05-25 12:52:55.469 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 208.207318] ironic-python-agent[408]: 2020-05-25 12:52:55.474 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 208.213362] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 208.217351] ironic-python-agent[408]: 2020-05-25 12:52:55.483 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 208.539892] ironic-python-agent[408]: 2020-05-25 12:52:55.803 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.319s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 208.556662] ironic-python-agent[408]: 2020-05-25 12:52:55.815 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 6 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 208.564516] ironic-python-agent[408]: 2020-05-25 12:52:55.830 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 208.577701] ironic-python-agent[408]: 2020-05-25 12:52:55.836 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 208.579724] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 208.584028] ironic-python-agent[408]: 2020-05-25 12:52:55.845 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 208.889763] ironic-python-agent[408]: 2020-05-25 12:52:56.140 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.294s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 208.892173] ironic-python-agent[408]: 2020-05-25 12:52:56.146 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 7 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 208.894705] ironic-python-agent[408]: 2020-05-25 12:52:56.149 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 208.898765] ironic-python-agent[408]: 2020-05-25 12:52:56.151 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 208.907718] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 208.920820] ironic-python-agent[408]: 2020-05-25 12:52:56.156 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 209.189336] ironic-python-agent[408]: 2020-05-25 12:52:56.454 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.298s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 209.198321] ironic-python-agent[408]: 2020-05-25 12:52:56.466 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 8 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 209.204982] ironic-python-agent[408]: 2020-05-25 12:52:56.474 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 209.211776] ironic-python-agent[408]: 2020-05-25 12:52:56.480 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 209.213755] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 209.221305] ironic-python-agent[408]: 2020-05-25 12:52:56.490 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 209.517942] ironic-python-agent[408]: 2020-05-25 12:52:56.783 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.292s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 209.530349] ironic-python-agent[408]: 2020-05-25 12:52:56.798 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 9 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 209.540417] ironic-python-agent[408]: 2020-05-25 12:52:56.809 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 209.549360] ironic-python-agent[408]: 2020-05-25 12:52:56.817 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 209.551713] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 209.562365] ironic-python-agent[408]: 2020-05-25 12:52:56.830 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 209.859355] ironic-python-agent[408]: 2020-05-25 12:52:57.116 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.285s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 209.868413] ironic-python-agent[408]: 2020-05-25 12:52:57.127 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 10 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 209.878406] ironic-python-agent[408]: 2020-05-25 12:52:57.147 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 209.888358] ironic-python-agent[408]: 2020-05-25 12:52:57.156 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 209.890953] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 209.903301] ironic-python-agent[408]: 2020-05-25 12:52:57.171 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}' execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 210.312583] ironic-python-agent[408]: 2020-05-25 12:52:57.577 408 DEBUG oslo_concurrency.processutils [-] CMD "ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" returned: 0 in 0.405s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 210.321325] ironic-python-agent[408]: 2020-05-25 12:52:57.588 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ipmitool lan6 print 11 enables | awk '/IPv6\/IPv4 Addressing Enables[ \t]*:/{print $NF}'" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 210.328272] ironic-python-agent[408]: 2020-05-25 12:52:57.595 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 210.333354] ironic-python-agent[408]: 2020-05-25 12:52:57.600 408 DEBUG ironic_lib.utils [-] Command stderr is: "Could not open device at /dev/ipmi0 or /dev/ipmi/0 or /dev/ipmidev/0: No such file or directory | |
| [ 210.335594] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 210.343360] ironic-python-agent[408]: 2020-05-25 12:52:57.612 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 215.603703] ironic-python-agent[408]: 2020-05-25 12:53:02.859 408 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 5.246s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 215.611647] ironic-python-agent[408]: 2020-05-25 12:53:02.872 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lshw -quiet -json" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 215.617378] ironic-python-agent[408]: 2020-05-25 12:53:02.880 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 215.628313] ironic-python-agent[408]: 2020-05-25 12:53:02.896 408 DEBUG root [-] The current boot mode is uefi get_boot_info /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1083[00m | |
| [ 216.331311] ironic-python-agent[408]: 2020-05-25 12:53:03.596 408 DEBUG ironic_python_agent.agent [-] Received lookup results: {'node': {'uuid': '88833e40-aa2b-4b68-99fa-34c85532a049', 'instance_info': {'image_source': '0ae62a29-2d4f-48c5-9ef0-835627cb81c5', 'root_gb': '7', 'swap_mb': '0', 'display_name': 'tempest-BaremetalBasicOps-server-220936478', 'vcpus': '2', 'nova_host_id': 'ci-testing-1', 'memory_mb': '3072', 'local_gb': '0', 'capabilities': '{"boot_mode": "uefi"}', 'traits': ['CUSTOM_GOLD'], 'configdrive': '******'}, 'driver_internal_info': {'is_whole_disk_image': True, 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}], 'deploy_step_index': 0, 'last_power_state_change': '2020-05-25T12:47:59.610205', 'agent_secret_token': '******'}, 'properties': {'cpu_arch': 'x86_64', 'capabilities': 'boot_mode:uefi'}, 'links': [{'href': 'https://192.168.0.24/baremetal/v1/nodes/88833e40-aa2b-4b68-99fa-34c85532a049', 'rel': 'self'}, {'href': 'https://192.168.0.24/baremetal/nodes/88833e40-aa2b-4b68-99fa-34c85532a049', 'rel': 'bookmark'}]}, 'config': {'metrics': {'backend': 'noop', 'prepend_host': False, 'prepend_uuid': False, 'prepend_host_reverse': True, 'global_prefix': None}, 'metrics_statsd': {'statsd_host': 'localhost', 'statsd_port': 8125}, 'heartbeat_timeout': 300, 'agent_token': 'SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM', 'agent_token_required': True}} run /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/agent.py:429[00m | |
| [ 216.345634] ironic-python-agent[408]: 2020-05-25 12:53:03.607 408 INFO ironic_python_agent.agent [-] Lookup succeeded, node UUID is 88833e40-aa2b-4b68-99fa-34c85532a049[00m | |
| [ 216.351322] ironic-python-agent[408]: 2020-05-25 12:53:03.617 408 INFO root [-] Cached node 88833e40-aa2b-4b68-99fa-34c85532a049, waiting for its root device to appear[00m | |
| [ 216.376310] ironic-python-agent[408]: 2020-05-25 12:53:03.625 408 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1038[00m | |
| [ 216.384386] ironic-python-agent[408]: 2020-05-25 12:53:03.652 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 216.609316] ironic-python-agent[408]: 2020-05-25 12:53:03.864 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.213s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 216.615379] ironic-python-agent[408]: 2020-05-25 12:53:03.873 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 216.619571] ironic-python-agent[408]: 2020-05-25 12:53:03.881 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 216.623774] ironic-python-agent[408]: 2020-05-25 12:53:03.887 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 216.632671] ironic-python-agent[408]: 2020-05-25 12:53:03.900 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 216.786570] ironic-python-agent[408]: 2020-05-25 12:53:04.051 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.151s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 216.795367] ironic-python-agent[408]: 2020-05-25 12:53:04.062 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 216.801347] ironic-python-agent[408]: 2020-05-25 12:53:04.068 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 216.805825] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 216.809941] ironic-python-agent[408]: 2020-05-25 12:53:04.073 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 216.917386] ironic-python-agent[408]: 2020-05-25 12:53:04.183 408 INFO root [-] Picked root device /dev/sda for node 88833e40-aa2b-4b68-99fa-34c85532a049 based on root device hints None[00m | |
| [ 216.927392] ironic-python-agent[408]: 2020-05-25 12:53:04.196 408 DEBUG ironic_python_agent.agent [-] Agent token recorded as designated by the ironic installation. run /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/agent.py:449[00m | |
| [ 216.954294] ironic-python-agent[408]: 2020-05-25 12:53:04.221 408 INFO oslo.service.wsgi [-] ironic-python-agent listening on :::9999[00m | |
| [ 216.993324] ironic-python-agent[408]: 2020-05-25 12:53:04.236 408 INFO ironic_python_agent.api.app [-] Started API service on port 9999[00m | |
| [ 217.008700] ironic-python-agent[408]: 2020-05-25 12:53:04.276 408 INFO ironic_python_agent.agent [-] starting heartbeater[00m | |
| [ 217.026428] ironic-python-agent[408]: 2020-05-25 12:53:04.289 408 INFO eventlet.wsgi.server [-] (408) wsgi starting up on http://[::]:9999[00m | |
| [ 217.038630] ironic-python-agent[408]: 2020-05-25 12:53:04.295 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ip route get 192.168.0.24 execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 217.978765] ironic-python-agent[408]: 2020-05-25 12:53:05.212 408 DEBUG oslo_concurrency.processutils [-] CMD "ip route get 192.168.0.24" returned: 0 in 0.916s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 218.066514] ironic-python-agent[408]: 2020-05-25 12:53:05.333 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ip route get 192.168.0.24" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 218.106622] ironic-python-agent[408]: 2020-05-25 12:53:05.374 408 DEBUG ironic_lib.utils [-] Command stdout is: "192.168.0.24 via 10.1.0.1 dev ens1 src 10.1.0.8 uid 0 | |
| [ 218.110895] ironic-python-agent[408]: cache | |
| [ 218.114795] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 218.303602] ironic-python-agent[408]: 2020-05-25 12:53:05.569 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 219.342677] ironic-python-agent[408]: 2020-05-25 12:53:06.598 408 DEBUG eventlet.wsgi.server [-] (408) accepted ('::ffff:172.24.5.1', 45908, 0, 0) server /opt/ironic-python-agent/lib64/python3.6/site-packages/eventlet/wsgi.py:985[00m | |
| [ 219.361593] ironic-python-agent[408]: 2020-05-25 12:53:06.620 408 INFO ironic_python_agent.agent [-] heartbeat successful[00m | |
| [ 219.376623] ironic-python-agent[408]: 2020-05-25 12:53:06.646 408 INFO ironic_python_agent.agent [-] sleeping before next heartbeat, interval: 158.76669576123342[00m | |
| [ 219.448357] ironic-python-agent[408]: 2020-05-25 12:53:06.714 408 DEBUG root [-] Executing command: deploy.get_deploy_steps with args: {'node': {'id': 1, 'uuid': '88833e40-aa2b-4b68-99fa-34c85532a049', 'name': 'node-0', 'chassis_id': 1, 'instance_uuid': '92403745-5aec-4e22-9bb6-d08af2c7e3b3', 'driver': 'ipmi', 'driver_info': {'ipmi_address': '192.168.0.24', 'ipmi_username': 'admin', 'ipmi_password': '******', 'ipmi_port': 6230}, 'driver_internal_info': {'is_whole_disk_image': True, 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}], 'deploy_step_index': 0, 'last_power_state_change': '2020-05-25T12:47:59.610205', 'agent_secret_token': '******', 'agent_url': 'http://10.1.0.8:9999', 'agent_version': '6.2.0.dev14', 'agent_last_heartbeat': '2020-05-25T12:53:07.655808'}, 'clean_step': {}, 'deploy_step': {'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}, 'raid_config': {}, 'target_raid_config': {}, 'instance_info': {'image_source': '0ae62a29-2d4f-48c5-9ef0-835627cb81c5', 'root_gb': '7', 'swap_mb': '0', 'display_name': 'tempest-BaremetalBasicOps-server-220936478', 'vcpus': '2', 'nova_host_id': 'ci-testing-1', 'memory_mb': '3072', 'local_gb': '0', 'capabilities': '{"boot_mode": "uefi"}', 'traits': ['CUSTOM_GOLD'], 'configdrive': '<...>'}, 'properties': {'cpu_arch': 'x86_64', 'capabilities': 'boot_mode:uefi'}, 'reservation': 'ci-testing-1', 'conductor_affinity': 1, 'conductor_group': '', 'power_state': 'power on', 'target_power_state': None, 'provision_state': 'wait call-back', 'provision_updated_at': '2020-05-25T12:48:11.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': None, 'extra': {}, 'automated_clean': None, 'protected': False, 'protected_reason': None, 'allocation_id': None, 'bios_interface': 'no-bios', 'boot_interface': 'pxe', 'console_interface': 'no-console', 'deploy_interface': 'iscsi', 'inspect_interface': 'no-inspect', 'management_interface': 'ipmitool', 'network_interface': 'flat', 'power_interface': 'ipmitool', 'raid_interface': 'no-raid', 'rescue_interface': 'no-rescue', 'storage_interface': 'noop', 'vendor_interface': 'ipmitool', 'traits': {'objects': [{'node_id': '1', 'trait': 'CUSTOM_GOLD', 'created_at': '2020-05-25T12:30:45.000000', 'updated_at': None}]}, 'owner': None, 'lessee': None, 'description': None, 'retired': False, 'retired_reason': None, 'network_data': {}, 'created_at': '2020-05-25T12:30:44.000000', 'updated_at': '2020-05-25T12:53:07.664714'}, 'ports': [{'id': 1, 'uuid': '8600ce7f-3476-4fd4-95a9-a95246775137', 'node_id': 1, 'address': '52:54:00:03:7e:e8', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'tenant_vif_port_id': 'b31cff80-33aa-4edc-ad99-d068b9292785'}, 'physical_network': None, 'is_smartnic': False, 'created_at': '2020-05-25T12:30:50.000000', 'updated_at': '2020-05-25T12:47:26.000000'}]} execute_command /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/base.py:243[00m | |
| [ 219.463524] ironic-python-agent[408]: 2020-05-25 12:53:06.730 408 DEBUG root [-] Getting deploy steps, called with node: {'id': 1, 'uuid': '88833e40-aa2b-4b68-99fa-34c85532a049', 'name': 'node-0', 'chassis_id': 1, 'instance_uuid': '92403745-5aec-4e22-9bb6-d08af2c7e3b3', 'driver': 'ipmi', 'driver_info': {'ipmi_address': '192.168.0.24', 'ipmi_username': 'admin', 'ipmi_password': '******', 'ipmi_port': 6230}, 'driver_internal_info': {'is_whole_disk_image': True, 'deploy_steps': [{'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}], 'deploy_step_index': 0, 'last_power_state_change': '2020-05-25T12:47:59.610205', 'agent_secret_token': '******', 'agent_url': 'http://10.1.0.8:9999', 'agent_version': '6.2.0.dev14', 'agent_last_heartbeat': '2020-05-25T12:53:07.655808'}, 'clean_step': {}, 'deploy_step': {'step': 'deploy', 'priority': 100, 'argsinfo': None, 'interface': 'deploy'}, 'raid_config': {}, 'target_raid_config': {}, 'instance_info': {'image_source': '0ae62a29-2d4f-48c5-9ef0-835627cb81c5', 'root_gb': '7', 'swap_mb': '0', 'display_name': 'tempest-BaremetalBasicOps-server-220936478', 'vcpus': '2', 'nova_host_id': 'ci-testing-1', 'memory_mb': '3072', 'local_gb': '0', 'capabilities': '{"boot_mode": "uefi"}', 'traits': ['CUSTOM_GOLD'], 'configdrive': '******'}, 'properties': {'cpu_arch': 'x86_64', 'capabilities': 'boot_mode:uefi'}, 'reservation': 'ci-testing-1', 'conductor_affinity': 1, 'conductor_group': '', 'power_state': 'power on', 'target_power_state': None, 'provision_state': 'wait call-back', 'provision_updated_at': '2020-05-25T12:48:11.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': None, 'extra': {}, 'automated_clean': None, 'protected': False, 'protected_reason': None, 'allocation_id': None, 'bios_interface': 'no-bios', 'boot_interface': 'pxe', 'console_interface': 'no-console', 'deploy_interface': 'iscsi', 'inspect_interface': 'no-inspect', 'management_interface': 'ipmitool', 'network_interface': 'flat', 'power_interface': 'ipmitool', 'raid_interface': 'no-raid', 'rescue_interface': 'no-rescue', 'storage_interface': 'noop', 'vendor_interface': 'ipmitool', 'traits': {'objects': [{'node_id': '1', 'trait': 'CUSTOM_GOLD', 'created_at': '2020-05-25T12:30:45.000000', 'updated_at': None}]}, 'owner': None, 'lessee': None, 'description': None, 'retired': False, 'retired_reason': None, 'network_data': {}, 'created_at': '2020-05-25T12:30:44.000000', 'updated_at': '2020-05-25T12:53:07.664714'}, ports: [{'id': 1, 'uuid': '8600ce7f-3476-4fd4-95a9-a95246775137', 'node_id': 1, 'address': '52:54:00:03:7e:e8', 'extra': {}, 'local_link_connection': {}, 'portgroup_id': None, 'pxe_enabled': True, 'internal_info': {'tenant_vif_port_id': 'b31cff80-33aa-4edc-ad99-d068b9292785'}, 'physical_network': None, 'is_smartnic': False, 'created_at': '2020-05-25T12:30:50.000000', 'updated_at': '2020-05-25T12:47:26.000000'}] get_deploy_steps /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/deploy.py:34[00m | |
| [ 219.476373] ironic-python-agent[408]: 2020-05-25 12:53:06.745 408 DEBUG root [-] Deploy steps before deduplication: {'GenericHardwareManager': [{'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}}}]} get_deploy_steps /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/deploy.py:40[00m | |
| [ 219.484333] ironic-python-agent[408]: 2020-05-25 12:53:06.753 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iscsistart -f execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 219.632872] ironic-python-agent[408]: 2020-05-25 12:53:06.896 408 DEBUG oslo_concurrency.processutils [-] CMD "iscsistart -f" returned: 21 in 0.142s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 219.641363] ironic-python-agent[408]: 2020-05-25 12:53:06.910 408 DEBUG oslo_concurrency.processutils [-] 'iscsistart -f' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 219.649250] ironic-python-agent[408]: 2020-05-25 12:53:06.917 408 DEBUG root [-] No iscsi connection detected. Skipping iscsi. Error: Unexpected error while running command. | |
| [ 219.651465] ironic-python-agent[408]: Command: iscsistart -f | |
| [ 219.653460] ironic-python-agent[408]: Exit code: 21 | |
| [ 219.654290] ironic-python-agent[408]: Stdout: 'Could not get list of targets from firmware.\n' | |
| [ 219.655327] ironic-python-agent[408]: Stderr: '' _check_for_iscsi /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:126[00m | |
| [ 219.665578] ironic-python-agent[408]: 2020-05-25 12:53:06.929 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --assemble --scan --verbose execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 219.842625] ironic-python-agent[408]: 2020-05-25 12:53:07.107 408 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --assemble --scan --verbose" returned: 1 in 0.181s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 219.852314] ironic-python-agent[408]: 2020-05-25 12:53:07.120 408 DEBUG oslo_concurrency.processutils [-] 'mdadm --assemble --scan --verbose' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 219.858298] ironic-python-agent[408]: 2020-05-25 12:53:07.127 408 INFO root [-] No new RAID devices assembled during start-up[00m | |
| [ 219.868270] ironic-python-agent[408]: 2020-05-25 12:53:07.137 408 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1038[00m | |
| [ 219.875255] ironic-python-agent[408]: 2020-05-25 12:53:07.144 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 220.079710] ironic-python-agent[408]: 2020-05-25 12:53:07.344 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.199s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 220.088256] ironic-python-agent[408]: 2020-05-25 12:53:07.355 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 220.093328] ironic-python-agent[408]: 2020-05-25 12:53:07.360 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 220.099327] ironic-python-agent[408]: 2020-05-25 12:53:07.367 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 220.108307] ironic-python-agent[408]: 2020-05-25 12:53:07.377 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 220.261581] ironic-python-agent[408]: 2020-05-25 12:53:07.526 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.149s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 220.270298] ironic-python-agent[408]: 2020-05-25 12:53:07.539 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 220.277297] ironic-python-agent[408]: 2020-05-25 12:53:07.546 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 220.279411] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 220.284364] ironic-python-agent[408]: 2020-05-25 12:53:07.551 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 220.403269] ironic-python-agent[408]: 2020-05-25 12:53:07.659 408 INFO root [-] Picked root device /dev/sda for node 88833e40-aa2b-4b68-99fa-34c85532a049 based on root device hints None[00m | |
| [ 220.408473] ironic-python-agent[408]: 2020-05-25 12:53:07.669 408 DEBUG root [-] Returning deploy steps: defaultdict(<class 'list'>, {'GenericHardwareManager': [{'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}}}]}) get_deploy_steps /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/deploy.py:42[00m | |
| [ 220.484432] ironic-python-agent[408]: 2020-05-25 12:53:07.734 408 INFO root [-] Error formatting log line msg='Command *(name)s completed: *(result)s' err=TypeError('first argument must be callable or None',)[00m | |
| [ 220.510436] ironic-python-agent[408]: 2020-05-25 12:53:07.778 408 INFO eventlet.wsgi.server [-] ::ffff:172.24.5.1 "POST /v1/commands/?wait=true&agent_token=SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM HTTP/1.1" status: 200 len: 3771 time: 1.1215458[00m | |
| [ 220.578440] ironic-python-agent[408]: 2020-05-25 12:53:07.846 408 DEBUG root [-] Executing command: iscsi.start_iscsi_target with args: {'iqn': 'iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049', 'portal_port': 3260, 'wipe_disk_metadata': True} execute_command /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/base.py:243[00m | |
| [ 220.587368] ironic-python-agent[408]: 2020-05-25 12:53:07.855 408 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1038[00m | |
| [ 220.595376] ironic-python-agent[408]: 2020-05-25 12:53:07.864 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 220.811369] ironic-python-agent[408]: 2020-05-25 12:53:08.074 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.210s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 220.818719] ironic-python-agent[408]: 2020-05-25 12:53:08.083 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 220.825324] ironic-python-agent[408]: 2020-05-25 12:53:08.092 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 220.833368] ironic-python-agent[408]: 2020-05-25 12:53:08.097 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 220.843341] ironic-python-agent[408]: 2020-05-25 12:53:08.112 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 220.992583] ironic-python-agent[408]: 2020-05-25 12:53:08.255 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.143s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 220.999364] ironic-python-agent[408]: 2020-05-25 12:53:08.263 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 221.006363] ironic-python-agent[408]: 2020-05-25 12:53:08.271 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 221.008476] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 221.016362] ironic-python-agent[408]: 2020-05-25 12:53:08.278 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 221.124773] ironic-python-agent[408]: 2020-05-25 12:53:08.388 408 INFO root [-] Picked root device /dev/sda for node 88833e40-aa2b-4b68-99fa-34c85532a049 based on root device hints None[00m | |
| [ 221.131381] ironic-python-agent[408]: 2020-05-25 12:53:08.396 408 DEBUG ironic_lib.disk_utils [-] Start destroy disk metadata for node 88833e40-aa2b-4b68-99fa-34c85532a049. destroy_disk_metadata /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/disk_utils.py:441[00m | |
| [ 221.145340] ironic-python-agent[408]: 2020-05-25 12:53:08.414 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): wipefs --force --all /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 221.328802] ironic-python-agent[408]: 2020-05-25 12:53:08.587 408 DEBUG oslo_concurrency.processutils [-] CMD "wipefs --force --all /dev/sda" returned: 0 in 0.171s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 221.342378] ironic-python-agent[408]: 2020-05-25 12:53:08.610 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "wipefs --force --all /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 221.351069] ironic-python-agent[408]: 2020-05-25 12:53:08.618 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 221.362927] ironic-python-agent[408]: 2020-05-25 12:53:08.628 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 221.381721] ironic-python-agent[408]: 2020-05-25 12:53:08.645 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): blockdev --getsz /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 221.617385] ironic-python-agent[408]: 2020-05-25 12:53:08.859 408 DEBUG oslo_concurrency.processutils [-] CMD "blockdev --getsz /dev/sda" returned: 0 in 0.213s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 221.624970] ironic-python-agent[408]: 2020-05-25 12:53:08.886 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "blockdev --getsz /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 221.638216] ironic-python-agent[408]: 2020-05-25 12:53:08.903 408 DEBUG ironic_lib.utils [-] Command stdout is: "16777216 | |
| [ 221.640469] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 221.653342] ironic-python-agent[408]: 2020-05-25 12:53:08.916 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 221.670916] ironic-python-agent[408]: 2020-05-25 12:53:08.937 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): dd bs=512 if=/dev/zero of=/dev/sda count=33 execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 221.880953] ironic-python-agent[408]: 2020-05-25 12:53:09.146 408 DEBUG oslo_concurrency.processutils [-] CMD "dd bs=512 if=/dev/zero of=/dev/sda count=33" returned: 0 in 0.209s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 221.890741] ironic-python-agent[408]: 2020-05-25 12:53:09.157 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "dd bs=512 if=/dev/zero of=/dev/sda count=33" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 221.897799] ironic-python-agent[408]: 2020-05-25 12:53:09.165 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 221.904506] ironic-python-agent[408]: 2020-05-25 12:53:09.172 408 DEBUG ironic_lib.utils [-] Command stderr is: "33+0 records in | |
| [ 221.905829] ironic-python-agent[408]: 33+0 records out | |
| [ 221.906783] ironic-python-agent[408]: 16896 bytes (17 kB, 16 KiB) copied, 0.0204556 s, 826 kB/s | |
| [ 221.907873] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 221.930977] ironic-python-agent[408]: 2020-05-25 12:53:09.197 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): dd bs=512 if=/dev/zero of=/dev/sda count=33 seek=16777183 execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 222.077648] ironic-python-agent[408]: 2020-05-25 12:53:09.342 408 DEBUG oslo_concurrency.processutils [-] CMD "dd bs=512 if=/dev/zero of=/dev/sda count=33 seek=16777183" returned: 0 in 0.144s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 222.087342] ironic-python-agent[408]: 2020-05-25 12:53:09.348 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "dd bs=512 if=/dev/zero of=/dev/sda count=33 seek=16777183" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 222.094892] ironic-python-agent[408]: 2020-05-25 12:53:09.363 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 222.100385] ironic-python-agent[408]: 2020-05-25 12:53:09.370 408 DEBUG ironic_lib.utils [-] Command stderr is: "33+0 records in | |
| [ 222.101578] ironic-python-agent[408]: 33+0 records out | |
| [ 222.102384] ironic-python-agent[408]: 16896 bytes (17 kB, 16 KiB) copied, 0.015061 s, 1.1 MB/s | |
| [ 222.103212] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 222.113526] ironic-python-agent[408]: 2020-05-25 12:53:09.382 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): sgdisk -Z /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 223.482613] ironic-python-agent[408]: 2020-05-25 12:53:10.734 408 DEBUG oslo_concurrency.processutils [-] CMD "sgdisk -Z /dev/sda" returned: 0 in 1.351s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 223.497532] ironic-python-agent[408]: 2020-05-25 12:53:10.760 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "sgdisk -Z /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 223.510320] ironic-python-agent[408]: 2020-05-25 12:53:10.774 408 DEBUG ironic_lib.utils [-] Command stdout is: "Creating new GPT entries. | |
| [ 223.512252] ironic-python-agent[408]: GPT data structures destroyed! You may now partition the disk using fdisk or | |
| [ 223.514295] ironic-python-agent[408]: other utilities. | |
| [ 223.515718] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 223.528848] ironic-python-agent[408]: 2020-05-25 12:53:10.791 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 223.621940] ironic-python-agent[408]: 2020-05-25 12:53:10.885 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): fuser /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 224.044550] ironic-python-agent[408]: 2020-05-25 12:53:11.307 408 DEBUG oslo_concurrency.processutils [-] CMD "fuser /dev/sda" returned: 0 in 0.421s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 224.055666] ironic-python-agent[408]: 2020-05-25 12:53:11.323 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "fuser /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 224.063965] ironic-python-agent[408]: 2020-05-25 12:53:11.330 408 DEBUG ironic_lib.utils [-] Command stdout is: " 583" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 224.071793] ironic-python-agent[408]: 2020-05-25 12:53:11.339 408 DEBUG ironic_lib.utils [-] Command stderr is: "/dev/sda: | |
| [ 224.073225] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 224.619017] ironic-python-agent[408]: 2020-05-25 12:53:11.870 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): fuser /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 224.906025] ironic-python-agent[408]: 2020-05-25 12:53:12.156 408 DEBUG oslo_concurrency.processutils [-] CMD "fuser /dev/sda" returned: 0 in 0.287s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 224.918520] ironic-python-agent[408]: 2020-05-25 12:53:12.179 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "fuser /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 224.922779] ironic-python-agent[408]: 2020-05-25 12:53:12.188 408 DEBUG ironic_lib.utils [-] Command stdout is: " 583" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 224.931458] ironic-python-agent[408]: 2020-05-25 12:53:12.199 408 DEBUG ironic_lib.utils [-] Command stderr is: "/dev/sda: | |
| [ 224.936363] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 225.602588] ironic-python-agent[408]: 2020-05-25 12:53:12.869 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): fuser /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 225.819872] ironic-python-agent[408]: 2020-05-25 12:53:13.075 408 DEBUG oslo_concurrency.processutils [-] CMD "fuser /dev/sda" returned: 1 in 0.205s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 225.827928] ironic-python-agent[408]: 2020-05-25 12:53:13.086 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "fuser /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 225.838483] ironic-python-agent[408]: 2020-05-25 12:53:13.093 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 225.847436] ironic-python-agent[408]: 2020-05-25 12:53:13.108 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 225.859544] ironic-python-agent[408]: 2020-05-25 12:53:13.127 408 INFO ironic_lib.disk_utils [-] Disk metadata on /dev/sda successfully destroyed for node 88833e40-aa2b-4b68-99fa-34c85532a049[00m | |
| [ 225.871393] ironic-python-agent[408]: 2020-05-25 12:53:13.137 408 DEBUG ironic_python_agent.extensions.iscsi [-] Starting ISCSI target with iqn iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049 on device /dev/sda start_iscsi_target /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/iscsi.py:196[00m | |
| [ 226.217816] Rounding down aligned max_sectors from 4294967295 to 4294967288 | |
| [ 226.865271] Rounding down aligned max_sectors from 65535 to 65528 | |
| [ 227.523766] ironic-python-agent[408]: 2020-05-25 12:53:14.777 408 DEBUG ironic_python_agent.extensions.iscsi [-] Linux-IO configuration: {'storage_objects': [{'attributes': {'block_size': 512, 'emulate_3pc': 1, 'emulate_caw': 1, 'emulate_dpo': 1, 'emulate_fua_read': 1, 'emulate_fua_write': 1, 'emulate_model_alias': 0, 'emulate_pr': 1, 'emulate_rest_reord': 0, 'emulate_tas': 1, 'emulate_tpu': 0, 'emulate_tpws': 0, 'emulate_ua_intlck_ctrl': 0, 'emulate_write_cache': 0, 'enforce_pr_isids': 1, 'force_pr_aptpl': 0, 'is_nonrot': 0, 'max_unmap_block_desc_count': 1, 'max_unmap_lba_count': 4194240, 'max_write_same_len': 65535, 'optimal_sectors': 65528, 'pi_prot_format': 0, 'pi_prot_type': 0, 'pi_prot_verify': 0, 'queue_depth': 2, 'unmap_granularity': 1, 'unmap_granularity_alignment': 0, 'unmap_zeroes_data': 0}, 'name': 'iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049', 'plugin': 'block', 'alua_tpgs': [{'name': 'default_tg_pt_gp', 'tg_pt_gp_id': 0, 'alua_access_state': 0, 'alua_access_status': 0, 'alua_write_metadata': 0, 'alua_access_type': 3, 'preferred': 0, 'nonop_delay_msecs': 100, 'trans_delay_msecs': 0, 'implicit_trans_secs': 0, 'alua_support_offline': 1, 'alua_support_standby': 1, 'alua_support_transitioning': 1, 'alua_support_active_nonoptimized': 1, 'alua_support_unavailable': 1, 'alua_support_active_optimized': 1}], 'write_back': False, 'readonly': False, 'wwn': '46db06ab-99b5-4231-9163-d6f0d06ce0ec', 'dev': '/dev/sda'}], 'targets': [{'wwn': 'iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049', 'fabric': 'iscsi', 'tpgs': [{'attributes': {'authentication': 0, 'cache_dynamic_acls': 1, 'default_cmdsn_depth': 64, 'default_erl': 0, 'demo_mode_discovery': 1, 'demo_mode_write_protect': 0, 'fabric_prot_type': 0, 'generate_node_acls': 1, 'login_keys_workaround': 1, 'login_timeout': 15, 'netif_timeout': 2, 'prod_mode_write_protect': 0, 't10_pi': 0, 'tpg_enabled_sendtargets': 1}, 'parameters': {'AuthMethod': 'CHAP,None', 'DataDigest': 'CRC32C,None', 'DataPDUInOrder': 'Yes', 'DataSequenceInOrder': 'Yes', 'DefaultTime2Retain': '20', 'DefaultTime2Wait': '2', 'ErrorRecoveryLevel': '0', 'FirstBurstLength': '65536', 'HeaderDigest': 'CRC32C,None', 'IFMarkInt': 'Reject', 'IFMarker': 'No', 'ImmediateData': 'Yes', 'InitialR2T': 'Yes', 'MaxBurstLength': '262144', 'MaxConnections': '1', 'MaxOutstandingR2T': '1', 'MaxRecvDataSegmentLength': '8192', 'MaxXmitDataSegmentLength': '262144', 'OFMarkInt': 'Reject', 'OFMarker': 'No', 'TargetAlias': 'LIO Target'}, 'tag': 1, 'enable': True, 'luns': [{'storage_object': '/backstores/block/iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049', 'index': 1, 'alias': '1047fbd5c1', 'alua_tg_pt_gp_name': 'default_tg_pt_gp'}], 'portals': [{'port': 3260, 'ip_address': '[::]', 'iser': False, 'offload': False}], 'node_acls': []}]}], 'fabric_modules': []} start_iscsi_target /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/iscsi.py:212[00m | |
| [ 227.533890] ironic-python-agent[408]: 2020-05-25 12:53:14.791 408 INFO ironic_python_agent.extensions.iscsi [-] Created iSCSI target with iqn iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049, portal port 3260, on device /dev/sda using linux-io[00m | |
| [ 227.543297] ironic-python-agent[408]: 2020-05-25 12:53:14.809 408 INFO root [-] Command iscsi.start_iscsi_target completed: Command name: start_iscsi_target, params: {'iqn': 'iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049', 'portal_port': 3260, 'wipe_disk_metadata': True}, status: SUCCEEDED, result: {'iscsi_target_iqn': 'iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049'}.[00m | |
| [ 227.561411] ironic-python-agent[408]: 2020-05-25 12:53:14.830 408 INFO eventlet.wsgi.server [-] ::ffff:172.24.5.1 "POST /v1/commands/?wait=true&agent_token=SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM HTTP/1.1" status: 200 len: 519 time: 6.9915295[00m | |
| [ 229.260515] iSCSI/iqn.1993-08.org.debian:01:7f571ae31d88: Unsupported SCSI Opcode 0xa3, sending CHECK_CONDITION. | |
| [ 231.013042] hrtimer: interrupt took 4662210 ns | |
| [ 251.784931] ironic-python-agent[408]: 2020-05-25 12:53:39.022 408 DEBUG root [-] Executing command: image.install_bootloader with args: {'root_uuid': None, 'efi_system_part_uuid': None, 'prep_boot_part_uuid': None, 'target_boot_mode': 'uefi'} execute_command /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/base.py:243[00m | |
| [ 251.798449] ironic-python-agent[408]: 2020-05-25 12:53:39.034 408 DEBUG root [-] Looking for a device matching root hints None get_os_install_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1038[00m | |
| [ 251.800685] ironic-python-agent[408]: 2020-05-25 12:53:39.046 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 252.040532] ironic-python-agent[408]: 2020-05-25 12:53:39.303 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.256s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 252.047158] ironic-python-agent[408]: 2020-05-25 12:53:39.310 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 252.051359] ironic-python-agent[408]: 2020-05-25 12:53:39.316 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 252.057361] ironic-python-agent[408]: 2020-05-25 12:53:39.323 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 252.066282] ironic-python-agent[408]: 2020-05-25 12:53:39.335 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 252.233588] ironic-python-agent[408]: 2020-05-25 12:53:39.498 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" returned: 0 in 0.163s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 252.242335] ironic-python-agent[408]: 2020-05-25 12:53:39.510 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk -Pbia -oKNAME,MODEL,SIZE,ROTA,TYPE" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 252.248284] ironic-python-agent[408]: 2020-05-25 12:53:39.514 408 DEBUG ironic_lib.utils [-] Command stdout is: "KNAME="sda" MODEL="QEMU HARDDISK " SIZE="8589934592" ROTA="1" TYPE="disk" | |
| [ 252.250561] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 252.256349] ironic-python-agent[408]: 2020-05-25 12:53:39.522 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 252.366772] ironic-python-agent[408]: 2020-05-25 12:53:39.633 408 INFO root [-] Picked root device /dev/sda for node 88833e40-aa2b-4b68-99fa-34c85532a049 based on root device hints None[00m | |
| [ 252.388326] ironic-python-agent[408]: 2020-05-25 12:53:39.657 408 INFO ironic_python_agent.extensions.iscsi [-] Deleting iSCSI target iqn.2008-10.org.openstack:88833e40-aa2b-4b68-99fa-34c85532a049 for device /dev/sda.[00m | |
| [ 252.456339] ironic-python-agent[408]: 2020-05-25 12:53:39.724 408 DEBUG root [-] The current boot mode is uefi get_boot_info /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:1083[00m | |
| [ 252.465265] ironic-python-agent[408]: 2020-05-25 12:53:39.734 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mdadm --detail /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 252.587563] ironic-python-agent[408]: 2020-05-25 12:53:39.852 408 DEBUG oslo_concurrency.processutils [-] CMD "mdadm --detail /dev/sda" returned: 1 in 0.117s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 252.595319] ironic-python-agent[408]: 2020-05-25 12:53:39.863 408 DEBUG oslo_concurrency.processutils [-] 'mdadm --detail /dev/sda' failed. Not Retrying. execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:457[00m | |
| [ 252.603281] ironic-python-agent[408]: 2020-05-25 12:53:39.871 408 DEBUG root [-] /dev/sda is not an md device is_md_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/hardware.py:240[00m | |
| [ 252.610279] ironic-python-agent[408]: 2020-05-25 12:53:39.879 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): efibootmgr --version execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 252.724548] ironic-python-agent[408]: 2020-05-25 12:53:39.989 408 DEBUG oslo_concurrency.processutils [-] CMD "efibootmgr --version" returned: 0 in 0.109s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 252.733359] ironic-python-agent[408]: 2020-05-25 12:53:40.000 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "efibootmgr --version" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 252.738318] ironic-python-agent[408]: 2020-05-25 12:53:40.005 408 DEBUG ironic_lib.utils [-] Command stdout is: "version 16 | |
| [ 252.740519] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 252.745347] ironic-python-agent[408]: 2020-05-25 12:53:40.012 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 252.753252] ironic-python-agent[408]: 2020-05-25 12:53:40.022 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): partx -u /dev/sda execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 252.917877] ironic-python-agent[408]: 2020-05-25 12:53:40.169 408 DEBUG oslo_concurrency.processutils [-] CMD "partx -u /dev/sda" returned: 0 in 0.146s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 252.931331] ironic-python-agent[408]: 2020-05-25 12:53:40.183 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "partx -u /dev/sda" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 252.945497] ironic-python-agent[408]: 2020-05-25 12:53:40.201 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 252.972554] ironic-python-agent[408]: 2020-05-25 12:53:40.219 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 252.996837] ironic-python-agent[408]: 2020-05-25 12:53:40.250 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): udevadm settle execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 253.360775] ironic-python-agent[408]: 2020-05-25 12:53:40.616 408 DEBUG oslo_concurrency.processutils [-] CMD "udevadm settle" returned: 0 in 0.365s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 253.375365] ironic-python-agent[408]: 2020-05-25 12:53:40.638 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "udevadm settle" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 253.383552] ironic-python-agent[408]: 2020-05-25 12:53:40.647 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 253.391497] ironic-python-agent[408]: 2020-05-25 12:53:40.655 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 253.422566] ironic-python-agent[408]: 2020-05-25 12:53:40.688 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted -s /dev/sda -- print execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 254.589935] ironic-python-agent[408]: 2020-05-25 12:53:41.779 408 DEBUG oslo_concurrency.processutils [-] CMD "parted -s /dev/sda -- print" returned: 0 in 1.091s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 254.594246] ironic-python-agent[408]: 2020-05-25 12:53:41.793 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "parted -s /dev/sda -- print" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 254.600779] sda: sda1 sda2 sda15 | |
| [ 254.604465] ironic-python-agent[408]: 2020-05-25 12:53:41.802 408 DEBUG ironic_lib.utils [-] Command stdout is: "Model: ATA QEMU HARDDISK (scsi) | |
| [ 254.606677] ironic-python-agent[408]: Disk /dev/sda: 8590MB | |
| [ 254.608657] ironic-python-agent[408]: Sector size (logical/physical): 512B/512B | |
| [ 254.628638] ironic-python-agent[408]: Partition Table: gpt | |
| [ 254.630975] ironic-python-agent[408]: Disk Flags: | |
| [ 254.634334] ironic-python-agent[408]: Number Start End Size File system Name Flags | |
| [ 254.644482] ironic-python-agent[408]: 15 1049kB 9437kB 8389kB boot, esp | |
| [ 254.645986] ironic-python-agent[408]: 1 9437kB 117MB 108MB ext3 | |
| [ 254.648456] ironic-python-agent[408]: 2 8522MB 8590MB 68.1MB | |
| [ 254.650874] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 254.660339] ironic-python-agent[408]: 2020-05-25 12:53:41.806 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 254.665542] ironic-python-agent[408]: 2020-05-25 12:53:41.815 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): parted -s -m /dev/sda unit MiB print execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 255.347646] ironic-python-agent[408]: 2020-05-25 12:53:42.607 408 DEBUG oslo_concurrency.processutils [-] CMD "parted -s -m /dev/sda unit MiB print" returned: 0 in 0.791s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 255.351817] ironic-python-agent[408]: 2020-05-25 12:53:42.616 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "parted -s -m /dev/sda unit MiB print" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 255.357810] ironic-python-agent[408]: 2020-05-25 12:53:42.625 408 DEBUG ironic_lib.utils [-] Command stdout is: "BYT; | |
| [ 255.360002] ironic-python-agent[408]: /dev/sda:8192MiB:scsi:512:512:gpt:ATA QEMU HARDDISK:; | |
| [ 255.362048] ironic-python-agent[408]: 15:1.00MiB:9.00MiB:8.00MiB:::boot, esp; | |
| [ 255.375751] ironic-python-agent[408]: 1:9.00MiB:112MiB:103MiB:ext3::; | |
| [ 255.388370] ironic-python-agent[408]: 2:8127MiB:8192MiB:65.0MiB:::; | |
| [ 255.391018] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 255.407061] ironic-python-agent[408]: 2020-05-25 12:53:42.631 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 255.410580] ironic-python-agent[408]: 2020-05-25 12:53:42.644 408 DEBUG ironic_python_agent.utils [-] Found EFI partition {'number': 15, 'start': 1, 'end': 9, 'size': 8, 'filesystem': '', 'partition_name': '', 'flags': 'boot, esp'} on device /dev/sda. get_efi_part_on_device /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/utils.py:625[00m | |
| [ 255.412897] ironic-python-agent[408]: 2020-05-25 12:53:42.656 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): mount /dev/sda15 /tmp/tmp8nvqh2mr/boot/efi execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 256.107339] ironic-python-agent[408]: 2020-05-25 12:53:43.350 408 DEBUG oslo_concurrency.processutils [-] CMD "mount /dev/sda15 /tmp/tmp8nvqh2mr/boot/efi" returned: 0 in 0.693s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 256.115381] ironic-python-agent[408]: 2020-05-25 12:53:43.372 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "mount /dev/sda15 /tmp/tmp8nvqh2mr/boot/efi" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 256.126507] ironic-python-agent[408]: 2020-05-25 12:53:43.391 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 256.137009] ironic-python-agent[408]: 2020-05-25 12:53:43.403 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 256.160277] ironic-python-agent[408]: 2020-05-25 12:53:43.419 408 DEBUG ironic_python_agent.extensions.image [-] Looking for all efi files on /tmp/tmp8nvqh2mr/boot/efi _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:212[00m | |
| [ 256.177900] ironic-python-agent[408]: 2020-05-25 12:53:43.444 408 DEBUG ironic_python_agent.extensions.image [-] efi files found in /tmp/tmp8nvqh2mr/boot/efi : [] _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:217[00m | |
| [ 256.240881] ironic-python-agent[408]: 2020-05-25 12:53:43.505 408 DEBUG ironic_python_agent.extensions.image [-] efi files found in /tmp/tmp8nvqh2mr/boot/efi : [] _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:217[00m | |
| [ 256.292563] ironic-python-agent[408]: 2020-05-25 12:53:43.556 408 DEBUG ironic_python_agent.extensions.image [-] efi files found in /tmp/tmp8nvqh2mr/boot/efi : ['bootx64.efi'] _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:217[00m | |
| [ 256.302443] ironic-python-agent[408]: 2020-05-25 12:53:43.566 408 DEBUG ironic_python_agent.extensions.image [-] Checking if /tmp/tmp8nvqh2mr/boot/efi/EFI/BOOT/bootx64.efi is executable _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:220[00m | |
| [ 256.314440] ironic-python-agent[408]: 2020-05-25 12:53:43.580 408 DEBUG ironic_python_agent.extensions.image [-] \EFI\BOOT\bootx64.efi is a valid bootloader _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:223[00m | |
| [ 256.375686] ironic-python-agent[408]: 2020-05-25 12:53:43.641 408 DEBUG ironic_python_agent.extensions.image [-] efi files found in /tmp/tmp8nvqh2mr/boot/efi : [] _get_efi_bootloaders /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:217[00m | |
| [ 256.385481] ironic-python-agent[408]: 2020-05-25 12:53:43.651 408 DEBUG ironic_python_agent.extensions.image [-] Getting information about boot order _run_efibootmgr /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:237[00m | |
| [ 256.399456] ironic-python-agent[408]: 2020-05-25 12:53:43.665 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): efibootmgr execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 256.680549] ironic-python-agent[408]: 2020-05-25 12:53:43.937 408 DEBUG oslo_concurrency.processutils [-] CMD "efibootmgr" returned: 0 in 0.270s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 256.692450] ironic-python-agent[408]: 2020-05-25 12:53:43.956 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "efibootmgr" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 256.700490] ironic-python-agent[408]: 2020-05-25 12:53:43.964 408 DEBUG ironic_lib.utils [-] Command stdout is: "BootCurrent: 0003 | |
| [ 256.702797] ironic-python-agent[408]: Timeout: 0 seconds | |
| [ 256.704917] ironic-python-agent[408]: BootOrder: 0003,0004 | |
| [ 256.716458] ironic-python-agent[408]: Boot0003* EFI Network | |
| [ 256.718793] ironic-python-agent[408]: Boot0004* EFI Internal Shell | |
| [ 256.720918] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 256.742540] ironic-python-agent[408]: 2020-05-25 12:53:43.972 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 256.766746] ironic-python-agent[408]: 2020-05-25 12:53:44.010 408 DEBUG ironic_python_agent.extensions.image [-] Adding loader \EFI\BOOT\bootx64.efi on partition 15 of device /dev/sda _run_efibootmgr /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/image.py:251[00m | |
| [ 256.781920] ironic-python-agent[408]: 2020-05-25 12:53:44.014 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): efibootmgr -c -d /dev/sda -p 15 -w -L ironic1 -l \EFI\BOOT\bootx64.efi execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 257.179351] ironic-python-agent[408]: 2020-05-25 12:53:44.428 408 DEBUG oslo_concurrency.processutils [-] CMD "efibootmgr -c -d /dev/sda -p 15 -w -L ironic1 -l \EFI\BOOT\bootx64.efi" returned: 0 in 0.412s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 257.185622] ironic-python-agent[408]: 2020-05-25 12:53:44.442 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "efibootmgr -c -d /dev/sda -p 15 -w -L ironic1 -l \EFI\BOOT\bootx64.efi" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 257.197304] ironic-python-agent[408]: 2020-05-25 12:53:44.462 408 DEBUG ironic_lib.utils [-] Command stdout is: "BootCurrent: 0003 | |
| [ 257.199428] ironic-python-agent[408]: Timeout: 0 seconds | |
| [ 257.201415] ironic-python-agent[408]: BootOrder: 0000,0003,0004 | |
| [ 257.211555] ironic-python-agent[408]: Boot0003* EFI Network | |
| [ 257.213688] ironic-python-agent[408]: Boot0004* EFI Internal Shell | |
| [ 257.215709] ironic-python-agent[408]: Boot0000* ironic1 | |
| [ 257.217678] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 257.229351] ironic-python-agent[408]: 2020-05-25 12:53:44.476 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 257.231612] ironic-python-agent[408]: 2020-05-25 12:53:44.494 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): umount /tmp/tmp8nvqh2mr/boot/efi execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 257.463041] ironic-python-agent[408]: 2020-05-25 12:53:44.729 408 DEBUG oslo_concurrency.processutils [-] CMD "umount /tmp/tmp8nvqh2mr/boot/efi" returned: 0 in 0.235s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 257.472406] ironic-python-agent[408]: 2020-05-25 12:53:44.740 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "umount /tmp/tmp8nvqh2mr/boot/efi" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 257.478329] ironic-python-agent[408]: 2020-05-25 12:53:44.746 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 257.484346] ironic-python-agent[408]: 2020-05-25 12:53:44.752 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 257.491309] ironic-python-agent[408]: 2020-05-25 12:53:44.760 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): sync execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 257.598648] ironic-python-agent[408]: 2020-05-25 12:53:44.864 408 DEBUG oslo_concurrency.processutils [-] CMD "sync" returned: 0 in 0.104s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 257.607295] ironic-python-agent[408]: 2020-05-25 12:53:44.875 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "sync" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 257.613284] ironic-python-agent[408]: 2020-05-25 12:53:44.879 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 257.620180] ironic-python-agent[408]: 2020-05-25 12:53:44.885 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 257.633336] ironic-python-agent[408]: 2020-05-25 12:53:44.900 408 INFO root [-] Command image.install_bootloader completed: Command name: install_bootloader, params: {'root_uuid': None, 'efi_system_part_uuid': None, 'prep_boot_part_uuid': None, 'target_boot_mode': 'uefi'}, status: SUCCEEDED, result: None.[00m | |
| [ 257.652775] ironic-python-agent[408]: 2020-05-25 12:53:44.920 408 INFO eventlet.wsgi.server [-] ::ffff:172.24.5.1 "POST /v1/commands/?wait=true&agent_token=SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM HTTP/1.1" status: 200 len: 421 time: 5.9107163[00m | |
| [ 272.920497] ironic-python-agent[408]: 2020-05-25 12:54:00.175 408 DEBUG eventlet.wsgi.server [-] (408) accepted ('::ffff:172.24.5.1', 46700, 0, 0) server /opt/ironic-python-agent/lib64/python3.6/site-packages/eventlet/wsgi.py:985[00m | |
| [ 272.953438] ironic-python-agent[408]: 2020-05-25 12:54:00.221 408 DEBUG root [-] Executing command: log.collect_system_logs with args: {} execute_command /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/base.py:243[00m | |
| [ 272.960370] ironic-python-agent[408]: 2020-05-25 12:54:00.229 408 INFO ironic_python_agent.utils [-] Collecting system logs and debugging information[00m | |
| [ 273.134578] ironic-python-agent[408]: 2020-05-25 12:54:00.399 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): journalctl --full --no-pager -b execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 273.685645] ironic-python-agent[408]: 2020-05-25 12:54:00.951 408 DEBUG oslo_concurrency.processutils [-] CMD "journalctl --full --no-pager -b" returned: 0 in 0.552s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 273.694319] ironic-python-agent[408]: 2020-05-25 12:54:00.962 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "journalctl --full --no-pager -b" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 273.726392] ironic-python-agent[408]: 2020-05-25 12:54:00.966 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 273.734301] ironic-python-agent[408]: 2020-05-25 12:54:01.002 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ps au execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 273.985322] ironic-python-agent[408]: 2020-05-25 12:54:01.240 408 DEBUG oslo_concurrency.processutils [-] CMD "ps au" returned: 0 in 0.237s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 273.991646] ironic-python-agent[408]: 2020-05-25 12:54:01.249 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ps au" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 273.999379] ironic-python-agent[408]: 2020-05-25 12:54:01.257 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 274.009350] ironic-python-agent[408]: 2020-05-25 12:54:01.278 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): df -a execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 274.148623] systemd[1]: proc-sys-fs-binfmt_misc.automount: Got automount request for /proc/sys/fs/binfmt_misc, triggered by 636 (df) | |
| [ 274.223927] systemd[1]: Mounting Arbitrary Executable File Formats File System... | |
| [ 274.456307] systemd[1]: Mounted Arbitrary Executable File Formats File System. | |
| [ 274.517453] ironic-python-agent[408]: 2020-05-25 12:54:01.776 408 DEBUG oslo_concurrency.processutils [-] CMD "df -a" returned: 0 in 0.492s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 274.531835] ironic-python-agent[408]: 2020-05-25 12:54:01.798 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "df -a" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 274.543992] ironic-python-agent[408]: 2020-05-25 12:54:01.810 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 274.559356] ironic-python-agent[408]: 2020-05-25 12:54:01.825 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): iptables -L execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 275.052542] ironic-python-agent[408]: 2020-05-25 12:54:02.311 408 DEBUG oslo_concurrency.processutils [-] CMD "iptables -L" returned: 0 in 0.486s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 275.066369] ironic-python-agent[408]: 2020-05-25 12:54:02.330 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "iptables -L" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 275.074910] ironic-python-agent[408]: 2020-05-25 12:54:02.334 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 275.086612] ironic-python-agent[408]: 2020-05-25 12:54:02.345 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): ip addr execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 275.215421] ironic-python-agent[408]: 2020-05-25 12:54:02.481 408 DEBUG oslo_concurrency.processutils [-] CMD "ip addr" returned: 0 in 0.134s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 275.224304] ironic-python-agent[408]: 2020-05-25 12:54:02.492 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "ip addr" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 275.237790] ironic-python-agent[408]: 2020-05-25 12:54:02.500 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 275.246521] ironic-python-agent[408]: 2020-05-25 12:54:02.515 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lshw -quiet -json execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 280.670474] ironic-python-agent[408]: 2020-05-25 12:54:07.922 408 DEBUG oslo_concurrency.processutils [-] CMD "lshw -quiet -json" returned: 0 in 5.406s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 280.682519] ironic-python-agent[408]: 2020-05-25 12:54:07.934 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lshw -quiet -json" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 280.689871] ironic-python-agent[408]: 2020-05-25 12:54:07.940 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 280.696964] ironic-python-agent[408]: 2020-05-25 12:54:07.956 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 280.893635] ironic-python-agent[408]: 2020-05-25 12:54:08.158 408 DEBUG oslo_concurrency.processutils [-] CMD "lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" returned: 0 in 0.203s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 280.908501] ironic-python-agent[408]: 2020-05-25 12:54:08.169 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "lsblk --all -oKNAME,MODEL,SIZE,ROTA,TYPE,UUID,PARTUUID" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 280.916623] ironic-python-agent[408]: 2020-05-25 12:54:08.182 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 280.924543] ironic-python-agent[408]: 2020-05-25 12:54:08.192 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): cat /proc/mdstat execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 281.050750] ironic-python-agent[408]: 2020-05-25 12:54:08.313 408 DEBUG oslo_concurrency.processutils [-] CMD "cat /proc/mdstat" returned: 0 in 0.119s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 281.061374] ironic-python-agent[408]: 2020-05-25 12:54:08.326 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "cat /proc/mdstat" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 281.067340] ironic-python-agent[408]: 2020-05-25 12:54:08.333 408 DEBUG ironic_lib.utils [-] Command stderr is: "b''" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 281.166532] ironic-python-agent[408]: 2020-05-25 12:54:08.434 408 INFO root [-] Command log.collect_system_logs completed: Command name: collect_system_logs, params: {}, status: SUCCEEDED, result: {'system_logs': '<...>'}.[00m | |
| [ 281.190649] ironic-python-agent[408]: 2020-05-25 12:54:08.459 408 INFO eventlet.wsgi.server [-] ::ffff:172.24.5.1 "POST /v1/commands/?wait=true&agent_token=SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM HTTP/1.1" status: 200 len: 40695 time: 8.2475095[00m | |
| [ 281.227321] ironic-python-agent[408]: 2020-05-25 12:54:08.495 408 DEBUG root [-] Executing command: standby.power_off with args: {} execute_command /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/base.py:243[00m | |
| [ 281.242348] ironic-python-agent[408]: 2020-05-25 12:54:08.510 408 INFO ironic_python_agent.extensions.standby [-] Powering off system[00m | |
| [ 281.266682] ironic-python-agent[408]: 2020-05-25 12:54:08.529 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): hwclock -v --systohc execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 281.418375] ironic-python-agent[408]: 2020-05-25 12:54:08.672 408 INFO root [-] Command standby.power_off completed: Command name: power_off, params: {}, status: RUNNING, result: None.[00m | |
| [ 281.471360] ironic-python-agent[408]: 2020-05-25 12:54:08.734 408 INFO eventlet.wsgi.server [-] ::ffff:172.24.5.1 "POST /v1/commands/?wait=false&agent_token=SWvD82uqGFKBIT8PCFQEQnMbkhIBL_zW01s0aklRtqM HTTP/1.1" status: 200 len: 306 time: 0.2438455[00m | |
| [ 282.431681] ironic-python-agent[408]: 2020-05-25 12:54:09.682 408 DEBUG oslo_concurrency.processutils [-] CMD "hwclock -v --systohc" returned: 0 in 1.152s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 282.439892] ironic-python-agent[408]: 2020-05-25 12:54:09.689 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "hwclock -v --systohc" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 282.447851] ironic-python-agent[408]: 2020-05-25 12:54:09.697 408 DEBUG ironic_lib.utils [-] Command stdout is: "hwclock from util-linux 2.32.1 | |
| [ 282.456681] ironic-python-agent[408]: System Time: 1590411248.682382 | |
| [ 282.458941] ironic-python-agent[408]: Trying to open: /dev/rtc0 | |
| [ 282.462557] ironic-python-agent[408]: Using the rtc interface to the clock. | |
| [ 282.464782] ironic-python-agent[408]: Last drift adjustment done at 0 seconds after 1969 | |
| [ 282.469656] ironic-python-agent[408]: Last calibration done at 0 seconds after 1969 | |
| [ 282.472022] ironic-python-agent[408]: Hardware clock is on unknown time | |
| [ 282.475856] ironic-python-agent[408]: Assuming hardware clock is kept in UTC time. | |
| [ 282.479810] ironic-python-agent[408]: missed it - 1590411248.727164 is too far past 1590411248.500000 (0.227164 > 0.001000) | |
| [ 282.482780] ironic-python-agent[408]: 1590411249.500794 is close enough to 1590411249.500000 (0.000794 < 0.002000) | |
| [ 282.486792] ironic-python-agent[408]: Set RTC to 1590411249 (1590411248 + 1; refsystime = 1590411248.000000) | |
| [ 282.490961] ironic-python-agent[408]: Setting Hardware Clock to 12:54:09 = 1590411249 seconds since 1969 | |
| [ 282.498670] ironic-python-agent[408]: ioctl(RTC_SET_TIME) was successful. | |
| [ 282.504659] ironic-python-agent[408]: Not adjusting drift factor because the --update-drift option was not used. | |
| [ 282.509679] ironic-python-agent[408]: New /etc/adjtime data: | |
| [ 282.511944] ironic-python-agent[408]: 0.000000 1590411248 0.000000 | |
| [ 282.512993] ironic-python-agent[408]: 1590411248 | |
| [ 282.517863] ironic-python-agent[408]: UTC | |
| [ 282.521585] ironic-python-agent[408]: " execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 282.525468] ironic-python-agent[408]: 2020-05-25 12:54:09.715 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 282.531569] ironic-python-agent[408]: 2020-05-25 12:54:09.768 408 DEBUG ironic_python_agent.extensions.standby [-] Flushing file system buffers sync /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_python_agent/extensions/standby.py:701[00m | |
| [ 282.635796] ironic-python-agent[408]: 2020-05-25 12:54:09.904 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): sync execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [ 283.126833] ironic-python-agent[408]: 2020-05-25 12:54:10.230 408 DEBUG oslo_concurrency.processutils [-] CMD "sync" returned: 0 in 0.325s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [ 283.160993] ironic-python-agent[408]: 2020-05-25 12:54:10.429 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "sync" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| [ 283.178399] ironic-python-agent[408]: 2020-05-25 12:54:10.445 408 DEBUG ironic_lib.utils [-] Command stdout is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:103[00m | |
| [ 283.365864] ironic-python-agent[408]: 2020-05-25 12:54:10.634 408 DEBUG ironic_lib.utils [-] Command stderr is: "" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:104[00m | |
| [ 283.691640] ironic-python-agent[408]: 2020-05-25 12:54:10.942 408 DEBUG oslo_concurrency.processutils [-] Running cmd (subprocess): poweroff execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:372[00m | |
| [[0;32m OK [0m] Stopped target Graphical Interface. | |
| [ 284.644046] systemd[1]: Stopped target Graphical Interface. | |
| [ 284.689381] systemd[1]: Stopping Restore /run/initramfs on shutdown... | |
| Stopping Restore /run/initramfs on shutdown... | |
| [[0;32m OK [0m] Stopped target Multi-User System. | |
| [ 284.716248] systemd[1]: Stopped target Multi-User System. | |
| [[0;32m OK [0m] Stopped target Login Prompts. | |
| [ 284.726800] systemd[1]: Stopped target Login Prompts. | |
| Stopping Serial Getty on ttyS0... | |
| [[ 284.757549] ironic-python-agent[408]: 2020-05-25 12:54:11.938 408 DEBUG oslo_concurrency.processutils [-] CMD "poweroff" returned: 0 in 1.244s execute /opt/ironic-python-agent/lib64/python3.6/site-packages/oslo_concurrency/processutils.py:409[00m | |
| [0;32m OK [0m] Reached target Unmount All Filesystems. | |
| Stopping Hardware RNG Entropy Gatherer Daemon... | |
| [ 284.790257] systemd[1]: Stopping Serial Getty on ttyS0... | |
| [ 284.804075] systemd[1]: Reached target Unmount All Filesystems. | |
| Stopping Command Scheduler... | |
| Stopping Ironic Python Agent... | |
| [ 284.852581] systemd[1]: Stopping Hardware RNG Entropy Gatherer Daemon... | |
| [ 284.862920] ironic-python-agent[408]: 2020-05-25 12:54:12.038 408 DEBUG ironic_lib.utils [-] Execution completed, command line is "poweroff" execute /opt/ironic-python-agent/lib64/python3.6/site-packages/ironic_lib/utils.py:101[00m | |
| Stopping Job spooling tools... | |
| Stopping NTP client/server... | |
| [[0;32m OK [0m] Stopped target Timers. | |
| [[0;32m OK [0m] Stopped Daily Cleanup of Temporary Directories. | |
| [ 284.947654] crond[335]: (CRON) INFO (Shutting down) | |
| [[0;32m OK [0m] Stopped dnf makecache --timer. | |
| Stopping Getty on tty1... | |
| [ 285.011689] systemd[1]: Stopping Command Scheduler... | |
| Stopping Login Service... | |
| [ 285.133713] chronyd[239]: chronyd exiting | |
| [ 285.154011] systemd[1]: Stopping Ironic Python Agent... | |
| [ 285.201455] systemd[1]: Stopping Job spooling tools... | |
| [ 285.215884] systemd[1]: Stopping NTP client/server... | |
| [ 285.249797] systemd[1]: Stopped target Timers. | |
| [ 285.278014] systemd[1]: Stopped Daily Cleanup of Temporary Directories. | |
| [ 285.303737] systemd[1]: Stopped dnf makecache --timer. | |
| [ 285.325783] systemd[1]: Stopping Getty on tty1... | |
| [ 285.352018] systemd[1]: Stopping Login Service... | |
| [ 285.570441] systemd[1]: Stopped Hardware RNG Entropy Gatherer Daemon. | |
| [[0;32m OK [0m] Stopped Hardware RNG Entropy Gatherer Daemon. | |
| [[0;32m OK [0m] Stopped Login Service. | |
| [ 285.759685] systemd[1]: Stopped Login Service. | |
| [[0;32m OK [0m] Stopped NTP client/server. | |
| [ 285.816183] systemd[1]: Stopped NTP client/server. | |
| [ 286.224471] systemd[1]: Stopped Serial Getty on ttyS0. | |
| [[0;32m OK [0m] Stopped Serial Getty on ttyS0. | |
| [ 286.557433] systemd[1]: Stopped Getty on tty1. | |
| [[0;32m OK [0m] Stopped Getty on tty1. | |
| [ 286.685407] systemd[1]: Stopped Command Scheduler. | |
| [[0;32m OK [0m] Stopped Command Scheduler. | |
| [ 286.802699] systemd[1]: Stopped Job spooling tools. | |
| [[0;32m OK [0m] Stopped Job spooling tools. | |
| [ 286.870919] systemd[1]: Stopped Ironic Python Agent. | |
| [[0;32m OK [0m] Stopped Ironic Python Agent. | |
| [ 286.929765] systemd[1]: Stopped Restore /run/initramfs on shutdown. | |
| [[0;32m OK [0m] Stopped Restore /run/initramfs on shutdown. | |
| [ 287.047023] systemd[1]: Stopped target Network is Online. | |
| [[0;32m OK [0m] Stopped target Network is Online. | |
| [ 287.058612] systemd[1]: Stopped DHCP interface ens1. | |
| [[0;32m OK [0m] Stopped DHCP interface ens1. | |
| [ 287.079877] systemd[1]: Removed slice system-dhcp\x2dinterface.slice. | |
| [[0;32m OK [0m] Removed slice system-dhcp\x2dinterface.slice. | |
| [[0;32m OK [0m] Stopped Initialize iBFT network interfaces. | |
| [ 287.095820] systemd[1]: Stopped Initialize iBFT network interfaces. | |
| [ 287.108442] systemd[1]: Removed slice system-getty.slice. | |
| [[0;32m OK [0m] Removed slice system-getty.slice. | |
| [ 287.130774] systemd[1]: Stopping Permit User Sessions... | |
| Stopping Permit User Sessions... | |
| [ 287.149464] systemd[1]: Removed slice system-serial\x2dgetty.slice. | |
| [[0;32m OK [0m] Removed slice system-serial\x2dgetty.slice. | |
| [ 287.311668] systemd[1]: Stopped Permit User Sessions. | |
| [[0;32m OK [0m] Stopped Permit User Sessions. | |
| [ 287.320962] systemd[1]: Stopped target Remote File Systems. | |
| [[0;32m OK [0m] Stopped target Remote File Systems. | |
| [ 287.329330] systemd[1]: Stopped target Remote File Systems (Pre). | |
| [[0;32m OK [0m] Stopped target Remote File Systems (Pre). | |
| [ 287.368597] systemd[1]: Stopping Logout off all iSCSI sessions on shutdown... | |
| Stopping Logout off all iSCSI sessions on shutdown... | |
| [ 287.567983] iscsiadm[674]: iscsiadm: No matching sessions found | |
| [ 287.602415] systemd[1]: Stopped Logout off all iSCSI sessions on shutdown. | |
| [[0;32m OK [0m] Stopped Logout off all iSCSI sessions on shutdown. | |
| [ 287.626791] systemd[1]: Stopped target Network. | |
| [[0;32m OK [0m] Stopped target Network. | |
| [ 287.654529] Stopping Network Manager... | |
| NetworkManager[237]: <info> [1590411254.9130] caught SIGTERM, shutting down normally. | |
| [ 287.682638] systemd[1]: Stopping Network Manager... | |
| [ 287.798322] NetworkManager[237]: <info> [1590411255.0688] dhcp4 (ens1): canceled DHCP transaction | |
| [ 287.831843] NetworkManager[237]: <info> [1590411255.0829] dhcp4 (ens1): state changed bound -> done | |
| [ 287.838519] NetworkManager[237]: <info> [1590411255.0852] device (ens1): DHCPv4: 480 seconds grace period started | |
| [ 287.855745] NetworkManager[237]: <info> [1590411255.1237] manager: NetworkManager state is now CONNECTED_SITE | |
| [ 287.878509] NetworkManager[237]: <info> [1590411255.1501] exiting (success) | |
| [ 287.924447] dbus-daemon[233]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.1' (uid=0 pid=237 comm="/usr/sbin/NetworkManager --no-daemon ") | |
| [ 287.955744] dbus-daemon[233]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.nm-dispatcher.service': Refusing activation, D-Bus is shutting down. | |
| [ 288.007768] systemd[1]: [[0;32m OK [0m] Stopped Network Manager. | |
| Stopped Network Manager. | |
| [ 288.069966] systemd[1]: Stopping D-Bus System Message Bus... | |
| Stopping D-Bus System Message Bus... | |
| [ 288.124513] systemd[1]: Stopped D-Bus System Message Bus. | |
| [[0;32m OK [0m] Stopped D-Bus System Message Bus. | |
| [ 288.158704] systemd[1]: Stopped target Basic System. | |
| [[0;32m OK [0m] Stopped target Basic System. | |
| [ 288.173673] systemd[1]: Stopped target Paths. | |
| [[0;32m OK [0m] Stopped target Paths. | |
| [[0;32m OK [0m] Stopped Ironic user rescue - notify path existence. | |
| [ 288.195643] systemd[1]: Stopped Ironic user rescue - notify path existence. | |
| [[0;32m OK [0m] Stopped target Sockets. | |
| [ 288.209426] systemd[1]: Stopped target Sockets. | |
| [[0;32m OK [0m] Closed Open-iSCSI iscsiuio Socket. | |
| [ 288.218041] systemd[1]: Closed Open-iSCSI iscsiuio Socket. | |
| [[0;32m OK [0m] Closed Open-iSCSI iscsid Socket. | |
| [ 288.233757] systemd[1]: Closed Open-iSCSI iscsid Socket. | |
| [[0;32m OK [0m] Stopped target Slices. | |
| [ 288.239859] systemd[1]: Stopped target Slices. | |
| [ 288.259994] systemd[1]: Removed slice User and Session Slice. | |
| [[0;32m OK [0m] Removed slice User and Session Slice. | |
| [ 288.281591] systemd[1]: Closed D-Bus System Message Bus Socket. | |
| [[0;32m OK [0m] Closed D-Bus System Message Bus Socket. | |
| [ 288.291868] systemd[1]: Stopped target System Initialization. | |
| [[0;32m OK [0m] Stopped target System Initialization. | |
| [ 288.305606] systemd[1]: Stopped Mark the need to relabel after reboot. | |
| [[0;32m OK [0m] Stopped Mark the need to relabel after reboot. | |
| [ 288.341440] systemd[1]: Stopping Load/Save Random Seed... | |
| Stopping Load/Save Random Seed... | |
| [ 288.424292] systemd[1]: Stopping Update UTMP about System Boot/Shutdown... | |
| Stopping Update UTMP about System Boot/Shutdown... | |
| [[0;32m OK [0m] Stopped Read and set NIS domainname from /etc/sysconfig/network. | |
| [ 288.450472] systemd[1]: Stopped Read and set NIS domainname from /etc/sysconfig/network. | |
| [[0;32m OK [0m] Stopped target Swap. | |
| [ 288.470738] systemd[1]: Stopped target Swap. | |
| [[0;32m OK [0m] Stopped Apply Kernel Variables. | |
| [ 288.488668] [[0;32m OK [0m] Stopped target Local Encrypted Volumes. | |
| systemd[1]: Stopped Apply Kernel Variables. | |
| [[0;32m OK [0m] Stopped Dispatch Password Requests to Console Directory Watch. | |
| [ 288.500737] systemd[1]: Stopped target Local Encrypted Volumes. | |
| [[0;32m OK [0m] Stopped Forward Password Requests to Wall Directory Watch. | |
| [[0;32m OK [0m] Stopped Update is Completed. | |
| [[0;32m OK [0m] Stopped Rebuild Dynamic Linker Cache. | |
| [ 288.510799] systemd[1]: Stopped Dispatch Password Requests to Console Directory Watch. | |
| [[0;32m OK [0m] Stopped Rebuild Hardware Database. | |
| [ 288.526584] systemd[1]: Stopped Forward Password Requests to Wall Directory Watch. | |
| [[0;32m OK [0m] Stopped Rebuild Journal Catalog. | |
| [ 288.536642] systemd[1]: Stopped Update is Completed. | |
| [[0;32m OK [0m] Stopped Load Kernel Modules. | |
| [ 288.542798] systemd[1]: Stopped Rebuild Dynamic Linker Cache. | |
| [ 288.546196] systemd[1]: Stopped Rebuild Hardware Database. | |
| [ 288.556947] systemd[1]: Stopped Rebuild Journal Catalog. | |
| [ 288.562798] systemd[1]: Stopped Load Kernel Modules. | |
| [ 288.800892] systemd[1]: Stopped Load/Save Random Seed. | |
| [[0;32m OK [0m] Stopped Load/Save Random Seed. | |
| [ 288.916855] systemd[1]: Stopped Update UTMP about System Boot/Shutdown. | |
| [[0;32m OK [0m] Stopped Update UTMP about System Boot/Shutdown. | |
| [ 288.934293] systemd[1]: Stopped Create Volatile Files and Directories. | |
| [[0;32m OK [0m] Stopped Create Volatile Files and Directories. | |
| [ 288.945511] systemd[1]: Stopped target Local File Systems. | |
| [[0;32m OK [0m] Stopped target Local File Systems. | |
| [ 288.961605] systemd[1]: Stopped target Local File Systems (Pre). | |
| [[0;32m OK [0m] Stopped target Local File Systems (Pre). | |
| [ 288.971900] systemd[1]: Stopped Create Static Device Nodes in /dev. | |
| [[0;32m OK [0m] Stopped Create Static Device Nodes in /dev. | |
| [ 288.983901] systemd[1]: Stopped Create System Users. | |
| [[0;32m OK [0m] Stopped Create System Users. | |
| [[0;32m OK [0m] Stopped Remount Root and Kernel File Systems. | |
| [ 288.994117] systemd[1]: Stopped Remount Root and Kernel File Systems. | |
| [[0;32m OK [0m] Reached target Shutdown. | |
| [[0;32m OK [0m] Reached target Final Step. | |
| [ 289.019560] systemd[1]: Reached target Shutdown. | |
| [ 289.023777] systemd[1]: Reached target Final Step. | |
| [ 289.087452] systemd[1]: Starting Power-Off... | |
| Starting Power-Off... | |
| [ 289.287777] systemd[1]: Shutting down. | |
| [ 289.444465] systemd-shutdow: 36 output lines suppressed due to ratelimiting | |
| [ 289.615311] systemd-shutdown[1]: Syncing filesystems and block devices. | |
| [ 289.626362] systemd-shutdown[1]: Sending SIGTERM to remaining processes... | |
| [ 289.666611] systemd-journald[174]: Received SIGTERM from PID 1 (systemd-shutdow). | |
| [ 289.705995] systemd-shutdown[1]: Sending SIGKILL to remaining processes... | |
| [ 289.761813] systemd-shutdown[1]: Unmounting file systems. | |
| [ 289.793041] [686]: Remounting '/' read-only in with options 'size=1318556k,nr_inodes=329639'. | |
| [ 289.798502] systemd-shutdown[1]: All filesystems unmounted. | |
| [ 289.798800] systemd-shutdown[1]: Deactivating swaps. | |
| [ 289.800696] systemd-shutdown[1]: All swaps deactivated. | |
| [ 289.800952] systemd-shutdown[1]: Detaching loop devices. | |
| [ 289.807738] systemd-shutdown[1]: All loop devices detached. | |
| [ 289.807988] systemd-shutdown[1]: Detaching DM devices. | |
| [ 289.958355] kvm: exiting hardware virtualization | |
| [ 289.959809] sd 0:0:1:0: [sda] Synchronizing SCSI cache | |
| [ 289.961384] sd 0:0:1:0: [sda] Stopping disk | |
| [ 289.967264] ACPI: Preparing to enter system sleep state S5 | |
| [ 289.970510] reboot: Power down |
Sign up for free
to join this conversation on GitHub.
Already have an account?
Sign in to comment