* i915_init takes a full second of kernel init time
@ 2011-12-13 19:55 Scott James Remnant
2011-12-13 20:02 ` Jesse Barnes
0 siblings, 1 reply; 10+ messages in thread
From: Scott James Remnant @ 2011-12-13 19:55 UTC (permalink / raw)
To: intel-gfx; +Cc: Stéphane Marchesin, Daniel Stone
[-- Attachment #1: Type: text/plain, Size: 340 bytes --]
I've been investigating Chrome OS boot time and noticed the anomaly
where i915_init takes up a considerable amount of kernel startup time,
one second in fact. I've attached a full dmesg with drm.debug=0xff for
analysis at Daniel's suggestion.
Thanks
Scott
--
Scott James Remnant | Chrome OS Systems | keybuk@google.com | Google
[-- Attachment #2: alex.dmesg --]
[-- Type: application/octet-stream, Size: 110844 bytes --]
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.0.8 (keybuk@angrybirds.mtv.corp.google.com) (gcc version 4.6.x-google 20110718 (prerelease) (gcc-4.6.0_cos_gg_180104) ) #1 SMP Tue Dec 13 19:03:12 UTC 2011
[ 0.000000] Disabled fast string operations
[ 0.000000] BIOS-provided physical RAM map:
[ 0.000000] BIOS-e820: 0000000000000000 - 0000000000001000 (usable)
[ 0.000000] BIOS-e820: 00000000fffff000 - 0000000100000000 (reserved)
[ 0.000000] NX (Execute Disable) protection: active
[ 0.000000] EFI v2.00 by INSYDE Corp.
[ 0.000000] ACPI=0x7f5f2000 ACPI 2.0=0x7f5f2014 SMBIOS=0x7f495d98
[ 0.000000] Kernel-defined memdesc doesn't match the one from EFI!
[ 0.000000] EFI: mem00: type=3, attr=0xf, range=[0x0000000000000000-0x0000000000001000) (0MB)
[ 0.000000] EFI: mem01: type=7, attr=0xf, range=[0x0000000000001000-0x000000000000e000) (0MB)
[ 0.000000] EFI: mem02: type=3, attr=0xf, range=[0x000000000000e000-0x0000000000010000) (0MB)
[ 0.000000] EFI: mem03: type=7, attr=0xf, range=[0x0000000000010000-0x000000000002e000) (0MB)
[ 0.000000] EFI: mem04: type=3, attr=0xf, range=[0x000000000002e000-0x000000000002f000) (0MB)
[ 0.000000] EFI: mem05: type=4, attr=0xf, range=[0x000000000002f000-0x0000000000030000) (0MB)
[ 0.000000] EFI: mem06: type=3, attr=0xf, range=[0x0000000000030000-0x000000000008f000) (0MB)
[ 0.000000] EFI: mem07: type=10, attr=0xf, range=[0x000000000008f000-0x0000000000090000) (0MB)
[ 0.000000] EFI: mem08: type=3, attr=0xf, range=[0x0000000000090000-0x00000000000a0000) (0MB)
[ 0.000000] EFI: mem09: type=2, attr=0xf, range=[0x0000000000100000-0x0000000000b00000) (10MB)
[ 0.000000] EFI: mem10: type=3, attr=0xf, range=[0x0000000000b00000-0x0000000000f00000) (4MB)
[ 0.000000] EFI: mem11: type=0, attr=0xf, range=[0x0000000000f00000-0x0000000001000000) (1MB)
[ 0.000000] EFI: mem12: type=7, attr=0xf, range=[0x0000000001000000-0x000000007bf0d000) (1967MB)
[ 0.000000] EFI: mem13: type=4, attr=0xf, range=[0x000000007bf0d000-0x000000007bf2b000) (0MB)
[ 0.000000] EFI: mem14: type=7, attr=0xf, range=[0x000000007bf2b000-0x000000007bf31000) (0MB)
[ 0.000000] EFI: mem15: type=1, attr=0xf, range=[0x000000007bf31000-0x000000007bf3a000) (0MB)
[ 0.000000] EFI: mem16: type=4, attr=0xf, range=[0x000000007bf3a000-0x000000007bf7e000) (0MB)
[ 0.000000] EFI: mem17: type=3, attr=0xf, range=[0x000000007bf7e000-0x000000007bf84000) (0MB)
[ 0.000000] EFI: mem18: type=4, attr=0xf, range=[0x000000007bf84000-0x000000007c1d8000) (2MB)
[ 0.000000] EFI: mem19: type=7, attr=0xf, range=[0x000000007c1d8000-0x000000007c1dc000) (0MB)
[ 0.000000] EFI: mem20: type=4, attr=0xf, range=[0x000000007c1dc000-0x000000007c212000) (0MB)
[ 0.000000] EFI: mem21: type=3, attr=0xf, range=[0x000000007c212000-0x000000007c217000) (0MB)
[ 0.000000] EFI: mem22: type=4, attr=0xf, range=[0x000000007c217000-0x000000007c21a000) (0MB)
[ 0.000000] EFI: mem23: type=3, attr=0xf, range=[0x000000007c21a000-0x000000007c21d000) (0MB)
[ 0.000000] EFI: mem24: type=4, attr=0xf, range=[0x000000007c21d000-0x000000007c223000) (0MB)
[ 0.000000] EFI: mem25: type=3, attr=0xf, range=[0x000000007c223000-0x000000007c229000) (0MB)
[ 0.000000] EFI: mem26: type=4, attr=0xf, range=[0x000000007c229000-0x000000007c22a000) (0MB)
[ 0.000000] EFI: mem27: type=3, attr=0xf, range=[0x000000007c22a000-0x000000007c230000) (0MB)
[ 0.000000] EFI: mem28: type=4, attr=0xf, range=[0x000000007c230000-0x000000007c231000) (0MB)
[ 0.000000] EFI: mem29: type=3, attr=0xf, range=[0x000000007c231000-0x000000007c23b000) (0MB)
[ 0.000000] EFI: mem30: type=4, attr=0xf, range=[0x000000007c23b000-0x000000007c23c000) (0MB)
[ 0.000000] EFI: mem31: type=3, attr=0xf, range=[0x000000007c23c000-0x000000007c240000) (0MB)
[ 0.000000] EFI: mem32: type=4, attr=0xf, range=[0x000000007c240000-0x000000007c261000) (0MB)
[ 0.000000] EFI: mem33: type=3, attr=0xf, range=[0x000000007c261000-0x000000007c262000) (0MB)
[ 0.000000] EFI: mem34: type=4, attr=0xf, range=[0x000000007c262000-0x000000007c263000) (0MB)
[ 0.000000] EFI: mem35: type=3, attr=0xf, range=[0x000000007c263000-0x000000007c264000) (0MB)
[ 0.000000] EFI: mem36: type=4, attr=0xf, range=[0x000000007c264000-0x000000007c265000) (0MB)
[ 0.000000] EFI: mem37: type=3, attr=0xf, range=[0x000000007c265000-0x000000007c26c000) (0MB)
[ 0.000000] EFI: mem38: type=4, attr=0xf, range=[0x000000007c26c000-0x000000007c26f000) (0MB)
[ 0.000000] EFI: mem39: type=3, attr=0xf, range=[0x000000007c26f000-0x000000007c271000) (0MB)
[ 0.000000] EFI: mem40: type=4, attr=0xf, range=[0x000000007c271000-0x000000007c273000) (0MB)
[ 0.000000] EFI: mem41: type=3, attr=0xf, range=[0x000000007c273000-0x000000007c275000) (0MB)
[ 0.000000] EFI: mem42: type=4, attr=0xf, range=[0x000000007c275000-0x000000007c276000) (0MB)
[ 0.000000] EFI: mem43: type=3, attr=0xf, range=[0x000000007c276000-0x000000007c27b000) (0MB)
[ 0.000000] EFI: mem44: type=4, attr=0xf, range=[0x000000007c27b000-0x000000007c62e000) (3MB)
[ 0.000000] EFI: mem45: type=3, attr=0xf, range=[0x000000007c62e000-0x000000007c634000) (0MB)
[ 0.000000] EFI: mem46: type=4, attr=0xf, range=[0x000000007c634000-0x000000007c635000) (0MB)
[ 0.000000] EFI: mem47: type=3, attr=0xf, range=[0x000000007c635000-0x000000007c637000) (0MB)
[ 0.000000] EFI: mem48: type=4, attr=0xf, range=[0x000000007c637000-0x000000007c63e000) (0MB)
[ 0.000000] EFI: mem49: type=3, attr=0xf, range=[0x000000007c63e000-0x000000007c65c000) (0MB)
[ 0.000000] EFI: mem50: type=4, attr=0xf, range=[0x000000007c65c000-0x000000007c6ac000) (0MB)
[ 0.000000] EFI: mem51: type=3, attr=0xf, range=[0x000000007c6ac000-0x000000007c6bc000) (0MB)
[ 0.000000] EFI: mem52: type=4, attr=0xf, range=[0x000000007c6bc000-0x000000007f23f000) (43MB)
[ 0.000000] EFI: mem53: type=3, attr=0xf, range=[0x000000007f23f000-0x000000007f33f000) (1MB)
[ 0.000000] EFI: mem54: type=5, attr=0x800000000000000f, range=[0x000000007f33f000-0x000000007f370000) (0MB)
[ 0.000000] EFI: mem55: type=5, attr=0x800000000000000f, range=[0x000000007f370000-0x000000007f3bf000) (0MB)
[ 0.000000] EFI: mem56: type=6, attr=0x800000000000000f, range=[0x000000007f3bf000-0x000000007f416000) (0MB)
[ 0.000000] EFI: mem57: type=6, attr=0x800000000000000f, range=[0x000000007f416000-0x000000007f43f000) (0MB)
[ 0.000000] EFI: mem58: type=0, attr=0xf, range=[0x000000007f43f000-0x000000007f486000) (0MB)
[ 0.000000] EFI: mem59: type=0, attr=0xf, range=[0x000000007f486000-0x000000007f4bf000) (0MB)
[ 0.000000] EFI: mem60: type=10, attr=0xf, range=[0x000000007f4bf000-0x000000007f582000) (0MB)
[ 0.000000] EFI: mem61: type=10, attr=0xf, range=[0x000000007f582000-0x000000007f5bf000) (0MB)
[ 0.000000] EFI: mem62: type=9, attr=0xf, range=[0x000000007f5bf000-0x000000007f5e2000) (0MB)
[ 0.000000] EFI: mem63: type=9, attr=0xf, range=[0x000000007f5e2000-0x000000007f5f3000) (0MB)
[ 0.000000] EFI: mem64: type=4, attr=0xf, range=[0x000000007f5f3000-0x000000007f600000) (0MB)
[ 0.000000] EFI: mem65: type=11, attr=0x8000000000000001, range=[0x00000000fed1c000-0x00000000fed20000) (0MB)
[ 0.000000] EFI: mem66: type=11, attr=0x8000000000000000, range=[0x00000000ffc00000-0x00000000ffc10000) (0MB)
[ 0.000000] EFI: mem67: type=11, attr=0x8000000000000000, range=[0x00000000ffc10000-0x00000000ffc20000) (0MB)
[ 0.000000] EFI: mem68: type=11, attr=0x8000000000000000, range=[0x00000000ffc20000-0x0000000100000000) (3MB)
[ 0.000000] DMI 2.7 present.
[ 0.000000] DMI: SAMSUNG ELECTRONICS CO., LTD. Alex/G100 , BIOS Alex.03.61.0735.0056G3.0021 04/07/2011
[ 0.000000] e820 update range: 0000000000000000 - 0000000000010000 (usable) ==> (reserved)
[ 0.000000] e820 remove range: 00000000000a0000 - 0000000000100000 (usable)
[ 0.000000] last_pfn = 0x7f600 max_arch_pfn = 0x1000000
[ 0.000000] MTRR default type: uncachable
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-BFFFF uncachable
[ 0.000000] C0000-FFFFF write-protect
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 0FFC00000 mask FFFC00000 write-protect
[ 0.000000] 1 base 000000000 mask FC0000000 write-back
[ 0.000000] 2 base 040000000 mask FC0000000 write-back
[ 0.000000] 3 base 07F800000 mask FFF800000 uncachable
[ 0.000000] 4 disabled
[ 0.000000] 5 disabled
[ 0.000000] 6 disabled
[ 0.000000] x86 PAT enabled: cpu 0, old 0x7040600070406, new 0x7010600070106
[ 0.000000] Warning only 1919MB will be used.
[ 0.000000] Use a HIGHMEM enabled kernel.
[ 0.000000] initial memory mapped : 0 - 02000000
[ 0.000000] Base memory trampoline at [8009b000] 9b000 size 16384
[ 0.000000] init_memory_mapping: 0000000000000000-0000000077f64000
[ 0.000000] 0000000000 - 0000200000 page 4k
[ 0.000000] 0000200000 - 0077e00000 page 2M
[ 0.000000] 0077e00000 - 0077f64000 page 4k
[ 0.000000] kernel direct mapping tables up to 77f64000 @ 1ffa000-2000000
[ 0.000000] ACPI: RSDP 7f5f2014 00024 (v02 INTEL )
[ 0.000000] ACPI: XSDT 7f5f2120 00074 (v01 INTEL PineTrlM 00000001 01000013)
[ 0.000000] ACPI: FACP 7f5f1000 000F4 (v04 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: DSDT 7f5e8000 05E37 (v01 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: FACS 7f58f000 00040
[ 0.000000] ACPI: HPET 7f5f0000 00038 (v01 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: APIC 7f5ef000 00078 (v02 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: MCFG 7f5ee000 0003C (v01 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: SLIC 7f5e7000 00176 (v01 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: BOOT 7f5e6000 00028 (v01 INTEL PineTrlM 00000001 MSFT 01000013)
[ 0.000000] ACPI: SSDT 7f5e5000 00655 (v01 PmRef CpuPm 00003000 INTL 20100121)
[ 0.000000] ACPI: SSDT 7f5e4000 00259 (v01 PmRef Cpu0Tst 00003000 INTL 20100121)
[ 0.000000] ACPI: SSDT 7f5e3000 0020F (v01 PmRef ApTst 00003000 INTL 20100121)
[ 0.000000] ACPI: WDAT 7f5e2000 00194 (v01 INSYDE INSYDE 00000001 MSFT 01000013)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] 1919MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 77f64000
[ 0.000000] low ram: 0 - 77f64000
[ 0.000000] Zone PFN ranges:
[ 0.000000] DMA 0x00000010 -> 0x00001000
[ 0.000000] Normal 0x00001000 -> 0x00077f64
[ 0.000000] Movable zone start PFN for each node
[ 0.000000] early_node_map[4] active PFN ranges
[ 0.000000] 0: 0x00000010 -> 0x0000008f
[ 0.000000] 0: 0x00000090 -> 0x000000a0
[ 0.000000] 0: 0x00000100 -> 0x00000f00
[ 0.000000] 0: 0x00001000 -> 0x00077f64
[ 0.000000] On node 0 totalpages: 490995
[ 0.000000] DMA zone: 32 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3695 pages, LIFO batch:0
[ 0.000000] Normal zone: 3807 pages used for memmap
[ 0.000000] Normal zone: 483461 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[0x01] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x02] lapic_id[0x01] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x03] lapic_id[0x02] enabled)
[ 0.000000] ACPI: LAPIC (acpi_id[0x04] lapic_id[0x03] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x01] dfl dfl lint[0x1])
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0x02] dfl dfl lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x04] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 4, 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 4 CPUs, 0 hotplug CPUs
[ 0.000000] nr_irqs_gsi: 40
[ 0.000000] Allocating PCI resources starting at 7f600000 (gap: 7f600000:7f71c000)
[ 0.000000] setup_percpu: NR_CPUS:4 nr_cpumask_bits:4 nr_cpu_ids:4 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 11 pages/cpu @f6c00000 s23520 r0 d21536 u524288
[ 0.000000] pcpu-alloc: s23520 r0 d21536 u524288 alloc=1*2097152
[ 0.000000] pcpu-alloc: [0] 0 1 2 3
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 487156
[ 0.000000] Kernel command line: quiet loglevel=1 console=tty2 init=/sbin/init add_efi_memmap boot=local noresume noswap i915.modeset=1 cros_secure kern_guid=f3492094-3ff7-6042-9ecf-b32e378b5015 tpm_tis.force=1 tpm_tis.interrupts=0 nmi_watchdog=panic,lapic root=/dev/dm-0 rootwait ro dm_verity.error_behavior=3 dm_verity.max_bios=-1 dm_verity.dev_wait=1 dm="vroot none ro,0 1740800 verity payload=f3492094-3ff7-6042-9ecf-b32e378b5015+1 hashtree=f3492094-3ff7-6042-9ecf-b32e378b5015+1 hashstart=1740800 alg=sha1 root_hexdigest=edc631c21df4c08e9bc0599cc1bb028912287b26 salt=8111783a108908e0c3befe8f758dd10ab041c2123a4555768308bcb71b69ab48" noinitrd initcall_debug drm.debug=0xff cros_debug vt.global_cursor_default=0
[ 0.000000] dm: will configure 'vroot' on dm-0
[ 0.000000] PID hash table entries: 4096 (order: 2, 16384 bytes)
[ 0.000000] Dentry cache hash table entries: 262144 (order: 8, 1048576 bytes)
[ 0.000000] Inode-cache hash table entries: 131072 (order: 7, 524288 bytes)
[ 0.000000] Initializing CPU#0
[ 0.000000] Memory: 1934920k/1965456k available (3579k kernel code, 1679k data, 444k init, 0k highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xfff66000 - 0xfffff000 ( 612 kB)
[ 0.000000] vmalloc : 0xf8764000 - 0xfff64000 ( 120 MB)
[ 0.000000] lowmem : 0x80000000 - 0xf7f64000 (1919 MB)
[ 0.000000] .init : 0x81523000 - 0x81592000 ( 444 kB)
[ 0.000000] .data : 0x8137ec40 - 0x81522840 (1679 kB)
[ 0.000000] .text : 0x81000000 - 0x8137ec40 (3579 kB)
[ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.000000] SLUB: Genslabs=15, HWalign=64, Order=0-3, MinObjects=0, CPUs=4, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] NR_IRQS:2304 nr_irqs:712 16
[ 0.000000] CPU 0 irqstacks, hard=f6808000 soft=f680a000
[ 0.000000] Extended CMOS year: 2000
[ 0.000000] Console: colour dummy device 80x25
[ 0.000000] console [tty2] enabled
[ 0.000000] hpet clockevent registered
[ 0.000000] Initial TSC value: 48223443210
[ 0.000000] Fast TSC calibration using PIT
[ 0.000000] Initial usec timer 29015308
[ 0.000000] Detected 1662.734 MHz processor.
[ 0.001005] Calibrating delay loop (skipped), value calculated using timer frequency.. 3325.46 BogoMIPS (lpj=1662734)
[ 0.001017] pid_max: default: 32768 minimum: 301
[ 0.001073] Security Framework initialized
[ 0.001114] Mount-cache hash table entries: 512
[ 0.002173] Disabled fast string operations
[ 0.002182] CPU: Physical Processor ID: 0
[ 0.002187] CPU: Processor Core ID: 0
[ 0.002193] mce: CPU supports 5 MCE banks
[ 0.002209] CPU0: Thermal monitoring enabled (TM2)
[ 0.002217] using mwait in idle threads.
[ 0.002292] ACPI: Core revision 20110413
[ 0.016027] ftrace: allocating 20131 entries in 40 pages
[ 0.017092] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.017520] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.027833] CPU0: Intel(R) Atom(TM) CPU N570 @ 1.66GHz stepping 0a
[ 0.027998] calling trace_init_flags_sys_exit+0x0/0x11 @ 1
[ 0.027998] initcall trace_init_flags_sys_exit+0x0/0x11 returned 0 after 0 usecs
[ 0.027998] calling trace_init_flags_sys_enter+0x0/0x11 @ 1
[ 0.027998] initcall trace_init_flags_sys_enter+0x0/0x11 returned 0 after 0 usecs
[ 0.027998] calling init_hw_perf_events+0x0/0xdd4 @ 1
[ 0.027998] Performance Events: PEBS fmt0+, Atom events, Intel PMU driver.
[ 0.027998] ... version: 3
[ 0.027998] ... bit width: 40
[ 0.027998] ... generic registers: 2
[ 0.027998] ... value mask: 000000ffffffffff
[ 0.027998] ... max period: 000000007fffffff
[ 0.027998] ... fixed-purpose events: 3
[ 0.027998] ... event mask: 0000000700000003
[ 0.027998] initcall init_hw_perf_events+0x0/0xdd4 returned 0 after 0 usecs
[ 0.027998] calling register_trigger_all_cpu_backtrace+0x0/0x11 @ 1
[ 0.027998] initcall register_trigger_all_cpu_backtrace+0x0/0x11 returned 0 after 0 usecs
[ 0.027998] calling migration_init+0x0/0x59 @ 1
[ 0.027998] initcall migration_init+0x0/0x59 returned 0 after 0 usecs
[ 0.027998] calling spawn_ksoftirqd+0x0/0x41 @ 1
[ 0.027998] initcall spawn_ksoftirqd+0x0/0x41 returned 0 after 0 usecs
[ 0.027998] calling init_workqueues+0x0/0x21e @ 1
[ 0.027998] initcall init_workqueues+0x0/0x21e returned 0 after 0 usecs
[ 0.027998] calling cpu_stop_init+0x0/0x94 @ 1
[ 0.027998] initcall cpu_stop_init+0x0/0x94 returned 0 after 0 usecs
[ 0.027998] calling rcu_scheduler_really_started+0x0/0x11 @ 1
[ 0.027998] initcall rcu_scheduler_really_started+0x0/0x11 returned 0 after 0 usecs
[ 0.027998] calling relay_init+0x0/0x11 @ 1
[ 0.027998] initcall relay_init+0x0/0x11 returned 0 after 0 usecs
[ 0.027998] calling tracer_alloc_buffers+0x0/0x123 @ 1
[ 0.027998] initcall tracer_alloc_buffers+0x0/0x123 returned 0 after 0 usecs
[ 0.027998] calling init_trace_printk+0x0/0xf @ 1
[ 0.027998] initcall init_trace_printk+0x0/0xf returned 0 after 0 usecs
[ 0.027998] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.028014] CPU 1 irqstacks, hard=f68f2000 soft=f68f4000
[ 0.028020] Booting Node 0, Processors #1
[ 0.028027] smpboot cpu 1: start_ip = 9b000
[ 0.001999] Initializing CPU#1
[ 0.001999] Disabled fast string operations
[ 0.200031] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.200273] CPU 2 irqstacks, hard=f6902000 soft=f6904000
[ 0.200279] #2
[ 0.200283] smpboot cpu 2: start_ip = 9b000
[ 0.001999] Initializing CPU#2
[ 0.001999] Disabled fast string operations
[ 0.271967] TSC synchronization [CPU#0 -> CPU#2]:
[ 0.271967] Measured 170 cycles TSC warp between CPUs, turning off TSC clock.
[ 0.271967] Marking TSC unstable due to check_tsc_sync_source failed
[ 0.272010] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.272221] CPU 3 irqstacks, hard=f6910000 soft=f6912000
[ 0.272227] #3 Ok.
[ 0.272231] smpboot cpu 3: start_ip = 9b000
[ 0.001999] Initializing CPU#3
[ 0.001999] Disabled fast string operations
[ 0.343068] NMI watchdog enabled, takes one hw-pmu counter.
[ 0.343126] Brought up 4 CPUs
[ 0.343132] Total of 4 processors activated (13300.04 BogoMIPS).
[ 0.344124] devtmpfs: initialized
[ 0.345141] calling init_mmap_min_addr+0x0/0x11 @ 1
[ 0.345150] initcall init_mmap_min_addr+0x0/0x11 returned 0 after 0 usecs
[ 0.345158] calling init_cpufreq_transition_notifier_list+0x0/0x18 @ 1
[ 0.345171] initcall init_cpufreq_transition_notifier_list+0x0/0x18 returned 0 after 0 usecs
[ 0.345180] calling net_ns_init+0x0/0x165 @ 1
[ 0.345189] initcall net_ns_init+0x0/0x165 returned 0 after 0 usecs
[ 0.345198] calling cpufreq_tsc+0x0/0x26 @ 1
[ 0.345206] initcall cpufreq_tsc+0x0/0x26 returned 0 after 0 usecs
[ 0.345213] calling pci_reboot_init+0x0/0x11 @ 1
[ 0.345228] initcall pci_reboot_init+0x0/0x11 returned 0 after 0 usecs
[ 0.345235] calling reboot_init+0x0/0x11 @ 1
[ 0.345260] initcall reboot_init+0x0/0x11 returned 0 after 0 usecs
[ 0.345267] calling init_lapic_sysfs+0x0/0x1b @ 1
[ 0.345275] initcall init_lapic_sysfs+0x0/0x1b returned 0 after 0 usecs
[ 0.345282] calling init_smp_flush+0x0/0x39 @ 1
[ 0.345291] initcall init_smp_flush+0x0/0x39 returned 0 after 0 usecs
[ 0.345299] calling alloc_frozen_cpus+0x0/0xc @ 1
[ 0.345307] initcall alloc_frozen_cpus+0x0/0xc returned 0 after 0 usecs
[ 0.345314] calling sysctl_init+0x0/0x13 @ 1
[ 0.345324] initcall sysctl_init+0x0/0x13 returned 0 after 0 usecs
[ 0.345331] calling ksysfs_init+0x0/0x74 @ 1
[ 0.345348] initcall ksysfs_init+0x0/0x74 returned 0 after 0 usecs
[ 0.345355] calling init_jiffies_clocksource+0x0/0xf @ 1
[ 0.345364] initcall init_jiffies_clocksource+0x0/0xf returned 0 after 0 usecs
[ 0.345371] calling pm_init+0x0/0x51 @ 1
[ 0.345395] initcall pm_init+0x0/0x51 returned 0 after 0 usecs
[ 0.345402] calling init_seccomp_filter+0x0/0x59 @ 1
[ 0.347722] initcall init_seccomp_filter+0x0/0x59 returned 0 after 1952 usecs
[ 0.347730] calling init_ftrace_syscalls+0x0/0x6f @ 1
[ 0.349993] initcall init_ftrace_syscalls+0x0/0x6f returned 0 after 2929 usecs
[ 0.350002] calling init_zero_pfn+0x0/0x3c @ 1
[ 0.350009] initcall init_zero_pfn+0x0/0x3c returned 0 after 0 usecs
[ 0.350017] calling fsnotify_init+0x0/0x1f @ 1
[ 0.350028] initcall fsnotify_init+0x0/0x1f returned 0 after 0 usecs
[ 0.350034] calling filelock_init+0x0/0x27 @ 1
[ 0.350048] initcall filelock_init+0x0/0x27 returned 0 after 0 usecs
[ 0.350055] calling init_script_binfmt+0x0/0x11 @ 1
[ 0.350066] initcall init_script_binfmt+0x0/0x11 returned 0 after 0 usecs
[ 0.350073] calling init_elf_binfmt+0x0/0x11 @ 1
[ 0.350080] initcall init_elf_binfmt+0x0/0x11 returned 0 after 0 usecs
[ 0.350087] calling debugfs_init+0x0/0x4a @ 1
[ 0.350101] initcall debugfs_init+0x0/0x4a returned 0 after 0 usecs
[ 0.350108] calling securityfs_init+0x0/0x41 @ 1
[ 0.350121] initcall securityfs_init+0x0/0x41 returned 0 after 0 usecs
[ 0.350128] calling random32_init+0x0/0xa7 @ 1
[ 0.350137] initcall random32_init+0x0/0xa7 returned 0 after 0 usecs
[ 0.350145] calling early_resume_init+0x0/0x174 @ 1
[ 0.350175] Time: 19:33:20 Date: 12/13/11
[ 0.350182] initcall early_resume_init+0x0/0x174 returned 0 after 0 usecs
[ 0.350190] calling cpufreq_core_init+0x0/0x79 @ 1
[ 0.350203] initcall cpufreq_core_init+0x0/0x79 returned 0 after 0 usecs
[ 0.350210] calling cpuidle_init+0x0/0x32 @ 1
[ 0.350224] initcall cpuidle_init+0x0/0x32 returned 0 after 0 usecs
[ 0.350231] calling sock_init+0x0/0x72 @ 1
[ 0.350299] initcall sock_init+0x0/0x72 returned 0 after 0 usecs
[ 0.350307] calling netlink_proto_init+0x0/0x15d @ 1
[ 0.350322] NET: Registered protocol family 16
[ 0.350346] initcall netlink_proto_init+0x0/0x15d returned 0 after 0 usecs
[ 0.350354] calling bdi_class_init+0x0/0x3c @ 1
[ 0.350405] initcall bdi_class_init+0x0/0x3c returned 0 after 0 usecs
[ 0.350413] calling kobject_uevent_init+0x0/0x1e @ 1
[ 0.350427] initcall kobject_uevent_init+0x0/0x1e returned 0 after 0 usecs
[ 0.350434] calling gpiolib_sysfs_init+0x0/0x74 @ 1
[ 0.350470] initcall gpiolib_sysfs_init+0x0/0x74 returned 0 after 0 usecs
[ 0.350478] calling pcibus_class_init+0x0/0x14 @ 1
[ 0.350500] initcall pcibus_class_init+0x0/0x14 returned 0 after 0 usecs
[ 0.350508] calling pci_driver_init+0x0/0xf @ 1
[ 0.350542] initcall pci_driver_init+0x0/0xf returned 0 after 0 usecs
[ 0.350550] calling backlight_class_init+0x0/0x4d @ 1
[ 0.350573] initcall backlight_class_init+0x0/0x4d returned 0 after 0 usecs
[ 0.350581] calling video_output_class_init+0x0/0x14 @ 1
[ 0.350603] initcall video_output_class_init+0x0/0x14 returned 0 after 0 usecs
[ 0.350610] calling tty_class_init+0x0/0x2b @ 1
[ 0.350631] initcall tty_class_init+0x0/0x2b returned 0 after 0 usecs
[ 0.350638] calling vtconsole_class_init+0x0/0xa9 @ 1
[ 0.350708] initcall vtconsole_class_init+0x0/0xa9 returned 0 after 0 usecs
[ 0.350716] calling wakeup_sources_debugfs_init+0x0/0x24 @ 1
[ 0.350730] initcall wakeup_sources_debugfs_init+0x0/0x24 returned 0 after 0 usecs
[ 0.350737] calling spi_init+0x0/0x7e @ 1
[ 0.350782] initcall spi_init+0x0/0x7e returned 0 after 0 usecs
[ 0.350789] calling i2c_init+0x0/0x59 @ 1
[ 0.350841] initcall i2c_init+0x0/0x59 returned 0 after 0 usecs
[ 0.350849] calling amd_postcore_init+0x0/0x133 @ 1
[ 0.350857] initcall amd_postcore_init+0x0/0x133 returned 0 after 0 usecs
[ 0.350865] calling arch_kdebugfs_init+0x0/0x1eb @ 1
[ 0.350890] initcall arch_kdebugfs_init+0x0/0x1eb returned 0 after 0 usecs
[ 0.350898] calling init_pit_clocksource+0x0/0x38 @ 1
[ 0.350906] initcall init_pit_clocksource+0x0/0x38 returned 0 after 0 usecs
[ 0.350913] calling configure_trampolines+0x0/0x1f @ 1
[ 0.350941] initcall configure_trampolines+0x0/0x1f returned 0 after 0 usecs
[ 0.350949] calling mtrr_if_init+0x0/0x4d @ 1
[ 0.350972] initcall mtrr_if_init+0x0/0x4d returned 0 after 0 usecs
[ 0.350979] calling ffh_cstate_init+0x0/0x27 @ 1
[ 0.350999] initcall ffh_cstate_init+0x0/0x27 returned 0 after 0 usecs
[ 0.351006] calling acpi_pci_init+0x0/0x64 @ 1
[ 0.351011] ACPI FADT declares the system doesn't support PCIe ASPM, so disable it
[ 0.351020] ACPI: bus type pci registered
[ 0.351027] initcall acpi_pci_init+0x0/0x64 returned 0 after 0 usecs
[ 0.351034] calling dmi_id_init+0x0/0x288 @ 1
[ 0.351136] initcall dmi_id_init+0x0/0x288 returned 0 after 0 usecs
[ 0.351144] calling pci_arch_init+0x0/0x62 @ 1
[ 0.351184] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[ 0.351192] PCI: not using MMCONFIG
[ 0.351529] PCI : PCI BIOS aera is rw and x. Use pci=nobios if you want it NX.
[ 0.351537] PCI: PCI BIOS revision 3.00 entry at 0xef753, last bus=1
[ 0.351542] PCI: Using configuration type 1 for base access
[ 0.351574] initcall pci_arch_init+0x0/0x62 returned 0 after 0 usecs
[ 0.351583] calling topology_init+0x0/0x36 @ 1
[ 0.351654] initcall topology_init+0x0/0x36 returned 0 after 0 usecs
[ 0.351662] calling mtrr_init_finialize+0x0/0x30 @ 1
[ 0.351667] mtrr: your CPUs had inconsistent variable MTRR settings
[ 0.351671] mtrr: probably your BIOS does not setup all CPUs.
[ 0.351676] mtrr: corrected configuration.
[ 0.351682] initcall mtrr_init_finialize+0x0/0x30 returned 0 after 0 usecs
[ 0.351690] calling param_sysfs_init+0x0/0x157 @ 1
[ 0.354796] initcall param_sysfs_init+0x0/0x157 returned 0 after 2929 usecs
[ 0.354806] calling pm_sysrq_init+0x0/0x1b @ 1
[ 0.354814] initcall pm_sysrq_init+0x0/0x1b returned 0 after 0 usecs
[ 0.354822] calling default_bdi_init+0x0/0x90 @ 1
[ 0.355042] initcall default_bdi_init+0x0/0x90 returned 0 after 976 usecs
[ 0.355042] calling init_bio+0x0/0xbc @ 1
[ 0.355081] bio: create slab <bio-0> at 0
[ 0.355105] initcall init_bio+0x0/0xbc returned 0 after 0 usecs
[ 0.355117] calling fsnotify_notification_init+0x0/0x76 @ 1
[ 0.355143] initcall fsnotify_notification_init+0x0/0x76 returned 0 after 0 usecs
[ 0.355151] calling cryptomgr_init+0x0/0xf @ 1
[ 0.355159] initcall cryptomgr_init+0x0/0xf returned 0 after 0 usecs
[ 0.355166] calling blk_settings_init+0x0/0x1d @ 1
[ 0.355173] initcall blk_settings_init+0x0/0x1d returned 0 after 0 usecs
[ 0.355180] calling blk_ioc_init+0x0/0x24 @ 1
[ 0.355193] initcall blk_ioc_init+0x0/0x24 returned 0 after 0 usecs
[ 0.355200] calling blk_softirq_init+0x0/0x54 @ 1
[ 0.355209] initcall blk_softirq_init+0x0/0x54 returned 0 after 0 usecs
[ 0.355217] calling blk_iopoll_setup+0x0/0x54 @ 1
[ 0.355224] initcall blk_iopoll_setup+0x0/0x54 returned 0 after 0 usecs
[ 0.355231] calling genhd_device_init+0x0/0x61 @ 1
[ 0.355275] initcall genhd_device_init+0x0/0x61 returned 0 after 0 usecs
[ 0.355275] calling gpiolib_debugfs_init+0x0/0x1f @ 1
[ 0.355275] initcall gpiolib_debugfs_init+0x0/0x1f returned 0 after 0 usecs
[ 0.355275] calling pci_slot_init+0x0/0x40 @ 1
[ 0.355275] initcall pci_slot_init+0x0/0x40 returned 0 after 0 usecs
[ 0.355275] calling fbmem_init+0x0/0x80 @ 1
[ 0.355275] initcall fbmem_init+0x0/0x80 returned 0 after 0 usecs
[ 0.355275] calling acpi_init+0x0/0x251 @ 1
[ 0.359355] ACPI: EC: Look up EC in DSDT
[ 0.363141] ACPI: Executed 1 blocks of module-level executable AML code
[ 0.366118] [Firmware Bug]: ACPI: BIOS _OSI(Linux) query ignored
[ 0.367212] ACPI: SSDT 7f494818 00789 (v01 PmRef Cpu0Ist 00003000 INTL 20100121)
[ 0.368636] ACPI: Dynamic OEM Table Load:
[ 0.368645] ACPI: SSDT (null) 00789 (v01 PmRef Cpu0Ist 00003000 INTL 20100121)
[ 0.368936] ACPI: SSDT 7f493c18 001C3 (v02 PmRef Cpu0Cst 00003001 INTL 20100121)
[ 0.369842] ACPI: Dynamic OEM Table Load:
[ 0.369850] ACPI: SSDT (null) 001C3 (v02 PmRef Cpu0Cst 00003001 INTL 20100121)
[ 0.375710] ACPI: SSDT 7f493e18 001B5 (v01 PmRef ApIst 00003000 INTL 20100121)
[ 0.376708] ACPI: Dynamic OEM Table Load:
[ 0.376717] ACPI: SSDT (null) 001B5 (v01 PmRef ApIst 00003000 INTL 20100121)
[ 0.379250] ACPI: SSDT 7f492f18 000C9 (v02 PmRef ApCst 00003000 INTL 20100121)
[ 0.380190] ACPI: Dynamic OEM Table Load:
[ 0.380199] ACPI: SSDT (null) 000C9 (v02 PmRef ApCst 00003000 INTL 20100121)
[ 0.387133] ACPI: Interpreter enabled
[ 0.387147] ACPI: (supports S0 S3 S5)
[ 0.387192] ACPI: Using IOAPIC for interrupt routing
[ 0.387242] PCI: MMCONFIG for domain 0000 [bus 00-ff] at [mem 0xe0000000-0xefffffff] (base 0xe0000000)
[ 0.387987] PCI: MMCONFIG at [mem 0xe0000000-0xefffffff] reserved in ACPI motherboard resources
[ 0.387993] PCI: Using MMCONFIG for extended config space
[ 0.407042] ACPI: Power Resource [FN00] (off)
[ 0.409038] ACPI: Power Resource [FN01] (on)
[ 0.412037] ACPI: Power Resource [FN02] (on)
[ 0.415019] ACPI: Power Resource [FN03] (on)
[ 0.417035] ACPI: Power Resource [FN04] (on)
[ 0.451750] ACPI: EC: GPE = 0x16, I/O: command/status = 0x66, data = 0x62
[ 0.451834] initcall acpi_init+0x0/0x251 returned 0 after 93735 usecs
[ 0.451844] calling dock_init+0x0/0x81 @ 1
[ 0.452248] ACPI: No dock devices found.
[ 0.452257] initcall dock_init+0x0/0x81 returned 0 after 976 usecs
[ 0.452264] calling acpi_pci_root_init+0x0/0x25 @ 1
[ 0.452275] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[ 0.453364] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[ 0.455130] pci_root PNP0A08:00: host bridge window [io 0x0000-0x0cf7]
[ 0.455139] pci_root PNP0A08:00: host bridge window [io 0x0d00-0xffff]
[ 0.455146] pci_root PNP0A08:00: host bridge window [mem 0x000a0000-0x000bffff]
[ 0.455155] pci_root PNP0A08:00: host bridge window [mem 0x80000000-0xfebfffff]
[ 0.455185] pci 0000:00:00.0: [8086:a010] type 0 class 0x000600
[ 0.455249] pci 0000:00:02.0: [8086:a011] type 0 class 0x000300
[ 0.455267] pci 0000:00:02.0: reg 10: [mem 0x92180000-0x921fffff]
[ 0.455279] pci 0000:00:02.0: reg 14: [io 0x20c0-0x20c7]
[ 0.455290] pci 0000:00:02.0: reg 18: [mem 0x80000000-0x8fffffff pref]
[ 0.455302] pci 0000:00:02.0: reg 1c: [mem 0x92000000-0x920fffff]
[ 0.455354] pci 0000:00:02.1: [8086:a012] type 0 class 0x000380
[ 0.455369] pci 0000:00:02.1: reg 10: [mem 0x92100000-0x9217ffff]
[ 0.455498] pci 0000:00:1b.0: [8086:27d8] type 0 class 0x000403
[ 0.455529] pci 0000:00:1b.0: reg 10: [mem 0x92200000-0x92203fff 64bit]
[ 0.455628] pci 0000:00:1b.0: PME# supported from D0 D3hot D3cold
[ 0.455671] pci 0000:00:1c.0: [8086:27d0] type 1 class 0x000604
[ 0.455779] pci 0000:00:1c.0: PME# supported from D0 D3hot D3cold
[ 0.455835] pci 0000:00:1d.0: [8086:27c8] type 0 class 0x000c03
[ 0.455915] pci 0000:00:1d.0: reg 20: [io 0x2080-0x209f]
[ 0.455990] pci 0000:00:1d.1: [8086:27c9] type 0 class 0x000c03
[ 0.456077] pci 0000:00:1d.1: reg 20: [io 0x2060-0x207f]
[ 0.456141] pci 0000:00:1d.2: [8086:27ca] type 0 class 0x000c03
[ 0.456222] pci 0000:00:1d.2: reg 20: [io 0x2040-0x205f]
[ 0.456285] pci 0000:00:1d.3: [8086:27cb] type 0 class 0x000c03
[ 0.456366] pci 0000:00:1d.3: reg 20: [io 0x2020-0x203f]
[ 0.456442] pci 0000:00:1d.7: [8086:27cc] type 0 class 0x000c03
[ 0.457632] pci 0000:00:1d.7: reg 10: [mem 0x92205000-0x922053ff]
[ 0.465983] pci 0000:00:1d.7: PME# supported from D0 D3hot D3cold
[ 0.466030] pci 0000:00:1e.0: [8086:2448] type 1 class 0x000604
[ 0.466137] pci 0000:00:1f.0: [8086:27bc] type 0 class 0x000601
[ 0.466316] pci 0000:00:1f.2: [8086:27c1] type 0 class 0x000106
[ 0.466347] pci 0000:00:1f.2: reg 10: [io 0x20b8-0x20bf]
[ 0.466365] pci 0000:00:1f.2: reg 14: [io 0x20cc-0x20cf]
[ 0.466382] pci 0000:00:1f.2: reg 18: [io 0x20b0-0x20b7]
[ 0.466399] pci 0000:00:1f.2: reg 1c: [io 0x20c8-0x20cb]
[ 0.466416] pci 0000:00:1f.2: reg 20: [io 0x20a0-0x20af]
[ 0.466434] pci 0000:00:1f.2: reg 24: [mem 0x92204000-0x922043ff]
[ 0.466486] pci 0000:00:1f.2: PME# supported from D3hot
[ 0.466520] pci 0000:00:1f.3: [8086:27da] type 0 class 0x000c05
[ 0.466599] pci 0000:00:1f.3: reg 20: [io 0x2000-0x201f]
[ 0.466756] pci 0000:01:00.0: [168c:0030] type 0 class 0x000280
[ 0.466793] pci 0000:01:00.0: reg 10: [mem 0x91000000-0x9101ffff 64bit]
[ 0.466868] pci 0000:01:00.0: reg 30: [mem 0xffff0000-0xffffffff pref]
[ 0.466960] pci 0000:01:00.0: supports D1
[ 0.466966] pci 0000:01:00.0: PME# supported from D0 D1 D3hot
[ 0.467003] pci 0000:00:1c.0: PCI bridge to [bus 01-01]
[ 0.467012] pci 0000:00:1c.0: bridge window [io 0x1000-0x1fff]
[ 0.467023] pci 0000:00:1c.0: bridge window [mem 0x91000000-0x91ffffff]
[ 0.467038] pci 0000:00:1c.0: bridge window [mem 0x90000000-0x90ffffff 64bit pref]
[ 0.467150] pci 0000:00:1e.0: PCI bridge to [bus 02-02] (subtractive decode)
[ 0.467160] pci 0000:00:1e.0: bridge window [io 0xf000-0x0000] (disabled)
[ 0.467171] pci 0000:00:1e.0: bridge window [mem 0xfff00000-0x000fffff] (disabled)
[ 0.467184] pci 0000:00:1e.0: bridge window [mem 0xfff00000-0x000fffff pref] (disabled)
[ 0.467192] pci 0000:00:1e.0: bridge window [io 0x0000-0x0cf7] (subtractive decode)
[ 0.467199] pci 0000:00:1e.0: bridge window [io 0x0d00-0xffff] (subtractive decode)
[ 0.467207] pci 0000:00:1e.0: bridge window [mem 0x000a0000-0x000bffff] (subtractive decode)
[ 0.467215] pci 0000:00:1e.0: bridge window [mem 0x80000000-0xfebfffff] (subtractive decode)
[ 0.467237] pci_bus 0000:00: on NUMA node 0
[ 0.467247] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0._PRT]
[ 0.467579] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.P32_._PRT]
[ 0.467793] ACPI: PCI Interrupt Routing Table [\_SB_.PCI0.EXP1._PRT]
[ 0.468205] pci0000:00: Requesting ACPI _OSC control (0x1d)
[ 0.468709] pci0000:00: ACPI _OSC control (0x1c) granted
[ 0.476877] initcall acpi_pci_root_init+0x0/0x25 returned 0 after 23433 usecs
[ 0.476887] calling acpi_pci_link_init+0x0/0x3a @ 1
[ 0.477042] ACPI: PCI Interrupt Link [LNKA] (IRQs 3 4 5 7 9 10 *11 12)
[ 0.477209] ACPI: PCI Interrupt Link [LNKB] (IRQs 3 4 5 7 9 *10 11 12)
[ 0.477368] ACPI: PCI Interrupt Link [LNKC] (IRQs 3 4 5 7 9 10 *11 12)
[ 0.477527] ACPI: PCI Interrupt Link [LNKD] (IRQs 3 4 5 7 9 10 *11 12)
[ 0.477686] ACPI: PCI Interrupt Link [LNKE] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
[ 0.477855] ACPI: PCI Interrupt Link [LNKF] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
[ 0.478031] ACPI: PCI Interrupt Link [LNKG] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
[ 0.478200] ACPI: PCI Interrupt Link [LNKH] (IRQs 3 4 5 7 9 10 11 12) *0, disabled.
[ 0.478295] initcall acpi_pci_link_init+0x0/0x3a returned 0 after 1952 usecs
[ 0.478304] calling pnp_init+0x0/0xf @ 1
[ 0.478341] initcall pnp_init+0x0/0xf returned 0 after 0 usecs
[ 0.478348] calling misc_init+0x0/0x9b @ 1
[ 0.478384] initcall misc_init+0x0/0x9b returned 0 after 0 usecs
[ 0.478392] calling cn_init+0x0/0x89 @ 1
[ 0.478418] initcall cn_init+0x0/0x89 returned 0 after 0 usecs
[ 0.478425] calling init_scsi+0x0/0x84 @ 1
[ 0.478642] SCSI subsystem initialized
[ 0.478651] initcall init_scsi+0x0/0x84 returned 0 after 0 usecs
[ 0.478658] calling ata_init+0x0/0x293 @ 1
[ 0.478693] libata version 3.00 loaded.
[ 0.478693] initcall ata_init+0x0/0x293 returned 0 after 0 usecs
[ 0.478693] calling phy_init+0x0/0x28 @ 1
[ 0.478975] initcall phy_init+0x0/0x28 returned 0 after 976 usecs
[ 0.478982] calling usb_init+0x0/0x159 @ 1
[ 0.479084] usbcore: registered new interface driver usbfs
[ 0.479127] usbcore: registered new interface driver hub
[ 0.479143] usbcore: registered new device driver usb
[ 0.479143] initcall usb_init+0x0/0x159 returned 0 after 0 usecs
[ 0.479143] calling serio_init+0x0/0x28 @ 1
[ 0.479143] initcall serio_init+0x0/0x28 returned 0 after 0 usecs
[ 0.479143] calling input_init+0x0/0xe4 @ 1
[ 0.479143] initcall input_init+0x0/0xe4 returned 0 after 0 usecs
[ 0.479143] calling rtc_init+0x0/0x59 @ 1
[ 0.479159] initcall rtc_init+0x0/0x59 returned 0 after 0 usecs
[ 0.479166] calling power_supply_class_init+0x0/0x35 @ 1
[ 0.479190] initcall power_supply_class_init+0x0/0x35 returned 0 after 0 usecs
[ 0.479198] calling hwmon_init+0x0/0xd7 @ 1
[ 0.479227] initcall hwmon_init+0x0/0xd7 returned 0 after 0 usecs
[ 0.479234] calling leds_init+0x0/0x39 @ 1
[ 0.479257] initcall leds_init+0x0/0x39 returned 0 after 0 usecs
[ 0.479264] calling chromeos_acpi_init+0x0/0xac @ 1
[ 0.479566] chromeos_acpi: registering CHSW 0
[ 0.480563] chromeos_acpi: registering VBNV 0
[ 0.480572] chromeos_acpi: registering VBNV 1
[ 0.483704] chromeos_acpi: truncating buffer from 3750 to 1336
[ 0.484276] chromeos_acpi: installed
[ 0.484287] initcall chromeos_acpi_init+0x0/0xac returned 0 after 4882 usecs
[ 0.484295] calling chromeos_ramoops_init+0x0/0x26 @ 1
[ 0.484346] initcall chromeos_ramoops_init+0x0/0x26 returned 0 after 0 usecs
[ 0.484354] calling init_soundcore+0x0/0x2b @ 1
[ 0.484380] initcall init_soundcore+0x0/0x2b returned 0 after 0 usecs
[ 0.484388] calling alsa_sound_init+0x0/0x84 @ 1
[ 0.484417] Advanced Linux Sound Architecture Driver Version 1.0.24.
[ 0.484426] initcall alsa_sound_init+0x0/0x84 returned 0 after 0 usecs
[ 0.484434] calling pci_subsys_init+0x0/0x44 @ 1
[ 0.484438] PCI: Using ACPI for IRQ routing
[ 0.484446] PCI: pci_cache_line_size set to 64 bytes
[ 0.484560] reserve RAM buffer: 000000000008f000 - 000000000008ffff
[ 0.484568] reserve RAM buffer: 000000007f33f000 - 000000007fffffff
[ 0.484576] reserve RAM buffer: 000000007f600000 - 000000007fffffff
[ 0.484585] initcall pci_subsys_init+0x0/0x44 returned 0 after 0 usecs
[ 0.484593] calling proto_init+0x0/0xf @ 1
[ 0.484605] initcall proto_init+0x0/0xf returned 0 after 0 usecs
[ 0.484613] calling net_dev_init+0x0/0x1c9 @ 1
[ 0.484831] initcall net_dev_init+0x0/0x1c9 returned 0 after 0 usecs
[ 0.484840] calling neigh_init+0x0/0x66 @ 1
[ 0.484847] initcall neigh_init+0x0/0x66 returned 0 after 0 usecs
[ 0.484854] calling pktsched_init+0x0/0xce @ 1
[ 0.484866] initcall pktsched_init+0x0/0xce returned 0 after 0 usecs
[ 0.484872] calling genl_init+0x0/0x73 @ 1
[ 0.484898] initcall genl_init+0x0/0x73 returned 0 after 0 usecs
[ 0.484906] calling wireless_nlevent_init+0x0/0xf @ 1
[ 0.484914] initcall wireless_nlevent_init+0x0/0xf returned 0 after 0 usecs
[ 0.484922] calling rfkill_init+0x0/0x6a @ 1
[ 0.485036] initcall rfkill_init+0x0/0x6a returned 0 after 976 usecs
[ 0.485046] calling sysctl_init+0x0/0x3b @ 1
[ 0.485055] initcall sysctl_init+0x0/0x3b returned 0 after 0 usecs
[ 0.485063] calling print_ICs+0x0/0x41b @ 1
[ 0.485071] initcall print_ICs+0x0/0x41b returned 0 after 0 usecs
[ 0.485078] calling hpet_late_init+0x0/0x101 @ 1
[ 0.485086] HPET: 3 timers in total, 0 timers will be used for per-cpu timer
[ 0.485097] hpet0: at MMIO 0xfed00000, IRQs 2, 8, 0
[ 0.485109] hpet0: 3 comparators, 64-bit 14.318180 MHz counter
[ 0.490993] initcall hpet_late_init+0x0/0x101 returned 0 after 5858 usecs
[ 0.490993] calling init_amd_nbs+0x0/0x9f @ 1
[ 0.490993] initcall init_amd_nbs+0x0/0x9f returned 0 after 0 usecs
[ 0.490998] calling clocksource_done_booting+0x0/0x4f @ 1
[ 0.491005] Switching to clocksource hpet
[ 0.491045] initcall clocksource_done_booting+0x0/0x4f returned 0 after 6 usecs
[ 0.491053] calling ftrace_init_debugfs+0x0/0x93 @ 1
[ 0.491097] initcall ftrace_init_debugfs+0x0/0x93 returned 0 after 33 usecs
[ 0.491105] calling rb_init_debugfs+0x0/0x27 @ 1
[ 0.491122] initcall rb_init_debugfs+0x0/0x27 returned 0 after 8 usecs
[ 0.491129] calling tracer_init_debugfs+0x0/0x2cf @ 1
[ 0.491318] initcall tracer_init_debugfs+0x0/0x2cf returned 0 after 175 usecs
[ 0.491326] calling init_trace_printk_function_export+0x0/0x2a @ 1
[ 0.491341] initcall init_trace_printk_function_export+0x0/0x2a returned 0 after 4 usecs
[ 0.491348] calling event_trace_init+0x0/0x231 @ 1
[ 0.491742] Switched to NOHz mode on CPU #3
[ 0.491767] Switched to NOHz mode on CPU #0
[ 0.491822] Switched to NOHz mode on CPU #2
[ 0.491869] Switched to NOHz mode on CPU #1
[ 0.509764] initcall event_trace_init+0x0/0x231 returned 0 after 18062 usecs
[ 0.509776] calling init_pipe_fs+0x0/0x3d @ 1
[ 0.509820] initcall init_pipe_fs+0x0/0x3d returned 0 after 32 usecs
[ 0.509827] calling eventpoll_init+0x0/0xfd @ 1
[ 0.509853] initcall eventpoll_init+0x0/0xfd returned 0 after 17 usecs
[ 0.509860] calling anon_inode_init+0x0/0xfa @ 1
[ 0.509887] initcall anon_inode_init+0x0/0xfa returned 0 after 17 usecs
[ 0.509895] calling blk_scsi_ioctl_init+0x0/0x288 @ 1
[ 0.509906] initcall blk_scsi_ioctl_init+0x0/0x288 returned 0 after 2 usecs
[ 0.509914] calling acpi_event_init+0x0/0x6c @ 1
[ 0.509947] initcall acpi_event_init+0x0/0x6c returned 0 after 23 usecs
[ 0.509954] calling pnp_system_init+0x0/0xf @ 1
[ 0.509997] initcall pnp_system_init+0x0/0xf returned 0 after 33 usecs
[ 0.510039] calling pnpacpi_init+0x0/0x7b @ 1
[ 0.510045] pnp: PnP ACPI init
[ 0.510086] ACPI: bus type pnp registered
[ 0.512807] pnp 00:00: [bus 00-ff]
[ 0.512822] pnp 00:00: [io 0x0000-0x0cf7 window]
[ 0.512833] pnp 00:00: [io 0x0cf8-0x0cff]
[ 0.512843] pnp 00:00: [io 0x0d00-0xffff window]
[ 0.512855] pnp 00:00: [mem 0x000a0000-0x000bffff window]
[ 0.512866] pnp 00:00: [mem 0x000c0000-0x000c3fff window]
[ 0.512876] pnp 00:00: [mem 0x000c4000-0x000c7fff window]
[ 0.512887] pnp 00:00: [mem 0x000c8000-0x000cbfff window]
[ 0.512898] pnp 00:00: [mem 0x000cc000-0x000cffff window]
[ 0.512909] pnp 00:00: [mem 0x000d0000-0x000d3fff window]
[ 0.512920] pnp 00:00: [mem 0x000d4000-0x000d7fff window]
[ 0.512931] pnp 00:00: [mem 0x000d8000-0x000dbfff window]
[ 0.512942] pnp 00:00: [mem 0x000dc000-0x000dffff window]
[ 0.512952] pnp 00:00: [mem 0x000e0000-0x000e3fff window]
[ 0.512963] pnp 00:00: [mem 0x000e4000-0x000e7fff window]
[ 0.512974] pnp 00:00: [mem 0x000e8000-0x000ebfff window]
[ 0.512985] pnp 00:00: [mem 0x000ec000-0x000effff window]
[ 0.512996] pnp 00:00: [mem 0x000f0000-0x000fffff window]
[ 0.513037] pnp 00:00: [mem 0x80000000-0xfebfffff window]
[ 0.513282] pnp 00:00: Plug and Play ACPI device, IDs PNP0a08 PNP0a03 (active)
[ 0.513847] pnp 00:01: [io 0x164e-0x164f]
[ 0.513859] pnp 00:01: [io 0x0061]
[ 0.513869] pnp 00:01: [io 0x0070]
[ 0.513878] pnp 00:01: [io 0x0080]
[ 0.513887] pnp 00:01: [io 0x0092]
[ 0.513896] pnp 00:01: [io 0x00b2-0x00b3]
[ 0.513905] pnp 00:01: [io 0x0063]
[ 0.513914] pnp 00:01: [io 0x0065]
[ 0.513923] pnp 00:01: [io 0x0067]
[ 0.513933] pnp 00:01: [io 0x0600-0x060f]
[ 0.513942] pnp 00:01: [io 0x0610]
[ 0.513951] pnp 00:01: [io 0x0800-0x080f]
[ 0.513961] pnp 00:01: [io 0x0810-0x0817]
[ 0.513971] pnp 00:01: [io 0x0a00-0x0a01]
[ 0.513980] pnp 00:01: [io 0x0400-0x047f]
[ 0.513990] pnp 00:01: [io 0x0500-0x053f]
[ 0.514000] pnp 00:01: [mem 0xe0000000-0xefffffff]
[ 0.514040] pnp 00:01: [mem 0xfed1c000-0xfed1ffff]
[ 0.514050] pnp 00:01: [mem 0xfed14000-0xfed17fff]
[ 0.514061] pnp 00:01: [mem 0xfed18000-0xfed18fff]
[ 0.514071] pnp 00:01: [mem 0xfed19000-0xfed19fff]
[ 0.514081] pnp 00:01: [mem 0xfec00000-0xfec00fff]
[ 0.514091] pnp 00:01: [mem 0xfee00000-0xfee00fff]
[ 0.514138] pnp 00:01: disabling [io 0x164e-0x164f] because it overlaps 0000:00:1c.0 BAR 7 [io 0x1000-0x1fff]
[ 0.514326] system 00:01: [io 0x0600-0x060f] has been reserved
[ 0.514340] system 00:01: [io 0x0610] has been reserved
[ 0.514353] system 00:01: [io 0x0800-0x080f] has been reserved
[ 0.514366] system 00:01: [io 0x0810-0x0817] has been reserved
[ 0.514378] system 00:01: [io 0x0a00-0x0a01] has been reserved
[ 0.514391] system 00:01: [io 0x0400-0x047f] has been reserved
[ 0.514404] system 00:01: [io 0x0500-0x053f] has been reserved
[ 0.514419] system 00:01: [mem 0xe0000000-0xefffffff] has been reserved
[ 0.514433] system 00:01: [mem 0xfed1c000-0xfed1ffff] has been reserved
[ 0.514446] system 00:01: [mem 0xfed14000-0xfed17fff] has been reserved
[ 0.514460] system 00:01: [mem 0xfed18000-0xfed18fff] has been reserved
[ 0.514473] system 00:01: [mem 0xfed19000-0xfed19fff] has been reserved
[ 0.514487] system 00:01: [mem 0xfec00000-0xfec00fff] could not be reserved
[ 0.514501] system 00:01: [mem 0xfee00000-0xfee00fff] has been reserved
[ 0.514516] system 00:01: Plug and Play ACPI device, IDs PNP0c02 (active)
[ 0.514569] pnp 00:02: [io 0x0000-0x001f]
[ 0.514580] pnp 00:02: [io 0x0081-0x0091]
[ 0.514589] pnp 00:02: [io 0x0093-0x009f]
[ 0.514599] pnp 00:02: [io 0x00c0-0x00df]
[ 0.514609] pnp 00:02: [dma 4]
[ 0.514724] pnp 00:02: Plug and Play ACPI device, IDs PNP0200 (active)
[ 0.514833] pnp 00:03: [io 0x0070-0x0077]
[ 0.514951] pnp 00:03: Plug and Play ACPI device, IDs PNP0b00 (active)
[ 0.515268] pnp 00:04: [irq 0 disabled]
[ 0.515304] pnp 00:04: [irq 8]
[ 0.515315] pnp 00:04: [mem 0xfed00000-0xfed003ff]
[ 0.515437] pnp 00:04: Plug and Play ACPI device, IDs PNP0103 (active)
[ 0.515495] pnp 00:05: [io 0x00f0]
[ 0.515516] pnp 00:05: [irq 13]
[ 0.515637] pnp 00:05: Plug and Play ACPI device, IDs PNP0c04 (active)
[ 0.515692] pnp 00:06: [mem 0xff800000-0xffffffff]
[ 0.515811] pnp 00:06: Plug and Play ACPI device, IDs INT0800 (active)
[ 0.517110] pnp 00:07: [io 0x0060]
[ 0.517118] pnp 00:07: [io 0x0064]
[ 0.517138] pnp 00:07: [irq 1]
[ 0.517250] pnp 00:07: Plug and Play ACPI device, IDs PNP0303 (active)
[ 0.517306] pnp 00:08: [irq 12]
[ 0.517383] pnp 00:08: Plug and Play ACPI device, IDs PNP0f13 (active)
[ 0.517637] pnp: PnP ACPI: found 9 devices
[ 0.517642] ACPI: ACPI bus type pnp unregistered
[ 0.517655] initcall pnpacpi_init+0x0/0x7b returned 0 after 7426 usecs
[ 0.517663] calling chr_dev_init+0x0/0xa5 @ 1
[ 0.522104] initcall chr_dev_init+0x0/0xa5 returned 0 after 4324 usecs
[ 0.522114] calling firmware_class_init+0x0/0x14 @ 1
[ 0.522148] initcall firmware_class_init+0x0/0x14 returned 0 after 24 usecs
[ 0.522156] calling thermal_init+0x0/0x5f @ 1
[ 0.522194] initcall thermal_init+0x0/0x5f returned 0 after 28 usecs
[ 0.522202] calling cpufreq_gov_performance_init+0x0/0xf @ 1
[ 0.522213] initcall cpufreq_gov_performance_init+0x0/0xf returned 0 after 2 usecs
[ 0.522221] calling cpufreq_gov_dbs_init+0x0/0x57 @ 1
[ 0.522234] initcall cpufreq_gov_dbs_init+0x0/0x57 returned 0 after 4 usecs
[ 0.522242] calling init_acpi_pm_clocksource+0x0/0x170 @ 1
[ 0.556803] initcall init_acpi_pm_clocksource+0x0/0x170 returned 0 after 33742 usecs
[ 0.556812] calling pcibios_assign_resources+0x0/0x81 @ 1
[ 0.556850] pci 0000:01:00.0: no compatible bridge window for [mem 0xffff0000-0xffffffff pref]
[ 0.556858] PCI: max bus depth: 1 pci_try_num: 2
[ 0.556901] pci 0000:01:00.0: BAR 6: assigned [mem 0x90000000-0x9000ffff pref]
[ 0.556909] pci 0000:00:1c.0: PCI bridge to [bus 01-01]
[ 0.556917] pci 0000:00:1c.0: bridge window [io 0x1000-0x1fff]
[ 0.556929] pci 0000:00:1c.0: bridge window [mem 0x91000000-0x91ffffff]
[ 0.556940] pci 0000:00:1c.0: bridge window [mem 0x90000000-0x90ffffff 64bit pref]
[ 0.556954] pci 0000:00:1e.0: PCI bridge to [bus 02-02]
[ 0.556959] pci 0000:00:1e.0: bridge window [io disabled]
[ 0.556968] pci 0000:00:1e.0: bridge window [mem disabled]
[ 0.556977] pci 0000:00:1e.0: bridge window [mem pref disabled]
[ 0.557056] pci_bus 0000:00: resource 4 [io 0x0000-0x0cf7]
[ 0.557063] pci_bus 0000:00: resource 5 [io 0x0d00-0xffff]
[ 0.557070] pci_bus 0000:00: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.557077] pci_bus 0000:00: resource 7 [mem 0x80000000-0xfebfffff]
[ 0.557084] pci_bus 0000:01: resource 0 [io 0x1000-0x1fff]
[ 0.557090] pci_bus 0000:01: resource 1 [mem 0x91000000-0x91ffffff]
[ 0.557097] pci_bus 0000:01: resource 2 [mem 0x90000000-0x90ffffff 64bit pref]
[ 0.557105] pci_bus 0000:02: resource 4 [io 0x0000-0x0cf7]
[ 0.557111] pci_bus 0000:02: resource 5 [io 0x0d00-0xffff]
[ 0.557118] pci_bus 0000:02: resource 6 [mem 0x000a0000-0x000bffff]
[ 0.557124] pci_bus 0000:02: resource 7 [mem 0x80000000-0xfebfffff]
[ 0.557135] initcall pcibios_assign_resources+0x0/0x81 returned 0 after 304 usecs
[ 0.557143] calling sysctl_core_init+0x0/0x2d @ 1
[ 0.557164] initcall sysctl_core_init+0x0/0x2d returned 0 after 11 usecs
[ 0.557171] calling inet_init+0x0/0x23b @ 1
[ 0.557223] NET: Registered protocol family 2
[ 0.557289] IP route cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.557737] TCP established hash table entries: 262144 (order: 9, 2097152 bytes)
[ 0.559724] TCP bind hash table entries: 65536 (order: 8, 1310720 bytes)
[ 0.560868] TCP: Hash tables configured (established 262144 bind 65536)
[ 0.560876] TCP reno registered
[ 0.560892] UDP hash table entries: 1024 (order: 3, 49152 bytes)
[ 0.560942] UDP-Lite hash table entries: 1024 (order: 3, 49152 bytes)
[ 0.561249] initcall inet_init+0x0/0x23b returned 0 after 3961 usecs
[ 0.561259] calling af_unix_init+0x0/0x47 @ 1
[ 0.561269] NET: Registered protocol family 1
[ 0.561285] initcall af_unix_init+0x0/0x47 returned 0 after 16 usecs
[ 0.561294] calling pci_apply_final_quirks+0x0/0xe0 @ 1
[ 0.561322] pci 0000:00:02.0: Boot video device
[ 0.573114] PCI: CLS 64 bytes, default 64
[ 0.573126] initcall pci_apply_final_quirks+0x0/0xe0 returned 0 after 11543 usecs
[ 0.573135] calling populate_rootfs+0x0/0x85 @ 1
[ 0.573263] initcall populate_rootfs+0x0/0x85 returned 0 after 114 usecs
[ 0.573271] calling pci_iommu_init+0x0/0x34 @ 1
[ 0.573282] initcall pci_iommu_init+0x0/0x34 returned 0 after 1 usecs
[ 0.573289] calling i8259A_init_ops+0x0/0x1d @ 1
[ 0.573300] initcall i8259A_init_ops+0x0/0x1d returned 0 after 2 usecs
[ 0.573307] calling sbf_init+0x0/0xdb @ 1
[ 0.573317] Simple Boot Flag at 0x44 set to 0x80
[ 0.573328] initcall sbf_init+0x0/0xdb returned 0 after 11 usecs
[ 0.573335] calling init_tsc_clocksource+0x0/0x58 @ 1
[ 0.573355] initcall init_tsc_clocksource+0x0/0x58 returned 0 after 10 usecs
[ 0.573363] calling add_rtc_cmos+0x0/0x70 @ 1
[ 0.573377] initcall add_rtc_cmos+0x0/0x70 returned 0 after 5 usecs
[ 0.573383] calling i8237A_init_ops+0x0/0x11 @ 1
[ 0.573409] initcall i8237A_init_ops+0x0/0x11 returned 0 after 16 usecs
[ 0.573422] calling cache_sysfs_init+0x0/0x55 @ 1
[ 0.573845] initcall cache_sysfs_init+0x0/0x55 returned 0 after 403 usecs
[ 0.573853] calling mcheck_init_device+0x0/0xd3 @ 1
[ 0.574117] initcall mcheck_init_device+0x0/0xd3 returned 0 after 246 usecs
[ 0.574126] calling thermal_throttle_init_device+0x0/0x77 @ 1
[ 0.574150] initcall thermal_throttle_init_device+0x0/0x77 returned 0 after 14 usecs
[ 0.574158] calling msr_init+0x0/0x126 @ 1
[ 0.574492] initcall msr_init+0x0/0x126 returned 0 after 316 usecs
[ 0.574500] calling cpuid_init+0x0/0x126 @ 1
[ 0.574771] initcall cpuid_init+0x0/0x126 returned 0 after 255 usecs
[ 0.574780] calling ioapic_init_ops+0x0/0x11 @ 1
[ 0.574790] initcall ioapic_init_ops+0x0/0x11 returned 0 after 1 usecs
[ 0.574798] calling microcode_init+0x0/0x10d @ 1
[ 0.574862] microcode: CPU0 sig=0x106ca, pf=0x10, revision=0x107
[ 0.574878] microcode: CPU1 sig=0x106ca, pf=0x10, revision=0x107
[ 0.574902] microcode: CPU2 sig=0x106ca, pf=0x10, revision=0x107
[ 0.574926] microcode: CPU3 sig=0x106ca, pf=0x10, revision=0x107
[ 0.574991] microcode: Microcode Update Driver: v2.00 <tigran@aivazian.fsnet.co.uk>, Peter Oruba
[ 0.575003] initcall microcode_init+0x0/0x10d returned 0 after 191 usecs
[ 0.575034] calling start_periodic_check_for_corruption+0x0/0x40 @ 1
[ 0.575045] initcall start_periodic_check_for_corruption+0x0/0x40 returned 0 after 1 usecs
[ 0.575052] calling aes_init+0x0/0xf @ 1
[ 0.575225] initcall aes_init+0x0/0xf returned 0 after 155 usecs
[ 0.575238] calling proc_schedstat_init+0x0/0x1c @ 1
[ 0.575263] initcall proc_schedstat_init+0x0/0x1c returned 0 after 12 usecs
[ 0.575275] calling proc_execdomains_init+0x0/0x1c @ 1
[ 0.575294] initcall proc_execdomains_init+0x0/0x1c returned 0 after 5 usecs
[ 0.575306] calling ioresources_init+0x0/0x31 @ 1
[ 0.575329] initcall ioresources_init+0x0/0x31 returned 0 after 12 usecs
[ 0.575336] calling uid_cache_init+0x0/0x78 @ 1
[ 0.575351] initcall uid_cache_init+0x0/0x78 returned 0 after 7 usecs
[ 0.575358] calling init_posix_timers+0x0/0x184 @ 1
[ 0.575382] initcall init_posix_timers+0x0/0x184 returned 0 after 14 usecs
[ 0.575389] calling init_posix_cpu_timers+0x0/0x9b @ 1
[ 0.575399] initcall init_posix_cpu_timers+0x0/0x9b returned 0 after 1 usecs
[ 0.575406] calling nsproxy_cache_init+0x0/0x27 @ 1
[ 0.575417] initcall nsproxy_cache_init+0x0/0x27 returned 0 after 2 usecs
[ 0.575425] calling create_proc_profile+0x0/0x1f9 @ 1
[ 0.575436] initcall create_proc_profile+0x0/0x1f9 returned 0 after 1 usecs
[ 0.575443] calling timekeeping_init_ops+0x0/0x11 @ 1
[ 0.575453] initcall timekeeping_init_ops+0x0/0x11 returned 0 after 1 usecs
[ 0.575460] calling init_clocksource_sysfs+0x0/0x43 @ 1
[ 0.575507] initcall init_clocksource_sysfs+0x0/0x43 returned 0 after 37 usecs
[ 0.575515] calling init_timer_list_procfs+0x0/0x27 @ 1
[ 0.575528] initcall init_timer_list_procfs+0x0/0x27 returned 0 after 4 usecs
[ 0.575535] calling alarmtimer_init+0x0/0x10a @ 1
[ 0.575628] initcall alarmtimer_init+0x0/0x10a returned 0 after 82 usecs
[ 0.575636] calling init_tstats_procfs+0x0/0x27 @ 1
[ 0.575653] initcall init_tstats_procfs+0x0/0x27 returned 0 after 7 usecs
[ 0.575660] calling futex_init+0x0/0x5b @ 1
[ 0.575677] initcall futex_init+0x0/0x5b returned 0 after 9 usecs
[ 0.575684] calling proc_dma_init+0x0/0x1c @ 1
[ 0.575697] initcall proc_dma_init+0x0/0x1c returned 0 after 4 usecs
[ 0.575704] calling proc_modules_init+0x0/0x1c @ 1
[ 0.575716] initcall proc_modules_init+0x0/0x1c returned 0 after 3 usecs
[ 0.575723] calling kallsyms_init+0x0/0x1f @ 1
[ 0.575735] initcall kallsyms_init+0x0/0x1f returned 0 after 3 usecs
[ 0.575742] calling user_namespaces_init+0x0/0x27 @ 1
[ 0.575761] initcall user_namespaces_init+0x0/0x27 returned 0 after 10 usecs
[ 0.575768] calling pid_namespaces_init+0x0/0x27 @ 1
[ 0.575779] initcall pid_namespaces_init+0x0/0x27 returned 0 after 1 usecs
[ 0.575786] calling hung_task_init+0x0/0x44 @ 1
[ 0.575883] initcall hung_task_init+0x0/0x44 returned 0 after 80 usecs
[ 0.575896] calling utsname_sysctl_init+0x0/0x11 @ 1
[ 0.575917] initcall utsname_sysctl_init+0x0/0x11 returned 0 after 7 usecs
[ 0.575931] calling init_tracepoints+0x0/0x14 @ 1
[ 0.575947] initcall init_tracepoints+0x0/0x14 returned 0 after 2 usecs
[ 0.575959] calling ftrace_mod_cmd_init+0x0/0xf @ 1
[ 0.575975] initcall ftrace_mod_cmd_init+0x0/0xf returned 0 after 2 usecs
[ 0.575986] calling init_events+0x0/0x57 @ 1
[ 0.576008] initcall init_events+0x0/0x57 returned 0 after 9 usecs
[ 0.576028] calling init_function_trace+0x0/0x35 @ 1
[ 0.576060] initcall init_function_trace+0x0/0x35 returned 0 after 3 usecs
[ 0.576067] calling init_blk_tracer+0x0/0x4b @ 1
[ 0.576078] initcall init_blk_tracer+0x0/0x4b returned 0 after 2 usecs
[ 0.576085] calling perf_event_sysfs_init+0x0/0x80 @ 1
[ 0.576266] initcall perf_event_sysfs_init+0x0/0x80 returned 0 after 167 usecs
[ 0.576276] calling init_per_zone_wmark_min+0x0/0x75 @ 1
[ 0.584364] initcall init_per_zone_wmark_min+0x0/0x75 returned 0 after 7881 usecs
[ 0.584374] calling kswapd_init+0x0/0x1d @ 1
[ 0.584510] initcall kswapd_init+0x0/0x1d returned 0 after 118 usecs
[ 0.584524] calling setup_vmstat+0x0/0xa3 @ 1
[ 0.584581] initcall setup_vmstat+0x0/0xa3 returned 0 after 40 usecs
[ 0.584593] calling mm_sysfs_init+0x0/0x22 @ 1
[ 0.584622] initcall mm_sysfs_init+0x0/0x22 returned 0 after 15 usecs
[ 0.584635] calling proc_vmalloc_init+0x0/0x1f @ 1
[ 0.584656] initcall proc_vmalloc_init+0x0/0x1f returned 0 after 10 usecs
[ 0.584664] calling slab_proc_init+0x0/0x1f @ 1
[ 0.584676] initcall slab_proc_init+0x0/0x1f returned 0 after 4 usecs
[ 0.584684] calling slab_sysfs_init+0x0/0xda @ 1
[ 0.588923] initcall slab_sysfs_init+0x0/0xda returned 0 after 4130 usecs
[ 0.588932] calling fcntl_init+0x0/0x24 @ 1
[ 0.588953] initcall fcntl_init+0x0/0x24 returned 0 after 11 usecs
[ 0.588960] calling proc_filesystems_init+0x0/0x1c @ 1
[ 0.588974] initcall proc_filesystems_init+0x0/0x1c returned 0 after 5 usecs
[ 0.588981] calling fsnotify_mark_init+0x0/0x33 @ 1
[ 0.589095] initcall fsnotify_mark_init+0x0/0x33 returned 0 after 96 usecs
[ 0.589108] calling inotify_user_setup+0x0/0x65 @ 1
[ 0.589165] initcall inotify_user_setup+0x0/0x65 returned 0 after 42 usecs
[ 0.589176] calling aio_setup+0x0/0x6a @ 1
[ 0.589246] initcall aio_setup+0x0/0x6a returned 0 after 58 usecs
[ 0.589253] calling proc_locks_init+0x0/0x1c @ 1
[ 0.589267] initcall proc_locks_init+0x0/0x1c returned 0 after 5 usecs
[ 0.589274] calling init_mbcache+0x0/0x11 @ 1
[ 0.589283] initcall init_mbcache+0x0/0x11 returned 0 after 1 usecs
[ 0.589290] calling proc_cmdline_init+0x0/0x1c @ 1
[ 0.589302] initcall proc_cmdline_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589309] calling proc_consoles_init+0x0/0x1c @ 1
[ 0.589321] initcall proc_consoles_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589328] calling proc_cpuinfo_init+0x0/0x1c @ 1
[ 0.589340] initcall proc_cpuinfo_init+0x0/0x1c returned 0 after 3 usecs
[ 0.589347] calling proc_devices_init+0x0/0x1c @ 1
[ 0.589359] initcall proc_devices_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589366] calling proc_interrupts_init+0x0/0x1c @ 1
[ 0.589378] initcall proc_interrupts_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589385] calling proc_loadavg_init+0x0/0x1c @ 1
[ 0.589397] initcall proc_loadavg_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589404] calling proc_meminfo_init+0x0/0x1c @ 1
[ 0.589416] initcall proc_meminfo_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589423] calling proc_stat_init+0x0/0x1c @ 1
[ 0.589435] initcall proc_stat_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589442] calling proc_uptime_init+0x0/0x1c @ 1
[ 0.589454] initcall proc_uptime_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589461] calling proc_version_init+0x0/0x1c @ 1
[ 0.589473] initcall proc_version_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589480] calling proc_softirqs_init+0x0/0x1c @ 1
[ 0.589493] initcall proc_softirqs_init+0x0/0x1c returned 0 after 4 usecs
[ 0.589499] calling proc_kcore_init+0x0/0x8a @ 1
[ 0.589518] initcall proc_kcore_init+0x0/0x8a returned 0 after 10 usecs
[ 0.589525] calling proc_kmsg_init+0x0/0x1f @ 1
[ 0.589537] initcall proc_kmsg_init+0x0/0x1f returned 0 after 4 usecs
[ 0.589544] calling proc_page_init+0x0/0x37 @ 1
[ 0.589559] initcall proc_page_init+0x0/0x37 returned 0 after 7 usecs
[ 0.589566] calling init_devpts_fs+0x0/0x3d @ 1
[ 0.589604] initcall init_devpts_fs+0x0/0x3d returned 0 after 28 usecs
[ 0.589611] calling init_ext3_fs+0x0/0x66 @ 1
[ 0.589789] initcall init_ext3_fs+0x0/0x66 returned 0 after 165 usecs
[ 0.589797] calling init_ext2_fs+0x0/0x66 @ 1
[ 0.589885] initcall init_ext2_fs+0x0/0x66 returned 0 after 78 usecs
[ 0.589893] calling ext4_init_fs+0x0/0x1a9 @ 1
[ 0.590426] initcall ext4_init_fs+0x0/0x1a9 returned 0 after 510 usecs
[ 0.590433] calling journal_init+0x0/0x85 @ 1
[ 0.590695] initcall journal_init+0x0/0x85 returned 0 after 246 usecs
[ 0.590702] calling journal_init+0x0/0xe1 @ 1
[ 0.590841] initcall journal_init+0x0/0xe1 returned 0 after 126 usecs
[ 0.590849] calling init_ramfs_fs+0x0/0xf @ 1
[ 0.590860] initcall init_ramfs_fs+0x0/0xf returned 0 after 3 usecs
[ 0.590867] calling ecryptfs_init+0x0/0x1c1 @ 1
[ 0.591462] initcall ecryptfs_init+0x0/0x1c1 returned 0 after 569 usecs
[ 0.591471] calling init_pstore_fs+0x0/0xf @ 1
[ 0.591483] initcall init_pstore_fs+0x0/0xf returned 0 after 3 usecs
[ 0.591490] calling ipc_init+0x0/0x20 @ 1
[ 0.591503] msgmni has been set to 3779
[ 0.591518] initcall ipc_init+0x0/0x20 returned 0 after 19 usecs
[ 0.591525] calling ipc_sysctl_init+0x0/0x11 @ 1
[ 0.591539] initcall ipc_sysctl_init+0x0/0x11 returned 0 after 5 usecs
[ 0.591546] calling key_proc_init+0x0/0x4e @ 1
[ 0.591562] initcall key_proc_init+0x0/0x4e returned 0 after 7 usecs
[ 0.591568] calling crypto_wq_init+0x0/0x2c @ 1
[ 0.591660] initcall crypto_wq_init+0x0/0x2c returned 0 after 79 usecs
[ 0.591668] calling crypto_algapi_init+0x0/0xc @ 1
[ 0.591683] initcall crypto_algapi_init+0x0/0xc returned 0 after 6 usecs
[ 0.591690] calling skcipher_module_init+0x0/0x2c @ 1
[ 0.591699] initcall skcipher_module_init+0x0/0x2c returned 0 after 1 usecs
[ 0.591707] calling chainiv_module_init+0x0/0xf @ 1
[ 0.591718] initcall chainiv_module_init+0x0/0xf returned 0 after 3 usecs
[ 0.591725] calling eseqiv_module_init+0x0/0xf @ 1
[ 0.591735] initcall eseqiv_module_init+0x0/0xf returned 0 after 1 usecs
[ 0.591742] calling hmac_module_init+0x0/0xf @ 1
[ 0.591752] initcall hmac_module_init+0x0/0xf returned 0 after 1 usecs
[ 0.591759] calling md5_mod_init+0x0/0xf @ 1
[ 0.591883] initcall md5_mod_init+0x0/0xf returned 0 after 107 usecs
[ 0.591896] calling sha1_generic_mod_init+0x0/0xf @ 1
[ 0.592085] initcall sha1_generic_mod_init+0x0/0xf returned 0 after 167 usecs
[ 0.592098] calling crypto_ecb_module_init+0x0/0xf @ 1
[ 0.592114] initcall crypto_ecb_module_init+0x0/0xf returned 0 after 2 usecs
[ 0.592126] calling crypto_cbc_module_init+0x0/0xf @ 1
[ 0.592142] initcall crypto_cbc_module_init+0x0/0xf returned 0 after 2 usecs
[ 0.592154] calling des_generic_mod_init+0x0/0x33 @ 1
[ 0.592415] initcall des_generic_mod_init+0x0/0x33 returned 0 after 241 usecs
[ 0.592422] calling aes_init+0x0/0xf @ 1
[ 0.592542] initcall aes_init+0x0/0xf returned 0 after 103 usecs
[ 0.592554] calling arc4_init+0x0/0xf @ 1
[ 0.592702] initcall arc4_init+0x0/0xf returned 0 after 129 usecs
[ 0.592715] calling crypto_authenc_module_init+0x0/0xf @ 1
[ 0.592732] initcall crypto_authenc_module_init+0x0/0xf returned 0 after 2 usecs
[ 0.592744] calling crypto_authenc_esn_module_init+0x0/0xf @ 1
[ 0.592760] initcall crypto_authenc_esn_module_init+0x0/0xf returned 0 after 2 usecs
[ 0.592772] calling krng_mod_init+0x0/0xf @ 1
[ 0.592912] initcall krng_mod_init+0x0/0xf returned 0 after 121 usecs
[ 0.592925] calling proc_genhd_init+0x0/0x31 @ 1
[ 0.592954] initcall proc_genhd_init+0x0/0x31 returned 0 after 15 usecs
[ 0.592966] calling bsg_init+0x0/0x10b @ 1
[ 0.593156] Block layer SCSI generic (bsg) driver version 0.4 loaded (major 253)
[ 0.593167] initcall bsg_init+0x0/0x10b returned 0 after 185 usecs
[ 0.593174] calling noop_init+0x0/0x11 @ 1
[ 0.593181] io scheduler noop registered
[ 0.593188] initcall noop_init+0x0/0x11 returned 0 after 6 usecs
[ 0.593196] calling deadline_init+0x0/0x11 @ 1
[ 0.593201] io scheduler deadline registered
[ 0.593209] initcall deadline_init+0x0/0x11 returned 0 after 5 usecs
[ 0.593216] calling cfq_init+0x0/0x93 @ 1
[ 0.593252] io scheduler cfq registered (default)
[ 0.593261] initcall cfq_init+0x0/0x93 returned 0 after 35 usecs
[ 0.593268] calling btree_module_init+0x0/0x24 @ 1
[ 0.593294] initcall btree_module_init+0x0/0x24 returned 0 after 16 usecs
[ 0.593302] calling percpu_counter_startup+0x0/0x2e @ 1
[ 0.593314] initcall percpu_counter_startup+0x0/0x2e returned 0 after 3 usecs
[ 0.593321] calling pci_proc_init+0x0/0x5b @ 1
[ 0.593411] initcall pci_proc_init+0x0/0x5b returned 0 after 79 usecs
[ 0.593419] calling pcie_portdrv_init+0x0/0x69 @ 1
[ 0.593578] pcieport 0000:00:1c.0: irq 40 for MSI/MSI-X
[ 0.593758] initcall pcie_portdrv_init+0x0/0x69 returned 0 after 321 usecs
[ 0.593766] calling aer_service_init+0x0/0x1f @ 1
[ 0.593798] initcall aer_service_init+0x0/0x1f returned 0 after 21 usecs
[ 0.593805] calling pcie_pme_service_init+0x0/0xf @ 1
[ 0.593859] pcieport 0000:00:1c.0: Signaling PME through PCIe PME interrupt
[ 0.593866] pci 0000:01:00.0: Signaling PME through PCIe PME interrupt
[ 0.593875] pcie_pme 0000:00:1c.0:pcie01: service driver pcie_pme loaded
[ 0.593904] initcall pcie_pme_service_init+0x0/0xf returned 0 after 87 usecs
[ 0.593912] calling ioapic_init+0x0/0x16 @ 1
[ 0.593951] initcall ioapic_init+0x0/0x16 returned 0 after 29 usecs
[ 0.593958] calling pci_hotplug_init+0x0/0x18 @ 1
[ 0.593964] pci_hotplug: PCI Hot Plug PCI Core version: 0.5
[ 0.593973] initcall pci_hotplug_init+0x0/0x18 returned 0 after 5 usecs
[ 0.593980] calling pcied_init+0x0/0xd0 @ 1
[ 0.594081] pciehp: PCI Express Hot Plug Controller Driver version: 0.4
[ 0.594091] initcall pcied_init+0x0/0xd0 returned 0 after 99 usecs
[ 0.594099] calling fb_console_init+0x0/0xf5 @ 1
[ 0.594153] initcall fb_console_init+0x0/0xf5 returned 0 after 43 usecs
[ 0.594161] calling genericbl_init+0x0/0xf @ 1
[ 0.594199] initcall genericbl_init+0x0/0xf returned 0 after 28 usecs
[ 0.594207] calling acpi_reserve_resources+0x0/0xc8 @ 1
[ 0.594227] initcall acpi_reserve_resources+0x0/0xc8 returned 0 after 10 usecs
[ 0.594235] calling irqrouter_init_ops+0x0/0x23 @ 1
[ 0.594246] initcall irqrouter_init_ops+0x0/0x23 returned 0 after 1 usecs
[ 0.594253] calling acpi_ac_init+0x0/0x23 @ 1
[ 0.596968] ACPI: AC Adapter [AC] (on-line)
[ 0.597123] initcall acpi_ac_init+0x0/0x23 returned 0 after 2790 usecs
[ 0.597132] calling acpi_button_init+0x0/0xf @ 1
[ 0.597293] input: Power Button as /devices/LNXSYSTM:00/device:00/PNP0C0C:00/input/input0
[ 0.597305] ACPI: Power Button [PWRB]
[ 0.597439] input: Sleep Button as /devices/LNXSYSTM:00/device:00/PNP0C0E:00/input/input1
[ 0.597448] ACPI: Sleep Button [SLPB]
[ 0.597594] input: Lid Switch as /devices/LNXSYSTM:00/device:00/PNP0C0D:00/input/input2
[ 0.612131] ACPI: Lid Switch [LID0]
[ 0.612352] input: Power Button as /devices/LNXSYSTM:00/LNXPWRBN:00/input/input3
[ 0.612362] ACPI: Power Button [PWRF]
[ 0.612403] initcall acpi_button_init+0x0/0xf returned 0 after 14901 usecs
[ 0.612411] calling acpi_fan_init+0x0/0x15 @ 1
[ 0.616002] ACPI: Fan [FAN0] (off)
[ 0.618711] ACPI: Fan [FAN1] (on)
[ 0.621349] ACPI: Fan [FAN2] (on)
[ 0.624110] ACPI: Fan [FAN3] (on)
[ 0.626797] ACPI: Fan [FAN4] (on)
[ 0.626834] initcall acpi_fan_init+0x0/0x15 returned 0 after 14072 usecs
[ 0.626843] calling acpi_video_init+0x0/0x69 @ 1
[ 0.626866] initcall acpi_video_init+0x0/0x69 returned 0 after 13 usecs
[ 0.626874] calling acpi_processor_init+0x0/0xb8 @ 1
[ 0.626881] ACPI: acpi_idle registered with cpuidle
[ 0.636552] initcall acpi_processor_init+0x0/0xb8 returned 0 after 9429 usecs
[ 0.636570] calling acpi_container_init+0x0/0x3d @ 1
[ 0.642536] initcall acpi_container_init+0x0/0x3d returned 0 after 5806 usecs
[ 0.642546] calling acpi_thermal_init+0x0/0x3a @ 1
[ 0.645827] thermal LNXTHERM:00: registered as thermal_zone0
[ 0.645834] ACPI: Thermal Zone [THRM] (65 C)
[ 0.645875] initcall acpi_thermal_init+0x0/0x3a returned 0 after 3238 usecs
[ 0.645883] calling acpi_battery_init+0x0/0x13 @ 1
[ 0.645902] initcall acpi_battery_init+0x0/0x13 returned 0 after 8 usecs
[ 0.645911] calling pty_init+0x0/0x202 @ 1
[ 0.646045] calling 1_acpi_battery_init_async+0x0/0x18 @ 5
[ 0.646120] initcall pty_init+0x0/0x202 returned 0 after 192 usecs
[ 0.646130] calling sysrq_init+0x0/0x5d @ 1
[ 0.646158] initcall sysrq_init+0x0/0x5d returned 0 after 15 usecs
[ 0.646169] calling serial8250_init+0x0/0x157 @ 1
[ 0.646178] Serial: 8250/16550 driver, 4 ports, IRQ sharing disabled
[ 0.657962] ACPI: Battery Slot [BAT1] (battery present)
[ 0.658051] initcall 1_acpi_battery_init_async+0x0/0x18 returned 0 after 11707 usecs
[ 0.683109] initcall serial8250_init+0x0/0x157 returned 0 after 36062 usecs
[ 0.683118] calling serial8250_pnp_init+0x0/0xf @ 1
[ 0.683291] initcall serial8250_pnp_init+0x0/0xf returned 0 after 160 usecs
[ 0.683299] calling serial8250_pci_init+0x0/0x16 @ 1
[ 0.683399] initcall serial8250_pci_init+0x0/0x16 returned 0 after 88 usecs
[ 0.683407] calling rand_initialize+0x0/0x2a @ 1
[ 0.683468] initcall rand_initialize+0x0/0x2a returned 0 after 51 usecs
[ 0.683475] calling hpet_init+0x0/0x57 @ 1
[ 0.683776] initcall hpet_init+0x0/0x57 returned 0 after 283 usecs
[ 0.683784] calling nvram_init+0x0/0x70 @ 1
[ 0.683864] Non-volatile memory driver v1.3
[ 0.683874] initcall nvram_init+0x0/0x70 returned 0 after 78 usecs
[ 0.683881] calling mod_init+0x0/0x1b8 @ 1
[ 0.683986] initcall mod_init+0x0/0x1b8 returned -19 after 95 usecs
[ 0.683993] calling agp_init+0x0/0x21 @ 1
[ 0.683999] Linux agpgart interface v0.103
[ 0.684007] initcall agp_init+0x0/0x21 returned 0 after 5 usecs
[ 0.684040] calling agp_intel_init+0x0/0x24 @ 1
[ 0.684145] agpgart-intel 0000:00:00.0: Intel GMA3150 Chipset
[ 0.684446] agpgart-intel 0000:00:00.0: detected gtt size: 524288K total, 262144K mappable
[ 0.684828] agpgart-intel 0000:00:00.0: detected 8192K stolen memory
[ 0.685113] agpgart-intel 0000:00:00.0: AGP aperture is 256M @ 0x80000000
[ 0.685174] initcall agp_intel_init+0x0/0x24 returned 0 after 1094 usecs
[ 0.685182] calling init_tis+0x0/0x8f @ 1
[ 0.685408] tpm_tis tpm_tis: 1.2 TPM (device-id 0xB, rev-id 16)
[ 0.787094] initcall init_tis+0x0/0x8f returned 0 after 99508 usecs
[ 0.787108] calling ramoops_init+0x0/0x14 @ 1
[ 0.788478] initcall ramoops_init+0x0/0x14 returned 0 after 1328 usecs
[ 0.788485] calling drm_core_init+0x0/0xfb @ 1
[ 0.788541] [drm] Initialized drm 1.1.0 20060810
[ 0.788550] initcall drm_core_init+0x0/0xfb returned 0 after 54 usecs
[ 0.788557] calling i915_init+0x0/0x81 @ 1
[ 0.788563] [drm:drm_pci_init],
[ 0.788586] [drm:drm_get_pci_dev],
[ 0.788638] [drm:drm_get_minor],
[ 0.788928] [drm:drm_get_minor], new minor assigned 64
[ 0.788935] [drm:drm_get_minor],
[ 0.789214] [drm:drm_get_minor], new minor assigned 0
[ 0.856190] [drm:intel_opregion_setup], graphic opregion physical addr: 0x7f58c018
[ 0.856226] [drm:intel_opregion_setup], Public ACPI methods supported
[ 0.856233] [drm:intel_opregion_setup], SWSCI supported
[ 0.856238] [drm:intel_opregion_setup], ASLE supported
[ 0.856289] i915 0000:00:02.0: irq 41 for MSI/MSI-X
[ 0.856300] [drm] Supports vblank timestamp caching Rev 1 (10.10.2010).
[ 0.856305] [drm] Driver supports precise vblank timestamp query.
[ 0.856317] [drm:init_vbt_defaults], Set default to SSC at 100MHz
[ 0.856324] [drm:intel_parse_bios], Using VBT from OpRegion: $VBT PINEVIEW d
[ 0.856331] [drm:parse_general_definitions], crt_ddc_bus_pin: 2
[ 0.856341] [drm:parse_lfp_panel_data], Found panel mode in BIOS VBT tables:
[ 0.856348] [drm:drm_mode_debug_printmodeline], Modeline 0:"1280x800" 0 70700 1280 1296 1344 1440 800 801 804 818 0x8 0xa
[ 0.856363] [drm:parse_sdvo_panel_data], Found SDVO panel mode in BIOS VBT tables:
[ 0.856369] [drm:drm_mode_debug_printmodeline], Modeline 0:"1600x1200" 0 162000 1600 1664 1856 2160 1200 1201 1204 1250 0x8 0xa
[ 0.856382] [drm:parse_sdvo_device_mapping], No SDVO device info is found in VBT
[ 0.856398] [drm:intel_dsm_pci_probe], no _DSM method for intel device
[ 0.856423] [drm:intel_modeset_init], 2 display pipes available.
[ 0.856437] [drm:intel_crtc_init], swapping pipes & planes for FBC
[ 0.899057] [drm:intel_crtc_init], swapping pipes & planes for FBC
[ 0.952613] [drm:drm_sysfs_connector_add], adding "LVDS-1" to sysfs
[ 0.952672] [drm:drm_sysfs_hotplug_event], generating hotplug event
[ 0.952697] [drm:intel_panel_get_backlight], get backlight PWM = 13046
[ 0.952705] [drm:intel_panel_get_max_backlight], max backlight PWM = 13046
[ 0.952755] [drm:intel_panel_get_backlight], get backlight PWM = 13046
[ 0.952768] [drm:drm_sysfs_connector_add], adding "VGA-1" to sysfs
[ 0.952815] [drm:drm_sysfs_hotplug_event], generating hotplug event
[ 0.952840] [drm:intel_panel_get_backlight], get backlight PWM = 13046
[ 0.952846] [drm:intel_panel_set_backlight], set backlight PWM = 0
[ 1.206060] [drm:i915_get_vblank_counter], trying to get vblank count for disabled pipe A
[ 1.206075] [drm:i915_get_vblank_timestamp], crtc 0 is disabled
[ 1.206088] [drm:i915_get_vblank_counter], trying to get vblank count for disabled pipe A
[ 1.206107] [drm:intel_update_fbc],
[ 1.206114] [drm:pineview_update_wm], Self-refresh is disabled
[ 1.206122] [drm:i915_get_vblank_counter], trying to get vblank count for disabled pipe B
[ 1.206129] [drm:i915_get_vblank_timestamp], crtc 1 is disabled
[ 1.206137] [drm:i915_get_vblank_counter], trying to get vblank count for disabled pipe B
[ 1.206147] [drm:intel_update_fbc],
[ 1.206153] [drm:pineview_update_wm], Self-refresh is disabled
[ 1.214189] [drm:init_status_page], render ring hws offset: 0x00000000
[ 1.214445] [drm] initialized overlay support
[ 1.214453] [drm:drm_irq_install], irq=41
[ 1.214504] [drm:intel_panel_set_backlight], set backlight PWM = 0
[ 1.214519] [drm:drm_helper_probe_single_connector_modes], [CONNECTOR:5:LVDS-1]
[ 1.214540] [drm:drm_helper_probe_single_connector_modes], [CONNECTOR:5:LVDS-1] probed modes :
[ 1.214548] [drm:drm_mode_debug_printmodeline], Modeline 13:"1280x800" 60 70700 1280 1296 1344 1440 800 801 804 818 0x48 0xa
[ 1.214562] [drm:drm_helper_probe_single_connector_modes], [CONNECTOR:11:VGA-1]
[ 1.228048] [drm:intel_crt_detect], CRT not detected via hotplug
[ 1.228062] [drm:drm_helper_probe_single_connector_modes], [CONNECTOR:11:VGA-1] disconnected
[ 1.228075] [drm:drm_setup_crtcs],
[ 1.228086] [drm:drm_enable_connectors], connector 5 enabled? yes
[ 1.228102] [drm:drm_enable_connectors], connector 11 enabled? no
[ 1.228109] [drm:drm_target_preferred], looking for cmdline mode on connector 5
[ 1.228117] [drm:drm_target_preferred], looking for preferred mode on connector 5
[ 1.228124] [drm:drm_target_preferred], found mode 1280x800
[ 1.228130] [drm:drm_setup_crtcs], picking CRTCs for 4096x4096 config
[ 1.228138] [drm:drm_setup_crtcs], desired mode 1280x800 set on crtc 4
[ 1.237862] [drm:intelfb_create], allocated 1280x800 fb: 0x00030000, bo f63da900
[ 1.238040] fbcon: inteldrmfb (fb0) is primary device
[ 1.238137] [drm:drm_crtc_helper_set_config],
[ 1.238143] [drm:drm_crtc_helper_set_config], [CRTC:3] [NOFB]
[ 1.238156] [drm:drm_crtc_helper_set_config],
[ 1.238160] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 1.238172] [drm:drm_crtc_helper_set_config], crtc has no fb, full mode set
[ 1.238177] [drm:drm_crtc_helper_set_config], modes are different, full mode set
[ 1.238182] [drm:drm_mode_debug_printmodeline], Modeline 0:"" 0 0 0 0 0 0 0 0 0 0 0x0 0x0
[ 1.238191] [drm:drm_mode_debug_printmodeline], Modeline 8:"1280x800" 60 70700 1280 1296 1344 1440 800 801 804 818 0x48 0xa
[ 1.238203] [drm:drm_crtc_helper_set_config], encoder changed, full mode switch
[ 1.238208] [drm:drm_crtc_helper_set_config], crtc changed, full mode switch
[ 1.238214] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 1.238221] [drm:drm_crtc_helper_set_config], attempting to set mode from userspace
[ 1.238226] [drm:drm_mode_debug_printmodeline], Modeline 8:"1280x800" 60 70700 1280 1296 1344 1440 800 801 804 818 0x48 0xa
[ 1.238242] [drm:drm_crtc_helper_set_mode], [CRTC:4]
[ 1.238248] [drm:intel_panel_set_backlight], set backlight PWM = 0
[ 1.238261] [drm:drm_vblank_get], enabling vblank on crtc 1, ret: -22
[ 1.239342] [drm:i9xx_crtc_mode_set], Mode for pipe B:
[ 1.239347] [drm:drm_mode_debug_printmodeline], Modeline 8:"1280x800" 60 70700 1280 1296 1344 1440 800 801 804 818 0x48 0xa
[ 1.273051] [drm:i9xx_update_plane], Writing base 00030000 00000000 0 0 5120
[ 1.273066] [drm:intel_update_fbc],
[ 1.273076] [drm:intel_calculate_wm], FIFO entries required for mode: 29
[ 1.273085] [drm:intel_calculate_wm], FIFO watermark level: 473
[ 1.273094] [drm:pineview_update_wm], DSPFW1 register is ec830f0f
[ 1.273106] [drm:intel_calculate_wm], FIFO entries required for mode: 31
[ 1.273111] [drm:intel_calculate_wm], FIFO watermark level: 476
[ 1.273117] [drm:intel_calculate_wm], FIFO entries required for mode: 160
[ 1.273122] [drm:intel_calculate_wm], FIFO watermark level: 342
[ 1.273127] [drm:intel_calculate_wm], FIFO entries required for mode: 162
[ 1.273133] [drm:intel_calculate_wm], FIFO watermark level: 345
[ 1.273138] [drm:pineview_update_wm], DSPFW3 register is 3f3f0156
[ 1.273144] [drm:pineview_update_wm], Self-refresh is enabled
[ 1.273150] [drm:drm_crtc_helper_set_mode], [ENCODER:6:LVDS-6] set [MODE:8:1280x800]
[ 1.273157] [drm:intel_calculate_wm], FIFO entries required for mode: 29
[ 1.273162] [drm:intel_calculate_wm], FIFO watermark level: 473
[ 1.273168] [drm:pineview_update_wm], DSPFW1 register is ec830f0f
[ 1.273173] [drm:intel_calculate_wm], FIFO entries required for mode: 31
[ 1.273178] [drm:intel_calculate_wm], FIFO watermark level: 476
[ 1.273184] [drm:intel_calculate_wm], FIFO entries required for mode: 160
[ 1.273189] [drm:intel_calculate_wm], FIFO watermark level: 342
[ 1.273194] [drm:intel_calculate_wm], FIFO entries required for mode: 162
[ 1.273199] [drm:intel_calculate_wm], FIFO watermark level: 345
[ 1.273205] [drm:pineview_update_wm], DSPFW3 register is 7f3f0156
[ 1.273210] [drm:pineview_update_wm], Self-refresh is enabled
[ 1.273754] [drm:intel_update_fbc],
[ 1.273758] [drm:intel_lvds_enable], applying panel-fitter: 8, 0
[ 1.673051] [drm:intel_panel_set_backlight], set backlight PWM = 13046
[ 1.673064] [drm:drm_calc_timestamping_constants], crtc 4: hwmode: htotal 1440, vtotal 818, vdisplay 800
[ 1.673077] [drm:drm_calc_timestamping_constants], crtc 4: clock 70700 kHz framedur 16660206 linedur 20367, pixeldur 14
[ 1.673093] [drm:drm_crtc_helper_set_config], Setting connector DPMS state to on
[ 1.673106] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] set DPMS on
[ 1.673195] [drm:drm_crtc_helper_set_config],
[ 1.673200] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 1.673214] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 1.687255] Console: switching to colour frame buffer device 160x50
[ 1.687266] [drm:drm_crtc_helper_set_config],
[ 1.687271] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 1.687284] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 1.701335] fb0: inteldrmfb frame buffer device
[ 1.701339] drm: registered panic notifier
[ 1.703500] [drm:intel_panel_get_max_backlight], max backlight PWM = 13046
[ 1.703516] [drm:intel_panel_set_backlight], set backlight PWM = 13046
[ 1.703715] [drm:intel_panel_get_max_backlight], max backlight PWM = 13046
[ 1.703727] [drm:intel_panel_set_backlight], set backlight PWM = 7827
[ 1.703884] acpi device:2c: registered as cooling_device9
[ 1.704297] input: Video Bus as /devices/LNXSYSTM:00/device:00/PNP0A08:00/LNXVIDEO:00/input/input4
[ 1.704311] ACPI: Video Device [OVGA] (multi-head: yes rom: no post: no)
[ 1.704359] [drm] Initialized i915 1.6.0 20080730 for 0000:00:02.0 on minor 0
[ 1.704422] initcall i915_init+0x0/0x81 returned 0 after 894386 usecs
[ 1.704430] calling cn_proc_init+0x0/0x30 @ 1
[ 1.704442] initcall cn_proc_init+0x0/0x30 returned 0 after 3 usecs
[ 1.704450] calling topology_sysfs_init+0x0/0x51 @ 1
[ 1.704501] initcall topology_sysfs_init+0x0/0x51 returned 0 after 42 usecs
[ 1.704509] calling loop_init+0x0/0x1c2 @ 1
[ 1.706038] loop: module loaded
[ 1.706049] initcall loop_init+0x0/0x1c2 returned 0 after 1494 usecs
[ 1.706057] calling spi_transport_init+0x0/0x6c @ 1
[ 1.706109] initcall spi_transport_init+0x0/0x6c returned 0 after 41 usecs
[ 1.706116] calling init_sd+0x0/0xe5 @ 1
[ 1.706213] initcall init_sd+0x0/0xe5 returned 0 after 85 usecs
[ 1.706221] calling ahci_init+0x0/0x16 @ 1
[ 1.706276] ahci 0000:00:1f.2: version 3.0
[ 1.706397] ahci 0000:00:1f.2: irq 42 for MSI/MSI-X
[ 1.706468] ahci: SSS flag set, parallel bus scan disabled
[ 1.706512] ahci 0000:00:1f.2: AHCI 0001.0100 32 slots 4 ports 3 Gbps 0x1 impl SATA mode
[ 1.706521] ahci 0000:00:1f.2: flags: 64bit ncq stag pm led clo pio slum part
[ 1.707644] scsi0 : ahci
[ 1.707959] scsi1 : ahci
[ 1.708250] scsi2 : ahci
[ 1.708514] scsi3 : ahci
[ 1.709094] ata1: SATA max UDMA/133 abar m1024@0x92204000 port 0x92204100 irq 42
[ 1.709100] ata2: DUMMY
[ 1.709104] ata3: DUMMY
[ 1.709108] ata4: DUMMY
[ 1.709155] calling 2_async_port_probe+0x0/0x50 @ 5
[ 1.709193] initcall ahci_init+0x0/0x16 returned 0 after 2890 usecs
[ 1.709204] calling piix_init+0x0/0x24 @ 1
[ 1.709280] calling 3_async_port_probe+0x0/0x50 @ 43
[ 1.709294] initcall piix_init+0x0/0x24 returned 0 after 66 usecs
[ 1.709305] calling ata_generic_init+0x0/0x16 @ 1
[ 1.709317] async_waiting @ 43
[ 1.709372] initcall ata_generic_init+0x0/0x16 returned 0 after 54 usecs
[ 1.709388] calling 4_async_port_probe+0x0/0x50 @ 50
[ 1.709399] calling net_olddevs_init+0x0/0x84 @ 1
[ 1.709411] async_waiting @ 50
[ 1.709423] initcall net_olddevs_init+0x0/0x84 returned 0 after 12 usecs
[ 1.709434] calling mon_init+0x0/0xe7 @ 1
[ 1.709497] calling 5_async_port_probe+0x0/0x50 @ 51
[ 1.709512] async_waiting @ 51
[ 1.709604] initcall mon_init+0x0/0xe7 returned 0 after 154 usecs
[ 1.709612] calling ehci_hcd_init+0x0/0x68 @ 1
[ 1.709618] ehci_hcd: USB 2.0 'Enhanced' Host Controller (EHCI) Driver
[ 1.709693] ehci_hcd 0000:00:1d.7: EHCI Host Controller
[ 1.709848] ehci_hcd 0000:00:1d.7: new USB bus registered, assigned bus number 1
[ 1.709890] ehci_hcd 0000:00:1d.7: using broken periodic workaround
[ 1.709909] ehci_hcd 0000:00:1d.7: debug port 1
[ 1.713799] ehci_hcd 0000:00:1d.7: cache line size of 64 is not supported
[ 1.713840] ehci_hcd 0000:00:1d.7: irq 16, io mem 0x92205000
[ 1.723061] ehci_hcd 0000:00:1d.7: USB 2.0 started, EHCI 1.00
[ 1.723132] usb usb1: New USB device found, idVendor=1d6b, idProduct=0002
[ 1.723139] usb usb1: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.723146] usb usb1: Product: EHCI Host Controller
[ 1.723151] usb usb1: Manufacturer: Linux 3.0.8 ehci_hcd
[ 1.723157] usb usb1: SerialNumber: 0000:00:1d.7
[ 1.723412] hub 1-0:1.0: USB hub found
[ 1.723425] hub 1-0:1.0: 8 ports detected
[ 1.723612] initcall ehci_hcd_init+0x0/0x68 returned 0 after 13660 usecs
[ 1.723621] calling ohci_hcd_mod_init+0x0/0x4b @ 1
[ 1.723627] ohci_hcd: USB 1.1 'Open' Host Controller (OHCI) Driver
[ 1.723667] initcall ohci_hcd_mod_init+0x0/0x4b returned 0 after 36 usecs
[ 1.723675] calling uhci_hcd_init+0x0/0xad @ 1
[ 1.723681] uhci_hcd: USB Universal Host Controller Interface driver
[ 1.723757] uhci_hcd 0000:00:1d.0: UHCI Host Controller
[ 1.723858] uhci_hcd 0000:00:1d.0: new USB bus registered, assigned bus number 2
[ 1.723901] uhci_hcd 0000:00:1d.0: irq 16, io base 0x00002080
[ 1.723990] usb usb2: New USB device found, idVendor=1d6b, idProduct=0001
[ 1.723998] usb usb2: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.724004] usb usb2: Product: UHCI Host Controller
[ 1.724036] usb usb2: Manufacturer: Linux 3.0.8 uhci_hcd
[ 1.724042] usb usb2: SerialNumber: 0000:00:1d.0
[ 1.724279] hub 2-0:1.0: USB hub found
[ 1.724292] hub 2-0:1.0: 2 ports detected
[ 1.724452] uhci_hcd 0000:00:1d.1: UHCI Host Controller
[ 1.724551] uhci_hcd 0000:00:1d.1: new USB bus registered, assigned bus number 3
[ 1.724616] uhci_hcd 0000:00:1d.1: irq 17, io base 0x00002060
[ 1.724699] usb usb3: New USB device found, idVendor=1d6b, idProduct=0001
[ 1.724707] usb usb3: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.724713] usb usb3: Product: UHCI Host Controller
[ 1.724718] usb usb3: Manufacturer: Linux 3.0.8 uhci_hcd
[ 1.724724] usb usb3: SerialNumber: 0000:00:1d.1
[ 1.724954] hub 3-0:1.0: USB hub found
[ 1.724966] hub 3-0:1.0: 2 ports detected
[ 1.725152] uhci_hcd 0000:00:1d.2: UHCI Host Controller
[ 1.725250] uhci_hcd 0000:00:1d.2: new USB bus registered, assigned bus number 4
[ 1.725310] uhci_hcd 0000:00:1d.2: irq 18, io base 0x00002040
[ 1.725394] usb usb4: New USB device found, idVendor=1d6b, idProduct=0001
[ 1.725401] usb usb4: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.725407] usb usb4: Product: UHCI Host Controller
[ 1.725413] usb usb4: Manufacturer: Linux 3.0.8 uhci_hcd
[ 1.725418] usb usb4: SerialNumber: 0000:00:1d.2
[ 1.725648] hub 4-0:1.0: USB hub found
[ 1.725660] hub 4-0:1.0: 2 ports detected
[ 1.725813] uhci_hcd 0000:00:1d.3: UHCI Host Controller
[ 1.725926] uhci_hcd 0000:00:1d.3: new USB bus registered, assigned bus number 5
[ 1.725986] uhci_hcd 0000:00:1d.3: irq 19, io base 0x00002020
[ 1.726096] usb usb5: New USB device found, idVendor=1d6b, idProduct=0001
[ 1.726104] usb usb5: New USB device strings: Mfr=3, Product=2, SerialNumber=1
[ 1.726110] usb usb5: Product: UHCI Host Controller
[ 1.726116] usb usb5: Manufacturer: Linux 3.0.8 uhci_hcd
[ 1.726121] usb usb5: SerialNumber: 0000:00:1d.3
[ 1.726348] hub 5-0:1.0: USB hub found
[ 1.726360] hub 5-0:1.0: 2 ports detected
[ 1.726523] initcall uhci_hcd_init+0x0/0xad returned 0 after 2769 usecs
[ 1.726532] calling acm_init+0x0/0xcd @ 1
[ 1.726588] usbcore: registered new interface driver cdc_acm
[ 1.726593] cdc_acm: USB Abstract Control Model driver for USB modems and ISDN adapters
[ 1.726603] initcall acm_init+0x0/0xcd returned 0 after 60 usecs
[ 1.726610] calling usb_stor_init+0x0/0x42 @ 1
[ 1.726615] Initializing USB Mass Storage driver...
[ 1.726701] usbcore: registered new interface driver usb-storage
[ 1.726706] USB Mass Storage support registered.
[ 1.726716] initcall usb_stor_init+0x0/0x42 returned 0 after 95 usecs
[ 1.726723] calling usb_usual_init+0x0/0x30 @ 1
[ 1.726809] usbcore: registered new interface driver libusual
[ 1.726819] initcall usb_usual_init+0x0/0x30 returned 0 after 84 usecs
[ 1.726826] calling realtek_cr_init+0x0/0x16 @ 1
[ 1.726865] usbcore: registered new interface driver ums-realtek
[ 1.726874] initcall realtek_cr_init+0x0/0x16 returned 0 after 39 usecs
[ 1.726882] calling usb_serial_init+0x0/0x18f @ 1
[ 1.726961] usbcore: registered new interface driver usbserial
[ 1.726988] USB Serial support registered for generic
[ 1.727062] usbcore: registered new interface driver usbserial_generic
[ 1.727068] usbserial: USB Serial Driver core
[ 1.727077] initcall usb_serial_init+0x0/0x18f returned 0 after 180 usecs
[ 1.727085] calling i8042_init+0x0/0x3c1 @ 1
[ 1.727191] i8042: PNP: PS/2 Controller [PNP0303:KBC,PNP0f13:MOUE] at 0x60,0x64 irq 1,12
[ 1.738314] i8042: Detected active multiplexing controller, rev 1.1
[ 1.744288] serio: i8042 KBD port at 0x60,0x64 irq 1
[ 1.744316] serio: i8042 AUX0 port at 0x60,0x64 irq 12
[ 1.744456] serio: i8042 AUX1 port at 0x60,0x64 irq 12
[ 1.744566] serio: i8042 AUX2 port at 0x60,0x64 irq 12
[ 1.744673] serio: i8042 AUX3 port at 0x60,0x64 irq 12
[ 1.744914] initcall i8042_init+0x0/0x3c1 returned 0 after 17397 usecs
[ 1.744928] calling serio_raw_init+0x0/0x16 @ 1
[ 1.744990] initcall serio_raw_init+0x0/0x16 returned 0 after 46 usecs
[ 1.745003] calling mousedev_init+0x0/0x51 @ 1
[ 1.745245] mousedev: PS/2 mouse device common for all mice
[ 1.745263] initcall mousedev_init+0x0/0x51 returned 0 after 214 usecs
[ 1.745275] calling evdev_init+0x0/0xf @ 1
[ 1.745832] initcall evdev_init+0x0/0xf returned 0 after 530 usecs
[ 1.745846] calling atkbd_init+0x0/0x20 @ 1
[ 1.745939] initcall atkbd_init+0x0/0x20 returned 0 after 76 usecs
[ 1.745952] calling gpio_keys_init+0x0/0xf @ 1
[ 1.746094] initcall gpio_keys_init+0x0/0xf returned 0 after 122 usecs
[ 1.746107] calling psmouse_init+0x0/0x6b @ 1
[ 1.746363] initcall psmouse_init+0x0/0x6b returned 0 after 234 usecs
[ 1.746376] calling coretemp_init+0x0/0x69 @ 1
[ 1.746607] coretemp coretemp.0: Unable to read TjMax from CPU.
[ 1.746625] coretemp coretemp.0: Using relative temperature scale!
[ 1.746660] coretemp coretemp.0: Unable to read TjMax from CPU.
[ 1.746673] coretemp coretemp.0: Using relative temperature scale!
[ 1.746713] initcall coretemp_init+0x0/0x69 returned 0 after 315 usecs
[ 1.746725] calling dm_init+0x0/0x30 @ 1
[ 1.747313] device-mapper: ioctl: 4.20.0-ioctl (2011-02-02) initialised: dm-devel@redhat.com
[ 1.747332] initcall dm_init+0x0/0x30 returned 0 after 578 usecs
[ 1.747345] calling dm_verity_init+0x0/0x10e @ 1
[ 1.747539] device-mapper: verity: version 0.1.0 loaded
[ 1.747555] initcall dm_verity_init+0x0/0x10e returned 0 after 191 usecs
[ 1.747568] calling dm_verity_chromeos_init+0x0/0x35 @ 1
[ 1.747578] device-mapper: verity-chromeos: dm-verity-chromeos registered
[ 1.747592] initcall dm_verity_chromeos_init+0x0/0x35 returned 0 after 10 usecs
[ 1.747605] calling cpufreq_stats_init+0x0/0x8e @ 1
[ 1.747624] initcall cpufreq_stats_init+0x0/0x8e returned 0 after 6 usecs
[ 1.747637] calling cpufreq_gov_userspace_init+0x0/0xf @ 1
[ 1.747654] initcall cpufreq_gov_userspace_init+0x0/0xf returned 0 after 2 usecs
[ 1.747666] calling init_ladder+0x0/0xf @ 1
[ 1.748152] cpuidle: using governor ladder
[ 1.748168] initcall init_ladder+0x0/0xf returned 0 after 474 usecs
[ 1.748180] calling init_menu+0x0/0xf @ 1
[ 1.748940] cpuidle: using governor menu
[ 1.748955] initcall init_menu+0x0/0xf returned 0 after 744 usecs
[ 1.748968] calling hid_init+0x0/0x58 @ 1
[ 1.749135] initcall hid_init+0x0/0x58 returned 0 after 146 usecs
[ 1.749148] calling hid_init+0x0/0x65 @ 1
[ 1.749278] usbcore: registered new interface driver usbhid
[ 1.749286] usbhid: USB HID core driver
[ 1.749299] initcall hid_init+0x0/0x65 returned 0 after 134 usecs
[ 1.749311] calling staging_init+0x0/0x7 @ 1
[ 1.749325] initcall staging_init+0x0/0x7 returned 0 after 1 usecs
[ 1.749338] calling alsa_sound_last_init+0x0/0x50 @ 1
[ 1.749346] ALSA device list:
[ 1.749352] No soundcards found.
[ 1.749365] initcall alsa_sound_last_init+0x0/0x50 returned 0 after 13 usecs
[ 1.749379] calling flow_cache_init_global+0x0/0x107 @ 1
[ 1.749476] initcall flow_cache_init_global+0x0/0x107 returned 0 after 78 usecs
[ 1.749488] calling blackhole_module_init+0x0/0xf @ 1
[ 1.749504] initcall blackhole_module_init+0x0/0xf returned 0 after 3 usecs
[ 1.749516] calling nfnetlink_init+0x0/0x1e @ 1
[ 1.749525] Netfilter messages via NETLINK v0.30.
[ 1.749567] initcall nfnetlink_init+0x0/0x1e returned 0 after 36 usecs
[ 1.749578] calling nfnetlink_log_init+0x0/0xb3 @ 1
[ 1.749619] initcall nfnetlink_log_init+0x0/0xb3 returned 0 after 26 usecs
[ 1.749631] calling nf_conntrack_standalone_init+0x0/0xf @ 1
[ 1.749643] nf_conntrack version 0.5.0 (16384 buckets, 65536 max)
[ 1.750238] initcall nf_conntrack_standalone_init+0x0/0xf returned 0 after 575 usecs
[ 1.750252] calling ctnetlink_init+0x0/0x62 @ 1
[ 1.750261] ctnetlink v0.93: registering with nfnetlink.
[ 1.750275] initcall ctnetlink_init+0x0/0x62 returned 0 after 10 usecs
[ 1.750286] calling xt_init+0x0/0xbf @ 1
[ 1.750302] initcall xt_init+0x0/0xbf returned 0 after 4 usecs
[ 1.750313] calling tcpudp_mt_init+0x0/0x14 @ 1
[ 1.750329] initcall tcpudp_mt_init+0x0/0x14 returned 0 after 3 usecs
[ 1.750340] calling nflog_tg_init+0x0/0xf @ 1
[ 1.750354] initcall nflog_tg_init+0x0/0xf returned 0 after 1 usecs
[ 1.750365] calling secmark_tg_init+0x0/0xf @ 1
[ 1.750379] initcall secmark_tg_init+0x0/0xf returned 0 after 1 usecs
[ 1.750390] calling conntrack_mt_init+0x0/0x14 @ 1
[ 1.750405] initcall conntrack_mt_init+0x0/0x14 returned 0 after 1 usecs
[ 1.750416] calling policy_mt_init+0x0/0x14 @ 1
[ 1.750430] initcall policy_mt_init+0x0/0x14 returned 0 after 1 usecs
[ 1.750441] calling state_mt_init+0x0/0xf @ 1
[ 1.750455] initcall state_mt_init+0x0/0xf returned 0 after 1 usecs
[ 1.750467] calling sysctl_ipv4_init+0x0/0x71 @ 1
[ 1.750499] initcall sysctl_ipv4_init+0x0/0x71 returned 0 after 18 usecs
[ 1.750511] calling init_syncookies+0x0/0x16 @ 1
[ 1.750618] initcall init_syncookies+0x0/0x16 returned 0 after 91 usecs
[ 1.750631] calling ipv4_netfilter_init+0x0/0x14 @ 1
[ 1.750646] initcall ipv4_netfilter_init+0x0/0x14 returned 0 after 2 usecs
[ 1.750659] calling nf_conntrack_l3proto_ipv4_init+0x0/0x11d @ 1
[ 1.750744] initcall nf_conntrack_l3proto_ipv4_init+0x0/0x11d returned 0 after 67 usecs
[ 1.750757] calling nf_defrag_init+0x0/0x14 @ 1
[ 1.750771] initcall nf_defrag_init+0x0/0x14 returned 0 after 1 usecs
[ 1.750783] calling ip_tables_init+0x0/0x8c @ 1
[ 1.750810] ip_tables: (C) 2000-2006 Netfilter Core Team
[ 1.750824] initcall ip_tables_init+0x0/0x8c returned 0 after 27 usecs
[ 1.750836] calling iptable_filter_init+0x0/0x5d @ 1
[ 1.750890] initcall iptable_filter_init+0x0/0x5d returned 0 after 39 usecs
[ 1.750902] calling iptable_mangle_init+0x0/0x40 @ 1
[ 1.751006] initcall iptable_mangle_init+0x0/0x40 returned 0 after 82 usecs
[ 1.751052] calling log_tg_init+0x0/0x24 @ 1
[ 1.751085] initcall log_tg_init+0x0/0x24 returned 0 after 16 usecs
[ 1.751097] calling reject_tg_init+0x0/0xf @ 1
[ 1.751111] initcall reject_tg_init+0x0/0xf returned 0 after 1 usecs
[ 1.751123] calling ulog_tg_init+0x0/0xb2 @ 1
[ 1.751154] initcall ulog_tg_init+0x0/0xb2 returned 0 after 17 usecs
[ 1.751166] calling cubictcp_register+0x0/0x73 @ 1
[ 1.751176] TCP cubic registered
[ 1.751188] initcall cubictcp_register+0x0/0x73 returned 0 after 9 usecs
[ 1.751200] calling xfrm_user_init+0x0/0x40 @ 1
[ 1.751208] Initializing XFRM netlink socket
[ 1.751231] initcall xfrm_user_init+0x0/0x40 returned 0 after 17 usecs
[ 1.751243] calling inet6_init+0x0/0x283 @ 1
[ 1.751853] NET: Registered protocol family 10
[ 1.752561] initcall inet6_init+0x0/0x283 returned 0 after 1266 usecs
[ 1.752576] calling packet_init+0x0/0x39 @ 1
[ 1.752586] NET: Registered protocol family 17
[ 1.752610] initcall packet_init+0x0/0x39 returned 0 after 19 usecs
[ 1.752623] calling mcheck_debugfs_init+0x0/0x37 @ 1
[ 1.752659] initcall mcheck_debugfs_init+0x0/0x37 returned 0 after 21 usecs
[ 1.752672] calling severities_debugfs_init+0x0/0x37 @ 1
[ 1.752693] initcall severities_debugfs_init+0x0/0x37 returned 0 after 7 usecs
[ 1.752705] calling hpet_insert_resource+0x0/0x1e @ 1
[ 1.752724] initcall hpet_insert_resource+0x0/0x1e returned 0 after 4 usecs
[ 1.752736] calling update_mp_table+0x0/0x40c @ 1
[ 1.752751] initcall update_mp_table+0x0/0x40c returned 0 after 1 usecs
[ 1.752764] calling lapic_insert_resource+0x0/0x45 @ 1
[ 1.752780] initcall lapic_insert_resource+0x0/0x45 returned 0 after 3 usecs
[ 1.752793] calling io_apic_bug_finalize+0x0/0x1a @ 1
[ 1.752808] initcall io_apic_bug_finalize+0x0/0x1a returned 0 after 1 usecs
[ 1.752821] calling print_ipi_mode+0x0/0x26 @ 1
[ 1.752830] Using IPI No-Shortcut mode
[ 1.752842] initcall print_ipi_mode+0x0/0x26 returned 0 after 8 usecs
[ 1.752855] calling check_early_ioremap_leak+0x0/0x47 @ 1
[ 1.752870] initcall check_early_ioremap_leak+0x0/0x47 returned 0 after 1 usecs
[ 1.752883] calling pat_memtype_list_init+0x0/0x2e @ 1
[ 1.752906] initcall pat_memtype_list_init+0x0/0x2e returned 0 after 7 usecs
[ 1.752918] calling efi_debugfs_setup+0x0/0x36 @ 1
[ 1.752939] initcall efi_debugfs_setup+0x0/0x36 returned 0 after 7 usecs
[ 1.752953] calling init_oops_id+0x0/0x3f @ 1
[ 1.752987] initcall init_oops_id+0x0/0x3f returned 0 after 15 usecs
[ 1.753002] calling printk_late_init+0x0/0x4d @ 1
[ 1.753082] initcall printk_late_init+0x0/0x4d returned 0 after 5 usecs
[ 1.753098] calling pm_qos_power_init+0x0/0xae @ 1
[ 1.753458] initcall pm_qos_power_init+0x0/0xae returned 0 after 335 usecs
[ 1.753473] calling clear_boot_tracer+0x0/0x27 @ 1
[ 1.753489] initcall clear_boot_tracer+0x0/0x27 returned 0 after 1 usecs
[ 1.753502] calling random32_reseed+0x0/0x83 @ 1
[ 1.753576] initcall random32_reseed+0x0/0x83 returned 0 after 60 usecs
[ 1.753589] calling pci_resource_alignment_sysfs_init+0x0/0x14 @ 1
[ 1.753612] initcall pci_resource_alignment_sysfs_init+0x0/0x14 returned 0 after 8 usecs
[ 1.753625] calling pci_sysfs_init+0x0/0x44 @ 1
[ 1.754248] initcall pci_sysfs_init+0x0/0x44 returned 0 after 592 usecs
[ 1.754262] calling random_int_secret_init+0x0/0x16 @ 1
[ 1.754325] initcall random_int_secret_init+0x0/0x16 returned 0 after 48 usecs
[ 1.754338] calling late_resume_init+0x0/0xca @ 1
[ 1.754349] Magic number: 7:60:597
[ 1.754500] initcall late_resume_init+0x0/0xca returned 0 after 145 usecs
[ 1.754513] calling scsi_complete_async_scans+0x0/0xfe @ 1
[ 1.754529] initcall scsi_complete_async_scans+0x0/0xfe returned 0 after 1 usecs
[ 1.754542] calling acpi_cpufreq_init+0x0/0x87 @ 1
[ 1.757473] initcall acpi_cpufreq_init+0x0/0x87 returned 0 after 2842 usecs
[ 1.757488] calling memmap_init+0x0/0x26 @ 1
[ 1.757535] initcall memmap_init+0x0/0x26 returned 0 after 31 usecs
[ 1.757548] calling pci_mmcfg_late_insert_resources+0x0/0x4f @ 1
[ 1.757571] initcall pci_mmcfg_late_insert_resources+0x0/0x4f returned 0 after 7 usecs
[ 1.757584] calling net_secret_init+0x0/0x16 @ 1
[ 1.757650] initcall net_secret_init+0x0/0x16 returned 0 after 50 usecs
[ 1.757662] calling tcp_congestion_default+0x0/0xf @ 1
[ 1.757679] initcall tcp_congestion_default+0x0/0xf returned 0 after 3 usecs
[ 1.757692] calling initialize_hashrnd+0x0/0x16 @ 1
[ 1.757715] initcall initialize_hashrnd+0x0/0x16 returned 0 after 9 usecs
[ 1.758261] async_waiting @ 1
[ 1.766798] input: AT Translated Set 2 keyboard as /devices/platform/i8042/serio0/input/input5
[ 1.819139] ata1: SATA link up 3.0 Gbps (SStatus 123 SControl 300)
[ 1.820219] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[ 1.820712] ata1.00: ATA-8: SanDisk SSD P4 16GB, SSD 8.13, max UDMA/133
[ 1.820727] ata1.00: 31277232 sectors, multi 1: LBA48
[ 1.821570] ata1.00: ACPI cmd ef/10:03:00:00:00:a0 (SET FEATURES) filtered out
[ 1.821695] ata1.00: configured for UDMA/133
[ 1.821750] async_waiting @ 5
[ 1.821763] async_continuing @ 5 after 2 usec
[ 1.821983] scsi 0:0:0:0: Direct-Access ATA SanDisk SSD P4 1 SSD PQ: 0 ANSI: 5
[ 1.822623] calling 6_sd_probe_async+0x0/0x183 @ 52
[ 1.822744] initcall 2_async_port_probe+0x0/0x50 returned 0 after 110906 usecs
[ 1.822834] async_continuing @ 43 after 110846 usec
[ 1.822890] sd 0:0:0:0: [sda] 31277232 512-byte logical blocks: (16.0 GB/14.9 GiB)
[ 1.822915] async_waiting @ 43
[ 1.822927] async_continuing @ 43 after 2 usec
[ 1.822944] initcall 3_async_port_probe+0x0/0x50 returned 0 after 110959 usecs
[ 1.822999] async_continuing @ 50 after 110912 usec
[ 1.823129] async_waiting @ 50
[ 1.823145] async_continuing @ 50 after 2 usec
[ 1.823166] initcall 4_async_port_probe+0x0/0x50 returned 0 after 111082 usecs
[ 1.823215] async_continuing @ 51 after 111024 usec
[ 1.823313] async_waiting @ 51
[ 1.823324] async_continuing @ 51 after 1 usec
[ 1.823341] initcall 5_async_port_probe+0x0/0x50 returned 0 after 111155 usecs
[ 1.823383] async_continuing @ 1 after 63583 usec
[ 1.823391] async_waiting @ 1
[ 1.823605] sd 0:0:0:0: [sda] Write Protect is off
[ 1.823622] sd 0:0:0:0: [sda] Mode Sense: 00 3a 00 00
[ 1.823768] sd 0:0:0:0: [sda] Write cache: enabled, read cache: enabled, doesn't support DPO or FUA
[ 1.830544] sda: sda1 sda2 sda3 sda4 sda5 sda6 sda7 sda8 sda9 sda10 sda11 sda12
[ 1.833556] sd 0:0:0:0: [sda] Attached SCSI disk
[ 1.833578] initcall 6_sd_probe_async+0x0/0x183 returned 0 after 10676 usecs
[ 1.833631] async_continuing @ 1 after 9988 usec
[ 1.833641] dm: attempting early device configuration.
[ 1.834352] device-mapper: init: adding target '0 1740800 verity payload=f3492094-3ff7-6042-9ecf-b32e378b5015+1 hashtree=f3492094-3ff7-6042-9ecf-b32e378b5015+1 hashstart=1740800 alg=sha1 root_hexdigest=edc631c21df4c08e9bc0599cc1bb028912287b26 salt=8111783a108908e0c3befe8f758dd10ab041c2123a4555768308bcb71b69ab48'
[ 1.834381] device-mapper: verity: Argument 0: 'payload=f3492094-3ff7-6042-9ecf-b32e378b5015+1'
[ 1.834394] device-mapper: verity: Argument 1: 'hashtree=f3492094-3ff7-6042-9ecf-b32e378b5015+1'
[ 1.834406] device-mapper: verity: Argument 2: 'hashstart=1740800'
[ 1.834417] device-mapper: verity: Argument 3: 'alg=sha1'
[ 1.834426] device-mapper: verity: Argument 4: 'root_hexdigest=edc631c21df4c08e9bc0599cc1bb028912287b26'
[ 1.834438] device-mapper: verity: Argument 5: 'salt=8111783a108908e0c3befe8f758dd10ab041c2123a4555768308bcb71b69ab48'
[ 1.834663] device-mapper: verity: dev:sda5 hash:sda5 [sectors:13720 blocks:217600]
[ 1.834880] dm: dm-0 is ready
[ 1.850074] VFS: Mounted root (ext2 filesystem) readonly on device 254:0.
[ 1.852762] devtmpfs: mounted
[ 1.852797] async_waiting @ 1
[ 1.852809] async_continuing @ 1 after 1 usec
[ 1.853054] Freeing unused kernel memory: 444k freed
[ 1.853660] Write protecting the kernel text: 3580k
[ 1.853822] Write protecting the kernel read-only data: 1376k
[ 1.853833] NX-protecting the kernel data: 2564k
[ 2.025135] usb 1-2: new high speed USB device number 2 using ehci_hcd
[ 2.038729] init: Failed to spawn help main process: unable to execute: No such file or directory
[ 2.056499] udevd[64]: starting version 171
[ 2.126933] [drm:drm_stub_open],
[ 2.126946] [drm:drm_open_helper], pid = 69, minor = 0
[ 2.126960] [drm:i915_driver_open],
[ 2.126971] [drm:drm_setup],
[ 2.126985] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127024] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127047] [drm:drm_release], open_count = 1
[ 2.127057] [drm:drm_release], pid = 69, device = 0xe200, open_count = 1
[ 2.127073] [drm:drm_lastclose],
[ 2.127081] [drm:drm_crtc_helper_set_config],
[ 2.127088] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 2.127111] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 2.127121] [drm:drm_lastclose], driver lastclose completed
[ 2.127129] [drm:drm_lastclose], lastclose completed
[ 2.127163] [drm:drm_stub_open],
[ 2.127171] [drm:drm_open_helper], pid = 69, minor = 0
[ 2.127184] [drm:i915_driver_open],
[ 2.127196] [drm:drm_setup],
[ 2.127210] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127225] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127242] [drm:drm_release], open_count = 1
[ 2.127250] [drm:drm_release], pid = 69, device = 0xe200, open_count = 1
[ 2.127262] [drm:drm_lastclose],
[ 2.127269] [drm:drm_crtc_helper_set_config],
[ 2.127275] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 2.127294] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 2.127304] [drm:drm_lastclose], driver lastclose completed
[ 2.127312] [drm:drm_lastclose], lastclose completed
[ 2.127343] [drm:drm_stub_open],
[ 2.127350] [drm:drm_open_helper], pid = 69, minor = 0
[ 2.127360] [drm:i915_driver_open],
[ 2.127369] [drm:drm_setup],
[ 2.127380] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127394] [drm:drm_ioctl], pid=69, cmd=0xc0246400, nr=0x00, dev 0xe200, auth=1
[ 2.127416] [drm:drm_ioctl], pid=69, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[ 2.127429] [drm:drm_ioctl], pid=69, cmd=0xc0086401, nr=0x01, dev 0xe200, auth=1
[ 2.127446] [drm:drm_ioctl], pid=69, cmd=0xc04064a0, nr=0xa0, dev 0xe200, auth=1
[ 2.127459] [drm:drm_mode_getresources], CRTC[2] CONNECTORS[2] ENCODERS[2]
[ 2.127470] [drm:drm_ioctl], pid=69, cmd=0xc04064a0, nr=0xa0, dev 0xe200, auth=1
[ 2.127483] [drm:drm_mode_getresources], CRTC[2] CONNECTORS[2] ENCODERS[2]
[ 2.127497] [drm:drm_ioctl], pid=69, cmd=0xc06864a1, nr=0xa1, dev 0xe200, auth=1
[ 2.127516] [drm:drm_ioctl], pid=69, cmd=0xc02064a5, nr=0xa5, dev 0xe200, auth=1
[ 2.127661] [drm:drm_release], open_count = 1
[ 2.127669] [drm:drm_release], pid = 69, device = 0xe200, open_count = 1
[ 2.127681] [drm:drm_lastclose],
[ 2.127688] [drm:drm_crtc_helper_set_config],
[ 2.127695] [drm:drm_crtc_helper_set_config], [CRTC:4] [FB:14] #connectors=1 (x y) (0 0)
[ 2.127714] [drm:drm_crtc_helper_set_config], [CONNECTOR:5:LVDS-1] to [CRTC:4]
[ 2.127724] [drm:drm_lastclose], driver lastclose completed
[ 2.127731] [drm:drm_lastclose], lastclose completed
[ 2.141934] usb 1-2: config 1 has an invalid interface number: 1 but max is 0
[ 2.141945] usb 1-2: config 1 has no interface number 0
[ 2.143821] usb 1-2: New USB device found, idVendor=05c6, idProduct=9244
[ 2.143837] usb 1-2: New USB device strings: Mfr=3, Product=2, SerialNumber=0
[ 2.143849] usb 1-2: Product: Qualcomm Gobi 2000
[ 2.143858] usb 1-2: Manufacturer: Qualcomm Incorporated
[ 2.247118] usb 1-4: new high speed USB device number 3 using ehci_hcd
[ 2.306528] calling init_module+0x0/0x1000 [qcserial] @ 112
[ 2.306607] USB Serial support registered for Qualcomm USB modem
[ 2.306664] qcserial 1-2:1.1: Qualcomm USB modem converter detected
[ 2.306887] usb 1-2: Qualcomm USB modem converter now attached to ttyUSB0
[ 2.306927] usbcore: registered new interface driver qcserial
[ 2.306938] initcall init_module+0x0/0x1000 [qcserial] returned 0 after 387 usecs
[ 2.377151] usb 1-4: New USB device found, idVendor=0b95, idProduct=7720
[ 2.377171] usb 1-4: New USB device strings: Mfr=1, Product=2, SerialNumber=3
[ 2.377187] usb 1-4: Product: AX88x72A
[ 2.377199] usb 1-4: Manufacturer: ASIX Elec. Corp.
[ 2.377211] usb 1-4: SerialNumber: 000001
[ 2.424735] calling init_module+0x0/0x1000 [usbnet] @ 151
[ 2.424768] initcall init_module+0x0/0x1000 [usbnet] returned 0 after 13 usecs
[ 2.429411] calling init_module+0x0/0x1000 [asix] @ 151
[ 2.481184] usb 1-5: new high speed USB device number 4 using ehci_hcd
[ 2.656200] Synaptics Touchpad, model: 1, fw: 8.0, id: 0x1e2b1, caps: 0xd00223/0x840300/0x122c00
[ 2.706963] input: SynPS/2 Synaptics TouchPad as /devices/platform/i8042/serio4/input/input6
[ 2.713710] usb 1-5: New USB device found, idVendor=2232, idProduct=1013
[ 2.713727] usb 1-5: New USB device strings: Mfr=1, Product=2, SerialNumber=0
[ 2.713734] usb 1-5: Product: WebCam SC-13HDO10B39N
[ 2.713739] usb 1-5: Manufacturer: Namuga
[ 2.733750] asix 1-4:1.0: eth0: register 'asix' at usb-0000:00:1d.7-4, ASIX AX88772 USB 2.0 Ethernet, 68:7f:74:b5:7d:bb
[ 2.733879] usbcore: registered new interface driver asix
[ 2.733901] initcall init_module+0x0/0x1000 [asix] returned 0 after 297332 usecs
[ 2.827382] serio_raw serio4: raw access enabled on isa0060/serio4 (serio_raw0, minor 1)
[ 3.156150] EXT4-fs (sda1): warning: maximal mount count reached, running e2fsck is recommended
[ 3.157806] EXT4-fs (sda1): recovery complete
[ 3.158693] EXT4-fs (sda1): mounted filesystem with ordered data mode. Opts: commit=600
[ 3.159379] calling init_module+0x0/0x1000 [videodev] @ 193
[ 3.159389] Linux video capture interface: v2.00
[ 3.159463] initcall init_module+0x0/0x1000 [videodev] returned 0 after 62 usecs
[ 3.170425] calling init_module+0x0/0x1000 [uvcvideo] @ 193
[ 3.170582] uvcvideo: Found UVC 1.00 device WebCam SC-13HDO10B39N (2232:1013)
[ 3.188084] usbcore: registered new interface driver uvcvideo
[ 3.188095] USB Video Class driver (v1.1.0)
[ 3.188120] initcall init_module+0x0/0x1000 [uvcvideo] returned 0 after 17252 usecs
[ 3.244270] [drm:intel_panel_get_max_backlight], max backlight PWM = 13046
[ 3.244281] [drm:intel_panel_set_backlight], set backlight PWM = 13046
[ 3.856733] EXT4-fs (sda1): re-mounted. Opts: user_xattr,acl,commit=600,barrier=1,data=ordered
[ 3.977810] EXT4-fs (sda1): re-mounted. Opts: user_xattr,acl,commit=600,barrier=1,data=ordered
[-- Attachment #3: alex.svg --]
[-- Type: image/svg+xml, Size: 10699 bytes --]
[-- Attachment #4: Type: text/plain, Size: 159 bytes --]
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
http://lists.freedesktop.org/mailman/listinfo/intel-gfx
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-13 19:55 i915_init takes a full second of kernel init time Scott James Remnant
@ 2011-12-13 20:02 ` Jesse Barnes
2011-12-13 21:34 ` Scott James Remnant
0 siblings, 1 reply; 10+ messages in thread
From: Jesse Barnes @ 2011-12-13 20:02 UTC (permalink / raw)
To: Scott James Remnant; +Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
[-- Attachment #1.1: Type: text/plain, Size: 665 bytes --]
On Tue, 13 Dec 2011 11:55:06 -0800
Scott James Remnant <keybuk@google.com> wrote:
> I've been investigating Chrome OS boot time and noticed the anomaly
> where i915_init takes up a considerable amount of kernel startup time,
> one second in fact. I've attached a full dmesg with drm.debug=0xff for
> analysis at Daniel's suggestion.
I'm not surprised... we haven't optimized init time in awhile and lots
of delays have crept in.
What kind of panel does this laptop have? Can you enable drm debugging
(drm.debug=1 on the boot line) and see where the big delays are in
modesetting?
Thanks,
--
Jesse Barnes, Intel Open Source Technology Center
[-- Attachment #1.2: signature.asc --]
[-- Type: application/pgp-signature, Size: 836 bytes --]
[-- Attachment #2: Type: text/plain, Size: 159 bytes --]
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
http://lists.freedesktop.org/mailman/listinfo/intel-gfx
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-13 20:02 ` Jesse Barnes
@ 2011-12-13 21:34 ` Scott James Remnant
2011-12-13 22:01 ` Jesse Barnes
0 siblings, 1 reply; 10+ messages in thread
From: Scott James Remnant @ 2011-12-13 21:34 UTC (permalink / raw)
To: Jesse Barnes; +Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
On Tue, Dec 13, 2011 at 12:02 PM, Jesse Barnes <jbarnes@virtuousgeek.org> wrote:
> On Tue, 13 Dec 2011 11:55:06 -0800
> Scott James Remnant <keybuk@google.com> wrote:
>
>> I've been investigating Chrome OS boot time and noticed the anomaly
>> where i915_init takes up a considerable amount of kernel startup time,
>> one second in fact. I've attached a full dmesg with drm.debug=0xff for
>> analysis at Daniel's suggestion.
>
> I'm not surprised... we haven't optimized init time in awhile and lots
> of delays have crept in.
>
> What kind of panel does this laptop have? Can you enable drm debugging
> (drm.debug=1 on the boot line) and see where the big delays are in
> modesetting?
>
Is this different to drm.debug=0xff ?
Scott
--
Scott James Remnant | Chrome OS Systems | keybuk@google.com | Google
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-13 21:34 ` Scott James Remnant
@ 2011-12-13 22:01 ` Jesse Barnes
2011-12-13 22:21 ` Chris Wilson
0 siblings, 1 reply; 10+ messages in thread
From: Jesse Barnes @ 2011-12-13 22:01 UTC (permalink / raw)
To: Scott James Remnant; +Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
[-- Attachment #1.1: Type: text/plain, Size: 2746 bytes --]
On Tue, 13 Dec 2011 13:34:38 -0800
Scott James Remnant <keybuk@google.com> wrote:
> On Tue, Dec 13, 2011 at 12:02 PM, Jesse Barnes <jbarnes@virtuousgeek.org> wrote:
> > On Tue, 13 Dec 2011 11:55:06 -0800
> > Scott James Remnant <keybuk@google.com> wrote:
> >
> >> I've been investigating Chrome OS boot time and noticed the anomaly
> >> where i915_init takes up a considerable amount of kernel startup time,
> >> one second in fact. I've attached a full dmesg with drm.debug=0xff for
> >> analysis at Daniel's suggestion.
> >
> > I'm not surprised... we haven't optimized init time in awhile and lots
> > of delays have crept in.
> >
> > What kind of panel does this laptop have? Can you enable drm debugging
> > (drm.debug=1 on the boot line) and see where the big delays are in
> > modesetting?
> >
> Is this different to drm.debug=0xff ?
Should be the same... and now I look again and see you already attached
it, doh!
Looks like there are a couple of big jumps:
[ 0.899057] [drm:intel_crtc_init], swapping pipes & planes for FBC
[ 0.952613] [drm:drm_sysfs_connector_add], adding "LVDS-1" to sysfs
Between these two calls, we do the output setup stuff in i915. There
are likely some delays there due to trying to fetch the EDID.
[ 0.952846] [drm:intel_panel_set_backlight], set backlight PWM = 0
[ 1.206060] [drm:i915_get_vblank_counter], trying to get vblank count for disabled pipe A
Not sure where this is coming from offhand...
[ 1.206153] [drm:pineview_update_wm], Self-refresh is disabled
[ 1.214189] [drm:init_status_page], render ring hws offset: 0x00000000
This looks like a mode set or CRTC disable happened before we
initialized the status page.
[ 1.239347] [drm:drm_mode_debug_printmodeline], Modeline 8:"1280x800" 60 70700 1280 1296 1344 1440 800 801 804 818 0x48 0xa
[ 1.273051] [drm:i9xx_update_plane], Writing base 00030000 00000000 0 0 5120
Looks like a mode set occurred, that'll also take awhile.
[ 1.273758] [drm:intel_lvds_enable], applying panel-fitter: 8, 0
[ 1.673051] [drm:intel_panel_set_backlight], set backlight PWM = 13046
So it's an LVDS machine... this is probably part of the LVDS mode set.
We had some async code to take all of this out of the boot time
critical path at least... I thought Chris merged them long ago but I
guess they were dropped. Chris?
In general, we can put a lot of the stuff we do into delayed work
handlers; e.g. when we shut things off we're often supposed to wait for
a full frame (i.e. the next vblank) before doing anything else. This
could be done with a semaphore and delayed work though to keep things
snappy.
--
Jesse Barnes, Intel Open Source Technology Center
[-- Attachment #1.2: signature.asc --]
[-- Type: application/pgp-signature, Size: 836 bytes --]
[-- Attachment #2: Type: text/plain, Size: 159 bytes --]
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
http://lists.freedesktop.org/mailman/listinfo/intel-gfx
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-13 22:01 ` Jesse Barnes
@ 2011-12-13 22:21 ` Chris Wilson
2011-12-15 0:38 ` Scott James Remnant
0 siblings, 1 reply; 10+ messages in thread
From: Chris Wilson @ 2011-12-13 22:21 UTC (permalink / raw)
To: Jesse Barnes, Scott James Remnant; +Cc: intel-gfx, Daniel Stone
On Tue, 13 Dec 2011 14:01:29 -0800, Jesse Barnes <jbarnes@virtuousgeek.org> wrote:
> We had some async code to take all of this out of the boot time
> critical path at least... I thought Chris merged them long ago but I
> guess they were dropped. Chris?
It never made it upstream because it had a tendency to hang machines
during boot, as the async code was broken at the time wrt handling
multiple async domains and it interacted badly with PIO hard disk
controllers.
After a little bit of digging I found:
http://cgit.freedesktop.org/~ickle/linux-2.6/commit/?h=async&id=470d6985b508466308fc4c6aec945cdbf6de39b8
-Chris
--
Chris Wilson, Intel Open Source Technology Centre
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-13 22:21 ` Chris Wilson
@ 2011-12-15 0:38 ` Scott James Remnant
2011-12-15 10:38 ` Chris Wilson
0 siblings, 1 reply; 10+ messages in thread
From: Scott James Remnant @ 2011-12-15 0:38 UTC (permalink / raw)
To: Chris Wilson; +Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
On Tue, Dec 13, 2011 at 2:21 PM, Chris Wilson <chris@chris-wilson.co.uk> wrote:
>
> On Tue, 13 Dec 2011 14:01:29 -0800, Jesse Barnes <jbarnes@virtuousgeek.org> wrote:
> > We had some async code to take all of this out of the boot time
> > critical path at least... I thought Chris merged them long ago but I
> > guess they were dropped. Chris?
>
> It never made it upstream because it had a tendency to hang machines
> during boot, as the async code was broken at the time wrt handling
> multiple async domains and it interacted badly with PIO hard disk
> controllers.
>
> After a little bit of digging I found:
> http://cgit.freedesktop.org/~ickle/linux-2.6/commit/?h=async&id=470d6985b508466308fc4c6aec945cdbf6de39b8
> -Chris
>
I've tried this patch, but it doesn't really reduce the startup time
by much, the mainline of i915_init is still taking >0.7s with the
patch applied.
Scott
--
Scott James Remnant | Chrome OS Systems | keybuk@google.com | Google
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-15 0:38 ` Scott James Remnant
@ 2011-12-15 10:38 ` Chris Wilson
2011-12-15 17:48 ` Keith Packard
2011-12-15 21:48 ` Scott James Remnant
0 siblings, 2 replies; 10+ messages in thread
From: Chris Wilson @ 2011-12-15 10:38 UTC (permalink / raw)
To: Scott James Remnant; +Cc: intel-gfx, Daniel Stone
On Wed, 14 Dec 2011 16:38:09 -0800, Scott James Remnant <keybuk@google.com> wrote:
> > After a little bit of digging I found:
> > http://cgit.freedesktop.org/~ickle/linux-2.6/commit/?h=async&id=470d6985b508466308fc4c6aec945cdbf6de39b8
> > -Chris
> >
> I've tried this patch, but it doesn't really reduce the startup time
> by much, the mainline of i915_init is still taking >0.7s with the
> patch applied.
Reverting de842eff4101 (drm/i915: Wait for LVDS panel power sequence)
should get another 0.4s back if intel_lvds_enable() is still in the
critical path. After that the focus looks to be upon speeding up
modeset.
-Chris
--
Chris Wilson, Intel Open Source Technology Centre
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-15 10:38 ` Chris Wilson
@ 2011-12-15 17:48 ` Keith Packard
2011-12-15 21:48 ` Scott James Remnant
1 sibling, 0 replies; 10+ messages in thread
From: Keith Packard @ 2011-12-15 17:48 UTC (permalink / raw)
To: Chris Wilson, Scott James Remnant
Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
[-- Attachment #1.1: Type: text/plain, Size: 746 bytes --]
On Thu, 15 Dec 2011 10:38:16 +0000, Chris Wilson <chris@chris-wilson.co.uk> wrote:
> Reverting de842eff4101 (drm/i915: Wait for LVDS panel power sequence)
> should get another 0.4s back if intel_lvds_enable() is still in the
> critical path. After that the focus looks to be upon speeding up
> modeset.
*not* doing modeset for LVDS/eDP is the key here -- the panel is
probably already running, and all we need to do is change the scaling
parameters, which (on new hardware) doesn't require a full mode set.
I worked with vorlon to investigate what this would take and got pretty
far down the path; the big missing piece is that we have to have
a valid scanout buffer for the whole setup sequence.
--
keith.packard@intel.com
[-- Attachment #1.2: Type: application/pgp-signature, Size: 827 bytes --]
[-- Attachment #2: Type: text/plain, Size: 159 bytes --]
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
http://lists.freedesktop.org/mailman/listinfo/intel-gfx
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-15 10:38 ` Chris Wilson
2011-12-15 17:48 ` Keith Packard
@ 2011-12-15 21:48 ` Scott James Remnant
2011-12-15 22:22 ` Keith Packard
1 sibling, 1 reply; 10+ messages in thread
From: Scott James Remnant @ 2011-12-15 21:48 UTC (permalink / raw)
To: Chris Wilson; +Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
On Thu, Dec 15, 2011 at 2:38 AM, Chris Wilson <chris@chris-wilson.co.uk> wrote:
> On Wed, 14 Dec 2011 16:38:09 -0800, Scott James Remnant <keybuk@google.com> wrote:
>> > After a little bit of digging I found:
>> > http://cgit.freedesktop.org/~ickle/linux-2.6/commit/?h=async&id=470d6985b508466308fc4c6aec945cdbf6de39b8
>> > -Chris
>> >
>> I've tried this patch, but it doesn't really reduce the startup time
>> by much, the mainline of i915_init is still taking >0.7s with the
>> patch applied.
>
> Reverting de842eff4101 (drm/i915: Wait for LVDS panel power sequence)
> should get another 0.4s back if intel_lvds_enable() is still in the
> critical path. After that the focus looks to be upon speeding up
> modeset.
>
This gives us a significant chunk of time back (>0.5s) - what would be
the downside of carrying a revert to this patch?
Scott
--
Scott James Remnant | Chrome OS Systems | keybuk@google.com | Google
^ permalink raw reply [flat|nested] 10+ messages in thread
* Re: i915_init takes a full second of kernel init time
2011-12-15 21:48 ` Scott James Remnant
@ 2011-12-15 22:22 ` Keith Packard
0 siblings, 0 replies; 10+ messages in thread
From: Keith Packard @ 2011-12-15 22:22 UTC (permalink / raw)
To: Scott James Remnant, Chris Wilson
Cc: intel-gfx, Stéphane Marchesin, Daniel Stone
[-- Attachment #1.1: Type: text/plain, Size: 494 bytes --]
On Thu, 15 Dec 2011 13:48:00 -0800, Scott James Remnant <keybuk@google.com> wrote:
> This gives us a significant chunk of time back (>0.5s) - what would be
> the downside of carrying a revert to this patch?
It fails to light up some LVDS panels, causing machines to not be
usable.
That's generally what we've found when we try to take short-cuts through
the mode setting code; many machines will work fine, but a few take
their pixels and go home.
--
keith.packard@intel.com
[-- Attachment #1.2: Type: application/pgp-signature, Size: 827 bytes --]
[-- Attachment #2: Type: text/plain, Size: 159 bytes --]
_______________________________________________
Intel-gfx mailing list
Intel-gfx@lists.freedesktop.org
http://lists.freedesktop.org/mailman/listinfo/intel-gfx
^ permalink raw reply [flat|nested] 10+ messages in thread
end of thread, other threads:[~2011-12-15 22:21 UTC | newest]
Thread overview: 10+ messages (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2011-12-13 19:55 i915_init takes a full second of kernel init time Scott James Remnant
2011-12-13 20:02 ` Jesse Barnes
2011-12-13 21:34 ` Scott James Remnant
2011-12-13 22:01 ` Jesse Barnes
2011-12-13 22:21 ` Chris Wilson
2011-12-15 0:38 ` Scott James Remnant
2011-12-15 10:38 ` Chris Wilson
2011-12-15 17:48 ` Keith Packard
2011-12-15 21:48 ` Scott James Remnant
2011-12-15 22:22 ` Keith Packard
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox