View Issue Details

IDProjectCategoryView StatusLast Update
0015735CentOS-7systemdpublic2019-01-24 22:07
Reporteriwienand 
PrioritynormalSeverityminorReproducibilityalways
Status newResolutionopen 
Product Version7.6.1810 
Target VersionFixed in Version 
Summary0015735: Boot speed regression with 219-62.el7_6.2
DescriptionWe are seeing a significant boot speed regression with systems using systemd 219-62.el7_6.2

Starting with the standard 1809 upstream cloud image [1] we have upgraded to the latest 3.10.0-957.1.3.el7.x86_64 kernel and dracut-033-554.el7.

We see standard initramfs boot times, measured between the two welcome outputs, of around 20s (leading timestamps from qemu console)

---
[00:00:08.819] Welcome to CentOS Linux 7 (Core) dracut-033-554.el7 (Initramfs)!
[00:00:37.331] Welcome to CentOS Linux 7 (Core)!
---

Sample boot in vanilla.txt

We then upgrade just systemd (to 219-62.el7_6.2) and regenerate the initramfs (logs in dracut.txt)

The boot is then consistently slower; up to a minute

---
[00:00:05.598] Welcome to CentOS Linux 7 (Core) dracut-033-554.el7 (Initramfs)!
[00:01:10.092] Welcome to CentOS Linux 7 (Core)!
---

The bulk of the time lost appears to be in this section seeming around device enumeration or similar

For example; on a vanilla boot this section completes in a handful of seconds

---
[00:00:23.597] [ 22.180822] Floppy drive(s): fd0 is 2.88M AMI BIOS
[00:00:23.733] [ 22.317691] FDC 0 is a S82078B
[00:00:24.371] [ 22.955816] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[00:00:24.372] [ 22.956397] e1000: Copyright (c) 1999-2006 Intel Corporation.
[00:00:25.540] [ 24.124559] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[00:00:25.871] [ 24.455781] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[00:00:25.872] [ 24.456548] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[00:00:25.972] [ 24.556354] scsi host0: ata_piix
[00:00:25.983] [ 24.567042] scsi host1: ata_piix
[00:00:25.987] [ 24.568560] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[00:00:25.988] [ 24.572632] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[00:00:26.151] [ 24.735266] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[00:00:26.151] [ 24.735677] ata1.00: 16777216 sectors, multi 16: LBA48
[00:00:26.154] [ 24.736601] ata1.00: configured for MWDMA2
[00:00:26.172] [ 24.756606] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[00:00:26.174] [ 24.758058] ata2.00: configured for MWDMA2
[00:00:26.182] [ 24.759598] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5
[00:00:26.223] [ 24.807081] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5
[00:00:26.728] [ 25.304171] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[00:00:26.728] [ 25.312774] cdrom: Uniform CD-ROM driver Revision: 3.20
[00:00:26.827] [ 25.410954] sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.58 GB/8.00 GiB)
[00:00:26.834] [ 25.413448] sd 0:0:0:0: [sda] Write Protect is off
[00:00:26.854] [ 25.438570] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[00:00:26.875] [ 25.459216] sda: sda1
[00:00:26.938] [ 25.512092] sd 0:0:0:0: [sda] Attached SCSI disk
[00:00:27.437] [ OK ] Found device QEMU_HARDDISK 1.
---

but with the new systemd we consistently see this taking 30s or longer

---
[00:00:32.579] [ 31.849773] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[00:00:32.581] [ 31.853670] e1000: Copyright (c) 1999-2006 Intel Corporation.
[00:00:35.997] [ 35.257500] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[00:00:36.355] [ 35.626662] Floppy drive(s): fd0 is 2.88M AMI BIOS
[00:00:36.432] [ 35.701279] FDC 0 is a S82078B
[00:00:38.522] [ 37.793928] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[00:00:38.524] [ 37.796910] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[00:00:38.916] [ 38.178057] scsi host0: ata_piix
[00:00:39.058] [ 38.330017] scsi host1: ata_piix
[00:00:39.080] [ 38.352710] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[00:00:39.081] [ 38.353662] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[00:00:39.243] [ 38.515027] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[00:00:39.243] [ 38.515104] ata1.00: 16777216 sectors, multi 16: LBA48
[00:00:39.247] [ 38.517247] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[00:00:39.249] [ 38.521332] ata1.00: configured for MWDMA2
[00:00:39.281] [ 38.553352] ata2.00: configured for MWDMA2
[00:00:39.347] [ 38.608707] scsi 0:0:0:0: Direct-Access ATA QEMU HARDDISK 2.5+ PQ: 0 ANSI: 5
[00:00:39.628] [ 38.900089] scsi 1:0:0:0: CD-ROM QEMU QEMU DVD-ROM 2.5+ PQ: 0 ANSI: 5 <------------ this long pause is very consistent!
[00:00:56.392] [ 55.657487] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[00:00:56.394] [ 55.666568] cdrom: Uniform CD-ROM driver Revision: 3.20
[00:00:57.374] [ 56.645341] sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.58 GB/8.00 GiB)
[00:00:57.449] [ 56.720919] sd 0:0:0:0: [sda] Write Protect is off
[00:00:57.463] [ 56.735477] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[00:00:57.623] [ 56.894502] sda: sda1
[00:00:57.864] [ 57.136359] sd 0:0:0:0: [sda] Attached SCSI disk
[00:00:58.858] [ OK ] Found device QEMU_HARDDISK 1.
---

Note the only thing that has changed is the upgrade of systemd and the regeneration of the initramfs. I have extracted both the old and new initramfs and compared them; the non-binary diff components are small and consist of http://paste.openstack.org/show/743145/. The other changes all just reflect the binary changes in the systemd tools.

The "MountFlags=slave" in the udev service looked suspect; however reverting that change, regenerating the initramfs and rebooting showed no difference.




[1] https://cloud.centos.org/centos/7/images/CentOS-7-x86_64-GenericCloud-1809.qcow2
Steps To ReproduceStaring from

https://cloud.centos.org/centos/7/images/CentOS-7-x86_64-GenericCloud-1809.qcow2

run yum upgrade kernel and dracut for baseline. Then upgrade systemd and "dracut -vf", the next boot will be slower.

Testing is being done on a binary translation host, as this reflects what is happening in the upstream CI jobs. The boot command is

$ qemu-system-x86_64 -msg timestamp=on -drive file=CentOS-7-x86_64-GenericCloud-1809.qcow2 -nographic -m 1G -cdrom ./cloud-init/init.iso

where cloud-init/init.iso just has a key and password for cloud-init.
Additional InformationThere is more debugging information at https://etherpad.openstack.org/p/dib-centos-slow
TagsNo tags attached.
abrt_hash
URL

Activities

iwienand

iwienand

2019-01-23 08:05

reporter  

dracut.txt (12,084 bytes)
[00:03:56.537] Last login: Wed Jan 23 04:58:26 on ttyS0
[00:03:58.056] [centos@cloudimg ~]$ sudo yum install systemd
[00:04:05.823] Loaded plugins: fastestmirror
[00:04:07.075] Loading mirror speeds from cached hostfile
[00:04:07.148]  * base: mirror.optus.net
[00:04:07.191]  * extras: mirror.optus.net
[00:04:07.204]  * updates: ftp.swin.edu.au
[00:04:13.215] Resolving Dependencies
[00:04:13.235] --> Running transaction check
[00:04:13.240] ---> Package systemd.x86_64 0:219-57.el7_5.3 will be updated
[00:04:13.962] --> Processing Dependency: systemd = 219-57.el7_5.3 for package: systemd-sysv-219-57.el7_5.3.x86_64
[00:04:14.014] ---> Package systemd.x86_64 0:219-62.el7_6.2 will be an update
[00:04:14.782] --> Processing Dependency: systemd-libs = 219-62.el7_6.2 for package: systemd-219-62.el7_6.2.x86_64
[00:04:15.023] --> Processing Dependency: libcryptsetup.so.12(CRYPTSETUP_2.0)(64bit) for package: systemd-219-62.el7_6.2.x86_64
[00:04:15.146] --> Processing Dependency: libcryptsetup.so.12()(64bit) for package: systemd-219-62.el7_6.2.x86_64
[00:04:15.150] --> Running transaction check
[00:04:15.156] ---> Package cryptsetup-libs.x86_64 0:1.7.4-4.el7 will be updated
[00:04:15.176] ---> Package cryptsetup-libs.x86_64 0:2.0.3-3.el7 will be an update
[00:04:15.309] --> Processing Dependency: libjson-c.so.2()(64bit) for package: cryptsetup-libs-2.0.3-3.el7.x86_64
[00:04:15.412] ---> Package systemd-libs.x86_64 0:219-57.el7_5.3 will be updated
[00:04:15.451] ---> Package systemd-libs.x86_64 0:219-62.el7_6.2 will be an update
[00:04:15.531] ---> Package systemd-sysv.x86_64 0:219-57.el7_5.3 will be updated
[00:04:15.540] ---> Package systemd-sysv.x86_64 0:219-62.el7_6.2 will be an update
[00:04:15.588] --> Running transaction check
[00:04:15.603] ---> Package json-c.x86_64 0:0.11-4.el7_0 will be installed
[00:04:21.451] --> Finished Dependency Resolution
[00:04:23.549] 
[00:04:23.550] Dependencies Resolved
[00:04:23.632] 
[00:04:23.633] ================================================================================
[00:04:23.633]  Package               Arch         Version                 Repository     Size
[00:04:23.634] ================================================================================
[00:04:23.636] Updating:
[00:04:23.637]  systemd               x86_64       219-62.el7_6.2          updates       5.1 M
[00:04:23.639] Installing for dependencies:
[00:04:23.640]  json-c                x86_64       0.11-4.el7_0            base           31 k
[00:04:23.641] Updating for dependencies:
[00:04:23.642]  cryptsetup-libs       x86_64       2.0.3-3.el7             base          338 k
[00:04:23.644]  systemd-libs          x86_64       219-62.el7_6.2          updates       406 k
[00:04:23.645]  systemd-sysv          x86_64       219-62.el7_6.2          updates        83 k
[00:04:23.646] 
[00:04:23.646] Transaction Summary
[00:04:23.646] ================================================================================
[00:04:23.647] Install             ( 1 Dependent package)
[00:04:23.649] Upgrade  1 Package  (+3 Dependent packages)
[00:04:23.650] 
[00:04:23.668] Total download size: 5.9 M
[00:04:23.674] Is this ok [y/d/N]: y
[00:04:26.209] Downloading packages:
[00:04:26.290] Delta RPMs disabled because /usr/bin/applydeltarpm not installed.
(1/5): json-c-0.11-4.el7_0.x86_64.rpm                      |  31 kB   00:04     
(2/5): cryptsetup-libs-2.0.3-3.el7.x86_64.rpm              | 338 kB   00:05     
(3/5): systemd-sysv-219-62.el7_6.2.x86_64.rpm              |  83 kB   00:05     
(4/5): systemd-libs-219-62.el7_6.2.x86_64.rpm              | 406 kB   00:05     
(5/5): systemd-219-62.el7_6.2.x86_64.rpm                   | 5.1 MB   00:07     
[00:04:34.252] --------------------------------------------------------------------------------
[00:04:34.258] Total                                              758 kB/s | 5.9 MB  00:07     
[00:04:34.465] Running transaction check
[00:04:35.639] Running transaction test
[00:04:36.464] Transaction test succeeded
[00:04:36.475] Running transaction
  Installing : json-c-0.11-4.el7_0.x86_64                                   1/9 
  Updating   : cryptsetup-libs-2.0.3-3.el7.x86_64                           2/9 
  Updating   : systemd-libs-219-62.el7_6.2.x86_64                           3/9 
  Updating   : systemd-219-62.el7_6.2.x86_64                                4/9 
  Updating   : systemd-sysv-219-62.el7_6.2.x86_64                           5/9 
  Cleanup    : systemd-sysv-219-57.el7_5.3.x86_64                           6/9 
  Cleanup    : systemd-219-57.el7_5.3.x86_64                                7/9 
  Cleanup    : cryptsetup-libs-1.7.4-4.el7.x86_64                           8/9 
  Cleanup    : systemd-libs-219-57.el7_5.3.x86_64                           9/9 
  Verifying  : cryptsetup-libs-2.0.3-3.el7.x86_64                           1/9 
  Verifying  : systemd-sysv-219-62.el7_6.2.x86_64                           2/9 
  Verifying  : systemd-libs-219-62.el7_6.2.x86_64                           3/9 
  Verifying  : systemd-219-62.el7_6.2.x86_64                                4/9 
  Verifying  : json-c-0.11-4.el7_0.x86_64                                   5/9 
  Verifying  : cryptsetup-libs-1.7.4-4.el7.x86_64                           6/9 
  Verifying  : systemd-sysv-219-57.el7_5.3.x86_64                           7/9 
  Verifying  : systemd-219-57.el7_5.3.x86_64                                8/9 
  Verifying  : systemd-libs-219-57.el7_5.3.x86_64                           9/9 
