[SOLVED] Slower boot with kernel 4.7

jwhendy · 2016-08-19 23:27:43

After upgrading to linux-4.7, I'm noticing my boot takes quite a bit longer than usual. I have an encrypted root and enter a password which accounts for a majority of the time, but the hang is noticeable. It's right after selecting the kernel to boot in syslinux. It waits for many seconds at "Probing EDD", then changes the resolution and the next line I see is "Creating slide systemd.slice" or similar.

Here's my dmesg from this fresh boot:

[    0.000000] microcode: microcode updated early to revision 0x20, date = 2016-03-16
[    0.000000] Linux version 4.7.0-1-ARCH (builduser@tobias) (gcc version 6.1.1 20160802 (GCC) ) #1 SMP PREEMPT Mon Aug 8 22:05:58 CEST 2016
[    0.000000] Command line: BOOT_IMAGE=../vmlinuz-linux luks.uuid=7101e83b-31c0-4cdf-bc07-678e00e19c32 root=UUID=eb20c219-0df8-4051-bad2-39d57aed7b59 luks.allow-discards libata.force=1:noncq rw initrd=../intel-ucode.img,../initramfs-linux.img
[    0.000000] x86/fpu: xstate_offset[2]:  576, xstate_sizes[2]:  256
[    0.000000] x86/fpu: Supporting XSAVE feature 0x001: 'x87 floating point registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x002: 'SSE registers'
[    0.000000] x86/fpu: Supporting XSAVE feature 0x004: 'AVX registers'
[    0.000000] x86/fpu: Enabled xstate features 0x7, context size is 832 bytes, using 'standard' format.
[    0.000000] x86/fpu: Using 'eager' FPU context switches.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009dbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009dc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000e0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000003ab7efff] usable
[    0.000000] BIOS-e820: [mem 0x000000003ab7f000-0x000000003be7efff] reserved
[    0.000000] BIOS-e820: [mem 0x000000003be7f000-0x000000003bf7efff] ACPI NVS
[    0.000000] BIOS-e820: [mem 0x000000003bf7f000-0x000000003bffefff] ACPI data
[    0.000000] BIOS-e820: [mem 0x000000003bfff000-0x000000003bffffff] usable
[    0.000000] BIOS-e820: [mem 0x000000003c000000-0x000000004e1fffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000e0000000-0x00000000efffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fec00000-0x00000000fec00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed10000-0x00000000fed13fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed18000-0x00000000fed19fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fed1c000-0x00000000fed1ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fee00000-0x00000000fee00fff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000ff600000-0x00000000ffffffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000100000000-0x00000006afdfffff] usable
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.7 present.
[    0.000000] DMI: Hewlett-Packard HP ZBook 15 G2/2253, BIOS M70 Ver. 80.08 04/13/2015
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x6afe00 max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 00FF000000 mask 7FFF000000 write-protect
[    0.000000]   1 base 0000000000 mask 7FC0000000 write-back
[    0.000000]   2 base 003E000000 mask 7FFE000000 uncachable
[    0.000000]   3 base 0100000000 mask 7F00000000 write-back
[    0.000000]   4 base 0200000000 mask 7E00000000 write-back
[    0.000000]   5 base 0400000000 mask 7E00000000 write-back
[    0.000000]   6 base 0600000000 mask 7F80000000 write-back
[    0.000000]   7 base 0680000000 mask 7FC0000000 write-back
[    0.000000]   8 base 06B0000000 mask 7FF0000000 uncachable
[    0.000000]   9 base 06AFE00000 mask 7FFFE00000 uncachable
[    0.000000] x86/PAT: Configuration [0-7]: WB  WC  UC- UC  WB  WC  UC- WT  
[    0.000000] e820: last_pfn = 0x3c000 max_arch_pfn = 0x400000000
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] Base memory trampoline at [ffff880000097000] 97000 size 24576
[    0.000000] Using GB pages for direct mapping
[    0.000000] BRK [0x01b58000, 0x01b58fff] PGTABLE
[    0.000000] BRK [0x01b59000, 0x01b59fff] PGTABLE
[    0.000000] BRK [0x01b5a000, 0x01b5afff] PGTABLE
[    0.000000] BRK [0x01b5b000, 0x01b5bfff] PGTABLE
[    0.000000] BRK [0x01b5c000, 0x01b5cfff] PGTABLE
[    0.000000] RAMDISK: [mem 0x3a256000-0x3ab7efff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000FC2A0 000024 (v02 HPQOEM)
[    0.000000] ACPI: XSDT 0x000000003BFFD0E8 0000B4 (v01 HPQOEM SLIC-MPC 00000050      01000013)
[    0.000000] ACPI: FACP 0x000000003BFFB000 00010C (v05 HPQOEM 2253     00000050 HP   00000001)
[    0.000000] ACPI: DSDT 0x000000003BFCF000 026CBD (v02 HPQOEM 2253     00000001 INTL 20130927)
[    0.000000] ACPI: FACS 0x000000003BF76000 000040
[    0.000000] ACPI: HPET 0x000000003BFFA000 000038 (v01 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: APIC 0x000000003BFF9000 0000BC (v01 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: MCFG 0x000000003BFF8000 00003C (v01 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: TCPA 0x000000003BFF6000 000032 (v02 HPQOEM 2253     00000000 HP   00000001)
[    0.000000] ACPI: SSDT 0x000000003BFCC000 00042C (v01 HPQOEM SataAhci 00001000 INTL 20130927)
[    0.000000] ACPI: SSDT 0x000000003BFCB000 00048A (v01 HPQOEM PtidDevc 00001000 INTL 20130927)
[    0.000000] ACPI: SLIC 0x000000003BFCA000 000176 (v01 HPQOEM SLIC-MPC 00000001 HP   00000001)
[    0.000000] ACPI: MSDM 0x000000003BFC9000 000055 (v03 HPQOEM SLIC-MPC 00000000 HP   00000001)
[    0.000000] ACPI: FPDT 0x000000003BFC8000 000044 (v01 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: BGRT 0x000000003BFC7000 000038 (v00 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: SSDT 0x000000003BFC1000 000544 (v01 PmRef  Cpu0Ist  00003000 INTL 20130927)
[    0.000000] ACPI: SSDT 0x000000003BFC0000 000B8F (v01 CpuRef CpuSsdt  00003000 INTL 20130927)
[    0.000000] ACPI: SSDT 0x000000003BFBF000 0001D5 (v01 PmRef  LakeTiny 00003000 INTL 20130927)
[    0.000000] ACPI: SSDT 0x000000003BFBE000 000913 (v01 SaSsdt SaSsdt   00003000 INTL 20130927)
[    0.000000] ACPI: SSDT 0x000000003BFBB000 002F5C (v01 HP     NVSGTBL  00001000 INTL 20130927)
[    0.000000] ACPI: ASF! 0x000000003BFF7000 0000A5 (v32 HPQOEM 2253     00000001 HP   00000001)
[    0.000000] ACPI: DMAR 0x000000003BFBA000 0000B8 (v01 INTEL  BDW      00000001 INTL 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x00000006afdfffff]
[    0.000000] NODE_DATA(0) allocated [mem 0x6afdfb000-0x6afdfefff]
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x00000000ffffffff]
[    0.000000]   Normal   [mem 0x0000000100000000-0x00000006afdfffff]
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009cfff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x000000003ab7efff]
[    0.000000]   node   0: [mem 0x000000003bfff000-0x000000003bffffff]
[    0.000000]   node   0: [mem 0x0000000100000000-0x00000006afdfffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x00000006afdfffff]
[    0.000000] On node 0 totalpages: 6203676
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3996 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 3694 pages used for memmap
[    0.000000]   DMA32 zone: 236416 pages, LIFO batch:31
[    0.000000]   Normal zone: 93176 pages used for memmap
[    0.000000]   Normal zone: 5963264 pages, LIFO batch:31
[    0.000000] Reserving Intel graphics stolen memory at 0x3e200000-0x4e1fffff
[    0.000000] ACPI: PM-Timer IO Port: 0x1808
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x03] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x04] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x05] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x06] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x07] high edge lint[0x1])
[    0.000000] IOAPIC[0]: apic_id 0, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 8 CPUs, 0 hotplug CPUs
[    0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009d000-0x0009dfff]
[    0.000000] PM: Registered nosave memory: [mem 0x0009e000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000dffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000e0000-0x000fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x3ab7f000-0x3be7efff]
[    0.000000] PM: Registered nosave memory: [mem 0x3be7f000-0x3bf7efff]
[    0.000000] PM: Registered nosave memory: [mem 0x3bf7f000-0x3bffefff]
[    0.000000] PM: Registered nosave memory: [mem 0x3c000000-0x4e1fffff]
[    0.000000] PM: Registered nosave memory: [mem 0x4e200000-0xdfffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xe0000000-0xefffffff]
[    0.000000] PM: Registered nosave memory: [mem 0xf0000000-0xfebfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec00000-0xfec00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfec01000-0xfed0ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed10000-0xfed13fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed14000-0xfed17fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed18000-0xfed19fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed1a000-0xfed1bfff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed1c000-0xfed1ffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfed20000-0xfedfffff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee00000-0xfee00fff]
[    0.000000] PM: Registered nosave memory: [mem 0xfee01000-0xff5fffff]
[    0.000000] PM: Registered nosave memory: [mem 0xff600000-0xffffffff]
[    0.000000] e820: [mem 0x4e200000-0xdfffffff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] clocksource: refined-jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370452778343963 ns
[    0.000000] setup_percpu: NR_CPUS:128 nr_cpumask_bits:128 nr_cpu_ids:8 nr_node_ids:1
[    0.000000] percpu: Embedded 33 pages/cpu @ffff8806afa00000 s97944 r8192 d29032 u262144
[    0.000000] pcpu-alloc: s97944 r8192 d29032 u262144 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 2 3 4 5 6 7 
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 6106721
[    0.000000] Policy zone: Normal
[    0.000000] Kernel command line: BOOT_IMAGE=../vmlinuz-linux luks.uuid=7101e83b-31c0-4cdf-bc07-678e00e19c32 root=UUID=eb20c219-0df8-4051-bad2-39d57aed7b59 luks.allow-discards libata.force=1:noncq rw initrd=../intel-ucode.img,../initramfs-linux.img
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Calgary: detecting Calgary via BIOS EBDA area
[    0.000000] Calgary: Unable to locate Rio Grande table in EBDA - bailing!
[    0.000000] Memory: 24337096K/24814704K available (6024K kernel code, 977K rwdata, 1836K rodata, 1244K init, 1164K bss, 477608K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=8, Nodes=1
[    0.000000] Preemptible hierarchical RCU implementation.
[    0.000000] 	Build-time adjustment of leaf fanout to 64.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=128 to nr_cpu_ids=8.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=8
[    0.000000] NR_IRQS:8448 nr_irqs:488 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] clocksource: hpet: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 133484882848 ns
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Fast TSC calibration using PIT
[    0.000000] tsc: Detected 2793.611 MHz processor
[    0.000020] Calibrating delay loop (skipped), value calculated using timer frequency.. 5589.02 BogoMIPS (lpj=9312036)
[    0.000022] pid_max: default: 32768 minimum: 301
[    0.000027] ACPI: Core revision 20160422
[    0.013028] ACPI: 8 ACPI AML tables successfully acquired and loaded

