lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Message-ID: <20090219145328.GA20029@elte.hu>
Date:	Thu, 19 Feb 2009 15:53:28 +0100
From:	Ingo Molnar <mingo@...e.hu>
To:	Peter Zijlstra <peterz@...radead.org>
Cc:	lkml <linux-kernel@...r.kernel.org>,
	Johannes Weiner <hannes@...xchg.org>
Subject: Re: [PATCH] mutex: enable spinning for DEBUG_MUTEX


* Ingo Molnar <mingo@...e.hu> wrote:

> * Peter Zijlstra <peterz@...radead.org> wrote:
> 
> > Subject: mutex: enable spinning for DEBUG_MUTEX
> > From: Johannes Weiner <hannes@...xchg.org>
> > Date: Thu, 15 Jan 2009 15:57:38 +0100
> > 
> > Spinning with CONFIG_DEBUG_MUTEX is possible but care must 
> > be taken with the debug checks vs. racy access to the lock 
> > owner field.

This commit crashed all my testboxes - 32-bit and 64-bit ones 
alike. One (32-bit) crashlog is below - the hang seems to be 
around this place:

[   45.056031]  [<c0104d6e>] ? common_interrupt+0x2e/0x34
[   45.056031]  [<c019007b>] ? relay_open_buf+0x24d/0x34f
[   45.056031]  [<c0137084>] ? mutex_spin_on_owner+0x8e/0xc1
[   45.056031]  [<c08ae2e5>] ? mutex_lock_nested+0x5e/0x595
[   45.056031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[   45.056031]  [<c017cca4>] ? kprobe_target+0x0/0x15
[   45.056031]  [<c08b452c>] ? register_kprobe+0x19f/0x4f4

Config attached.

	Ingo

[    0.000000] Linux version 2.6.29-rc5-tip (mingo@...us) (gcc version 4.2.2) #208 SMP Thu Feb 19 12:56:31 CET 2009
[    0.000000] KERNEL supported cpus:
[    0.000000]   Intel GenuineIntel
[    0.000000]   AMD AuthenticAMD
[    0.000000]   Centaur CentaurHauls
[    0.000000]   Transmeta GenuineTMx86
[    0.000000]   Transmeta TransmetaCPU
[    0.000000]   UMC UMC UMC UMC
[    0.000000] BIOS-provided physical RAM map:
[    0.000000]  BIOS-e820: 0000000000000000 - 000000000009fc00 (usable)
[    0.000000]  BIOS-e820: 000000000009fc00 - 00000000000a0000 (reserved)
[    0.000000]  BIOS-e820: 00000000000f0000 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000003fff0000 (usable)
[    0.000000]  BIOS-e820: 000000003fff0000 - 000000003fff3000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000003fff3000 - 0000000040000000 (ACPI data)
[    0.000000]  BIOS-e820: 00000000fec00000 - 0000000100000000 (reserved)
[    0.000000] console [earlyser0] enabled
[    0.000000] debug: ignoring loglevel setting.
[    0.000000] using polling idle threads.
[    0.000000] DMI 2.3 present.
[    0.000000] last_pfn = 0x3fff0 max_arch_pfn = 0x100000
[    0.000000] kernel direct mapping tables up to 377fe000 @ 7000-c000
[    0.000000] 135MB HIGHMEM available.
[    0.000000] 887MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 377fe000
[    0.000000]   low ram: 00000000 - 377fe000
[    0.000000]   bootmap 00008000 - 0000ef00
[    0.000000] (8 early reservations) ==> bootmem [0000000000 - 00377fe000]
[    0.000000]   #0 [0000000000 - 0000001000]   BIOS data page ==> [0000000000 - 0000001000]
[    0.000000]   #1 [0000001000 - 0000002000]    EX TRAMPOLINE ==> [0000001000 - 0000002000]
[    0.000000]   #2 [0000006000 - 0000007000]       TRAMPOLINE ==> [0000006000 - 0000007000]
[    0.000000]   #3 [0000100000 - 00017e683c]    TEXT DATA BSS ==> [0000100000 - 00017e683c]
[    0.000000]   #4 [00017e7000 - 00017ef000]    INIT_PG_TABLE ==> [00017e7000 - 00017ef000]
[    0.000000]   #5 [000009f000 - 0000100000]    BIOS reserved ==> [000009f000 - 0000100000]
[    0.000000]   #6 [0000007000 - 0000008000]          PGTABLE ==> [0000007000 - 0000008000]
[    0.000000]   #7 [0000008000 - 000000f000]          BOOTMAP ==> [0000008000 - 000000f000]
[    0.000000] Scan SMP from c0000000 for 1024 bytes.
[    0.000000] Scan SMP from c009fc00 for 1024 bytes.
[    0.000000] Scan SMP from c00f0000 for 65536 bytes.
[    0.000000] found SMP MP-table at [c00f4f90] f4f90
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000000 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x000377fe
[    0.000000]   HighMem  0x000377fe -> 0x0003fff0
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[2] active PFN ranges
[    0.000000]     0: 0x00000000 -> 0x0000009f
[    0.000000]     0: 0x00000100 -> 0x0003fff0
[    0.000000] On node 0 totalpages: 262031
[    0.000000] free_area_init_node: node 0, pgdat c0dee7e0, node_mem_map c17ef000
[    0.000000]   DMA zone: 52 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3947 pages, LIFO batch:0
[    0.000000]   Normal zone: 2834 pages used for memmap
[    0.000000]   Normal zone: 220396 pages, LIFO batch:31
[    0.000000]   HighMem zone: 442 pages used for memmap
[    0.000000]   HighMem zone: 34360 pages, LIFO batch:7
[    0.000000] Using APIC driver default
[    0.000000] Intel MultiProcessor Specification v1.4
[    0.000000]     Virtual Wire compatibility mode.
[    0.000000] MPTABLE: OEM ID: OEM00000
[    0.000000] MPTABLE: Product ID: PROD00000000
[    0.000000] MPTABLE: APIC at: 0xFEE00000
[    0.000000] Processor #0 (Bootup-CPU)
[    0.000000] Processor #1
[    0.000000] Processor #2
[    0.000000] Processor #3
[    0.000000] Bus #0 is PCI   
[    0.000000] Bus #1 is PCI   
[    0.000000] Bus #2 is PCI   
[    0.000000] Bus #3 is PCI   
[    0.000000] Bus #4 is PCI   
[    0.000000] Bus #5 is ISA   
[    0.000000] I/O APIC #4 Version 17 at 0xFEC00000.
[    0.000000] Int: type 0, pol 3, trig 3, bus 00, IRQ 7d, APIC ID 4, APIC INT 11
[    0.000000] Int: type 0, pol 3, trig 3, bus 00, IRQ 7f, APIC ID 4, APIC INT 13
[    0.000000] Int: type 0, pol 3, trig 3, bus 00, IRQ 7e, APIC ID 4, APIC INT 17
[    0.000000] Int: type 0, pol 3, trig 3, bus 00, IRQ 7d, APIC ID 4, APIC INT 11
[    0.000000] Int: type 0, pol 3, trig 3, bus 01, IRQ 00, APIC ID 4, APIC INT 16
[    0.000000] Int: type 0, pol 3, trig 3, bus 04, IRQ 1c, APIC ID 4, APIC INT 10
[    0.000000] Int: type 0, pol 3, trig 3, bus 04, IRQ 0c, APIC ID 4, APIC INT 13
[    0.000000] Int: type 0, pol 3, trig 3, bus 04, IRQ 10, APIC ID 4, APIC INT 10
[    0.000000] Int: type 0, pol 3, trig 3, bus 03, IRQ 10, APIC ID 4, APIC INT 12
[    0.000000] Int: type 0, pol 3, trig 3, bus 03, IRQ 11, APIC ID 4, APIC INT 12
[    0.000000] Int: type 0, pol 3, trig 3, bus 00, IRQ 7c, APIC ID 4, APIC INT 10
[    0.000000] Int: type 3, pol 0, trig 0, bus 05, IRQ 00, APIC ID 4, APIC INT 00
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 01, APIC ID 4, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 00, APIC ID 4, APIC INT 02
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 03, APIC ID 4, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 04, APIC ID 4, APIC INT 04
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 06, APIC ID 4, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 07, APIC ID 4, APIC INT 07
[    0.000000] Int: type 0, pol 1, trig 1, bus 05, IRQ 08, APIC ID 4, APIC INT 08
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 0d, APIC ID 4, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 0e, APIC ID 4, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 05, IRQ 0f, APIC ID 4, APIC INT 0f
[    0.000000] Lint: type 3, pol 0, trig 0, bus 00, IRQ 00, APIC ID ff, APIC LINT 00
[    0.000000] Lint: type 1, pol 0, trig 0, bus 00, IRQ 00, APIC ID ff, APIC LINT 01
[    0.000000] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.000000] Processors: 4
[    0.000000] SMP: Allowing 4 CPUs, 0 hotplug CPUs
[    0.000000] mapped APIC to ffffb000 (fee00000)
[    0.000000] mapped IOAPIC to ffffa000 (fec00000)
[    0.000000] nr_irqs_gsi: 24
[    0.000000] Allocating PCI resources starting at 50000000 (gap: 40000000:bec00000)
[    0.000000] NR_CPUS:32 nr_cpumask_bits:32 nr_cpu_ids:4 nr_node_ids:1
[    0.000000] PERCPU: Allocating 36864 bytes of per cpu data
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 258703
[    0.000000] Kernel command line: root=/dev/sda3 debug earlyprintk=serial,ttyS0,115200,keep initcall_debug apic=verbose ignore_loglevel highres=0 nmi_watchdog=0 nolapic_timer idle=mwait idle=poll nopat notsc pci=nomsi
[    0.000000] notsc: Kernel compiled with CONFIG_X86_TSC, cannot disable TSC completely.
[    0.000000] Enabling fast FPU save and restore... done.
[    0.000000] Enabling unmasked SIMD FPU exception support... done.
[    0.000000] Initializing CPU#0
[    0.000000] RCU-based detection of stalled CPUs is enabled.
[    0.000000] NR_IRQS:2304 nr_irqs:440
[    0.000000] CPU 0 irqstacks, hard=c10ea000 soft=c10ca000
[    0.000000] PID hash table entries: 4096 (order: 12, 16384 bytes)
[    0.000000] Fast TSC calibration using PIT
[    0.000000] Detected 2378.879 MHz processor.
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.000000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.000000] ... MAX_LOCK_DEPTH:          48
[    0.000000] ... MAX_LOCKDEP_KEYS:        8191
[    0.000000] ... CLASSHASH_SIZE:          4096
[    0.000000] ... MAX_LOCKDEP_ENTRIES:     8192
[    0.000000] ... MAX_LOCKDEP_CHAINS:      16384
[    0.000000] ... CHAINHASH_SIZE:          8192
[    0.000000]  memory used by lock dependency info: 2847 kB
[    0.000000]  per task-struct memory footprint: 1152 bytes
[    0.000000] ------------------------
[    0.000000] | Locking API testsuite:
[    0.000000] ----------------------------------------------------------------------------
[    0.000000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]                  bad unlock order:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]               recursive read-lock:             |  ok  |             |  ok  |
[    0.000000]            recursive read-lock #2:             |  ok  |             |  ok  |
[    0.000000]             mixed read-write-lock:             |  ok  |             |  ok  |
[    0.000000]             mixed write-read-lock:             |  ok  |             |  ok  |
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.000000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |
[    0.000000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.000000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |
[    0.000000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |
[    0.000000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |
[    0.000000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.000000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |
[    0.000000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.000000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |
[    0.000000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.000000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.000000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |
[    0.000000]       hard-irq read-recursion/123:  ok  |
[    0.000000]       soft-irq read-recursion/123:  ok  |
[    0.000000]       hard-irq read-recursion/132:  ok  |
[    0.000000]       soft-irq read-recursion/132:  ok  |
[    0.000000]       hard-irq read-recursion/213:  ok  |
[    0.000000]       soft-irq read-recursion/213:  ok  |
[    0.000000]       hard-irq read-recursion/231:  ok  |
[    0.000000]       soft-irq read-recursion/231:  ok  |
[    0.000000]       hard-irq read-recursion/312:  ok  |
[    0.000000]       soft-irq read-recursion/312:  ok  |
[    0.000000]       hard-irq read-recursion/321:  ok  |
[    0.000000]       soft-irq read-recursion/321:  ok  |
[    0.000000] -------------------------------------------------------
[    0.000000] Good, all 218 testcases passed! |
[    0.000000] ---------------------------------
[    0.000000] Dentry cache hash table entries: 131072 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 65536 (order: 6, 262144 bytes)
[    0.000000] allocated 5242560 bytes of page_cgroup
[    0.000000] please try cgroup_disable=memory option if you don't want
[    0.000000] Memory: 1004816k/1048512k available (7895k kernel code, 42980k reserved, 7727k data, 508k init, 139208k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xffe19000 - 0xfffff000   (1944 kB)
[    0.000000]     pkmap   : 0xff800000 - 0xffc00000   (4096 kB)
[    0.000000]     vmalloc : 0xf7ffe000 - 0xff7fe000   ( 120 MB)
[    0.000000]     lowmem  : 0xc0000000 - 0xf77fe000   ( 887 MB)
[    0.000000]       .init : 0xc1048000 - 0xc10c7000   ( 508 kB)
[    0.000000]       .data : 0xc08b5f52 - 0xc1041b90   (7727 kB)
[    0.000000]       .text : 0xc0100000 - 0xc08b5f52   (7895 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: Genslabs=12, HWalign=128, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[    0.004000] Calibrating delay loop... 3153.92 BogoMIPS (lpj=6307840)
[    0.112000] Security Framework initialized
[    0.116000] SELinux:  Initializing.
[    0.120000] SELinux:  Starting in enforcing mode
[    0.124000] Mount-cache hash table entries: 512
[    0.132001] Initializing cgroup subsys ns
[    0.136001] Initializing cgroup subsys cpuacct
[    0.140001] Initializing cgroup subsys memory
[    0.144001] Initializing cgroup subsys devices
[    0.148001] Initializing cgroup subsys freezer
[    0.152001] CPU: Trace cache: 12K uops, L1 D cache: 8K
[    0.160001] CPU: L2 cache: 512K
[    0.160001] [ds] using Netburst configuration
[    0.164001] CPU: Physical Processor ID: 0
[    0.164001] CPU: Processor Core ID: 0
[    0.168001] WARNING: polling idle and HT enabled, performance may degrade.
[    0.172001] Checking 'hlt' instruction... OK.
[    0.192001] ftrace: converting mcount calls to 0f 1f 44 00 00
[    0.196001] ftrace: allocating 19906 entries in 79 pages
[    0.208001] enabled ExtINT on CPU#0
[    0.212001] ExtINT not setup in hardware but reported by MP table
[    0.216001] ENABLING IO-APIC IRQs
[    0.220001] Setting 4 in the phys_id_present_map
[    0.224001] ...changing IO-APIC physical APIC ID to 4 ... ok.
[    0.228001] init IO_APIC IRQs
[    0.228001]  4-0 (apicid-pin) not connected
[    0.236001] IOAPIC[0]: Set routing entry (4-1 -> 0x31 -> IRQ 1 Mode:0 Active:0)
[    0.240001] IOAPIC[0]: Set routing entry (4-2 -> 0x30 -> IRQ 0 Mode:0 Active:0)
[    0.244001] IOAPIC[0]: Set routing entry (4-3 -> 0x33 -> IRQ 3 Mode:0 Active:0)
[    0.248001] IOAPIC[0]: Set routing entry (4-4 -> 0x34 -> IRQ 4 Mode:0 Active:0)
[    0.252001]  4-5 (apicid-pin) not connected
[    0.256001] IOAPIC[0]: Set routing entry (4-6 -> 0x36 -> IRQ 6 Mode:0 Active:0)
[    0.260001] IOAPIC[0]: Set routing entry (4-7 -> 0x37 -> IRQ 7 Mode:0 Active:0)
[    0.264001] IOAPIC[0]: Set routing entry (4-8 -> 0x38 -> IRQ 8 Mode:0 Active:0)
[    0.268001]  4-9 4-10 4-11 4-12 (apicid-pin) not connected
[    0.276001] IOAPIC[0]: Set routing entry (4-13 -> 0x3d -> IRQ 13 Mode:0 Active:0)
[    0.280001] IOAPIC[0]: Set routing entry (4-14 -> 0x3e -> IRQ 14 Mode:0 Active:0)
[    0.284001] IOAPIC[0]: Set routing entry (4-15 -> 0x3f -> IRQ 15 Mode:0 Active:0)
[    0.288001]   alloc irq_desc for 16 on cpu 0 node 0
[    0.288001]   alloc kstat_irqs on cpu 0 node 0
[    0.292001] IOAPIC[0]: Set routing entry (4-16 -> 0x49 -> IRQ 16 Mode:1 Active:1)
[    0.296001]   alloc irq_desc for 17 on cpu 0 node 0
[    0.296001]   alloc kstat_irqs on cpu 0 node 0
[    0.300001] IOAPIC[0]: Set routing entry (4-17 -> 0x51 -> IRQ 17 Mode:1 Active:1)
[    0.304001]   alloc irq_desc for 18 on cpu 0 node 0
[    0.304001]   alloc kstat_irqs on cpu 0 node 0
[    0.308001] IOAPIC[0]: Set routing entry (4-18 -> 0x59 -> IRQ 18 Mode:1 Active:1)
[    0.312001]   alloc irq_desc for 19 on cpu 0 node 0
[    0.312001]   alloc kstat_irqs on cpu 0 node 0
[    0.316001] IOAPIC[0]: Set routing entry (4-19 -> 0x61 -> IRQ 19 Mode:1 Active:1)
[    0.320001]  4-20 4-21 (apicid-pin) not connected
[    0.324001]   alloc irq_desc for 22 on cpu 0 node 0
[    0.324001]   alloc kstat_irqs on cpu 0 node 0
[    0.328001] IOAPIC[0]: Set routing entry (4-22 -> 0x69 -> IRQ 22 Mode:1 Active:1)
[    0.332001]   alloc irq_desc for 23 on cpu 0 node 0
[    0.332001]   alloc kstat_irqs on cpu 0 node 0
[    0.336001] IOAPIC[0]: Set routing entry (4-23 -> 0x71 -> IRQ 23 Mode:1 Active:1)
[    0.340001] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=0 pin2=0
[    0.384001] CPU0: Intel(R) XEON(TM) CPU 2.40GHz stepping 04
[    0.392001] Disabling APIC timer
[    0.400001] calling  migration_init+0x0/0x6e @ 1
[    0.404001] initcall migration_init+0x0/0x6e returned 1 after 0 usecs
[    0.408001] initcall migration_init+0x0/0x6e returned with error code 1 
[    0.412001] calling  spawn_ksoftirqd+0x0/0x6a @ 1
[    0.416001] initcall spawn_ksoftirqd+0x0/0x6a returned 0 after 0 usecs
[    0.420001] calling  init_call_single_data+0x0/0x6b @ 1
[    0.424001] initcall init_call_single_data+0x0/0x6b returned 0 after 0 usecs
[    0.428001] calling  spawn_softlockup_task+0x0/0x79 @ 1
[    0.432001] initcall spawn_softlockup_task+0x0/0x79 returned 0 after 0 usecs
[    0.436001] calling  relay_init+0x0/0x11 @ 1
[    0.440001] initcall relay_init+0x0/0x11 returned 0 after 0 usecs
[    0.444001] calling  tracer_alloc_buffers+0x0/0x179 @ 1
[    0.456001] initcall tracer_alloc_buffers+0x0/0x179 returned 0 after 7812 usecs
[    0.460001] calling  perf_counter_init+0x0/0x27 @ 1
[    0.464001] initcall perf_counter_init+0x0/0x27 returned 0 after 0 usecs
[    0.468001] lockdep: fixing up alternatives.
[    0.472001] CPU 1 irqstacks, hard=c10eb000 soft=c10cb000
[    0.476001] Booting processor 1 APIC 0x1 ip 0x6000
[    0.004000] Initializing CPU#1
[    0.004000] masked ExtINT on CPU#1
[    0.004000] Calibrating delay loop... 3170.30 BogoMIPS (lpj=6340608)
[    0.004000] CPU: Trace cache: 12K uops, L1 D cache: 8K
[    0.004000] CPU: L2 cache: 512K
[    0.004000] [ds] using Netburst configuration
[    0.004000] CPU: Physical Processor ID: 3
[    0.004000] CPU: Processor Core ID: 0
[    0.004000] WARNING: polling idle and HT enabled, performance may degrade.
[    0.596001] CPU1: Intel(R) XEON(TM) CPU 2.40GHz stepping 04
[    0.604001] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[    0.612001] lockdep: fixing up alternatives.
[    0.616001] CPU 2 irqstacks, hard=c10ec000 soft=c10cc000
[    0.620001] Booting processor 2 APIC 0x2 ip 0x6000
[    0.004000] Initializing CPU#2
[    0.004000] masked ExtINT on CPU#2
[    0.004000] Calibrating delay loop... 1585.15 BogoMIPS (lpj=3170304)
[    0.004000] CPU: Trace cache: 12K uops, L1 D cache: 8K
[    0.004000] CPU: L2 cache: 512K
[    0.004000] [ds] using Netburst configuration
[    0.004000] CPU: Physical Processor ID: 0
[    0.004000] CPU: Processor Core ID: 0
[    0.004000] WARNING: polling idle and HT enabled, performance may degrade.
[    0.736002] CPU2: Intel(R) XEON(TM) CPU 2.40GHz stepping 04
[    0.744002] checking TSC synchronization [CPU#0 -> CPU#2]: passed.
[    0.752002] lockdep: fixing up alternatives.
[    0.756002] CPU 3 irqstacks, hard=c10ed000 soft=c10cd000
[    0.760002] Booting processor 3 APIC 0x3 ip 0x6000
[    0.004000] Initializing CPU#3
[    0.004000] masked ExtINT on CPU#3
[    0.004000] Calibrating delay loop... 2342.91 BogoMIPS (lpj=4685824)
[    0.004000] CPU: Trace cache: 12K uops, L1 D cache: 8K
[    0.004000] CPU: L2 cache: 512K
[    0.004000] [ds] using Netburst configuration
[    0.004000] CPU: Physical Processor ID: 3
[    0.004000] CPU: Processor Core ID: 0
[    0.004000] WARNING: polling idle and HT enabled, performance may degrade.
[    0.876002] CPU3: Intel(R) XEON(TM) CPU 2.40GHz stepping 04
[    0.884002] checking TSC synchronization [CPU#0 -> CPU#3]: passed.
[    0.888002] Brought up 4 CPUs
[    0.892002] Total of 4 processors activated (10252.28 BogoMIPS).
[    0.896002] device: 'platform': device_add
[    0.900002] PM: Adding info for No Bus:platform
[    0.904002] khelper used greatest stack depth: 2696 bytes left
[    0.904002] bus: 'platform': registered
[    0.904002] Registering sysdev class 'cpu'
[    0.908002] calling  init_cpufreq_transition_notifier_list+0x0/0x18 @ 1
[    0.908002] initcall init_cpufreq_transition_notifier_list+0x0/0x18 returned 0 after 0 usecs
[    0.908002] calling  net_ns_init+0x0/0xd1 @ 1
[    0.908002] net_namespace: 1164 bytes
[    0.908002] initcall net_ns_init+0x0/0xd1 returned 0 after 0 usecs
[    0.908002] calling  cpufreq_tsc+0x0/0x41 @ 1
[    0.908002] initcall cpufreq_tsc+0x0/0x41 returned 0 after 0 usecs
[    0.908002] calling  reboot_init+0x0/0x11 @ 1
[    0.908002] initcall reboot_init+0x0/0x11 returned 0 after 0 usecs
[    0.908002] calling  print_banner+0x0/0xd @ 1
[    0.908002] Booting paravirtualized kernel on bare hardware
[    0.908002] initcall print_banner+0x0/0xd returned 0 after 0 usecs
[    0.908002] calling  init_smp_flush+0x0/0x2f @ 1
[    0.908002] initcall init_smp_flush+0x0/0x2f returned 0 after 0 usecs
[    0.908002] calling  alloc_frozen_cpus+0x0/0x12 @ 1
[    0.908002] initcall alloc_frozen_cpus+0x0/0x12 returned 0 after 0 usecs
[    0.908002] calling  sysctl_init+0x0/0x13 @ 1
[    0.908002] initcall sysctl_init+0x0/0x13 returned 0 after 0 usecs
[    0.908002] calling  ksysfs_init+0x0/0x101 @ 1
[    0.968002] initcall ksysfs_init+0x0/0x101 returned 0 after 58593 usecs
[    0.968002] calling  async_init+0x0/0x96 @ 1
[    0.968002] initcall async_init+0x0/0x96 returned 0 after 0 usecs
[    0.968002] calling  init_jiffies_clocksource+0x0/0xf @ 1
[    0.968002] initcall init_jiffies_clocksource+0x0/0xf returned 0 after 0 usecs
[    0.968002] calling  pm_init+0x0/0x37 @ 1
[    0.968002] initcall pm_init+0x0/0x37 returned 0 after 0 usecs
[    0.968002] calling  filelock_init+0x0/0x27 @ 1
[    0.968002] initcall filelock_init+0x0/0x27 returned 0 after 0 usecs
[    0.968002] calling  init_aout_binfmt+0x0/0xf @ 1
[    0.968002] initcall init_aout_binfmt+0x0/0xf returned 0 after 0 usecs
[    0.968002] calling  init_misc_binfmt+0x0/0x4f @ 1
[    0.968002] initcall init_misc_binfmt+0x0/0x4f returned 0 after 0 usecs
[    0.968002] calling  init_script_binfmt+0x0/0xf @ 1
[    0.968002] initcall init_script_binfmt+0x0/0xf returned 0 after 0 usecs
[    0.968002] calling  init_elf_binfmt+0x0/0xf @ 1
[    0.968002] initcall init_elf_binfmt+0x0/0xf returned 0 after 0 usecs
[    0.968002] calling  debugfs_init+0x0/0x5d @ 1
[    0.968002] initcall debugfs_init+0x0/0x5d returned 0 after 0 usecs
[    0.968002] calling  securityfs_init+0x0/0x5d @ 1
[    0.968002] initcall securityfs_init+0x0/0x5d returned 0 after 0 usecs
[    0.968002] calling  random32_init+0x0/0xbc @ 1
[    0.968002] initcall random32_init+0x0/0xbc returned 0 after 0 usecs
[    0.968002] calling  regulator_init+0x0/0x23 @ 1
[    0.968002] regulator: core version 0.5
[    0.968002] device class 'regulator': registering
[    1.164003] initcall regulator_init+0x0/0x23 returned 0 after 191406 usecs
[    1.168003] calling  cpufreq_core_init+0x0/0x72 @ 1
[    1.172003] initcall cpufreq_core_init+0x0/0x72 returned 0 after 0 usecs
[    1.176003] calling  virtio_init+0x0/0x30 @ 1
[    1.180003] bus: 'virtio': registered
[    1.184003] initcall virtio_init+0x0/0x30 returned 0 after 3906 usecs
[    1.188003] calling  sock_init+0x0/0x65 @ 1
[    1.192003] initcall sock_init+0x0/0x65 returned 0 after 0 usecs
[    1.196003] calling  net_inuse_init+0x0/0x2b @ 1
[    1.200003] initcall net_inuse_init+0x0/0x2b returned 0 after 0 usecs
[    1.204003] calling  netpoll_init+0x0/0x39 @ 1
[    1.208003] initcall netpoll_init+0x0/0x39 returned 0 after 0 usecs
[    1.212003] calling  netlink_proto_init+0x0/0x165 @ 1
[    1.216003] NET: Registered protocol family 16
[    1.224003] initcall netlink_proto_init+0x0/0x165 returned 0 after 7812 usecs
[    1.228003] calling  bdi_class_init+0x0/0x35 @ 1
[    1.236003] device class 'bdi': registering
[    1.240003] initcall bdi_class_init+0x0/0x35 returned 0 after 3906 usecs
[    1.244003] calling  kobject_uevent_init+0x0/0x4b @ 1
[    1.248003] initcall kobject_uevent_init+0x0/0x4b returned 0 after 0 usecs
[    1.252003] calling  gpiolib_sysfs_init+0x0/0x90 @ 1
[    1.256003] device class 'gpio': registering
[    1.260003] initcall gpiolib_sysfs_init+0x0/0x90 returned 0 after 3906 usecs
[    1.264003] calling  pcibus_class_init+0x0/0x14 @ 1
[    1.268003] device class 'pci_bus': registering
[    1.272003] initcall pcibus_class_init+0x0/0x14 returned 0 after 3906 usecs
[    1.276003] calling  pci_driver_init+0x0/0xf @ 1
[    1.280003] bus: 'pci': registered
[    1.284003] initcall pci_driver_init+0x0/0xf returned 0 after 3906 usecs
[    1.288003] calling  backlight_class_init+0x0/0x7c @ 1
[    1.292003] device class 'backlight': registering
[    1.296003] initcall backlight_class_init+0x0/0x7c returned 0 after 3906 usecs
[    1.300003] calling  video_output_class_init+0x0/0x14 @ 1
[    1.304003] device class 'video_output': registering
[    1.308003] initcall video_output_class_init+0x0/0x14 returned 0 after 3906 usecs
[    1.312003] calling  tty_class_init+0x0/0x4e @ 1
[    1.316003] device class 'tty': registering
[    1.320003] initcall tty_class_init+0x0/0x4e returned 0 after 3906 usecs
[    1.324003] calling  vtconsole_class_init+0x0/0x125 @ 1
[    1.328003] device class 'vtconsole': registering
[    1.332003] device: 'vtcon0': device_add
[    1.336003] PM: Adding info for No Bus:vtcon0
[    1.340003] initcall vtconsole_class_init+0x0/0x125 returned 0 after 11718 usecs
[    1.344003] calling  i2c_init+0x0/0x7e @ 1
[    1.348003] bus: 'i2c': registered
[    1.352003] device class 'i2c-adapter': registering
[    1.360003] bus: 'i2c': add driver dummy
[    1.364003] i2c-core: driver [dummy] registered
[    1.368003] initcall i2c_init+0x0/0x7e returned 0 after 19531 usecs
[    1.372003] calling  lguest_devices_init+0x0/0x187 @ 1
[    1.376003] initcall lguest_devices_init+0x0/0x187 returned 0 after 0 usecs
[    1.380003] calling  amd_postcore_init+0x0/0x95 @ 1
[    1.384003] initcall amd_postcore_init+0x0/0x95 returned 0 after 0 usecs
[    1.388003] calling  arch_kdebugfs_init+0x0/0x356 @ 1
[    1.392003] initcall arch_kdebugfs_init+0x0/0x356 returned 0 after 0 usecs
[    1.396003] calling  init_pit_clocksource+0x0/0xaa @ 1
[    1.400003] initcall init_pit_clocksource+0x0/0xaa returned 0 after 0 usecs
[    1.404003] calling  kdump_buf_page_init+0x0/0x6d @ 1
[    1.408003] initcall kdump_buf_page_init+0x0/0x6d returned 0 after 0 usecs
[    1.412003] calling  dmi_id_init+0x0/0x3d0 @ 1
[    1.416003] device class 'dmi': registering
[    1.424003] device: 'id': device_add
[    1.424003] PM: Adding info for No Bus:id
[    1.432003] initcall dmi_id_init+0x0/0x3d0 returned 0 after 15625 usecs
[    1.436003] calling  dma_bus_init+0x0/0x32 @ 1
[    1.440003] device class 'dma': registering
[    1.444003] initcall dma_bus_init+0x0/0x32 returned 0 after 3906 usecs
[    1.448003] calling  dma_channel_table_init+0x0/0x113 @ 1
[    1.452003] initcall dma_channel_table_init+0x0/0x113 returned 0 after 0 usecs
[    1.456003] calling  dca_init+0x0/0x19 @ 1
[    1.460003] dca service started, version 1.8
[    1.464003] device class 'dca': registering
[    1.468003] initcall dca_init+0x0/0x19 returned 0 after 7812 usecs
[    1.472003] calling  pci_arch_init+0x0/0x6a @ 1
[    1.476003] PCI: PCI BIOS revision 2.10 entry at 0xfb3b0, last bus=4
[    1.480003] PCI: Using configuration type 1 for base access
[    1.484003] initcall pci_arch_init+0x0/0x6a returned 0 after 7812 usecs
[    1.488003] calling  topology_init+0x0/0x49 @ 1
[    1.492003] Registering sys device of class 'cpu'
[    1.496003] Registering sys device 'cpu0'
[    1.500003] Registering sys device of class 'cpu'
[    1.504003] Registering sys device 'cpu1'
[    1.508003] Registering sys device of class 'cpu'
[    1.512003] Registering sys device 'cpu2'
[    1.516003] Registering sys device of class 'cpu'
[    1.520003] Registering sys device 'cpu3'
[    1.524003] initcall topology_init+0x0/0x49 returned 0 after 31250 usecs
[    1.528003] calling  mca_init+0x0/0x430 @ 1
[    1.532003] bus: 'MCA': registered
[    1.536003] initcall mca_init+0x0/0x430 returned -19 after 3906 usecs
[    1.540003] calling  param_sysfs_init+0x0/0x27c @ 1
[    1.728004] initcall param_sysfs_init+0x0/0x27c returned 0 after 179687 usecs
[    1.732004] calling  pm_sysrq_init+0x0/0x1b @ 1
[    1.736004] initcall pm_sysrq_init+0x0/0x1b returned 0 after 0 usecs
[    1.740004] calling  readahead_init+0x0/0x3d @ 1
[    1.748004] device: 'default': device_add
[    1.752004] PM: Adding info for No Bus:default
[    1.756004] initcall readahead_init+0x0/0x3d returned 0 after 7812 usecs
[    1.760004] calling  init_bio+0x0/0x101 @ 1
[    1.764004] bio: create slab <bio-0> at 0
[    1.768004] initcall init_bio+0x0/0x101 returned 0 after 3906 usecs
[    1.772004] calling  integrity_init+0x0/0x43 @ 1
[    1.776004] initcall integrity_init+0x0/0x43 returned 0 after 0 usecs
[    1.780004] calling  cryptomgr_init+0x0/0x45 @ 1
[    1.784004] initcall cryptomgr_init+0x0/0x45 returned 0 after 0 usecs
[    1.788004] calling  blk_settings_init+0x0/0x1d @ 1
[    1.792004] initcall blk_settings_init+0x0/0x1d returned 0 after 0 usecs
[    1.796004] calling  blk_ioc_init+0x0/0x24 @ 1
[    1.800004] initcall blk_ioc_init+0x0/0x24 returned 0 after 0 usecs
[    1.804004] calling  blk_softirq_init+0x0/0x6e @ 1
[    1.808004] initcall blk_softirq_init+0x0/0x6e returned 0 after 0 usecs
[    1.812004] calling  genhd_device_init+0x0/0x77 @ 1
[    1.820004] device class 'block': registering
[    1.824004] initcall genhd_device_init+0x0/0x77 returned 0 after 3906 usecs
[    1.828004] calling  blk_dev_integrity_init+0x0/0x24 @ 1
[    1.832004] initcall blk_dev_integrity_init+0x0/0x24 returned 0 after 0 usecs
[    1.836004] calling  gpiolib_debugfs_init+0x0/0x1f @ 1
[    1.840004] initcall gpiolib_debugfs_init+0x0/0x1f returned 0 after 0 usecs
[    1.844004] calling  pca953x_init+0x0/0x11 @ 1
[    1.848004] bus: 'i2c': add driver pca953x
[    1.852004] i2c-core: driver [pca953x] registered
[    1.856004] initcall pca953x_init+0x0/0x11 returned 0 after 7812 usecs
[    1.860004] calling  pci_slot_init+0x0/0x4c @ 1
[    1.864004] initcall pci_slot_init+0x0/0x4c returned 0 after 0 usecs
[    1.868004] calling  pnp_init+0x0/0xf @ 1
[    1.876004] bus: 'pnp': registered
[    1.876004] initcall pnp_init+0x0/0xf returned 0 after 3906 usecs
[    1.880004] calling  misc_init+0x0/0xba @ 1
[    1.884004] device class 'misc': registering
[    1.888004] initcall misc_init+0x0/0xba returned 0 after 3906 usecs
[    1.892004] calling  phy_init+0x0/0x45 @ 1
[    1.896004] device class 'mdio_bus': registering
[    1.900004] bus: 'mdio_bus': registered
[    1.904004] bus: 'mdio_bus': add driver Generic PHY
[    1.908004] initcall phy_init+0x0/0x45 returned 0 after 11718 usecs
[    1.912004] calling  init_scsi+0x0/0xe1 @ 1
[    1.916004] device class 'scsi_host': registering
[    1.924004] bus: 'scsi': registered
[    1.924004] device class 'scsi_device': registering
[    1.928004] SCSI subsystem initialized
[    1.932004] initcall init_scsi+0x0/0xe1 returned 0 after 15625 usecs
[    1.936004] calling  ata_init+0x0/0x39e @ 1
[    1.944004] libata version 3.00 loaded.
[    1.948004] initcall ata_init+0x0/0x39e returned 0 after 7812 usecs
[    1.952004] calling  init_pcmcia_cs+0x0/0x28 @ 1
[    1.956004] device class 'pcmcia_socket': registering
[    1.960004] initcall init_pcmcia_cs+0x0/0x28 returned 0 after 3906 usecs
[    1.964004] calling  usb_init+0x0/0x1e2 @ 1
[    1.968004] bus: 'usb': registered
[    1.972004] device class 'usb_host': registering
[    1.980004] bus: 'usb': add driver usbfs
[    1.984004] usbcore: registered new interface driver usbfs
[    1.988004] device class 'usb_device': registering
[    1.992004] bus: 'usb': add driver hub
[    1.996004] usbcore: registered new interface driver hub
[    2.000004] bus: 'usb': add driver usb
[    2.004004] usbcore: registered new device driver usb
[    2.008004] initcall usb_init+0x0/0x1e2 returned 0 after 39062 usecs
[    2.012004] calling  serio_init+0x0/0xc1 @ 1
[    2.016004] bus: 'serio': registered
[    2.020004] initcall serio_init+0x0/0xc1 returned 0 after 3906 usecs
[    2.024004] calling  gameport_init+0x0/0xc1 @ 1
[    2.028004] bus: 'gameport': registered
[    2.032004] initcall gameport_init+0x0/0xc1 returned 0 after 3906 usecs
[    2.036004] calling  input_init+0x0/0x136 @ 1
[    2.040004] device class 'input': registering
[    2.048004] initcall input_init+0x0/0x136 returned 0 after 7812 usecs
[    2.052004] calling  power_supply_class_init+0x0/0x55 @ 1
[    2.056004] device class 'power_supply': registering
[    2.060004] initcall power_supply_class_init+0x0/0x55 returned 0 after 3906 usecs
[    2.064004] calling  leds_init+0x0/0x5c @ 1
[    2.068004] device class 'leds': registering
[    2.072004] initcall leds_init+0x0/0x5c returned 0 after 3906 usecs
[    2.076004] calling  ac97_bus_init+0x0/0xf @ 1
[    2.080004] bus: 'ac97': registered
[    2.084004] initcall ac97_bus_init+0x0/0xf returned 0 after 3906 usecs
[    2.088004] calling  pci_subsys_init+0x0/0x135 @ 1
[    2.092004] PCI: Probing PCI hardware
[    2.096004] PCI: Probing PCI hardware (bus 00)
[    2.104004] device: 'pci0000:00': device_add
[    2.108004] PM: Adding info for No Bus:pci0000:00
[    2.112004] device: '0000:00': device_add
[    2.116004] PM: Adding info for No Bus:0000:00
[    2.120004] PCI: Scanning bus 0000:00
[    2.124004] pci 0000:00:00.0: found [8086:2531] class 000600 header type 00
[    2.128004] pci 0000:00:00.0: reg 10 32bit mmio: [0xd8000000-0xdbffffff]
[    2.132004] pci 0000:00:01.0: found [8086:2532] class 000604 header type 01
[    2.136004] pci 0000:00:02.0: found [8086:2533] class 000604 header type 01
[    2.140004] pci 0000:00:1e.0: found [8086:244e] class 000604 header type 01
[    2.144004] pci 0000:00:1f.0: found [8086:2440] class 000601 header type 00
[    2.152004] pci 0000:00:1f.1: found [8086:244b] class 000101 header type 00
[    2.156004] pci 0000:00:1f.1: reg 20 io port: [0xf000-0xf00f]
[    2.160004] pci 0000:00:1f.2: found [8086:2442] class 000c03 header type 00
[    2.168004] pci 0000:00:1f.2: reg 20 io port: [0xd000-0xd01f]
[    2.172004] pci 0000:00:1f.3: found [8086:2443] class 000c05 header type 00
[    2.176004] pci 0000:00:1f.3: reg 20 io port: [0x500-0x50f]
[    2.180004] pci 0000:00:1f.4: found [8086:2444] class 000c03 header type 00
[    2.184004] pci 0000:00:1f.4: reg 20 io port: [0xd800-0xd81f]
[    2.188004] pci 0000:00:1f.5: found [8086:2445] class 000401 header type 00
[    2.192004] pci 0000:00:1f.5: reg 10 io port: [0xdc00-0xdcff]
[    2.196004] pci 0000:00:1f.5: reg 14 io port: [0xe000-0xe03f]
[    2.200004] PCI: Fixups for bus 0000:00
[    2.204004] pci 0000:00:01.0: scanning behind bridge, config 010100, pass 0
[    2.208004] PCI: Scanning bus 0000:01
[    2.212005] pci 0000:01:00.0: found [1002:5157] class 000300 header type 00
[    2.216005] pci 0000:01:00.0: reg 10 32bit mmio: [0xd0000000-0xd7ffffff]
[    2.220005] pci 0000:01:00.0: reg 14 io port: [0xa000-0xa0ff]
[    2.224005] pci 0000:01:00.0: reg 18 32bit mmio: [0xdd000000-0xdd00ffff]
[    2.228005] pci 0000:01:00.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[    2.232005] pci 0000:01:00.0: supports D1 D2
[    2.236005] PCI: Fixups for bus 0000:01
[    2.240005] pci 0000:00:01.0: bridge io port: [0xa000-0xafff]
[    2.244005] pci 0000:00:01.0: bridge 32bit mmio: [0xdc000000-0xddffffff]
[    2.248005] pci 0000:00:01.0: bridge 32bit mmio pref: [0xd0000000-0xd7ffffff]
[    2.252005] PCI: Bus scan for 0000:01 returning with max=01
[    2.256005] pci 0000:00:02.0: scanning behind bridge, config 030200, pass 0
[    2.260005] PCI: Scanning bus 0000:02
[    2.264005] pci 0000:02:1f.0: found [8086:1360] class 000604 header type 01
[    2.268005] PCI: Fixups for bus 0000:02
[    2.272005] pci 0000:00:02.0: bridge io port: [0xb000-0xbfff]
[    2.276005] pci 0000:00:02.0: bridge 32bit mmio: [0xde000000-0xdfffffff]
[    2.280005] pci 0000:02:1f.0: scanning behind bridge, config 030302, pass 0
[    2.284005] PCI: Scanning bus 0000:03
[    2.288005] pci 0000:03:00.0: found [8086:1161] class 000800 header type 00
[    2.292005] pci 0000:03:00.0: reg 10 32bit mmio: [0xdf001000-0xdf001fff]
[    2.296005] pci 0000:03:04.0: found [9005:00cf] class 000100 header type 00
[    2.300005] pci 0000:03:04.0: reg 10 io port: [0xb000-0xb0ff]
[    2.304005] pci 0000:03:04.0: reg 14 64bit mmio: [0xdf000000-0xdf000fff]
[    2.308005] pci 0000:03:04.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[    2.312005] pci 0000:03:04.1: found [9005:00cf] class 000100 header type 00
[    2.316005] pci 0000:03:04.1: reg 10 io port: [0xb400-0xb4ff]
[    2.320005] pci 0000:03:04.1: reg 14 64bit mmio: [0xdf002000-0xdf002fff]
[    2.324005] pci 0000:03:04.1: reg 30 32bit mmio: [0x000000-0x01ffff]
[    2.328005] PCI: Fixups for bus 0000:03
[    2.332005] pci 0000:02:1f.0: bridge io port: [0xb000-0xbfff]
[    2.336005] pci 0000:02:1f.0: bridge 32bit mmio: [0xde000000-0xdfffffff]
[    2.340005] PCI: Bus scan for 0000:03 returning with max=03
[    2.344005] pci 0000:02:1f.0: scanning behind bridge, config 030302, pass 1
[    2.348005] PCI: Bus scan for 0000:02 returning with max=03
[    2.352005] pci 0000:00:1e.0: scanning behind bridge, config 040400, pass 0
[    2.356005] PCI: Scanning bus 0000:04
[    2.360005] pci 0000:04:03.0: found [8086:1229] class 000200 header type 00
[    2.364005] pci 0000:04:03.0: reg 10 32bit mmio: [0xe1241000-0xe1241fff]
[    2.368005] pci 0000:04:03.0: reg 14 io port: [0xc000-0xc03f]
[    2.372005] pci 0000:04:03.0: reg 18 32bit mmio: [0xe1000000-0xe10fffff]
[    2.376005] pci 0000:04:03.0: reg 30 32bit mmio: [0x000000-0x0fffff]
[    2.380005] pci 0000:04:03.0: supports D1 D2
[    2.384005] pci 0000:04:03.0: PME# supported from D0 D1 D2 D3hot D3cold
[    2.388005] pci 0000:04:03.0: PME# disabled
[    2.392005] pci 0000:04:04.0: found [8086:1229] class 000200 header type 00
[    2.396005] pci 0000:04:04.0: reg 10 32bit mmio: [0xe1240000-0xe1240fff]
[    2.400005] pci 0000:04:04.0: reg 14 io port: [0xc400-0xc43f]
[    2.404005] pci 0000:04:04.0: reg 18 32bit mmio: [0xe1100000-0xe11fffff]
[    2.408005] pci 0000:04:04.0: reg 30 32bit mmio: [0x000000-0x0fffff]
[    2.416005] pci 0000:04:04.0: supports D1 D2
[    2.420005] pci 0000:04:04.0: PME# supported from D0 D1 D2 D3hot D3cold
[    2.424005] pci 0000:04:04.0: PME# disabled
[    2.428005] pci 0000:04:07.0: found [8086:100c] class 000200 header type 00
[    2.432005] pci 0000:04:07.0: reg 10 32bit mmio: [0xe1200000-0xe121ffff]
[    2.436005] pci 0000:04:07.0: reg 14 32bit mmio: [0xe1220000-0xe123ffff]
[    2.440005] pci 0000:04:07.0: reg 18 io port: [0xc800-0xc81f]
[    2.444005] pci 0000:04:07.0: reg 30 32bit mmio: [0x000000-0x01ffff]
[    2.448005] pci 0000:04:07.0: PME# supported from D0 D3hot D3cold
[    2.452005] pci 0000:04:07.0: PME# disabled
[    2.456005] PCI: Fixups for bus 0000:04
[    2.460005] pci 0000:00:1e.0: bridge io port: [0xc000-0xcfff]
[    2.464005] pci 0000:00:1e.0: bridge 32bit mmio: [0xe0000000-0xe1ffffff]
[    2.468005] PCI: Bus scan for 0000:04 returning with max=04
[    2.472005] pci 0000:00:01.0: scanning behind bridge, config 010100, pass 1
[    2.476005] pci 0000:00:02.0: scanning behind bridge, config 030200, pass 1
[    2.480005] pci 0000:00:1e.0: scanning behind bridge, config 040400, pass 1
[    2.484005] PCI: Bus scan for 0000:00 returning with max=04
[    2.488005] device: '0000:00:00.0': device_add
[    2.492005] bus: 'pci': add device 0000:00:00.0
[    2.496005] PM: Adding info for pci:0000:00:00.0
[    2.500005] device: '0000:00:01.0': device_add
[    2.504005] bus: 'pci': add device 0000:00:01.0
[    2.508005] PM: Adding info for pci:0000:00:01.0
[    2.512005] device: '0000:00:02.0': device_add
[    2.516005] bus: 'pci': add device 0000:00:02.0
[    2.520005] PM: Adding info for pci:0000:00:02.0
[    2.524005] device: '0000:00:1e.0': device_add
[    2.528005] bus: 'pci': add device 0000:00:1e.0
[    2.532005] PM: Adding info for pci:0000:00:1e.0
[    2.536005] device: '0000:00:1f.0': device_add
[    2.540005] bus: 'pci': add device 0000:00:1f.0
[    2.548005] PM: Adding info for pci:0000:00:1f.0
[    2.552005] device: '0000:00:1f.1': device_add
[    2.556005] bus: 'pci': add device 0000:00:1f.1
[    2.560005] PM: Adding info for pci:0000:00:1f.1
[    2.564005] device: '0000:00:1f.2': device_add
[    2.568005] bus: 'pci': add device 0000:00:1f.2
[    2.572005] PM: Adding info for pci:0000:00:1f.2
[    2.576005] device: '0000:00:1f.3': device_add
[    2.580005] bus: 'pci': add device 0000:00:1f.3
[    2.584005] PM: Adding info for pci:0000:00:1f.3
[    2.588005] device: '0000:00:1f.4': device_add
[    2.592005] bus: 'pci': add device 0000:00:1f.4
[    2.596005] PM: Adding info for pci:0000:00:1f.4
[    2.600005] device: '0000:00:1f.5': device_add
[    2.604005] bus: 'pci': add device 0000:00:1f.5
[    2.608005] PM: Adding info for pci:0000:00:1f.5
[    2.612005] device: '0000:01:00.0': device_add
[    2.620005] bus: 'pci': add device 0000:01:00.0
[    2.624005] PM: Adding info for pci:0000:01:00.0
[    2.628005] device: '0000:01': device_add
[    2.632005] PM: Adding info for No Bus:0000:01
[    2.636005] device: '0000:02:1f.0': device_add
[    2.640005] bus: 'pci': add device 0000:02:1f.0
[    2.644005] PM: Adding info for pci:0000:02:1f.0
[    2.648005] device: '0000:03:00.0': device_add
[    2.652005] bus: 'pci': add device 0000:03:00.0
[    2.656005] PM: Adding info for pci:0000:03:00.0
[    2.660005] device: '0000:03:04.0': device_add
[    2.664005] bus: 'pci': add device 0000:03:04.0
[    2.668005] PM: Adding info for pci:0000:03:04.0
[    2.672005] device: '0000:03:04.1': device_add
[    2.676005] bus: 'pci': add device 0000:03:04.1
[    2.680005] PM: Adding info for pci:0000:03:04.1
[    2.684005] device: '0000:03': device_add
[    2.688005] PM: Adding info for No Bus:0000:03
[    2.692005] device: '0000:02': device_add
[    2.696005] PM: Adding info for No Bus:0000:02
[    2.700005] device: '0000:04:03.0': device_add
[    2.704005] bus: 'pci': add device 0000:04:03.0
[    2.708005] PM: Adding info for pci:0000:04:03.0
[    2.712005] device: '0000:04:04.0': device_add
[    2.716005] bus: 'pci': add device 0000:04:04.0
[    2.720005] PM: Adding info for pci:0000:04:04.0
[    2.724005] device: '0000:04:07.0': device_add
[    2.728005] bus: 'pci': add device 0000:04:07.0
[    2.732005] PM: Adding info for pci:0000:04:07.0
[    2.736005] device: '0000:04': device_add
[    2.740005] PM: Adding info for No Bus:0000:04
[    2.748005] pci 0000:00:1f.0: PIIX/ICH IRQ router [8086:2440]
[    2.752005] pci 0000:00:1f.2: PCI->APIC IRQ transform: INT D -> IRQ 19
[    2.756005] pci 0000:00:1f.3: PCI->APIC IRQ transform: INT B -> IRQ 17
[    2.760005] pci 0000:00:1f.4: PCI->APIC IRQ transform: INT C -> IRQ 23
[    2.764005] pci 0000:00:1f.5: PCI->APIC IRQ transform: INT B -> IRQ 17
[    2.768005] pci 0000:01:00.0: PCI->APIC IRQ transform: INT A -> IRQ 22
[    2.772005] pci 0000:03:04.0: PCI->APIC IRQ transform: INT A -> IRQ 18
[    2.776005] pci 0000:03:04.1: PCI->APIC IRQ transform: INT B -> IRQ 18
[    2.780006] pci 0000:04:03.0: PCI->APIC IRQ transform: INT A -> IRQ 19
[    2.784006] pci 0000:04:04.0: PCI->APIC IRQ transform: INT A -> IRQ 16
[    2.788006] pci 0000:04:07.0: PCI->APIC IRQ transform: INT A -> IRQ 16
[    2.792006] initcall pci_subsys_init+0x0/0x135 returned 0 after 683594 usecs
[    2.796006] calling  proto_init+0x0/0xf @ 1
[    2.800006] initcall proto_init+0x0/0xf returned 0 after 0 usecs
[    2.804006] calling  net_dev_init+0x0/0x1c2 @ 1
[    2.808006] device class 'net': registering
[    2.812006] device: 'lo': device_add
[    2.816006] PM: Adding info for No Bus:lo
[    2.824006] initcall net_dev_init+0x0/0x1c2 returned 0 after 15625 usecs
[    2.828006] calling  neigh_init+0x0/0x66 @ 1
[    2.832006] initcall neigh_init+0x0/0x66 returned 0 after 0 usecs
[    2.836006] calling  pktsched_init+0x0/0xa9 @ 1
[    2.840006] initcall pktsched_init+0x0/0xa9 returned 0 after 0 usecs
[    2.844006] calling  tc_filter_init+0x0/0x43 @ 1
[    2.848006] initcall tc_filter_init+0x0/0x43 returned 0 after 0 usecs
[    2.852006] calling  tc_action_init+0x0/0x43 @ 1
[    2.856006] initcall tc_action_init+0x0/0x43 returned 0 after 0 usecs
[    2.860006] calling  genl_init+0x0/0xe7 @ 1
[    2.880006] initcall genl_init+0x0/0xe7 returned 0 after 15625 usecs
[    2.884006] calling  cipso_v4_init+0x0/0x8f @ 1
[    2.888006] initcall cipso_v4_init+0x0/0x8f returned 0 after 0 usecs
[    2.892006] calling  wanrouter_init+0x0/0x50 @ 1
[    2.896006] Sangoma WANPIPE Router v1.1 (c) 1995-2000 Sangoma Technologies Inc.
[    2.900006] initcall wanrouter_init+0x0/0x50 returned 0 after 3906 usecs
[    2.904006] calling  wireless_nlevent_init+0x0/0x39 @ 1
[    2.908006] initcall wireless_nlevent_init+0x0/0x39 returned 0 after 0 usecs
[    2.912006] calling  cfg80211_init+0x0/0xa4 @ 1
[    2.916006] device class 'ieee80211': registering
[    2.936006] Registering platform device 'regulatory.0'. Parent at platform
[    2.940006] device: 'regulatory.0': device_add
[    2.944006] bus: 'platform': add device regulatory.0
[    2.948006] PM: Adding info for platform:regulatory.0
[    2.952006] cfg80211: Using static regulatory domain info
[    2.956006] cfg80211: Regulatory domain: US
[    2.960006] 	(start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[    2.964006] 	(2402000 KHz - 2472000 KHz @ 40000 KHz), (600 mBi, 2700 mBm)
[    2.968006] 	(5170000 KHz - 5190000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[    2.972006] 	(5190000 KHz - 5210000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[    2.976006] 	(5210000 KHz - 5230000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[    2.980006] 	(5230000 KHz - 5330000 KHz @ 40000 KHz), (600 mBi, 2300 mBm)
[    2.984006] 	(5735000 KHz - 5835000 KHz @ 40000 KHz), (600 mBi, 3000 mBm)
[    2.988006] cfg80211: Calling CRDA for country: US
[    2.992006] initcall cfg80211_init+0x0/0xa4 returned 0 after 74218 usecs
[    2.996006] calling  ieee80211_init+0x0/0xc @ 1
[    3.000006] initcall ieee80211_init+0x0/0xc returned 0 after 0 usecs
[    3.004006] calling  netlbl_init+0x0/0xa6 @ 1
[    3.008006] NetLabel: Initializing
[    3.012006] NetLabel:  domain hash size = 128
[    3.016006] NetLabel:  protocols = UNLABELED CIPSOv4
[    3.020006] NetLabel:  unlabeled traffic allowed by default
[    3.024006] initcall netlbl_init+0x0/0xa6 returned 0 after 15625 usecs
[    3.028006] calling  sysctl_init+0x0/0x49 @ 1
[    3.032006] initcall sysctl_init+0x0/0x49 returned 0 after 0 usecs
[    3.036006] calling  pci_iommu_init+0x0/0xc @ 1
[    3.040006] initcall pci_iommu_init+0x0/0xc returned 0 after 0 usecs
[    3.044006] calling  print_all_ICs+0x0/0x4ce @ 1
[    3.048006] 
[    3.048006] printing PIC contents
[    3.052006] ... PIC  IMR: fffb
[    3.052006] ... PIC  IRR: 0001
[    3.056006] ... PIC  ISR: 0000
[    3.056006] ... PIC ELCR: 1e20
[    3.060006] 
[    3.060006] printing local APIC contents on CPU#0/0:
[    3.060006] ... APIC ID:      00000000 (0)
[    3.060006] ... APIC VERSION: 00050014
[    3.060006] ... APIC TASKPRI: 00000000 (00)
[    3.060006] ... APIC PROCPRI: 00000000
[    3.060006] ... APIC LDR: 01000000
[    3.060006] ... APIC DFR: ffffffff
[    3.060006] ... APIC SPIV: 000001ff
[    3.060006] ... APIC ISR field:
[    3.060006] 0123456789abcdef0123456789abcdef
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] ... APIC TMR field:
[    3.060006] 0123456789abcdef0123456789abcdef
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] ... APIC IRR field:
[    3.060006] 0123456789abcdef0123456789abcdef
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000001000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] 00000000000000000000000000000000
[    3.060006] ... APIC ESR: 00000000
[    3.060006] ... APIC ICR: 000008ef
[    3.060006] ... APIC ICR2: 0e000000
[    3.060006] ... APIC LVTT: 000100ef
[    3.060006] ... APIC LVTPC: 00010000
[    3.060006] ... APIC LVT0: 00010700
[    3.060006] ... APIC LVT1: 00000400
[    3.060006] ... APIC LVTERR: 000000fe
[    3.060006] ... APIC TMICT: 00000000
[    3.060006] ... APIC TMCCT: 00000000
[    3.060006] ... APIC TDCR: 00000000
[    3.060006] 
[    3.064006] 
[    3.064006] printing local APIC contents on CPU#1/1:
[    3.068006] ... APIC ID:      01000000 (1)
[    3.068006] ... APIC VERSION: 00050014
[    3.068006] ... APIC TASKPRI: 00000000 (00)
[    3.068006] ... APIC PROCPRI: 00000000
[    3.068006] ... APIC LDR: 02000000
[    3.068006] ... APIC DFR: ffffffff
[    3.068006] ... APIC SPIV: 000001ff
[    3.068006] ... APIC ISR field:
[    3.068006] 0123456789abcdef0123456789abcdef
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] ... APIC TMR field:
[    3.068006] 0123456789abcdef0123456789abcdef
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] ... APIC IRR field:
[    3.068006] 0123456789abcdef0123456789abcdef
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000000000000000000000
[    3.068006] 00000000000000010000000000000000
[    3.068006] ... APIC ESR: 00000000
[    3.068006] ... APIC ICR: 00000000
[    3.068006] ... APIC ICR2: 00000000
[    3.068006] ... APIC LVTT: 000100ef
[    3.068006] ... APIC LVTPC: 00010000
[    3.068006] ... APIC LVT0: 00010700
[    3.068006] ... APIC LVT1: 00010400
[    3.068006] ... APIC LVTERR: 000000fe
[    3.068006] ... APIC TMICT: 00000000
[    3.068006] ... APIC TMCCT: 00000000
[    3.068006] ... APIC TDCR: 00000000
[    3.068006] 
[    3.260006] 
[    3.260006] printing local APIC contents on CPU#2/2:
[    3.264007] ... APIC ID:      02000000 (2)
[    3.264007] ... APIC VERSION: 00050014
[    3.264007] ... APIC TASKPRI: 00000000 (00)
[    3.264007] ... APIC PROCPRI: 00000000
[    3.264007] ... APIC LDR: 04000000
[    3.264007] ... APIC DFR: ffffffff
[    3.264007] ... APIC SPIV: 000001ff
[    3.264007] ... APIC ISR field:
[    3.264007] 0123456789abcdef0123456789abcdef
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] ... APIC TMR field:
[    3.264007] 0123456789abcdef0123456789abcdef
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] ... APIC IRR field:
[    3.264007] 0123456789abcdef0123456789abcdef
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000000000000000000000
[    3.264007] 00000000000000010000000000000000
[    3.264007] ... APIC ESR: 00000000
[    3.264007] ... APIC ICR: 00000000
[    3.264007] ... APIC ICR2: 00000000
[    3.264007] ... APIC LVTT: 000100ef
[    3.264007] ... APIC LVTPC: 00010000
[    3.264007] ... APIC LVT0: 00010700
[    3.264007] ... APIC LVT1: 00010400
[    3.264007] ... APIC LVTERR: 000000fe
[    3.264007] ... APIC TMICT: 00000000
[    3.264007] ... APIC TMCCT: 00000000
[    3.264007] ... APIC TDCR: 00000000
[    3.264007] 
[    3.460007] 
[    3.460007] printing local APIC contents on CPU#3/3:
[    3.464007] ... APIC ID:      03000000 (3)
[    3.464007] ... APIC VERSION: 00050014
[    3.464007] ... APIC TASKPRI: 00000000 (00)
[    3.464007] ... APIC PROCPRI: 00000000
[    3.464007] ... APIC LDR: 08000000
[    3.464007] ... APIC DFR: ffffffff
[    3.464007] ... APIC SPIV: 000001ff
[    3.464007] ... APIC ISR field:
[    3.464007] 0123456789abcdef0123456789abcdef
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] ... APIC TMR field:
[    3.464007] 0123456789abcdef0123456789abcdef
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] ... APIC IRR field:
[    3.464007] 0123456789abcdef0123456789abcdef
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000000000000000000000
[    3.464007] 00000000000000010000000000000000
[    3.464007] ... APIC ESR: 00000000
[    3.464007] ... APIC ICR: 00000000
[    3.464007] ... APIC ICR2: 00000000
[    3.464007] ... APIC LVTT: 000100ef
[    3.464007] ... APIC LVTPC: 00010000
[    3.464007] ... APIC LVT0: 00010700
[    3.464007] ... APIC LVT1: 00010400
[    3.464007] ... APIC LVTERR: 000000fe
[    3.464007] ... APIC TMICT: 00000000
[    3.464007] ... APIC TMCCT: 00000000
[    3.464007] ... APIC TDCR: 00000000
[    3.464007] 
[    3.656007] number of MP IRQ sources: 21.
[    3.660007] number of IO-APIC #4 registers: 24.
[    3.664007] testing the IO APIC.......................
[    3.668007] 
[    3.672007] IO APIC #4......
[    3.672007] .... register #00: 04000000
[    3.676007] .......    : physical APIC id: 04
[    3.680007] .......    : Delivery Type: 0
[    3.684007] .......    : LTS          : 0
[    3.688007] .... register #01: 00178020
[    3.692007] .......     : max redirection entries: 0017
[    3.696007] .......     : PRQ implemented: 1
[    3.700007] .......     : IO APIC version: 0020
[    3.704007] .... register #02: 00000000
[    3.708007] .......     : arbitration: 00
[    3.712007] .... register #03: 00000001
[    3.716007] .......     : Boot DT    : 1
[    3.720007] .... IRQ redirection table:
[    3.724007]  NR Dst Mask Trig IRR Pol Stat Dmod Deli Vect:   
[    3.728007]  00 000 1    0    0   0   0    0    0    00
[    3.732007]  01 00F 0    0    0   0   0    1    1    31
[    3.740007]  02 00F 0    0    0   0   0    1    1    30
[    3.744007]  03 00F 0    0    0   0   0    1    1    33
[    3.748007]  04 00F 0    0    0   0   0    1    1    34
[    3.756007]  05 000 1    0    0   0   0    0    0    00
[    3.760007]  06 00F 0    0    0   0   0    1    1    36
[    3.764007]  07 00F 0    0    0   0   0    1    1    37
[    3.772007]  08 00F 0    0    0   0   0    1    1    38
[    3.776007]  09 000 1    0    0   0   0    0    0    00
[    3.780007]  0a 000 1    0    0   0   0    0    0    00
[    3.784007]  0b 000 1    0    0   0   0    0    0    00
[    3.792007]  0c 000 1    0    0   0   0    0    0    00
[    3.796007]  0d 00F 0    0    0   0   0    1    1    3D
[    3.800007]  0e 00F 0    0    0   0   0    1    1    3E
[    3.808007]  0f 00F 0    0    0   0   0    1    1    3F
[    3.812007]  10 00F 1    1    0   1   0    1    1    49
[    3.816007]  11 00F 1    1    0   1   0    1    1    51
[    3.824007]  12 00F 1    1    0   1   0    1    1    59
[    3.828007]  13 00F 1    1    0   1   0    1    1    61
[    3.832007]  14 000 1    0    0   0   0    0    0    00
[    3.836007]  15 000 1    0    0   0   0    0    0    00
[    3.844007]  16 00F 1    1    0   1   0    1    1    69
[    3.848007]  17 00F 1    1    0   1   0    1    1    71
[    3.852007] IRQ to pin mappings:
[    3.856007] IRQ0 -> 0:2
[    3.860007] IRQ1 -> 0:1
[    3.860007] IRQ3 -> 0:3
[    3.864007] IRQ4 -> 0:4
[    3.868007] IRQ6 -> 0:6
[    3.868007] IRQ7 -> 0:7
[    3.872007] IRQ8 -> 0:8
[    3.872007] IRQ13 -> 0:13
[    3.876007] IRQ14 -> 0:14
[    3.880007] IRQ15 -> 0:15
[    3.880007] IRQ16 -> 0:16
[    3.884007] IRQ17 -> 0:17
[    3.888007] IRQ18 -> 0:18
[    3.888007] IRQ19 -> 0:19
[    3.892007] IRQ22 -> 0:22
[    3.896007] IRQ23 -> 0:23
[    3.896007] .................................... done.
[    3.900007] initcall print_all_ICs+0x0/0x4ce returned 0 after 832032 usecs
[    3.904007] calling  init_kmmio+0x0/0x23 @ 1
[    3.908007] initcall init_kmmio+0x0/0x23 returned 0 after 0 usecs
[    3.912007] calling  clocksource_done_booting+0x0/0x11 @ 1
[    3.916007] initcall clocksource_done_booting+0x0/0x11 returned 0 after 0 usecs
[    3.920007] calling  ftrace_init_debugfs+0x0/0xe7 @ 1
[    3.924007] initcall ftrace_init_debugfs+0x0/0xe7 returned 0 after 0 usecs
[    3.928007] calling  rb_init_debugfs+0x0/0x38 @ 1
[    3.932007] initcall rb_init_debugfs+0x0/0x38 returned 0 after 0 usecs
[    3.936007] calling  tracer_init_debugfs+0x0/0x272 @ 1
[    3.940007] initcall tracer_init_debugfs+0x0/0x272 returned 0 after 0 usecs
[    3.944007] calling  all_annotated_branch_stats+0x0/0x27 @ 1
[    3.948007] Warning: could not register all branches stats
[    3.952007] initcall all_annotated_branch_stats+0x0/0x27 returned 1 after 3906 usecs
[    3.956007] initcall all_annotated_branch_stats+0x0/0x27 returned with error code 1 
[    3.960007] calling  init_annotated_branch_stats+0x0/0x27 @ 1
[    3.964007] Warning: could not register annotated branches stats
[    3.968007] initcall init_annotated_branch_stats+0x0/0x27 returned 1 after 3906 usecs
[    3.972007] initcall init_annotated_branch_stats+0x0/0x27 returned with error code 1 
[    3.976008] calling  init_pipe_fs+0x0/0x76 @ 1
[    3.980008] initcall init_pipe_fs+0x0/0x76 returned 0 after 0 usecs
[    3.984008] calling  init_mnt_writers+0x0/0x7d @ 1
[    3.988008] initcall init_mnt_writers+0x0/0x7d returned 0 after 0 usecs
[    3.992008] calling  eventpoll_init+0x0/0x98 @ 1
[    3.996008] initcall eventpoll_init+0x0/0x98 returned 0 after 0 usecs
[    4.000008] calling  anon_inode_init+0x0/0x15b @ 1
[    4.004008] initcall anon_inode_init+0x0/0x15b returned 0 after 0 usecs
[    4.008008] calling  pnpbios_init+0x0/0x46a @ 1
[    4.012008] PnPBIOS: Scanning system for PnP BIOS support...
[    4.020008] PnPBIOS: Found PnP BIOS installation structure at 0xc00fbe20
[    4.024008] PnPBIOS: PnP BIOS version 1.0, entry 0xf0000:0xbe50, dseg 0xf0000
[    4.028008] device: 'pnp0': device_add
[    4.032008] PM: Adding info for No Bus:pnp0
[    4.036008] device: '00:00': device_add
[    4.040008] bus: 'pnp': add device 00:00
[    4.044008] PM: Adding info for pnp:00:00
[    4.048008] device: '00:01': device_add
[    4.052008] bus: 'pnp': add device 00:01
[    4.056008] PM: Adding info for pnp:00:01
[    4.060008] device: '00:02': device_add
[    4.064008] bus: 'pnp': add device 00:02
[    4.068008] PM: Adding info for pnp:00:02
[    4.072008] device: '00:03': device_add
[    4.076008] bus: 'pnp': add device 00:03
[    4.080008] PM: Adding info for pnp:00:03
[    4.084008] device: '00:04': device_add
[    4.088008] bus: 'pnp': add device 00:04
[    4.092008] PM: Adding info for pnp:00:04
[    4.096008] device: '00:05': device_add
[    4.100008] bus: 'pnp': add device 00:05
[    4.104008] PM: Adding info for pnp:00:05
[    4.112008] device: '00:06': device_add
[    4.116008] bus: 'pnp': add device 00:06
[    4.120008] PM: Adding info for pnp:00:06
[    4.124008] device: '00:07': device_add
[    4.128008] bus: 'pnp': add device 00:07
[    4.132008] PM: Adding info for pnp:00:07
[    4.136008] pnp 00:08: mem resource (0x0-0x9ffff) overlaps 0000:04:03.0 BAR 6 (0x0-0xfffff), disabling
[    4.140008] pnp 00:08: mem resource (0x0-0x9ffff) overlaps 0000:04:04.0 BAR 6 (0x0-0xfffff), disabling
[    4.144008] device: '00:08': device_add
[    4.148008] bus: 'pnp': add device 00:08
[    4.152008] PM: Adding info for pnp:00:08
[    4.160008] pnp 00:09: mem resource (0xf0000-0xf3fff) overlaps 0000:04:03.0 BAR 6 (0x0-0xfffff), disabling
[    4.164008] pnp 00:09: mem resource (0xf4000-0xf7fff) overlaps 0000:04:03.0 BAR 6 (0x0-0xfffff), disabling
[    4.168008] pnp 00:09: mem resource (0xf8000-0xfffff) overlaps 0000:04:03.0 BAR 6 (0x0-0xfffff), disabling
[    4.172008] pnp 00:09: mem resource (0xd5800-0xd7fff) overlaps 0000:04:03.0 BAR 6 (0x0-0xfffff), disabling
[    4.176008] pnp 00:09: mem resource (0xf0000-0xf3fff) overlaps 0000:04:04.0 BAR 6 (0x0-0xfffff), disabling
[    4.180008] pnp 00:09: mem resource (0xf4000-0xf7fff) overlaps 0000:04:04.0 BAR 6 (0x0-0xfffff), disabling
[    4.184008] pnp 00:09: mem resource (0xf8000-0xfffff) overlaps 0000:04:04.0 BAR 6 (0x0-0xfffff), disabling
[    4.188008] pnp 00:09: mem resource (0xd5800-0xd7fff) overlaps 0000:04:04.0 BAR 6 (0x0-0xfffff), disabling
[    4.192008] device: '00:09': device_add
[    4.196008] bus: 'pnp': add device 00:09
[    4.200008] PM: Adding info for pnp:00:09
[    4.204008] device: '00:0a': device_add
[    4.208008] bus: 'pnp': add device 00:0a
[    4.212008] PM: Adding info for pnp:00:0a
[    4.216008] device: '00:0c': device_add
[    4.220008] bus: 'pnp': add device 00:0c
[    4.224008] PM: Adding info for pnp:00:0c
[    4.228008] device: '00:0d': device_add
[    4.232008] bus: 'pnp': add device 00:0d
[    4.236008] PM: Adding info for pnp:00:0d
[    4.240008] device: '00:0f': device_add
[    4.244008] bus: 'pnp': add device 00:0f
[    4.248008] PM: Adding info for pnp:00:0f
[    4.252008] device: '00:10': device_add
[    4.256008] bus: 'pnp': add device 00:10
[    4.260008] PM: Adding info for pnp:00:10
[    4.268008] PnPBIOS: 15 nodes reported by PnP BIOS; 15 recorded by driver
[    4.272008] initcall pnpbios_init+0x0/0x46a returned 0 after 253906 usecs
[    4.276008] calling  pnp_system_init+0x0/0xf @ 1
[    4.280008] bus: 'pnp': add driver system
[    4.284008] bus: 'pnp': driver_probe_device: matched device 00:08 with driver system
[    4.288008] bus: 'pnp': really_probe: probing driver system with device 00:08
[    4.292008] system 00:08: iomem range 0xffb00000-0xffb7ffff has been reserved
[    4.296008] system 00:08: iomem range 0xfff00000-0xffffffff has been reserved
[    4.300008] system 00:08: iomem range 0xfec00000-0xfecfffff has been reserved
[    4.304008] system 00:08: iomem range 0xfee00000-0xfee0ffff has been reserved
[    4.308008] system 00:08: iomem range 0x100000-0xffffff could not be reserved
[    4.312008] driver: '00:08': driver_bound: bound to device 'system'
[    4.316008] bus: 'pnp': really_probe: bound device 00:08 to driver system
[    4.320008] bus: 'pnp': driver_probe_device: matched device 00:09 with driver system
[    4.324008] bus: 'pnp': really_probe: probing driver system with device 00:09
[    4.328008] driver: '00:09': driver_bound: bound to device 'system'
[    4.332008] bus: 'pnp': really_probe: bound device 00:09 to driver system
[    4.336008] initcall pnp_system_init+0x0/0xf returned 0 after 54687 usecs
[    4.340008] calling  chr_dev_init+0x0/0xa7 @ 1
[    4.344008] device class 'mem': registering
[    4.348008] device: 'mem': device_add
[    4.352008] PM: Adding info for No Bus:mem
[    4.356008] device: 'null': device_add
[    4.360008] PM: Adding info for No Bus:null
[    4.368008] device: 'port': device_add
[    4.372008] PM: Adding info for No Bus:port
[    4.376008] device: 'zero': device_add
[    4.380008] PM: Adding info for No Bus:zero
[    4.384008] device: 'full': device_add
[    4.388008] PM: Adding info for No Bus:full
[    4.392008] device: 'random': device_add
[    4.396008] PM: Adding info for No Bus:random
[    4.400008] device: 'urandom': device_add
[    4.404008] PM: Adding info for No Bus:urandom
[    4.408008] device: 'kmsg': device_add
[    4.412008] PM: Adding info for No Bus:kmsg
[    4.416008] device: 'oldmem': device_add
[    4.420008] PM: Adding info for No Bus:oldmem
[    4.424008] initcall chr_dev_init+0x0/0xa7 returned 0 after 78125 usecs
[    4.428008] calling  firmware_class_init+0x0/0x7b @ 1
[    4.432008] device class 'firmware': registering
[    4.436008] initcall firmware_class_init+0x0/0x7b returned 0 after 3906 usecs
[    4.440008] calling  cpufreq_gov_performance_init+0x0/0xf @ 1
[    4.444008] initcall cpufreq_gov_performance_init+0x0/0xf returned 0 after 0 usecs
[    4.448008] calling  ssb_modinit+0x0/0x7d @ 1
[    4.452008] bus: 'ssb': registered
[    4.456008] bus: 'pci': add driver b43-pci-bridge
[    4.460008] initcall ssb_modinit+0x0/0x7d returned 0 after 7812 usecs
[    4.464008] calling  pcibios_assign_resources+0x0/0xa5 @ 1
[    4.468008] pci 0000:01:00.0: BAR 6: got res [0xdc000000-0xdc01ffff] bus [0xdc000000-0xdc01ffff] flags 0x27200
[    4.472008] pci 0000:00:01.0: PCI bridge, secondary bus 0000:01
[    4.476008] pci 0000:00:01.0:   IO window: 0xa000-0xafff
[    4.484008] pci 0000:00:01.0:   MEM window: 0xdc000000-0xddffffff
[    4.488008] pci 0000:00:01.0:   PREFETCH window: 0x000000d0000000-0x000000d7ffffff
[    4.492008] pci 0000:03:04.0: BAR 6: got res [0x50000000-0x5001ffff] bus [0x50000000-0x5001ffff] flags 0x27200
[    4.496008] pci 0000:03:04.1: BAR 6: got res [0x50020000-0x5003ffff] bus [0x50020000-0x5003ffff] flags 0x27200
[    4.500008] pci 0000:02:1f.0: PCI bridge, secondary bus 0000:03
[    4.504008] pci 0000:02:1f.0:   IO window: 0xb000-0xbfff
[    4.508008] pci 0000:02:1f.0:   MEM window: 0xde000000-0xdfffffff
[    4.512008] pci 0000:02:1f.0:   PREFETCH window: 0x00000050000000-0x000000500fffff
[    4.516008] pci 0000:00:02.0: PCI bridge, secondary bus 0000:02
[    4.520008] pci 0000:00:02.0:   IO window: 0xb000-0xbfff
[    4.524008] pci 0000:00:02.0:   MEM window: 0xde000000-0xdfffffff
[    4.528008] pci 0000:00:02.0:   PREFETCH window: 0x00000050000000-0x000000500fffff
[    4.532008] pci 0000:04:03.0: BAR 6: got res [0x50100000-0x501fffff] bus [0x50100000-0x501fffff] flags 0x27200
[    4.536008] pci 0000:04:04.0: BAR 6: got res [0x50200000-0x502fffff] bus [0x50200000-0x502fffff] flags 0x27200
[    4.540008] pci 0000:04:07.0: BAR 6: got res [0x50300000-0x5031ffff] bus [0x50300000-0x5031ffff] flags 0x27200
[    4.544008] pci 0000:00:1e.0: PCI bridge, secondary bus 0000:04
[    4.548008] pci 0000:00:1e.0:   IO window: 0xc000-0xcfff
[    4.552008] pci 0000:00:1e.0:   MEM window: 0xe0000000-0xe1ffffff
[    4.556008] pci 0000:00:1e.0:   PREFETCH window: 0x00000050100000-0x000000503fffff
[    4.560008] pci 0000:02:1f.0: setting latency timer to 64
[    4.564008] pci 0000:00:1e.0: setting latency timer to 64
[    4.568008] pci_bus 0000:00: resource 0 io:  [0x00-0xffff]
[    4.572008] pci_bus 0000:00: resource 1 mem: [0x000000-0xffffffff]
[    4.576008] pci_bus 0000:01: resource 0 io:  [0xa000-0xafff]
[    4.580008] pci_bus 0000:01: resource 1 mem: [0xdc000000-0xddffffff]
[    4.584008] pci_bus 0000:01: resource 2 mem: [0xd0000000-0xd7ffffff]
[    4.588008] pci_bus 0000:01: resource 3 mem: [0x0-0x0]
[    4.592008] pci_bus 0000:02: resource 0 io:  [0xb000-0xbfff]
[    4.596008] pci_bus 0000:02: resource 1 mem: [0xde000000-0xdfffffff]
[    4.600008] pci_bus 0000:02: resource 2 mem: [0x50000000-0x500fffff]
[    4.604008] pci_bus 0000:02: resource 3 mem: [0x0-0x0]
[    4.608008] pci_bus 0000:03: resource 0 io:  [0xb000-0xbfff]
[    4.612009] pci_bus 0000:03: resource 1 mem: [0xde000000-0xdfffffff]
[    4.616009] pci_bus 0000:03: resource 2 mem: [0x50000000-0x500fffff]
[    4.620009] pci_bus 0000:03: resource 3 mem: [0x0-0x0]
[    4.624009] pci_bus 0000:04: resource 0 io:  [0xc000-0xcfff]
[    4.628009] pci_bus 0000:04: resource 1 mem: [0xe0000000-0xe1ffffff]
[    4.632009] pci_bus 0000:04: resource 2 mem: [0x50100000-0x503fffff]
[    4.636009] pci_bus 0000:04: resource 3 mem: [0x0-0x0]
[    4.640009] initcall pcibios_assign_resources+0x0/0xa5 returned 0 after 167969 usecs
[    4.644009] calling  sysctl_core_init+0x0/0x2d @ 1
[    4.648009] initcall sysctl_core_init+0x0/0x2d returned 0 after 0 usecs
[    4.652009] calling  inet_init+0x0/0x27e @ 1
[    4.656009] NET: Registered protocol family 2
[    4.708009] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    4.716009] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    4.720009] TCP bind hash table entries: 65536 (order: 9, 2097152 bytes)
[    4.744009] TCP: Hash tables configured (established 131072 bind 65536)
[    4.748009] TCP reno registered
[    4.764009] initcall inet_init+0x0/0x27e returned 0 after 105468 usecs
[    4.768009] calling  af_unix_init+0x0/0x53 @ 1
[    4.772009] NET: Registered protocol family 1
[    4.776009] initcall af_unix_init+0x0/0x53 returned 0 after 3906 usecs
[    4.780009] calling  populate_rootfs+0x0/0xc5 @ 1
[    4.788009] initcall populate_rootfs+0x0/0xc5 returned 0 after 3906 usecs
[    4.792009] calling  i8259A_init_sysfs+0x0/0x2b @ 1
[    4.796009] Registering sysdev class 'i8259'
[    4.800009] Registering sys device of class 'i8259'
[    4.804009] Registering sys device 'i82590'
[    4.808009] initcall i8259A_init_sysfs+0x0/0x2b returned 0 after 11718 usecs
[    4.812009] calling  sbf_init+0x0/0x11f @ 1
[    4.816009] initcall sbf_init+0x0/0x11f returned 0 after 0 usecs
[    4.820009] calling  i8237A_init_sysfs+0x0/0x2b @ 1
[    4.824009] Registering sysdev class 'i8237'
[    4.828009] Registering sys device of class 'i8237'
[    4.832009] Registering sys device 'i82370'
[    4.836009] initcall i8237A_init_sysfs+0x0/0x2b returned 0 after 11718 usecs
[    4.840009] calling  add_rtc_cmos+0x0/0x8a @ 1
[    4.844009] initcall add_rtc_cmos+0x0/0x8a returned 0 after 0 usecs
[    4.848009] calling  cache_sysfs_init+0x0/0x89 @ 1
[    4.852009] initcall cache_sysfs_init+0x0/0x89 returned 0 after 0 usecs
[    4.856009] calling  cpufreq_gx_init+0x0/0x1b4 @ 1
[    4.860009] initcall cpufreq_gx_init+0x0/0x1b4 returned -19 after 0 usecs
[    4.864009] calling  speedstep_init+0x0/0x157 @ 1
[    4.868009] initcall speedstep_init+0x0/0x157 returned -19 after 0 usecs
[    4.872009] calling  msr_init+0x0/0x149 @ 1
[    4.876009] device class 'msr': registering
[    4.880009] device: 'msr0': device_add
[    4.884009] PM: Adding info for No Bus:msr0
[    4.888009] device: 'msr1': device_add
[    4.892009] PM: Adding info for No Bus:msr1
[    4.900009] device: 'msr2': device_add
[    4.904009] PM: Adding info for No Bus:msr2
[    4.908009] device: 'msr3': device_add
[    4.912009] PM: Adding info for No Bus:msr3
[    4.916009] initcall msr_init+0x0/0x149 returned 0 after 39062 usecs
[    4.920009] calling  apm_init+0x0/0x4c1 @ 1
[    4.924009] apm: BIOS version 1.2 Flags 0x07 (Driver version 1.16ac)
[    4.928009] apm: disabled - APM is not SMP safe.
[    4.932009] initcall apm_init+0x0/0x4c1 returned -19 after 7812 usecs
[    4.936009] calling  init_lapic_sysfs+0x0/0x44 @ 1
[    4.940009] Registering sysdev class 'lapic'
[    4.944009] Registering sys device of class 'lapic'
[    4.948009] Registering sys device 'lapic0'
[    4.952009] initcall init_lapic_sysfs+0x0/0x44 returned 0 after 11718 usecs
[    4.956009] calling  ioapic_init_sysfs+0x0/0xb9 @ 1
[    4.960009] Registering sysdev class 'ioapic'
[    4.968009] Registering sys device of class 'ioapic'
[    4.972009] Registering sys device 'ioapic0'
[    4.976009] initcall ioapic_init_sysfs+0x0/0xb9 returned 0 after 15625 usecs
[    4.980009] calling  init_vmi_clocksource+0x0/0xf4 @ 1
[    4.984009] initcall init_vmi_clocksource+0x0/0xf4 returned 0 after 0 usecs
[    4.988009] calling  add_pcspkr+0x0/0x45 @ 1
[    4.992009] Registering platform device 'pcspkr'. Parent at platform
[    4.996009] device: 'pcspkr': device_add
[    5.000009] bus: 'platform': add device pcspkr
[    5.004009] PM: Adding info for platform:pcspkr
[    5.008009] initcall add_pcspkr+0x0/0x45 returned 0 after 15625 usecs
[    5.012009] calling  scx200_init+0x0/0x20 @ 1
[    5.016009] scx200: NatSemi SCx200 Driver
[    5.020009] bus: 'pci': add driver scx200
[    5.028009] initcall scx200_init+0x0/0x20 returned 0 after 11718 usecs
[    5.032009] calling  start_periodic_check_for_corruption+0x0/0x4a @ 1
[    5.036009] initcall start_periodic_check_for_corruption+0x0/0x4a returned 0 after 0 usecs
[    5.040009] calling  start_pageattr_test+0x0/0x74 @ 1
[    5.044009] initcall start_pageattr_test+0x0/0x74 returned 0 after 0 usecs
[    5.048009] calling  init+0x0/0xf @ 1
[    5.052009] initcall init+0x0/0xf returned 0 after 0 usecs
[    5.056009] calling  proc_execdomains_init+0x0/0x1c @ 1
[    5.060009] initcall proc_execdomains_init+0x0/0x1c returned 0 after 0 usecs
[    5.068009] calling  ioresources_init+0x0/0x31 @ 1
[    5.072009] initcall ioresources_init+0x0/0x31 returned 0 after 0 usecs
[    5.076009] calling  uid_cache_init+0x0/0x5c @ 1
[    5.080009] initcall uid_cache_init+0x0/0x5c returned 0 after 0 usecs
[    5.084009] calling  init_posix_timers+0x0/0xac @ 1
[    5.088009] initcall init_posix_timers+0x0/0xac returned 0 after 0 usecs
[    5.092009] calling  init_posix_cpu_timers+0x0/0x90 @ 1
[    5.096009] initcall init_posix_cpu_timers+0x0/0x90 returned 0 after 0 usecs
[    5.100009] calling  nsproxy_cache_init+0x0/0x27 @ 1
[    5.104009] initcall nsproxy_cache_init+0x0/0x27 returned 0 after 0 usecs
[    5.108009] calling  create_proc_profile+0x0/0x259 @ 1
[    5.112009] initcall create_proc_profile+0x0/0x259 returned 0 after 0 usecs
[    5.116009] calling  timekeeping_init_device+0x0/0x2b @ 1
[    5.120009] Registering sysdev class 'timekeeping'
[    5.124009] Registering sys device of class 'timekeeping'
[    5.128009] Registering sys device 'timekeeping0'
[    5.132009] initcall timekeeping_init_device+0x0/0x2b returned 0 after 11718 usecs
[    5.136009] calling  init_clocksource_sysfs+0x0/0x6d @ 1
[    5.140009] Registering sysdev class 'clocksource'
[    5.144009] Registering sys device of class 'clocksource'
[    5.148009] Registering sys device 'clocksource0'
[    5.152009] initcall init_clocksource_sysfs+0x0/0x6d returned 0 after 11718 usecs
[    5.156009] calling  init_timer_list_procfs+0x0/0x38 @ 1
[    5.160009] initcall init_timer_list_procfs+0x0/0x38 returned 0 after 0 usecs
[    5.164009] calling  init_tstats_procfs+0x0/0x38 @ 1
[    5.168009] initcall init_tstats_procfs+0x0/0x38 returned 0 after 0 usecs
[    5.172009] calling  lockdep_proc_init+0x0/0x4f @ 1
[    5.176009] initcall lockdep_proc_init+0x0/0x4f returned 0 after 0 usecs
[    5.180009] calling  futex_init+0x0/0x7d @ 1
[    5.184009] initcall futex_init+0x0/0x7d returned 0 after 0 usecs
[    5.188009] calling  proc_dma_init+0x0/0x1c @ 1
[    5.192009] initcall proc_dma_init+0x0/0x1c returned 0 after 0 usecs
[    5.196009] calling  proc_modules_init+0x0/0x1c @ 1
[    5.200009] initcall proc_modules_init+0x0/0x1c returned 0 after 0 usecs
[    5.204009] calling  percpu_modinit+0x0/0xa1 @ 1
[    5.208009] initcall percpu_modinit+0x0/0xa1 returned 0 after 0 usecs
[    5.212009] calling  kallsyms_init+0x0/0x1f @ 1
[    5.216009] initcall kallsyms_init+0x0/0x1f returned 0 after 0 usecs
[    5.220009] calling  pid_namespaces_init+0x0/0x27 @ 1
[    5.224009] initcall pid_namespaces_init+0x0/0x27 returned 0 after 0 usecs
[    5.228009] calling  audit_init+0x0/0x182 @ 1
[    5.232009] audit: initializing netlink socket (disabled)
[    5.236009] type=2000 audit(1235057932.236:1): initialized
[    5.240009] initcall audit_init+0x0/0x182 returned 0 after 7812 usecs
[    5.244009] calling  audit_tree_init+0x0/0x60 @ 1
[    5.248009] initcall audit_tree_init+0x0/0x60 returned 0 after 0 usecs
[    5.252009] calling  init_kprobes+0x0/0x191 @ 1
[    5.288010] Kprobe smoke test started
[    5.356010] ------------[ cut here ]------------
[    5.356010] WARNING: at kernel/lockdep.c:2870 check_flags+0x5c/0x166()
[    5.356010] Hardware name: P4DC6
[    5.356010] Modules linked in:
[    5.356010] Pid: 1, comm: swapper Not tainted 2.6.29-rc5-tip #208
[    5.356010] Call Trace:
[    5.356010]  [<c013cb2e>] warn_slowpath+0x95/0xab
[    5.356010]  [<c0165477>] ? add_lock_to_list+0x74/0xa6
[    5.356010]  [<c016846c>] ? validate_chain+0xa36/0xbef
[    5.356010]  [<c01685d7>] ? validate_chain+0xba1/0xbef
[    5.356010]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[    5.356010]  [<c016525c>] check_flags+0x5c/0x166
[    5.356010]  [<c0168dc1>] lock_acquire+0x34/0x8b
[    5.356010]  [<c08ae2c6>] mutex_lock_nested+0x3f/0x595
[    5.356010]  [<c08b4243>] ? unregister_kretprobes+0x26/0x158
[    5.356010]  [<c08b3248>] ? trampoline_handler+0x12c/0x19e
[    5.356010]  [<c017d2b6>] ? init_test_probes+0x42a/0x5ef
[    5.356010]  [<c08b4243>] unregister_kretprobes+0x26/0x158
[    5.356010]  [<c08b438b>] unregister_kretprobe+0x16/0x18
[    5.356010]  [<c017d2c0>] init_test_probes+0x434/0x5ef
[    5.356010]  [<c1061de5>] init_kprobes+0x187/0x191
[    5.356010]  [<c0166bf6>] ? debug_check_no_locks_freed+0x10d/0x12d
[    5.356010]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[    5.356010]  [<c01608ec>] ? jiffies_read+0x8/0x14
[    5.356010]  [<c015edd3>] ? getnstimeofday+0xac/0x137
[    5.356010]  [<c015993e>] ? ktime_get_ts+0x6d/0x72
[    5.356010]  [<c015995b>] ? ktime_get+0x18/0x34
[    5.356010]  [<c1061c5e>] ? init_kprobes+0x0/0x191
[    5.356010]  [<c0101082>] do_one_initcall+0x78/0x1dc
[    5.356010]  [<c02ffec4>] ? number+0x1a0/0x36e
[    5.356010]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[    5.356010]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[    5.356010]  [<c0301460>] ? vsnprintf+0xacc/0xb52
[    5.356010]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[    5.356010]  [<c0166bef>] ? debug_check_no_locks_freed+0x106/0x12d
[    5.356010]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[    5.356010]  [<c03128a6>] ? _raw_spin_unlock+0x167/0x16f
[    5.356010]  [<c08b0484>] ? _spin_unlock+0x22/0x25
[    5.356010]  [<c022ce36>] ? proc_register+0x208/0x212
[    5.356010]  [<c022cfd2>] ? create_proc_entry+0xae/0xda
[    5.356010]  [<c018e573>] ? init_irq_proc+0x85/0x94
[    5.356010]  [<c10486e2>] kernel_init+0x140/0x1ad
[    5.356010]  [<c10485a2>] ? kernel_init+0x0/0x1ad
[    5.356010]  [<c010515f>] kernel_thread_helper+0x7/0x10
[    5.356010] ---[ end trace a7919e7f17c0a725 ]---
[    5.356010] possible reason: unannotated irqs-off.
[    5.356010] irq event stamp: 222111
[    5.356010] hardirqs last  enabled at (222111): [<c08b0965>] _spin_unlock_irqrestore+0x4d/0x59
[    5.356010] hardirqs last disabled at (222110): [<c08b086b>] _spin_lock_irqsave+0x1f/0x44
[    5.356010] softirqs last  enabled at (221796): [<c01438eb>] __do_softirq+0x182/0x19e
[    5.356010] softirqs last disabled at (221791): [<c0106703>] do_softirq+0x7e/0x16f
[   35.044031] CPA self-test:
[   35.044031]  4k 2046 large 220 gb 0 x 2266[c0000000-f77fd000] miss 0
[   35.152031]  4k 182270 large 44 gb 0 x 182314[c0000000-f77fd000] miss 0
[   35.228031]  4k 182270 large 44 gb 0 x 182314[c0000000-f77fd000] miss 0
[   35.232031] ok.
[   45.056031] INFO: RCU detected CPU 0 stall (t=4294903560/2500 jiffies)
[   45.056031] Pid: 1, comm: swapper Tainted: G        W  2.6.29-rc5-tip #208
[   45.056031] Call Trace:
[   45.056031]  [<c018ef02>] __rcu_pending+0x5b/0x284
[   45.056031]  [<c018f14c>] rcu_pending+0x21/0x4c
[   45.056031]  [<c0149817>] update_process_times+0x30/0x61
[   45.056031]  [<c0162319>] tick_periodic+0x79/0x85
[   45.056031]  [<c0162343>] tick_handle_periodic+0x1e/0x7f
[   45.056031]  [<c0162601>] tick_do_broadcast+0x3f/0x7d
[   45.056031]  [<c01626d3>] tick_do_periodic_broadcast+0x2e/0x3a
[   45.056031]  [<c01626f1>] tick_handle_periodic_broadcast+0x12/0x75
[   45.056031]  [<c0106cfd>] timer_interrupt+0x4d/0x83
[   45.056031]  [<c018ba2a>] handle_IRQ_event+0x33/0x80
[   45.056031]  [<c018dccf>] handle_edge_irq+0x168/0x1bc
[   45.056031]  [<c018db67>] ? handle_edge_irq+0x0/0x1bc
[   45.056031]  <IRQ>  [<c0105fa9>] ? do_IRQ+0x45/0xa6
[   45.056031]  [<c0104d6e>] ? common_interrupt+0x2e/0x34
[   45.056031]  [<c019007b>] ? relay_open_buf+0x24d/0x34f
[   45.056031]  [<c0137084>] ? mutex_spin_on_owner+0x8e/0xc1
[   45.056031]  [<c08ae2e5>] ? mutex_lock_nested+0x5e/0x595
[   45.056031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[   45.056031]  [<c017cca4>] ? kprobe_target+0x0/0x15
[   45.056031]  [<c08b452c>] ? register_kprobe+0x19f/0x4f4
[   45.056031]  [<c08b49fb>] ? register_kretprobe+0x17a/0x1e2
[   45.056031]  [<c08b4a99>] ? register_kretprobes+0x36/0x73
[   45.056031]  [<c017d340>] ? init_test_probes+0x4b4/0x5ef
[   45.056031]  [<c1061de5>] ? init_kprobes+0x187/0x191
[   45.056031]  [<c0166bf6>] ? debug_check_no_locks_freed+0x10d/0x12d
[   45.056031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[   45.056031]  [<c01608ec>] ? jiffies_read+0x8/0x14
[   45.056031]  [<c015edd3>] ? getnstimeofday+0xac/0x137
[   45.056031]  [<c015993e>] ? ktime_get_ts+0x6d/0x72
[   45.056031]  [<c015995b>] ? ktime_get+0x18/0x34
[   45.056031]  [<c1061c5e>] ? init_kprobes+0x0/0x191
[   45.056031]  [<c0101082>] ? do_one_initcall+0x78/0x1dc
[   45.056031]  [<c02ffec4>] ? number+0x1a0/0x36e
[   45.056031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[   45.056031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[   45.056031]  [<c0301460>] ? vsnprintf+0xacc/0xb52
[   45.056031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[   45.056031]  [<c0166bef>] ? debug_check_no_locks_freed+0x106/0x12d
[   45.056031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[   45.056031]  [<c03128a6>] ? _raw_spin_unlock+0x167/0x16f
[   45.056031]  [<c08b0484>] ? _spin_unlock+0x22/0x25
[   45.056031]  [<c022ce36>] ? proc_register+0x208/0x212
[   45.056031]  [<c022cfd2>] ? create_proc_entry+0xae/0xda
[   45.056031]  [<c018e573>] ? init_irq_proc+0x85/0x94
[   45.056031]  [<c10486e2>] ? kernel_init+0x140/0x1ad
[   45.056031]  [<c10485a2>] ? kernel_init+0x0/0x1ad
[   45.056031]  [<c010515f>] ? kernel_thread_helper+0x7/0x10
[   75.056031] INFO: RCU detected CPU 0 stall (t=4294911060/10000 jiffies)
[   75.056031] Pid: 1, comm: swapper Tainted: G        W  2.6.29-rc5-tip #208
[   75.056031] Call Trace:
[   75.056031]  [<c018ef02>] __rcu_pending+0x5b/0x284
[   75.056031]  [<c018f14c>] rcu_pending+0x21/0x4c
[   75.056031]  [<c0149817>] update_process_times+0x30/0x61
[   75.056031]  [<c0162319>] tick_periodic+0x79/0x85
[   75.056031]  [<c0162343>] tick_handle_periodic+0x1e/0x7f
[   75.056031]  [<c0162601>] tick_do_broadcast+0x3f/0x7d
[   75.056031]  [<c01626d3>] tick_do_periodic_broadcast+0x2e/0x3a
[   75.056031]  [<c01626f1>] tick_handle_periodic_broadcast+0x12/0x75
[   75.056031]  [<c0106cfd>] timer_interrupt+0x4d/0x83
[   75.056031]  [<c018ba2a>] handle_IRQ_event+0x33/0x80
[   75.056031]  [<c018dccf>] handle_edge_irq+0x168/0x1bc
[   75.056031]  [<c018db67>] ? handle_edge_irq+0x0/0x1bc
[   75.056031]  <IRQ>  [<c0105fa9>] ? do_IRQ+0x45/0xa6
[   75.056031]  [<c0104d6e>] ? common_interrupt+0x2e/0x34
[   75.056031]  [<c01900d8>] ? relay_open_buf+0x2aa/0x34f
[   75.056031]  [<c0137093>] ? mutex_spin_on_owner+0x9d/0xc1
[   75.056031]  [<c08ae2e5>] ? mutex_lock_nested+0x5e/0x595
[   75.056031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[   75.056031]  [<c017cca4>] ? kprobe_target+0x0/0x15
[   75.056031]  [<c08b452c>] ? register_kprobe+0x19f/0x4f4
[   75.056031]  [<c08b49fb>] ? register_kretprobe+0x17a/0x1e2
[   75.056031]  [<c08b4a99>] ? register_kretprobes+0x36/0x73
[   75.056031]  [<c017d340>] ? init_test_probes+0x4b4/0x5ef
[   75.056031]  [<c1061de5>] ? init_kprobes+0x187/0x191
[   75.056031]  [<c0166bf6>] ? debug_check_no_locks_freed+0x10d/0x12d
[   75.056031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[   75.056031]  [<c01608ec>] ? jiffies_read+0x8/0x14
[   75.056031]  [<c015edd3>] ? getnstimeofday+0xac/0x137
[   75.056031]  [<c015993e>] ? ktime_get_ts+0x6d/0x72
[   75.056031]  [<c015995b>] ? ktime_get+0x18/0x34
[   75.056031]  [<c1061c5e>] ? init_kprobes+0x0/0x191
[   75.056031]  [<c0101082>] ? do_one_initcall+0x78/0x1dc
[   75.056031]  [<c02ffec4>] ? number+0x1a0/0x36e
[   75.056031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[   75.056031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[   75.056031]  [<c0301460>] ? vsnprintf+0xacc/0xb52
[   75.056031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[   75.056031]  [<c0166bef>] ? debug_check_no_locks_freed+0x106/0x12d
[   75.056031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[   75.056031]  [<c03128a6>] ? _raw_spin_unlock+0x167/0x16f
[   75.056031]  [<c08b0484>] ? _spin_unlock+0x22/0x25
[   75.056031]  [<c022ce36>] ? proc_register+0x208/0x212
[   75.056031]  [<c022cfd2>] ? create_proc_entry+0xae/0xda
[   75.056031]  [<c018e573>] ? init_irq_proc+0x85/0x94
[   75.056031]  [<c10486e2>] ? kernel_init+0x140/0x1ad
[   75.056031]  [<c10485a2>] ? kernel_init+0x0/0x1ad
[   75.056031]  [<c010515f>] ? kernel_thread_helper+0x7/0x10
[  105.468031] INFO: RCU detected CPU 0 stall (t=4294918663/2500 jiffies)
[  105.468031] Pid: 1, comm: swapper Tainted: G        W  2.6.29-rc5-tip #208
[  105.468031] Call Trace:
[  105.468031]  [<c018ef02>] __rcu_pending+0x5b/0x284
[  105.468031]  [<c018f14c>] rcu_pending+0x21/0x4c
[  105.468031]  [<c0149817>] update_process_times+0x30/0x61
[  105.468031]  [<c0162319>] tick_periodic+0x79/0x85
[  105.468031]  [<c0162343>] tick_handle_periodic+0x1e/0x7f
[  105.468031]  [<c0162601>] tick_do_broadcast+0x3f/0x7d
[  105.468031]  [<c01626d3>] tick_do_periodic_broadcast+0x2e/0x3a
[  105.468031]  [<c01626f1>] tick_handle_periodic_broadcast+0x12/0x75
[  105.468031]  [<c0106cfd>] timer_interrupt+0x4d/0x83
[  105.468031]  [<c018ba2a>] handle_IRQ_event+0x33/0x80
[  105.468031]  [<c018dccf>] handle_edge_irq+0x168/0x1bc
[  105.468031]  [<c018db67>] ? handle_edge_irq+0x0/0x1bc
[  105.468031]  <IRQ>  [<c0105fa9>] ? do_IRQ+0x45/0xa6
[  105.468031]  [<c0104d6e>] ? common_interrupt+0x2e/0x34
[  105.468031]  [<c0137093>] ? mutex_spin_on_owner+0x9d/0xc1
[  105.468031]  [<c08ae2e5>] ? mutex_lock_nested+0x5e/0x595
[  105.468031]  [<c013465e>] ? default_wake_function+0x0/0x12
[  105.468031]  [<c08b511c>] ? free_insn_slot+0x1a/0xe4
[  105.468031]  [<c08b2ad6>] ? arch_remove_kprobe+0x21/0x33
[  105.468031]  [<c08b409b>] ? __unregister_kprobe_bottom+0x19/0x4f
[  105.468031]  [<c08b42ae>] ? unregister_kretprobes+0x91/0x158
[  105.468031]  [<c017d3f3>] ? init_test_probes+0x567/0x5ef
[  105.468031]  [<c1061de5>] ? init_kprobes+0x187/0x191
[  105.468031]  [<c0166bf6>] ? debug_check_no_locks_freed+0x10d/0x12d
[  105.468031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[  105.468031]  [<c01608ec>] ? jiffies_read+0x8/0x14
[  105.468031]  [<c015edd3>] ? getnstimeofday+0xac/0x137
[  105.468031]  [<c015993e>] ? ktime_get_ts+0x6d/0x72
[  105.468031]  [<c015995b>] ? ktime_get+0x18/0x34
[  105.468031]  [<c1061c5e>] ? init_kprobes+0x0/0x191
[  105.468031]  [<c0101082>] ? do_one_initcall+0x78/0x1dc
[  105.468031]  [<c02ffec4>] ? number+0x1a0/0x36e
[  105.468031]  [<c0166965>] ? trace_hardirqs_on+0xb/0xd
[  105.468031]  [<c016691b>] ? trace_hardirqs_on_caller+0x124/0x163
[  105.468031]  [<c0301460>] ? vsnprintf+0xacc/0xb52
[  105.468031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[  105.468031]  [<c0166bef>] ? debug_check_no_locks_freed+0x106/0x12d
[  105.468031]  [<c0168d37>] ? __lock_acquire+0x712/0x768
[  105.468031]  [<c03128a6>] ? _raw_spin_unlock+0x167/0x16f
[  105.468031]  [<c08b0484>] ? _spin_unlock+0x22/0x25
[  105.468031]  [<c022ce36>] ? proc_register+0x208/0x212
[  105.468031]  [<c022cfd2>] ? create_proc_entry+0xae/0xda
[  105.468031]  [<c018e573>] ? init_irq_proc+0x85/0x94
[  105.468031]  [<c10486e2>] ? kernel_init+0x140/0x1ad
[  105.468031]  [<c10485a2>] ? kernel_init+0x0/0x1ad
[  105.468031]  [<c010515f>] ? kernel_thread_helper+0x7/0x10

View attachment "config" of type "text/plain" (72326 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