Bug 75251

Summary: PCI/shpchp - wrong bus speed computation
Product: Drivers Reporter: Marcel Apfelbaum (marcel.a)
Component: PCIAssignee: Greg Kroah-Hartman (greg)
Status: RESOLVED CODE_FIX    
Severity: low CC: bjorn, m.s.tsirkin
Priority: P1    
Hardware: x86-64   
OS: Linux   
Kernel Version: 3.15.0-rc1+ Subsystem:
Regression: Yes Bisected commit-id:

Description Marcel Apfelbaum 2014-05-01 18:51:31 UTC
When a board is added, the shpchp driver checks if there
is a mismatch between the bridge's adapter and the bus speed.
If there is, it sets the subordinate speed (if there is no device on it).

However, it takes the reference of the board speed from the primary bus
and not from the subordinate. If the primary bus is PCI and not PCIX/PCIe,
its speed is not updated and remains 0xff. As a result hotplug fails
with error: "Speed of bus ff and adapter 0 mismatch".


lspci:
00:00.0 Host bridge: Intel Corporation 440FX - 82441FX PMC [Natoma] (rev 02)
00:01.0 ISA bridge: Intel Corporation 82371SB PIIX3 ISA [Natoma/Triton II]
00:01.1 IDE interface: Intel Corporation 82371SB PIIX3 IDE [Natoma/Triton II]
00:01.3 Bridge: Intel Corporation 82371AB/EB/MB PIIX4 ACPI (rev 03)
00:02.0 VGA compatible controller: Cirrus Logic GD 5446
00:03.0 PCI bridge: Red Hat, Inc. QEMU PCI-PCI bridge
00:04.0 Unclassified device [0002]: Red Hat, Inc Virtio filesystem
00:05.0 SCSI storage controller: Red Hat, Inc Virtio block device
01:01.0 Ethernet controller: Intel Corporation 82540EM Gigabit Ethernet Controller (rev 03)