[    0.013046] Security Framework initialized
[    0.013047] Yama: becoming mindful.
[    0.014449] Dentry cache hash table entries: 4194304 (order: 13, 33554432 bytes)
[    0.019435] Inode-cache hash table entries: 2097152 (order: 12, 16777216 bytes)
[    0.021605] Mount-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.021630] Mountpoint-cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.021901] CPU: Physical Processor ID: 0
[    0.021902] CPU: Processor Core ID: 0
[    0.021906] ENERGY_PERF_BIAS: Set to 'normal', was 'performance'
[    0.021907] ENERGY_PERF_BIAS: View and update with x86_energy_perf_policy(8)
[    0.021910] mce: CPU supports 9 MCE banks
[    0.021918] CPU0: Thermal monitoring enabled (TM1)
[    0.021932] process: using mwait in idle threads
[    0.021935] Last level iTLB entries: 4KB 1024, 2MB 1024, 4MB 1024
[    0.021935] Last level dTLB entries: 4KB 1024, 2MB 1024, 4MB 1024, 1GB 4
[    0.022245] Freeing SMP alternatives memory: 24K (ffffffff81a2d000 - ffffffff81a33000)
[    0.023240] ftrace: allocating 23790 entries in 93 pages
[    0.031271] smpboot: Max logical packages: 2
[    0.031273] smpboot: APIC(0) Converting physical 0 to logical package 0
[    0.031280] DMAR: Host address width 39
[    0.031281] DMAR: DRHD base: 0x000000fed90000 flags: 0x0
[    0.031285] DMAR: dmar0: reg_base_addr fed90000 ver 1:0 cap c0000020660462 ecap f0101a
[    0.031286] DMAR: DRHD base: 0x000000fed91000 flags: 0x1
[    0.031290] DMAR: dmar1: reg_base_addr fed91000 ver 1:0 cap d2008c20660462 ecap f010da
[    0.031291] DMAR: RMRR base: 0x0000003be1e000 end: 0x0000003be3dfff
[    0.031292] DMAR: RMRR base: 0x0000003e000000 end: 0x0000004e1fffff
[    0.031293] DMAR-IR: IOAPIC id 0 under DRHD base  0xfed91000 IOMMU 1
[    0.031294] DMAR-IR: HPET id 0 under DRHD base 0xfed91000
[    0.031295] DMAR-IR: x2apic is disabled because BIOS sets x2apic opt out bit.
[    0.031296] DMAR-IR: Use 'intremap=no_x2apic_optout' to override the BIOS setting.
[    0.031548] DMAR-IR: Enabled IRQ remapping in xapic mode
[    0.031549] x2apic: IRQ remapping doesn't support X2APIC mode
[    0.031958] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.064966] TSC deadline timer enabled
[    0.064969] smpboot: CPU0: Intel(R) Core(TM) i7-4810MQ CPU @ 2.80GHz (family: 0x6, model: 0x3c, stepping: 0x3)
[    0.064972] Performance Events: PEBS fmt2+, 16-deep LBR, Haswell events, full-width counters, Intel PMU driver.
[    0.064988] ... version:                3
[    0.064989] ... bit width:              48
[    0.064990] ... generic registers:      4
[    0.064990] ... value mask:             0000ffffffffffff
[    0.064991] ... max period:             0000ffffffffffff
[    0.064992] ... fixed-purpose events:   3
[    0.064992] ... event mask:             000000070000000f
[    0.088455] NMI watchdog: enabled on all CPUs, permanently consumes one hw-PMU counter.
[    0.105069] x86: Booting SMP configuration:
[    0.105071] .... node  #0, CPUs:      #1 #2 #3 #4 #5 #6 #7
[    0.770696] x86: Booted up 1 node, 8 CPUs
[    0.770700] smpboot: Total of 8 processors activated (44724.36 BogoMIPS)
[    0.777541] devtmpfs: initialized
[    0.777591] x86/mm: Memory block size: 128MB
[    0.780566] PM: Registering ACPI NVS region [mem 0x3be7f000-0x3bf7efff] (1048576 bytes)
[    0.780626] clocksource: jiffies: mask: 0xffffffff max_cycles: 0xffffffff, max_idle_ns: 6370867519511994 ns
[    0.780660] pinctrl core: initialized pinctrl subsystem
[    0.780697] RTC time: 23:16:14, date: 08/19/16
[    0.780781] NET: Registered protocol family 16
[    0.793662] cpuidle: using governor ladder
[    0.806998] cpuidle: using governor menu
[    0.807016] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[    0.807018] ACPI: bus type PCI registered
[    0.807019] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.807066] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[    0.807068] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
[    0.807077] PCI: Using configuration type 1 for base access
[    0.807093] core: PMU erratum BJ122, BV98, HSD29 worked around, HT is on
[    0.820410] HugeTLB registered 1 GB page size, pre-allocated 0 pages
[    0.820411] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.820591] ACPI: Added _OSI(Module Device)
[    0.820593] ACPI: Added _OSI(Processor Device)
[    0.820594] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.820595] ACPI: Added _OSI(Processor Aggregator Device)
[    0.825539] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query ignored
[    0.830103] ACPI: Dynamic OEM Table Load:
[    0.830110] ACPI: SSDT 0xFFFF880693401800 0003F3 (v01 PmRef  Cpu0Cst  00003001 INTL 20130927)
[    0.830707] ACPI: Dynamic OEM Table Load:
[    0.830713] ACPI: SSDT 0xFFFF8806938F3000 0005DB (v01 PmRef  ApIst    00003000 INTL 20130927)
[    0.831333] ACPI: Dynamic OEM Table Load:
[    0.831337] ACPI: SSDT 0xFFFF880693BEE000 000119 (v01 PmRef  ApCst    00003000 INTL 20130927)
[    0.832820] ACPI : EC: EC started
[    5.666961] ACPI: Interpreter enabled
[    5.666983] ACPI: (supports S0 S3 S4 S5)
[    5.666985] ACPI: Using IOAPIC for interrupt routing
[    5.667000] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    5.668749] ACPI: Power Resource [APPR] (off)
[    5.670526] ACPI: Power Resource [COMP] (off)
[    5.670723] ACPI: Power Resource [LPP] (on)
[    5.673487] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-fe])
[    5.673492] acpi PNP0A08:00: _OSC: OS supports [ExtendedConfig ASPM ClockPM Segments MSI]
[    5.674660] acpi PNP0A08:00: _OSC: OS now controls [PCIeHotplug PME AER PCIeCapability]
[    5.674662] acpi PNP0A08:00: FADT indicates ASPM is unsupported, using BIOS configuration
[    5.675044] PCI host bridge to bus 0000:00
[    5.675047] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    5.675048] pci_bus 0000:00: root bus resource [io  0x0d00-0xffff window]
[    5.675049] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    5.675051] pci_bus 0000:00: root bus resource [mem 0x4e200000-0xdfffffff window]
[    5.675052] pci_bus 0000:00: root bus resource [mem 0xf0000000-0xfed3ffff window]
[    5.675053] pci_bus 0000:00: root bus resource [mem 0xfed45000-0xfedfffff window]
[    5.675054] pci_bus 0000:00: root bus resource [mem 0xfee01000-0xffffffff window]
[    5.675055] pci_bus 0000:00: root bus resource [bus 00-fe]
[    5.675061] pci 0000:00:00.0: [8086:0c04] type 00 class 0x060000
[    5.675154] pci 0000:00:01.0: [8086:0c01] type 01 class 0x060400
[    5.675187] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
[    5.675290] pci 0000:00:02.0: [8086:0416] type 00 class 0x030000
[    5.675299] pci 0000:00:02.0: reg 0x10: [mem 0xce400000-0xce7fffff 64bit]
[    5.675303] pci 0000:00:02.0: reg 0x18: [mem 0xa0000000-0xafffffff 64bit pref]
[    5.675307] pci 0000:00:02.0: reg 0x20: [io  0x7000-0x703f]
[    5.675418] pci 0000:00:14.0: [8086:8c31] type 00 class 0x0c0330
[    5.675433] pci 0000:00:14.0: reg 0x10: [mem 0xce920000-0xce92ffff 64bit]
[    5.675484] pci 0000:00:14.0: PME# supported from D3hot D3cold
[    5.675534] pci 0000:00:14.0: System wakeup disabled by ACPI
[    5.675563] pci 0000:00:16.0: [8086:8c3a] type 00 class 0x078000
[    5.675579] pci 0000:00:16.0: reg 0x10: [mem 0xce938000-0xce93800f 64bit]
[    5.675641] pci 0000:00:16.0: PME# supported from D0 D3hot D3cold
[    5.675715] pci 0000:00:16.3: [8086:8c3d] type 00 class 0x070002
[    5.675728] pci 0000:00:16.3: reg 0x10: [io  0x70b0-0x70b7]
[    5.675735] pci 0000:00:16.3: reg 0x14: [mem 0xce93b000-0xce93bfff]
[    5.675866] pci 0000:00:19.0: [8086:153a] type 00 class 0x020000
[    5.675880] pci 0000:00:19.0: reg 0x10: [mem 0xce900000-0xce91ffff]
[    5.675888] pci 0000:00:19.0: reg 0x14: [mem 0xce93a000-0xce93afff]
[    5.675895] pci 0000:00:19.0: reg 0x18: [io  0x7080-0x709f]
[    5.675951] pci 0000:00:19.0: PME# supported from D0 D3hot D3cold
[    5.676001] pci 0000:00:19.0: System wakeup disabled by ACPI
[    5.676031] pci 0000:00:1a.0: [8086:8c2d] type 00 class 0x0c0320
[    5.676046] pci 0000:00:1a.0: reg 0x10: [mem 0xce936000-0xce9363ff]
[    5.676127] pci 0000:00:1a.0: PME# supported from D0 D3hot D3cold
[    5.676178] pci 0000:00:1a.0: System wakeup disabled by ACPI
[    5.676208] pci 0000:00:1b.0: [8086:8c20] type 00 class 0x040300
[    5.676223] pci 0000:00:1b.0: reg 0x10: [mem 0xce930000-0xce933fff 64bit]
[    5.676288] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    5.676366] pci 0000:00:1c.0: [8086:8c10] type 01 class 0x060400
[    5.676436] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    5.676540] pci 0000:00:1c.4: [8086:8c18] type 01 class 0x060400
[    5.676604] pci 0000:00:1c.4: PME# supported from D0 D3hot D3cold
[    5.676703] pci 0000:00:1c.6: [8086:8c1c] type 01 class 0x060400
[    5.676767] pci 0000:00:1c.6: PME# supported from D0 D3hot D3cold
[    5.676838] pci 0000:00:1c.6: System wakeup disabled by ACPI
[    5.676869] pci 0000:00:1d.0: [8086:8c26] type 00 class 0x0c0320
[    5.676885] pci 0000:00:1d.0: reg 0x10: [mem 0xce935000-0xce9353ff]
[    5.676971] pci 0000:00:1d.0: PME# supported from D0 D3hot D3cold
[    5.677024] pci 0000:00:1d.0: System wakeup disabled by ACPI
[    5.677055] pci 0000:00:1f.0: [8086:8c4f] type 00 class 0x060100
[    5.677221] pci 0000:00:1f.2: [8086:8c03] type 00 class 0x010601
[    5.677233] pci 0000:00:1f.2: reg 0x10: [io  0x70a8-0x70af]
[    5.677240] pci 0000:00:1f.2: reg 0x14: [io  0x70bc-0x70bf]
[    5.677246] pci 0000:00:1f.2: reg 0x18: [io  0x70a0-0x70a7]
[    5.677253] pci 0000:00:1f.2: reg 0x1c: [io  0x70b8-0x70bb]
[    5.677260] pci 0000:00:1f.2: reg 0x20: [io  0x7060-0x707f]
[    5.677266] pci 0000:00:1f.2: reg 0x24: [mem 0xce934000-0xce9347ff]
[    5.677302] pci 0000:00:1f.2: PME# supported from D3hot
[    5.677374] pci 0000:00:1f.3: [8086:8c22] type 00 class 0x0c0500
[    5.677388] pci 0000:00:1f.3: reg 0x10: [mem 0xce937000-0xce9370ff 64bit]
[    5.677406] pci 0000:00:1f.3: reg 0x20: [io  0xef80-0xef9f]
[    5.677540] pci 0000:01:00.0: [10de:11fc] type 00 class 0x030000
[    5.677554] pci 0000:01:00.0: reg 0x10: [mem 0xcd000000-0xcdffffff]
[    5.677568] pci 0000:01:00.0: reg 0x14: [mem 0x50000000-0x5fffffff 64bit pref]
[    5.677581] pci 0000:01:00.0: reg 0x1c: [mem 0x60000000-0x61ffffff 64bit pref]
[    5.677589] pci 0000:01:00.0: reg 0x24: [io  0x6000-0x607f]
[    5.677598] pci 0000:01:00.0: reg 0x30: [mem 0xfff80000-0xffffffff pref]
[    5.677831] pci 0000:01:00.1: [10de:0e0b] type 00 class 0x040300
[    5.677848] pci 0000:01:00.1: reg 0x10: [mem 0xce000000-0xce003fff]
[    5.683575] pci 0000:00:01.0: PCI bridge to [bus 01]
[    5.683577] pci 0000:00:01.0:   bridge window [io  0x6000-0x6fff]
[    5.683579] pci 0000:00:01.0:   bridge window [mem 0xcd000000-0xce0fffff]
[    5.683581] pci 0000:00:01.0:   bridge window [mem 0x50000000-0x61ffffff 64bit pref]
[    5.683631] pci 0000:00:1c.0: PCI bridge to [bus 02-3a]
[    5.683636] pci 0000:00:1c.0:   bridge window [mem 0xb0000000-0xc60fffff]
[    5.683641] pci 0000:00:1c.0:   bridge window [mem 0x70000000-0x91ffffff 64bit pref]
[    5.683763] pci 0000:3b:00.0: [1b4b:9183] type 00 class 0x010601
[    5.683778] pci 0000:3b:00.0: reg 0x10: [io  0x5028-0x502f]
[    5.683787] pci 0000:3b:00.0: reg 0x14: [io  0x5034-0x5037]
[    5.683796] pci 0000:3b:00.0: reg 0x18: [io  0x5020-0x5027]
[    5.683805] pci 0000:3b:00.0: reg 0x1c: [io  0x5030-0x5033]
[    5.683814] pci 0000:3b:00.0: reg 0x20: [io  0x5000-0x501f]
[    5.683824] pci 0000:3b:00.0: reg 0x24: [mem 0xce800000-0xce8001ff]
[    5.683833] pci 0000:3b:00.0: reg 0x30: [mem 0xfffe0000-0xffffffff pref]
[    5.683888] pci 0000:3b:00.0: PME# supported from D3hot
[    5.690258] pci 0000:00:1c.4: PCI bridge to [bus 3b]
[    5.690261] pci 0000:00:1c.4:   bridge window [io  0x5000-0x5fff]
[    5.690264] pci 0000:00:1c.4:   bridge window [mem 0xce800000-0xce8fffff]
[    5.690317] pci 0000:3c:00.0: [12d8:2404] type 01 class 0x060400
[    5.690433] pci 0000:3c:00.0: supports D1 D2
[    5.690434] pci 0000:3c:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    5.696905] pci 0000:00:1c.6: PCI bridge to [bus 3c-60]
[    5.696909] pci 0000:00:1c.6:   bridge window [io  0x3000-0x4fff]
[    5.696912] pci 0000:00:1c.6:   bridge window [mem 0xc8000000-0xcc1fffff]
[    5.696974] pci 0000:3d:01.0: [12d8:2404] type 01 class 0x060400
[    5.697091] pci 0000:3d:01.0: supports D1 D2
[    5.697092] pci 0000:3d:01.0: PME# supported from D0 D1 D2 D3hot D3cold
[    5.697153] pci 0000:3d:02.0: [12d8:2404] type 01 class 0x060400
[    5.697269] pci 0000:3d:02.0: supports D1 D2
[    5.697270] pci 0000:3d:02.0: PME# supported from D0 D1 D2 D3hot D3cold
[    5.697329] pci 0000:3d:03.0: [12d8:2404] type 01 class 0x060400
[    5.697445] pci 0000:3d:03.0: supports D1 D2
[    5.697446] pci 0000:3d:03.0: PME# supported from D0 D1 D2 D3hot D3cold
[    5.697523] pci 0000:3c:00.0: PCI bridge to [bus 3d-60]
[    5.697529] pci 0000:3c:00.0:   bridge window [io  0x3000-0x4fff]
[    5.697533] pci 0000:3c:00.0:   bridge window [mem 0xc8000000-0xcc1fffff]
[    5.697685] pci 0000:3e:00.0: [8086:08b1] type 00 class 0x028000
[    5.697735] pci 0000:3e:00.0: reg 0x10: [mem 0x00000000-0x00001fff 64bit]
[    5.698069] pci 0000:3e:00.0: PME# supported from D0 D3hot D3cold
[    5.703662] pci 0000:3d:01.0: PCI bridge to [bus 3e]
[    5.703670] pci 0000:3d:01.0:   bridge window [mem 0xcc100000-0xcc1fffff]
[    5.703721] pci 0000:3d:02.0: PCI bridge to [bus 3f-5f]
[    5.703728] pci 0000:3d:02.0:   bridge window [io  0x3000-0x4fff]
[    5.703731] pci 0000:3d:02.0:   bridge window [mem 0xc8000000-0xcbffffff]
[    5.703802] pci 0000:60:00.0: [10ec:5249] type 00 class 0xff0000
[    5.703831] pci 0000:60:00.0: reg 0x10: [mem 0xcc000000-0xcc000fff]
[    5.704041] pci 0000:60:00.0: supports D1 D2
[    5.704042] pci 0000:60:00.0: PME# supported from D1 D2 D3hot D3cold
[    5.710256] pci 0000:3d:03.0: PCI bridge to [bus 60]
[    5.710265] pci 0000:3d:03.0:   bridge window [mem 0xcc000000-0xcc0fffff]
[    5.711076] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711111] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711144] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711176] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 10 *11 12 14 15)
[    5.711208] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 *5 6 10 11 12 14 15)
[    5.711240] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    5.711272] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711305] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    5.711566] ACPI: Enabled 7 GPEs in block 00 to 3F
[    5.711639] ACPI : EC: EC stopped
[    5.711667] ACPI : EC: GPE = 0x16, I/O: command/status = 0x66, data = 0x62
[    5.711668] ACPI : EC: EC started
[   10.526295] vgaarb: setting as boot device: PCI:0000:00:02.0
[   10.526298] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[   10.526302] vgaarb: device added: PCI:0000:01:00.0,decodes=io+mem,owns=none,locks=none
[   10.526304] vgaarb: loaded
[   10.526305] vgaarb: bridge control possible 0000:01:00.0
[   10.526306] vgaarb: no bridge control possible 0000:00:02.0
[   10.526353] PCI: Using ACPI for IRQ routing
[   10.532124] PCI: pci_cache_line_size set to 64 bytes
[   10.532351] e820: reserve RAM buffer [mem 0x0009dc00-0x0009ffff]
[   10.532353] e820: reserve RAM buffer [mem 0x3ab7f000-0x3bffffff]
[   10.532354] e820: reserve RAM buffer [mem 0x6afe00000-0x6afffffff]
[   10.532421] NetLabel: Initializing
[   10.532422] NetLabel:  domain hash size = 128
[   10.532423] NetLabel:  protocols = UNLABELED CIPSOv4
[   10.532431] NetLabel:  unlabeled traffic allowed by default
[   10.532474] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0, 0, 0, 0, 0, 0
[   10.532478] hpet0: 8 comparators, 64-bit 14.318180 MHz counter
[   10.534490] amd_nb: Cannot enumerate AMD northbridges
[   10.534501] clocksource: Switched to clocksource hpet
[   10.538739] VFS: Disk quotas dquot_6.6.0
[   10.538753] VFS: Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[   10.538804] pnp: PnP ACPI init
[   10.538954] pnp 00:00: disabling [mem 0x00000000-0x00000fff] because it overlaps 0000:3e:00.0 BAR 0 [mem 0x00000000-0x00001fff 64bit]
[   10.538978] system 00:00: [mem 0xfed1c000-0xfed1ffff] has been reserved
[   10.538979] system 00:00: [mem 0xfed10000-0xfed17fff] could not be reserved
[   10.538981] system 00:00: [mem 0xfed18000-0xfed18fff] has been reserved
[   10.538982] system 00:00: [mem 0xfed19000-0xfed19fff] has been reserved
[   10.538983] system 00:00: [mem 0xe0000000-0xefffffff] has been reserved
[   10.538984] system 00:00: [mem 0xfed20000-0xfed3ffff] has been reserved
[   10.538986] system 00:00: [mem 0xfed90000-0xfed93fff] could not be reserved
[   10.538987] system 00:00: [mem 0xfed45000-0xfed8ffff] has been reserved
[   10.538988] system 00:00: [mem 0xfec00000-0xfec00fff] could not be reserved
[   10.538989] system 00:00: [mem 0x4e210000-0x4e21ffff] has been reserved
[   10.538991] system 00:00: [mem 0x4e200000-0x4e20ffff] has been reserved
[   10.538993] system 00:00: Plug and Play ACPI device, IDs PNP0c02 (active)
[   10.539151] system 00:01: [io  0x0200-0x020f] has been reserved
[   10.539152] system 00:01: [io  0x0220-0x027f] has been reserved
[   10.539154] system 00:01: [io  0xffff] has been reserved
[   10.539155] system 00:01: [io  0xffff] has been reserved
[   10.539156] system 00:01: [io  0xffff] has been reserved
[   10.539157] system 00:01: [io  0x1800-0x18fe] has been reserved
[   10.539158] system 00:01: [io  0xef80-0xef9f] has been reserved
[   10.539160] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[   10.539197] system 00:02: [io  0x0800-0x087f] has been reserved
[   10.539199] system 00:02: Plug and Play ACPI device, IDs PNP0c02 (active)
[   10.539215] pnp 00:03: Plug and Play ACPI device, IDs PNP0b00 (active)
[   10.539507] pnp 00:04: [dma 0 disabled]
[   10.539555] pnp 00:04: Plug and Play ACPI device, IDs PNP0401 (active)
[   10.539570] pnp 00:05: Plug and Play ACPI device, IDs HPQ8002 PNP0303 (active)
[   10.539586] pnp 00:06: Plug and Play ACPI device, IDs SYN3023 SYN0100 SYN0002 PNP0f13 (active)
[   10.539934] pnp 00:07: Plug and Play ACPI device, IDs IFX0102 PNP0c31 (active)
[   10.539959] pnp: PnP ACPI: found 8 devices
[   10.545855] clocksource: acpi_pm: mask: 0xffffff max_cycles: 0xffffff, max_idle_ns: 2085701024 ns
[   10.545858] pci 0000:01:00.0: can't claim BAR 6 [mem 0xfff80000-0xffffffff pref]: no compatible bridge window
[   10.545860] pci 0000:3b:00.0: can't claim BAR 6 [mem 0xfffe0000-0xffffffff pref]: no compatible bridge window
[   10.545873] pci 0000:00:1c.0: bridge window [io  0x1000-0x0fff] to [bus 02-3a] add_size 1000
[   10.545896] pci 0000:3d:02.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 3f-5f] add_size 200000 add_align 100000
[   10.545906] pci 0000:3d:03.0: bridge window [io  0x1000-0x0fff] to [bus 60] add_size 1000
[   10.545908] pci 0000:3d:03.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 60] add_size 200000 add_align 100000
[   10.545917] pci 0000:3d:02.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545919] pci 0000:3d:02.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545920] pci 0000:3d:03.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545922] pci 0000:3d:03.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545923] pci 0000:3c:00.0: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 3d-60] add_size 400000 add_align 100000
[   10.545930] pci 0000:3c:00.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545931] pci 0000:3c:00.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545932] pci 0000:00:1c.6: bridge window [mem 0x00100000-0x000fffff 64bit pref] to [bus 3c-60] add_size 400000 add_align 100000
[   10.545935] pci 0000:00:1c.6: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545936] pci 0000:00:1c.6: res[15]=[mem 0x00100000-0x004fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545938] pci 0000:00:1c.0: res[13]=[io  0x1000-0x0fff] res_to_dev_res add_size 1000 min_align 1000
[   10.545939] pci 0000:00:1c.0: res[13]=[io  0x1000-0x1fff] res_to_dev_res add_size 1000 min_align 1000
[   10.545944] pci 0000:00:1c.6: BAR 15: assigned [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.545946] pci 0000:00:1c.0: BAR 13: assigned [io  0x2000-0x2fff]
[   10.545949] pci 0000:01:00.0: BAR 6: assigned [mem 0xce080000-0xce0fffff pref]
[   10.545950] pci 0000:00:01.0: PCI bridge to [bus 01]
[   10.545952] pci 0000:00:01.0:   bridge window [io  0x6000-0x6fff]
[   10.545955] pci 0000:00:01.0:   bridge window [mem 0xcd000000-0xce0fffff]
[   10.545957] pci 0000:00:01.0:   bridge window [mem 0x50000000-0x61ffffff 64bit pref]
[   10.545960] pci 0000:00:1c.0: PCI bridge to [bus 02-3a]
[   10.545962] pci 0000:00:1c.0:   bridge window [io  0x2000-0x2fff]
[   10.545966] pci 0000:00:1c.0:   bridge window [mem 0xb0000000-0xc60fffff]
[   10.545970] pci 0000:00:1c.0:   bridge window [mem 0x70000000-0x91ffffff 64bit pref]
[   10.545976] pci 0000:3b:00.0: BAR 6: assigned [mem 0xce820000-0xce83ffff pref]
[   10.545977] pci 0000:00:1c.4: PCI bridge to [bus 3b]
[   10.545979] pci 0000:00:1c.4:   bridge window [io  0x5000-0x5fff]
[   10.545983] pci 0000:00:1c.4:   bridge window [mem 0xce800000-0xce8fffff]
[   10.545991] pci 0000:3c:00.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545992] pci 0000:3c:00.0: res[15]=[mem 0x00100000-0x004fffff 64bit pref] res_to_dev_res add_size 400000 min_align 100000
[   10.545994] pci 0000:3c:00.0: BAR 15: assigned [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.545996] pci 0000:3d:02.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545997] pci 0000:3d:02.0: res[15]=[mem 0x00100000-0x002fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.545998] pci 0000:3d:03.0: res[15]=[mem 0x00100000-0x000fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.546000] pci 0000:3d:03.0: res[15]=[mem 0x00100000-0x002fffff 64bit pref] res_to_dev_res add_size 200000 min_align 100000
[   10.546001] pci 0000:3d:03.0: res[13]=[io  0x1000-0x0fff] res_to_dev_res add_size 1000 min_align 1000
[   10.546002] pci 0000:3d:03.0: res[13]=[io  0x1000-0x1fff] res_to_dev_res add_size 1000 min_align 1000
[   10.546004] pci 0000:3d:02.0: BAR 15: assigned [mem 0x4e300000-0x4e4fffff 64bit pref]
[   10.546005] pci 0000:3d:03.0: BAR 15: assigned [mem 0x4e500000-0x4e6fffff 64bit pref]
[   10.546007] pci 0000:3d:03.0: BAR 13: no space for [io  size 0x1000]
[   10.546008] pci 0000:3d:03.0: BAR 13: failed to assign [io  size 0x1000]
[   10.546009] pci 0000:3d:03.0: BAR 13: no space for [io  size 0x1000]
[   10.546010] pci 0000:3d:03.0: BAR 13: failed to assign [io  size 0x1000]
[   10.546013] pci 0000:3e:00.0: BAR 0: assigned [mem 0xcc100000-0xcc101fff 64bit]
[   10.546110] pci 0000:3d:01.0: PCI bridge to [bus 3e]
[   10.546115] pci 0000:3d:01.0:   bridge window [mem 0xcc100000-0xcc1fffff]
[   10.546123] pci 0000:3d:02.0: PCI bridge to [bus 3f-5f]
[   10.546126] pci 0000:3d:02.0:   bridge window [io  0x3000-0x4fff]
[   10.546131] pci 0000:3d:02.0:   bridge window [mem 0xc8000000-0xcbffffff]
[   10.546135] pci 0000:3d:02.0:   bridge window [mem 0x4e300000-0x4e4fffff 64bit pref]
[   10.546142] pci 0000:3d:03.0: PCI bridge to [bus 60]
[   10.546147] pci 0000:3d:03.0:   bridge window [mem 0xcc000000-0xcc0fffff]
[   10.546151] pci 0000:3d:03.0:   bridge window [mem 0x4e500000-0x4e6fffff 64bit pref]
[   10.546158] pci 0000:3c:00.0: PCI bridge to [bus 3d-60]
[   10.546160] pci 0000:3c:00.0:   bridge window [io  0x3000-0x4fff]
[   10.546166] pci 0000:3c:00.0:   bridge window [mem 0xc8000000-0xcc1fffff]
[   10.546170] pci 0000:3c:00.0:   bridge window [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.546176] pci 0000:00:1c.6: PCI bridge to [bus 3c-60]
[   10.546178] pci 0000:00:1c.6:   bridge window [io  0x3000-0x4fff]
[   10.546182] pci 0000:00:1c.6:   bridge window [mem 0xc8000000-0xcc1fffff]
[   10.546186] pci 0000:00:1c.6:   bridge window [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.546192] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7 window]
[   10.546193] pci_bus 0000:00: resource 5 [io  0x0d00-0xffff window]
[   10.546194] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff window]
[   10.546195] pci_bus 0000:00: resource 7 [mem 0x4e200000-0xdfffffff window]
[   10.546196] pci_bus 0000:00: resource 8 [mem 0xf0000000-0xfed3ffff window]
[   10.546197] pci_bus 0000:00: resource 9 [mem 0xfed45000-0xfedfffff window]
[   10.546198] pci_bus 0000:00: resource 10 [mem 0xfee01000-0xffffffff window]
[   10.546200] pci_bus 0000:01: resource 0 [io  0x6000-0x6fff]
[   10.546201] pci_bus 0000:01: resource 1 [mem 0xcd000000-0xce0fffff]
[   10.546202] pci_bus 0000:01: resource 2 [mem 0x50000000-0x61ffffff 64bit pref]
[   10.546203] pci_bus 0000:02: resource 0 [io  0x2000-0x2fff]
[   10.546204] pci_bus 0000:02: resource 1 [mem 0xb0000000-0xc60fffff]
[   10.546205] pci_bus 0000:02: resource 2 [mem 0x70000000-0x91ffffff 64bit pref]
[   10.546206] pci_bus 0000:3b: resource 0 [io  0x5000-0x5fff]
[   10.546207] pci_bus 0000:3b: resource 1 [mem 0xce800000-0xce8fffff]
[   10.546209] pci_bus 0000:3c: resource 0 [io  0x3000-0x4fff]
[   10.546210] pci_bus 0000:3c: resource 1 [mem 0xc8000000-0xcc1fffff]
[   10.546211] pci_bus 0000:3c: resource 2 [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.546212] pci_bus 0000:3d: resource 0 [io  0x3000-0x4fff]
[   10.546213] pci_bus 0000:3d: resource 1 [mem 0xc8000000-0xcc1fffff]
[   10.546214] pci_bus 0000:3d: resource 2 [mem 0x4e300000-0x4e6fffff 64bit pref]
[   10.546215] pci_bus 0000:3e: resource 1 [mem 0xcc100000-0xcc1fffff]
[   10.546216] pci_bus 0000:3f: resource 0 [io  0x3000-0x4fff]
[   10.546217] pci_bus 0000:3f: resource 1 [mem 0xc8000000-0xcbffffff]
[   10.546219] pci_bus 0000:3f: resource 2 [mem 0x4e300000-0x4e4fffff 64bit pref]
[   10.546220] pci_bus 0000:60: resource 1 [mem 0xcc000000-0xcc0fffff]
[   10.546221] pci_bus 0000:60: resource 2 [mem 0x4e500000-0x4e6fffff 64bit pref]
[   10.546239] NET: Registered protocol family 2
[   10.546408] TCP established hash table entries: 262144 (order: 9, 2097152 bytes)
[   10.546673] TCP bind hash table entries: 65536 (order: 8, 1048576 bytes)
[   10.546782] TCP: Hash tables configured (established 262144 bind 65536)
[   10.546812] UDP hash table entries: 16384 (order: 7, 524288 bytes)
[   10.546882] UDP-Lite hash table entries: 16384 (order: 7, 524288 bytes)
[   10.546969] NET: Registered protocol family 1
[   10.546980] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[   10.546986] pci 0000:00:14.0: enabling device (0000 -> 0002)
[   10.547534] PCI: CLS 64 bytes, default 64
[   10.547566] Unpacking initramfs...
[   10.658375] Freeing initrd memory: 9380K (ffff88003a256000 - ffff88003ab7f000)
[   10.658399] PCI-DMA: Using software bounce buffering for IO (SWIOTLB)
[   10.658401] software IO TLB [mem 0x36256000-0x3a256000] (64MB) mapped at [ffff880036256000-ffff88003a255fff]
[   10.658500] Scanning for low memory corruption every 60 seconds
[   10.658723] futex hash table entries: 2048 (order: 5, 131072 bytes)
[   10.659031] Initialise system trusted keyrings
[   10.659120] workingset: timestamp_bits=38 max_order=23 bucket_order=0
[   10.660314] zbud: loaded
[   10.660483] Key type big_key registered
[   10.660682] Key type asymmetric registered
[   10.660719] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 249)
[   10.660745] io scheduler noop registered
[   10.660746] io scheduler deadline registered
[   10.660768] io scheduler cfq registered (default)
[   10.661948] pcieport 0000:00:01.0: Signaling PME through PCIe PME interrupt
[   10.661950] pci 0000:01:00.0: Signaling PME through PCIe PME interrupt
[   10.661952] pci 0000:01:00.1: Signaling PME through PCIe PME interrupt
[   10.661954] pcie_pme 0000:00:01.0:pcie001: service driver pcie_pme loaded
[   10.661971] pcieport 0000:00:1c.0: Signaling PME through PCIe PME interrupt
[   10.661975] pcie_pme 0000:00:1c.0:pcie001: service driver pcie_pme loaded
[   10.661989] pcieport 0000:00:1c.4: Signaling PME through PCIe PME interrupt
[   10.661991] pci 0000:3b:00.0: Signaling PME through PCIe PME interrupt
[   10.661994] pcie_pme 0000:00:1c.4:pcie001: service driver pcie_pme loaded
[   10.662009] pcieport 0000:00:1c.6: Signaling PME through PCIe PME interrupt
[   10.662010] pcieport 0000:3c:00.0: Signaling PME through PCIe PME interrupt
[   10.662011] pcieport 0000:3d:01.0: Signaling PME through PCIe PME interrupt
[   10.662012] pci 0000:3e:00.0: Signaling PME through PCIe PME interrupt
[   10.662013] pcieport 0000:3d:02.0: Signaling PME through PCIe PME interrupt
[   10.662014] pcieport 0000:3d:03.0: Signaling PME through PCIe PME interrupt
[   10.662015] pci 0000:60:00.0: Signaling PME through PCIe PME interrupt
[   10.662018] pcie_pme 0000:00:1c.6:pcie001: service driver pcie_pme loaded
[   10.662021] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[   10.662030] pciehp 0000:00:1c.0:pcie004: Slot #0 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl+ LLActRep+
[   10.662055] pciehp 0000:00:1c.0:pcie004: service driver pciehp loaded
[   10.662062] pciehp 0000:3d:02.0:pcie204: Slot #0 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl- LLActRep+
[   10.662094] pciehp 0000:3d:02.0:pcie204: service driver pciehp loaded
[   10.662102] pciehp 0000:3d:03.0:pcie204: Slot #0 AttnBtn- PwrCtrl- MRL- AttnInd- PwrInd- HotPlug+ Surprise+ Interlock- NoCompl- LLActRep+
[   10.662132] pciehp 0000:3d:03.0:pcie204: service driver pciehp loaded
[   10.662136] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[   10.662144] intel_idle: MWAIT substates: 0x42120
[   10.662145] intel_idle: v0.4.1 model 0x3C
[   10.662351] intel_idle: lapic_timer_reliable_states 0xffffffff
[   10.662617] GHES: HEST is not enabled!
[   10.662652] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[   10.683237] 0000:00:16.3: ttyS0 at I/O 0x70b0 (irq = 19, base_baud = 115200) is a 16550A
[   10.683432] Linux agpgart interface v0.103
[   10.683920] rtc_cmos 00:03: RTC can wake from S4
[   10.684046] rtc_cmos 00:03: rtc core: registered rtc_cmos as rtc0
[   10.684071] rtc_cmos 00:03: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[   10.684079] intel_pstate: Intel P-state driver initializing
[   10.684297] ledtrig-cpu: registered to indicate activity on CPUs
[   10.685257] NET: Registered protocol family 10
[   10.685720] NET: Registered protocol family 17
[   10.686444] microcode: CPU0 sig=0x306c3, pf=0x10, revision=0x20
[   10.686465] microcode: CPU1 sig=0x306c3, pf=0x10, revision=0x20
[   10.686479] microcode: CPU2 sig=0x306c3, pf=0x10, revision=0x20
[   10.686501] microcode: CPU3 sig=0x306c3, pf=0x10, revision=0x20
[   10.686546] microcode: CPU4 sig=0x306c3, pf=0x10, revision=0x20
[   10.686568] microcode: CPU5 sig=0x306c3, pf=0x10, revision=0x20
[   10.686587] microcode: CPU6 sig=0x306c3, pf=0x10, revision=0x20
[   10.686606] microcode: CPU7 sig=0x306c3, pf=0x10, revision=0x20
[   10.686758] microcode: Microcode Update Driver: v2.01 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[   10.687264] registered taskstats version 1
[   10.687272] Loading compiled-in X.509 certificates
[   10.687315] zswap: loaded using pool lzo/zbud
[   10.753868]   Magic number: 12:600:302
[   10.753898] tty tty31: hash matches
[   10.753947] memory memory160: hash matches
[   10.754019] rtc_cmos 00:03: setting system clock to 2016-08-19 23:16:24 UTC (1471648584)
[   10.754073] PM: Hibernation image not present or could not be loaded.
[   10.755023] Freeing unused kernel memory: 1244K (ffffffff818f6000 - ffffffff81a2d000)
[   10.755025] Write protecting the kernel read-only data: 8192k
[   10.755199] Freeing unused kernel memory: 104K (ffff8800015e6000 - ffff880001600000)
[   10.755753] Freeing unused kernel memory: 212K (ffff8800017cb000 - ffff880001800000)
[   10.758354] random: systemd urandom read with 3 bits of entropy available
[   10.773012] systemd[1]: systemd 231 running in system mode. (+PAM -AUDIT -SELINUX -IMA -APPARMOR +SMACK -SYSVINIT +UTMP +LIBCRYPTSETUP +GCRYPT +GNUTLS +ACL +XZ +LZ4 +SECCOMP +BLKID +ELFUTILS +KMOD +IDN)
[   10.773143] systemd[1]: Detected architecture x86-64.
[   10.773145] systemd[1]: Running in initial RAM disk.
[   10.773147] systemd[1]: Running with unpopulated /etc.
[   10.773483] systemd[1]: No hostname configured.
[   10.773485] systemd[1]: Set hostname to <localhost>.
[   10.773500] systemd[1]: Initializing machine ID from random generator.
[   10.774437] systemd[1]: Populated /etc with preset unit settings.
[   10.779208] systemd[1]: Created slice system.slice.
[   10.779529] systemd[1]: Listening on Journal Socket (/dev/log).
[   10.780401] systemd[1]: Reached target Timers.
[   10.780702] systemd[1]: Listening on udev Kernel Socket.
[   10.780979] systemd[1]: Reached target Slices.
[   10.781326] systemd[1]: Created slice system-systemd\x2dcryptsetup.slice.
[   10.781607] systemd[1]: Reached target Swap.
[   10.781899] systemd[1]: Started Dispatch Password Requests to Console Directory Watch.
[   10.782178] systemd[1]: Reached target Paths.
[   10.782474] systemd[1]: Listening on Journal Socket.
[   10.782951] systemd[1]: Starting Journal Service...
[   10.783415] systemd[1]: Starting Create list of required static device nodes for the current kernel...
[   10.783651] systemd[1]: Listening on udev Control Socket.
[   10.783942] systemd[1]: Reached target Sockets.
[   10.784428] systemd[1]: Starting udev Coldplug all Devices...
[   10.784707] systemd[1]: Reached target Local File Systems.
[   10.785428] systemd[1]: Started Create list of required static device nodes for the current kernel.
[   10.786023] systemd[1]: Starting Create Static Device Nodes in /dev...
[   10.787475] systemd[1]: Started Create Static Device Nodes in /dev.
[   10.788114] systemd[1]: Starting udev Kernel Device Manager...
[   10.789471] systemd[1]: Started udev Kernel Device Manager.
[   10.804197] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input0
[   10.804200] ACPI: Sleep Button [SLPB]
[   10.804235] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input1
[   10.804268] ACPI: Lid Switch [LID]
[   10.804312] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input2
[   10.804314] ACPI: Power Button [PWRF]
[   10.806038] systemd[1]: Started udev Coldplug all Devices.
[   10.807891] systemd[1]: Started Journal Service.
[   10.808461] i8042: PNP: PS/2 Controller [PNP0303:PS2K,PNP0f13:PS2M] at 0x60,0x64 irq 1,12
[   10.809637] i8042: Detected active multiplexing controller, rev 1.1
[   10.810301] serio: i8042 KBD port at 0x60,0x64 irq 1
[   10.810338] serio: i8042 AUX0 port at 0x60,0x64 irq 12
[   10.810369] serio: i8042 AUX1 port at 0x60,0x64 irq 12
[   10.810389] serio: i8042 AUX2 port at 0x60,0x64 irq 12
[   10.810404] serio: i8042 AUX3 port at 0x60,0x64 irq 12
[   10.812724] ACPI: bus type USB registered
[   10.812752] usbcore: registered new interface driver usbfs
[   10.812761] usbcore: registered new interface driver hub
[   10.812788] [drm] Initialized drm 1.1.0 20060810
[   10.812790] usbcore: registered new device driver usb
[   10.813240] SCSI subsystem initialized
[   10.813326] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[   10.813546] ehci-pci: EHCI PCI platform driver
[   10.813714] ehci-pci 0000:00:1a.0: EHCI Host Controller
[   10.813720] ehci-pci 0000:00:1a.0: new USB bus registered, assigned bus number 1
[   10.813730] ehci-pci 0000:00:1a.0: debug port 2
[   10.817522] libata version 3.00 loaded.
[   10.817641] ehci-pci 0000:00:1a.0: cache line size of 64 is not supported
[   10.817663] ehci-pci 0000:00:1a.0: irq 16, io mem 0xce936000
[   10.818136] AVX2 version of gcm_enc/dec engaged.
[   10.818138] AES CTR mode by8 optimization enabled
[   10.824514] ehci-pci 0000:00:1a.0: USB 2.0 started, EHCI 1.00
[   10.824641] hub 1-0:1.0: USB hub found
[   10.824644] hub 1-0:1.0: 3 ports detected
[   10.824877] ehci-pci 0000:00:1d.0: EHCI Host Controller
[   10.824881] ehci-pci 0000:00:1d.0: new USB bus registered, assigned bus number 2
[   10.824890] ehci-pci 0000:00:1d.0: debug port 2
[   10.828775] ehci-pci 0000:00:1d.0: cache line size of 64 is not supported
[   10.828791] ehci-pci 0000:00:1d.0: irq 17, io mem 0xce935000
[   10.841218] ehci-pci 0000:00:1d.0: USB 2.0 started, EHCI 1.00
[   10.841460] hub 2-0:1.0: USB hub found
[   10.841465] hub 2-0:1.0: 3 ports detected
[   10.841773] xhci_hcd 0000:00:14.0: xHCI Host Controller
[   10.841785] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 3
[   10.842867] xhci_hcd 0000:00:14.0: hcc params 0x200077c1 hci version 0x100 quirks 0x00009810
[   10.842872] xhci_hcd 0000:00:14.0: cache line size of 64 is not supported
[   10.843034] hub 3-0:1.0: USB hub found
[   10.843050] hub 3-0:1.0: 15 ports detected
[   10.843706] xhci_hcd 0000:00:14.0: xHCI Host Controller
[   10.843708] xhci_hcd 0000:00:14.0: new USB bus registered, assigned bus number 4
[   10.843796] hub 4-0:1.0: USB hub found
[   10.843806] hub 4-0:1.0: 6 ports detected
[   10.843922] usb: port power management may be unreliable
[   10.844090] ahci 0000:00:1f.2: version 3.0
[   10.844209] ahci 0000:00:1f.2: SSS flag set, parallel bus scan disabled
[   10.844233] ahci 0000:00:1f.2: AHCI 0001.0300 32 slots 6 ports 6 Gbps 0xd impl SATA mode
[   10.844234] ahci 0000:00:1f.2: flags: 64bit ncq stag pm led clo pio slum part ems sxs apst 
[   10.855106] scsi host0: ahci
[   10.855292] scsi host1: ahci
[   10.855474] scsi host2: ahci
[   10.855600] scsi host3: ahci
[   10.855687] scsi host4: ahci
[   10.855778] scsi host5: ahci
[   10.855816] ata1: SATA max UDMA/133 abar m2048@0xce934000 port 0xce934100 irq 34
[   10.855817] ata2: DUMMY
[   10.855819] ata3: SATA max UDMA/133 abar m2048@0xce934000 port 0xce934200 irq 34
[   10.855822] ata4: SATA max UDMA/133 abar m2048@0xce934000 port 0xce934280 irq 34
[   10.855823] ata5: DUMMY
[   10.855824] ata6: DUMMY
[   10.856069] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input3
[   10.856405] [drm] Memory usable by graphics device = 2048M
[   10.856408] [drm] Replacing VGA console driver
[   10.857139] Console: switching to colour dummy device 80x25
[   10.857183] [drm] ACPI BIOS requests an excessive sleep of 5000 ms, using 1500 ms instead
[   10.863299] [drm] Supports vblank timestamp caching Rev 2 (21.10.2013).
[   10.863302] [drm] Driver supports precise vblank timestamp query.
[   10.864904] vgaarb: device changed decodes: PCI:0000:00:02.0,olddecodes=io+mem,decodes=none:owns=io+mem
[   10.866069] ahci 0000:3b:00.0: AHCI 0001.0000 32 slots 1 ports 6 Gbps 0x1 impl SATA mode
[   10.866072] ahci 0000:3b:00.0: flags: 64bit ncq sntf led pio slum part 
[   10.866243] scsi host6: ahci
[   10.866294] ata7: SATA max UDMA/133 abar m512@0xce800000 port 0xce800100 irq 35
[   10.962050] ACPI: Video Device [DGFX] (multi-head: yes  rom: yes  post: no)
[   10.963582] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/device:00/LNXVIDEO:00/input/input8
[   10.964344] ACPI: Video Device [GFX0] (multi-head: yes  rom: no  post: no)
[   10.964425] input: Video Bus as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0A08:00/LNXVIDEO:01/input/input9
[   10.964464] [drm] Initialized i915 1.6.0 20160425 for 0000:00:02.0 on minor 0
[   10.973063] fbcon: inteldrmfb (fb0) is primary device
[   11.131216] usb 1-1: new high-speed USB device number 2 using ehci-pci
[   11.151213] usb 2-1: new high-speed USB device number 2 using ehci-pci
[   11.165060] ata1: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[   11.166605] ata1.00: FORCE: horkage modified (noncq)
[   11.166749] ata1.00: supports DRM functions and may not be fully accessible
[   11.166750] ata1.00: ATA-9: Samsung SSD 850 EVO 120GB, EMT01B6Q, max UDMA/133
[   11.166751] ata1.00: 234441648 sectors, multi 1: LBA48 NCQ (not used)
[   11.167495] ata1.00: supports DRM functions and may not be fully accessible
[   11.167719] ata1.00: configured for UDMA/133
[   11.168105] scsi 0:0:0:0: Direct-Access     ATA      Samsung SSD 850  1B6Q PQ: 0 ANSI: 5
[   11.175244] ata7: SATA link up 6.0 Gbps (SStatus 133 SControl 300)
[   11.175791] ata7.00: ATA-9: SanDisk SD6PP4M-256G-1006, A200806, max UDMA/100
[   11.175792] ata7.00: 500118192 sectors, multi 1: LBA48 NCQ (depth 31/32), AA
[   11.176377] ata7.00: configured for UDMA/100
[   11.201228] usb 3-5: new full-speed USB device number 2 using xhci_hcd
[   11.255243] hub 1-1:1.0: USB hub found
[   11.255316] hub 1-1:1.0: 6 ports detected
[   11.275622] hub 2-1:1.0: USB hub found
[   11.275708] hub 2-1:1.0: 8 ports detected
[   11.515557] ata3: SATA link down (SStatus 0 SControl 300)
[   11.534557] usb 3-7: new high-speed USB device number 3 using xhci_hcd
[   11.661219] tsc: Refined TSC clocksource calibration: 2793.531 MHz
[   11.661221] clocksource: tsc: mask: 0xffffffffffffffff max_cycles: 0x284461bc4f9, max_idle_ns: 440795228872 ns
[   11.825165] ata4: SATA link down (SStatus 0 SControl 300)
[   11.825540] scsi 6:0:0:0: Direct-Access     ATA      SanDisk SD6PP4M- 806  PQ: 0 ANSI: 5
[   11.924544] usb 3-12: new full-speed USB device number 4 using xhci_hcd
[   12.021475] Console: switching to colour frame buffer device 240x67
[   12.024352] i915 0000:00:02.0: fb0: inteldrmfb frame buffer device
[   12.039890] sd 0:0:0:0: [sda] 234441648 512-byte logical blocks: (120 GB/112 GiB)
[   12.039917] sd 0:0:0:0: [sda] Write Protect is off
[   12.039919] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[   12.039922] sd 6:0:0:0: [sdb] 500118192 512-byte logical blocks: (256 GB/238 GiB)
[   12.039924] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   12.039992] sd 6:0:0:0: [sdb] Write Protect is off
[   12.039995] sd 6:0:0:0: [sdb] Mode Sense: 00 3a 00 00
[   12.040021] sd 6:0:0:0: [sdb] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[   12.041522]  sda: sda1 sda2
[   12.041745] sd 0:0:0:0: [sda] Attached SCSI disk
[   12.046614]  sdb: sdb1 sdb2 sdb3
[   12.047024] sd 6:0:0:0: [sdb] Attached SCSI disk
[   12.077733] device-mapper: uevent: version 1.0.3
[   12.077786] device-mapper: ioctl: 4.34.0-ioctl (2015-10-28) initialised: dm-devel@redhat.com
[   12.661444] clocksource: Switched to clocksource tsc
[   18.121738] random: nonblocking pool is initialized
[   20.910362] NET: Registered protocol family 38
[   21.151704] EXT4-fs (dm-0): mounted filesystem with ordered data mode. Opts: (null)
[   21.222451] systemd-journald[144]: Received SIGTERM from PID 1 (systemd).
[   21.293942] ip_tables: (C) 2000-2006 Netfilter Core Team
[   21.513077] FUJITSU Extended Socket Network Device Driver - version 1.1 - Copyright (c) 2015 FUJITSU LIMITED
[   21.515547] Initializing HPQ6001 module
[   21.515617] input: HP Wireless hotkeys as /devices/virtual/input/input10
[   21.515888] wmi: Mapper loaded
[   21.531999] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[   21.533106] hp_accel: hardware type HPZBook15 found
[   21.534087] EDAC MC: Ver: 3.0.0
[   21.535281] EDAC ie31200: No ECC support
[   21.558945] snd_hda_intel 0000:01:00.1: Disabling MSI
[   21.558951] snd_hda_intel 0000:01:00.1: Handle vga_switcheroo audio client
[   21.566902] pps_core: LinuxPPS API ver. 1 registered
[   21.566904] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[   21.567864] PTP clock support registered
[   21.571819] snd_hda_codec_realtek hdaudioC0D0: autoconfig for ALC3228: line_outs=1 (0x14/0x0/0x0/0x0/0x0) type:speaker
[   21.571821] snd_hda_codec_realtek hdaudioC0D0:    speaker_outs=0 (0x0/0x0/0x0/0x0/0x0)
[   21.571822] snd_hda_codec_realtek hdaudioC0D0:    hp_outs=1 (0x15/0x0/0x0/0x0/0x0)
[   21.571823] snd_hda_codec_realtek hdaudioC0D0:    mono: mono_out=0x0
[   21.571823] snd_hda_codec_realtek hdaudioC0D0:    inputs:
[   21.571825] snd_hda_codec_realtek hdaudioC0D0:      Mic=0x1a
[   21.571826] snd_hda_codec_realtek hdaudioC0D0:      Internal Mic=0x12
[   21.574579] e1000e: Intel(R) PRO/1000 Network Driver - 3.2.6-k
[   21.574582] e1000e: Copyright(c) 1999 - 2015 Intel Corporation.
[   21.574761] e1000e 0000:00:19.0: Interrupt Throttling Rate (ints/sec) set to dynamic conservative mode
[   21.578018] input: HDA Digital PCBeep as /devices/pci0000:00/0000:00:1b.0/sound/card0/input11
[   21.578106] input: HDA Intel PCH Mic as /devices/pci0000:00/0000:00:1b.0/sound/card0/input12
[   21.578136] input: HDA Intel PCH Headphone as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13
[   21.587539] tpm_tis 00:07: 1.2 TPM (device-id 0x1A, rev-id 16)
[   21.602427] Bluetooth: Core ver 2.21
[   21.602439] NET: Registered protocol family 31
[   21.602440] Bluetooth: HCI device and connection manager initialized
[   21.602443] Bluetooth: HCI socket layer initialized
[   21.602445] Bluetooth: L2CAP socket layer initialized
[   21.602448] Bluetooth: SCO socket layer initialized
[   21.605768] usbcore: registered new interface driver btusb
[   21.618918] Bluetooth: hci0: read Intel version: 370710018002030d00
[   21.619859] media: Linux media interface: v0.10
[   21.623825] Bluetooth: hci0: Intel Bluetooth firmware file: intel/ibt-hw-37.7.10-fw-1.80.2.3.d.bseq
[   21.623901] Linux video capture interface: v2.00
[   21.629781] uvcvideo: Found UVC 1.00 device HP HD Webcam (04f2:b477)
[   21.632326] uvcvideo 3-7:1.0: Entity type for entity Extension 5 was not initialized!
[   21.632329] uvcvideo 3-7:1.0: Entity type for entity Extension 2 was not initialized!
[   21.632330] uvcvideo 3-7:1.0: Entity type for entity Processing 3 was not initialized!
[   21.632331] uvcvideo 3-7:1.0: Entity type for entity Camera 1 was not initialized!
[   21.632375] input: HP HD Webcam as /devices/pci0000:00/0000:00:14.0/usb3/3-7/3-7:1.0/input/input14
[   21.632409] usbcore: registered new interface driver uvcvideo
[   21.632410] USB Video Class driver (1.1.1)
[   21.660840] lis3lv02d: 8 bits 3DC sensor found
[   21.663823] e1000e 0000:00:19.0 0000:00:19.0 (uninitialized): registered PHC clock
[   21.670242] Intel(R) Wireless WiFi driver for Linux
[   21.670244] Copyright(c) 2003- 2015 Intel Corporation
[   21.677865] iwlwifi 0000:3e:00.0: loaded firmware version 17.352738.0 op_mode iwlmvm
[   21.691785] iwlwifi 0000:3e:00.0: Detected Intel(R) Dual Band Wireless N 7260, REV=0x144
[   21.693985] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   21.694344] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   21.742628] e1000e 0000:00:19.0 eth0: (PCI Express:2.5GT/s:Width x1) 48:0f:cf:b6:67:de
[   21.742634] e1000e 0000:00:19.0 eth0: Intel(R) PRO/1000 Network Connection
[   21.742677] e1000e 0000:00:19.0 eth0: MAC: 11, PHY: 12, PBA No: FFFFFF-0FF
[   21.752902] Bluetooth: hci0: Intel Bluetooth firmware patch completed and activated
[   21.754471] i801_smbus 0000:00:1f.3: SMBus using PCI interrupt
[   21.783495] input: PC Speaker as /devices/platform/pcspkr/input/input17
[   21.799094] EXT4-fs (sda1): mounting ext2 file system using the ext4 subsystem
[   21.801419] EXT4-fs (sda1): mounted filesystem without journal. Opts: (null)
[   21.827328] RAPL PMU: API unit is 2^-32 Joules, 4 fixed counters, 655360 ms ovfl timer
[   21.827330] RAPL PMU: hw unit of domain pp0-core 2^-14 Joules
[   21.827331] RAPL PMU: hw unit of domain package 2^-14 Joules
[   21.827332] RAPL PMU: hw unit of domain dram 2^-14 Joules
[   21.827332] RAPL PMU: hw unit of domain pp1-gpu 2^-14 Joules
[   21.847546] tpm_tis 00:07: TPM is disabled/deactivated (0x7)
[   21.848032] parport_pc 00:04: reported by Plug and Play ACPI
[   21.848091] parport0: PC-style at 0x378 (0x778), irq 5 [PCSPP,TRISTATE,EPP]
[   21.850793] intel_rapl: Found RAPL domain package
[   21.850796] intel_rapl: Found RAPL domain core
[   21.850798] intel_rapl: Found RAPL domain uncore
[   21.850799] intel_rapl: Found RAPL domain dram
[   21.923643] input: HP WMI hotkeys as /devices/virtual/input/input18
[   21.925419] ieee80211 phy0: Selected rate control algorithm 'iwl-mvm-rs'
[   21.930062] ppdev: user-space parallel port driver
[   21.954444] e1000e 0000:00:19.0 enp0s25: renamed from eth0
[   21.954525] input: HDA NVidia HDMI/DP,pcm=3 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input19
[   21.954560] input: HDA NVidia HDMI/DP,pcm=7 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input20
[   21.954589] input: HDA NVidia HDMI/DP,pcm=8 as /devices/pci0000:00/0000:00:01.0/0000:01:00.1/sound/card1/input21
[   21.954653] iTCO_vendor_support: vendor-support=0
[   21.955035] iTCO_wdt: Intel TCO WatchDog Timer Driver v1.11
[   21.955067] iTCO_wdt: Found a Lynx Point TCO device (Version=2, TCOBASE=0x1860)
[   21.955127] iTCO_wdt: initialized. heartbeat=30 sec (nowayout=0)
[   21.971242] systemd-journald[349]: Received request to flush runtime journal from PID 1
[   21.981105] iwlwifi 0000:3e:00.0 wlp62s0: renamed from wlan0
[   22.067999] thermal LNXTHERM:00: registered as thermal_zone1
[   22.068003] ACPI: Thermal Zone [CPUZ] (85 C)
[   22.281404] NET: Registered protocol family 3
[   22.299737] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   22.300179] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   22.367642] ACPI: AC Adapter [AC] (off-line)
[   22.506379] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   22.506818] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   22.530326] IPv6: ADDRCONF(NETDEV_UP): wlp62s0: link is not ready
[   22.604671] thermal LNXTHERM:01: registered as thermal_zone2
[   22.604674] ACPI: Thermal Zone [GFXZ] (45 C)
[   22.614813] ACPI: Battery Slot [BAT0] (battery present)
[   22.614886] ACPI: Battery Slot [BAT1] (battery absent)
[   22.884346] input: PS/2 Generic Mouse as /devices/platform/i8042/serio2/input/input16
[   22.885690] mousedev: PS/2 mouse device common for all mice
[   22.999757] thermal LNXTHERM:02: registered as thermal_zone3
[   22.999761] ACPI: Thermal Zone [EXTZ] (85 C)
[   23.014778] thermal LNXTHERM:03: registered as thermal_zone4
[   23.014780] ACPI: Thermal Zone [LOCZ] (85 C)
[   23.027524] input: ST LIS3LV02DL Accelerometer as /devices/platform/lis3lv02d/input/input23
[   23.029890] thermal LNXTHERM:04: registered as thermal_zone5
[   23.029892] ACPI: Thermal Zone [BATZ] (36 C)
[   23.030127] thermal LNXTHERM:05: registered as thermal_zone6
[   23.030128] ACPI: Thermal Zone [PCHZ] (84 C)
[   23.658170] psmouse serio3: synaptics: queried max coordinates: x [..5660], y [..4730]
[   23.692403] psmouse serio3: synaptics: queried min coordinates: x [1324..], y [1248..]
[   23.749016] psmouse serio3: synaptics: Touchpad model: 1, fw: 8.1, id: 0x1e2b1, caps: 0xd40123/0x840300/0x26800/0x0, board id: 2706, fw id: 1486004
[   23.785536] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio3/input/input22
[   28.234201] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
[   33.676847] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   33.677306] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   33.894181] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   33.894626] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   33.919504] IPv6: ADDRCONF(NETDEV_UP): wlp62s0: link is not ready
[   34.035706] e1000e: enp0s25 NIC Link is Down
[   34.244057] IPv6: ADDRCONF(NETDEV_UP): enp0s25: link is not ready
[   34.263994] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   34.264434] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   34.466159] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   34.466599] iwlwifi 0000:3e:00.0: L1 Enabled - LTR Enabled
[   34.491825] IPv6: ADDRCONF(NETDEV_UP): wlp62s0: link is not ready
[   35.367327] fuse init (API version 7.25)
[   39.966492] wlp62s0: authenticate with 68:7f:74:d6:2f:b3
[   39.970765] wlp62s0: send auth to 68:7f:74:d6:2f:b3 (try 1/3)
[   39.972803] wlp62s0: authenticated
[   39.972833] iwlwifi 0000:3e:00.0 wlp62s0: disabling HT as WMM/QoS is not supported by the AP
[   39.972837] iwlwifi 0000:3e:00.0 wlp62s0: disabling VHT as WMM/QoS is not supported by the AP
[   39.973720] wlp62s0: associate with 68:7f:74:d6:2f:b3 (try 1/3)
[   39.976278] wlp62s0: RX AssocResp from 68:7f:74:d6:2f:b3 (capab=0x411 status=0 aid=1)
[   39.981098] wlp62s0: associated
[   39.981129] IPv6: ADDRCONF(NETDEV_CHANGE): wlp62s0: link becomes ready

