[ltt-dev] Time in trace passes too slowly

Francis Giraldeau francis.giraldeau at usherbrooke.ca
Mon Apr 18 10:11:44 EDT 2011


On Wed, 2011-04-13 at 11:38 -0400, Mathieu Desnoyers wrote:
> * Francis Giraldeau (francis.giraldeau at usherbrooke.ca) wrote:
> > Hi, 
> > 
> > In a trace, the time difference between two timestamps doesn't match the
> > time it should have elapsed. For example, when measuring the duration of
> > "sleep 1" in the trace, 0.358s is obtained. The real duration of the
> > sleep is indeed 1 second. The error seems proportional to a constant
> > factor. 
> > 
> > I get this behavior on a 32 bits Ubuntu with 2.6.35-24-lttng from the
> > PPA. I'm running the same version of kernel but configured with 64 bits
> > and durations are correct. 
> > 
> > I put a sample trace here:
> > 
> > http://secretaire.dorsal.polymtl.ca/~fgiraldeau/sleep-1x-1sec.tar.gz
> > 
> > Seems a bug to me, or maybe I'm doing something wrong?
> 
> What is the output of your /proc/cpuinfo ?
> 
> Can you paste your dmesg ?

The required information is attached, but I performed the trace again
and the problem does not occur. I tried to trace after sleep cycle,
changing the CPU frequency, still correct. I don't know what happened to
get those weird timestamps. Since I can't reproduce the problem, I will
not be able to tell if it's solved... If I discover an effective way to
reproduce the wrong behavior, I will post it.

Cheer, 

Francis

-------------- next part --------------
processor	: 0
vendor_id	: GenuineIntel
cpu family	: 6
model		: 14
model name	: Genuine Intel(R) CPU           T2400  @ 1.83GHz
stepping	: 8
cpu MHz		: 1000.000
cache size	: 2048 KB
physical id	: 0
siblings	: 2
core id		: 0
cpu cores	: 2
apicid		: 0
initial apicid	: 0
fdiv_bug	: no
hlt_bug		: no
f00f_bug	: no
coma_bug	: no
fpu		: yes
fpu_exception	: yes
cpuid level	: 10
wp		: yes
flags		: fpu vme de pse tsc msr pae mce cx8 apic mtrr pge mca cmov clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe nx constant_tsc arch_perfmon bts aperfmperf pni monitor vmx est tm2 xtpr pdcm dts
bogomips	: 3661.75
clflush size	: 64
cache_alignment	: 64
address sizes	: 32 bits physical, 32 bits virtual
power management:

processor	: 1
vendor_id	: GenuineIntel
cpu family	: 6
model		: 14
model name	: Genuine Intel(R) CPU           T2400  @ 1.83GHz
stepping	: 8
cpu MHz		: 1000.000
cache size	: 2048 KB
physical id	: 0
siblings	: 2
core id		: 1
cpu cores	: 2
apicid		: 1
initial apicid	: 1
fdiv_bug	: no
hlt_bug		: no
f00f_bug	: no
coma_bug	: no
fpu		: yes
fpu_exception	: yes
cpuid level	: 10
wp		: yes
flags		: fpu vme de pse tsc msr pae mce cx8 apic mtrr pge mca cmov clflush dts acpi mmx fxsr sse sse2 ss ht tm pbe nx constant_tsc arch_perfmon bts aperfmperf pni monitor vmx est tm2 xtpr pdcm dts
bogomips	: 3661.77
clflush size	: 64
cache_alignment	: 64
address sizes	: 32 bits physical, 32 bits virtual
power management:

-------------- next part --------------
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 2.6.35-24-lttng (buildd at nannyberry) (gcc version 4.4.5 (Ubuntu/Linaro 4.4.4-14ubuntu5) ) #37~lttng1-Ubuntu SMP Sun Oct 31 05:21:09 UTC 2010 (Ubuntu 2.6.35-24.37~lttng1-lttng 2.6.35.7)
[    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: 00000000000ede00 - 0000000000100000 (reserved)
[    0.000000]  BIOS-e820: 0000000000100000 - 000000007f0bd000 (usable)
[    0.000000]  BIOS-e820: 000000007f0bd000 - 000000007f2be000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000007f2be000 - 000000007feb9000 (ACPI data)
[    0.000000]  BIOS-e820: 000000007feb9000 - 000000007feef000 (ACPI NVS)
[    0.000000]  BIOS-e820: 000000007feef000 - 000000007ff00000 (ACPI data)
[    0.000000]  BIOS-e820: 000000007ff00000 - 0000000080000000 (reserved)
[    0.000000]  BIOS-e820: 00000000e0000000 - 00000000f0000000 (reserved)
[    0.000000]  BIOS-e820: 00000000fec00000 - 00000000fec01000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed14000 - 00000000fed1a000 (reserved)
[    0.000000]  BIOS-e820: 00000000fed1c000 - 00000000fed20000 (reserved)
[    0.000000]  BIOS-e820: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  BIOS-e820: 00000000ffe00000 - 0000000100000000 (reserved)
[    0.000000] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[    0.000000] DMI 2.4 present.
[    0.000000] e820 update range: 0000000000000000 - 0000000000001000 (usable) ==> (reserved)
[    0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[    0.000000] last_pfn = 0x7f0bd max_arch_pfn = 0x100000
[    0.000000] MTRR default type: uncachable
[    0.000000] MTRR fixed ranges enabled:
[    0.000000]   00000-9FFFF write-back
[    0.000000]   A0000-BFFFF uncachable
[    0.000000]   C0000-CFFFF write-protect
[    0.000000]   D0000-DFFFF uncachable
[    0.000000]   E0000-FFFFF write-protect
[    0.000000] MTRR variable ranges enabled:
[    0.000000]   0 base 0FFE00000 mask FFFE00000 write-protect
[    0.000000]   1 base 000000000 mask F80000000 write-back
[    0.000000]   2 base 07FF00000 mask FFFF00000 uncachable
[    0.000000]   3 disabled
[    0.000000]   4 disabled
[    0.000000]   5 disabled
[    0.000000]   6 disabled
[    0.000000]   7 disabled
[    0.000000] PAT not supported by CPU.
[    0.000000] e820 update range: 0000000000002000 - 0000000000010000 (usable) ==> (reserved)
[    0.000000] Scanning 1 areas for low memory corruption
[    0.000000] modified physical RAM map:
[    0.000000]  modified: 0000000000000000 - 0000000000001000 (reserved)
[    0.000000]  modified: 0000000000001000 - 0000000000002000 (usable)
[    0.000000]  modified: 0000000000002000 - 0000000000010000 (reserved)
[    0.000000]  modified: 0000000000010000 - 000000000009fc00 (usable)
[    0.000000]  modified: 000000000009fc00 - 00000000000a0000 (reserved)
[    0.000000]  modified: 00000000000ede00 - 0000000000100000 (reserved)
[    0.000000]  modified: 0000000000100000 - 000000007f0bd000 (usable)
[    0.000000]  modified: 000000007f0bd000 - 000000007f2be000 (ACPI NVS)
[    0.000000]  modified: 000000007f2be000 - 000000007feb9000 (ACPI data)
[    0.000000]  modified: 000000007feb9000 - 000000007feef000 (ACPI NVS)
[    0.000000]  modified: 000000007feef000 - 000000007ff00000 (ACPI data)
[    0.000000]  modified: 000000007ff00000 - 0000000080000000 (reserved)
[    0.000000]  modified: 00000000e0000000 - 00000000f0000000 (reserved)
[    0.000000]  modified: 00000000fec00000 - 00000000fec01000 (reserved)
[    0.000000]  modified: 00000000fed14000 - 00000000fed1a000 (reserved)
[    0.000000]  modified: 00000000fed1c000 - 00000000fed20000 (reserved)
[    0.000000]  modified: 00000000fee00000 - 00000000fee01000 (reserved)
[    0.000000]  modified: 00000000ffe00000 - 0000000100000000 (reserved)
[    0.000000] initial memory mapped : 0 - 00c00000
[    0.000000] init_memory_mapping: 0000000000000000-00000000377fe000
[    0.000000]  0000000000 - 0000400000 page 4k
[    0.000000]  0000400000 - 0037400000 page 2M
[    0.000000]  0037400000 - 00377fe000 page 4k
[    0.000000] kernel direct mapping tables up to 377fe000 @ 15000-1a000
[    0.000000] RAMDISK: 370b5000 - 37ff0000
[    0.000000] Allocated new RAMDISK: 009b1000 - 018ebc64
[    0.000000] Move RAMDISK from 00000000370b5000 - 0000000037fefc63 to 009b1000 - 018ebc63
[    0.000000] ACPI: RSDP 000fe020 00024 (v02 APPLE )
[    0.000000] ACPI: XSDT 7fefd120 00074 (v01 APPLE   Apple00 00000055      01000013)
[    0.000000] ACPI: FACP 7fefb000 000F4 (v03 APPLE   Apple00 00000055 Loki 0000005F)
[    0.000000] ACPI: DSDT 7fef0000 048C0 (v01 APPLE  MacBookP 00010001 INTL 20050309)
[    0.000000] ACPI: FACS 7febb000 00040
[    0.000000] ACPI: HPET 7fefa000 00038 (v01 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: APIC 7fef9000 00068 (v01 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: MCFG 7fef8000 0003C (v01 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: ASF! 7fef7000 0009C (v32 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: SBST 7fef6000 00030 (v01 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: ECDT 7fef5000 00053 (v01 APPLE   Apple00 00000001 Loki 0000005F)
[    0.000000] ACPI: SSDT 7feb6000 0064F (v01 APPLE   SataPri 00001000 INTL 20050309)
[    0.000000] ACPI: SSDT 7feb5000 0069C (v01 APPLE   SataSec 00001000 INTL 20050309)
[    0.000000] ACPI: SSDT 7feef000 004DC (v01 APPLE     CpuPm 00003000 INTL 20050309)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] 1144MB HIGHMEM available.
[    0.000000] 887MB LOWMEM available.
[    0.000000]   mapped low ram: 0 - 377fe000
[    0.000000]   low ram: 0 - 377fe000
[    0.000000] Zone PFN ranges:
[    0.000000]   DMA      0x00000001 -> 0x00001000
[    0.000000]   Normal   0x00001000 -> 0x000377fe
[    0.000000]   HighMem  0x000377fe -> 0x0007f0bd
[    0.000000] Movable zone start PFN for each node
[    0.000000] early_node_map[3] active PFN ranges
[    0.000000]     0: 0x00000001 -> 0x00000002
[    0.000000]     0: 0x00000010 -> 0x0000009f
[    0.000000]     0: 0x00000100 -> 0x0007f0bd
[    0.000000] On node 0 totalpages: 520269
[    0.000000] free_area_init_node: node 0, pgdat c0807e40, node_mem_map c18ed020
[    0.000000]   DMA zone: 32 pages used for memmap
[    0.000000]   DMA zone: 0 pages reserved
[    0.000000]   DMA zone: 3952 pages, LIFO batch:0
[    0.000000]   Normal zone: 1744 pages used for memmap
[    0.000000]   Normal zone: 221486 pages, LIFO batch:31
[    0.000000]   HighMem zone: 2290 pages used for memmap
[    0.000000]   HighMem zone: 290765 pages, LIFO batch:31
[    0.000000] Using APIC driver default
[    0.000000] ACPI: PM-Timer IO Port: 0x408
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x00] high edge lint[0x1])
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] high edge lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x01] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 1, version 32, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] ACPI: IRQ2 used by override.
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] SMP: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] nr_irqs_gsi: 40
[    0.000000] early_res array is doubled to 64 at [16000 - 167ff]
[    0.000000] PM: Registered nosave memory: 0000000000002000 - 0000000000010000
[    0.000000] PM: Registered nosave memory: 000000000009f000 - 00000000000a0000
[    0.000000] PM: Registered nosave memory: 00000000000a0000 - 00000000000ee000
[    0.000000] PM: Registered nosave memory: 00000000000ee000 - 0000000000100000
[    0.000000] Allocating PCI resources starting at 80000000 (gap: 80000000:60000000)
[    0.000000] Booting paravirtualized kernel on bare hardware
[    0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] PERCPU: Embedded 14 pages/cpu @c2c00000 s36480 r0 d20864 u2097152
[    0.000000] pcpu-alloc: s36480 r0 d20864 u2097152 alloc=1*4194304
[    0.000000] pcpu-alloc: [0] 0 1 
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 516203
[    0.000000] Kernel command line: BOOT_IMAGE=/boot/vmlinuz-2.6.35-24-lttng root=UUID=1b4d9240-fa4b-43c1-a188-e3fb72b88216 ro quiet splash
[    0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[    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] Enabling fast FPU save and restore... done.
[    0.000000] Enabling unmasked SIMD FPU exception support... done.
[    0.000000] Initializing CPU#0
[    0.000000] allocated 10407600 bytes of page_cgroup
[    0.000000] please try 'cgroup_disable=memory' option if you don't want memory cgroups
[    0.000000] Subtract (51 early reservations)
[    0.000000]   #1 [0000001000 - 0000002000]   EX TRAMPOLINE
[    0.000000]   #2 [0000100000 - 00009accdc]   TEXT DATA BSS
[    0.000000]   #3 [000009fc00 - 0000100000]   BIOS reserved
[    0.000000]   #4 [00009ad000 - 00009b0211]             BRK
[    0.000000]   #5 [0000010000 - 0000011000]      TRAMPOLINE
[    0.000000]   #6 [0000011000 - 0000015000]     ACPI WAKEUP
[    0.000000]   #7 [0000015000 - 0000016000]         PGTABLE
[    0.000000]   #8 [00009b1000 - 00018ec000]     NEW RAMDISK
[    0.000000]   #9 [00018ec000 - 00018ed000]         BOOTMEM
[    0.000000]   #10 [00018ed000 - 00028d5000]         BOOTMEM
[    0.000000]   #11 [00028d5000 - 00028d5004]         BOOTMEM
[    0.000000]   #12 [00028d5040 - 00028d5100]         BOOTMEM
[    0.000000]   #13 [00028d5100 - 00028d5154]         BOOTMEM
[    0.000000]   #14 [00028d5180 - 00028d8180]         BOOTMEM
[    0.000000]   #15 [00028d8180 - 00028d81ec]         BOOTMEM
[    0.000000]   #16 [00028d8200 - 00028de200]         BOOTMEM
[    0.000000]   #17 [00028de200 - 00028de225]         BOOTMEM
[    0.000000]   #18 [00028de240 - 00028de267]         BOOTMEM
[    0.000000]   #19 [00028de280 - 00028de478]         BOOTMEM
[    0.000000]   #20 [00028de480 - 00028de4c0]         BOOTMEM
[    0.000000]   #21 [00028de4c0 - 00028de500]         BOOTMEM
[    0.000000]   #22 [00028de500 - 00028de540]         BOOTMEM
[    0.000000]   #23 [00028de540 - 00028de580]         BOOTMEM
[    0.000000]   #24 [00028de580 - 00028de5c0]         BOOTMEM
[    0.000000]   #25 [00028de5c0 - 00028de600]         BOOTMEM
[    0.000000]   #26 [00028de600 - 00028de640]         BOOTMEM
[    0.000000]   #27 [00028de640 - 00028de680]         BOOTMEM
[    0.000000]   #28 [00028de680 - 00028de6c0]         BOOTMEM
[    0.000000]   #29 [00028de6c0 - 00028de700]         BOOTMEM
[    0.000000]   #30 [00028de700 - 00028de740]         BOOTMEM
[    0.000000]   #31 [00028de740 - 00028de780]         BOOTMEM
[    0.000000]   #32 [00028de780 - 00028de7c0]         BOOTMEM
[    0.000000]   #33 [00028de7c0 - 00028de800]         BOOTMEM
[    0.000000]   #34 [00028de800 - 00028de840]         BOOTMEM
[    0.000000]   #35 [00028de840 - 00028de850]         BOOTMEM
[    0.000000]   #36 [00028de880 - 00028de890]         BOOTMEM
[    0.000000]   #37 [00028de8c0 - 00028de928]         BOOTMEM
[    0.000000]   #38 [00028de940 - 00028de9a8]         BOOTMEM
[    0.000000]   #39 [0002c00000 - 0002c0e000]         BOOTMEM
[    0.000000]   #40 [0002e00000 - 0002e0e000]         BOOTMEM
[    0.000000]   #41 [00028e09c0 - 00028e09c4]         BOOTMEM
[    0.000000]   #42 [00028e0a00 - 00028e0a04]         BOOTMEM
[    0.000000]   #43 [00028e0a40 - 00028e0a48]         BOOTMEM
[    0.000000]   #44 [00028e0a80 - 00028e0a88]         BOOTMEM
[    0.000000]   #45 [00028e0ac0 - 00028e0b68]         BOOTMEM
[    0.000000]   #46 [00028e0b80 - 00028e0be8]         BOOTMEM
[    0.000000]   #47 [00028e0c00 - 00028e4c00]         BOOTMEM
[    0.000000]   #48 [00028e4c00 - 0002964c00]         BOOTMEM
[    0.000000]   #49 [0002964c00 - 00029a4c00]         BOOTMEM
[    0.000000]   #50 [0002e0e000 - 00037faeb0]         BOOTMEM
[    0.000000] Initializing HighMem for node 0 (000377fe:0007f0bd)
[    0.000000] Memory: 2029156k/2081524k available (4949k kernel code, 51920k reserved, 2362k data, 684k init, 1172220k highmem)
[    0.000000] virtual kernel memory layout:
[    0.000000]     fixmap  : 0xfff16000 - 0xfffff000   ( 932 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 : 0xc0825000 - 0xc08d0000   ( 684 kB)
[    0.000000]       .data : 0xc05d5792 - 0xc0824368   (2362 kB)
[    0.000000]       .text : 0xc0100000 - 0xc05d5792   (4949 kB)
[    0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[    0.000000] SLUB: Genslabs=13, HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] 	RCU dyntick-idle grace-period acceleration is enabled.
[    0.000000] 	RCU-based detection of stalled CPUs is disabled.
[    0.000000] 	Verbose stalled-CPUs detection is disabled.
[    0.000000] NR_IRQS:2304 nr_irqs:512
[    0.000000] Extended CMOS year: 2000
[    0.000000] Console: colour VGA+ 80x25
[    0.000000] console [tty0] enabled
[    0.000000] hpet clockevent registered
[    0.000000] Fast TSC calibration failed
[    0.000000] TSC: Unable to calibrate against PIT
[    0.000000] TSC: using HPET reference calibration
[    0.000000] Detected 1830.876 MHz processor.
[    0.016006] Calibrating delay loop (skipped), value calculated using timer frequency.. 3661.75 BogoMIPS (lpj=7323504)
[    0.016012] pid_max: default: 32768 minimum: 301
[    0.016033] Security Framework initialized
[    0.016053] AppArmor: AppArmor initialized
[    0.016055] Yama: becoming mindful.
[    0.016121] Mount-cache hash table entries: 512
[    0.016269] Initializing cgroup subsys ns
[    0.016275] Initializing cgroup subsys cpuacct
[    0.016280] Initializing cgroup subsys memory
[    0.016291] Initializing cgroup subsys devices
[    0.016294] Initializing cgroup subsys freezer
[    0.016297] Initializing cgroup subsys net_cls
[    0.016328] CPU: Physical Processor ID: 0
[    0.016330] CPU: Processor Core ID: 0
[    0.016334] mce: CPU supports 6 MCE banks
[    0.016345] CPU0: Thermal monitoring enabled (TM2)
[    0.016349] using mwait in idle threads.
[    0.016357] Performance Events: Core events, core PMU driver.
[    0.016368] ... version:                1
[    0.016370] ... bit width:              40
[    0.016372] ... generic registers:      2
[    0.016375] ... value mask:             000000ffffffffff
[    0.016377] ... max period:             000000007fffffff
[    0.016380] ... fixed-purpose events:   0
[    0.016382] ... event mask:             0000000000000003
[    0.020187] ACPI: Core revision 20100428
[    0.036009] ftrace: converting mcount calls to 0f 1f 44 00 00
[    0.036016] ftrace: allocating 21862 entries in 43 pages
[    0.040058] Enabling APIC mode:  Flat.  Using 1 I/O APICs
[    0.040439] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    0.081442] CPU0: Genuine Intel(R) CPU           T2400  @ 1.83GHz stepping 08
[    0.084000] Booting Node   0, Processors  #1 Ok.
[    0.020000] Initializing CPU#1
[    0.168061] checking TSC synchronization [CPU#0 -> CPU#1]: passed.
[    0.168308] Brought up 2 CPUs
[    0.168312] Total of 2 processors activated (7323.53 BogoMIPS).
[    0.168880] devtmpfs: initialized
[    0.169216] regulator: core version 0.5
[    0.169251] Time: 19:02:17  Date: 04/17/11
[    0.169298] NET: Registered protocol family 16
[    0.169328] Trying to unpack rootfs image as initramfs...
[    0.169451] EISA bus registered
[    0.169462] ACPI: bus type pci registered
[    0.169548] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[    0.169552] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in E820
[    0.169555] PCI: Using MMCONFIG for extended config space
[    0.169558] PCI: Using configuration type 1 for base access
[    0.176160] bio: create slab <bio-0> at 0
[    0.177540] ACPI: EC: EC description table is found, configuring boot EC
[    0.180598] ACPI: BIOS _OSI(Linux) query ignored
[    0.184325] ACPI: SSDT 7feb3c90 0022A (v01 APPLE   Cpu0Ist 00003000 INTL 20050309)
[    0.184736] ACPI: Dynamic OEM Table Load:
[    0.184741] ACPI: SSDT (null) 0022A (v01 APPLE   Cpu0Ist 00003000 INTL 20050309)
[    0.184992] ACPI: SSDT 7feb2c10 002C3 (v01 APPLE   Cpu0Cst 00003001 INTL 20050309)
[    0.185382] ACPI: Dynamic OEM Table Load:
[    0.185386] ACPI: SSDT (null) 002C3 (v01 APPLE   Cpu0Cst 00003001 INTL 20050309)
[    0.185828] ACPI: SSDT 7feb3f10 00087 (v01 APPLE   Cpu1Ist 00003000 INTL 20050309)
[    0.186228] ACPI: Dynamic OEM Table Load:
[    0.186232] ACPI: SSDT (null) 00087 (v01 APPLE   Cpu1Ist 00003000 INTL 20050309)
[    0.186401] ACPI: SSDT 7feb2f10 00085 (v01 APPLE   Cpu1Cst 00003000 INTL 20050309)
[    0.186793] ACPI: Dynamic OEM Table Load:
[    0.186797] ACPI: SSDT (null) 00085 (v01 APPLE   Cpu1Cst 00003000 INTL 20050309)
[    0.186866] ACPI: Interpreter enabled
[    0.186871] ACPI: (supports S0 S3 S4 S5)
[    0.186897] ACPI: Using IOAPIC for interrupt routing
[    0.198408] ACPI: EC: GPE = 0x17, I/O: command/status = 0x66, data = 0x62
[    0.198408] ACPI: No dock devices found.
[    0.198408] PCI: Ignoring host bridge windows from ACPI; if necessary, use "pci=use_crs" and report a bug
[    0.198408] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    0.200953] pci_root PNP0A08:00: host bridge window [io  0x0000-0x0cf7] (ignored)
[    0.200957] pci_root PNP0A08:00: host bridge window [io  0x0d00-0xffff] (ignored)
[    0.200960] pci_root PNP0A08:00: host bridge window [mem 0x000a0000-0x000bffff] (ignored)
[    0.200965] pci_root PNP0A08:00: host bridge window [mem 0x80000000-0xfebfffff] (ignored)
[    0.201061] pci 0000:00:01.0: PME# supported from D0 D3hot D3cold
[    0.201065] pci 0000:00:01.0: PME# disabled
[    0.201097] pci 0000:00:07.0: reg 10: [mem 0x90404000-0x90404fff]
[    0.201192] pci 0000:00:1b.0: reg 10: [mem 0x90400000-0x90403fff 64bit]
[    0.201255] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[    0.201261] pci 0000:00:1b.0: PME# disabled
[    0.201363] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[    0.201369] pci 0000:00:1c.0: PME# disabled
[    0.201475] pci 0000:00:1c.1: PME# supported from D0 D3hot D3cold
[    0.201481] pci 0000:00:1c.1: PME# disabled
[    0.201585] pci 0000:00:1c.2: PME# supported from D0 D3hot D3cold
[    0.201591] pci 0000:00:1c.2: PME# disabled
[    0.201660] pci 0000:00:1d.0: reg 20: [io  0x40a0-0x40bf]
[    0.201726] pci 0000:00:1d.1: reg 20: [io  0x4080-0x409f]
[    0.201792] pci 0000:00:1d.2: reg 20: [io  0x4060-0x407f]
[    0.201858] pci 0000:00:1d.3: reg 20: [io  0x4040-0x405f]
[    0.201922] pci 0000:00:1d.7: reg 10: [mem 0x90405400-0x904057ff]
[    0.201987] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[    0.201994] pci 0000:00:1d.7: PME# disabled
[    0.202163] pci 0000:00:1f.0: quirk: [io  0x0400-0x047f] claimed by ICH6 ACPI/GPIO/TCO
[    0.202168] pci 0000:00:1f.0: quirk: [io  0x0500-0x053f] claimed by ICH6 GPIO
[    0.202174] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 1 PIO at 0680 (mask 000f)
[    0.202179] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 2 PIO at 1640 (mask 000f)
[    0.202185] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 3 PIO at 4700 (mask 0003)
[    0.202190] pci 0000:00:1f.0: ICH7 LPC Generic IO decode 4 PIO at 0300 (mask 001f)
[    0.202245] pci 0000:00:1f.1: reg 10: [io  0x40e8-0x40ef]
[    0.202253] pci 0000:00:1f.1: reg 14: [io  0x40fc-0x40ff]
[    0.202262] pci 0000:00:1f.1: reg 18: [io  0x40e0-0x40e7]
[    0.202271] pci 0000:00:1f.1: reg 1c: [io  0x40f8-0x40fb]
[    0.202279] pci 0000:00:1f.1: reg 20: [io  0x40c0-0x40cf]
[    0.202334] pci 0000:00:1f.2: reg 10: [io  0x40d8-0x40df]
[    0.202343] pci 0000:00:1f.2: reg 14: [io  0x40f4-0x40f7]
[    0.202352] pci 0000:00:1f.2: reg 18: [io  0x40d0-0x40d7]
[    0.202360] pci 0000:00:1f.2: reg 1c: [io  0x40f0-0x40f3]
[    0.202369] pci 0000:00:1f.2: reg 20: [io  0x4020-0x402f]
[    0.202378] pci 0000:00:1f.2: reg 24: [mem 0x90405000-0x904053ff]
[    0.202410] pci 0000:00:1f.2: PME# supported from D3hot
[    0.202415] pci 0000:00:1f.2: PME# disabled
[    0.202478] pci 0000:00:1f.3: reg 20: [io  0xefa0-0xefbf]
[    0.202587] pci 0000:01:00.0: reg 10: [mem 0x80000000-0x87ffffff pref]
[    0.202598] pci 0000:01:00.0: reg 14: [io  0x3000-0x30ff]
[    0.202610] pci 0000:01:00.0: reg 18: [mem 0x90300000-0x9030ffff]
[    0.202642] pci 0000:01:00.0: reg 30: [mem 0xfffe0000-0xffffffff pref]
[    0.202689] pci 0000:01:00.0: supports D1 D2
[    0.202715] pci 0000:01:00.0: disabling ASPM on pre-1.1 PCIe device.  You can enable it with 'pcie_aspm=force'
[    0.202729] pci 0000:00:01.0: PCI bridge to [bus 01-01]
[    0.202734] pci 0000:00:01.0:   bridge window [io  0x3000-0x3fff]
[    0.202738] pci 0000:00:01.0:   bridge window [mem 0x90300000-0x903fffff]
[    0.202744] pci 0000:00:01.0:   bridge window [mem 0x80000000-0x87ffffff 64bit pref]
[    0.202860] pci 0000:02:00.0: reg 10: [mem 0x90200000-0x90203fff 64bit]
[    0.202872] pci 0000:02:00.0: reg 18: [io  0x2000-0x20ff]
[    0.202909] pci 0000:02:00.0: reg 30: [mem 0xfffe0000-0xffffffff pref]
[    0.202965] pci 0000:02:00.0: supports D1 D2
[    0.202968] pci 0000:02:00.0: PME# supported from D0 D1 D2 D3hot D3cold
[    0.202975] pci 0000:02:00.0: PME# disabled
[    0.202994] pci 0000:02:00.0: disabling ASPM on pre-1.1 PCIe device.  You can enable it with 'pcie_aspm=force'
[    0.203008] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
[    0.203014] pci 0000:00:1c.0:   bridge window [io  0x2000-0x2fff]
[    0.203020] pci 0000:00:1c.0:   bridge window [mem 0x90200000-0x902fffff]
[    0.203029] pci 0000:00:1c.0:   bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[    0.203140] pci 0000:03:00.0: reg 10: [mem 0x90100000-0x9010ffff 64bit]
[    0.203266] pci 0000:03:00.0: disabling ASPM on pre-1.1 PCIe device.  You can enable it with 'pcie_aspm=force'
[    0.203279] pci 0000:00:1c.1: PCI bridge to [bus 03-03]
[    0.203285] pci 0000:00:1c.1:   bridge window [io  0xf000-0x0000] (disabled)
[    0.203292] pci 0000:00:1c.1:   bridge window [mem 0x90100000-0x901fffff]
[    0.203301] pci 0000:00:1c.1:   bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[    0.203365] pci 0000:00:1c.2: PCI bridge to [bus 04-0b]
[    0.203371] pci 0000:00:1c.2:   bridge window [io  0x1000-0x1fff]
[    0.203377] pci 0000:00:1c.2:   bridge window [mem 0x8c100000-0x900fffff]
[    0.203386] pci 0000:00:1c.2:   bridge window [mem 0x88000000-0x8bffffff 64bit pref]
[    0.203458] pci 0000:0c:03.0: reg 10: [mem 0x8c000000-0x8c000fff]
[    0.203521] pci 0000:0c:03.0: supports D1 D2
[    0.203524] pci 0000:0c:03.0: PME# supported from D0 D1 D2 D3hot
[    0.203530] pci 0000:0c:03.0: PME# disabled
[    0.203591] pci 0000:00:1e.0: PCI bridge to [bus 0c-0c] (subtractive decode)
[    0.203597] pci 0000:00:1e.0:   bridge window [io  0xf000-0x0000] (disabled)
[    0.203604] pci 0000:00:1e.0:   bridge window [mem 0x8c000000-0x8c0fffff]
[    0.203613] pci 0000:00:1e.0:   bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[    0.203616] pci 0000:00:1e.0:   bridge window [io  0x0000-0xffff] (subtractive decode)
[    0.203620] pci 0000:00:1e.0:   bridge window [mem 0x00000000-0xffffffff] (subtractive decode)
[    0.203653] pci_bus 0000:00: on NUMA node 0
[    0.203659] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[    0.203909] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PEGP._PRT]
[    0.204017] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP01._PRT]
[    0.204114] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP02._PRT]
[    0.204209] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.RP03._PRT]
[    0.204330] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.PCIB._PRT]
[    0.213005] ACPI: PCI Interrupt Link [LNKA] (IRQs 1 3 4 5 6 7 10 12 14 15) *11
[    0.213122] ACPI: PCI Interrupt Link [LNKB] (IRQs 1 3 4 5 6 7 *11 12 14 15)
[    0.213234] ACPI: PCI Interrupt Link [LNKC] (IRQs 1 3 4 5 6 7 10 12 14 15) *11
[    0.213348] ACPI: PCI Interrupt Link [LNKD] (IRQs 1 3 4 5 6 7 *11 12 14 15)
[    0.213458] ACPI: PCI Interrupt Link [LNKE] (IRQs 1 3 4 5 6 7 10 12 14 15) *0, disabled.
[    0.213571] ACPI: PCI Interrupt Link [LNKF] (IRQs 1 3 4 5 6 7 11 12 14 15) *0, disabled.
[    0.213685] ACPI: PCI Interrupt Link [LNKG] (IRQs 1 3 4 5 6 7 10 12 14 15) *11
[    0.213799] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 6 7 *11 12 14 15)
[    0.213853] HEST: Table is not found!
[    0.213968] vgaarb: device added: PCI:0000:01:00.0,decodes=io+mem,owns=io+mem,locks=none
[    0.213974] vgaarb: loaded
[    0.214174] SCSI subsystem initialized
[    0.216280] libata version 3.00 loaded.
[    0.216280] usbcore: registered new interface driver usbfs
[    0.216280] usbcore: registered new interface driver hub
[    0.216280] usbcore: registered new device driver usb
[    0.216301] ACPI: WMI: Mapper loaded
[    0.216304] PCI: Using ACPI for IRQ routing
[    0.216307] PCI: pci_cache_line_size set to 64 bytes
[    0.216419] reserve RAM buffer: 0000000000002000 - 000000000000ffff 
[    0.216423] reserve RAM buffer: 000000000009fc00 - 000000000009ffff 
[    0.216426] reserve RAM buffer: 000000007f0bd000 - 000000007fffffff 
[    0.216547] NetLabel: Initializing
[    0.216549] NetLabel:  domain hash size = 128
[    0.216551] NetLabel:  protocols = UNLABELED CIPSOv4
[    0.216568] NetLabel:  unlabeled traffic allowed by default
[    0.216606] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[    0.216614] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[    0.216621] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
[    0.224033] Switching to clocksource tsc
[    0.236816] AppArmor: AppArmor Filesystem Enabled
[    0.236836] pnp: PnP ACPI init
[    0.236864] ACPI: bus type pnp registered
[    0.238802] pnp 00:08: disabling [io  0x1640-0x164f] because it overlaps 0000:00:1c.2 BAR 13 [io  0x1000-0x1fff]
[    0.245413] pnp: PnP ACPI: found 10 devices
[    0.245417] ACPI: ACPI bus type pnp unregistered
[    0.245422] PnPBIOS: Disabled by ACPI PNP
[    0.245442] system 00:01: [mem 0xe0000000-0xefffffff] has been reserved
[    0.245446] system 00:01: [mem 0xfed14000-0xfed17fff] has been reserved
[    0.245450] system 00:01: [mem 0xfed18000-0xfed18fff] has been reserved
[    0.245454] system 00:01: [mem 0xfed19000-0xfed19fff] has been reserved
[    0.245458] system 00:01: [mem 0xfed1c000-0xfed1ffff] has been reserved
[    0.245462] system 00:01: [mem 0xfed20000-0xfed8ffff] has been reserved
[    0.245471] system 00:06: [mem 0xfed00000-0xfed003ff] has been reserved
[    0.245590] system 00:08: [io  0x0680-0x06ef] has been reserved
[    0.245617] system 00:08: [io  0x0800-0x080f] has been reserved
[    0.245642] system 00:08: [io  0x0810-0x0817] has been reserved
[    0.245645] system 00:08: [io  0x0400-0x047f] has been reserved
[    0.245649] system 00:08: [io  0x0500-0x053f] has been reserved
[    0.282082] pci 0000:01:00.0: no compatible bridge window for [mem 0xfffe0000-0xffffffff pref]
[    0.282090] pci 0000:02:00.0: no compatible bridge window for [mem 0xfffe0000-0xffffffff pref]
[    0.282155] pci 0000:00:1c.0: BAR 15: assigned [mem 0x90500000-0x906fffff pref]
[    0.282159] pci 0000:00:1c.1: BAR 15: assigned [mem 0x90700000-0x908fffff 64bit pref]
[    0.282164] pci 0000:00:1c.1: BAR 13: assigned [io  0x5000-0x5fff]
[    0.282169] pci 0000:01:00.0: BAR 6: assigned [mem 0x90320000-0x9033ffff pref]
[    0.282173] pci 0000:00:01.0: PCI bridge to [bus 01-01]
[    0.282177] pci 0000:00:01.0:   bridge window [io  0x3000-0x3fff]
[    0.282182] pci 0000:00:01.0:   bridge window [mem 0x90300000-0x903fffff]
[    0.282187] pci 0000:00:01.0:   bridge window [mem 0x80000000-0x87ffffff 64bit pref]
[    0.282193] pci 0000:02:00.0: BAR 6: assigned [mem 0x90500000-0x9051ffff pref]
[    0.282196] pci 0000:00:1c.0: PCI bridge to [bus 02-02]
[    0.282201] pci 0000:00:1c.0:   bridge window [io  0x2000-0x2fff]
[    0.282208] pci 0000:00:1c.0:   bridge window [mem 0x90200000-0x902fffff]
[    0.282214] pci 0000:00:1c.0:   bridge window [mem 0x90500000-0x906fffff pref]
[    0.282223] pci 0000:00:1c.1: PCI bridge to [bus 03-03]
[    0.282227] pci 0000:00:1c.1:   bridge window [io  0x5000-0x5fff]
[    0.282234] pci 0000:00:1c.1:   bridge window [mem 0x90100000-0x901fffff]
[    0.282240] pci 0000:00:1c.1:   bridge window [mem 0x90700000-0x908fffff 64bit pref]
[    0.282249] pci 0000:00:1c.2: PCI bridge to [bus 04-0b]
[    0.282254] pci 0000:00:1c.2:   bridge window [io  0x1000-0x1fff]
[    0.282261] pci 0000:00:1c.2:   bridge window [mem 0x8c100000-0x900fffff]
[    0.282267] pci 0000:00:1c.2:   bridge window [mem 0x88000000-0x8bffffff 64bit pref]
[    0.282276] pci 0000:00:1e.0: PCI bridge to [bus 0c-0c]
[    0.282279] pci 0000:00:1e.0:   bridge window [io  disabled]
[    0.282286] pci 0000:00:1e.0:   bridge window [mem 0x8c000000-0x8c0fffff]
[    0.282292] pci 0000:00:1e.0:   bridge window [mem pref disabled]
[    0.282316]   alloc irq_desc for 16 on node -1
[    0.282318]   alloc kstat_irqs on node -1
[    0.282328] pci 0000:00:01.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    0.282333] pci 0000:00:01.0: setting latency timer to 64
[    0.282344]   alloc irq_desc for 17 on node -1
[    0.282347]   alloc kstat_irqs on node -1
[    0.282351] pci 0000:00:1c.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[    0.282357] pci 0000:00:1c.0: setting latency timer to 64
[    0.282369] pci 0000:00:1c.1: PCI INT B -> GSI 16 (level, low) -> IRQ 16
[    0.282375] pci 0000:00:1c.1: setting latency timer to 64
[    0.282386]   alloc irq_desc for 18 on node -1
[    0.282388]   alloc kstat_irqs on node -1
[    0.282393] pci 0000:00:1c.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[    0.282398] pci 0000:00:1c.2: setting latency timer to 64
[    0.282502] pci 0000:00:1e.0: power state changed by ACPI to D0
[    0.282562] pci 0000:00:1e.0: power state changed by ACPI to D0
[    0.282570] pci 0000:00:1e.0: setting latency timer to 64
[    0.282575] pci_bus 0000:00: resource 0 [io  0x0000-0xffff]
[    0.282579] pci_bus 0000:00: resource 1 [mem 0x00000000-0xffffffff]
[    0.282583] pci_bus 0000:01: resource 0 [io  0x3000-0x3fff]
[    0.282586] pci_bus 0000:01: resource 1 [mem 0x90300000-0x903fffff]
[    0.282589] pci_bus 0000:01: resource 2 [mem 0x80000000-0x87ffffff 64bit pref]
[    0.282593] pci_bus 0000:02: resource 0 [io  0x2000-0x2fff]
[    0.282596] pci_bus 0000:02: resource 1 [mem 0x90200000-0x902fffff]
[    0.282599] pci_bus 0000:02: resource 2 [mem 0x90500000-0x906fffff pref]
[    0.282602] pci_bus 0000:03: resource 0 [io  0x5000-0x5fff]
[    0.282605] pci_bus 0000:03: resource 1 [mem 0x90100000-0x901fffff]
[    0.282609] pci_bus 0000:03: resource 2 [mem 0x90700000-0x908fffff 64bit pref]
[    0.282612] pci_bus 0000:04: resource 0 [io  0x1000-0x1fff]
[    0.282615] pci_bus 0000:04: resource 1 [mem 0x8c100000-0x900fffff]
[    0.282619] pci_bus 0000:04: resource 2 [mem 0x88000000-0x8bffffff 64bit pref]
[    0.282622] pci_bus 0000:0c: resource 1 [mem 0x8c000000-0x8c0fffff]
[    0.282625] pci_bus 0000:0c: resource 4 [io  0x0000-0xffff]
[    0.282628] pci_bus 0000:0c: resource 5 [mem 0x00000000-0xffffffff]
[    0.282678] NET: Registered protocol family 2
[    0.282767] IP route cache hash table entries: 32768 (order: 5, 131072 bytes)
[    0.283061] TCP established hash table entries: 131072 (order: 8, 1048576 bytes)
[    0.283778] TCP bind hash table entries: 65536 (order: 7, 524288 bytes)
[    0.284128] TCP: Hash tables configured (established 131072 bind 65536)
[    0.284132] TCP reno registered
[    0.284139] UDP hash table entries: 512 (order: 2, 16384 bytes)
[    0.284154] UDP-Lite hash table entries: 512 (order: 2, 16384 bytes)
[    0.284289] NET: Registered protocol family 1
[    0.284542] pci 0000:01:00.0: Boot video device
[    0.284556] PCI: CLS mismatch (256 != 64), using 64 bytes
[    0.284804] cpufreq-nforce2: No nForce2 chipset.
[    0.284846] Scanning for low memory corruption every 60 seconds
[    0.285030] audit: initializing netlink socket (disabled)
[    0.285041] type=2000 audit(1303066936.280:1): initialized
[    0.298245] highmem bounce pool size: 64 pages
[    0.298252] HugeTLB registered 4 MB page size, pre-allocated 0 pages
[    0.300496] VFS: Disk quotas dquot_6.5.2
[    0.300574] Dquot-cache hash table entries: 1024 (order 0, 4096 bytes)
[    0.301302] fuse init (API version 7.14)
[    0.301413] msgmni has been set to 1673
[    0.302762] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[    0.302767] io scheduler noop registered
[    0.302769] io scheduler deadline registered
[    0.302785] io scheduler cfq registered (default)
[    0.302924] pcieport 0000:00:01.0: setting latency timer to 64
[    0.302956]   alloc irq_desc for 40 on node -1
[    0.302959]   alloc kstat_irqs on node -1
[    0.302969] pcieport 0000:00:01.0: irq 40 for MSI/MSI-X
[    0.303044] pcieport 0000:00:1c.0: setting latency timer to 64
[    0.303092]   alloc irq_desc for 41 on node -1
[    0.303094]   alloc kstat_irqs on node -1
[    0.303104] pcieport 0000:00:1c.0: irq 41 for MSI/MSI-X
[    0.303216] pcieport 0000:00:1c.1: setting latency timer to 64
[    0.303264]   alloc irq_desc for 42 on node -1
[    0.303266]   alloc kstat_irqs on node -1
[    0.303276] pcieport 0000:00:1c.1: irq 42 for MSI/MSI-X
[    0.303386] pcieport 0000:00:1c.2: setting latency timer to 64
[    0.303435]   alloc irq_desc for 43 on node -1
[    0.303437]   alloc kstat_irqs on node -1
[    0.303447] pcieport 0000:00:1c.2: irq 43 for MSI/MSI-X
[    0.303593] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[    0.307627] pciehp 0000:00:1c.0:pcie04: HPC vendor_id 8086 device_id 27d0 ss_vid 0 ss_did 0
[    0.307676] pciehp 0000:00:1c.0:pcie04: service driver pciehp loaded
[    0.307697] pciehp 0000:00:1c.1:pcie04: HPC vendor_id 8086 device_id 27d2 ss_vid 0 ss_did 0
[    0.307733] pciehp 0000:00:1c.1:pcie04: service driver pciehp loaded
[    0.307751] pciehp 0000:00:1c.2:pcie04: HPC vendor_id 8086 device_id 27d4 ss_vid 0 ss_did 0
[    0.307787] pciehp 0000:00:1c.2:pcie04: service driver pciehp loaded
[    0.307797] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[    0.307914] efifb: dmi detected MacBookPro1,1 - framebuffer at 80010000 (1440x900, stride 5888)
[    0.307923] intel_idle: MWAIT substates: 0x22220
[    0.307925] intel_idle: does not run on family 6 model 14
[    0.308029] ACPI: AC Adapter [ADP1] (on-line)
[    0.308124] input: Lid Switch as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0D:00/input/input0
[    0.308165] ACPI: Lid Switch [LID0]
[    0.308224] input: Power Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0C:00/input/input1
[    0.308230] ACPI: Power Button [PWRB]
[    0.308283] input: Sleep Button as /devices/LNXSYSTM:00/LNXSYBUS:00/PNP0C0E:00/input/input2
[    0.308288] ACPI: Sleep Button [SLPB]
[    0.308359] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[    0.308364] ACPI: Power Button [PWRF]
[    0.308619] ACPI: acpi_idle registered with cpuidle
[    0.308840] Monitor-Mwait will be used to enter C-1 state
[    0.308878] Monitor-Mwait will be used to enter C-2 state
[    0.308909] Monitor-Mwait will be used to enter C-3 state
[    0.308917] Marking TSC unstable due to TSC halts in idle
[    0.309050] Switching to clocksource hpet
[    0.321264] ERST: Table is not found!
[    0.321567] Serial: 8250/16550 driver, 4 ports, IRQ sharing enabled
[    0.323372] brd: module loaded
[    0.324035] loop: module loaded
[    0.324257] ata_piix 0000:00:1f.1: version 2.13
[    0.324312] ata_piix 0000:00:1f.1: power state changed by ACPI to D0
[    0.324351] ata_piix 0000:00:1f.1: power state changed by ACPI to D0
[    0.324362] ata_piix 0000:00:1f.1: PCI INT A -> GSI 18 (level, low) -> IRQ 18
[    0.324413] ata_piix 0000:00:1f.1: setting latency timer to 64
[    0.325162] scsi0 : ata_piix
[    0.325254] isapnp: Scanning for PnP cards...
[    0.330823] scsi1 : ata_piix
[    0.380437] ata1: PATA max UDMA/100 cmd 0x1f0 ctl 0x3f6 bmdma 0x40c0 irq 14
[    0.380442] ata2: PATA max UDMA/100 cmd 0x170 ctl 0x376 bmdma 0x40c8 irq 15
[    0.380483] ata_piix 0000:00:1f.2: enabling device (0005 -> 0007)
[    0.380493]   alloc irq_desc for 19 on node -1
[    0.380496]   alloc kstat_irqs on node -1
[    0.380504] ata_piix 0000:00:1f.2: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[    0.380511] ata_piix 0000:00:1f.2: MAP [ P0 P2 -- -- ]
[    0.521930] ACPI: Battery Slot [BAT0] (battery present)
[    0.536111] ata_piix 0000:00:1f.2: setting latency timer to 64
[    0.549811] scsi2 : ata_piix
[    0.556225] scsi3 : ata_piix
[    0.557397] ata3: SATA max UDMA/133 cmd 0x40d8 ctl 0x40f4 bmdma 0x4020 irq 19
[    0.557401] ata4: SATA max UDMA/133 cmd 0x40d0 ctl 0x40f0 bmdma 0x4028 irq 19
[    0.557825] Fixed MDIO Bus: probed
[    0.557867] PPP generic driver version 2.4.2
[    0.557943] tun: Universal TUN/TAP device driver, 1.6
[    0.557945] tun: (C) 1999-2004 Max Krasnyansky <maxk at qualcomm.com>
[    0.558051] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[    0.558080]   alloc irq_desc for 23 on node -1
[    0.558082]   alloc kstat_irqs on node -1
[    0.558091] ehci_hcd 0000:00:1d.7: PCI INT A -> GSI 23 (level, low) -> IRQ 23
[    0.558112] ehci_hcd 0000:00:1d.7: setting latency timer to 64
[    0.558117] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[    0.558165] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[    0.558190] ehci_hcd 0000:00:1d.7: using broken periodic workaround
[    0.558203] ehci_hcd 0000:00:1d.7: debug port 1
[    0.562097] ehci_hcd 0000:00:1d.7: cache line size of 64 is not supported
[    0.562120] ehci_hcd 0000:00:1d.7: irq 23, io mem 0x90405400
[    0.584098] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00
[    0.584300] hub 1-0:1.0: USB hub found
[    0.584307] hub 1-0:1.0: 8 ports detected
[    0.584418] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[    0.584439] uhci_hcd: USB Universal Host Controller Interface driver
[    0.584490] uhci_hcd 0000:00:1d.0: PCI INT A -> GSI 23 (level, low) -> IRQ 23
[    0.584501] uhci_hcd 0000:00:1d.0: setting latency timer to 64
[    0.584506] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[    0.584557] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[    0.584591] uhci_hcd 0000:00:1d.0: irq 23, io base 0x000040a0
[    0.584731] hub 2-0:1.0: USB hub found
[    0.584737] hub 2-0:1.0: 2 ports detected
[    0.584814] uhci_hcd 0000:00:1d.1: PCI INT B -> GSI 19 (level, low) -> IRQ 19
[    0.584821] uhci_hcd 0000:00:1d.1: setting latency timer to 64
[    0.584825] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[    0.584865] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[    0.584897] uhci_hcd 0000:00:1d.1: irq 19, io base 0x00004080
[    0.585045] hub 3-0:1.0: USB hub found
[    0.585050] hub 3-0:1.0: 2 ports detected
[    0.585118] uhci_hcd 0000:00:1d.2: PCI INT C -> GSI 18 (level, low) -> IRQ 18
[    0.585126] uhci_hcd 0000:00:1d.2: setting latency timer to 64
[    0.585130] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[    0.585172] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[    0.585217] uhci_hcd 0000:00:1d.2: irq 18, io base 0x00004060
[    0.585360] hub 4-0:1.0: USB hub found
[    0.585367] hub 4-0:1.0: 2 ports detected
[    0.585437] uhci_hcd 0000:00:1d.3: PCI INT D -> GSI 16 (level, low) -> IRQ 16
[    0.585444] uhci_hcd 0000:00:1d.3: setting latency timer to 64
[    0.585448] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[    0.585495] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[    0.585535] uhci_hcd 0000:00:1d.3: irq 16, io base 0x00004040
[    0.585672] hub 5-0:1.0: USB hub found
[    0.585676] hub 5-0:1.0: 2 ports detected
[    0.585822] PNP: No PS/2 controller found. Probing ports directly.
[    0.586713] i8042.c: No controller found.
[    0.586778] mice: PS/2 mouse device common for all mice
[    0.586981] rtc_cmos 00:09: RTC can wake from S4
[    0.587029] rtc_cmos 00:09: rtc core: registered rtc_cmos as rtc0
[    0.587065] rtc0: alarms up to one month, y3k, 242 bytes nvram, hpet irqs
[    0.587198] device-mapper: uevent: version 1.0.3
[    0.596480] device-mapper: ioctl: 4.17.0-ioctl (2010-03-05) initialised: dm-devel at redhat.com
[    0.680579] isapnp: No Plug & Play device found
[    0.692242] Freeing initrd memory: 15596k freed
[    0.692266] ata1.00: ATAPI: MATSHITADVD-R   UJ-857, HAE4, max UDMA/66
[    0.701406] device-mapper: multipath: version 1.1.1 loaded
[    0.701416] device-mapper: multipath round-robin: version 1.0.0 loaded
[    0.701690] EISA: Probing bus 0 at eisa.0
[    0.701701] Cannot allocate resource for EISA slot 1
[    0.701704] Cannot allocate resource for EISA slot 2
[    0.701706] Cannot allocate resource for EISA slot 3
[    0.701709] Cannot allocate resource for EISA slot 4
[    0.701713] Cannot allocate resource for EISA slot 5
[    0.701728] EISA: Detected 0 cards.
[    0.702001] cpuidle: using governor ladder
[    0.702135] cpuidle: using governor menu
[    0.702523] TCP cubic registered
[    0.702690] NET: Registered protocol family 10
[    0.703143] lo: Disabled Privacy Extensions
[    0.703430] NET: Registered protocol family 17
[    0.706693] Using IPI No-Shortcut mode
[    0.706819] PM: Resume from disk failed.
[    0.706834] registered taskstats version 1
[    0.707180]   Magic number: 7:242:41
[    0.707198] bdi 7:6: hash matches
[    0.707314] rtc_cmos 00:09: setting system clock to 2011-04-17 19:02:17 UTC (1303066937)
[    0.707318] BIOS EDD facility v0.16 2004-Jun-25, 0 devices found
[    0.707320] EDD information not available.
[    0.709565] ata1.00: configured for UDMA/66
[    0.711795] scsi 0:0:0:0: CD-ROM            MATSHITA DVD-R   UJ-857   HAE4 PQ: 0 ANSI: 5
[    0.716360] sr0: scsi3-mmc drive: 24x/24x writer cd/rw xa/form2 cdda tray
[    0.716366] Uniform CD-ROM driver Revision: 3.20
[    0.716560] sr 0:0:0:0: Attached scsi CD-ROM sr0
[    0.716680] sr 0:0:0:0: Attached scsi generic sg0 type 5
[    0.764983] ata3.01: ATA-7: ST910021AS, 3.06, max UDMA/133
[    0.764990] ata3.01: 195371568 sectors, multi 16: LBA48 NCQ (depth 0/32)
[    0.781616] ata3.01: configured for UDMA/133
[    0.781739] scsi 2:0:1:0: Direct-Access     ATA      ST910021AS       3.06 PQ: 0 ANSI: 5
[    0.781882] sd 2:0:1:0: [sda] 195371568 512-byte logical blocks: (100 GB/93.1 GiB)
[    0.781897] sd 2:0:1:0: Attached scsi generic sg1 type 0
[    0.781979] sd 2:0:1:0: [sda] Write Protect is off
[    0.781983] sd 2:0:1:0: [sda] Mode Sense: 00 3a 00 00
[    0.782022] sd 2:0:1:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[    0.782297]  sda: sda1 sda2 sda3 sda4 sda5
[    0.882427] sd 2:0:1:0: [sda] Attached SCSI disk
[    0.882456] Freeing unused kernel memory: 684k freed
[    0.882913] Write protecting the kernel text: 4952k
[    0.882979] Write protecting the kernel read-only data: 1988k
[    0.903289] udev[93]: starting version 163
[    1.004428] usb 1-3: new high speed USB device using ehci_hcd and address 3
[    1.019301] sky2: driver version 1.28
[    1.019354] sky2 0000:02:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    1.019370] sky2 0000:02:00.0: setting latency timer to 64
[    1.019405] sky2 0000:02:00.0: Yukon-2 EC chip revision 2
[    1.019511]   alloc irq_desc for 44 on node -1
[    1.019514]   alloc kstat_irqs on node -1
[    1.019532] sky2 0000:02:00.0: irq 44 for MSI/MSI-X
[    1.020744] sky2 0000:02:00.0: eth0: addr 00:16:cb:86:4a:ac
[    1.040937] Linux agpgart interface v0.103
[    1.105725] [drm] Initialized drm 1.1.0 20060810
[    1.109835] firewire_ohci 0000:0c:03.0: PCI INT A -> GSI 19 (level, low) -> IRQ 19
[    1.138002] hub 1-3:1.0: USB hub found
[    1.138312] hub 1-3:1.0: 4 ports detected
[    1.162854] [drm] radeon defaulting to kernel modesetting.
[    1.162858] [drm] radeon kernel modesetting enabled.
[    1.162927] radeon 0000:01:00.0: PCI INT A -> GSI 16 (level, low) -> IRQ 16
[    1.162933] radeon 0000:01:00.0: setting latency timer to 64
[    1.164778] [drm] initializing kernel modesetting (RV530 0x1002:0x71C5).
[    1.164952] [drm] register mmio base: 0x90300000
[    1.164955] [drm] register mmio size: 65536
[    1.165117] ATOM BIOS: M56P
[    1.165150] [drm] Generation 2 PCI interface, using max accessible memory
[    1.165156] radeon 0000:01:00.0: VRAM: 128M 0x00000000 - 0x07FFFFFF (128M used)
[    1.165160] radeon 0000:01:00.0: GTT: 512M 0x08000000 - 0x27FFFFFF
[    1.165243]   alloc irq_desc for 45 on node -1
[    1.165246]   alloc kstat_irqs on node -1
[    1.165258] radeon 0000:01:00.0: irq 45 for MSI/MSI-X
[    1.165265] radeon 0000:01:00.0: radeon: using MSI.
[    1.165293] [drm] radeon: irq initialized.
[    1.166084] [drm] Detected VRAM RAM=128M, BAR=128M
[    1.166088] [drm] RAM width 128bits DDR
[    1.166220] [TTM] Zone  kernel: Available graphics memory: 436608 kiB.
[    1.166223] [TTM] Zone highmem: Available graphics memory: 1022718 kiB.
[    1.166226] [TTM] Initializing pool allocator.
[    1.166252] [drm] radeon: 128M of VRAM memory ready
[    1.166254] [drm] radeon: 512M of GTT memory ready.
[    1.166281] [drm] GART: num cpu pages 131072, num gpu pages 131072
[    1.167992] [drm] radeon: 1 quad pipes, 2 z pipes initialized.
[    1.169480] [drm] PCIE GART of 512M enabled (table at 0x00040000).
[    1.169539] [drm] Loading R500 Microcode
[    1.171350] [drm] radeon: ring at 0x0000000008000000
[    1.171395] [drm] ring test succeeded in 12 usecs
[    1.171614] [drm] radeon: ib pool ready.
[    1.171752] [drm] ib test succeeded in 0 usecs
[    1.171763] failed to evaluate ATIF got AE_BAD_PARAMETER
[    1.171766] radeon 0000:01:00.0: Error during ACPI methods call
[    1.171801] [drm] Default TV standard: NTSC
[    1.171888] [drm] Default TV standard: NTSC
[    1.171977] [drm] Radeon Display Connectors
[    1.171979] [drm] Connector 0:
[    1.171981] [drm]   LVDS
[    1.171985] [drm]   DDC: 0x7e60 0x7e60 0x7e64 0x7e64 0x7e68 0x7e68 0x7e6c 0x7e6c
[    1.171987] [drm]   Encoders:
[    1.171990] [drm]     LCD1: INTERNAL_LVTM1
[    1.171992] [drm] Connector 1:
[    1.171994] [drm]   S-video
[    1.171995] [drm]   Encoders:
[    1.171997] [drm]     TV1: INTERNAL_KLDSCP_DAC2
[    1.172000] [drm] Connector 2:
[    1.172001] [drm]   DVI-I
[    1.172003] [drm]   HPD1
[    1.172006] [drm]   DDC: 0x7e40 0x7e40 0x7e44 0x7e44 0x7e48 0x7e48 0x7e4c 0x7e4c
[    1.172009] [drm]   Encoders:
[    1.172011] [drm]     CRT2: INTERNAL_KLDSCP_DAC2
[    1.172013] [drm]     DFP1: INTERNAL_KLDSCP_TMDS1
[    1.244027] firewire_ohci: Added fw-ohci device 0000:0c:03.0, OHCI v1.0, 8 IR + 8 IT contexts, quirks 0x0
[    1.248040] usb 1-4: new high speed USB device using ehci_hcd and address 4
[    1.278721] [drm] Possible lm63 thermal controller at 0x4c
[    1.278791] [drm] radeon: power management initialized
[    1.494003] [drm] fb mappable at 0x800C0000
[    1.494006] [drm] vram apper at 0x80000000
[    1.494009] [drm] size 7258112
[    1.494011] [drm] fb depth is 24
[    1.494013] [drm]    pitch is 6912
[    1.523727] Console: switching to colour frame buffer device 180x56
[    1.530065] fb0: radeondrmfb frame buffer device
[    1.530068] drm: registered panic notifier
[    1.530072] Slow work thread pool: Starting up
[    1.530225] Slow work thread pool: Ready
[    1.530232] [drm] Initialized radeon 2.5.0 20080528 for 0000:01:00.0 on minor 0
[    1.736190] firewire_core: created device fw0: GUID 0016cbfffe5693cc, S400
[    1.844033] usb 2-2: new full speed USB device using uhci_hcd and address 2
[    2.060542] usbcore: registered new interface driver hiddev
[    2.060805] usbcore: registered new interface driver usbhid
[    2.060807] usbhid: USB HID core driver
[    2.068786] input: Apple Computer Apple Internal Keyboard / Trackpad as /devices/pci0000:00/0000:00:1d.0/usb2/2-2/2-2:1.0/input/input4
[    2.068975] apple 0003:05AC:0218.0001: input,hidraw0: USB HID v1.11 Keyboard [Apple Computer Apple Internal Keyboard / Trackpad] on usb-0000:00:1d.0-2/input0
[    2.071792] input: Apple Computer Apple Internal Keyboard / Trackpad as /devices/pci0000:00/0000:00:1d.0/usb2/2-2/2-2:1.2/input/input5
[    2.071948] apple 0003:05AC:0218.0002: input,hidraw1: USB HID v1.11 Device [Apple Computer Apple Internal Keyboard / Trackpad] on usb-0000:00:1d.0-2/input2
[    2.284077] usb 4-2: new full speed USB device using uhci_hcd and address 2
[    2.470492] generic-usb 0003:05AC:8240.0003: hiddev96,hidraw2: USB HID v1.11 Device [Apple Computer, Inc. IR Receiver] on usb-0000:00:1d.2-2/input0
[    2.708059] usb 5-1: new full speed USB device using uhci_hcd and address 2
[    2.909766] input: HID 05ac:1000 as /devices/pci0000:00/0000:00:1d.3/usb5/5-1/5-1:1.0/input/input6
[    2.909860] generic-usb 0003:05AC:1000.0004: input,hidraw3: USB HID v1.11 Keyboard [HID 05ac:1000] on usb-0000:00:1d.3-1/input0
[    2.949850] input: HID 05ac:1000 as /devices/pci0000:00/0000:00:1d.3/usb5/5-1/5-1:1.1/input/input7
[    2.949968] generic-usb 0003:05AC:1000.0005: input,hidraw4: USB HID v1.11 Mouse [HID 05ac:1000] on usb-0000:00:1d.3-1/input1
[    3.020390] usb 1-3.1: new low speed USB device using ehci_hcd and address 7
[    3.132183] input: Logitech Logitech USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-3/1-3.1/1-3.1:1.0/input/input8
[    3.132295] generic-usb 0003:046D:C308.0006: input,hidraw5: USB HID v1.10 Keyboard [Logitech Logitech USB Keyboard] on usb-0000:00:1d.7-3.1/input0
[    3.146892] input: Logitech Logitech USB Keyboard as /devices/pci0000:00/0000:00:1d.7/usb1/1-3/1-3.1/1-3.1:1.1/input/input9
[    3.147023] generic-usb 0003:046D:C308.0007: input,hidraw6: USB HID v1.10 Mouse [Logitech Logitech USB Keyboard] on usb-0000:00:1d.7-3.1/input1
[    3.216387] usb 1-3.2: new low speed USB device using ehci_hcd and address 8
[    3.318093] input: Logitech USB-PS/2 Optical Mouse as /devices/pci0000:00/0000:00:1d.7/usb1/1-3/1-3.2/1-3.2:1.0/input/input10
[    3.318214] generic-usb 0003:046D:C00E.0008: input,hidraw7: USB HID v1.10 Mouse [Logitech USB-PS/2 Optical Mouse] on usb-0000:00:1d.7-3.2/input0
[    3.388408] usb 1-3.3: new full speed USB device using ehci_hcd and address 9
[    3.552420] usb 1-3.4: new high speed USB device using ehci_hcd and address 10
[    3.694444] Initializing USB Mass Storage driver...
[    3.694589] usb-storage 1-3.4:1.0: Quirks match for vid 0bc2 pid 3010: 8000
[    3.694655] scsi4 : usb-storage 1-3.4:1.0
[    3.694759] usbcore: registered new interface driver usb-storage
[    3.694762] USB Mass Storage support registered.
[    4.697625] scsi 4:0:0:0: Direct-Access     Seagate  FreeAgent Pro    400A PQ: 0 ANSI: 4
[    4.698628] sd 4:0:0:0: Attached scsi generic sg2 type 0
[    4.702708] sd 4:0:0:0: [sdb] 976773168 512-byte logical blocks: (500 GB/465 GiB)
[    4.705327] sd 4:0:0:0: [sdb] Write Protect is off
[    4.705334] sd 4:0:0:0: [sdb] Mode Sense: 1c 00 00 00
[    4.705340] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[    4.711951] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[    4.711960]  sdb: sdb1
[    4.721081] sd 4:0:0:0: [sdb] Assuming drive cache: write through
[    4.721089] sd 4:0:0:0: [sdb] Attached SCSI disk
[    7.282358] EXT4-fs (sda4): mounted filesystem with ordered data mode. Opts: (null)
[   11.950803] udev[464]: starting version 163
[   12.217029] EXT4-fs (sda4): re-mounted. Opts: errors=remount-ro
[   12.794928] usb 5-1: usbfs: USBDEVFS_CONTROL failed cmd hid2hci rqt 64 rq 0 len 0 ret -84
[   12.800086] usb 5-1: USB disconnect, address 2
[   13.483005] appletouch: Geyser mode initialized.
[   13.483285] input: appletouch as /devices/pci0000:00/0000:00:1d.0/usb2/2-2/2-2:1.1/input/input11
[   13.483693] usbcore: registered new interface driver appletouch
[   13.537268] usb 5-1: new full speed USB device using uhci_hcd and address 3
[   14.095071] applesmc: Apple MacBook Pro detected:
[   14.095076] applesmc:  - Model with accelerometer
[   14.095078] applesmc:  - Model with light sensors and backlight
[   14.095081] applesmc:  - Model with 12 temperature sensors
[   14.149756] applesmc: device successfully initialized (0xe0, 0x00).
[   14.149760] applesmc: device successfully initialized.
[   14.150570] applesmc: 2 fans found.
[   14.177301] input: applesmc as /devices/platform/applesmc.768/input/input12
[   14.209717] Registered led device: smc::kbd_backlight
[   14.209745] applesmc: driver successfully loaded.
[   14.790107] Bluetooth: Core ver 2.15
[   14.790407] NET: Registered protocol family 31
[   14.790410] Bluetooth: HCI device and connection manager initialized
[   14.790414] Bluetooth: HCI socket layer initialized
[   14.814171] cfg80211: Calling CRDA to update world regulatory domain
[   14.865917] tpm_inf_pnp 00:03: Found TPM with ID IFX0101
[   14.865975] tpm_inf_pnp 00:03: TPM found: config base 0x4e, data base 0x4700, chip version 0x000b, vendor id 0x15d1 (Infineon), product id 0x000b (SLB 9635 TT 1.2)
[   15.163186] Unable to load isight firmware
[   15.163230] usbcore: registered new interface driver isight_firmware
[   15.229433] Bluetooth: Generic Bluetooth USB driver ver 0.6
[   15.229660] usbcore: registered new interface driver btusb
[   15.302312] intel_rng: FWH not detected
[   15.427459] leds_ss4200: no LED devices found
[   15.634609] lp: driver loaded but no devices found
[   16.277876] ath5k 0000:03:00.0: PCI INT A -> GSI 17 (level, low) -> IRQ 17
[   16.277891] ath5k 0000:03:00.0: setting latency timer to 64
[   16.277934] ath5k 0000:03:00.0: registered as 'phy0'
[   16.285792] type=1400 audit(1303066953.076:2): apparmor="STATUS" operation="profile_load" name="/sbin/dhclient3" pid=615 comm="apparmor_parser"
[   16.286510] type=1400 audit(1303066953.076:3): apparmor="STATUS" operation="profile_load" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=615 comm="apparmor_parser"
[   16.286903] type=1400 audit(1303066953.076:4): apparmor="STATUS" operation="profile_load" name="/usr/lib/connman/scripts/dhclient-script" pid=615 comm="apparmor_parser"
[   16.837724] ath: EEPROM regdomain: 0x65
[   16.837729] ath: EEPROM indicates we should expect a direct regpair map
[   16.837734] ath: Country alpha2 being used: 00
[   16.837736] ath: Regpair used: 0x65
[   16.875090] cfg80211: World regulatory domain updated:
[   16.875094]     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[   16.875098]     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   16.875102]     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[   16.875105]     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[   16.875109]     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   16.875112]     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[   16.909431]   alloc irq_desc for 22 on node -1
[   16.909435]   alloc kstat_irqs on node -1
[   16.909444] HDA Intel 0000:00:1b.0: PCI INT A -> GSI 22 (level, low) -> IRQ 22
[   16.909495]   alloc irq_desc for 46 on node -1
[   16.909498]   alloc kstat_irqs on node -1
[   16.909511] HDA Intel 0000:00:1b.0: irq 46 for MSI/MSI-X
[   16.909548] HDA Intel 0000:00:1b.0: setting latency timer to 64
[   17.247131] hda_codec: STAC922x, Apple subsys_id=106b0200
[   17.268642] input: HDA Intel Line In at Ext Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input13
[   17.268762] input: HDA Intel HP Out at Ext Rear Jack as /devices/pci0000:00/0000:00:1b.0/sound/card0/input14
[   17.272651] phy0: Selected rate control algorithm 'minstrel'
[   17.273386] ath5k phy0: Atheros AR5414 chip found (MAC: 0xa3, PHY: 0x61)
[   18.006777] usblp0: USB Bidirectional printer dev 9 if 0 alt 1 proto 2 vid 0x03F0 pid 0x0D17
[   18.006821] usbcore: registered new interface driver usblp
[   21.619455] type=1400 audit(1303066958.408:5): apparmor="STATUS" operation="profile_replace" name="/sbin/dhclient3" pid=1172 comm="apparmor_parser"
[   21.620328] type=1400 audit(1303066958.412:6): apparmor="STATUS" operation="profile_replace" name="/usr/lib/NetworkManager/nm-dhcp-client.action" pid=1172 comm="apparmor_parser"
[   21.620725] type=1400 audit(1303066958.412:7): apparmor="STATUS" operation="profile_replace" name="/usr/lib/connman/scripts/dhclient-script" pid=1172 comm="apparmor_parser"
[   21.935841] padlock: VIA PadLock not detected.
[   21.980487] type=1400 audit(1303066958.772:8): apparmor="STATUS" operation="profile_load" name="/usr/share/gdm/guest-session/Xsession" pid=1171 comm="apparmor_parser"
[   22.901162] padlock: VIA PadLock Hash Engine not detected.
[   23.433868] ppdev: user-space parallel port driver
[   23.515195] type=1400 audit(1303066960.304:9): apparmor="STATUS" operation="profile_load" name="/usr/lib/cups/backend/cups-pdf" pid=1195 comm="apparmor_parser"
[   23.516136] type=1400 audit(1303066960.308:10): apparmor="STATUS" operation="profile_load" name="/usr/sbin/cupsd" pid=1195 comm="apparmor_parser"
[   23.780204] type=1400 audit(1303066960.572:11): apparmor="STATUS" operation="profile_load" name="/usr/sbin/dhcpd3" pid=1292 comm="apparmor_parser"
[   23.946820] type=1400 audit(1303066960.736:12): apparmor="STATUS" operation="profile_load" name="/usr/sbin/tcpdump" pid=1299 comm="apparmor_parser"
[   24.066940] type=1400 audit(1303066960.856:13): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince" pid=1173 comm="apparmor_parser"
[   24.076100] type=1400 audit(1303066960.868:14): apparmor="STATUS" operation="profile_load" name="/usr/bin/evince-previewer" pid=1173 comm="apparmor_parser"
[   26.225175] sky2 0000:02:00.0: eth0: enabling interface
[   26.225401] ADDRCONF(NETDEV_UP): eth0: link is not ready
[   26.243360] ADDRCONF(NETDEV_UP): wlan0: link is not ready
[   26.915353] Adding 3416060k swap on /dev/mapper/cryptswap1.  Priority:-1 extents:1 across:3416060k 
[   29.048653] usb 1-3.3: usbfs: interface 0 claimed by usblp while 'usb' sets config #1
[   49.768441] EXT4-fs (sda4): re-mounted. Opts: errors=remount-ro,commit=0
[   51.116829] Bluetooth: L2CAP ver 2.14
[   51.116833] Bluetooth: L2CAP socket layer initialized
[   51.268420] input: Mouseemu virtual keyboard as /devices/virtual/input/input15
[   51.268649] input: Mouseemu virtual mouse as /devices/virtual/input/input16
[   51.269751] Bluetooth: BNEP (Ethernet Emulation) ver 1.3
[   51.269755] Bluetooth: BNEP filters: protocol multicast
[   51.335894] Bluetooth: SCO (Voice Link) ver 0.6
[   51.335898] Bluetooth: SCO socket layer initialized
[   51.778543] Bluetooth: RFCOMM TTY layer initialized
[   51.778550] Bluetooth: RFCOMM socket layer initialized
[   51.778553] Bluetooth: RFCOMM ver 1.11
[   99.513569] Bluetooth: HIDP (Human Interface Emulation) ver 1.2
[  111.315527] wlan0: authenticate with 00:11:95:3d:6b:4b (try 1)
[  111.320393] wlan0: authenticated
[  111.320420] wlan0: associate with 00:11:95:3d:6b:4b (try 1)
[  111.323371] wlan0: RX AssocResp from 00:11:95:3d:6b:4b (capab=0x431 status=0 aid=14)
[  111.323428] wlan0: associated
[  111.324189] ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[  118.686353] EXT3-fs: barriers not enabled
[  118.694831] kjournald starting.  Commit interval 5 seconds
[  118.695386] EXT3-fs (sdb1): warning: checktime reached, running e2fsck is recommended
[  118.696467] EXT3-fs (sdb1): using internal journal
[  118.696478] EXT3-fs (sdb1): mounted filesystem with ordered data mode
[  122.032028] wlan0: no IPv6 routers present
[ 3058.827497] lo: Disabled Privacy Extensions
[ 6246.670194] lo: Disabled Privacy Extensions
[ 7310.865625] CE: hpet increased min_delta_ns to 7500 nsec
[ 7310.866467] CE: hpet increased min_delta_ns to 11250 nsec
[ 7310.866954] CE: hpet increased min_delta_ns to 16875 nsec
[ 7310.867296] CE: hpet increased min_delta_ns to 25312 nsec
[ 7310.867646] CE: hpet increased min_delta_ns to 37968 nsec
[ 7316.969370] CE: hpet increased min_delta_ns to 56952 nsec
[ 7319.373765] CE: hpet increased min_delta_ns to 85428 nsec
[23344.407880] CE: hpet increased min_delta_ns to 128142 nsec
[23344.489901] CE: hpet increased min_delta_ns to 192213 nsec
[23344.538389] CE: hpet increased min_delta_ns to 288319 nsec
[23409.941816] CE: hpet increased min_delta_ns to 432478 nsec
[23812.309747] hrtimer: interrupt took 1208667 ns
[44025.290518] wlan0: deauthenticated from 00:11:95:3d:6b:4b (Reason: 3)
[44025.301352] cfg80211: All devices are disconnected, going to restore regulatory settings
[44025.301364] cfg80211: Restoring regulatory settings
[44025.301372] cfg80211: Calling CRDA to update world regulatory domain
[44025.487428] cfg80211: World regulatory domain updated:
[44025.487438]     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[44025.487445]     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[44025.487452]     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[44025.487458]     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[44025.487464]     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[44025.487471]     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[44028.015199] wlan0: authenticate with 00:11:95:3d:6b:4b (try 1)
[44028.016746] wlan0: authenticated
[44028.016768] wlan0: associate with 00:11:95:3d:6b:4b (try 1)
[44028.018890] wlan0: RX AssocResp from 00:11:95:3d:6b:4b (capab=0x431 status=0 aid=14)
[44028.018898] wlan0: associated
[57702.411273] wlan0: deauthenticated from 00:11:95:3d:6b:4b (Reason: 3)
[57702.421517] cfg80211: All devices are disconnected, going to restore regulatory settings
[57702.421530] cfg80211: Restoring regulatory settings
[57702.421537] cfg80211: Calling CRDA to update world regulatory domain
[57702.429063] cfg80211: World regulatory domain updated:
[57702.429070]     (start_freq - end_freq @ bandwidth), (max_antenna_gain, max_eirp)
[57702.429077]     (2402000 KHz - 2472000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[57702.429084]     (2457000 KHz - 2482000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[57702.429090]     (2474000 KHz - 2494000 KHz @ 20000 KHz), (300 mBi, 2000 mBm)
[57702.429097]     (5170000 KHz - 5250000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[57702.429103]     (5735000 KHz - 5835000 KHz @ 40000 KHz), (300 mBi, 2000 mBm)
[57705.143641] wlan0: authenticate with 00:11:95:3d:6b:4b (try 1)
[57705.145100] wlan0: authenticated
[57705.145125] wlan0: associate with 00:11:95:3d:6b:4b (try 1)
[57705.147091] wlan0: RX AssocResp from 00:11:95:3d:6b:4b (capab=0x431 status=0 aid=14)
[57705.147097] wlan0: associated
[66693.098131] lo: Disabled Privacy Extensions


More information about the lttng-dev mailing list