View Issue Details
ID | Project | Category | View Status | Date Submitted | Last Update |
---|---|---|---|---|---|
0015735 | CentOS-7 | systemd | public | 2019-01-23 08:05 | 2019-01-24 22:07 |
Reporter | iwienand | Assigned To | |||
Priority | normal | Severity | minor | Reproducibility | always |
Status | new | Resolution | open | ||
Product Version | 7.6.1810 | ||||
Summary | 0015735: Boot speed regression with 219-62.el7_6.2 | ||||
Description | We 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 Reproduce | Staring 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 Information | There is more debugging information at https://etherpad.openstack.org/p/dib-centos-slow | ||||
Tags | No tags attached. | ||||
abrt_hash | |||||
URL | |||||
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 *** 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: 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. |
|
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 |
|
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 |
|
@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? |
|
@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)! --- |
|
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 |