I don't see anything obvious in terms of warnings, though this might be a relevant excerpt, as the timer jumps from 5.7 to 10.5 seconds in this area:

[    5.711076] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711111] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711144] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711176] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 10 *11 12 14 15)
[    5.711208] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 *5 6 10 11 12 14 15)
[    5.711240] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    5.711272] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 *10 11 12 14 15)
[    5.711305] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 10 11 12 14 15) *0, disabled.
[    5.711566] ACPI: Enabled 7 GPEs in block 00 to 3F
[    5.711639] ACPI : EC: EC stopped
[    5.711667] ACPI : EC: GPE = 0x16, I/O: command/status = 0x66, data = 0x62
[    5.711668] ACPI : EC: EC started
[   10.526295] vgaarb: setting as boot device: PCI:0000:00:02.0
[   10.526298] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[   10.526302] vgaarb: device added: PCI:0000:01:00.0,decodes=io+mem,owns=none,locks=none
[   10.526304] vgaarb: loaded
[   10.526305] vgaarb: bridge control possible 0000:01:00.0
[   10.526306] vgaarb: no bridge control possible 0000:00:02.0
[   10.526353] PCI: Using ACPI for IRQ routing
[   10.532124] PCI: pci_cache_line_size set to 64 bytes
[   10.532351] e820: reserve RAM buffer [mem 0x0009dc00-0x0009ffff]
[   10.532353] e820: reserve RAM buffer [mem 0x3ab7f000-0x3bffffff]
[   10.532354] e820: reserve RAM buffer [mem 0x6afe00000-0x6afffffff]