[00:05:29.499] 
[00:05:29.500] Dependency Installed:
[00:05:29.500]   json-c.x86_64 0:0.11-4.el7_0                                                  
[00:05:29.501] 
[00:05:29.501] Updated:
[00:05:29.501]   systemd.x86_64 0:219-62.el7_6.2                                               
[00:05:29.502] 
[00:05:29.502] Dependency Updated:
[00:05:29.503]   cryptsetup-libs.x86_64 0:2.0.3-3.el7   systemd-libs.x86_64 0:219-62.el7_6.2  
[00:05:29.504]   systemd-sysv.x86_64 0:219-62.el7_6.2  
[00:05:29.510] 
[00:05:29.525] Complete!
[00:05:30.175] [centos@cloudimg ~]$ sudo dracut -fv
[00:06:10.696] Executing: /sbin/dracut -fv
[00:06:11.313] dracut module 'busybox' will not be installed, because command 'busybox' could not be found!
[00:06:11.956] dracut module 'plymouth' will not be installed, because command 'plymouthd' could not be found!
[00:06:12.039] dracut module 'plymouth' will not be installed, because command 'plymouth' could not be found!
[00:06:12.499] dracut module 'crypt' will not be installed, because command 'cryptsetup' could not be found!
[00:06:12.640] dracut module 'dmraid' will not be installed, because command 'dmraid' could not be found!
[00:06:12.815] dracut module 'dmsquash-live-ntfs' will not be installed, because command 'ntfs-3g' could not be found!
[00:06:13.067] dracut module 'lvm' will not be installed, because command 'lvm' could not be found!
[00:06:13.187] dracut module 'mdraid' will not be installed, because command 'mdadm' could not be found!
[00:06:13.330] dracut module 'multipath' will not be installed, because command 'multipath' could not be found!
[00:06:13.808] dracut module 'cifs' will not be installed, because command 'mount.cifs' could not be found!
[00:06:14.127] dracut module 'fcoe' will not be installed, because command 'dcbtool' could not be found!
[00:06:14.175] dracut module 'fcoe' will not be installed, because command 'fipvlan' could not be found!
[00:06:14.252] dracut module 'fcoe' will not be installed, because command 'lldpad' could not be found!
[00:06:14.329] dracut module 'fcoe' will not be installed, because command 'fcoemon' could not be found!
[00:06:14.395] dracut module 'fcoe' will not be installed, because command 'fcoeadm' could not be found!
[00:06:14.556] dracut module 'fcoe-uefi' will not be installed, because command 'dcbtool' could not be found!
[00:06:14.614] dracut module 'fcoe-uefi' will not be installed, because command 'fipvlan' could not be found!
[00:06:14.675] dracut module 'fcoe-uefi' will not be installed, because command 'lldpad' could not be found!
[00:06:14.844] dracut module 'iscsi' will not be installed, because command 'iscsistart' could not be found!
[00:06:14.920] dracut module 'iscsi' will not be installed, because command 'iscsi-iname' could not be found!
[00:06:15.047] dracut module 'nbd' will not be installed, because command 'nbd-client' could not be found!
[00:06:16.042] dracut module 'biosdevname' will not be installed, because command 'biosdevname' could not be found!
[00:06:20.062] *** Including module: bash ***
[00:06:20.370] *** Including module: modsign ***
[00:06:20.763] *** Including module: nss-softokn ***
[00:06:21.015] *** Including module: i18n ***
[00:06:31.399] *** Including module: network ***
[00:07:00.359] *** Including module: ifcfg ***
[00:07:00.918] *** Including module: btrfs ***
[00:07:02.133] *** Including module: kernel-modules ***
[00:09:57.433] *** Including module: qemu ***
[00:09:57.682] *** Including module: qemu-net ***
[00:09:57.847] *** Including module: nfs ***
[00:10:01.962] *** Including module: resume ***
[00:10:02.230] *** Including module: rootfs-block ***
[00:10:02.556] *** Including module: terminfo ***
[00:10:03.042] *** Including module: udev-rules ***
[00:10:03.469] Skipping udev rule: 40-redhat-cpu-hotplug.rules
[00:10:14.830] Skipping udev rule: 91-permissions.rules
[00:10:15.700] *** Including module: virtfs ***
[00:10:16.111] *** Including module: systemd ***
[00:10:27.041] *** Including module: usrmount ***
[00:10:27.335] *** Including module: base ***
[00:10:30.066] *** Including module: fs-lib ***
[00:10:30.946] *** Including module: microcode_ctl-fw_dir_override ***
[00:10:31.076]   microcode_ctl module: mangling fw_dir
[00:10:31.122]     microcode_ctl: reset fw_dir to "/lib/firmware/updates /lib/firmware"
[00:10:31.273]     microcode_ctl: processing data directory  "/usr/share/microcode_ctl/ucode_with_caveats/intel"...
[00:10:31.711] intel: model '', path ' intel-ucode/*', kvers ''
[00:10:31.712] intel: blacklist ''
[00:10:31.859]       microcode_ctl: intel: caveats check for kernel version "3.10.0-957.1.3.el7.x86_64" passed, adding "/usr/share/microcode_ctl/ucode_with_caveats/intel" to fw_dir variable
[00:10:31.925]     microcode_ctl: processing data directory  "/usr/share/microcode_ctl/ucode_with_caveats/intel-06-4f-01"...
[00:10:32.313] intel-06-4f-01: model 'GenuineIntel 06-4f-01', path ' intel-ucode/06-4f-01', kvers ' 4.17.0 3.10.0-894 3.10.0-862.6.1 3.10.0-693.35.1 3.10.0-514.52.1 3.10.0-327.70.1 2.6.32-754.1.1 2.6.32-573.58.1 2.6.32-5'
[00:10:32.319] intel-06-4f-01: blacklist ''
[00:10:32.348] intel-06-4f-01: caveat is disabled in configuration
[00:10:32.372]     microcode_ctl: kernel version "3.10.0-957.1.3.el7.x86_64" failed early load check for "intel-06-4f-01", skipping
[00:10:32.438]     microcode_ctl: final fw_dir: "/usr/share/microcode_ctl/ucode_with_caveats/intel /lib/firmware/updates /lib/firmware"
[00:10:32.538] *** Including module: shutdown ***
[00:10:34.716] *** Including modules done ***
[00:10:34.783] *** Installing kernel module dependencies and firmware ***
[00:11:33.406] *** Installing kernel module dependencies and firmware done ***
[00:11:34.125] *** Resolving executable dependencies ***
[00:12:20.623] *** Resolving executable dependencies done***
[00:12:21.243] *** Hardlinking files ***
[00:12:21.732] *** Hardlinking files done ***
[00:12:21.798] *** Stripping files ***
[00:12:24.845] *** Stripping files done ***
[00:12:24.957] *** Generating early-microcode cpio image contents ***
[00:12:25.068] *** Constructing AuthenticAMD.bin ****
[00:12:25.324] *** Constructing GenuineIntel.bin ****
[00:12:29.204] *** Constructing GenuineIntel.bin ****
[00:12:29.239] *** Store current command line parameters ***
[00:12:29.336] *** Creating image file ***
[00:12:29.390] *** Creating microcode section ***
[00:12:29.650] *** Created microcode section ***
[00:13:31.633] *** Creating image file done ***
[00:15:37.960] *** Creating initramfs image file '/boot/initramfs-3.10.0-957.1.3.el7.x86_64.img' done ***
dracut.txt (12,084 bytes)
systemd.txt (46,573 bytes)
[    0.000000] Initializing cgroup subsys cpuset
[00:00:00.000] [    0.000000] Initializing cgroup subsys cpu
[00:00:00.002] [    0.000000] Initializing cgroup subsys cpuacct
[00:00:00.003] [    0.000000] Linux version 3.10.0-957.1.3.el7.x86_64 (mockbuild@kbuilder.bsys.centos.org) (gcc version 4.8.5 20150623 (Red Hat 4.8.5-36) (GCC) ) #1 SMP Thu Nov 29 14:49:43 UTC 2018
[00:00:00.010] [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.10.0-957.1.3.el7.x86_64 root=UUID=de86ba8a-914b-4104-9fd8-f9de800452ea ro console=tty0 console=ttyS0,115200n8 crashkernel=auto console=ttyS0,115200 L8
[00:00:00.017] [    0.000000] e820: BIOS-provided physical RAM map:
[00:00:00.018] [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[00:00:00.019] [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[00:00:00.019] [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[00:00:00.020] [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdffff] usable
[00:00:00.021] [    0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] reserved
[00:00:00.021] [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[00:00:00.022] [    0.000000] NX (Execute Disable) protection: active
[00:00:00.022] [    0.000000] SMBIOS 2.8 present.
[00:00:00.022] [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS ?-20180724_192412-buildhw-07.phx2.fedoraproject.org-1.fc29 04/01/2014
[00:00:00.023] [    0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000
[00:00:00.024] [    0.000000] PAT configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- UC  
[00:00:00.024] [    0.000000] found SMP MP-table at [mem 0x000f5d20-0x000f5d2f] mapped at [ffffffffff200d20]
[00:00:00.025] [    0.000000] RAMDISK: [mem 0x32c82000-0x35638fff]
[00:00:00.026] [    0.000000] Early table checksum verification disabled
[00:00:00.026] [    0.000000] ACPI: RSDP 00000000000f5b20 00014 (v00 BOCHS )
[00:00:00.027] [    0.000000] ACPI: RSDT 000000003ffe157c 00030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[00:00:00.028] [    0.000000] ACPI: FACP 000000003ffe1458 00074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[00:00:00.028] [    0.000000] ACPI: DSDT 000000003ffe0040 01418 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[00:00:00.029] [    0.000000] ACPI: FACS 000000003ffe0000 00040
[00:00:00.030] [    0.000000] ACPI: APIC 000000003ffe14cc 00078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[00:00:00.030] [    0.000000] ACPI: HPET 000000003ffe1544 00038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[00:00:00.031] [    0.000000] No NUMA configuration found
[00:00:00.032] [    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff]
[00:00:00.032] [    0.000000] NODE_DATA(0) allocated [mem 0x3ffb9000-0x3ffdffff]
[00:00:00.032] [    0.000000] crashkernel=auto resulted in zero bytes of reserved memory.
[00:00:00.033] [    0.000000] Zone ranges:
[00:00:00.033] [    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[00:00:00.034] [    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[00:00:00.034] [    0.000000]   Normal   empty
[00:00:00.035] [    0.000000] Movable zone start for each node
[00:00:00.035] [    0.000000] Early memory node ranges
[00:00:00.035] [    0.000000]   node   0: [mem 0x00001000-0x0009efff]
[00:00:00.036] [    0.000000]   node   0: [mem 0x00100000-0x3ffdffff]
[00:00:00.036] [    0.000000] Initmem setup node 0 [mem 0x00001000-0x3ffdffff]
[00:00:00.037] [    0.000000] ACPI: PM-Timer IO Port: 0x608
[00:00:00.037] [    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[00:00:00.038] [    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[00:00:00.038] [    0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[00:00:00.039] [    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[00:00:00.039] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[00:00:00.040] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[00:00:00.041] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[00:00:00.041] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[00:00:00.042] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[00:00:00.043] [    0.000000] Using ACPI (MADT) for SMP configuration information
[00:00:00.043] [    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[00:00:00.044] [    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[00:00:00.044] [    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[00:00:00.045] [    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[00:00:00.045] [    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[00:00:00.046] [    0.000000] e820: [mem 0x40000000-0xfffbffff] available for PCI devices
[00:00:00.047] [    0.000000] Booting paravirtualized kernel on bare hardware
[00:00:00.047] [    0.000000] setup_percpu: NR_CPUS:5120 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[00:00:00.048] [    0.000000] PERCPU: Embedded 38 pages/cpu @ffff935f7fc00000 s118784 r8192 d28672 u2097152
[00:00:00.049] [    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257897
[00:00:00.050] [    0.000000] Policy zone: DMA32
[00:00:00.050] [    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.10.0-957.1.3.el7.x86_64 root=UUID=de86ba8a-914b-4104-9fd8-f9de800452ea ro console=tty0 console=ttyS0,115200n8 crashkernel=auto console=ttyS0,18
[00:00:00.051] [    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[00:00:00.052] [    0.000000] Memory: 969500k/1048448k available (7664k kernel code, 392k absent, 78556k reserved, 6055k data, 1876k init)
[00:00:00.053] [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[00:00:00.054] [    0.000000] Hierarchical RCU implementation.
[00:00:00.054] [    0.000000] 	RCU restricting CPUs from NR_CPUS=5120 to nr_cpu_ids=1.
[00:00:00.054] [    0.000000] NR_IRQS:327936 nr_irqs:256 0
[00:00:00.055] [    0.000000] Console: colour VGA+ 80x25
[00:00:00.055] [    0.000000] console [tty0] enabled
[00:00:00.055] [    0.000000] console [ttyS0] enabled
[00:00:00.059] [    0.000000] allocated 4194304 bytes of page_cgroup
[00:00:00.059] [    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[00:00:00.142] [    0.000000] tsc: Unable to calibrate against PIT
[00:00:00.144] [    0.000000] tsc: using HPET reference calibration
[00:00:00.145] [    0.000000] tsc: Detected 2808.109 MHz processor
[00:00:00.149] [    0.011000] Calibrating delay loop (skipped), value calculated using timer frequency.. 5616.21 BogoMIPS (lpj=2808109)
[00:00:00.152] [    0.013186] pid_max: default: 32768 minimum: 301
[00:00:00.153] [    0.015623] Security Framework initialized
[00:00:00.158] [    0.019466] SELinux:  Initializing.
[00:00:00.160] [    0.021272] Yama: becoming mindful.
[00:00:00.167] [    0.026000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[00:00:00.169] [    0.027000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[00:00:00.171] [    0.029153] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[00:00:00.172] [    0.029615] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[00:00:00.190] [    0.039000] Initializing cgroup subsys memory
[00:00:00.191] [    0.040266] Initializing cgroup subsys devices
[00:00:00.192] [    0.040722] Initializing cgroup subsys freezer
[00:00:00.192] [    0.041134] Initializing cgroup subsys net_cls
[00:00:00.192] [    0.041441] Initializing cgroup subsys blkio
[00:00:00.193] [    0.041803] Initializing cgroup subsys perf_event
[00:00:00.193] [    0.042103] Initializing cgroup subsys hugetlb
[00:00:00.193] [    0.042398] Initializing cgroup subsys pids
[00:00:00.194] [    0.043073] Initializing cgroup subsys net_prio
[00:00:00.199] [    0.046000] mce: CPU supports 10 MCE banks
[00:00:00.202] [    0.049000] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[00:00:00.203] [    0.049113] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0
[00:00:00.203] [    0.049613] tlb_flushall_shift: -1
[00:00:00.204] [    0.050446] Speculative Store Bypass: Vulnerable
[00:00:00.205] [    0.051111] FEATURE SPEC_CTRL Not Present
[00:00:00.205] [    0.051494] FEATURE IBPB_SUPPORT Not Present
[00:00:00.234] [    0.062000] Spectre V2 : Vulnerable: Retpoline without IBPB
[00:00:00.486] [    0.256151] random: fast init done
[00:00:00.649] [    0.354248] Freeing SMP alternatives: 28k freed
[00:00:00.675] [    0.376000] ACPI: Core revision 20130517
[00:00:00.717] [    0.407644] ACPI: All ACPI Tables successfully acquired
[00:00:00.722] [    0.410404] ftrace: allocating 29188 entries in 115 pages
[00:00:00.860] [    0.467000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[00:00:00.872] [    0.475000] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+ (fam: 06, model: 06, stepping: 03)
[00:00:01.029] [    0.475000] APIC calibration not consistent with PM-Timer: 154ms instead of 100ms
[00:00:01.029] [    0.475000] APIC delta adjusted to PM-Timer: 6250211 (9672642)
[00:00:01.031] [    0.476000] Performance Events: Broken PMU hardware detected, using software events only.
[00:00:01.033] [    0.477064] Failed to access perfctr msr (MSR c0010007 is 0)
[00:00:01.059] [    0.497192] Brought up 1 CPUs
[00:00:01.060] [    0.497745] smpboot: Max logical packages: 1
[00:00:01.060] [    0.498136] smpboot: Total of 1 processors activated (5616.21 BogoMIPS)
[00:00:01.067] [    0.502347] NMI watchdog: disabled (cpu0): hardware events not enabled
[00:00:01.068] [    0.503126] NMI watchdog: Shutting down hard lockup detector on all cpus
[00:00:01.103] [    0.527437] devtmpfs: initialized
[00:00:01.123] [    0.545614] EVM: security.selinux
[00:00:01.123] [    0.546076] EVM: security.ima
[00:00:01.124] [    0.546458] EVM: security.capability
[00:00:01.139] [    0.557704] atomic64 test passed for x86-64 platform with CX8 and with SSE
[00:00:01.140] [    0.558028] pinctrl core: initialized pinctrl subsystem
[00:00:01.142] [    0.559000] RTC time:  6:17:20, date: 01/23/19
[00:00:01.150] [    0.562847] NET: Registered protocol family 16
[00:00:01.162] [    0.573000] ACPI: bus type PCI registered
[00:00:01.163] [    0.574235] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[00:00:01.168] [    0.577000] PCI: Using configuration type 1 for base access
[00:00:01.214] [    0.606000] ACPI: Added _OSI(Module Device)
[00:00:01.215] [    0.606000] ACPI: Added _OSI(Processor Device)
[00:00:01.216] [    0.606000] ACPI: Added _OSI(3.0 _SCP Extensions)
[00:00:01.216] [    0.606000] ACPI: Added _OSI(Processor Aggregator Device)
[00:00:01.218] [    0.606400] ACPI: Added _OSI(Linux-Dell-Video)
[00:00:01.246] [    0.624000] ACPI: Interpreter enabled
[00:00:01.249] [    0.624891] ACPI: (supports S0 S3 S4 S5)
[00:00:01.250] [    0.625000] ACPI: Using IOAPIC for interrupt routing
[00:00:01.253] [    0.628168] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[00:00:01.257] [    0.631000] ACPI: Enabled 2 GPEs in block 00 to 0F
[00:00:01.321] [    0.660000] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[00:00:01.323] [    0.661377] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[00:00:01.325] [    0.662350] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[00:00:01.326] [    0.663623] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[00:00:01.339] [    0.674000] acpiphp: Slot [3] registered
[00:00:01.339] [    0.674084] acpiphp: Slot [4] registered
[00:00:01.340] [    0.675242] acpiphp: Slot [5] registered
[00:00:01.341] [    0.675832] acpiphp: Slot [6] registered
[00:00:01.341] [    0.676183] acpiphp: Slot [7] registered
[00:00:01.342] [    0.677200] acpiphp: Slot [8] registered
[00:00:01.342] [    0.677675] acpiphp: Slot [9] registered
[00:00:01.343] [    0.678394] acpiphp: Slot [10] registered
[00:00:01.346] [    0.680000] acpiphp: Slot [11] registered
[00:00:01.348] [    0.680803] acpiphp: Slot [12] registered
[00:00:01.349] [    0.681321] acpiphp: Slot [13] registered
[00:00:01.350] [    0.681877] acpiphp: Slot [14] registered
[00:00:01.350] [    0.682227] acpiphp: Slot [15] registered
[00:00:01.351] [    0.683197] acpiphp: Slot [16] registered
[00:00:01.352] [    0.683641] acpiphp: Slot [17] registered
[00:00:01.352] [    0.684203] acpiphp: Slot [18] registered
[00:00:01.353] [    0.684648] acpiphp: Slot [19] registered
[00:00:01.353] [    0.685000] acpiphp: Slot [20] registered
[00:00:01.354] [    0.685000] acpiphp: Slot [21] registered
[00:00:01.354] [    0.685000] acpiphp: Slot [22] registered
[00:00:01.355] [    0.686321] acpiphp: Slot [23] registered
[00:00:01.356] [    0.686747] acpiphp: Slot [24] registered
[00:00:01.356] [    0.687157] acpiphp: Slot [25] registered
[00:00:01.357] [    0.688061] acpiphp: Slot [26] registered
[00:00:01.358] [    0.688608] acpiphp: Slot [27] registered
[00:00:01.358] [    0.689258] acpiphp: Slot [28] registered
[00:00:01.359] [    0.689709] acpiphp: Slot [29] registered
[00:00:01.359] [    0.690261] acpiphp: Slot [30] registered
[00:00:01.360] [    0.691250] acpiphp: Slot [31] registered
[00:00:01.361] [    0.692406] PCI host bridge to bus 0000:00
[00:00:01.362] [    0.692995] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[00:00:01.363] [    0.693205] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[00:00:01.363] [    0.694081] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[00:00:01.364] [    0.695037] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[00:00:01.365] [    0.695566] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[00:00:01.365] [    0.696177] pci_bus 0000:00: root bus resource [bus 00-ff]
[00:00:01.377] [    0.701455] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[00:00:01.378] [    0.702000] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[00:00:01.379] [    0.702097] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[00:00:01.380] [    0.702644] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[00:00:01.382] [    0.705287] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[00:00:01.383] [    0.706096] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[00:00:01.419] [    0.718000] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[00:00:01.426] [    0.720000] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[00:00:01.436] [    0.727531] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[00:00:01.438] [    0.729360] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[00:00:01.440] [    0.730656] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[00:00:01.448] [    0.736652] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[00:00:01.449] [    0.737084] vgaarb: loaded
[00:00:01.449] [    0.737359] vgaarb: bridge control possible 0000:00:02.0
[00:00:01.452] [    0.739000] SCSI subsystem initialized
[00:00:01.453] [    0.740336] ACPI: bus type USB registered
[00:00:01.456] [    0.742280] usbcore: registered new interface driver usbfs
[00:00:01.460] [    0.745072] usbcore: registered new interface driver hub
[00:00:01.461] [    0.746307] usbcore: registered new device driver usb
[00:00:01.468] [    0.752438] EDAC MC: Ver: 3.0.0
[00:00:01.471] [    0.754000] PCI: Using ACPI for IRQ routing
[00:00:01.487] [    0.764031] NetLabel: Initializing
[00:00:01.487] [    0.764438] NetLabel:  domain hash size = 128
[00:00:01.488] [    0.764822] NetLabel:  protocols = UNLABELED CIPSOv4
[00:00:01.490] [    0.767000] NetLabel:  unlabeled traffic allowed by default
[00:00:01.492] [    0.768669] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[00:00:01.494] [    0.769420] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[00:00:01.495] [    0.771041] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[00:00:01.501] [    0.774319] amd_nb: Cannot enumerate AMD northbridges
[00:00:01.503] [    0.775657] Switched to clocksource hpet
[00:00:01.603] [    0.874464] pnp: PnP ACPI init
[00:00:01.605] [    0.877375] ACPI: bus type PNP registered
[00:00:01.623] [    0.892389] pnp: PnP ACPI: found 6 devices
[00:00:01.624] [    0.896897] ACPI: bus type PNP unregistered
[00:00:01.718] [    0.990450] NET: Registered protocol family 2
[00:00:01.726] [    0.998058] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[00:00:01.726] [    0.998921] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[00:00:01.727] [    0.999830] TCP: Hash tables configured (established 8192 bind 8192)
[00:00:01.729] [    1.001370] TCP: reno registered
[00:00:01.729] [    1.002004] UDP hash table entries: 512 (order: 2, 16384 bytes)
[00:00:01.730] [    1.002821] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[00:00:01.734] [    1.006540] NET: Registered protocol family 1
[00:00:01.735] [    1.007879] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[00:00:01.736] [    1.008844] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[00:00:01.737] [    1.009828] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[00:00:01.744] [    1.016425] Unpacking initramfs...
[00:00:04.416] [    3.674483] Freeing initrd memory: 42716k freed
[00:00:04.465] [    3.735194] sha1_ssse3: Neither AVX nor AVX2 nor SSSE3/SHA-NI is available/usable.
[00:00:04.466] [    3.738752] sha256_ssse3: Neither AVX nor SSSE3/SHA-NI is available/usable.
[00:00:04.473] [    3.741348] futex hash table entries: 256 (order: 2, 16384 bytes)
[00:00:04.476] [    3.748440] Initialise system trusted keyring
[00:00:04.478] [    3.750538] audit: initializing netlink socket (disabled)
[00:00:04.486] [    3.758079] type=2000 audit(1548224242.755:1): initialized
[00:00:04.589] [    3.861118] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[00:00:04.608] [    3.879491] zpool: loaded
[00:00:04.610] [    3.882081] zbud: loaded
[00:00:04.621] [    3.893751] VFS: Disk quotas dquot_6.5.2
[00:00:04.623] [    3.895062] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[00:00:04.646] [    3.916284] msgmni has been set to 1977
[00:00:04.651] [    3.923231] Key type big_key registered
[00:00:04.683] [    3.944197] NET: Registered protocol family 38
[00:00:04.688] [    3.960811] Key type asymmetric registered
[00:00:04.689] [    3.961171] Asymmetric key parser 'x509' registered
[00:00:04.691] [    3.963652] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[00:00:04.693] [    3.965703] io scheduler noop registered
[00:00:04.695] [    3.967086] io scheduler deadline registered (default)
[00:00:04.696] [    3.968451] io scheduler cfq registered
[00:00:04.697] [    3.969078] io scheduler mq-deadline registered
[00:00:04.698] [    3.970549] io scheduler kyber registered
[00:00:04.703] [    3.974705] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[00:00:04.704] [    3.976827] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[00:00:04.705] [    3.977750] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[00:00:04.713] [    3.985167] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[00:00:04.718] [    3.989119] ACPI: Power Button [PWRF]
[00:00:04.725] [    3.997826] GHES: HEST is not enabled!
[00:00:04.730] [    4.002524] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[00:00:04.759] [    4.031541] 00:05: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[00:00:04.780] [    4.051560] Non-volatile memory driver v1.3
[00:00:04.781] [    4.053766] Linux agpgart interface v0.103
[00:00:04.785] [    4.056121] crash memory driver: version 1.1
[00:00:04.788] [    4.060415] rdac: device handler registered
[00:00:04.789] [    4.061500] hp_sw: device handler registered
[00:00:04.789] [    4.061887] emc: device handler registered
[00:00:04.791] [    4.063330] alua: device handler registered
[00:00:04.794] [    4.066083] libphy: Fixed MDIO Bus: probed
[00:00:04.796] [    4.068545] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[00:00:04.802] [    4.073401] ehci-pci: EHCI PCI platform driver
[00:00:04.803] [    4.076003] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[00:00:04.804] [    4.077039] ohci-pci: OHCI PCI platform driver
[00:00:04.806] [    4.078513] uhci_hcd: USB Universal Host Controller Interface driver
[00:00:04.808] [    4.079099] usbcore: registered new interface driver usbserial_generic
[00:00:04.809] [    4.081984] usbserial: USB Serial support registered for generic
[00:00:04.811] [    4.083678] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[00:00:04.815] [    4.087635] serio: i8042 KBD port at 0x60,0x64 irq 1
[00:00:04.816] [    4.088495] serio: i8042 AUX port at 0x60,0x64 irq 12
[00:00:04.818] [    4.091006] mousedev: PS/2 mouse device common for all mice
[00:00:04.828] [    4.099337] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[00:00:04.832] [    4.104071] rtc_cmos 00:00: RTC can wake from S4
[00:00:04.842] [    4.109217] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[00:00:04.843] [    4.115983] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[00:00:04.845] [    4.117608] cpuidle: using governor menu
[00:00:04.858] [    4.130542] hidraw: raw HID events driver (C) Jiri Kosina
[00:00:04.862] [    4.134073] usbcore: registered new interface driver usbhid
[00:00:04.863] [    4.135383] usbhid: USB HID core driver
[00:00:04.864] [    4.137038] drop_monitor: Initializing network drop monitor service
[00:00:04.873] [    4.145082] TCP: cubic registered
[00:00:04.874] [    4.146023] Initializing XFRM netlink socket
[00:00:04.877] [    4.149313] NET: Registered protocol family 10
[00:00:04.889] [    4.159442] NET: Registered protocol family 17
[00:00:04.891] [    4.163108] mpls_gso: MPLS GSO support
[00:00:04.906] [    4.178264] Loading compiled-in X.509 certificates
[00:00:04.912] [    4.184022] Loaded X.509 cert 'CentOS Linux kpatch signing key: ea0413152cde1d98ebdca3fe6f0230904c9ef717'
[00:00:04.914] [    4.186161] Loaded X.509 cert 'CentOS Linux Driver update signing key: 7f421ee0ab69461574bb358861dbe77762a4201b'
[00:00:04.923] [    4.195287] Loaded X.509 cert 'CentOS Linux kernel signing key: e7cef3613a9b8bd012fae7498272159bb1879c65'
[00:00:04.925] [    4.197390] registered taskstats version 1
[00:00:05.112] [    4.378519] Key type trusted registered
[00:00:05.217] [    4.481586] Key type encrypted registered
[00:00:05.218] [    4.481586] IMA: No TPM chip found, activating TPM-bypass! (rc=-19)
[00:00:05.223] [    4.494395]   Magic number: 3:52:267
[00:00:05.225] [    4.497615] rtc_cmos 00:00: setting system clock to 2019-01-23 06:17:24 UTC (1548224244)
[00:00:05.245] [    4.517733] Freeing unused kernel memory: 1876k freed
[00:00:05.338] [    4.602091] Write protecting the kernel read-only data: 12288k
[00:00:05.346] [    4.618951] Freeing unused kernel memory: 516k freed
[00:00:05.361] [    4.633602] Freeing unused kernel memory: 600k freed
[00:00:05.457] [    4.729333] tsc: Refined TSC clocksource calibration: 2808.084 MHz
[00:00:05.458] [    4.730497] Switched to clocksource tsc
[00:00:05.580] [    4.851032] systemd[1]: systemd 219 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 -SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[00:00:05.587] [    4.859083] systemd[1]: Detected virtualization qemu.
[00:00:05.588] [    4.860850] systemd[1]: Detected architecture x86-64.
[00:00:05.589] [    4.861773] systemd[1]: Running in initial RAM disk.
[00:00:05.597] 
[00:00:05.598] Welcome to CentOS Linux 7 (Core) dracut-033-554.el7 (Initramfs)!
[00:00:05.599] 
[00:00:05.606] [    4.878078] systemd[1]: No hostname configured.
[00:00:05.607] [    4.879800] systemd[1]: Set hostname to <localhost>.
[00:00:05.610] [    4.882658] systemd[1]: Initializing machine ID from random generator.
[00:00:05.692] [    4.964499] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
[00:00:07.124] [    6.390711] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[00:00:07.128] [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[00:00:07.130] [    6.402449] systemd[1]: Reached target Swap.
[00:00:07.131] [  OK  ] Reached target Swap.
[00:00:07.133] [    6.405050] systemd[1]: Reached target Timers.
[00:00:07.134] [  OK  ] Reached target Timers.
[00:00:07.144] [    6.416196] systemd[1]: Created slice Root Slice.
[00:00:07.147] [  OK  ] Created slice Root Slice.
[00:00:07.151] [    6.423496] systemd[1]: Created slice System Slice.
[00:00:07.153] [  OK  ] Created slice System Slice.
[00:00:07.160] [    6.432749] systemd[1]: Listening on udev Control Socket.
[00:00:07.164] [  OK  ] Listening on udev Control Socket.
[00:00:07.172] [    6.443187] systemd[1]: Listening on udev Kernel Socket.
[00:00:07.174] [  OK  ] Listening on udev Kernel Socket.
[00:00:07.175] [    6.447617] systemd[1]: Reached target Slices.
[00:00:07.177] [  OK  ] Reached target Slices.
[00:00:07.179] [    6.451389] systemd[1]: Listening on Journal Socket.
[00:00:07.180] [  OK  ] Listening on Journal Socket.
[00:00:07.191] [    6.463449] systemd[1]: Reached target Sockets.
[00:00:07.194] [  OK  ] Reached target Sockets.
[00:00:07.248] [    6.520182] systemd[1]: Starting Journal Service...
[00:00:07.254]          Starting Journal Service...
[00:00:07.365] [    6.637814] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[00:00:07.371]          Starting Create list of required st... nodes for the current kernel...
[00:00:07.448] [    6.714592] systemd[1]: Starting Apply Kernel Variables...
[00:00:07.474]          Starting Apply Kernel Variables...
[00:00:07.485] [    6.757122] systemd[1]: Reached target Local File Systems.
[00:00:07.493] [  OK  ] Reached target Local File Systems.
[00:00:07.541] [    6.813930] systemd[1]: Reached target Paths.
[00:00:07.558] [  OK  ] Reached target Paths.
[00:00:07.687] [    6.948191] systemd[1]: Starting dracut cmdline hook...
[00:00:07.795]          Starting dracut cmdline hook...
[00:00:07.890] [    7.157262] systemd[1]: Started Create list of required static device nodes for the current kernel.
[00:00:07.924] [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[00:00:08.092] [    7.364156] systemd[1]: Starting Create Static Device Nodes in /dev...
[00:00:08.104]          Starting Create Static Device Nodes in /dev...
[00:00:08.260] [    7.532466] systemd[1]: Started Apply Kernel Variables.
[00:00:08.263] [  OK  ] Started Apply Kernel Variables.
[00:00:08.572] [    7.841540] systemd[1]: Started Create Static Device Nodes in /dev.
[00:00:08.585] [  OK  ] Started Create Static Device Nodes in /dev.
[00:00:08.813] [    8.079704] systemd[1]: Started Journal Service.
[00:00:08.833] [  OK  ] Started Journal Service.
[00:00:09.973] [  OK  ] Started dracut cmdline hook.
[00:00:10.123]          Starting dracut pre-udev hook...
[00:00:10.674] [    9.941495] RPC: Registered named UNIX socket transport module.
[00:00:10.675] [    9.947658] RPC: Registered udp transport module.
[00:00:10.675] [    9.947887] RPC: Registered tcp transport module.
[00:00:10.676] [    9.948172] RPC: Registered tcp NFSv4.1 backchannel transport module.
[00:00:11.928] [  OK  ] Started dracut pre-udev hook.
[00:00:11.994]          Starting udev Kernel Device Manager...
[00:00:12.205] [  OK  ] Started udev Kernel Device Manager.
[00:00:12.224]          Starting dracut pre-trigger hook...
[00:00:12.717] [  OK  ] Started dracut pre-trigger hook.
[00:00:12.763]          Starting udev Coldplug all Devices...
[00:00:15.924] [  OK  ] Started udev Coldplug all Devices.
[00:00:16.296] [  OK  ] Reached target System Initialization.
[00:00:16.315] [  OK  ] Reached target Basic System.
[00:00:16.778]          Starting dracut initqueue hook...
[00:00:25.584]          Mounting Configuration File System...
[00:00:26.870] [  OK  ] Mounted Configuration File System.
[00:00:32.579] [   31.849773] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[00:00:32.581] [   31.853670] e1000: Copyright (c) 1999-2006 Intel Corporation.
[00:00:35.997] [   35.257500] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[00:00:36.355] [   35.626662] Floppy drive(s): fd0 is 2.88M AMI BIOS
[00:00:36.432] [   35.701279] FDC 0 is a S82078B
[00:00:38.522] [   37.793928] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[00:00:38.524] [   37.796910] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[00:00:38.916] [   38.178057] scsi host0: ata_piix
[00:00:39.058] [   38.330017] scsi host1: ata_piix
[00:00:39.080] [   38.352710] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[00:00:39.081] [   38.353662] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[00:00:39.243] [   38.515027] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[00:00:39.243] [   38.515104] ata1.00: 16777216 sectors, multi 16: LBA48 
[00:00:39.247] [   38.517247] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[00:00:39.249] [   38.521332] ata1.00: configured for MWDMA2
[00:00:39.281] [   38.553352] ata2.00: configured for MWDMA2
[00:00:39.347] [   38.608707] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[00:00:39.628] [   38.900089] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[00:00:56.392] [   55.657487] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[00:00:56.394] [   55.666568] cdrom: Uniform CD-ROM driver Revision: 3.20
[00:00:57.374] [   56.645341] sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.58 GB/8.00 GiB)
[00:00:57.449] [   56.720919] sd 0:0:0:0: [sda] Write Protect is off
[00:00:57.463] [   56.735477] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[00:00:57.623] [   56.894502]  sda: sda1
[00:00:57.864] [   57.136359] sd 0:0:0:0: [sda] Attached SCSI disk
[00:00:58.858] [  OK  ] Found device QEMU_HARDDISK 1.
[00:00:59.014]          Starting File System Check on /dev/...a-914b-4104-9fd8-f9de800452ea...
[00:00:59.151] [  OK  ] Started dracut initqueue hook.
[00:00:59.282] [  OK  ] Reached target Remote File Systems (Pre).
[00:00:59.295] [  OK  ] Reached target Remote File Systems.
[00:00:59.593] [  OK  ] Started File System Check on /dev/d...a8a-914b-4104-9fd8-f9de800452ea.
[00:00:59.644]          Mounting /sysroot...
[00:01:00.312] [   59.584186] SGI XFS with ACLs, security attributes, no debug enabled
[00:01:00.469] [   59.738109] XFS (sda1): Mounting V5 Filesystem
[00:01:01.083] [   60.355000] random: crng init done
[00:01:01.212] [   60.480231] XFS (sda1): Ending clean mount
[00:01:01.678] [  OK  ] Mounted /sysroot.
[00:01:01.733] [  OK  ] Reached target Initrd Root File System.
[00:01:01.777]          Starting Reload Configuration from the Real Root...
[00:01:03.309] [  OK  ] Started Reload Configuration from the Real Root.
[00:01:03.317] [  OK  ] Reached target Initrd File Systems.
[00:01:03.337] [  OK  ] Reached target Initrd Default Target.
[00:01:03.373]          Starting dracut pre-pivot and cleanup hook...
[00:01:04.985] [  OK  ] Started dracut pre-pivot and cleanup hook.
[00:01:05.013]          Starting Cleaning Up and Shutting Down Daemons...
[00:01:05.156] [  OK  ] Stopped target Timers.
[00:01:05.199] [  OK  ] Stopped Cleaning Up and Shutting Down Daemons.
[00:01:05.227] [  OK  ] Stopped dracut pre-pivot and cleanup hook.
[00:01:05.246] [  OK  ] Stopped target Remote File Systems.
[00:01:05.259] [  OK  ] Stopped target Remote File Systems (Pre).
[00:01:05.312] [  OK  ] Stopped dracut initqueue hook.
[00:01:05.327] [  OK  ] Stopped target Initrd Default Target.
[00:01:05.346] [  OK  ] Stopped target Basic System.
[00:01:05.360] [  OK  ] Stopped target Slices.
[00:01:05.363] [  OK  ] Stopped target System Initialization.
[00:01:05.390] [  OK  ] Stopped Apply Kernel Variables.
[00:01:05.393] [  OK  ] Stopped target Swap.
[00:01:05.460] [  OK  ] Stopped udev Coldplug all Devices.
[00:01:05.505] [  OK  ] Stopped dracut pre-trigger hook.
[00:01:05.615]          Stopping udev Kernel Device Manager...
[00:01:05.686] [  OK  ] Stopped target Local File Systems.
[00:01:05.706] [  OK  ] Stopped target Paths.
[00:01:05.733] [  OK  ] Stopped Dispatch Password Requests to Console Directory Watch.
[00:01:05.749] [  OK  ] Stopped target Sockets.
[00:01:05.881] [  OK  ] Stopped udev Kernel Device Manager.
[00:01:06.010] [  OK  ] Stopped dracut pre-udev hook.
[00:01:06.040] [  OK  ] Stopped dracut cmdline hook.
[00:01:06.104] [  OK  ] Stopped Create Static Device Nodes in /dev.
[00:01:06.176] [  OK  ] Stopped Create list of required sta...ce nodes for the current kernel.
[00:01:06.194] [  OK  ] Closed udev Control Socket.
[00:01:06.204] [  OK  ] Closed udev Kernel Socket.
[00:01:06.318]          Starting Cleanup udevd DB...
[00:01:06.502] [  OK  ] Started Cleanup udevd DB.
[00:01:06.516] [  OK  ] Reached target Switch Root.
[00:01:06.610]          Starting Switch Root...
[00:01:07.020] [   66.290190] systemd-journald[95]: Received SIGTERM from PID 1 (systemd).
[00:01:07.709] [   66.981165] type=1404 audit(1548224306.982:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295
[00:01:09.225] [   68.497058] SELinux:  Class bpf not defined in policy.
[00:01:09.225] [   68.497715] SELinux: the above unknown classes and permissions will be allowed
[00:01:09.281] [   68.553187] type=1403 audit(1548224308.555:3): policy loaded auid=4294967295 ses=4294967295
[00:01:09.390] [   68.662568] systemd[1]: Successfully loaded SELinux policy in 1.694210s.
[00:01:09.666] [   68.938329] ip_tables: (C) 2000-2006 Netfilter Core Team
[00:01:09.667] [   68.939760] systemd[1]: Inserted module 'ip_tables'
[00:01:09.979] [   69.250965] systemd[1]: Relabelled /dev, /run and /sys/fs/cgroup in 243.723ms.
[00:01:10.092] 
[00:01:10.092] Welcome to CentOS Linux 7 (Core)!
[00:01:10.092] 
[00:01:12.238] [  OK  ] Stopped Switch Root.
[00:01:12.292] [  OK  ] Stopped Journal Service.
[00:01:12.367]          Starting Journal Service...
[00:01:12.411] [  OK  ] Set up automount Arbitrary Executab...ats File System Automount Point.
[00:01:12.477] [  OK  ] Listening on udev Kernel Socket.
[00:01:12.488] [  OK  ] Created slice system-getty.slice.
[00:01:12.530] [  OK  ] Listening on udev Control Socket.
[00:01:12.614]          Mounting POSIX Message Queue File System...
[00:01:12.631] [  OK  ] Reached target Swap.
[00:01:12.727]          Starting Read and set NIS domainname from /etc/sysconfig/network...
[00:01:12.814] [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[00:01:13.011]          Starting Create list of required st... nodes for the current kernel...
[00:01:13.031] [  OK  ] Started Forward Password Requests to Wall Directory Watch.
[00:01:13.154] [  OK  ] Listening on Delayed Shutdown Socket.
[00:01:13.200] [  OK  ] Stopped target Switch Root.
[00:01:13.211] [  OK  ] Stopped target Initrd File Systems.
[00:01:13.362] [  OK  ] Started Dispatch Password Requests to Console Directory Watch.
[00:01:13.403] [  OK  ] Reached target Paths.
[00:01:13.491] [  OK  ] Created slice system-serial\x2dgetty.slice.
[00:01:13.510] [  OK  ] Created slice User and Session Slice.
[00:01:13.542] [  OK  ] Reached target Slices.
[00:01:13.696]          Mounting Huge Pages File System...
[00:01:13.868]          Mounting Debug File System...
[00:01:13.951] [  OK  ] Stopped File System Check on Root Device.
[00:01:14.128]          Starting Remount Root and Kernel File Systems...
[00:01:14.317]          Starting Apply Kernel Variables...
[00:01:14.497]          Starting Setup Virtual Console...
[00:01:14.588] [  OK  ] Reached target Local Encrypted Volumes.
[00:01:14.617] [  OK  ] Reached target rpc_pipefs.target.
[00:01:14.678] [  OK  ] Stopped target Initrd Root File System.
[00:01:14.730] [  OK  ] Created slice system-selinux\x2dpol...grate\x2dlocal\x2dchanges.slice.
[00:01:14.917] [  OK  ] Mounted Debug File System.
[00:01:14.941] [  OK  ] Mounted POSIX Message Queue File System.
[00:01:14.970] [  OK  ] Mounted Huge Pages File System.
[00:01:15.045] [  OK  ] Started Journal Service.
[00:01:15.398] [  OK  ] Started Read and set NIS domainname from /etc/sysconfig/network.
[00:01:15.459] [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[00:01:15.569] [  OK  ] Started Remount Root and Kernel File Systems.
[00:01:15.631] [  OK  ] Started Apply Kernel Variables.
[00:01:15.797]          Starting Load/Save Random Seed...
[00:01:15.912]          Starting Configure read-only root support...
[00:01:16.077]          Starting udev Coldplug all Devices...
[00:01:16.228]          Starting Create Static Device Nodes in /dev...
[00:01:16.969]          Starting Flush Journal to Persistent Storage...
[00:01:17.779] [  OK  ] Started Load/Save Random Seed.
[00:01:18.404] [   77.670598] systemd-journald[1302]: Received request to flush runtime journal from PID 1
[00:01:18.768] [  OK  ] Started Flush Journal to Persistent Storage.
[00:01:19.049] [  OK  ] Started Create Static Device Nodes in /dev.
[00:01:19.307]          Starting udev Kernel Device Manager...
[00:01:19.329] [  OK  ] Reached target Local File Systems (Pre).
[00:01:19.539] [  OK  ] Started Configure read-only root support.
[00:01:19.551] [  OK  ] Reached target Local File Systems.
[00:01:19.674]          Starting Import network configuration from initramfs...
[00:01:19.848]          Starting Preprocess NFS configuration...
[00:01:20.336] [  OK  ] Started udev Coldplug all Devices.
[00:01:20.957] [  OK  ] Started udev Kernel Device Manager.
[00:01:21.077] [  OK  ] Started Preprocess NFS configuration.
[00:01:21.191] [  OK  ] Started Setup Virtual Console.
[  OK  ] Started Import network configuration from initramfs.
[00:01:28.652]          Starting Create Volatile Files and Directories...
[  OK  ] Started Create Volatile Files and Directories.
[00:01:37.063]          Starting Security Auditing Service...
[00:01:40.416] [   99.687637] type=1305 audit(1548224339.687:4): audit_pid=1717 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
[**    ] (2 of 2) A start job is running for dev-ttyS0.device (33s / 1min 30s)[  104.994335] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[   ***] (2 of 2) A start job is running for dev-ttyS0.device (37s / 1min 30s)[  109.269100] sd 0:0:0:0: Attached scsi generic sg0 type 0
[00:01:50.178] [  109.450005] sr 1:0:0:0: Attached scsi generic sg1 type 5
[  OK  ] Started Security Auditing Service.
[00:01:52.480]          Starting Update UTMP about System Boot/Shutdown...
[00:01:53.078] [  112.349831] [drm] Found bochs VGA, ID 0xb0c0.
[00:01:53.080] [  112.352401] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[00:01:53.152] [  112.424376] [TTM] Zone  kernel: Available graphics memory: 507618 kiB
[00:01:53.153] [  112.425484] [TTM] Initializing pool allocator
[00:01:53.182] [  112.454336] [TTM] Initializing DMA pool allocator
[00:01:53.608] [  112.880441] parport_pc 00:04: reported by Plug and Play ACPI
[00:01:53.615] [  112.887227] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[00:01:54.165] [  113.094783] fbcon: bochsdrmfb (fb0) is primary device
[00:01:54.165] [  113.234828] Console: switching to colour frame buffer device 128x48
[00:01:54.166] [  113.438464] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[00:01:54.400] [  113.672695] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[00:01:54.988] [  OK  ] Started Update UTMP about System Boot/Shutdown.
[00:01:55.232] [  OK  ] Reached target System Initialization.
[00:01:55.366] [  OK  ] Listening on RPCbind Server Activation Socket.
[00:01:55.683]          Starting RPC bind service...
[00:01:55.698] [  OK  ] Started Daily Cleanup of Temporary Directories.
[00:01:55.704] [  OK  ] Reached target Timers.
[00:01:56.281] [  OK  ] Listening on D-Bus System Message Bus Socket.
[00:01:56.393]          Starting Initial cloud-init job (pre-networking)...
[00:01:56.446] [  OK  ] Reached target Sockets.
[00:01:56.538] [  OK  ] Reached target Basic System.
[00:01:56.696]          Starting GSSAPI Proxy Daemon...
[00:01:57.143] [  OK  ] Started irqbalance daemon.
[00:01:57.437]          Starting Dump dmesg to /var/log/dmesg...
[00:01:58.121] [  OK  ] Started D-Bus System Message Bus.
[00:02:00.247] [  119.517338] input: PC Speaker as /devices/platform/pcspkr/input/input3
[00:02:08.851]          Starting NTP client/server...
[00:02:09.239]          Starting Authorization Manager...
[00:02:09.733]          Starting Login Service...
[00:02:10.927] [  OK  ] Started RPC bind service.
[00:02:11.693] [  OK  ] Started GSSAPI Proxy Daemon.
[00:02:13.619] [  OK  ] Started Dump dmesg to /var/log/dmesg.
[00:02:18.922] [  OK  ] Started NTP client/server.
[00:02:19.679] [  OK  ] Found device /dev/ttyS0.
[00:02:20.799] [  OK  ] Started Authorization Manager.
[00:02:21.449] [  OK  ] Started Login Service.
[00:02:21.891] [  OK  ] Reached target NFS client services.
[00:02:21.998] [  OK  ] Reached target Remote File Systems (Pre).
[00:02:22.048] [  OK  ] Reached target Remote File Systems.
[00:02:30.049] [  149.320866] ppdev: user-space parallel port driver
[00:02:37.536] [  156.786013] cloud-init[2216]: Cloud-init v. 0.7.9 running 'init-local' at Wed, 23 Jan 2019 06:19:56 +0000. Up 156.08 seconds.
[00:02:39.432] [  158.704093] blk_update_request: I/O error, dev fd0, sector 0
[00:02:39.626] [  158.898646] blk_update_request: I/O error, dev fd0, sector 0
[00:02:39.786] [  159.057672] blk_update_request: I/O error, dev fd0, sector 0
[00:02:42.734] [  OK  ] Started Initial cloud-init job (pre-networking).
[00:02:42.838] [  OK  ] Reached target Network (Pre).
[00:02:42.941]          Starting LSB: Bring up/down networking...
[     *] A start job is running for LSB: Bri...networking (1min 38s / 6min 30s)[  170.402036] e1000: ens3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[00:02:51.140] [  170.412349] IPv6: ADDRCONF(NETDEV_UP): ens3: link is not ready
[00:02:51.143] [  170.414862] IPv6: ADDRCONF(NETDEV_CHANGE): ens3: link becomes ready
[FAILED] Failed to start LSB: Bring up/down networking.
[00:03:08.854] See 'systemctl status network.service' for details.
[00:03:08.936] [  OK  ] Reached target Network.
[00:03:09.062]          Starting Dynamic System Tuning Daemon...
[00:03:09.124]          Starting Postfix Mail Transport Agent...
[00:03:09.316]          Starting Initial cloud-init job (metadata service crawler)...
[00:03:27.007] [  OK  ] Started Dynamic System Tuning Daemon.
[00:03:28.615] [  207.883051] cloud-init[2754]: Cloud-init v. 0.7.9 running 'init' at Wed, 23 Jan 2019 06:20:46 +0000. Up 206.95 seconds.
[00:03:29.779] [  209.050116] cloud-init[2754]: ci-info: +++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++
[00:03:29.787] [  209.058418] cloud-init[2754]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:03:29.806] [  209.077657] cloud-init[2754]: ci-info: | Device |  Up  |  Address  |      Mask     | Scope |     Hw-Address    |
[00:03:29.830] [  209.101065] cloud-init[2754]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:03:29.869] [  209.139044] cloud-init[2754]: ci-info: |  lo:   | True | 127.0.0.1 |   255.0.0.0   |   .   |         .         |
[00:03:29.940] [  209.210990] cloud-init[2754]: ci-info: |  lo:   | True |     .     |       .       |   d   |         .         |
[00:03:29.971] [  209.218290] cloud-init[2754]: ci-info: | ens3:  | True | 10.0.2.15 | 255.255.255.0 |   .   | 52:54:00:12:34:56 |
[00:03:30.020] [  209.255803] cloud-init[2754]: ci-info: | ens3:  | True |     .     |       .       |   d   | 52:54:00:12:34:56 |
[00:03:30.027] [  209.298498] cloud-init[2754]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:03:30.089] [  209.360459] cloud-init[2754]: ci-info: +++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++
[00:03:30.113] [  209.367347] cloud-init[2754]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:03:30.122] [  209.392421] cloud-init[2754]: ci-info: | Route | Destination | Gateway  |    Genmask    | Interface | Flags |
[00:03:30.190] [  209.461404] cloud-init[2754]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:03:30.241] [  209.468646] cloud-init[2754]: ci-info: |   0   |   0.0.0.0   | 10.0.2.2 |    0.0.0.0    |    ens3   |   UG  |
[00:03:30.272] [  209.524271] cloud-init[2754]: ci-info: |   1   |   10.0.2.0  | 0.0.0.0  | 255.255.255.0 |    ens3   |   U   |
[00:03:30.351] [  209.619158] cloud-init[2754]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:03:37.143] [  216.413049] hrtimer: interrupt took 3526952 ns
[00:03:37.894] [  OK  ] Started Postfix Mail Transport Agent.
[00:03:41.233] [  OK  ] Started Initial cloud-init job (metadata service crawler).
[00:03:41.291] [  OK  ] Reached target Cloud-config availability.
[00:03:41.456]          Starting OpenSSH server daemon...
[00:03:41.459] [  OK  ] Reached target Network is Online.
[00:03:41.565]          Starting System Logging Service...
[00:03:41.648]          Starting Notify NFS peers of a restart...
[00:03:41.781]          Starting Crash recovery kernel arming...
[00:03:41.879]          Starting Apply the settings specified in cloud-config...
[00:03:42.082]          Starting Permit User Sessions...
[00:03:43.104] [  OK  ] Started Notify NFS peers of a restart.
[00:03:43.267] [  OK  ] Started Permit User Sessions.
[00:03:43.439] [  OK  ] Started Serial Getty on ttyS0.
[00:03:43.603] [  OK  ] Started Command Scheduler.
[00:03:43.770] [  OK  ] Started Getty on tty1.
[00:03:43.854] [  OK  ] Reached target Login Prompts.
[00:03:44.093] [  OK  ] Started System Logging Service.
[00:03:44.827] [  OK  ] Started OpenSSH server daemon.
[00:03:45.058] [FAILED] Failed to start Crash recovery kernel arming.
[00:03:45.119] See 'systemctl status kdump.service' for details.
[00:03:49.752] 
[00:03:49.756] CentOS Linux 7 (Core)
[00:03:49.757] Kernel 3.10.0-957.1.3.el7.x86_64 on an x86_64
[00:03:49.759] 
[00:03:49.760] cloudimg login: 
systemd.txt (46,573 bytes)
vanilla.txt (47,977 bytes)
[    0.000000] Initializing cgroup subsys cpuset
[00:00:00.000] [    0.000000] Initializing cgroup subsys cpu
[00:00:00.001] [    0.000000] Initializing cgroup subsys cpuacct
[00:00:00.001] [    0.000000] Linux version 3.10.0-957.1.3.el7.x86_64 (mockbuild@kbuilder.bsys.centos.org) (gcc version 4.8.5 20150623 (Red Hat 4.8.5-36) (GCC) ) #1 SMP Thu Nov 29 14:49:43 UTC 2018
[00:00:00.002] [    0.000000] Command line: BOOT_IMAGE=/boot/vmlinuz-3.10.0-957.1.3.el7.x86_64 root=UUID=de86ba8a-914b-4104-9fd8-f9de800452ea ro console=tty0 console=ttyS0,115200n8 crashkernel=auto console=ttyS0,115200 L8
[00:00:00.003] [    0.000000] e820: BIOS-provided physical RAM map:
[00:00:00.003] [    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[00:00:00.004] [    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[00:00:00.004] [    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[00:00:00.005] [    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ffdffff] usable
[00:00:00.005] [    0.000000] BIOS-e820: [mem 0x000000003ffe0000-0x000000003fffffff] reserved
[00:00:00.006] [    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[00:00:00.006] [    0.000000] NX (Execute Disable) protection: active
[00:00:00.007] [    0.000000] SMBIOS 2.8 present.
[00:00:00.008] [    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS ?-20180724_192412-buildhw-07.phx2.fedoraproject.org-1.fc29 04/01/2014
[00:00:00.009] [    0.000000] e820: last_pfn = 0x3ffe0 max_arch_pfn = 0x400000000
[00:00:00.010] [    0.000000] PAT configuration [0-7]: WB  WC  UC- UC  WB  WP  UC- UC  
[00:00:00.011] [    0.000000] found SMP MP-table at [mem 0x000f5d20-0x000f5d2f] mapped at [ffffffffff200d20]
[00:00:00.012] [    0.000000] RAMDISK: [mem 0x32c8f000-0x3563ffff]
[00:00:00.012] [    0.000000] Early table checksum verification disabled
[00:00:00.013] [    0.000000] ACPI: RSDP 00000000000f5b20 00014 (v00 BOCHS )
[00:00:00.013] [    0.000000] ACPI: RSDT 000000003ffe157c 00030 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[00:00:00.014] [    0.000000] ACPI: FACP 000000003ffe1458 00074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[00:00:00.014] [    0.000000] ACPI: DSDT 000000003ffe0040 01418 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[00:00:00.015] [    0.000000] ACPI: FACS 000000003ffe0000 00040
[00:00:00.015] [    0.000000] ACPI: APIC 000000003ffe14cc 00078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[00:00:00.016] [    0.000000] ACPI: HPET 000000003ffe1544 00038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[00:00:00.017] [    0.000000] No NUMA configuration found
[00:00:00.017] [    0.000000] Faking a node at [mem 0x0000000000000000-0x000000003ffdffff]
[00:00:00.018] [    0.000000] NODE_DATA(0) allocated [mem 0x3ffb9000-0x3ffdffff]
[00:00:00.018] [    0.000000] crashkernel=auto resulted in zero bytes of reserved memory.
[00:00:00.018] [    0.000000] Zone ranges:
[00:00:00.019] [    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[00:00:00.019] [    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[00:00:00.019] [    0.000000]   Normal   empty
[00:00:00.020] [    0.000000] Movable zone start for each node
[00:00:00.020] [    0.000000] Early memory node ranges
[00:00:00.020] [    0.000000]   node   0: [mem 0x00001000-0x0009efff]
[00:00:00.020] [    0.000000]   node   0: [mem 0x00100000-0x3ffdffff]
[00:00:00.021] [    0.000000] Initmem setup node 0 [mem 0x00001000-0x3ffdffff]
[00:00:00.021] [    0.000000] ACPI: PM-Timer IO Port: 0x608
[00:00:00.021] [    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[00:00:00.022] [    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[00:00:00.022] [    0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[00:00:00.022] [    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[00:00:00.023] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[00:00:00.023] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[00:00:00.023] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[00:00:00.023] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[00:00:00.024] [    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[00:00:00.024] [    0.000000] Using ACPI (MADT) for SMP configuration information
[00:00:00.024] [    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[00:00:00.024] [    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[00:00:00.025] [    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[00:00:00.025] [    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[00:00:00.025] [    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[00:00:00.025] [    0.000000] e820: [mem 0x40000000-0xfffbffff] available for PCI devices
[00:00:00.026] [    0.000000] Booting paravirtualized kernel on bare hardware
[00:00:00.026] [    0.000000] setup_percpu: NR_CPUS:5120 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[00:00:00.026] [    0.000000] PERCPU: Embedded 38 pages/cpu @ffff9c99ffc00000 s118784 r8192 d28672 u2097152
[00:00:00.027] [    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 257897
[00:00:00.027] [    0.000000] Policy zone: DMA32
[00:00:00.027] [    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-3.10.0-957.1.3.el7.x86_64 root=UUID=de86ba8a-914b-4104-9fd8-f9de800452ea ro console=tty0 console=ttyS0,115200n8 crashkernel=auto console=ttyS0,18
[00:00:00.028] [    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[00:00:00.028] [    0.000000] Memory: 969524k/1048448k available (7664k kernel code, 392k absent, 78532k reserved, 6055k data, 1876k init)
[00:00:00.029] [    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[00:00:00.029] [    0.000000] Hierarchical RCU implementation.
[00:00:00.029] [    0.000000] 	RCU restricting CPUs from NR_CPUS=5120 to nr_cpu_ids=1.
[00:00:00.030] [    0.000000] NR_IRQS:327936 nr_irqs:256 0
[00:00:00.030] [    0.000000] Console: colour VGA+ 80x25
[00:00:00.030] [    0.000000] console [tty0] enabled
[00:00:00.031] [    0.000000] console [ttyS0] enabled
[00:00:00.034] [    0.000000] allocated 4194304 bytes of page_cgroup
[00:00:00.035] [    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[00:00:00.054] [    0.000000] tsc: Fast TSC calibration failed
[00:00:00.127] [    0.000000] tsc: Unable to calibrate against PIT
[00:00:00.128] [    0.000000] tsc: using HPET reference calibration
[00:00:00.130] [    0.000000] tsc: Detected 2807.969 MHz processor
[00:00:00.151] [    0.010271] Calibrating delay loop (skipped), value calculated using timer frequency.. 5615.93 BogoMIPS (lpj=2807969)
[00:00:00.169] [    0.012000] pid_max: default: 32768 minimum: 301
[00:00:00.172] [    0.012000] Security Framework initialized
[00:00:00.182] [    0.012000] SELinux:  Initializing.
[00:00:00.193] [    0.012000] Yama: becoming mindful.
[00:00:00.206] [    0.016000] Dentry cache hash table entries: 131072 (order: 8, 1048576 bytes)
[00:00:00.215] [    0.019000] Inode-cache hash table entries: 65536 (order: 7, 524288 bytes)
[00:00:00.221] [    0.019000] Mount-cache hash table entries: 2048 (order: 2, 16384 bytes)
[00:00:00.222] [    0.019000] Mountpoint-cache hash table entries: 2048 (order: 2, 16384 bytes)
[00:00:00.287] [    0.039000] Initializing cgroup subsys memory
[00:00:00.301] [    0.042247] Initializing cgroup subsys devices
[00:00:00.308] [    0.043810] Initializing cgroup subsys freezer
[00:00:00.309] [    0.044000] Initializing cgroup subsys net_cls
[00:00:00.311] [    0.044000] Initializing cgroup subsys blkio
[00:00:00.318] [    0.045315] Initializing cgroup subsys perf_event
[00:00:00.319] [    0.046342] Initializing cgroup subsys hugetlb
[00:00:00.320] [    0.047316] Initializing cgroup subsys pids
[00:00:00.321] [    0.048236] Initializing cgroup subsys net_prio
[00:00:00.331] [    0.054247] mce: CPU supports 10 MCE banks
[00:00:00.334] [    0.058325] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[00:00:00.335] [    0.059127] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0
[00:00:00.336] [    0.059825] tlb_flushall_shift: -1
[00:00:00.337] [    0.061265] Speculative Store Bypass: Vulnerable
[00:00:00.338] [    0.062220] FEATURE SPEC_CTRL Not Present
[00:00:00.340] [    0.063172] FEATURE IBPB_SUPPORT Not Present
[00:00:00.384] [    0.078416] Spectre V2 : Vulnerable: Retpoline without IBPB
[00:00:00.626] [    0.256457] random: fast init done
[00:00:01.030] [    0.569409] Freeing SMP alternatives: 28k freed
[00:00:01.074] [    0.606613] ACPI: Core revision 20130517
[00:00:01.138] [    0.639246] ACPI: All ACPI Tables successfully acquired
[00:00:01.141] [    0.641000] ftrace: allocating 29188 entries in 115 pages
[00:00:01.363] [    0.688000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[00:00:01.377] [    0.695348] smpboot: CPU0: AMD QEMU Virtual CPU version 2.5+ (fam: 06, model: 06, stepping: 03)
[00:00:01.563] [    0.696000] APIC calibration not consistent with PM-Timer: 171ms instead of 100ms
[00:00:01.564] [    0.696000] APIC delta adjusted to PM-Timer: 6249998 (10741759)
[00:00:01.566] [    0.698000] Performance Events: Broken PMU hardware detected, using software events only.
[00:00:01.570] [    0.701121] Failed to access perfctr msr (MSR c0010007 is 0)
[00:00:01.628] [    0.729446] Brought up 1 CPUs
[00:00:01.628] [    0.730105] smpboot: Max logical packages: 1
[00:00:01.629] [    0.731297] smpboot: Total of 1 processors activated (5615.93 BogoMIPS)
[00:00:01.638] [    0.735288] NMI watchdog: disabled (cpu0): hardware events not enabled
[00:00:01.639] [    0.736188] NMI watchdog: Shutting down hard lockup detector on all cpus
[00:00:01.740] [    0.811311] devtmpfs: initialized
[00:00:01.803] [    0.864276] EVM: security.selinux
[00:00:01.805] [    0.865140] EVM: security.ima
[00:00:01.806] [    0.866090] EVM: security.capability
[00:00:01.868] [    0.917147] atomic64 test passed for x86-64 platform with CX8 and with SSE
[00:00:01.872] [    0.918245] pinctrl core: initialized pinctrl subsystem
[00:00:01.883] [    0.929747] RTC time:  6:00:16, date: 01/23/19
[00:00:01.896] [    0.939202] NET: Registered protocol family 16
[00:00:01.940] [    0.967713] ACPI: bus type PCI registered
[00:00:01.947] [    0.970801] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[00:00:01.983] [    0.977000] PCI: Using configuration type 1 for base access
[00:00:02.092] [    1.029000] ACPI: Added _OSI(Module Device)
[00:00:02.093] [    1.029129] ACPI: Added _OSI(Processor Device)
[00:00:02.094] [    1.030044] ACPI: Added _OSI(3.0 _SCP Extensions)
[00:00:02.094] [    1.030511] ACPI: Added _OSI(Processor Aggregator Device)
[00:00:02.096] [    1.031314] ACPI: Added _OSI(Linux-Dell-Video)
[00:00:02.152] [    1.077705] ACPI: Interpreter enabled
[00:00:02.155] [    1.079642] ACPI: (supports S0 S3 S4 S5)
[00:00:02.155] [    1.080000] ACPI: Using IOAPIC for interrupt routing
[00:00:02.157] [    1.080000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[00:00:02.164] [    1.082000] ACPI: Enabled 2 GPEs in block 00 to 0F
[00:00:02.306] [    1.163416] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[00:00:02.307] [    1.164559] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[00:00:02.308] [    1.165417] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[00:00:02.310] [    1.167234] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[00:00:02.333] [    1.178000] acpiphp: Slot [3] registered
[00:00:02.341] [    1.179377] acpiphp: Slot [4] registered
[00:00:02.350] [    1.180161] acpiphp: Slot [5] registered
[00:00:02.351] [    1.180994] acpiphp: Slot [6] registered
[00:00:02.352] [    1.181000] acpiphp: Slot [7] registered
[00:00:02.353] [    1.181000] acpiphp: Slot [8] registered
[00:00:02.354] [    1.181000] acpiphp: Slot [9] registered
[00:00:02.355] [    1.181000] acpiphp: Slot [10] registered
[00:00:02.355] [    1.181000] acpiphp: Slot [11] registered
[00:00:02.356] [    1.181000] acpiphp: Slot [12] registered
[00:00:02.357] [    1.181000] acpiphp: Slot [13] registered
[00:00:02.357] [    1.181000] acpiphp: Slot [14] registered
[00:00:02.358] [    1.181000] acpiphp: Slot [15] registered
[00:00:02.360] [    1.181000] acpiphp: Slot [16] registered
[00:00:02.363] [    1.183189] acpiphp: Slot [17] registered
[00:00:02.372] [    1.185000] acpiphp: Slot [18] registered
[00:00:02.373] [    1.185000] acpiphp: Slot [19] registered
[00:00:02.375] [    1.185418] acpiphp: Slot [20] registered
[00:00:02.379] [    1.186000] acpiphp: Slot [21] registered
[00:00:02.381] [    1.186000] acpiphp: Slot [22] registered
[00:00:02.382] [    1.186325] acpiphp: Slot [23] registered
[00:00:02.385] [    1.188269] acpiphp: Slot [24] registered
[00:00:02.397] [    1.189000] acpiphp: Slot [25] registered
[00:00:02.401] [    1.189368] acpiphp: Slot [26] registered
[00:00:02.408] [    1.191000] acpiphp: Slot [27] registered
[00:00:02.419] [    1.195243] acpiphp: Slot [28] registered
[00:00:02.433] [    1.196000] acpiphp: Slot [29] registered
[00:00:02.437] [    1.196000] acpiphp: Slot [30] registered
[00:00:02.438] [    1.196000] acpiphp: Slot [31] registered
[00:00:02.439] [    1.196000] PCI host bridge to bus 0000:00
[00:00:02.440] [    1.196000] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[00:00:02.451] [    1.196433] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[00:00:02.452] [    1.197144] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[00:00:02.453] [    1.198082] pci_bus 0000:00: root bus resource [mem 0x40000000-0xfebfffff window]
[00:00:02.454] [    1.198869] pci_bus 0000:00: root bus resource [mem 0x100000000-0x17fffffff window]
[00:00:02.460] [    1.199766] pci_bus 0000:00: root bus resource [bus 00-ff]
[00:00:02.524] [    1.227558] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[00:00:02.525] [    1.228131] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[00:00:02.525] [    1.228682] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[00:00:02.527] [    1.230157] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[00:00:02.534] [    1.235538] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[00:00:02.535] [    1.236000] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[00:00:02.644] [    1.280108] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[00:00:02.654] [    1.287800] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[00:00:02.657] [    1.290423] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[00:00:02.668] [    1.300290] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[00:00:02.672] [    1.303418] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[00:00:02.703] [    1.331957] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[00:00:02.716] [    1.332869] vgaarb: loaded
[00:00:02.717] [    1.334000] vgaarb: bridge control possible 0000:00:02.0
[00:00:02.722] [    1.336139] SCSI subsystem initialized
[00:00:02.723] [    1.337874] ACPI: bus type USB registered
[00:00:02.726] [    1.340917] usbcore: registered new interface driver usbfs
[00:00:02.728] [    1.341000] usbcore: registered new interface driver hub
[00:00:02.735] [    1.347036] usbcore: registered new device driver usb
[00:00:02.739] [    1.351422] EDAC MC: Ver: 3.0.0
[00:00:02.753] [    1.365189] PCI: Using ACPI for IRQ routing
[00:00:02.776] [    1.386350] NetLabel: Initializing
[00:00:02.776] [    1.386800] NetLabel:  domain hash size = 128
[00:00:02.777] [    1.387000] NetLabel:  protocols = UNLABELED CIPSOv4
[00:00:02.783] [    1.391000] NetLabel:  unlabeled traffic allowed by default
[00:00:02.787] [    1.394000] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[00:00:02.788] [    1.394643] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[00:00:02.800] [    1.395000] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[00:00:02.814] [    1.399608] amd_nb: Cannot enumerate AMD northbridges
[00:00:02.819] [    1.402918] Switched to clocksource hpet
[00:00:02.976] [    1.558029] pnp: PnP ACPI init
[00:00:02.977] [    1.561585] ACPI: bus type PNP registered
[00:00:02.993] [    1.575610] pnp: PnP ACPI: found 6 devices
[00:00:02.994] [    1.575610] ACPI: bus type PNP unregistered
[00:00:03.097] [    1.677429] NET: Registered protocol family 2
[00:00:03.113] [    1.697242] TCP established hash table entries: 8192 (order: 4, 65536 bytes)
[00:00:03.115] [    1.698974] TCP bind hash table entries: 8192 (order: 5, 131072 bytes)
[00:00:03.118] [    1.702186] TCP: Hash tables configured (established 8192 bind 8192)
[00:00:03.120] [    1.702217] TCP: reno registered
[00:00:03.121] [    1.702217] UDP hash table entries: 512 (order: 2, 16384 bytes)
[00:00:03.122] [    1.702217] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[00:00:03.128] [    1.712781] NET: Registered protocol family 1
[00:00:03.129] [    1.713882] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[00:00:03.130] [    1.714292] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[00:00:03.131] [    1.715825] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[00:00:03.146] [    1.728081] Unpacking initramfs...
[00:00:07.111] [    5.694074] Freeing initrd memory: 42692k freed
[00:00:07.179] [    5.763456] sha1_ssse3: Neither AVX nor AVX2 nor SSSE3/SHA-NI is available/usable.
[00:00:07.179] [    5.763923] sha256_ssse3: Neither AVX nor SSSE3/SHA-NI is available/usable.
[00:00:07.190] [    5.774238] futex hash table entries: 256 (order: 2, 16384 bytes)
[00:00:07.191] [    5.775730] Initialise system trusted keyring
[00:00:07.193] [    5.777804] audit: initializing netlink socket (disabled)
[00:00:07.199] [    5.783127] type=2000 audit(1548223219.778:1): initialized
[00:00:07.314] [    5.898389] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[00:00:07.332] [    5.916292] zpool: loaded
[00:00:07.332] [    5.916879] zbud: loaded
[00:00:07.337] [    5.921629] VFS: Disk quotas dquot_6.5.2
[00:00:07.338] [    5.922666] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[00:00:07.344] [    5.928753] msgmni has been set to 1977
[00:00:07.347] [    5.931598] Key type big_key registered
[00:00:07.378] [    5.962724] NET: Registered protocol family 38
[00:00:07.386] [    5.970731] Key type asymmetric registered
[00:00:07.387] [    5.971232] Asymmetric key parser 'x509' registered
[00:00:07.390] [    5.974128] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 248)
[00:00:07.391] [    5.975898] io scheduler noop registered
[00:00:07.392] [    5.976413] io scheduler deadline registered (default)
[00:00:07.393] [    5.977538] io scheduler cfq registered
[00:00:07.393] [    5.977955] io scheduler mq-deadline registered
[00:00:07.394] [    5.978695] io scheduler kyber registered
[00:00:07.398] [    5.982329] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[00:00:07.399] [    5.983107] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[00:00:07.401] [    5.985518] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[00:00:07.414] [    5.995339] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[00:00:07.419] [    6.003665] ACPI: Power Button [PWRF]
[00:00:07.431] [    6.015266] GHES: HEST is not enabled!
[00:00:07.439] [    6.023788] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[00:00:07.467] [    6.051816] 00:05: ttyS0 at I/O 0x3f8 (irq = 4) is a 16550A
[00:00:07.495] [    6.079621] Non-volatile memory driver v1.3
[00:00:07.497] [    6.081027] Linux agpgart interface v0.103
[00:00:07.504] [    6.088035] crash memory driver: version 1.1
[00:00:07.507] [    6.090048] rdac: device handler registered
[00:00:07.509] [    6.093078] hp_sw: device handler registered
[00:00:07.509] [    6.093758] emc: device handler registered
[00:00:07.511] [    6.095175] alua: device handler registered
[00:00:07.520] [    6.104023] libphy: Fixed MDIO Bus: probed
[00:00:07.525] [    6.109872] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[00:00:07.526] [    6.110681] ehci-pci: EHCI PCI platform driver
[00:00:07.527] [    6.111495] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[00:00:07.529] [    6.113769] ohci-pci: OHCI PCI platform driver
[00:00:07.533] [    6.116756] uhci_hcd: USB Universal Host Controller Interface driver
[00:00:07.538] [    6.122652] usbcore: registered new interface driver usbserial_generic
[00:00:07.539] [    6.123145] usbserial: USB Serial support registered for generic
[00:00:07.542] [    6.126224] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[00:00:07.548] [    6.132072] serio: i8042 KBD port at 0x60,0x64 irq 1
[00:00:07.549] [    6.133569] serio: i8042 AUX port at 0x60,0x64 irq 12
[00:00:07.555] [    6.138554] mousedev: PS/2 mouse device common for all mice
[00:00:07.567] [    6.150357] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[00:00:07.571] [    6.155198] rtc_cmos 00:00: RTC can wake from S4
[00:00:07.588] [    6.172701] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[00:00:07.590] [    6.174685] rtc_cmos 00:00: alarms up to one day, y3k, 114 bytes nvram, hpet irqs
[00:00:07.593] [    6.177144] cpuidle: using governor menu
[00:00:07.621] [    6.205472] hidraw: raw HID events driver (C) Jiri Kosina
[00:00:07.628] [    6.212600] usbcore: registered new interface driver usbhid
[00:00:07.630] [    6.213939] usbhid: USB HID core driver
[00:00:07.634] [    6.218257] drop_monitor: Initializing network drop monitor service
[00:00:07.638] [    6.222356] TCP: cubic registered
[00:00:07.638] [    6.222851] Initializing XFRM netlink socket
[00:00:07.642] [    6.225954] NET: Registered protocol family 10
[00:00:07.655] [    6.233090] NET: Registered protocol family 17
[00:00:07.656] [    6.240225] mpls_gso: MPLS GSO support
[00:00:07.662] [    6.246053] Loading compiled-in X.509 certificates
[00:00:07.676] [    6.258334] Loaded X.509 cert 'CentOS Linux kpatch signing key: ea0413152cde1d98ebdca3fe6f0230904c9ef717'
[00:00:07.679] [    6.263632] Loaded X.509 cert 'CentOS Linux Driver update signing key: 7f421ee0ab69461574bb358861dbe77762a4201b'
[00:00:07.690] [    6.270566] Loaded X.509 cert 'CentOS Linux kernel signing key: e7cef3613a9b8bd012fae7498272159bb1879c65'
[00:00:07.692] [    6.276541] registered taskstats version 1
[00:00:07.922] [    6.506764] Key type trusted registered
[00:00:08.117] [    6.700514] Key type encrypted registered
[00:00:08.119] [    6.703103] IMA: No TPM chip found, activating TPM-bypass! (rc=-19)
[00:00:08.125] [    6.709322]   Magic number: 3:293:14
[00:00:08.126] [    6.710884] rtc_cmos 00:00: setting system clock to 2019-01-23 06:00:22 UTC (1548223222)
[00:00:08.148] [    6.732027] Freeing unused kernel memory: 1876k freed
[00:00:08.240] [    6.824208] Write protecting the kernel read-only data: 12288k
[00:00:08.243] [    6.827224] tsc: Refined TSC clocksource calibration: 2808.076 MHz
[00:00:08.244] [    6.828202] Switched to clocksource tsc
[00:00:08.257] [    6.841316] Freeing unused kernel memory: 516k freed
[00:00:08.277] [    6.861237] Freeing unused kernel memory: 600k freed
[00:00:08.484] [    7.068081] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input2
[00:00:08.793] [    7.376153] systemd[1]: systemd 219 running in system mode. (+PAM +AUDIT +SELINUX +IMA -APPARMOR +SMACK +SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 -SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[00:00:08.802] [    7.386107] systemd[1]: Detected virtualization qemu.
[00:00:08.803] [    7.387259] systemd[1]: Detected architecture x86-64.
[00:00:08.804] [    7.387923] systemd[1]: Running in initial RAM disk.
[00:00:08.813] 
[00:00:08.819] Welcome to CentOS Linux 7 (Core) dracut-033-554.el7 (Initramfs)!
[00:00:08.821] 
[00:00:08.824] [    7.408147] systemd[1]: No hostname configured.
[00:00:08.829] [    7.413240] systemd[1]: Set hostname to <localhost>.
[00:00:08.833] [    7.417436] systemd[1]: Initializing machine ID from random generator.
[00:00:11.919] [  OK  ] Reached target Local File Systems.
[00:00:11.927] [   10.510981] systemd[1]: Reached target Local File Systems.
[00:00:11.928] [   10.511482] systemd[1]: Starting Local File Systems.
[00:00:11.932] [   10.516587] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[00:00:11.933] [   10.516589] systemd[1]: Starting Dispatch Password Requests to Console Directory Watch.
[00:00:11.934] [  OK  ] Reached target Paths.
[00:00:11.935] [   10.516589] systemd[1]: Reached target Paths.
[00:00:11.936] [   10.516589] systemd[1]: Starting Paths.
[00:00:11.943] [  OK  ] Created slice Root Slice.
[00:00:11.944] [   10.527455] systemd[1]: Created slice Root Slice.
[00:00:11.944] [   10.527455] systemd[1]: Starting Root Slice.
[00:00:11.950] [  OK  ] Listening on udev Control Socket.
[00:00:11.951] [   10.533349] systemd[1]: Listening on udev Control Socket.
[00:00:11.952] [   10.536811] systemd[1]: Starting udev Control Socket.
[00:00:11.957] [  OK  ] Listening on udev Kernel Socket.
[00:00:11.960] [   10.544089] systemd[1]: Listening on udev Kernel Socket.
[00:00:11.983] [   10.566537] systemd[1]: Starting udev Kernel Socket.
[00:00:12.037] [  OK  ] Created slice System Slice.
[00:00:12.040] [   10.624029] systemd[1]: Created slice System Slice.
[00:00:12.041] [   10.625447] systemd[1]: Starting System Slice.
[00:00:12.044] [  OK  ] Reached target Slices.
[00:00:12.046] [   10.630333] systemd[1]: Reached target Slices.
[00:00:12.047] [   10.631772] systemd[1]: Starting Slices.
[00:00:12.050] [  OK  ] Listening on Journal Socket.
[00:00:12.051] [   10.632317] systemd[1]: Listening on Journal Socket.
[00:00:12.052] [   10.636649] systemd[1]: Starting Journal Socket.
[00:00:12.178] [   10.762399] systemd[1]: Starting Journal Service...
[00:00:12.184]          Starting Journal Service...
[00:00:12.312] [   10.896116] systemd[1]: Starting Apply Kernel Variables...
[00:00:12.316]          Starting Apply Kernel Variables...
[00:00:12.519] [   11.103030] systemd[1]: Starting dracut cmdline hook...
[00:00:12.521]          Starting dracut cmdline hook...
[00:00:12.901] [   11.484334] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[00:00:12.915]          Starting Create list of required st... nodes for the current kernel...
[00:00:12.918] [  OK  ] Reached target Swap.
[00:00:12.919] [   11.500021] systemd[1]: Reached target Swap.
[00:00:12.938] [   11.521996] systemd[1]: Starting Swap.
[00:00:12.949] [  OK  ] Reached target Sockets.
[00:00:13.024] [   11.605681] systemd[1]: Reached target Sockets.
[00:00:13.025] [   11.605681] systemd[1]: Starting Sockets.
[00:00:13.027] [  OK  ] Reached target Timers.
[00:00:13.028] [   11.605681] systemd[1]: Reached target Timers.
[00:00:13.029] [   11.605681] systemd[1]: Starting Timers.
[00:00:13.431] [  OK  ] Started Apply Kernel Variables.
[00:00:13.447] [   12.031667] systemd[1]: Started Apply Kernel Variables.
[00:00:13.685] [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[00:00:13.709] [   12.293772] systemd[1]: Started Create list of required static device nodes for the current kernel.
[00:00:13.857] [   12.429729] systemd[1]: Starting Create Static Device Nodes in /dev...
[00:00:13.859]          Starting Create Static Device Nodes in /dev...
[00:00:15.105] [  OK  ] Started Create Static Device Nodes in /dev.
[00:00:15.133] [   13.717079] systemd[1]: Started Create Static Device Nodes in /dev.
[00:00:15.682] [  OK  ] Started Journal Service.
[00:00:15.684] [   14.265768] systemd[1]: Started Journal Service.
[00:00:17.655] [  OK  ] Started dracut cmdline hook.
[00:00:17.849]          Starting dracut pre-udev hook...
[00:00:18.853] [   17.435068] RPC: Registered named UNIX socket transport module.
[00:00:18.854] [   17.435068] RPC: Registered udp transport module.
[00:00:18.854] [   17.435068] RPC: Registered tcp transport module.
[00:00:18.854] [   17.435068] RPC: Registered tcp NFSv4.1 backchannel transport module.
[00:00:20.607] [  OK  ] Started dracut pre-udev hook.
[00:00:20.659]          Starting udev Kernel Device Manager...
[00:00:20.875] [  OK  ] Started udev Kernel Device Manager.
[00:00:20.967]          Starting dracut pre-trigger hook...
[00:00:21.305] [  OK  ] Started dracut pre-trigger hook.
[00:00:21.357]          Starting udev Coldplug all Devices...
[00:00:22.339]          Mounting Configuration File System...
[00:00:22.467] [  OK  ] Started udev Coldplug all Devices.
[00:00:22.599]          Starting dracut initqueue hook...
[00:00:22.949] [  OK  ] Mounted Configuration File System.
[00:00:23.069] [  OK  ] Reached target System Initialization.
[00:00:23.149] [  OK  ] Reached target Basic System.
[00:00:23.597] [   22.180822] Floppy drive(s): fd0 is 2.88M AMI BIOS
[00:00:23.733] [   22.317691] FDC 0 is a S82078B
[00:00:24.371] [   22.955816] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[00:00:24.372] [   22.956397] e1000: Copyright (c) 1999-2006 Intel Corporation.
[00:00:25.540] [   24.124559] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[00:00:25.871] [   24.455781] e1000 0000:00:03.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[00:00:25.872] [   24.456548] e1000 0000:00:03.0 eth0: Intel(R) PRO/1000 Network Connection
[00:00:25.972] [   24.556354] scsi host0: ata_piix
[00:00:25.983] [   24.567042] scsi host1: ata_piix
[00:00:25.987] [   24.568560] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xc040 irq 14
[00:00:25.988] [   24.572632] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xc048 irq 15
[00:00:26.151] [   24.735266] ata1.00: ATA-7: QEMU HARDDISK, 2.5+, max UDMA/100
[00:00:26.151] [   24.735677] ata1.00: 16777216 sectors, multi 16: LBA48 
[00:00:26.154] [   24.736601] ata1.00: configured for MWDMA2
[00:00:26.172] [   24.756606] ata2.00: ATAPI: QEMU DVD-ROM, 2.5+, max UDMA/100
[00:00:26.174] [   24.758058] ata2.00: configured for MWDMA2
[00:00:26.182] [   24.759598] scsi 0:0:0:0: Direct-Access     ATA      QEMU HARDDISK    2.5+ PQ: 0 ANSI: 5
[00:00:26.223] [   24.807081] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.5+ PQ: 0 ANSI: 5
[00:00:26.728] [   25.304171] sr 1:0:0:0: [sr0] scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[00:00:26.728] [   25.312774] cdrom: Uniform CD-ROM driver Revision: 3.20
[00:00:26.827] [   25.410954] sd 0:0:0:0: [sda] 16777216 512-byte logical blocks: (8.58 GB/8.00 GiB)
[00:00:26.834] [   25.413448] sd 0:0:0:0: [sda] Write Protect is off
[00:00:26.854] [   25.438570] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[00:00:26.875] [   25.459216]  sda: sda1
[00:00:26.938] [   25.512092] sd 0:0:0:0: [sda] Attached SCSI disk
[00:00:27.437] [  OK  ] Found device QEMU_HARDDISK 1.
[00:00:27.459] [  OK  ] Started dracut initqueue hook.
[00:00:27.490] [  OK  ] Reached target Remote File Systems (Pre).
[00:00:27.505] [  OK  ] Reached target Remote File Systems.
[00:00:27.592]          Starting File System Check on /dev/...a-914b-4104-9fd8-f9de800452ea...
[00:00:27.919] [  OK  ] Started File System Check on /dev/d...a8a-914b-4104-9fd8-f9de800452ea.
[00:00:27.992]          Mounting /sysroot...
[00:00:28.795] [   27.376405] SGI XFS with ACLs, security attributes, no debug enabled
[00:00:28.902] [   27.482294] XFS (sda1): Mounting V5 Filesystem
[00:00:29.012] [   27.596504] XFS (sda1): Ending clean mount
[00:00:29.150] [  OK  ] Mounted /sysroot.
[00:00:29.248] [  OK  ] Reached target Initrd Root File System.
[00:00:29.339]          Starting Reload Configuration from the Real Root...
[00:00:30.931] [  OK  ] Started Reload Configuration from the Real Root.
[00:00:30.972] [  OK  ] Reached target Initrd File Systems.
[00:00:31.021] [  OK  ] Reached target Initrd Default Target.
[00:00:31.075]          Starting dracut pre-pivot and cleanup hook...
[00:00:32.458] [   31.038409] random: crng init done
[00:00:32.869] [  OK  ] Started dracut pre-pivot and cleanup hook.
[00:00:32.918]          Starting Cleaning Up and Shutting Down Daemons...
[00:00:33.094] [  OK  ] Stopped target Timers.
[00:00:33.168] [  OK  ] Stopped Cleaning Up and Shutting Down Daemons.
[00:00:33.230] [  OK  ] Stopped dracut pre-pivot and cleanup hook.
[00:00:33.262]          Stopping dracut pre-pivot and cleanup hook...
[00:00:33.308] [  OK  ] Stopped target Remote File Systems.
[00:00:33.316] [  OK  ] Stopped target Remote File Systems (Pre).
[00:00:33.360] [  OK  ] Stopped dracut initqueue hook.
[00:00:33.363]          Stopping dracut initqueue hook...
[00:00:33.365] [  OK  ] Stopped target Initrd Default Target.
[00:00:33.377] [  OK  ] Stopped target Basic System.
[00:00:33.383] [  OK  ] Stopped target Paths.
[00:00:33.391] [  OK  ] Stopped target Sockets.
[00:00:33.402] [  OK  ] Stopped target System Initialization.
[00:00:33.463] [  OK  ] Stopped udev Coldplug all Devices.
[00:00:33.466]          Stopping udev Coldplug all Devices...
[00:00:33.480] [  OK  ] Stopped dracut pre-trigger hook.
[00:00:33.493]          Stopping dracut pre-trigger hook...
[00:00:33.524] [  OK  ] Stopped Apply Kernel Variables.
[00:00:33.536]          Stopping Apply Kernel Variables...
[00:00:33.558] [  OK  ] Stopped target Local File Systems.
[00:00:33.685]          Stopping udev Kernel Device Manager...
[00:00:33.726] [  OK  ] Stopped target Swap.
[00:00:33.746] [  OK  ] Stopped target Slices.
[00:00:33.824] [  OK  ] Stopped udev Kernel Device Manager.
[00:00:33.941] [  OK  ] Stopped Create Static Device Nodes in /dev.
[00:00:33.953]          Stopping Create Static Device Nodes in /dev...
[00:00:34.001] [  OK  ] Stopped Create list of required sta...ce nodes for the current kernel.
[00:00:34.018]          Stopping Create list of required st... nodes for the current kernel...
[00:00:34.071] [  OK  ] Stopped dracut pre-udev hook.
[00:00:34.075]          Stopping dracut pre-udev hook...
[00:00:34.081] [  OK  ] Stopped dracut cmdline hook.
[00:00:34.084]          Stopping dracut cmdline hook...
[00:00:34.107] [  OK  ] Closed udev Kernel Socket.
[00:00:34.117] [  OK  ] Closed udev Control Socket.
[00:00:34.138]          Starting Cleanup udevd DB...
[00:00:34.385] [  OK  ] Started Cleanup udevd DB.
[00:00:34.416] [  OK  ] Reached target Switch Root.
[00:00:34.494]          Starting Switch Root...
[00:00:34.780] [   33.352456] systemd-journald[96]: Received SIGTERM from PID 1 (systemd).
[00:00:35.382] [   33.965915] type=1404 audit(1548223249.753:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295
[00:00:36.574] [   35.158841] SELinux:  Class bpf not defined in policy.
[00:00:36.575] [   35.159115] SELinux: the above unknown classes and permissions will be allowed
[00:00:36.634] [   35.218286] type=1403 audit(1548223251.006:3): policy loaded auid=4294967295 ses=4294967295
[00:00:36.720] [   35.304774] systemd[1]: Successfully loaded SELinux policy in 1.359168s.
[00:00:36.972] [   35.552358] ip_tables: (C) 2000-2006 Netfilter Core Team
[00:00:36.973] [   35.557647] systemd[1]: Inserted module 'ip_tables'
[00:00:37.226] [   35.809250] systemd[1]: Relabelled /dev and /run in 212.850ms.
[00:00:37.331] 
[00:00:37.331] Welcome to CentOS Linux 7 (Core)!
[00:00:37.334] 
[00:00:39.232] [  OK  ] Stopped Switch Root.
[00:00:39.294] [  OK  ] Stopped Journal Service.
[00:00:39.353]          Starting Journal Service...
[00:00:39.465]          Mounting Huge Pages File System...
[00:00:39.567] [  OK  ] Listening on Delayed Shutdown Socket.
[00:00:39.578] [  OK  ] Created slice User and Session Slice.
[00:00:39.602] [  OK  ] Reached target Slices.
[00:00:39.620] [  OK  ] Reached target Swap.
[00:00:39.663] [  OK  ] Listening on udev Kernel Socket.
[00:00:39.714] [  OK  ] Created slice system-getty.slice.
[00:00:39.718] [  OK  ] Reached target Paths.
[00:00:39.750] [  OK  ] Reached target Local Encrypted Volumes.
[00:00:39.816] [  OK  ] Listening on /dev/initctl Compatibility Named Pipe.
[00:00:39.945] [  OK  ] Set up automount Arbitrary Executab...ats File System Automount Point.
[00:00:40.021] [  OK  ] Stopped File System Check on Root Device.
[00:00:40.071]          Stopping File System Check on Root Device...
[00:00:40.211]          Mounting POSIX Message Queue File System...
[00:00:40.232] [  OK  ] Created slice system-serial\x2dgetty.slice.
[00:00:40.320] [  OK  ] Listening on udev Control Socket.
[00:00:40.346] [  OK  ] Stopped target Switch Root.
[00:00:40.355] [  OK  ] Stopped target Initrd Root File System.
[00:00:40.414] [  OK  ] Created slice system-selinux\x2dpol...grate\x2dlocal\x2dchanges.slice.
[00:00:40.542]          Starting Create list of required st... nodes for the current kernel...
[00:00:40.662]          Starting Read and set NIS domainname from /etc/sysconfig/network...
[00:00:40.684] [  OK  ] Stopped target Initrd File Systems.
[00:00:40.815]          Mounting Debug File System...
[00:00:41.026]          Starting Remount Root and Kernel File Systems...
[00:00:41.169]          Starting Setup Virtual Console...
[00:00:41.525]          Starting Apply Kernel Variables...
[00:00:41.554] [  OK  ] Reached target rpc_pipefs.target.
[00:00:42.053] [  OK  ] Mounted Debug File System.
[00:00:42.116] [  OK  ] Mounted POSIX Message Queue File System.
[00:00:42.144] [  OK  ] Mounted Huge Pages File System.
[00:00:42.302] [  OK  ] Started Journal Service.
[00:00:42.735] [  OK  ] Started Create list of required sta...ce nodes for the current kernel.
[00:00:42.923] [  OK  ] Started Read and set NIS domainname from /etc/sysconfig/network.
[00:00:43.021] [  OK  ] Started Remount Root and Kernel File Systems.
[00:00:43.122] [  OK  ] Started Apply Kernel Variables.
[00:00:43.338]          Starting udev Coldplug all Devices...
[00:00:43.494]          Starting Load/Save Random Seed...
[00:00:43.560]          Starting Configure read-only root support...
[00:00:43.747]          Starting Create Static Device Nodes in /dev...
[00:00:43.935]          Starting Flush Journal to Persistent Storage...
[00:00:44.670] [  OK  ] Started Load/Save Random Seed.
[00:00:45.754] [   44.336427] systemd-journald[380]: Received request to flush runtime journal from PID 1
[00:00:46.512] [  OK  ] Started Flush Journal to Persistent Storage.
[00:00:46.948] [  OK  ] Started Create Static Device Nodes in /dev.
[00:00:47.146]          Starting udev Kernel Device Manager...
[00:00:47.152] [  OK  ] Reached target Local File Systems (Pre).
[00:00:47.667] [  OK  ] Started Configure read-only root support.
[00:00:47.727] [  OK  ] Reached target Local File Systems.
[00:00:47.866]          Starting Import network configuration from initramfs...
[00:00:48.046]          Starting Preprocess NFS configuration...
[00:00:48.232] [  OK  ] Started udev Coldplug all Devices.
[00:00:48.985] [  OK  ] Started udev Kernel Device Manager.
[00:00:50.061] [  OK  ] Started Preprocess NFS configuration.
[00:00:52.379] [  OK  ] Started Setup Virtual Console.
[00:00:53.483] [   52.065991] parport_pc 00:04: reported by Plug and Play ACPI
[00:00:53.507] [   52.091712] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[00:00:53.690] [   52.274727] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0x700, revision 0
[00:00:54.136] [   52.719743] input: PC Speaker as /devices/platform/pcspkr/input/input3
[00:00:54.880] [  OK  ] Found device /dev/ttyS0.
[00:00:55.431] [   54.015776] sd 0:0:0:0: Attached scsi generic sg0 type 0
[00:00:55.481] [   54.065588] sr 1:0:0:0: Attached scsi generic sg1 type 5
[00:00:58.541] [  OK  ] Started Import network configuration from initramfs.
[00:00:58.719] [   57.303702] ppdev: user-space parallel port driver
[00:00:59.135] [   57.714525] hrtimer: interrupt took 8142005 ns
[00:00:59.662]          Starting Create Volatile Files and Directories...
[00:01:00.086] [   58.670098] [drm] Found bochs VGA, ID 0xb0c0.
[00:01:00.086] [   58.670751] [drm] Framebuffer size 16384 kB @ 0xfd000000, mmio @ 0xfebf0000.
[00:01:00.131] [   58.715764] [TTM] Zone  kernel: Available graphics memory: 507618 kiB
[00:01:00.132] [   58.716280] [TTM] Initializing pool allocator
[00:01:00.135] [   58.719638] [TTM] Initializing DMA pool allocator
[00:01:00.449] [   58.869210] fbcon: bochsdrmfb (fb0) is primary device
[00:01:00.450] [   58.943224] Console: switching to colour frame buffer device 128x48
[00:01:00.450] [   59.034749] bochs-drm 0000:00:02.0: fb0: bochsdrmfb frame buffer device
[00:01:00.589] [   59.165817] [drm] Initialized bochs-drm 1.0.0 20130925 for 0000:00:02.0 on minor 0
[00:01:02.471] [  OK  ] Started Create Volatile Files and Directories.
[00:01:03.047]          Starting Security Auditing Service...
[00:01:03.889] [   62.472092] type=1305 audit(1548223278.260:4): audit_pid=471 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
[00:01:05.294] [  OK  ] Started Security Auditing Service.
[00:01:05.371]          Starting Update UTMP about System Boot/Shutdown...
[00:01:05.598] [  OK  ] Started Update UTMP about System Boot/Shutdown.
[00:01:05.643] [  OK  ] Reached target System Initialization.
[00:01:05.732] [  OK  ] Listening on D-Bus System Message Bus Socket.
[00:01:05.805]          Starting Initial cloud-init job (pre-networking)...
[00:01:05.855] [  OK  ] Listening on RPCbind Server Activation Socket.
[00:01:05.894] [  OK  ] Reached target Sockets.
[00:01:05.920] [  OK  ] Reached target Basic System.
[00:01:06.023]          Starting Authorization Manager...
[00:01:06.106]          Starting GSSAPI Proxy Daemon...
[00:01:06.291]          Starting Dump dmesg to /var/log/dmesg...
[00:01:06.499] [  OK  ] Started irqbalance daemon.
[00:01:06.609]          Starting irqbalance daemon...
[00:01:06.786]          Starting Login Service...
[00:01:06.933] [  OK  ] Started D-Bus System Message Bus.
[00:01:10.206]          Starting D-Bus System Message Bus...
[00:01:10.508]          Starting NTP client/server...
[00:01:10.632]          Starting RPC bind service...
[00:01:10.691] [  OK  ] Reached target Timers.
[00:01:11.357] [  OK  ] Started GSSAPI Proxy Daemon.
[00:01:11.613] [  OK  ] Started Dump dmesg to /var/log/dmesg.
[00:01:11.843] [  OK  ] Started RPC bind service.
[00:01:12.637] [  OK  ] Started Authorization Manager.
[00:01:12.716] [  OK  ] Reached target NFS client services.
[00:01:12.770] [  OK  ] Reached target Remote File Systems (Pre).
[00:01:12.800] [  OK  ] Reached target Remote File Systems.
[00:01:12.921] [  OK  ] Started Login Service.
[00:01:13.666] [  OK  ] Started NTP client/server.
[00:01:22.789] [   81.365022] cloud-init[536]: Cloud-init v. 0.7.9 running 'init-local' at Wed, 23 Jan 2019 06:01:36 +0000. Up 80.45 seconds.
[00:01:25.059] [   83.642172] blk_update_request: I/O error, dev fd0, sector 0
[00:01:25.277] [   83.861101] blk_update_request: I/O error, dev fd0, sector 0
[00:01:25.461] [   84.045675] blk_update_request: I/O error, dev fd0, sector 0
[00:01:28.321] [  OK  ] Started Initial cloud-init job (pre-networking).
[00:01:28.351] [  OK  ] Reached target Network (Pre).
[00:01:28.465]          Starting LSB: Bring up/down networking...
[ ***  ] A start job is running for LSB: Bri...down networking (56s / 5min 49s)[   94.861897] IPv6: ADDRCONF(NETDEV_UP): ens3: link is not ready
[00:01:36.282] [   94.866061] e1000: ens3 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[00:01:36.295] [   94.876565] IPv6: ADDRCONF(NETDEV_CHANGE): ens3: link becomes ready
[FAILED] Failed to start LSB: Bring up/down networking.
[00:01:52.684] See 'systemctl status network.service' for details.
[00:01:52.832]          Starting Initial cloud-init job (metadata service crawler)...
[00:01:52.871] [  OK  ] Reached target Network.
[00:01:53.057]          Starting Postfix Mail Transport Agent...
[00:01:53.240]          Starting Dynamic System Tuning Daemon...
[00:02:18.565] [  OK  ] Started Dynamic System Tuning Daemon.
[00:02:20.196] [  138.776755] cloud-init[838]: Cloud-init v. 0.7.9 running 'init' at Wed, 23 Jan 2019 06:02:33 +0000. Up 137.72 seconds.
[00:02:21.494] [  140.077914] cloud-init[838]: ci-info: +++++++++++++++++++++++++++++Net device info+++++++++++++++++++++++++++++
[00:02:21.503] [  140.086661] cloud-init[838]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:02:21.559] [  140.142069] cloud-init[838]: ci-info: | Device |  Up  |  Address  |      Mask     | Scope |     Hw-Address    |
[00:02:21.592] [  140.175584] cloud-init[838]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:02:21.653] [  140.236635] cloud-init[838]: ci-info: |  lo:   | True | 127.0.0.1 |   255.0.0.0   |   .   |         .         |
[00:02:21.743] [  140.325919] cloud-init[838]: ci-info: |  lo:   | True |     .     |       .       |   d   |         .         |
[00:02:21.801] [  140.362115] cloud-init[838]: ci-info: | ens3:  | True | 10.0.2.15 | 255.255.255.0 |   .   | 52:54:00:12:34:56 |
[00:02:21.865] [  140.424599] cloud-init[838]: ci-info: | ens3:  | True |     .     |       .       |   d   | 52:54:00:12:34:56 |
[00:02:21.901] [  140.484690] cloud-init[838]: ci-info: +--------+------+-----------+---------------+-------+-------------------+
[00:02:22.013] [  140.563043] cloud-init[838]: ci-info: +++++++++++++++++++++++++++Route IPv4 info++++++++++++++++++++++++++++
[00:02:22.048] [  140.607485] cloud-init[838]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:02:22.122] [  140.703976] cloud-init[838]: ci-info: | Route | Destination | Gateway  |    Genmask    | Interface | Flags |
[00:02:22.163] [  140.746651] cloud-init[838]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:02:22.229] [  140.812751] cloud-init[838]: ci-info: |   0   |   0.0.0.0   | 10.0.2.2 |    0.0.0.0    |    ens3   |   UG  |
[00:02:22.294] [  140.876205] cloud-init[838]: ci-info: |   1   |   10.0.2.0  | 0.0.0.0  | 255.255.255.0 |    ens3   |   U   |
[00:02:22.363] [  140.946504] cloud-init[838]: ci-info: +-------+-------------+----------+---------------+-----------+-------+
[00:02:29.455] [  OK  ] Started Postfix Mail Transport Agent.
[00:02:36.252] [  OK  ] Started Initial cloud-init job (metadata service crawler).
[00:02:36.596] [  OK  ] Reached target Network is Online.
[00:02:36.912]          Starting Crash recovery kernel arming...
[00:02:37.153]          Starting System Logging Service...
[00:02:37.398]          Starting Notify NFS peers of a restart...
[00:02:37.812]          Starting OpenSSH server daemon...
[00:02:38.057]          Starting Permit User Sessions...
[00:02:38.148] [  OK  ] Reached target Cloud-config availability.
[00:02:38.430]          Starting Apply the settings specified in cloud-config...
[00:02:40.183] [  OK  ] Started Notify NFS peers of a restart.
[00:02:40.497] [  OK  ] Started Permit User Sessions.
[00:02:41.166] [  OK  ] Started Serial Getty on ttyS0.
[00:02:41.430]          Starting Serial Getty on ttyS0...
[00:02:41.725] [  OK  ] Started Getty on tty1.
[00:02:41.866]          Starting Getty on tty1...
[00:02:41.868] [  OK  ] Reached target Login Prompts.
[00:02:42.225] [  OK  ] Started Command Scheduler.
[00:02:42.528]          Starting Command Scheduler...
[00:02:43.270] [  OK  ] Started System Logging Service.
[00:02:44.056] [FAILED] Failed to start Crash recovery kernel arming.
[00:02:44.059] See 'systemctl status kdump.service' for details.
[00:02:45.658] [  OK  ] Started OpenSSH server daemon.
[00:02:48.787] 
[00:02:48.792] CentOS Linux 7 (Core)
[00:02:48.794] Kernel 3.10.0-957.1.3.el7.x86_64 on an x86_64
[00:02:48.797] 
[00:02:48.835] cloudimg login: [  192.076760] cloud-init[992]: Cloud-init v. 0.7.9 running 'modules:config' at Wed, 23 Jan 2019 06:03:23 +0000. Up 187.26 seconds.

vanilla.txt (47,977 bytes)
iwienand

iwienand

2019-01-24 04:13

reporter   ~0033665

Investigation seems to suggest one particular patch relating to hotplug memory in udev, which is possibly causing a lot of extra forks and hence boot overhead.

Since this seems to have been put in as a workaround for a bugzilla referenced bug, I have put the details into [2]

[1] https://git.centos.org/blob/rpms!systemd.git/c7/SOURCES!0659-rules-implement-new-memory-hotplug-policy.patch
[2] https://bugzilla.redhat.com/show_bug.cgi?id=1668982
iwienand

iwienand

2019-01-24 10:56

reporter   ~0033668

Note this has been closed as dup of

https://bugzilla.redhat.com/show_bug.cgi?id=1666612

Interestingly it is noted at the other end of the spectrum on a really large system. It might be worth tracking this until the issue is resolved
pgreco

pgreco

2019-01-24 12:00

developer   ~0033669

@iwienand https://bugzilla.redhat.com/show_bug.cgi?id=1666612 is marked as private, so I can't see it, please add pablo <at> fliagreco.com.ar to the cc list if you can

iiuc if we split the first line, it should work as expected
+SUBSYSTEM!="memory", GOTO="memory_hotplug_end"
+ACTION!="add", GOTO="memory_hotplug_end"
+PROGRAM="/bin/uname -p", RESULT=="s390*", GOTO="memory_hotplug_end"
+
+ENV{.state}="online"
+PROGRAM="/bin/systemd-detect-virt", RESULT=="none", ENV{.state}="online_movable"
+ATTR{state}=="offline", ATTR{state}="$env{.state}"
+
+LABEL="memory_hotplug_end"
Does it sound logic to you?
iwienand

iwienand

2019-01-24 22:07

reporter   ~0033671

@pgreco ; thanks I have asked for the bug to be made public, I don't think there's anything private in there, just some discussion over how the value can be cached.

I can confirm splitting the rule works (http://paste.openstack.org/show/743429/)

http://paste.openstack.org/show/743427/ is an example of udev testing that previously triggered this path, but doesn't with the change.

I also regenerated the initramfs with this, and the runtime is about what it was before; i.e. there is no longer a regression

---
[00:00:06.613] Welcome to CentOS Linux 7 (Core) dracut-033-554.el7 (Initramfs)!
[00:00:35.658] Welcome to CentOS Linux 7 (Core)!
---

Issue History

Date Modified Username Field Change
2019-01-23 08:05 iwienand New Issue
2019-01-23 08:05 iwienand File Added: dracut.txt
2019-01-23 08:05 iwienand File Added: systemd.txt
2019-01-23 08:05 iwienand File Added: vanilla.txt
2019-01-24 04:13 iwienand Note Added: 0033665
2019-01-24 10:56 iwienand Note Added: 0033668
2019-01-24 12:00 pgreco Note Added: 0033669
2019-01-24 22:07 iwienand Note Added: 0033671