dmesg: (when hot-plugging an e1000 device)
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Initializing cgroup subsys cpuacct
[    0.000000] Linux version 3.13.9-100.fc19.x86_64 (mockbuild@bkernel01.phx2.fedoraproject.org) (gcc version 4.8.2 20131212 (Red Hat 4.8.2-7) (GCC) ) #1 SMP Fri Apr 4 00:51:59 UTC 2014
[    0.000000] Command line: BOOT_IMAGE=/vmlinuz-3.13.9-100.fc19.x86_64 root=/dev/mapper/fedora-root ro rd.lvm.lv=fedora/swap rd.md=0 rd.dm=0 vconsole.keymap=us rd.luks=0 vconsole.font=latarcyrheb-sun16 rd.lvm.lv=fedora/root rhgb quiet LANG=en_GB.utf8
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x000000007fffdfff] usable
[    0.000000] BIOS-e820: [mem 0x000000007fffe000-0x000000007fffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.4 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Bochs 01/01/2011
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x7fffe max_arch_pfn = 0x400000000
[    0.000000] MTRR default type: write-back
[    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 0080000000 mask FF80000000 uncachable
[    0.000000]   1 disabled
[    0.000000]   2 disabled
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] PAT not supported by CPU.
[    0.000000] found SMP MP-table at [mem 0x000f55e0-0x000f55ef] mapped at [ffff8800000f55e0]
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x02010000, 0x02010fff] PGTABLE
[    0.000000] BRK [0x02011000, 0x02011fff] PGTABLE
[    0.000000] BRK [0x02012000, 0x02012fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x7fc00000-0x7fdfffff]
[    0.000000]  [mem 0x7fc00000-0x7fdfffff] page 2M
[    0.000000] BRK [0x02013000, 0x02013fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x7c000000-0x7fbfffff]
[    0.000000]  [mem 0x7c000000-0x7fbfffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x00100000-0x7bffffff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x7bffffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x7fe00000-0x7fffdfff]
[    0.000000]  [mem 0x7fe00000-0x7fffdfff] page 4k
[    0.000000] BRK [0x02014000, 0x02014fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x36d8e000-0x376befff]
[    0.000000] ACPI: RSDP 00000000000f5460 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 000000007ffff937 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 000000007ffff1c0 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 000000007fffe040 001180 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 000000007fffe000 000040
[    0.000000] ACPI: SSDT 000000007ffff234 000653 (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 000000007ffff887 000078 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 000000007ffff8ff 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] No NUMA configuration found
[    0.000000] Faking a node at [mem 0x0000000000000000-0x000000007fffdfff]
[    0.000000] Initmem setup node 0 [mem 0x00000000-0x7fffdfff]
[    0.000000]   NODE_DATA [mem 0x7ffea000-0x7fffdfff]
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:7ffda001, boot clock
[    0.000000]  [ffffea0000000000-ffffea0001ffffff] PMD -> [ffff88007d600000-ffff88007f5fffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x00001000-0x00ffffff]
[    0.000000]   DMA32    [mem 0x01000000-0xffffffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x00001000-0x0009efff]
[    0.000000]   node   0: [mem 0x00100000-0x7fffdfff]
[    0.000000] On node 0 totalpages: 524188
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 8128 pages used for memmap
[    0.000000]   DMA32 zone: 520190 pages, LIFO batch:31
[    0.000000] ACPI: PM-Timer IO Port: 0xb008
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 1 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 40
[    0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[    0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[    0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[    0.000000] e820: [mem 0x80000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] setup_percpu: NR_CPUS:1024 nr_cpumask_bits:1 nr_cpu_ids:1 nr_node_ids:1
[    0.000000] PERCPU: Embedded 29 pages/cpu @ffff88007fc00000 s86592 r8192 d24000 u2097152
[    0.000000] pcpu-alloc: s86592 r8192 d24000 u2097152 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 
[    0.000000] kvm-clock: cpu 0, msr 0:7ffda001, primary cpu clock
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr 7fc0e000
[    0.000000] Built 1 zonelists in Node order, mobility grouping on.  Total pages: 515975
[    0.000000] Policy zone: DMA32
[    0.000000] Kernel command line: BOOT_IMAGE=/vmlinuz-3.13.9-100.fc19.x86_64 root=/dev/mapper/fedora-root ro rd.lvm.lv=fedora/swap rd.md=0 rd.dm=0 vconsole.keymap=us rd.luks=0 vconsole.font=latarcyrheb-sun16 rd.lvm.lv=fedora/root rhgb quiet LANG=en_GB.utf8
[    0.000000] PID hash table entries: 4096 (order: 3, 32768 bytes)
[    0.000000] Memory: 2037648K/2096752K available (6738K kernel code, 1056K rwdata, 2980K rodata, 1464K init, 1604K bss, 59104K reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	RCU restricting CPUs from NR_CPUS=1024 to nr_cpu_ids=1.
[    0.000000] NR_IRQS:65792 nr_irqs:256 16
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] allocated 8388608 bytes of page_cgroup
[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2893.426 MHz processor
[    0.001000] Calibrating delay loop (skipped) preset value.. 5786.85 BogoMIPS (lpj=2893426)
[    0.001000] pid_max: default: 32768 minimum: 301
[    0.001000] Security Framework initialized
[    0.001000] SELinux:  Initializing.
[    0.001000] SELinux:  Starting in permissive mode
[    0.001000] Dentry cache hash table entries: 262144 (order: 9, 2097152 bytes)
[    0.001000] Inode-cache hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.001000] Mount-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    0.001000] Mountpoint-cache hash table entries: 4096 (order: 3, 32768 bytes)
[    0.001000] Initializing cgroup subsys memory
[    0.001000] Initializing cgroup subsys devices
[    0.001000] Initializing cgroup subsys freezer
[    0.001000] Initializing cgroup subsys net_cls
[    0.001000] Initializing cgroup subsys blkio
[    0.001000] Initializing cgroup subsys perf_event
[    0.001000] Initializing cgroup subsys hugetlb
[    0.001000] mce: CPU supports 10 MCE banks
[    0.001000] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0
tlb_flushall_shift: 6
[    0.010263] Freeing SMP alternatives memory: 24K (ffffffff81e77000 - ffffffff81e7d000)
[    0.012944] ACPI: Core revision 20131115
[    0.013334] ACPI: All ACPI Tables successfully acquired
[    0.013358] ftrace: allocating 25848 entries in 101 pages
[    0.018227] Enabling x2apic
[    0.018236] Enabled x2apic
[    0.018387] Switched APIC routing to physical x2apic.
[    0.019729] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.019732] smpboot: CPU0: Intel QEMU Virtual CPU version 2.0.50 (fam: 06, model: 06, stepping: 03)
[    0.020000] Performance Events: Broken PMU hardware detected, using software events only.
[    0.020000] Failed to access perfctr msr (MSR c1 is 0)
[    0.020097] x86: Booted up 1 node, 1 CPUs
[    0.020100] smpboot: Total of 1 processors activated (5786.85 BogoMIPS)
[    0.020270] NMI watchdog: disabled (cpu0): hardware events not enabled
[    0.020371] devtmpfs: initialized
[    0.021063] atomic64 test passed for x86-64 platform with CX8 and with SSE
[    0.021064] pinctrl core: initialized pinctrl subsystem
[    0.021131] RTC time: 18:47:42, date: 05/01/14
[    0.021156] NET: Registered protocol family 16
[    0.021220] cpuidle: using governor menu
[    0.021283] ACPI: bus type PCI registered
[    0.021284] acpiphp: ACPI Hot Plug PCI Controller Driver version: 0.5
[    0.021358] PCI: Using configuration type 1 for base access
[    0.021862] bio: create slab <bio-0> at 0
[    0.021925] ACPI: Added _OSI(Module Device)
[    0.021926] ACPI: Added _OSI(Processor Device)
[    0.021927] ACPI: Added _OSI(3.0 _SCP Extensions)
[    0.021928] ACPI: Added _OSI(Processor Aggregator Device)
[    0.022958] ACPI: Interpreter enabled
[    0.022961] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_] (20131115/hwxface-580)
[    0.022964] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_] (20131115/hwxface-580)
[    0.022970] ACPI: (supports S0 S3 S4 S5)
[    0.022971] ACPI: Using IOAPIC for interrupt routing
[    0.022979] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    0.023009] ACPI: No dock devices found.
[    0.024052] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.024056] acpi PNP0A03:00: _OSC: OS supports [ASPM ClockPM Segments MSI]
[    0.024058] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    0.024103] acpi PNP0A03:00: fail to add MMCONFIG information, can't access extended PCI configuration space under this bridge.
[    0.024229] PCI host bridge to bus 0000:00
[    0.024232] pci_bus 0000:00: root bus resource [bus 00-ff]
[    0.024234] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7]
[    0.024235] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff]
[    0.024237] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff]
[    0.024238] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf]
[    0.024239] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff]
[    0.024240] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff]
[    0.024242] pci_bus 0000:00: root bus resource [mem 0x80000000-0xfebfffff]
[    0.024281] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    0.024523] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    0.024870] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    0.030003] pci 0000:00:01.1: reg 0x20: [io  0xd080-0xd08f]
[    0.032660] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    0.033002] pci 0000:00:01.3: quirk: [io  0xb000-0xb03f] claimed by PIIX4 ACPI
[    0.033011] pci 0000:00:01.3: quirk: [io  0xb100-0xb10f] claimed by PIIX4 SMB
[    0.033139] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000
[    0.035005] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref]
[    0.037005] pci 0000:00:02.0: reg 0x14: [mem 0xfea10000-0xfea10fff]
[    0.047018] pci 0000:00:02.0: reg 0x30: [mem 0xfea00000-0xfea0ffff pref]
[    0.048051] pci 0000:00:03.0: [1b36:0001] type 01 class 0x060400
[    0.050004] pci 0000:00:03.0: reg 0x10: [mem 0xfea11000-0xfea110ff 64bit]
[    0.053155] pci 0000:00:04.0: [1af4:1009] type 00 class 0x000200
[    0.055003] pci 0000:00:04.0: reg 0x10: [io  0xd000-0xd03f]
[    0.057005] pci 0000:00:04.0: reg 0x14: [mem 0xfea12000-0xfea12fff]
[    0.067282] pci 0000:00:05.0: [1af4:1001] type 00 class 0x010000
[    0.069003] pci 0000:00:05.0: reg 0x10: [io  0xd040-0xd07f]
[    0.071004] pci 0000:00:05.0: reg 0x14: [mem 0xfea13000-0xfea13fff]
[    0.082198] pci 0000:01:01.0: [8086:100e] type 00 class 0x020000
[    0.084003] pci 0000:01:01.0: reg 0x10: [mem 0xfe840000-0xfe85ffff]
[    0.086002] pci 0000:01:01.0: reg 0x14: [io  0xc000-0xc03f]
[    0.096003] pci 0000:01:01.0: reg 0x30: [mem 0xfe800000-0xfe83ffff pref]
[    0.097025] pci 0000:00:03.0: PCI bridge to [bus 01]
[    0.097039] pci 0000:00:03.0:   bridge window [io  0xc000-0xcfff]
[    0.097051] pci 0000:00:03.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    0.097075] pci 0000:00:03.0:   bridge window [mem 0xfe000000-0xfe1fffff 64bit pref]
[    0.098716] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 *10 11)
[    0.098785] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 *10 11)
[    0.098847] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 10 *11)
[    0.099040] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 10 *11)
[    0.099073] ACPI: PCI Interrupt Link [LNKS] (IRQs *9)
[    0.099294] ACPI: Enabled 16 GPEs in block 00 to 0F
[    0.099299] ACPI: \_SB_.PCI0: notify handler is installed
[    0.099306] Found 1 acpi root devices
[    0.099383] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    0.099384] vgaarb: loaded
[    0.099385] vgaarb: bridge control possible 0000:00:02.0
[    0.099435] SCSI subsystem initialized
[    0.099465] libata version 3.00 loaded.
[    0.099476] ACPI: bus type USB registered
[    0.099489] usbcore: registered new interface driver usbfs
[    0.099495] usbcore: registered new interface driver hub
[    0.099506] usbcore: registered new device driver usb
[    0.099536] PCI: Using ACPI for IRQ routing
[    0.099537] PCI: pci_cache_line_size set to 64 bytes
[    0.099647] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    0.099648] e820: reserve RAM buffer [mem 0x7fffe000-0x7fffffff]
[    0.099709] NetLabel: Initializing
[    0.099710] NetLabel:  domain hash size = 128
[    0.099711] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.099722] NetLabel:  unlabeled traffic allowed by default
[    0.099770] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.099782] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.099784] hpet0: 3 comparators, 64-bit 100.000000 MHz counter
[    0.102028] Switched to clocksource kvm-clock
[    0.105099] pnp: PnP ACPI init
[    0.105111] ACPI: bus type PNP registered
[    0.105171] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    0.105207] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    0.105234] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    0.105263] pnp 00:03: [dma 2]
[    0.105273] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    0.105327] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    0.105369] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    0.105439] pnp 00:06: Plug and Play ACPI device, IDs PNP0103 (active)
[    0.105523] pnp 00:07: Plug and Play ACPI device, IDs PNP0a06 (active)
[    0.105526] pnp: PnP ACPI: found 8 devices
[    0.105527] ACPI: bus type PNP unregistered
[    0.112087] pci 0000:00:03.0: PCI bridge to [bus 01]
[    0.112099] pci 0000:00:03.0:   bridge window [io  0xc000-0xcfff]
[    0.113706] pci 0000:00:03.0:   bridge window [mem 0xfe800000-0xfe9fffff]
[    0.114789] pci 0000:00:03.0:   bridge window [mem 0xfe000000-0xfe1fffff 64bit pref]
[    0.116927] pci_bus 0000:00: resource 4 [io  0x0000-0x0cf7]
[    0.116929] pci_bus 0000:00: resource 5 [io  0x0d00-0xadff]
[    0.116930] pci_bus 0000:00: resource 6 [io  0xae0f-0xaeff]
[    0.116931] pci_bus 0000:00: resource 7 [io  0xaf20-0xafdf]
[    0.116932] pci_bus 0000:00: resource 8 [io  0xafe4-0xffff]
[    0.116933] pci_bus 0000:00: resource 9 [mem 0x000a0000-0x000bffff]
[    0.116935] pci_bus 0000:00: resource 10 [mem 0x80000000-0xfebfffff]
[    0.116936] pci_bus 0000:01: resource 0 [io  0xc000-0xcfff]
[    0.116937] pci_bus 0000:01: resource 1 [mem 0xfe800000-0xfe9fffff]
[    0.116938] pci_bus 0000:01: resource 2 [mem 0xfe000000-0xfe1fffff 64bit pref]
[    0.116961] NET: Registered protocol family 2
[    0.117065] TCP established hash table entries: 16384 (order: 5, 131072 bytes)
[    0.117091] TCP bind hash table entries: 16384 (order: 6, 262144 bytes)
[    0.117123] TCP: Hash tables configured (established 16384 bind 16384)
[    0.117140] TCP: reno registered
[    0.117143] UDP hash table entries: 1024 (order: 3, 32768 bytes)
[    0.117151] UDP-Lite hash table entries: 1024 (order: 3, 32768 bytes)
[    0.117184] NET: Registered protocol family 1
[    0.117190] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    0.117203] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    0.117217] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    0.117244] pci 0000:00:02.0: Boot video device
[    0.117275] PCI: CLS 0 bytes, default 64
[    0.117304] Unpacking initramfs...
[    0.218975] Freeing initrd memory: 9412K (ffff880036d8e000 - ffff8800376bf000)
[    0.219304] Initialise system trusted keyring
[    0.219339] audit: initializing netlink socket (disabled)
[    0.219346] type=2000 audit(1398970063.873:1): initialized
[    0.234523] HugeTLB registered 2 MB page size, pre-allocated 0 pages
[    0.235170] zbud: loaded
[    0.235268] VFS: Disk quotas dquot_6.5.2
[    0.235290] Dquot-cache hash table entries: 512 (order 0, 4096 bytes)
[    0.235485] msgmni has been set to 3998
[    0.235517] Key type big_key registered
[    0.235518] SELinux:  Registering netfilter hooks
[    0.235866] alg: No test for stdrng (krng)
[    0.235868] NET: Registered protocol family 38
[    0.235869] Key type asymmetric registered
[    0.235870] Asymmetric key parser 'x509' registered
[    0.235887] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 252)
[    0.235899] io scheduler noop registered
[    0.235900] io scheduler deadline registered
[    0.235913] io scheduler cfq registered (default)
[    0.235970] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.235979] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.235998] intel_idle: does not run on family 6 model 6
[    0.236040] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input0
[    0.236043] ACPI: Power Button [PWRF]
[    0.236119] GHES: HEST is not enabled!
[    0.236152] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.259482] 00:05: ttyS0 at I/O 0x3f8 (irq = 4, base_baud = 115200) is a 16550A
[    0.259756] Non-volatile memory driver v1.3
[    0.259757] Linux agpgart interface v0.103
[    0.259841] ata_piix 0000:00:01.1: version 2.13
[    0.260953] scsi0 : ata_piix
[    0.260986] scsi1 : ata_piix
[    0.261003] ata1: PATA max MWDMA2 cmd 0x1f0 ctl 0x3f6 bmdma 0xd080 irq 14
[    0.261014] ata2: PATA max MWDMA2 cmd 0x170 ctl 0x376 bmdma 0xd088 irq 15
[    0.261391] libphy: Fixed MDIO Bus: probed
[    0.261419] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.261423] ehci-pci: EHCI PCI platform driver
[    0.261429] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.261429] ohci-pci: OHCI PCI platform driver
[    0.261433] uhci_hcd: USB Universal Host Controller Interface driver
[    0.261461] usbcore: registered new interface driver usbserial
[    0.261466] usbcore: registered new interface driver usbserial_generic
[    0.261470] usbserial: USB Serial support registered for generic
[    0.261488] i8042: PNP: PS/2 Controller [PNP0303:KBD,PNP0f13:MOU] at 0x60,0x64 irq 1,12
[    0.262106] serio: i8042 KBD port at 0x60,0x64 irq 1
[    0.262109] serio: i8042 AUX port at 0x60,0x64 irq 12
[    0.262154] mousedev: PS/2 mouse device common for all mice
[    0.262358] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input1
[    0.263653] rtc_cmos 00:00: RTC can wake from S4
[    0.263851] rtc_cmos 00:00: rtc core: registered rtc_cmos as rtc0
[    0.263975] rtc_cmos 00:00: alarms up to one day, 114 bytes nvram, hpet irqs
[    0.264025] device-mapper: uevent: version 1.0.3
[    0.264057] device-mapper: ioctl: 4.27.0-ioctl (2013-10-30) initialised: dm-devel@redhat.com
[    0.264103] hidraw: raw HID events driver (C) Jiri Kosina
[    0.264152] usbcore: registered new interface driver usbhid
[    0.264153] usbhid: USB HID core driver
[    0.264168] drop_monitor: Initializing network drop monitor service
[    0.264209] ip_tables: (C) 2000-2006 Netfilter Core Team
[    0.264226] TCP: cubic registered
[    0.264227] Initializing XFRM netlink socket
[    0.264277] NET: Registered protocol family 10
[    0.264382] mip6: Mobile IPv6
[    0.264383] NET: Registered protocol family 17
[    0.264495] Loading compiled-in X.509 certificates
[    0.265114] Loaded X.509 cert 'Fedora kernel signing key: 5f7bc6b5d20a07962649ae80d432456c0e96b904'
[    0.265121] registered taskstats version 1
[    0.265388]   Magic number: 14:213:796
[    0.265460] rtc_cmos 00:00: setting system clock to 2014-05-01 18:47:43 UTC (1398970063)
[    0.265473] PM: Hibernation image not present or could not be loaded.
[    0.412806] ata2.01: NODEV after polling detection
[    0.413052] ata2.00: ATAPI: QEMU DVD-ROM, 2.0.50, max UDMA/100
[    0.413420] ata2.00: configured for MWDMA2
[    0.413804] scsi 1:0:0:0: CD-ROM            QEMU     QEMU DVD-ROM     2.0. PQ: 0 ANSI: 5
[    0.414375] sr0: scsi3-mmc drive: 4x/4x cd/rw xa/form2 tray
[    0.414377] cdrom: Uniform CD-ROM driver Revision: 3.20
[    0.414443] sr 1:0:0:0: Attached scsi CD-ROM sr0
[    0.414470] sr 1:0:0:0: Attached scsi generic sg0 type 5
[    0.415095] Freeing unused kernel memory: 1464K (ffffffff81d09000 - ffffffff81e77000)
[    0.415096] Write protecting the kernel read-only data: 12288k
[    0.417145] Freeing unused kernel memory: 1444K (ffff880001697000 - ffff880001800000)
[    0.418622] Freeing unused kernel memory: 1116K (ffff880001ae9000 - ffff880001c00000)
[    0.421056] systemd[1]: systemd 204 running in system mode. (+PAM +LIBWRAP +AUDIT +SELINUX +IMA +SYSVINIT +LIBCRYPTSETUP +GCRYPT +ACL +XZ)
[    0.421081] systemd[1]: Detected virtualization 'kvm'.
[    0.421083] systemd[1]: Running in initial RAM disk.
[    0.421162] systemd[1]: Set hostname to <localhost.localdomain>.
[    0.421524] random: systemd urandom read with 4 bits of entropy available
[    0.424551] systemd[1]: Expecting device dev-disk-by\x2duuid-1bf9bceb\x2d5389\x2d45aa\x2da61f\x2d480b94bd1147.device...
[    0.424560] systemd[1]: Expecting device dev-mapper-fedora\x2droot.device...
[    0.424565] systemd[1]: Expecting device dev-mapper-fedora\x2dswap.device...
[    0.424570] systemd[1]: Starting Timers.
[    0.424576] systemd[1]: Reached target Timers.
[    0.424627] systemd[1]: Starting udev Kernel Socket.
[    0.424646] systemd[1]: Listening on udev Kernel Socket.
[    0.424688] systemd[1]: Starting udev Control Socket.
[    0.424705] systemd[1]: Listening on udev Control Socket.
[    0.424725] systemd[1]: Starting Journal Socket.
[    0.424756] systemd[1]: Listening on Journal Socket.
[    0.424865] systemd[1]: Starting dracut cmdline hook...
[    0.425114] systemd[1]: Starting Sockets.
[    0.425121] systemd[1]: Reached target Sockets.
[    0.425142] systemd[1]: Starting Journal Service...
[    0.425365] systemd[1]: Started Journal Service.
[    0.428364] systemd-journald[49]: Vacuuming done, freed 0 bytes
[    0.431165] systemd[1]: Started Load Kernel Modules.
[    0.431174] systemd[1]: Starting Swap.
[    0.431181] systemd[1]: Reached target Swap.
[    0.431201] systemd[1]: Starting Local File Systems.
[    0.431208] systemd[1]: Reached target Local File Systems.
[    0.523034] systemd-udevd[135]: starting version 204
[    0.599633] e1000: Intel(R) PRO/1000 Network Driver - version 7.3.21-k8-NAPI
[    0.599636] e1000: Copyright (c) 1999-2006 Intel Corporation.
[    0.599888] ACPI: PCI Interrupt Link [LNKC] enabled at IRQ 11
[    0.603620] ACPI: PCI Interrupt Link [LNKD] enabled at IRQ 10
[    0.615490] ACPI: PCI Interrupt Link [LNKA] enabled at IRQ 10
[    0.622270] [drm] Initialized drm 1.1.0 20060810
[    0.895569] virtio-pci 0000:00:05.0: irq 40 for MSI/MSI-X
[    0.895585] virtio-pci 0000:00:05.0: irq 41 for MSI/MSI-X
[    0.895722] blk-mq: CPU -> queue map
[    0.895723]   CPU 0 -> Queue 0
[    0.896213]  vda: vda1 vda2 vda3 vda4
[    0.898235] [TTM] Zone  kernel: Available graphics memory: 1025554 kiB
[    0.898248] [TTM] Initializing pool allocator
[    0.898252] [TTM] Initializing DMA pool allocator
[    0.898449] [drm] fb mappable at 0x0
[    0.898450] [drm] vram aper at 0x0
[    0.898450] [drm] size 0
[    0.898451] [drm] fb depth is 24
[    0.898452] [drm]    pitch is 3072
[    0.898479] fbcon: cirrusdrmfb (fb0) is primary device
[    0.914517] Console: switching to colour frame buffer device 128x48
[    0.933387] cirrus 0000:00:02.0: fb0: cirrusdrmfb frame buffer device
[    0.933389] cirrus 0000:00:02.0: registered panic notifier
[    0.933467] [drm] Initialized cirrus 1.0.0 20110418 for 0000:00:02.0 on minor 0
[    0.952868] e1000 0000:01:01.0 eth0: (PCI:33MHz:32-bit) 52:54:00:12:34:56
[    0.952876] e1000 0000:01:01.0 eth0: Intel(R) PRO/1000 Network Connection
[    1.107863] bio: create slab <bio-1> at 1
[    1.139799] EXT4-fs (dm-1): mounted filesystem with ordered data mode. Opts: (null)
[    1.177880] systemd-journald[49]: Received SIGTERM
[    1.192980] type=1404 audit(1398970064.426:2): enforcing=1 old_enforcing=0 auid=4294967295 ses=4294967295
[    1.220052] tsc: Refined TSC clocksource calibration: 2893.425 MHz
[    1.305854] SELinux: 2048 avtab hash slots, 95783 rules.
[    1.315784] SELinux: 2048 avtab hash slots, 95783 rules.
[    1.366813] input: ImExPS/2 Generic Explorer Mouse as /devices/platform/i8042/serio1/input/input3
[    1.567539] SELinux:  8 users, 82 roles, 4563 types, 259 bools, 1 sens, 1024 cats
[    1.567542] SELinux:  83 classes, 95783 rules
[    1.570829] SELinux:  Completing initialization.
[    1.570831] SELinux:  Setting up existing superblocks.
[    1.570837] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
[    1.570840] SELinux: initialized (dev rootfs, type rootfs), uses genfs_contexts
[    1.570848] SELinux: initialized (dev bdev, type bdev), uses genfs_contexts
[    1.570853] SELinux: initialized (dev proc, type proc), uses genfs_contexts
[    1.570858] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
[    1.570871] SELinux: initialized (dev devtmpfs, type devtmpfs), uses transition SIDs
[    1.571278] SELinux: initialized (dev debugfs, type debugfs), uses genfs_contexts
[    1.571655] SELinux: initialized (dev sockfs, type sockfs), uses task SIDs
[    1.571657] SELinux: initialized (dev pipefs, type pipefs), uses task SIDs
[    1.571659] SELinux: initialized (dev anon_inodefs, type anon_inodefs), uses genfs_contexts
[    1.571661] SELinux: initialized (dev aio, type aio), not configured for labeling
[    1.571663] SELinux: initialized (dev devpts, type devpts), uses transition SIDs
[    1.571674] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs
[    1.571678] SELinux: initialized (dev mqueue, type mqueue), uses transition SIDs
[    1.571683] SELinux: initialized (dev selinuxfs, type selinuxfs), uses genfs_contexts
[    1.571689] SELinux: initialized (dev sysfs, type sysfs), uses genfs_contexts
[    1.571793] SELinux: initialized (dev securityfs, type securityfs), uses genfs_contexts
[    1.571795] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
[    1.571799] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
[    1.571849] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
[    1.571872] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571876] SELinux: initialized (dev pstore, type pstore), uses genfs_contexts
[    1.571877] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571880] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571894] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571898] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571900] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571902] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571903] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571907] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571910] SELinux: initialized (dev cgroup, type cgroup), uses genfs_contexts
[    1.571915] SELinux: initialized (dev dm-1, type ext4), uses xattr
[    1.572909] type=1403 audit(1398970064.806:3): policy loaded auid=4294967295 ses=4294967295
[    1.576799] systemd[1]: Successfully loaded SELinux policy in 384.588ms.
[    1.595075] systemd[1]: Relabelled /dev and /run in 9.496ms.
[    1.627611] LVM: Logical Volume autoactivation enabled.
[    1.627615] LVM: Activation generator successfully completed.
[    1.683925] SELinux: initialized (dev autofs, type autofs), uses genfs_contexts
[    1.712005] SELinux: initialized (dev hugetlbfs, type hugetlbfs), uses transition SIDs
[    1.712100] SELinux: initialized (dev tmpfs, type tmpfs), uses transition SIDs
[    1.714359] SELinux: initialized (dev configfs, type configfs), uses genfs_contexts
[    1.751978] systemd-journald[284]: Vacuuming done, freed 0 bytes
[    1.808040] systemd-udevd[295]: starting version 204
[    1.833826] EXT4-fs (dm-1): re-mounted. Opts: (null)
[    1.858055] FS-Cache: Loaded
[    1.860360] 9pnet: Installing 9P2000 support
[    1.863772] 9p: Installing v9fs 9p2000 file system support
[    1.863797] FS-Cache: Netfs '9p' registered for caching
[    1.863993] 9pnet: Could not find request transport: virtio
[    1.884877] systemd-journald[284]: Received request to flush runtime journal from PID 1
[    1.929118] type=1305 audit(1398970065.163:4): audit_pid=316 old=0 auid=4294967295 ses=4294967295 subj=system_u:system_r:auditd_t:s0 res=1
[    2.052565] shpchp 0000:00:03.0: Hotplug Controller:
[    2.052612] shpchp 0000:00:03.0:  cap_offset = 40
[    2.052642] shpchp 0000:00:03.0:  num_slots (indirect) 1f
[    2.052654] shpchp 0000:00:03.0:  offset 0: value 0
[    2.052668] shpchp 0000:00:03.0:  offset 1: value 1f
[    2.052680] shpchp 0000:00:03.0:  offset 2: value 0
[    2.052693] shpchp 0000:00:03.0:  offset 3: value e001011f
[    2.052706] shpchp 0000:00:03.0:  offset 4: value 1000000
[    2.052719] shpchp 0000:00:03.0:  offset 5: value 0
[    2.052731] shpchp 0000:00:03.0:  offset 6: value 0
[    2.052750] shpchp 0000:00:03.0:  offset 7: value 0
[    2.052763] shpchp 0000:00:03.0:  offset 8: value f
[    2.052776] shpchp 0000:00:03.0:  offset 9: value 7f000006
[    2.052788] shpchp 0000:00:03.0:  offset 10: value 7f000d0f
[    2.052801] shpchp 0000:00:03.0:  offset 11: value 7f000d0f
[    2.052814] shpchp 0000:00:03.0:  offset 12: value 7f000d0f
[    2.052827] shpchp 0000:00:03.0:  offset 13: value 7f000d0f
[    2.052839] shpchp 0000:00:03.0:  offset 14: value 7f000d0f
[    2.052852] shpchp 0000:00:03.0:  offset 15: value 7f000d0f
[    2.052864] shpchp 0000:00:03.0:  offset 16: value 7f000d0f
[    2.052876] shpchp 0000:00:03.0:  offset 17: value 7f000d0f
[    2.052889] shpchp 0000:00:03.0:  offset 18: value 7f000d0f
[    2.052903] shpchp 0000:00:03.0:  offset 19: value 7f000d0f
[    2.052915] shpchp 0000:00:03.0:  offset 20: value 7f000d0f
[    2.052928] shpchp 0000:00:03.0:  offset 21: value 7f000d0f
[    2.052940] shpchp 0000:00:03.0:  offset 22: value 7f000d0f
[    2.052953] shpchp 0000:00:03.0:  offset 23: value 7f000d0f
[    2.052965] shpchp 0000:00:03.0:  offset 24: value 7f000d0f
[    2.052978] shpchp 0000:00:03.0:  offset 25: value 7f000d0f
[    2.052990] shpchp 0000:00:03.0:  offset 26: value 7f000d0f
[    2.053015] shpchp 0000:00:03.0:  offset 27: value 7f000d0f
[    2.053030] shpchp 0000:00:03.0:  offset 28: value 7f000d0f
[    2.053043] shpchp 0000:00:03.0:  offset 29: value 7f000d0f
[    2.053056] shpchp 0000:00:03.0:  offset 30: value 7f000d0f
[    2.053068] shpchp 0000:00:03.0:  offset 31: value 7f000d0f
[    2.053081] shpchp 0000:00:03.0:  offset 32: value 7f000d0f
[    2.053093] shpchp 0000:00:03.0:  offset 33: value 7f000d0f
[    2.053106] shpchp 0000:00:03.0:  offset 34: value 7f000d0f
[    2.053118] shpchp 0000:00:03.0:  offset 35: value 7f000d0f
[    2.053131] shpchp 0000:00:03.0:  offset 36: value 7f000d0f
[    2.053143] shpchp 0000:00:03.0:  offset 37: value 7f000d0f
[    2.053156] shpchp 0000:00:03.0:  offset 38: value 7f000d0f
[    2.053168] shpchp 0000:00:03.0:  offset 39: value 7f000d0f
[    2.053170] shpchp 0000:00:03.0: HPC vendor_id 1b36 device_id 1 ss_vid 0 ss_did 0
[    2.054849] virtio-pci 0000:00:04.0: irq 42 for MSI/MSI-X
[    2.054867] virtio-pci 0000:00:04.0: irq 43 for MSI/MSI-X
[    2.055033] shpchp 0000:00:03.0: ctrl->creg ffffc900003d4000
[    2.055046] shpchp 0000:00:03.0: SERR_INTR_ENABLE = f
[    2.055056] shpchp 0000:00:03.0: SERR_INTR_ENABLE = f
[    2.055061] shpchp 0000:00:03.0: Default Logical Slot Register 0 value 7f000006
[    2.055070] shpchp 0000:00:03.0: Default Logical Slot Register 1 value 7f000d0f
[    2.055079] shpchp 0000:00:03.0: Default Logical Slot Register 2 value 7f000d0f
[    2.055087] shpchp 0000:00:03.0: Default Logical Slot Register 3 value 7f000d0f
[    2.055096] shpchp 0000:00:03.0: Default Logical Slot Register 4 value 7f000d0f
[    2.055105] shpchp 0000:00:03.0: Default Logical Slot Register 5 value 7f000d0f
[    2.055113] shpchp 0000:00:03.0: Default Logical Slot Register 6 value 7f000d0f
[    2.055122] shpchp 0000:00:03.0: Default Logical Slot Register 7 value 7f000d0f
[    2.055132] shpchp 0000:00:03.0: Default Logical Slot Register 8 value 7f000d0f
[    2.055140] shpchp 0000:00:03.0: Default Logical Slot Register 9 value 7f000d0f
[    2.055149] shpchp 0000:00:03.0: Default Logical Slot Register 10 value 7f000d0f
[    2.055158] shpchp 0000:00:03.0: Default Logical Slot Register 11 value 7f000d0f
[    2.055167] shpchp 0000:00:03.0: Default Logical Slot Register 12 value 7f000d0f
[    2.055175] shpchp 0000:00:03.0: Default Logical Slot Register 13 value 7f000d0f
[    2.055184] shpchp 0000:00:03.0: Default Logical Slot Register 14 value 7f000d0f
[    2.055193] shpchp 0000:00:03.0: Default Logical Slot Register 15 value 7f000d0f
[    2.055202] shpchp 0000:00:03.0: Default Logical Slot Register 16 value 7f000d0f
[    2.055210] shpchp 0000:00:03.0: Default Logical Slot Register 17 value 7f000d0f
[    2.055219] shpchp 0000:00:03.0: Default Logical Slot Register 18 value 7f000d0f
[    2.055228] shpchp 0000:00:03.0: Default Logical Slot Register 19 value 7f000d0f
[    2.055236] shpchp 0000:00:03.0: Default Logical Slot Register 20 value 7f000d0f
[    2.055245] shpchp 0000:00:03.0: Default Logical Slot Register 21 value 7f000d0f
[    2.055254] shpchp 0000:00:03.0: Default Logical Slot Register 22 value 7f000d0f
[    2.055263] shpchp 0000:00:03.0: Default Logical Slot Register 23 value 7f000d0f
[    2.055271] shpchp 0000:00:03.0: Default Logical Slot Register 24 value 7f000d0f
[    2.055280] shpchp 0000:00:03.0: Default Logical Slot Register 25 value 7f000d0f
[    2.055289] shpchp 0000:00:03.0: Default Logical Slot Register 26 value 7f000d0f
[    2.055297] shpchp 0000:00:03.0: Default Logical Slot Register 27 value 7f000d0f
[    2.055306] shpchp 0000:00:03.0: Default Logical Slot Register 28 value 7f000d0f
[    2.055315] shpchp 0000:00:03.0: Default Logical Slot Register 29 value 7f000d0f
[    2.055323] shpchp 0000:00:03.0: Default Logical Slot Register 30 value 7f000d0f
[    2.055397] shpchp 0000:00:03.0: irq 44 for MSI/MSI-X
[    2.058379] shpchp 0000:00:03.0: request_irq 44 (returns 0)
[    2.058382] shpchp 0000:00:03.0: HPC at 0000:00:03.0 irq=2c
[    2.058398] shpchp 0000:00:03.0: Max bus speed = 0
[    2.058407] shpchp: Current bus speed = 0
[    2.058413] shpchp 0000:00:03.0: Default Logical Slot Register 0 value 7f000006
[    2.058423] shpchp 0000:00:03.0: Default Logical Slot Register 1 value 7f000d0f
[    2.058432] shpchp 0000:00:03.0: Default Logical Slot Register 2 value 7f000d0f
[    2.058441] shpchp 0000:00:03.0: Default Logical Slot Register 3 value 7f000d0f
[    2.058449] shpchp 0000:00:03.0: Default Logical Slot Register 4 value 7f000d0f
[    2.058458] shpchp 0000:00:03.0: Default Logical Slot Register 5 value 7f000d0f
[    2.058467] shpchp 0000:00:03.0: Default Logical Slot Register 6 value 7f000d0f
[    2.058476] shpchp 0000:00:03.0: Default Logical Slot Register 7 value 7f000d0f
[    2.058485] shpchp 0000:00:03.0: Default Logical Slot Register 8 value 7f000d0f
[    2.058494] shpchp 0000:00:03.0: Default Logical Slot Register 9 value 7f000d0f
[    2.058503] shpchp 0000:00:03.0: Default Logical Slot Register 10 value 7f000d0f
[    2.058511] shpchp 0000:00:03.0: Default Logical Slot Register 11 value 7f000d0f
[    2.058520] shpchp 0000:00:03.0: Default Logical Slot Register 12 value 7f000d0f
[    2.058529] shpchp 0000:00:03.0: Default Logical Slot Register 13 value 7f000d0f
[    2.058538] shpchp 0000:00:03.0: Default Logical Slot Register 14 value 7f000d0f
[    2.058547] shpchp 0000:00:03.0: Default Logical Slot Register 15 value 7f000d0f
[    2.058556] shpchp 0000:00:03.0: Default Logical Slot Register 16 value 7f000d0f
[    2.058565] shpchp 0000:00:03.0: Default Logical Slot Register 17 value 7f000d0f
[    2.058573] shpchp 0000:00:03.0: Default Logical Slot Register 18 value 7f000d0f
[    2.058587] shpchp 0000:00:03.0: Default Logical Slot Register 19 value 7f000d0f
[    2.058599] shpchp 0000:00:03.0: Default Logical Slot Register 20 value 7f000d0f
[    2.058608] shpchp 0000:00:03.0: Default Logical Slot Register 21 value 7f000d0f
[    2.058617] shpchp 0000:00:03.0: Default Logical Slot Register 22 value 7f000d0f
[    2.058625] shpchp 0000:00:03.0: Default Logical Slot Register 23 value 7f000d0f
[    2.058634] shpchp 0000:00:03.0: Default Logical Slot Register 24 value 7f000d0f
[    2.058643] shpchp 0000:00:03.0: Default Logical Slot Register 25 value 7f000d0f
[    2.058652] shpchp 0000:00:03.0: Default Logical Slot Register 26 value 7f000d0f
[    2.058661] shpchp 0000:00:03.0: Default Logical Slot Register 27 value 7f000d0f
[    2.058669] shpchp 0000:00:03.0: Default Logical Slot Register 28 value 7f000d0f
[    2.058678] shpchp 0000:00:03.0: Default Logical Slot Register 29 value 7f000d0f
[    2.058687] shpchp 0000:00:03.0: Default Logical Slot Register 30 value 7f000d0f
[    2.058703] shpchp 0000:00:03.0: SERR_INTR_ENABLE = a
[    2.058715] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:01 hp_slot=0 sun=1 slot_device_offset=1
[    2.058727] shpchp 0000:00:03.0: get_power_status: physical_slot = 1
[    2.058734] shpchp 0000:00:03.0: get_attention_status: physical_slot = 1
[    2.058745] shpchp 0000:00:03.0: get_latch_status: physical_slot = 1
[    2.058750] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 1
[    2.058762] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:02 hp_slot=1 sun=2 slot_device_offset=1
[    2.058772] shpchp 0000:00:03.0: get_power_status: physical_slot = 2
[    2.058779] shpchp 0000:00:03.0: get_attention_status: physical_slot = 2
[    2.058784] shpchp 0000:00:03.0: get_latch_status: physical_slot = 2
[    2.058789] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 2
[    2.058805] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:03 hp_slot=2 sun=3 slot_device_offset=1
[    2.058812] shpchp 0000:00:03.0: get_power_status: physical_slot = 3-1
[    2.058819] shpchp 0000:00:03.0: get_attention_status: physical_slot = 3-1
[    2.058824] shpchp 0000:00:03.0: get_latch_status: physical_slot = 3-1
[    2.058829] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 3-1
[    2.059308] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:04 hp_slot=3 sun=4 slot_device_offset=1
[    2.059318] shpchp 0000:00:03.0: get_power_status: physical_slot = 4
[    2.059328] shpchp 0000:00:03.0: get_attention_status: physical_slot = 4
[    2.059334] shpchp 0000:00:03.0: get_latch_status: physical_slot = 4
[    2.059339] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 4
[    2.059350] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:05 hp_slot=4 sun=5 slot_device_offset=1
[    2.059356] shpchp 0000:00:03.0: get_power_status: physical_slot = 5
[    2.059362] shpchp 0000:00:03.0: get_attention_status: physical_slot = 5
[    2.059367] shpchp 0000:00:03.0: get_latch_status: physical_slot = 5
[    2.059372] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 5
[    2.059382] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:06 hp_slot=5 sun=6 slot_device_offset=1
[    2.059389] shpchp 0000:00:03.0: get_power_status: physical_slot = 6
[    2.059396] shpchp 0000:00:03.0: get_attention_status: physical_slot = 6
[    2.059401] shpchp 0000:00:03.0: get_latch_status: physical_slot = 6
[    2.059406] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 6
[    2.059875] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:07 hp_slot=6 sun=7 slot_device_offset=1
[    2.059888] shpchp 0000:00:03.0: get_power_status: physical_slot = 7
[    2.059905] shpchp 0000:00:03.0: get_attention_status: physical_slot = 7
[    2.059912] shpchp 0000:00:03.0: get_latch_status: physical_slot = 7
[    2.059917] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 7
[    2.060300] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:08 hp_slot=7 sun=8 slot_device_offset=1
[    2.060311] shpchp 0000:00:03.0: get_power_status: physical_slot = 8
[    2.060323] shpchp 0000:00:03.0: get_attention_status: physical_slot = 8
[    2.060329] shpchp 0000:00:03.0: get_latch_status: physical_slot = 8
[    2.060334] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 8
[    2.060488] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:09 hp_slot=8 sun=9 slot_device_offset=1
[    2.060497] shpchp 0000:00:03.0: get_power_status: physical_slot = 9
[    2.060508] shpchp 0000:00:03.0: get_attention_status: physical_slot = 9
[    2.060513] shpchp 0000:00:03.0: get_latch_status: physical_slot = 9
[    2.060518] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 9
[    2.061024] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0a hp_slot=9 sun=a slot_device_offset=1
[    2.061039] shpchp 0000:00:03.0: get_power_status: physical_slot = 10
[    2.061053] shpchp 0000:00:03.0: get_attention_status: physical_slot = 10
[    2.061058] shpchp 0000:00:03.0: get_latch_status: physical_slot = 10
[    2.061064] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 10
[    2.062256] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0b hp_slot=a sun=b slot_device_offset=1
[    2.062269] shpchp 0000:00:03.0: get_power_status: physical_slot = 11
[    2.062288] shpchp 0000:00:03.0: get_attention_status: physical_slot = 11
[    2.062294] shpchp 0000:00:03.0: get_latch_status: physical_slot = 11
[    2.062299] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 11
[    2.062312] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0c hp_slot=b sun=c slot_device_offset=1
[    2.062320] shpchp 0000:00:03.0: get_power_status: physical_slot = 12
[    2.062326] shpchp 0000:00:03.0: get_attention_status: physical_slot = 12
[    2.062331] shpchp 0000:00:03.0: get_latch_status: physical_slot = 12
[    2.062336] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 12
[    2.062346] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0d hp_slot=c sun=d slot_device_offset=1
[    2.062353] shpchp 0000:00:03.0: get_power_status: physical_slot = 13
[    2.062359] shpchp 0000:00:03.0: get_attention_status: physical_slot = 13
[    2.062365] shpchp 0000:00:03.0: get_latch_status: physical_slot = 13
[    2.062370] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 13
[    2.062784] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0e hp_slot=d sun=e slot_device_offset=1
[    2.062796] shpchp 0000:00:03.0: get_power_status: physical_slot = 14
[    2.062808] shpchp 0000:00:03.0: get_attention_status: physical_slot = 14
[    2.062814] shpchp 0000:00:03.0: get_latch_status: physical_slot = 14
[    2.062819] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 14
[    2.063482] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:0f hp_slot=e sun=f slot_device_offset=1
[    2.063493] shpchp 0000:00:03.0: get_power_status: physical_slot = 15
[    2.063507] shpchp 0000:00:03.0: get_attention_status: physical_slot = 15
[    2.063513] shpchp 0000:00:03.0: get_latch_status: physical_slot = 15
[    2.063518] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 15
[    2.063907] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:10 hp_slot=f sun=10 slot_device_offset=1
[    2.063917] shpchp 0000:00:03.0: get_power_status: physical_slot = 16
[    2.063930] shpchp 0000:00:03.0: get_attention_status: physical_slot = 16
[    2.063935] shpchp 0000:00:03.0: get_latch_status: physical_slot = 16
[    2.063941] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 16
[    2.063953] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:11 hp_slot=10 sun=11 slot_device_offset=1
[    2.063960] shpchp 0000:00:03.0: get_power_status: physical_slot = 17
[    2.063967] shpchp 0000:00:03.0: get_attention_status: physical_slot = 17
[    2.063972] shpchp 0000:00:03.0: get_latch_status: physical_slot = 17
[    2.063977] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 17
[    2.063987] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:12 hp_slot=11 sun=12 slot_device_offset=1
[    2.063995] shpchp 0000:00:03.0: get_power_status: physical_slot = 18
[    2.064016] shpchp 0000:00:03.0: get_attention_status: physical_slot = 18
[    2.064023] shpchp 0000:00:03.0: get_latch_status: physical_slot = 18
[    2.064028] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 18
[    2.064156] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:13 hp_slot=12 sun=13 slot_device_offset=1
[    2.064164] shpchp 0000:00:03.0: get_power_status: physical_slot = 19
[    2.064174] shpchp 0000:00:03.0: get_attention_status: physical_slot = 19
[    2.064180] shpchp 0000:00:03.0: get_latch_status: physical_slot = 19
[    2.064185] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 19
[    2.064468] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:14 hp_slot=13 sun=14 slot_device_offset=1
[    2.064476] shpchp 0000:00:03.0: get_power_status: physical_slot = 20
[    2.064487] shpchp 0000:00:03.0: get_attention_status: physical_slot = 20
[    2.064493] shpchp 0000:00:03.0: get_latch_status: physical_slot = 20
[    2.064498] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 20
[    2.064509] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:15 hp_slot=14 sun=15 slot_device_offset=1
[    2.064515] shpchp 0000:00:03.0: get_power_status: physical_slot = 21
[    2.064522] shpchp 0000:00:03.0: get_attention_status: physical_slot = 21
[    2.064527] shpchp 0000:00:03.0: get_latch_status: physical_slot = 21
[    2.064532] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 21
[    2.064541] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:16 hp_slot=15 sun=16 slot_device_offset=1
[    2.064549] shpchp 0000:00:03.0: get_power_status: physical_slot = 22
[    2.064557] shpchp 0000:00:03.0: get_attention_status: physical_slot = 22
[    2.064565] shpchp 0000:00:03.0: get_latch_status: physical_slot = 22
[    2.064574] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 22
[    2.065000] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:17 hp_slot=16 sun=17 slot_device_offset=1
[    2.065035] shpchp 0000:00:03.0: get_power_status: physical_slot = 23
[    2.065046] shpchp 0000:00:03.0: get_attention_status: physical_slot = 23
[    2.065052] shpchp 0000:00:03.0: get_latch_status: physical_slot = 23
[    2.065057] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 23
[    2.065068] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:18 hp_slot=17 sun=18 slot_device_offset=1
[    2.065075] shpchp 0000:00:03.0: get_power_status: physical_slot = 24
[    2.065081] shpchp 0000:00:03.0: get_attention_status: physical_slot = 24
[    2.065087] shpchp 0000:00:03.0: get_latch_status: physical_slot = 24
[    2.065092] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 24
[    2.065417] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:19 hp_slot=18 sun=19 slot_device_offset=1
[    2.065426] shpchp 0000:00:03.0: get_power_status: physical_slot = 25
[    2.065437] shpchp 0000:00:03.0: get_attention_status: physical_slot = 25
[    2.065443] shpchp 0000:00:03.0: get_latch_status: physical_slot = 25
[    2.065448] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 25
[    2.065727] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1a hp_slot=19 sun=1a slot_device_offset=1
[    2.065747] shpchp 0000:00:03.0: get_power_status: physical_slot = 26
[    2.065761] shpchp 0000:00:03.0: get_attention_status: physical_slot = 26
[    2.065769] shpchp 0000:00:03.0: get_latch_status: physical_slot = 26
[    2.065777] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 26
[    2.066049] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1b hp_slot=1a sun=1b slot_device_offset=1
[    2.066062] shpchp 0000:00:03.0: get_power_status: physical_slot = 27
[    2.066077] shpchp 0000:00:03.0: get_attention_status: physical_slot = 27
[    2.066089] shpchp 0000:00:03.0: get_latch_status: physical_slot = 27
[    2.066097] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 27
[    2.066840] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1c hp_slot=1b sun=1c slot_device_offset=1
[    2.066854] shpchp 0000:00:03.0: get_power_status: physical_slot = 28
[    2.066874] shpchp 0000:00:03.0: get_attention_status: physical_slot = 28
[    2.066882] shpchp 0000:00:03.0: get_latch_status: physical_slot = 28
[    2.066891] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 28
[    2.067041] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1d hp_slot=1c sun=1d slot_device_offset=1
[    2.067054] shpchp 0000:00:03.0: get_power_status: physical_slot = 29
[    2.067068] shpchp 0000:00:03.0: get_attention_status: physical_slot = 29
[    2.067076] shpchp 0000:00:03.0: get_latch_status: physical_slot = 29
[    2.067084] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 29
[    2.067099] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1e hp_slot=1d sun=1e slot_device_offset=1
[    2.067111] shpchp 0000:00:03.0: get_power_status: physical_slot = 30
[    2.067122] shpchp 0000:00:03.0: get_attention_status: physical_slot = 30
[    2.067130] shpchp 0000:00:03.0: get_latch_status: physical_slot = 30
[    2.067138] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 30
[    2.067997] shpchp 0000:00:03.0: Registering domain:bus:dev=0000:01:1f hp_slot=1e sun=1f slot_device_offset=1
[    2.068045] shpchp 0000:00:03.0: get_power_status: physical_slot = 31
[    2.068063] shpchp 0000:00:03.0: get_attention_status: physical_slot = 31
[    2.068071] shpchp 0000:00:03.0: get_latch_status: physical_slot = 31
[    2.068078] shpchp 0000:00:03.0: get_adapter_status: physical_slot = 31
[    2.068866] shpchp: shpcd_init: pci_register_driver = 0
[    2.068867] shpchp: Standard Hot Plug PCI Controller Driver version: 0.4
[    2.119268] parport_pc 00:04: reported by Plug and Play ACPI
[    2.119387] parport0: PC-style at 0x378, irq 7 [PCSPP,TRISTATE]
[    2.121193] piix4_smbus 0000:00:01.3: SMBus Host Controller at 0xb100, revision 0
[    2.127731] microcode: CPU0 sig=0x663, pf=0x1, revision=0x1
[    2.145316] platform microcode: Direct firmware load failed with error -2
[    2.145330] platform microcode: Falling back to user helper
[    2.145697] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[    2.156307] ppdev: user-space parallel port driver
[    2.177380] systemd-udevd[339]: renamed network interface eth0 to ens1
[    2.192647] Adding 2113532k swap on /dev/mapper/fedora-swap.  Priority:-1 extents:1 across:2113532k FS
[    2.246873] EXT4-fs (vda3): mounting ext2 file system using the ext4 subsystem
[    2.250802] EXT4-fs (vda4): mounting ext2 file system using the ext4 subsystem
[    2.252577] EXT4-fs (vda3): mounted filesystem without journal. Opts: (null)
[    2.252582] SELinux: initialized (dev vda3, type ext2), uses xattr
[    2.256499] EXT4-fs (vda4): mounted filesystem without journal. Opts: (null)
[    2.256504] SELinux: initialized (dev vda4, type ext2), uses xattr
[    2.306262] EXT4-fs (vda1): mounted filesystem with ordered data mode. Opts: (null)
[    2.306363] SELinux: initialized (dev vda1, type ext4), uses xattr
[    7.152044] SELinux: initialized (dev 9p, type 9p), uses genfs_contexts
[    7.192999] systemd-journald[284]: Received request to flush runtime journal from PID 1
[    7.391355] systemd[1]: Unit rngd.service entered failed state.
[    7.520879] random: nonblocking pool is initialized
[    7.977367] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[    8.024104] ip6_tables: (C) 2000-2006 Netfilter Core Team
[    8.104342] Ebtables v2.0 registered
[    8.132246] Bridge firewalling registered
[    8.494670] IPv6: ADDRCONF(NETDEV_UP): ens1: link is not ready
[    8.539700] Bluetooth: Core ver 2.17
[    8.539877] NET: Registered protocol family 31
[    8.539879] Bluetooth: HCI device and connection manager initialized
[    8.539889] Bluetooth: HCI socket layer initialized
[    8.539891] Bluetooth: L2CAP socket layer initialized
[    8.539895] Bluetooth: SCO socket layer initialized
[    8.550390] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[    8.550393] Bluetooth: BNEP filters: protocol multicast
[    8.550399] Bluetooth: BNEP socket layer initialized
[   10.488522] e1000: ens1 NIC Link is Up 1000 Mbps Full Duplex, Flow Control: RX
[   10.488920] IPv6: ADDRCONF(NETDEV_CHANGE): ens1: link becomes ready
[   52.326025] shpchp 0000:00:03.0: shpc_isr: intr_loc = 4
[   52.326115] shpchp 0000:00:03.0: shpc_isr: intr_loc2 = 4
[   52.326126] shpchp 0000:00:03.0: Slot 1 with intr, slot register = 600d000f
[   52.326128] shpchp 0000:00:03.0: Switch interrupt received
[   52.326148] shpchp 0000:00:03.0: Card present 1 Power status 0
[   52.326150] shpchp 0000:00:03.0: Latch close on Slot(2)
[   52.326159] shpchp 0000:00:03.0: Attention button interrupt received
[   52.326170] shpchp 0000:00:03.0: Button pressed on Slot(2)
[   52.326173] shpchp 0000:00:03.0: Presence/Notify input change
[   52.326180] shpchp 0000:00:03.0: Card present on Slot(2)
[   52.328967] shpchp 0000:00:03.0: PCI slot #2 - powering on due to button press.
[   52.328977] shpchp 0000:00:03.0: shpc_write_cmd: t_slot 2 cmd 8
[   52.328997] shpchp 0000:00:03.0: shpc_isr: intr_loc = 1
[   52.329010] shpchp 0000:00:03.0: shpc_isr: intr_loc2 = 0
[   52.329672] shpchp 0000:00:03.0: shpc_write_cmd: t_slot 2 cmd 30
[   52.329693] shpchp 0000:00:03.0: shpc_isr: intr_loc = 1
[   52.329713] shpchp 0000:00:03.0: shpc_isr: intr_loc2 = 0
[   57.344113] shpchp 0000:00:03.0: shpchp_enable_slot: p_slot->pwr_save 0
[   57.344120] shpchp 0000:00:03.0: board_added: p_slot->device, slot_offset, hp_slot = 2, 1 ,1
[   57.344126] shpchp 0000:00:03.0: shpc_write_cmd: t_slot 2 cmd 1
[   57.344146] shpchp 0000:00:03.0: shpc_isr: intr_loc = 1
[   57.344159] shpchp 0000:00:03.0: shpc_isr: intr_loc2 = 0
[   57.344196] shpchp 0000:00:03.0: hpc_get_adapter_speed: slot_reg = 60000039, pcix_cap = 0, m66_cap = 0
[   57.344197] shpchp 0000:00:03.0: Adapter speed = 0
[   57.344199] shpchp 0000:00:03.0: board_added: slots_not_empty 1, adapter_speed 0, bus_speed 255, max_bus_speed 255
[   57.344200] shpchp 0000:00:03.0: Speed of bus ff and adapter 0 mismatch
[   57.344973] shpchp 0000:00:03.0: shpc_write_cmd: t_slot 2 cmd c
[   57.344985] shpchp 0000:00:03.0: shpc_isr: intr_loc = 1
[   57.344998] shpchp 0000:00:03.0: shpc_isr: intr_loc2 = 0
Comment 1 Marcel Apfelbaum 2014-05-01 20:30:34 UTC
lspci -vv output:

00:00.0 Host bridge: Intel Corporation 440FX - 82441FX PMC [Natoma] (rev 02)
	Subsystem: Red Hat, Inc Qemu virtual machine
	Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:01.0 ISA bridge: Intel Corporation 82371SB PIIX3 ISA [Natoma/Triton II]
	Subsystem: Red Hat, Inc Qemu virtual machine
	Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-

00:01.1 IDE interface: Intel Corporation 82371SB PIIX3 IDE [Natoma/Triton II] (prog-if 80 [Master])
	Subsystem: Red Hat, Inc Qemu virtual machine
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Region 0: [virtual] Memory at 000001f0 (32-bit, non-prefetchable) [size=8]
	Region 1: [virtual] Memory at 000003f0 (type 3, non-prefetchable)
	Region 2: [virtual] Memory at 00000170 (32-bit, non-prefetchable) [size=8]
	Region 3: [virtual] Memory at 00000370 (type 3, non-prefetchable)
	Region 4: I/O ports at d080 [size=16]
	Kernel driver in use: ata_piix

00:01.3 Bridge: Intel Corporation 82371AB/EB/MB PIIX4 ACPI (rev 03)
	Subsystem: Red Hat, Inc Qemu virtual machine
	Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B+ ParErr- DEVSEL=medium >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Interrupt: pin A routed to IRQ 9
	Kernel driver in use: piix4_smbus