For reference, on the last 4.6 kernel, if I typed my 14 char password perfectly, I could get a boot time of ~13 seconds via systemd-analyze. Here's where I'm at now:

$ systemd-analyze
Startup finished in 10.746s (kernel) + 10.518s (initrd) + 4.060s (userspace) = 25.325s

$ systemd-analyze blame
          9.030s systemd-cryptsetup@luks\x2d7101e83b\x2d31c0\x2d4cdf\x2dbc07\x2d678e00e19c32.service
          3.286s updatedb.service
           593ms man-db.service
           559ms systemd-journald.service
           221ms wicd.service
            46ms systemd-udevd.service
            45ms systemd-logind.service
            34ms systemd-fsck@dev-disk-by\x2duuid-37441f68\x2d9d76\x2d45bc\x2db98c\x2d996e68a3555c.service
            32ms polkit.service
            30ms dev-hugepages.mount
            27ms logrotate.service
            27ms systemd-udev-trigger.service
            26ms systemd-tmpfiles-setup-dev.service
            26ms kmod-static-nodes.service
            26ms systemd-vconsole-setup.service
            25ms sys-kernel-debug.mount
            24ms systemd-remount-fs.service
            17ms shadow.service
            16ms systemd-modules-load.service
            16ms systemd-fsck-root.service
            15ms systemd-tmpfiles-setup.service
            14ms user@1000.service
            14ms dev-mqueue.mount
             8ms systemd-sysctl.service
             6ms sys-kernel-config.mount
             6ms systemd-random-seed.service
             4ms systemd-backlight@backlight:intel_backlight.service
             4ms boot.mount
             3ms systemd-journal-flush.service
             3ms tmp.mount
             2ms systemd-rfkill.service
             2ms systemd-update-utmp.service
             2ms rtkit-daemon.service
             2ms alsa-restore.service
             2ms systemd-user-sessions.service
             1ms sys-fs-fuse-connections.mount

I can revert to 4.6 if nothing sticks out to run a comparison. I was hoping to find other threads like this in the forums but perhaps others aren't seeing this? If anything stands out above, I'd be happy to pull other logs/configs as necessary, so just let me know.

Thanks!

Last edited by jwhendy (2016-09-07 14:01:10)

jwhendy · 2016-08-21 20:43:29

Okay, I installed linux-lts and nvidia-lts and booted to that kernel to compare. Typed my password pretty quickly, and here's the results with LTS:

$ systemd-analyze
Startup finished in 4.683s (kernel) + 7.625s (initrd) + 1.084s (userspace) = 13.393s

$ systemd-analyze blame
          5.852s systemd-cryptsetup@luks\x2d7101e83b\x2d31c0\x2d4cdf\x2dbc07\x2d678e00e19c32.service
           338ms systemd-journald.service
           174ms wicd.service
            81ms systemd-journal-flush.service
            71ms systemd-rfkill.service
            66ms systemd-fsck@dev-disk-by\x2duuid-37441f68\x2d9d76\x2d45bc\x2db98c\x2d996e68a3555c.service
            48ms systemd-udevd.service
            48ms systemd-logind.service
            47ms alsa-restore.service
            30ms sys-kernel-debug.mount
            28ms dev-mqueue.mount
            28ms systemd-udev-trigger.service
            26ms polkit.service
            23ms kmod-static-nodes.service
            23ms systemd-tmpfiles-setup-dev.service
            21ms dev-hugepages.mount
            19ms user@1000.service
            18ms systemd-remount-fs.service
            17ms systemd-fsck-root.service
            10ms systemd-sysctl.service
             8ms boot.mount
             7ms systemd-vconsole-setup.service
             6ms systemd-random-seed.service
             6ms systemd-backlight@backlight:intel_backlight.service
             6ms tmp.mount
             5ms sys-kernel-config.mount
             5ms systemd-modules-load.service
             5ms systemd-tmpfiles-setup.service
             2ms systemd-update-utmp.service
             2ms rtkit-daemon.service
             1ms systemd-user-sessions.service
             1ms sys-fs-fuse-connections.mount