00:02.0 VGA compatible controller: Cirrus Logic GD 5446 (prog-if 00 [VGA controller])
	Subsystem: Red Hat, Inc QEMU Virtual Machine
	Control: I/O+ Mem+ BusMaster- SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Region 0: Memory at fc000000 (32-bit, prefetchable) [size=32M]
	Region 1: Memory at fea10000 (32-bit, non-prefetchable) [size=4K]
	Expansion ROM at fea00000 [disabled] [size=64K]
	Kernel driver in use: cirrus

00:03.0 PCI bridge: Red Hat, Inc. QEMU PCI-PCI bridge (prog-if 00 [Normal decode])
	Physical Slot: 3
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz+ UDF- FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Region 0: Memory at fea11000 (64-bit, non-prefetchable) [size=256]
	Bus: primary=00, secondary=01, subordinate=01, sec-latency=0
	I/O behind bridge: 0000c000-0000cfff
	Memory behind bridge: fe800000-fe9fffff
	Prefetchable memory behind bridge: 00000000fe000000-00000000fe1fffff
	Secondary status: 66MHz+ FastB2B+ ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- <SERR- <PERR-
	BridgeCtl: Parity- SERR- NoISA- VGA- MAbort- >Reset- FastB2B-
		PriDiscTmr- SecDiscTmr- DiscTmrStat- DiscTmrSERREn-
	Capabilities: [4c] MSI: Enable+ Count=1/1 Maskable+ 64bit+
		Address: 00000000fee00000  Data: 4081
		Masking: 00000000  Pending: 00000000
	Capabilities: [48] Slot ID: 0 slots, First+, chassis 01
	Capabilities: [40] Hot-plug capable
	Kernel driver in use: shpchp