Compared to 4.7 above, this is 6sec longer for the kernel and 3sec longer for the initrd. Total time is ~12sec longer for kernel 4.7.

I did a side-by-side diff of the dmesg outputs to look for the major differences. Here's what I'm seeing (in all cases, lts is on the left; 4.7 on the right).

no cgroup lines in 4.7

[    0.012879] Yama: becoming mindful.			      |	[    0.013047] Yama: becoming mindful.			     
[    0.014450] Dentry cache hash table entries: 4194304 (orde |	[    0.014449] Dentry cache hash table entries: 4194304 (orde
[    0.019493] Inode-cache hash table entries: 2097152 (order |	[    0.019435] Inode-cache hash table entries: 2097152 (order
[    0.021648] Mount-cache hash table entries: 65536 (order:  |	[    0.021605] Mount-cache hash table entries: 65536 (order: 
[    0.021675] Mountpoint-cache hash table entries: 65536 (or |	[    0.021630] Mountpoint-cache hash table entries: 65536 (or
[    0.021923] Initializing cgroup subsys io		      |	
[    0.021926] Initializing cgroup subsys memory	      |	
[    0.021930] Initializing cgroup subsys devices	      |	
[    0.021933] Initializing cgroup subsys freezer	      |	
[    0.021934] Initializing cgroup subsys net_cls	      |	
[    0.021936] Initializing cgroup subsys pids		      |	
[    0.021954] CPU: Physical Processor ID: 0		      |	[    0.021901] CPU: Physical Processor ID: 0		     
[    0.021955] CPU: Processor Core ID: 0		      |	[    0.021902] CPU: Processor Core ID: 0		     
[    0.021958] ENERGY_PERF_BIAS: Set to 'normal', was 'perfor |	[    0.021906] ENERGY_PERF_BIAS: Set to 'normal', was 'perfor
[    0.021959] ENERGY_PERF_BIAS: View and update with x86_ene |	[    0.021907] ENERGY_PERF_BIAS: View and update with x86_ene
[    0.022778] mce: CPU supports 9 MCE banks		      |	[    0.021910] mce: CPU supports 9 MCE banks		     
[    0.022790] CPU0: Thermal monitoring enabled (TM1)	      |	[    0.021918] CPU0: Thermal monitoring enabled (TM1)	     
[    0.022799] process: using mwait in idle threads	      |	[    0.021932] process: using mwait in idle threads	     
[    0.022802] Last level iTLB entries: 4KB 1024, 2MB 1024, 4 |	[    0.021935] Last level iTLB entries: 4KB 1024, 2MB 1024, 4

Both kernels have a jump early on of several seconds after "ACPI : EC : EC Started"; for 4.7, it takes a full second longer:

[    0.274242] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query i |	[    0.825539] ACPI: [Firmware Bug]: BIOS _OSI(Linux) query i
[    0.276487] ACPI: Dynamic OEM Table Load:		      |	[    0.830103] ACPI: Dynamic OEM Table Load:		     
[    0.276494] ACPI: SSDT 0xFFFF880693B3B400 0003F3 (v01 PmRe |	[    0.830110] ACPI: SSDT 0xFFFF880693401800 0003F3 (v01 PmRe
[    0.277061] ACPI: Dynamic OEM Table Load:		      |	[    0.830707] ACPI: Dynamic OEM Table Load:		     
[    0.277067] ACPI: SSDT 0xFFFF880693EBB000 0005DB (v01 PmRe |	[    0.830713] ACPI: SSDT 0xFFFF8806938F3000 0005DB (v01 PmRe
[    0.277663] ACPI: Dynamic OEM Table Load:		      |	[    0.831333] ACPI: Dynamic OEM Table Load:		     
[    0.277667] ACPI: SSDT 0xFFFF880693B0F400 000119 (v01 PmRe |	[    0.831337] ACPI: SSDT 0xFFFF880693BEE000 000119 (v01 PmRe
[    0.279066] ACPI : EC: EC started			      |	[    0.832820] ACPI : EC: EC started			     
[    4.418531] ACPI: Interpreter enabled		      |	[    5.666961] ACPI: Interpreter enabled		     
[    4.418538] ACPI Exception: AE_NOT_FOUND, While evaluating |	
[    4.418543] ACPI Exception: AE_NOT_FOUND, While evaluating |	
[    4.418556] ACPI: (supports S0 S3 S4 S5)		      |	[    5.666983] ACPI: (supports S0 S3 S4 S5)		     
[    4.418557] ACPI: Using IOAPIC for interrupt routing	      |	[    5.666985] ACPI: Using IOAPIC for interrupt routing	     
[    4.418571] PCI: Using host bridge windows from ACPI; if n |	[    5.667000] PCI: Using host bridge windows from ACPI; if n

Here's the big one: LTS chugs along through this section while 4.7 shows extra lines for stopping/starting ACPI EC again, adding ~5sec:

[    4.527585] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6  |	[    5.711111] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 6 
[    4.527617] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6  |	[    5.711144] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 6 
[    4.527650] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6  |	[    5.711176] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 6 
[    4.527681] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 *5 6 |	[    5.711208] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 *5 6
[    4.527713] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6  |	[    5.711240] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 6 
[    4.527746] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6  |	[    5.711272] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 6 
[    4.527778] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6  |	[    5.711305] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 
[    4.528033] ACPI: Enabled 7 GPEs in block 00 to 3F	      |	[    5.711566] ACPI: Enabled 7 GPEs in block 00 to 3F	     
                                                              |	[    5.711639] ACPI : EC: EC stopped			     
[    4.528068] ACPI : EC: GPE = 0x16, I/O: command/status = 0 |	[    5.711667] ACPI : EC: GPE = 0x16, I/O: command/status = 0
                                                              |	[    5.711668] ACPI : EC: EC started			     
[    4.528134] vgaarb: setting as boot device: PCI:0000:00:02 |	[   10.526295] vgaarb: setting as boot device: PCI:0000:00:02
[    4.528135] vgaarb: device added: PCI:0000:00:02.0,decodes |	[   10.526298] vgaarb: device added: PCI:0000:00:02.0,decodes
[    4.528139] vgaarb: device added: PCI:0000:01:00.0,decodes |	[   10.526302] vgaarb: device added: PCI:0000:01:00.0,decodes
[    4.528141] vgaarb: loaded				      |	[   10.526304] vgaarb: loaded				     
[    4.528141] vgaarb: bridge control possible 0000:01:00.0   |	[   10.526305] vgaarb: bridge control possible 0000:01:00.0  
[    4.528142] vgaarb: no bridge control possible 0000:00:02. |	[   10.526306] vgaarb: no bridge control possible 0000:00:02.
[    4.528213] PCI: Using ACPI for IRQ routing		      |	[   10.526353] PCI: Using ACPI for IRQ routing		     
[    4.533939] PCI: pci_cache_line_size set to 64 bytes	      |	[   10.532124] PCI: pci_cache_line_size set to 64 bytes	     
[    4.534152] e820: reserve RAM buffer [mem 0x0009dc00-0x000 |	[   10.532351] e820: reserve RAM buffer [mem 0x0009dc00-0x000
[    4.534153] e820: reserve RAM buffer [mem 0x3ab7f000-0x3bf | [   10.532353] e820: reserve RAM buffer [mem 0x3ab7f000-0x3bf
[    4.534154] e820: reserve RAM buffer [mem 0x6afe00000-0x6a |	[   10.532354] e820: reserve RAM buffer [mem 0x6afe00000-0x6a

Any thoughts based on this?

jwhendy · 2016-08-21 21:11:35

Submitted kernel bug report after finding a user on reddit reporting what appears to be the same thing.

Marcin · 2016-08-31 23:23:17

Hi jwhendy.

I think you were very lucky with your 25.325s. I was lucky with my 20s. over one year ago. Now it looks like this:

systemd-analyze
Startup finished in 9.512s (kernel) + 34.114s (userspace) = 43.627s

More closely:

systemd-analyze blame
         37.228s updatedb.service
         16.274s systemd-journald.service
         15.082s man-db.service
         12.085s ufw.service
          9.735s dev-sda7.device
          9.155s org.cups.cupsd.service
          7.686s accounts-daemon.service
          6.312s pamac.service
          3.381s systemd-journal-flush.service
          2.713s systemd-fsck@dev-disk-by\x2duuid-21aedbb7\x2dcefc\x2d42c1\x2d8c85\x2d02a4fe040dbf.service
          2.687s systemd-udevd.service
          2.503s systemd-fsck@dev-disk-by\x2duuid-220d80fd\x2d9b13\x2d4156\x2db501\x2da4da0a52ffc5.service
          2.009s polkit.service
          1.759s systemd-vconsole-setup.service
          1.702s NetworkManager.service
          1.502s wpa_supplicant.service
          1.448s systemd-tmpfiles-setup-dev.service
          1.055s upower.service
          1.032s systemd-rfkill.service
           848ms systemd-tmpfiles-clean.service
           827ms systemd-backlight@backlight:acpi_video0.service
         37.228s updatedb.service
         16.274s systemd-journald.service
         15.082s man-db.service
         12.085s ufw.service
          9.735s dev-sda7.device
          9.155s org.cups.cupsd.service
          7.686s accounts-daemon.service
          6.312s pamac.service
          3.381s systemd-journal-flush.service
          2.713s systemd-fsck@dev-disk-by\x2duuid-21aedbb7\x2dcefc\x2d42c1\x2d8c85\x2d02a4fe040dbf.service
          2.687s systemd-udevd.service
          2.503s systemd-fsck@dev-disk-by\x2duuid-220d80fd\x2d9b13\x2d4156\x2db501\x2da4da0a52ffc5.service
          2.009s polkit.service
          1.759s systemd-vconsole-setup.service
          1.702s NetworkManager.service
          1.502s wpa_supplicant.service
          1.448s systemd-tmpfiles-setup-dev.service
          1.055s upower.service
          1.032s systemd-rfkill.service
           848ms systemd-tmpfiles-clean.service
           827ms systemd-backlight@backlight:acpi_video0.service
           736ms colord.service
           695ms udisks2.service
           635ms lightdm.service
           583ms systemd-sysctl.service
           542ms systemd-modules-load.service
           517ms dev-mqueue.mount
           511ms logrotate.service
           443ms systemd-udev-trigger.service
           435ms systemd-random-seed.service
           432ms dev-disk-by\x2duuid-9685e72e\x2db755\x2d4736\x2db917\x2d92ebe2970d00.swap
           430ms dev-hugepages.mount
           428ms systemd-remount-fs.service
           426ms sys-kernel-debug.mount
           346ms home.mount
           319ms shadow.service
           313ms systemd-update-utmp.service
           225ms rtkit-daemon.service
           195ms systemd-user-sessions.service
           175ms avahi-daemon.service
           164ms boot.mount
           162ms user@1000.service
           160ms kmod-static-nodes.service
           141ms systemd-timesyncd.service
           100ms systemd-tmpfiles-setup.service
            61ms systemd-logind.service
            31ms alsa-restore.service
            14ms sys-kernel-config.mount
             3ms sys-fs-fuse-connections.mount
             1ms tmp.mount

I presume that you were trying some instructions provided on Wiki https://wiki.archlinux.org/index.php/Im … ot_process

I also need to do my investigation. My systemd-analyze blame gives me very long list comparing to yours.

jwhendy · 2016-09-01 01:12:48

At least I'm not the only one! So you're saying this happened with 4.7, right? I was surprised not to find any other noise about this.

Re. your systemd results, I think you may have something odd going on. You have two sets of decreasing times with what appears to be repeated services. Did you accidentally double paste, or might you have two init services going (legacy and systemd) somehow?

The updatdb, jounald, and man-db results are quite troubling to my eye.

jwhendy · 2016-09-07 14:00:51

The kernel bug report resulted in a patch that appears to have fixed things for me. Sounds like it won't be applied until 4.9, so that was a bit of a bummer. In any case, tying up loose ends here and will mark this solved. Ended up being related to ACPI and EC devices. I don't know what that means, so I'm just going to quote Lv Zheng's synopsis:

I have a patchset covering this issue.
This is in fact not an issue.
Just because _REG (which consumes time on this platform) is executed more times than the previous version.
However it really can be tuned not to execute so many times by checking if 2 EC devices are equivelent.

Arch Linux

#1 2016-08-19 23:27:43

[SOLVED] Slower boot with kernel 4.7

#2 2016-08-21 20:43:29

Re: [SOLVED] Slower boot with kernel 4.7

#3 2016-08-21 21:11:35

Re: [SOLVED] Slower boot with kernel 4.7

#4 2016-08-31 23:23:17

Re: [SOLVED] Slower boot with kernel 4.7

#5 2016-09-01 01:12:48

Re: [SOLVED] Slower boot with kernel 4.7

#6 2016-09-07 14:00:51

Re: [SOLVED] Slower boot with kernel 4.7

Board footer