00:04.0 Unclassified device [0002]: Red Hat, Inc Virtio filesystem
	Subsystem: Red Hat, Inc Device 0009
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 10
	Region 0: I/O ports at d000 [size=64]
	Region 1: Memory at fea12000 (32-bit, non-prefetchable) [size=4K]
	Capabilities: [40] MSI-X: Enable+ Count=2 Masked-
		Vector table: BAR=1 offset=00000000
		PBA: BAR=1 offset=00000800
	Kernel driver in use: virtio-pci

00:05.0 SCSI storage controller: Red Hat, Inc Virtio block device
	Subsystem: Red Hat, Inc Device 0002
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx+
	Status: Cap+ 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 10
	Region 0: I/O ports at d040 [size=64]
	Region 1: Memory at fea13000 (32-bit, non-prefetchable) [size=4K]
	Capabilities: [40] MSI-X: Enable+ Count=2 Masked-
		Vector table: BAR=1 offset=00000000
		PBA: BAR=1 offset=00000800
	Kernel driver in use: virtio-pci

01:01.0 Ethernet controller: Intel Corporation 82540EM Gigabit Ethernet Controller (rev 03)
	Subsystem: Red Hat, Inc QEMU Virtual Machine
	Physical Slot: 1
	Control: I/O+ Mem+ BusMaster+ SpecCycle- MemWINV- VGASnoop- ParErr- Stepping- SERR+ FastB2B- DisINTx-
	Status: Cap- 66MHz- UDF- FastB2B- ParErr- DEVSEL=fast >TAbort- <TAbort- <MAbort- >SERR- <PERR- INTx-
	Latency: 0
	Interrupt: pin A routed to IRQ 10
	Region 0: Memory at fe840000 (32-bit, non-prefetchable) [size=128K]
	Region 1: I/O ports at c000 [size=64]
	Expansion ROM at fe800000 [disabled] [size=256K]
	Kernel driver in use: e1000
Comment 2 Greg Kroah-Hartman 2014-05-01 21:56:29 UTC
On Thu, May 01, 2014 at 06:51:31PM +0000, bugzilla-daemon@bugzilla.kernel.org wrote:
> https://bugzilla.kernel.org/show_bug.cgi?id=75251
> 
>             Bug ID: 75251
>            Summary: shpchp - wrong bus speed computation
>            Product: Drivers
>            Version: 2.5
>     Kernel Version: 3.15.0-rc1+
>           Hardware: x86-64
>                 OS: Linux
>               Tree: Mainline
>             Status: NEW
>           Severity: low
>           Priority: P1
>          Component: HotPlug
>           Assignee: greg@kroah.com
>           Reporter: marcel.a@redhat.com
>         Regression: No
> 
> When a board is added, the shpchp driver checks if there
> is a mismatch between the bridge's adapter and the bus speed.
> If there is, it sets the subordinate speed (if there is no device on it).

Please send this to the linux-pci@vger.kernel.org mailing list.
Comment 3 Marcel Apfelbaum 2014-05-02 04:01:48 UTC
(In reply to Greg Kroah-Hartman from comment #2)
> On Thu, May 01, 2014 at 06:51:31PM +0000,
> bugzilla-daemon@bugzilla.kernel.org wrote:
> > https://bugzilla.kernel.org/show_bug.cgi?id=75251
> > 
> >             Bug ID: 75251
> >            Summary: shpchp - wrong bus speed computation
> >            Product: Drivers
> >            Version: 2.5
> >     Kernel Version: 3.15.0-rc1+
> >           Hardware: x86-64
> >                 OS: Linux
> >               Tree: Mainline
> >             Status: NEW
> >           Severity: low
> >           Priority: P1
> >          Component: HotPlug
> >           Assignee: greg@kroah.com
> >           Reporter: marcel.a@redhat.com
> >         Regression: No
> > 
> > When a board is added, the shpchp driver checks if there
> > is a mismatch between the bridge's adapter and the bus speed.
> > If there is, it sets the subordinate speed (if there is no device on it).
> 
> Please send this to the linux-pci@vger.kernel.org mailing list.

Hi Greg,
I already sent the issue to the mailing list with a patch containing the fix.

Thanks,
Marcel
Comment 4 Michael S. Tsirkin 2014-05-04 08:51:10 UTC
Looking at git history, I suspect this is a regression
introduced by
        commit 3749c51ac6c1560aa1cb1520066bed84c6f8152a
        Author: Matthew Wilcox <matthew@wil.cx>
        Date:   Sun Dec 13 08:11:32 2009 -0500
            PCI: Make current and maximum bus speeds part of the PCI core
At least I remember this configuration worked for me early in 2009...
Comment 5 Michael S. Tsirkin 2014-05-04 08:51:29 UTC
patch here:
http://thread.gmane.org/gmane.linux.kernel/1694959/focus=31224
Comment 6 Michael S. Tsirkin 2014-05-04 08:53:58 UTC
if that commit is really the culprit, then the last working kernel is v2.6.32.
there are many live cds floating around using that kernel, should be easy
to try one of them.
Comment 7 Marcel Apfelbaum 2014-05-04 10:41:43 UTC
I checked 2.6.24-26-generic that comes with Ubuntu 8.04 and it works OK. So it is a regression.
Comment 8 Bjorn Helgaas 2014-06-03 23:11:11 UTC
This should be fixed by

http://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git/commit/?id=93fa9d32670f5592c8e56abc9928fc194e1e72fc

which will appear in v3.16-rc1.