LKML Archive on lore.kernel.org
help / color / mirror / Atom feed
* [x86_64/KASan] RIP: 0010:[<ffffffff811ee644>] [<ffffffff811ee644>] get_node_info
@ 2015-03-23  1:25 Fengguang Wu
  2015-03-23  7:13 ` Andrey Ryabinin
  2015-04-02 10:49 ` [PATCH] gcov: fix softlockups Andrey Ryabinin
  0 siblings, 2 replies; 3+ messages in thread
From: Fengguang Wu @ 2015-03-23  1:25 UTC (permalink / raw)
  To: Andrey Ryabinin; +Cc: fengguang.wu, LKP, linux-kernel

[-- Attachment #1: Type: text/plain, Size: 21076 bytes --]

Greetings,

0day kernel testing robot got the below dmesg and the first bad commit is

git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master

commit ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2
Author:     Andrey Ryabinin <a.ryabinin@samsung.com>
AuthorDate: Fri Feb 13 14:39:25 2015 -0800
Commit:     Linus Torvalds <torvalds@linux-foundation.org>
CommitDate: Fri Feb 13 21:21:41 2015 -0800

    x86_64: add KASan support
    
    This patch adds arch specific code for kernel address sanitizer.
    
    16TB of virtual addressed used for shadow memory.  It's located in range
    [ffffec0000000000 - fffffc0000000000] between vmemmap and %esp fixup
    stacks.
    
    At early stage we map whole shadow region with zero page.  Latter, after
    pages mapped to direct mapping address range we unmap zero pages from
    corresponding shadow (see kasan_map_shadow()) and allocate and map a real
    shadow memory reusing vmemmap_populate() function.
    
    Also replace __pa with __pa_nodebug before shadow initialized.  __pa with
    CONFIG_DEBUG_VIRTUAL=y make external function call (__phys_addr)
    __phys_addr is instrumented, so __asan_load could be called before shadow
    area initialized.
    
    Signed-off-by: Andrey Ryabinin <a.ryabinin@samsung.com>
    Cc: Dmitry Vyukov <dvyukov@google.com>
    Cc: Konstantin Serebryany <kcc@google.com>
    Cc: Dmitry Chernenkov <dmitryc@google.com>
    Signed-off-by: Andrey Konovalov <adech.fo@gmail.com>
    Cc: Yuri Gribov <tetra2005@gmail.com>
    Cc: Konstantin Khlebnikov <koct9i@gmail.com>
    Cc: Sasha Levin <sasha.levin@oracle.com>
    Cc: Christoph Lameter <cl@linux.com>
    Cc: Joonsoo Kim <iamjoonsoo.kim@lge.com>
    Cc: Dave Hansen <dave.hansen@intel.com>
    Cc: Andi Kleen <andi@firstfloor.org>
    Cc: Ingo Molnar <mingo@elte.hu>
    Cc: Thomas Gleixner <tglx@linutronix.de>
    Cc: "H. Peter Anvin" <hpa@zytor.com>
    Cc: Christoph Lameter <cl@linux.com>
    Cc: Pekka Enberg <penberg@kernel.org>
    Cc: David Rientjes <rientjes@google.com>
    Cc: Jim Davis <jim.epost@gmail.com>
    Signed-off-by: Andrew Morton <akpm@linux-foundation.org>
    Signed-off-by: Linus Torvalds <torvalds@linux-foundation.org>

+------------------------------------------------+------------+------------+------------+
|                                                | 786a895991 | ef7f0d6a6c | 22e834f5b2 |
+------------------------------------------------+------------+------------+------------+
| boot_successes                                 | 1866       | 738        | 9          |
| boot_failures                                  | 34         | 162        | 15         |
| BUG:kernel_test_crashed                        | 34         | 16         |            |
| RIP:kasan_mem_to_shadow                        | 0          | 13         |            |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 0          | 146        | 13         |
| backtrace:gcov_enable_events                   | 0          | 144        | 13         |
| backtrace:gcov_fs_init                         | 0          | 144        | 13         |
| backtrace:kernel_init_freeable                 | 0          | 146        | 13         |
| RIP:strcmp                                     | 0          | 18         | 1          |
| RIP:__asan_load8                               | 0          | 8          | 1          |
| RIP:__asan_load1                               | 0          | 62         | 9          |
| RIP:arch_local_irq_restore                     | 0          | 23         |            |
| RIP:get_node_info                              | 0          | 8          | 2          |
| RIP:__asan_load4                               | 0          | 9          |            |
| RIP:get_node_by_name                           | 0          | 1          |            |
| RIP:gcov_info_filename                         | 0          | 2          |            |
| RIP:__asan_store8                              | 0          | 1          |            |
| backtrace:pmu_dev_alloc                        | 0          | 1          |            |
| backtrace:perf_event_sysfs_init                | 0          | 1          |            |
| RIP:__schedule                                 | 0          | 1          |            |
| backtrace:kswapd_run                           | 0          | 1          |            |
| backtrace:kswapd_init                          | 0          | 1          |            |
| BUG:kernel_test_hang                           | 0          | 0          | 2          |
+------------------------------------------------+------------+------------+------------+

[   61.140344] irq event stamp: 1434402
[   61.140344] irq event stamp: 1434402
[   61.140344] hardirqs last  enabled at (1434401): 
[   61.140344] hardirqs last  enabled at (1434401): [<ffffffff821ac5af>] mutex_lock_nested+0x651/0x745
[<ffffffff821ac5af>] mutex_lock_nested+0x651/0x745
[   61.140344] hardirqs last disabled at (1434402): 
[   61.140344] hardirqs last disabled at (1434402): [<ffffffff821b395a>] apic_timer_interrupt+0x6a/0x80
[<ffffffff821b395a>] apic_timer_interrupt+0x6a/0x80
[   61.140344] softirqs last  enabled at (1434180): 
[   61.140344] softirqs last  enabled at (1434180): [<ffffffff81130203>] __do_softirq+0x375/0x40c
[<ffffffff81130203>] __do_softirq+0x375/0x40c
[   61.140344] softirqs last disabled at (1434173): 
[   61.140344] softirqs last disabled at (1434173): [<ffffffff811306c4>] irq_exit+0x8e/0x1fb
[<ffffffff811306c4>] irq_exit+0x8e/0x1fb
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-04819-gef7f0d6 #5
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-04819-gef7f0d6 #5
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] task: ffff88000f500000 ti: ffff88000f508000 task.ti: ffff88000f508000
[   61.140344] task: ffff88000f500000 ti: ffff88000f508000 task.ti: ffff88000f508000
[   61.140344] RIP: 0010:[<ffffffff811ee644>] 
[   61.140344] RIP: 0010:[<ffffffff811ee644>]  [<ffffffff811ee644>] get_node_info+0x25/0x51
 [<ffffffff811ee644>] get_node_info+0x25/0x51
[   61.140344] RSP: 0000:ffff88000f50bd98  EFLAGS: 00000292
[   61.140344] RSP: 0000:ffff88000f50bd98  EFLAGS: 00000292
[   61.140344] RAX: ffffed00027709cf RBX: 0000000000000206 RCX: ffffffff811ef606
[   61.140344] RAX: ffffed00027709cf RBX: 0000000000000206 RCX: ffffffff811ef606
[   61.140344] RDX: dffffc0000000000 RSI: ffffffff82853516 RDI: 1ffff100027709cf
[   61.140344] RDX: dffffc0000000000 RSI: ffffffff82853516 RDI: 1ffff100027709cf
[   61.140344] RBP: ffff88000f50bda8 R08: 0000000000000001 R09: 0000000000000000
[   61.140344] RBP: ffff88000f50bda8 R08: 0000000000000001 R09: 0000000000000000
[   61.140344] R10: ffffed00025e0297 R11: 000000000000011f R12: ffff88000f500000
[   61.140344] R10: ffffed00025e0297 R11: 000000000000011f R12: ffff88000f500000
[   61.140344] R13: ffffffff821ac5af R14: ffff88000f500000 R15: ffffffff811ef602
[   61.140344] R13: ffffffff821ac5af R14: ffff88000f500000 R15: ffffffff811ef602
[   61.140344] FS:  0000000000000000(0000) GS:ffff88000f800000(0000) knlGS:0000000000000000
[   61.140344] FS:  0000000000000000(0000) GS:ffff88000f800000(0000) knlGS:0000000000000000
[   61.140344] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   61.140344] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   61.140344] CR2: 0000000000000000 CR3: 0000000002990000 CR4: 00000000000006b0
[   61.140344] CR2: 0000000000000000 CR3: 0000000002990000 CR4: 00000000000006b0
[   61.140344] Stack:
[   61.140344] Stack:
[   61.140344]  ffffffff811ef606
[   61.140344]  ffffffff811ef606 ffff880013b84e40 ffff880013b84e40 ffff88000f50bdd8 ffff88000f50bdd8 ffffffff811ee6ba ffffffff811ee6ba

[   61.140344]  ffffffff811ef606
[   61.140344]  ffffffff811ef606 ffffffff829b5000 ffffffff829b5000 ffffffff829b5000 ffffffff829b5000 0000000000000000 0000000000000000

[   61.140344]  ffff88000f50be38
[   61.140344]  ffff88000f50be38 ffffffff811ef65e ffffffff811ef65e ffff88000f50bdf8 ffff88000f50bdf8 ffffffff821af754 ffffffff821af754

[   61.140344] Call Trace:
[   61.140344] Call Trace:
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344] Code: 
[   61.140344] Code: 02 02 48 48 89 89 e5 e5 5d 5d c3 c3 55 55 48 48 89 89 e5 e5 53 53 48 48 89 89 fb fb 51 51 48 48 8d 8d 7f 7f 58 58 e8 e8 29 29 cc cc 0a 0a 00 00 83 83 7b 7b 58 58 00 00 7e 7e 21 21 48 48 8d 8d 7b 7b 38 38 e8 e8 39 39 ce ce 0a 0a 00 00 48 48 8b 8b 5b 5b 38 38 <48> <48> 89 89 df df e8 e8 2d 2d ce ce 0a 0a 00 00 48 48 8b 8b 03 03 48 48 ff ff 05 05 6a 6a 85 85 ce ce 02 02 eb eb 14 14 48 48 

[   61.140344] Kernel panic - not syncing: softlockup: hung tasks
[   61.140344] Kernel panic - not syncing: softlockup: hung tasks
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G             L  3.19.0-04819-gef7f0d6 #5
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G             L  3.19.0-04819-gef7f0d6 #5
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344]  0000000000000000
[   61.140344]  0000000000000000 0000000000000000 0000000000000000 0000000000000000 0000000000000000 ffff88000f803de8 ffff88000f803de8

[   61.140344]  ffffffff821a4805
[   61.140344]  ffffffff821a4805 dffffc0000000000 dffffc0000000000 ffffffff8286a2ed ffffffff8286a2ed ffff88000f803e68 ffff88000f803e68

[   61.140344]  ffffffff8219ce07
[   61.140344]  ffffffff8219ce07 ffff88000f803e68 ffff88000f803e68 ffffffff00000008 ffffffff00000008 ffff88000f803e78 ffff88000f803e78

[   61.140344] Call Trace:
[   61.140344] Call Trace:
[   61.140344]  <IRQ> 
[   61.140344]  <IRQ>  [<ffffffff821a4805>] dump_stack+0xa0/0xd5
 [<ffffffff821a4805>] dump_stack+0xa0/0xd5
[   61.140344]  [<ffffffff8219ce07>] panic+0x158/0x34d
[   61.140344]  [<ffffffff8219ce07>] panic+0x158/0x34d
[   61.140344]  [<ffffffff811f19d4>] watchdog_timer_fn+0x2fa/0x32e
[   61.140344]  [<ffffffff811f19d4>] watchdog_timer_fn+0x2fa/0x32e
[   61.140344]  [<ffffffff811f16da>] ? watchdog_overflow_callback+0xf6/0xf6
[   61.140344]  [<ffffffff811f16da>] ? watchdog_overflow_callback+0xf6/0xf6
[   61.140344]  [<ffffffff811b7a87>] __run_hrtimer+0x99/0x158
[   61.140344]  [<ffffffff811b7a87>] __run_hrtimer+0x99/0x158
[   61.140344]  [<ffffffff811b889e>] hrtimer_interrupt+0x171/0x301
[   61.140344]  [<ffffffff811b889e>] hrtimer_interrupt+0x171/0x301
[   61.140344]  [<ffffffff810892df>] local_apic_timer_interrupt+0x8f/0x9e
[   61.140344]  [<ffffffff810892df>] local_apic_timer_interrupt+0x8f/0x9e
[   61.140344]  [<ffffffff81089d85>] smp_apic_timer_interrupt+0x2e/0x4d
[   61.140344]  [<ffffffff81089d85>] smp_apic_timer_interrupt+0x2e/0x4d
[   61.140344]  [<ffffffff821b395f>] apic_timer_interrupt+0x6f/0x80
[   61.140344]  [<ffffffff821b395f>] apic_timer_interrupt+0x6f/0x80
[   61.140344]  <EOI> 
[   61.140344]  <EOI>  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
 [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee644>] ? get_node_info+0x25/0x51
[   61.140344]  [<ffffffff811ee644>] ? get_node_info+0x25/0x51
[   61.140344]  [<ffffffff811ee640>] ? get_node_info+0x21/0x51
[   61.140344]  [<ffffffff811ee640>] ? get_node_info+0x21/0x51
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)
[   61.140344] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)

git bisect start 06e5801b8cb3fc057d88cb4dc03c0b64b2744cda v2.6.39 --
git bisect good 7810cc1e7721220f1ed2a23ca95113d6434f6dcd  # 13:21    900+   1060  digsig: Fix memory leakage in digsig_verify_rsa()
git bisect good f8f202348208fa8a2d817b42f250e145fa885620  # 13:54    900+    202  x86: Disable CONFIG_X86_DECODER_SELFTEST in allmod/allyesconfigs
git bisect good ad6ede80a0ad0eba6e0f77e35ab7753c60ed1e87  # 15:08    900+     26  Merge tag 'pm+acpi-3.17-rc3' of git://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm
git bisect good 350e4f4985472e29091b899bc227d75d2a66fb4c  # 15:52    900+     18  Merge tag 'trace-seq-buf-3.19' of git://git.kernel.org/pub/scm/linux/kernel/git/rostedt/linux-trace
git bisect good 6155bc14315763cf0f1989b3636ccc2f3e57f0d6  # 16:56    900+     15  Merge branch 'perf-urgent-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect  bad 05016b0f0a9d900e976db7f50a7761c0aefe5a1c  # 17:08     67-    104  Merge branch 'getname2' of git://git.kernel.org/pub/scm/linux/kernel/git/viro/vfs
git bisect good 6fc26fc5783add961533c819995bd97db05990f0  # 18:10    900+     16  Merge tag 'hsi-for-3.20' of git://git.kernel.org/pub/scm/linux/kernel/git/sre/linux-hsi
git bisect good 8329aa9fff3fca84009e6a444d8d160193643bac  # 18:47    900+     14  Revert "x86/apic: Only disable CPU x2apic mode when necessary"
git bisect  bad 37507717de51a8332a34ee07fd88700be88df5bf  # 19:38    121-     26  Merge branch 'perf-core-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect  bad 8c988ae787af4900bec5410658e8a82844185c85  # 19:59     36-      7  Merge branch 'for-linus-v3.20' of git://git.infradead.org/linux-ubifs
git bisect good 18320f2a6871aaf2522f793fee4a67eccf5e131a  # 21:35    900+     14  Merge tag 'pm+acpi-3.20-rc1-2' of git://git.kernel.org/pub/scm/linux/kernel/git/rafael/linux-pm
git bisect  bad fee5429e028c414d80d036198db30454cfd91b7a  # 21:57     58-     11  Merge git://git.kernel.org/pub/scm/linux/kernel/git/herbert/crypto-2.6
git bisect  bad 83e047c104aa95a8a683d6bd421df1551c17dbd2  # 22:02      1-      2  Merge branch 'akpm' (patches from Andrew)
git bisect good 327953e9af6c59ad111b28359e59e3ec0cbd71b6  # 23:47    900+     13  checkpatch: add check for keyword 'boolean' in Kconfig definitions
git bisect  bad 3f15801cdc2379ca4bf507f48bffd788f9e508ae  # 00:01     16-      7  lib: add kasan test module
git bisect good 0f3c5aab5e00527eb3167aa9d1725cca9320e01e  # 01:22    900+     12  checkpatch: add of_device_id to structs that should be const
git bisect  bad b8c73fc2493d42517be95cf2c89659fc6c6f4d02  # 01:43     10-      8  mm: page_alloc: add kasan hooks on alloc and free paths
git bisect good cb4188ac8e5779f66b9f55888ac2c75b391cde44  # 03:27    900+     15  compiler: introduce __alias(symbol) shortcut
git bisect good 786a8959912eb94fc2381c2ae487a96ce55dabca  # 04:05    900+     20  kasan: disable memory hotplug
git bisect  bad ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2  # 04:43     78-     30  x86_64: add KASan support
# first bad commit: [ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2] x86_64: add KASan support
git bisect good 786a8959912eb94fc2381c2ae487a96ce55dabca  # 06:36   1000+     34  kasan: disable memory hotplug
# extra tests with DEBUG_INFO
git bisect good ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2  # 08:31   1000+      9  x86_64: add KASan support
# extra tests on HEAD of linux-devel/devel-lkp-nhm1-smoke-201503220558
git bisect  bad 22e834f5b2e4a0cfc401f248ec3d57bfec9fa1b8  # 08:31      0-     15  0day head guard for 'devel-lkp-nhm1-smoke-201503220558'
# extra tests on tree/branch linus/master
git bisect  bad 4541c226057ab55f2ad6a330a0bb519a0000f9ba  # 08:46     27-     25  Merge tag 'driver-core-4.0-rc5' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/driver-core
# extra tests on tree/branch linus/master
git bisect  bad 4541c226057ab55f2ad6a330a0bb519a0000f9ba  # 08:46      0-     25  Merge tag 'driver-core-4.0-rc5' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/driver-core
# extra tests on tree/branch next/master


This script may reproduce the error.

----------------------------------------------------------------------------
#!/bin/bash

kernel=$1
initrd=quantal-core-x86_64.cgz

wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd

kvm=(
	qemu-system-x86_64
	-cpu kvm64
	-enable-kvm
	-kernel $kernel
	-initrd $initrd
	-m 320
	-smp 2
	-net nic,vlan=1,model=e1000
	-net user,vlan=1
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-rtc base=localtime
	-serial stdio
	-display none
	-monitor null 
)

append=(
	hung_task_panic=1
	earlyprintk=ttyS0,115200
	rd.udev.log-priority=err
	systemd.log_target=journal
	systemd.log_level=warning
	debug
	apic=debug
	sysrq_always_enabled
	rcupdate.rcu_cpu_stall_timeout=100
	panic=-1
	softlockup_panic=1
	nmi_watchdog=panic
	oops=panic
	load_ramdisk=2
	prompt_ramdisk=0
	console=ttyS0,115200
	console=tty0
	vga=normal
	root=/dev/ram0
	rw
	drbd.minor_count=8
)

"${kvm[@]}" --append "${append[*]}"
----------------------------------------------------------------------------

Thanks,
Fengguang

[-- Attachment #2: dmesg-quantal-client9-5:20150323061539:x86_64-randconfig-n0-03220557:3.19.0-04819-gef7f0d6:5 --]
[-- Type: text/plain, Size: 83435 bytes --]

early console in setup code
early console in decompress_kernel

Decompressing Linux... Parsing ELF... done.
Booting the kernel.
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.19.0-04819-gef7f0d6 (kbuild@lkp-nhm1) (gcc version 4.9.1 (Debian 4.9.1-19) ) #5 SMP Mon Mar 23 04:07:30 CST 2015
[    0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal  root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-03220557/linux-devel:devel-lkp-nhm1-smoke-201503220558:ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2:bisect-linux/.vmlinuz-ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2-20150323040824-847-client9 branch=linux-devel/devel-lkp-nhm1-smoke-201503220558 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 drbd.minor_count=8
[    0.000000] KERNEL supported cpus:
[    0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init.
[    0.000000] CPU: Your system may be unstable.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000013fdffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x13fe0 max_arch_pfn = 0x400000000
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x04565000, 0x04565fff] PGTABLE
[    0.000000] BRK [0x04566000, 0x04566fff] PGTABLE
[    0.000000] BRK [0x04567000, 0x04567fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x12600000-0x127fffff]
[    0.000000]  [mem 0x12600000-0x127fffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x00100000-0x125fffff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x125fffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x12800000-0x13fdffff]
[    0.000000]  [mem 0x12800000-0x13dfffff] page 2M
[    0.000000]  [mem 0x13e00000-0x13fdffff] page 4k
[    0.000000] BRK [0x04568000, 0x04568fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x12925000-0x13fd7fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F0C90 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE18BD 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE0B37 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 000AF7 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: SSDT 0x0000000013FE0BAB 000C5A (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x0000000013FE1805 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE1885 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff57d000 (        fee00000)
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:128a5001, primary cpu clock
[    0.000000]  [ffffea0000000000-ffffea00005fffff] PMD -> [ffff880011a00000-ffff880011ffffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x0000000013fdffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000013fdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fdffff]
[    0.000000] On node 0 totalpages: 81790
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 1216 pages used for memmap
[    0.000000]   DMA32 zone: 77792 pages, LIFO batch:15
[    0.000000]  [ffffed0000000000-ffffed00001fffff] PMD -> [ffff880012600000-ffff8800127fffff] on node -1
[    0.000000]  [ffffed0000200000-ffffed00003fffff] PMD -> [ffff880012400000-ffff8800125fffff] on node -1
[    0.000000]  [ffffed0000400000-ffffed00005fffff] PMD -> [ffff880012200000-ffff8800123fffff] on node -1
[    0.000000]  [ffffed0000600000-ffffed00007fffff] PMD -> [ffff880012000000-ffff8800121fffff] on node -1
[    0.000000]  [ffffed0000800000-ffffed00009fffff] PMD -> [ffff880011800000-ffff8800119fffff] on node -1
[    0.000000]  [ffffed0000a00000-ffffed0000bfffff] PMD -> [ffff880011600000-ffff8800117fffff] on node -1
[    0.000000]  [ffffed0000c00000-ffffed0000dfffff] PMD -> [ffff880011400000-ffff8800115fffff] on node -1
[    0.000000]  [ffffed0000e00000-ffffed0000ffffff] PMD -> [ffff880011200000-ffff8800113fffff] on node -1
[    0.000000]  [ffffed0001000000-ffffed00011fffff] PMD -> [ffff880011000000-ffff8800111fffff] on node -1
[    0.000000]  [ffffed0001200000-ffffed00013fffff] PMD -> [ffff880010e00000-ffff880010ffffff] on node -1
[    0.000000]  [ffffed0001400000-ffffed00015fffff] PMD -> [ffff880010c00000-ffff880010dfffff] on node -1
[    0.000000]  [ffffed0001600000-ffffed00017fffff] PMD -> [ffff880010a00000-ffff880010bfffff] on node -1
[    0.000000]  [ffffed0001800000-ffffed00019fffff] PMD -> [ffff880010800000-ffff8800109fffff] on node -1
[    0.000000]  [ffffed0001a00000-ffffed0001bfffff] PMD -> [ffff880010600000-ffff8800107fffff] on node -1
[    0.000000]  [ffffed0001c00000-ffffed0001dfffff] PMD -> [ffff880010400000-ffff8800105fffff] on node -1
[    0.000000]  [ffffed0001e00000-ffffed0001ffffff] PMD -> [ffff880010200000-ffff8800103fffff] on node -1
[    0.000000]  [ffffed0002000000-ffffed00021fffff] PMD -> [ffff880010000000-ffff8800101fffff] on node -1
[    0.000000]  [ffffed0002200000-ffffed00023fffff] PMD -> [ffff88000fe00000-ffff88000fffffff] on node -1
[    0.000000]  [ffffed0002400000-ffffed00025fffff] PMD -> [ffff88000fc00000-ffff88000fdfffff] on node -1
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff57d000 (        fee00000)
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffffffff57c000 (fec00000)
[    0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] PERCPU: Embedded 28 pages/cpu @ffff88000f800000 s84224 r0 d30464 u1048576
[    0.000000] pcpu-alloc: s84224 r0 d30464 u1048576 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr f80d280
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 80489
[    0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal  root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-03220557/linux-devel:devel-lkp-nhm1-smoke-201503220558:ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2:bisect-linux/.vmlinuz-ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2-20150323040824-847-client9 branch=linux-devel/devel-lkp-nhm1-smoke-201503220558 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 drbd.minor_count=8
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 199900K/327160K available (18131K kernel code, 6550K rwdata, 7948K rodata, 1164K init, 20800K bss, 127260K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] Running RCU self tests
[    0.000000] NR_IRQS:524544 nr_irqs:440 16
[    0.000000] Initializing cgroup subsys cpuset
[    0.000000] Initializing cgroup subsys cpu
[    0.000000] Linux version 3.19.0-04819-gef7f0d6 (kbuild@lkp-nhm1) (gcc version 4.9.1 (Debian 4.9.1-19) ) #5 SMP Mon Mar 23 04:07:30 CST 2015
[    0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal  root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-03220557/linux-devel:devel-lkp-nhm1-smoke-201503220558:ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2:bisect-linux/.vmlinuz-ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2-20150323040824-847-client9 branch=linux-devel/devel-lkp-nhm1-smoke-201503220558 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 drbd.minor_count=8
[    0.000000] KERNEL supported cpus:
[    0.000000] CPU: vendor_id 'GenuineIntel' unknown, using generic init.
[    0.000000] CPU: Your system may be unstable.
[    0.000000] e820: BIOS-provided physical RAM map:
[    0.000000] BIOS-e820: [mem 0x0000000000000000-0x000000000009fbff] usable
[    0.000000] BIOS-e820: [mem 0x000000000009fc00-0x000000000009ffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000000f0000-0x00000000000fffff] reserved
[    0.000000] BIOS-e820: [mem 0x0000000000100000-0x0000000013fdffff] usable
[    0.000000] BIOS-e820: [mem 0x0000000013fe0000-0x0000000013ffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000feffc000-0x00000000feffffff] reserved
[    0.000000] BIOS-e820: [mem 0x00000000fffc0000-0x00000000ffffffff] reserved
[    0.000000] bootconsole [earlyser0] enabled
[    0.000000] NX (Execute Disable) protection: active
[    0.000000] SMBIOS 2.8 present.
[    0.000000] DMI: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[    0.000000] Hypervisor detected: KVM
[    0.000000] e820: update [mem 0x00000000-0x00000fff] usable ==> reserved
[    0.000000] e820: remove [mem 0x000a0000-0x000fffff] usable
[    0.000000] e820: last_pfn = 0x13fe0 max_arch_pfn = 0x400000000
[    0.000000] Base memory trampoline at [ffff880000099000] 99000 size 24576
[    0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[    0.000000]  [mem 0x00000000-0x000fffff] page 4k
[    0.000000] BRK [0x04565000, 0x04565fff] PGTABLE
[    0.000000] BRK [0x04566000, 0x04566fff] PGTABLE
[    0.000000] BRK [0x04567000, 0x04567fff] PGTABLE
[    0.000000] init_memory_mapping: [mem 0x12600000-0x127fffff]
[    0.000000]  [mem 0x12600000-0x127fffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x00100000-0x125fffff]
[    0.000000]  [mem 0x00100000-0x001fffff] page 4k
[    0.000000]  [mem 0x00200000-0x125fffff] page 2M
[    0.000000] init_memory_mapping: [mem 0x12800000-0x13fdffff]
[    0.000000]  [mem 0x12800000-0x13dfffff] page 2M
[    0.000000]  [mem 0x13e00000-0x13fdffff] page 4k
[    0.000000] BRK [0x04568000, 0x04568fff] PGTABLE
[    0.000000] RAMDISK: [mem 0x12925000-0x13fd7fff]
[    0.000000] ACPI: Early table checksum verification disabled
[    0.000000] ACPI: RSDP 0x00000000000F0C90 000014 (v00 BOCHS )
[    0.000000] ACPI: RSDT 0x0000000013FE18BD 000034 (v01 BOCHS  BXPCRSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACP 0x0000000013FE0B37 000074 (v01 BOCHS  BXPCFACP 00000001 BXPC 00000001)
[    0.000000] ACPI: DSDT 0x0000000013FE0040 000AF7 (v01 BOCHS  BXPCDSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: FACS 0x0000000013FE0000 000040
[    0.000000] ACPI: SSDT 0x0000000013FE0BAB 000C5A (v01 BOCHS  BXPCSSDT 00000001 BXPC 00000001)
[    0.000000] ACPI: APIC 0x0000000013FE1805 000080 (v01 BOCHS  BXPCAPIC 00000001 BXPC 00000001)
[    0.000000] ACPI: HPET 0x0000000013FE1885 000038 (v01 BOCHS  BXPCHPET 00000001 BXPC 00000001)
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff57d000 (        fee00000)
[    0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[    0.000000] kvm-clock: cpu 0, msr 0:128a5001, primary cpu clock
[    0.000000]  [ffffea0000000000-ffffea00005fffff] PMD -> [ffff880011a00000-ffff880011ffffff] on node 0
[    0.000000] Zone ranges:
[    0.000000]   DMA      [mem 0x0000000000001000-0x0000000000ffffff]
[    0.000000]   DMA32    [mem 0x0000000001000000-0x0000000013fdffff]
[    0.000000]   Normal   empty
[    0.000000] Movable zone start for each node
[    0.000000] Early memory node ranges
[    0.000000]   node   0: [mem 0x0000000000001000-0x000000000009efff]
[    0.000000]   node   0: [mem 0x0000000000100000-0x0000000013fdffff]
[    0.000000] Initmem setup node 0 [mem 0x0000000000001000-0x0000000013fdffff]
[    0.000000] On node 0 totalpages: 81790
[    0.000000]   DMA zone: 64 pages used for memmap
[    0.000000]   DMA zone: 21 pages reserved
[    0.000000]   DMA zone: 3998 pages, LIFO batch:0
[    0.000000]   DMA32 zone: 1216 pages used for memmap
[    0.000000]   DMA32 zone: 77792 pages, LIFO batch:15
[    0.000000]  [ffffed0000000000-ffffed00001fffff] PMD -> [ffff880012600000-ffff8800127fffff] on node -1
[    0.000000]  [ffffed0000200000-ffffed00003fffff] PMD -> [ffff880012400000-ffff8800125fffff] on node -1
[    0.000000]  [ffffed0000400000-ffffed00005fffff] PMD -> [ffff880012200000-ffff8800123fffff] on node -1
[    0.000000]  [ffffed0000600000-ffffed00007fffff] PMD -> [ffff880012000000-ffff8800121fffff] on node -1
[    0.000000]  [ffffed0000800000-ffffed00009fffff] PMD -> [ffff880011800000-ffff8800119fffff] on node -1
[    0.000000]  [ffffed0000a00000-ffffed0000bfffff] PMD -> [ffff880011600000-ffff8800117fffff] on node -1
[    0.000000]  [ffffed0000c00000-ffffed0000dfffff] PMD -> [ffff880011400000-ffff8800115fffff] on node -1
[    0.000000]  [ffffed0000e00000-ffffed0000ffffff] PMD -> [ffff880011200000-ffff8800113fffff] on node -1
[    0.000000]  [ffffed0001000000-ffffed00011fffff] PMD -> [ffff880011000000-ffff8800111fffff] on node -1
[    0.000000]  [ffffed0001200000-ffffed00013fffff] PMD -> [ffff880010e00000-ffff880010ffffff] on node -1
[    0.000000]  [ffffed0001400000-ffffed00015fffff] PMD -> [ffff880010c00000-ffff880010dfffff] on node -1
[    0.000000]  [ffffed0001600000-ffffed00017fffff] PMD -> [ffff880010a00000-ffff880010bfffff] on node -1
[    0.000000]  [ffffed0001800000-ffffed00019fffff] PMD -> [ffff880010800000-ffff8800109fffff] on node -1
[    0.000000]  [ffffed0001a00000-ffffed0001bfffff] PMD -> [ffff880010600000-ffff8800107fffff] on node -1
[    0.000000]  [ffffed0001c00000-ffffed0001dfffff] PMD -> [ffff880010400000-ffff8800105fffff] on node -1
[    0.000000]  [ffffed0001e00000-ffffed0001ffffff] PMD -> [ffff880010200000-ffff8800103fffff] on node -1
[    0.000000]  [ffffed0002000000-ffffed00021fffff] PMD -> [ffff880010000000-ffff8800101fffff] on node -1
[    0.000000]  [ffffed0002200000-ffffed00023fffff] PMD -> [ffff88000fe00000-ffff88000fffffff] on node -1
[    0.000000]  [ffffed0002400000-ffffed00025fffff] PMD -> [ffff88000fc00000-ffff88000fdfffff] on node -1
[    0.000000] ACPI: PM-Timer IO Port: 0x608
[    0.000000] ACPI: Local APIC address 0xfee00000
[    0.000000] mapped APIC to ffffffffff57d000 (        fee00000)
[    0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[    0.000000] ACPI: LAPIC (acpi_id[0x01] lapic_id[0x01] enabled)
[    0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[    0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[    0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 00, APIC ID 0, APIC INT 02
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 5 global_irq 5 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 05, APIC ID 0, APIC INT 05
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 9 global_irq 9 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 09, APIC ID 0, APIC INT 09
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 10 global_irq 10 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0a, APIC ID 0, APIC INT 0a
[    0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 11 global_irq 11 high level)
[    0.000000] Int: type 0, pol 1, trig 3, bus 00, IRQ 0b, APIC ID 0, APIC INT 0b
[    0.000000] ACPI: IRQ0 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 01, APIC ID 0, APIC INT 01
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 03, APIC ID 0, APIC INT 03
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 04, APIC ID 0, APIC INT 04
[    0.000000] ACPI: IRQ5 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 06, APIC ID 0, APIC INT 06
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 07, APIC ID 0, APIC INT 07
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 08, APIC ID 0, APIC INT 08
[    0.000000] ACPI: IRQ9 used by override.
[    0.000000] ACPI: IRQ10 used by override.
[    0.000000] ACPI: IRQ11 used by override.
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0c, APIC ID 0, APIC INT 0c
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0d, APIC ID 0, APIC INT 0d
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0e, APIC ID 0, APIC INT 0e
[    0.000000] Int: type 0, pol 0, trig 0, bus 00, IRQ 0f, APIC ID 0, APIC INT 0f
[    0.000000] Using ACPI (MADT) for SMP configuration information
[    0.000000] ACPI: HPET id: 0x8086a201 base: 0xfed00000
[    0.000000] smpboot: Allowing 2 CPUs, 0 hotplug CPUs
[    0.000000] mapped IOAPIC to ffffffffff57c000 (fec00000)
[    0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[    0.000000] Booting paravirtualized kernel on KVM
[    0.000000] setup_percpu: NR_CPUS:8192 nr_cpumask_bits:2 nr_cpu_ids:2 nr_node_ids:1
[    0.000000] PERCPU: Embedded 28 pages/cpu @ffff88000f800000 s84224 r0 d30464 u1048576
[    0.000000] pcpu-alloc: s84224 r0 d30464 u1048576 alloc=1*2097152
[    0.000000] pcpu-alloc: [0] 0 1 
[    0.000000] KVM setup async PF for cpu 0
[    0.000000] kvm-stealtime: cpu 0, msr f80d280
[    0.000000] Built 1 zonelists in Zone order, mobility grouping on.  Total pages: 80489
[    0.000000] Kernel command line: hung_task_panic=1 earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal  root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-03220557/linux-devel:devel-lkp-nhm1-smoke-201503220558:ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2:bisect-linux/.vmlinuz-ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2-20150323040824-847-client9 branch=linux-devel/devel-lkp-nhm1-smoke-201503220558 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 drbd.minor_count=8
[    0.000000] PID hash table entries: 2048 (order: 2, 16384 bytes)
[    0.000000] Dentry cache hash table entries: 65536 (order: 7, 524288 bytes)
[    0.000000] Inode-cache hash table entries: 32768 (order: 6, 262144 bytes)
[    0.000000] Memory: 199900K/327160K available (18131K kernel code, 6550K rwdata, 7948K rodata, 1164K init, 20800K bss, 127260K reserved, 0K cma-reserved)
[    0.000000] SLUB: HWalign=64, Order=0-3, MinObjects=0, CPUs=2, Nodes=1
[    0.000000] Hierarchical RCU implementation.
[    0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=2
[    0.000000] Running RCU self tests
[    0.000000] NR_IRQS:524544 nr_irqs:440 16
[    0.000000] console [ttyS0] enabled
[    0.000000] console [ttyS0] enabled
[    0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[    0.000000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.000000] ... MAX_LOCKDEP_SUBCLASSES:  8
[    0.000000] ... MAX_LOCK_DEPTH:          48
[    0.000000] ... MAX_LOCK_DEPTH:          48
[    0.000000] ... MAX_LOCKDEP_KEYS:        8191
[    0.000000] ... MAX_LOCKDEP_KEYS:        8191
[    0.000000] ... CLASSHASH_SIZE:          4096
[    0.000000] ... CLASSHASH_SIZE:          4096
[    0.000000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.000000] ... MAX_LOCKDEP_ENTRIES:     32768
[    0.000000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000000] ... MAX_LOCKDEP_CHAINS:      65536
[    0.000000] ... CHAINHASH_SIZE:          32768
[    0.000000] ... CHAINHASH_SIZE:          32768
[    0.000000]  memory used by lock dependency info: 8159 kB
[    0.000000]  memory used by lock dependency info: 8159 kB
[    0.000000]  per task-struct memory footprint: 1920 bytes
[    0.000000]  per task-struct memory footprint: 1920 bytes
[    0.000000] ------------------------
[    0.000000] ------------------------
[    0.000000] | Locking API testsuite:
[    0.000000] | Locking API testsuite:
[    0.000000] ----------------------------------------------------------------------------
[    0.000000] ----------------------------------------------------------------------------
[    0.000000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.000000]                                  | spin |wlock |rlock |mutex | wsem | rsem |
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]                      A-A deadlock:
[    0.000000]                      A-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                  A-B-B-A deadlock:
[    0.000000]                  A-B-B-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]              A-B-B-C-C-A deadlock:
[    0.000000]              A-B-B-C-C-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]              A-B-C-A-B-C deadlock:
[    0.000000]              A-B-C-A-B-C deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          A-B-B-C-C-D-D-A deadlock:
[    0.000000]          A-B-B-C-C-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          A-B-C-D-B-D-D-A deadlock:
[    0.000000]          A-B-C-D-B-D-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          A-B-C-D-B-C-D-A deadlock:
[    0.000000]          A-B-C-D-B-C-D-A deadlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                     double unlock:
[    0.000000]                     double unlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                   initialize held:
[    0.000000]                   initialize held:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                  bad unlock order:
[    0.000000]                  bad unlock order:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]   --------------------------------------------------------------------------
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]               recursive read-lock:
[    0.000000]               recursive read-lock:             |             |  ok  |  ok  |             |             |  ok  |  ok  |

[    0.000000]            recursive read-lock #2:
[    0.000000]            recursive read-lock #2:             |             |  ok  |  ok  |             |             |  ok  |  ok  |

[    0.000000]             mixed read-write-lock:
[    0.000000]             mixed read-write-lock:             |             |  ok  |  ok  |             |             |  ok  |  ok  |

[    0.000000]             mixed write-read-lock:
[    0.000000]             mixed write-read-lock:             |             |  ok  |  ok  |             |             |  ok  |  ok  |

[    0.000000]   --------------------------------------------------------------------------
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]      hard-irqs-on + irq-safe-A/12:
[    0.000000]      hard-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]      soft-irqs-on + irq-safe-A/12:
[    0.000000]      soft-irqs-on + irq-safe-A/12:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]      hard-irqs-on + irq-safe-A/21:
[    0.000000]      hard-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]      soft-irqs-on + irq-safe-A/21:
[    0.000000]      soft-irqs-on + irq-safe-A/21:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]        sirq-safe-A => hirqs-on/12:
[    0.000000]        sirq-safe-A => hirqs-on/12:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]        sirq-safe-A => hirqs-on/21:
[    0.000000]        sirq-safe-A => hirqs-on/21:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          hard-safe-A + irqs-on/12:
[    0.000000]          hard-safe-A + irqs-on/12:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          soft-safe-A + irqs-on/12:
[    0.000000]          soft-safe-A + irqs-on/12:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          hard-safe-A + irqs-on/21:
[    0.000000]          hard-safe-A + irqs-on/21:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]          soft-safe-A + irqs-on/21:
[    0.000000]          soft-safe-A + irqs-on/21:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/123:
[    0.000000]     hard-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/123:
[    0.000000]     soft-safe-A + unsafe-B #1/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/132:
[    0.000000]     hard-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/132:
[    0.000000]     soft-safe-A + unsafe-B #1/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/213:
[    0.000000]     hard-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/213:
[    0.000000]     soft-safe-A + unsafe-B #1/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/231:
[    0.000000]     hard-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/231:
[    0.000000]     soft-safe-A + unsafe-B #1/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/312:
[    0.000000]     hard-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/312:
[    0.000000]     soft-safe-A + unsafe-B #1/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #1/321:
[    0.000000]     hard-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #1/321:
[    0.000000]     soft-safe-A + unsafe-B #1/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/123:
[    0.000000]     hard-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/123:
[    0.000000]     soft-safe-A + unsafe-B #2/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/132:
[    0.000000]     hard-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/132:
[    0.000000]     soft-safe-A + unsafe-B #2/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/213:
[    0.000000]     hard-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/213:
[    0.000000]     soft-safe-A + unsafe-B #2/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/231:
[    0.000000]     hard-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/231:
[    0.000000]     soft-safe-A + unsafe-B #2/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/312:
[    0.000000]     hard-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/312:
[    0.000000]     soft-safe-A + unsafe-B #2/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     hard-safe-A + unsafe-B #2/321:
[    0.000000]     hard-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]     soft-safe-A + unsafe-B #2/321:
[    0.000000]     soft-safe-A + unsafe-B #2/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/123:
[    0.000000]       hard-irq lock-inversion/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/123:
[    0.000000]       soft-irq lock-inversion/123:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/132:
[    0.000000]       hard-irq lock-inversion/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/132:
[    0.000000]       soft-irq lock-inversion/132:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/213:
[    0.000000]       hard-irq lock-inversion/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/213:
[    0.000000]       soft-irq lock-inversion/213:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/231:
[    0.000000]       hard-irq lock-inversion/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/231:
[    0.000000]       soft-irq lock-inversion/231:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/312:
[    0.000000]       hard-irq lock-inversion/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/312:
[    0.000000]       soft-irq lock-inversion/312:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq lock-inversion/321:
[    0.000000]       hard-irq lock-inversion/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       soft-irq lock-inversion/321:
[    0.000000]       soft-irq lock-inversion/321:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/123:
[    0.000000]       hard-irq read-recursion/123:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/123:
[    0.000000]       soft-irq read-recursion/123:  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/132:
[    0.000000]       hard-irq read-recursion/132:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/132:
[    0.000000]       soft-irq read-recursion/132:  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/213:
[    0.000000]       hard-irq read-recursion/213:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/213:
[    0.000000]       soft-irq read-recursion/213:  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/231:
[    0.000000]       hard-irq read-recursion/231:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/231:
[    0.000000]       soft-irq read-recursion/231:  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/312:
[    0.000000]       hard-irq read-recursion/312:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/312:
[    0.000000]       soft-irq read-recursion/312:  ok  |  ok  |

[    0.000000]       hard-irq read-recursion/321:
[    0.000000]       hard-irq read-recursion/321:  ok  |  ok  |

[    0.000000]       soft-irq read-recursion/321:
[    0.000000]       soft-irq read-recursion/321:  ok  |  ok  |

[    0.000000]   --------------------------------------------------------------------------
[    0.000000]   --------------------------------------------------------------------------
[    0.000000]   | Wound/wait tests |
[    0.000000]   | Wound/wait tests |
[    0.000000]   ---------------------
[    0.000000]   ---------------------
[    0.000000]                   ww api failures:
[    0.000000]                   ww api failures:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                ww contexts mixing:
[    0.000000]                ww contexts mixing:  ok  |  ok  |  ok  |  ok  |

[    0.000000]              finishing ww context:
[    0.000000]              finishing ww context:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                locking mismatches:
[    0.000000]                locking mismatches:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                  EDEADLK handling:
[    0.000000]                  EDEADLK handling:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]            spinlock nest unlocked:
[    0.000000]            spinlock nest unlocked:  ok  |  ok  |

[    0.000000]   -----------------------------------------------------
[    0.000000]   -----------------------------------------------------
[    0.000000]                                  |block | try  |context|
[    0.000000]                                  |block | try  |context|
[    0.000000]   -----------------------------------------------------
[    0.000000]   -----------------------------------------------------
[    0.000000]                           context:
[    0.000000]                           context:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                               try:
[    0.000000]                               try:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                             block:
[    0.000000]                             block:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000]                          spinlock:
[    0.000000]                          spinlock:  ok  |  ok  |  ok  |  ok  |  ok  |  ok  |

[    0.000000] -------------------------------------------------------
[    0.000000] -------------------------------------------------------
[    0.000000] Good, all 253 testcases passed! |
[    0.000000] Good, all 253 testcases passed! |
[    0.000000] ---------------------------------
[    0.000000] ---------------------------------
[    0.000000] hpet clockevent registered
[    0.000000] hpet clockevent registered
[    0.000000] tsc: Detected 2925.998 MHz processor
[    0.000000] tsc: Detected 2925.998 MHz processor
[    0.000000] tsc: Marking TSC unstable due to TSCs unsynchronized
[    0.000000] tsc: Marking TSC unstable due to TSCs unsynchronized
[    0.030000] Calibrating delay loop (skipped) preset value.. 
[    0.030000] Calibrating delay loop (skipped) preset value.. 5851.99 BogoMIPS (lpj=29259980)
5851.99 BogoMIPS (lpj=29259980)
[    0.030890] pid_max: default: 4096 minimum: 301
[    0.030890] pid_max: default: 4096 minimum: 301
[    0.040531] ACPI: Core revision 20150204
[    0.040531] ACPI: Core revision 20150204
[    0.813645] ACPI: 
[    0.813645] ACPI: All ACPI Tables successfully acquiredAll ACPI Tables successfully acquired

[    0.821014] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.821014] Mount-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.822877] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.822877] Mountpoint-cache hash table entries: 1024 (order: 1, 8192 bytes)
[    0.852260] Initializing cgroup subsys blkio
[    0.852260] Initializing cgroup subsys blkio
[    0.853491] Initializing cgroup subsys memory
[    0.853491] Initializing cgroup subsys memory
[    0.854931] Initializing cgroup subsys devices
[    0.854931] Initializing cgroup subsys devices
[    0.860195] Initializing cgroup subsys freezer
[    0.860195] Initializing cgroup subsys freezer
[    0.870166] Initializing cgroup subsys net_cls
[    0.870166] Initializing cgroup subsys net_cls
[    0.871475] Initializing cgroup subsys perf_event
[    0.871475] Initializing cgroup subsys perf_event
[    0.872914] Initializing cgroup subsys net_prio
[    0.872914] Initializing cgroup subsys net_prio
[    0.874224] Initializing cgroup subsys hugetlb
[    0.874224] Initializing cgroup subsys hugetlb
[    0.880094] Initializing cgroup subsys debug
[    0.880094] Initializing cgroup subsys debug
[    0.881920] mce: CPU supports 10 MCE banks
[    0.881920] mce: CPU supports 10 MCE banks
[    0.890049] mce: unknown CPU type - not enabling MCE support
[    0.890049] mce: unknown CPU type - not enabling MCE support
[    0.900028] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.900028] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[    0.910028] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.910028] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[    0.920767] Freeing SMP alternatives memory: 20K (ffffffff8310f000 - ffffffff83114000)
[    0.920767] Freeing SMP alternatives memory: 20K (ffffffff8310f000 - ffffffff83114000)
[    0.926221] Getting VERSION: 1050014
[    0.926221] Getting VERSION: 1050014
[    0.930038] Getting VERSION: 1050014
[    0.930038] Getting VERSION: 1050014
[    0.931057] Getting ID: 0
[    0.931057] Getting ID: 0
[    0.931831] Getting ID: ff000000
[    0.931831] Getting ID: ff000000
[    0.932706] Getting LVT0: 8700
[    0.932706] Getting LVT0: 8700
[    0.933506] Getting LVT1: 8400
[    0.933506] Getting LVT1: 8400
[    0.934397] enabled ExtINT on CPU#0
[    0.934397] enabled ExtINT on CPU#0
[    0.940577] ENABLING IO-APIC IRQs
[    0.940577] ENABLING IO-APIC IRQs
[    0.941594] init IO_APIC IRQs
[    0.941594] init IO_APIC IRQs
[    0.950031]  apic 0 pin 0 not connected
[    0.950031]  apic 0 pin 0 not connected
[    0.960382] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.960382] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[    0.962758] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.962758] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.970169] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.970169] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[    0.972432] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.972432] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[    0.980163] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.980163] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[    0.982764] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.982764] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[    0.990184] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.990184] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[    0.992464] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    0.992464] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[    1.000100] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    1.000100] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[    1.002504] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    1.002504] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[    1.010191] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    1.010191] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[    1.012831] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    1.012831] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[    1.020183] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    1.020183] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[    1.022518] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    1.022518] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[    1.030185] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    1.030185] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[    1.032556] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    1.032556] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[    1.040082]  apic 0 pin 16 not connected
[    1.040082]  apic 0 pin 16 not connected
[    1.041129]  apic 0 pin 17 not connected
[    1.041129]  apic 0 pin 17 not connected
[    1.042205]  apic 0 pin 18 not connected
[    1.042205]  apic 0 pin 18 not connected
[    1.043251]  apic 0 pin 19 not connected
[    1.043251]  apic 0 pin 19 not connected
[    1.044372]  apic 0 pin 20 not connected
[    1.044372]  apic 0 pin 20 not connected
[    1.050029]  apic 0 pin 21 not connected
[    1.050029]  apic 0 pin 21 not connected
[    1.051091]  apic 0 pin 22 not connected
[    1.051091]  apic 0 pin 22 not connected
[    1.052146]  apic 0 pin 23 not connected
[    1.052146]  apic 0 pin 23 not connected
[    1.053430] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    1.053430] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[    1.060036] Using local APIC timer interrupts.
[    1.060036] calibrating APIC timer ...
[    1.060036] Using local APIC timer interrupts.
[    1.060036] calibrating APIC timer ...
[    1.080000] ... lapic delta = 12568773
[    1.080000] ... lapic delta = 12568773
[    1.080000] ... PM-Timer delta = 719851
[    1.080000] ... PM-Timer delta = 719851
[    1.080000] APIC calibration not consistent with PM-Timer: 201ms instead of 100ms
[    1.080000] APIC calibration not consistent with PM-Timer: 201ms instead of 100ms
[    1.080000] APIC delta adjusted to PM-Timer: 6249963 (12568773)
[    1.080000] APIC delta adjusted to PM-Timer: 6249963 (12568773)
[    1.080000] TSC delta adjusted to PM-Timer: 292598727 (588420542)
[    1.080000] TSC delta adjusted to PM-Timer: 292598727 (588420542)
[    1.080000] ..... delta 6249963
[    1.080000] ..... delta 6249963
[    1.080000] ..... mult: 268433866
[    1.080000] ..... mult: 268433866
[    1.080000] ..... calibration result: 9999940
[    1.080000] ..... calibration result: 9999940
[    1.080000] ..... CPU clock speed is 2925.9872 MHz.
[    1.080000] ..... CPU clock speed is 2925.9872 MHz.
[    1.080000] ..... host bus clock speed is 999.9940 MHz.
[    1.080000] ..... host bus clock speed is 999.9940 MHz.
[    1.080105] smpboot: CPU0: 
[    1.080105] smpboot: CPU0: GenuineIntel GenuineIntel Common KVM processorCommon KVM processor (fam: 0f, model: 06 (fam: 0f, model: 06, stepping: 01)
, stepping: 01)
[    1.090938] Performance Events: 
[    1.090938] Performance Events: no PMU driver, software events only.
no PMU driver, software events only.
[    1.130542] NMI watchdog: disabled (cpu0): hardware events not enabled
[    1.130542] NMI watchdog: disabled (cpu0): hardware events not enabled
[    1.144204] x86: Booting SMP configuration:
[    1.144204] x86: Booting SMP configuration:
[    1.150028] .... node  #0, CPUs:  
[    1.150028] .... node  #0, CPUs:         #1 #1
[    1.124164] kvm-clock: cpu 1, msr 0:128a5041, secondary cpu clock
[    1.124164] masked ExtINT on CPU#1
[    1.190622] x86: Booted up 1 node, 2 CPUs
[    1.190622] x86: Booted up 1 node, 2 CPUs
[    1.191705] smpboot: Total of 2 processors activated (11703.99 BogoMIPS)
[    1.191705] smpboot: Total of 2 processors activated (11703.99 BogoMIPS)
[    1.200014] KVM setup async PF for cpu 1
[    1.200014] KVM setup async PF for cpu 1
[    1.201091] kvm-stealtime: cpu 1, msr f90d280
[    1.201091] kvm-stealtime: cpu 1, msr f90d280
[    1.262717] devtmpfs: initialized
[    1.262717] devtmpfs: initialized
[    1.380375] gcov: version magic: 0x3430392a
[    1.380375] gcov: version magic: 0x3430392a
[    1.483195] xor: measuring software checksum speed
[    1.483195] xor: measuring software checksum speed
[    1.580025]    prefetch64-sse:  3338.000 MB/sec
[    1.580025]    prefetch64-sse:  3338.000 MB/sec
[    1.680043]    generic_sse:  2240.400 MB/sec
[    1.680043]    generic_sse:  2240.400 MB/sec
[    1.681345] xor: using function: prefetch64-sse (3338.000 MB/sec)
[    1.681345] xor: using function: prefetch64-sse (3338.000 MB/sec)
[    1.690105] prandom: seed boundary self test passed
[    1.690105] prandom: seed boundary self test passed
[    1.714368] prandom: 100 self tests passed
[    1.714368] prandom: 100 self tests passed
[    1.751396] regulator-dummy: no parameters
[    1.751396] regulator-dummy: no parameters
[    1.771253] NET: Registered protocol family 16
[    1.771253] NET: Registered protocol family 16
[    1.851127] cpuidle: using governor ladder
[    1.851127] cpuidle: using governor ladder
[    1.870171] cpuidle: using governor menu
[    1.870171] cpuidle: using governor menu
[    1.874188] ACPI: bus type PCI registered
[    1.874188] ACPI: bus type PCI registered
[    1.876020] PCI: Using configuration type 1 for base access
[    1.876020] PCI: Using configuration type 1 for base access
[    2.940048] raid6: sse2x1     176 MB/s
[    2.940048] raid6: sse2x1     176 MB/s
[    3.110078] raid6: sse2x2     270 MB/s
[    3.110078] raid6: sse2x2     270 MB/s
[    3.280031] raid6: sse2x4     415 MB/s
[    3.280031] raid6: sse2x4     415 MB/s
[    3.281183] raid6: using algorithm sse2x4 (415 MB/s)
[    3.281183] raid6: using algorithm sse2x4 (415 MB/s)
[    3.288844] raid6: using intx1 recovery algorithm
[    3.288844] raid6: using intx1 recovery algorithm
[    3.310579] ACPI: Added _OSI(Module Device)
[    3.310579] ACPI: Added _OSI(Module Device)
[    3.311783] ACPI: Added _OSI(Processor Device)
[    3.311783] ACPI: Added _OSI(Processor Device)
[    3.313043] ACPI: Added _OSI(3.0 _SCP Extensions)
[    3.313043] ACPI: Added _OSI(3.0 _SCP Extensions)
[    3.320039] ACPI: Added _OSI(Processor Aggregator Device)
[    3.320039] ACPI: Added _OSI(Processor Aggregator Device)
[    3.380793] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:3)
[    3.380793] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:3)
[    3.750528] ACPI: Interpreter enabled
[    3.750528] ACPI: Interpreter enabled
[    3.751766] ACPI Exception: AE_NOT_FOUND, 
[    3.751766] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S1_]While evaluating Sleep State [\_S1_] (20150204/hwxface-580)
 (20150204/hwxface-580)
[    3.761674] ACPI Exception: AE_NOT_FOUND, 
[    3.761674] ACPI Exception: AE_NOT_FOUND, While evaluating Sleep State [\_S2_]While evaluating Sleep State [\_S2_] (20150204/hwxface-580)
 (20150204/hwxface-580)
[    3.780175] ACPI: (supports S0 S3 S5)
[    3.780175] ACPI: (supports S0 S3 S5)
[    3.781264] ACPI: Using IOAPIC for interrupt routing
[    3.781264] ACPI: Using IOAPIC for interrupt routing
[    3.790105] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    3.790105] PCI: Using host bridge windows from ACPI; if necessary, use "pci=nocrs" and report a bug
[    5.362363] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    5.362363] ACPI: PCI Root Bridge [PCI0] (domain 0000 [bus 00-ff])
[    5.370181] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    5.370181] acpi PNP0A03:00: _OSC: OS supports [Segments]
[    5.380809] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    5.380809] acpi PNP0A03:00: _OSC failed (AE_NOT_FOUND); disabling ASPM
[    5.430972] PCI host bridge to bus 0000:00
[    5.430972] PCI host bridge to bus 0000:00
[    5.432263] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.432263] pci_bus 0000:00: root bus resource [bus 00-ff]
[    5.433934] pci_bus 0000:00: root bus resource [io  0x0cf8-0x0cff]
[    5.433934] pci_bus 0000:00: root bus resource [io  0x0cf8-0x0cff]
[    5.435711] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    5.435711] pci_bus 0000:00: root bus resource [io  0x0000-0x0cf7 window]
[    5.440099] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
[    5.440099] pci_bus 0000:00: root bus resource [io  0x0d00-0xadff window]
[    5.442152] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
[    5.442152] pci_bus 0000:00: root bus resource [io  0xae0f-0xaeff window]
[    5.450095] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
[    5.450095] pci_bus 0000:00: root bus resource [io  0xaf20-0xafdf window]
[    5.451975] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
[    5.451975] pci_bus 0000:00: root bus resource [io  0xafe4-0xffff window]
[    5.453924] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    5.453924] pci_bus 0000:00: root bus resource [mem 0x000a0000-0x000bffff window]
[    5.460095] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    5.460095] pci_bus 0000:00: root bus resource [mem 0x14000000-0xfebfffff window]
[    5.470342] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    5.470342] pci 0000:00:00.0: [8086:1237] type 00 class 0x060000
[    5.500215] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    5.500215] pci 0000:00:01.0: [8086:7000] type 00 class 0x060100
[    5.543530] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    5.543530] pci 0000:00:01.1: [8086:7010] type 00 class 0x010180
[    5.561089] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    5.561089] pci 0000:00:01.1: reg 0x20: [io  0xc040-0xc04f]
[    5.570500] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    5.570500] pci 0000:00:01.1: legacy IDE quirk: reg 0x10: [io  0x01f0-0x01f7]
[    5.580025] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    5.580025] pci 0000:00:01.1: legacy IDE quirk: reg 0x14: [io  0x03f6]
[    5.581955] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    5.581955] pci 0000:00:01.1: legacy IDE quirk: reg 0x18: [io  0x0170-0x0177]
[    5.590043] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    5.590043] pci 0000:00:01.1: legacy IDE quirk: reg 0x1c: [io  0x0376]
[    5.642282] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    5.642282] pci 0000:00:01.3: [8086:7113] type 00 class 0x068000
[    5.650618] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    5.650618] pci 0000:00:01.3: quirk: [io  0x0600-0x063f] claimed by PIIX4 ACPI
[    5.652775] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    5.652775] pci 0000:00:01.3: quirk: [io  0x0700-0x070f] claimed by PIIX4 SMB
[    5.703360] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000
[    5.703360] pci 0000:00:02.0: [1013:00b8] type 00 class 0x030000
[    5.711532] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref]
[    5.711532] pci 0000:00:02.0: reg 0x10: [mem 0xfc000000-0xfdffffff pref]
[    5.721475] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff]
[    5.721475] pci 0000:00:02.0: reg 0x14: [mem 0xfebf0000-0xfebf0fff]
[    5.750058] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    5.750058] pci 0000:00:02.0: reg 0x30: [mem 0xfebe0000-0xfebeffff pref]
[    5.800958] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    5.800958] pci 0000:00:03.0: [8086:100e] type 00 class 0x020000
[    5.810645] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    5.810645] pci 0000:00:03.0: reg 0x10: [mem 0xfebc0000-0xfebdffff]
[    5.820045] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    5.820045] pci 0000:00:03.0: reg 0x14: [io  0xc000-0xc03f]
[    5.841235] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    5.841235] pci 0000:00:03.0: reg 0x30: [mem 0xfeb80000-0xfebbffff pref]
[    5.883148] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    5.883148] pci 0000:00:04.0: [8086:25ab] type 00 class 0x088000
[    5.900388] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    5.900388] pci 0000:00:04.0: reg 0x10: [mem 0xfebf1000-0xfebf100f]
[    5.954131] pci_bus 0000:00: on NUMA node 0
[    5.954131] pci_bus 0000:00: on NUMA node 0
[    6.094596] ACPI: PCI Interrupt Link [LNKA] (IRQs
[    6.094596] ACPI: PCI Interrupt Link [LNKA] (IRQs 5 5 *10 *10 11 11))

[    6.132425] ACPI: PCI Interrupt Link [LNKB] (IRQs
[    6.132425] ACPI: PCI Interrupt Link [LNKB] (IRQs 5 5 *10 *10 11 11))

[    6.170399] ACPI: PCI Interrupt Link [LNKC] (IRQs
[    6.170399] ACPI: PCI Interrupt Link [LNKC] (IRQs 5 5 10 10 *11 *11))

[    6.202382] ACPI: PCI Interrupt Link [LNKD] (IRQs
[    6.202382] ACPI: PCI Interrupt Link [LNKD] (IRQs 5 5 10 10 *11 *11))

[    6.222954] ACPI: PCI Interrupt Link [LNKS] (IRQs
[    6.222954] ACPI: PCI Interrupt Link [LNKS] (IRQs *9 *9))

[    6.342274] ACPI: 
[    6.342274] ACPI: Enabled 16 GPEs in block 00 to 0FEnabled 16 GPEs in block 00 to 0F

[    6.410517] vgaarb: setting as boot device: PCI:0000:00:02.0
[    6.410517] vgaarb: setting as boot device: PCI:0000:00:02.0
[    6.412187] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    6.412187] vgaarb: device added: PCI:0000:00:02.0,decodes=io+mem,owns=io+mem,locks=none
[    6.420039] vgaarb: loaded
[    6.420039] vgaarb: loaded
[    6.430016] vgaarb: bridge control possible 0000:00:02.0
[    6.430016] vgaarb: bridge control possible 0000:00:02.0
[    6.473433] SCSI subsystem initialized
[    6.473433] SCSI subsystem initialized
[    6.501697] libata version 3.00 loaded.
[    6.501697] libata version 3.00 loaded.
[    6.511808] Linux video capture interface: v2.00
[    6.511808] Linux video capture interface: v2.00
[    6.520047] pps_core: LinuxPPS API ver. 1 registered
[    6.520047] pps_core: LinuxPPS API ver. 1 registered
[    6.521463] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    6.521463] pps_core: Software ver. 5.3.6 - Copyright 2005-2007 Rodolfo Giometti <giometti@linux.it>
[    6.530165] PTP clock support registered
[    6.530165] PTP clock support registered
[    6.582692] Advanced Linux Sound Architecture Driver Initialized.
[    6.582692] Advanced Linux Sound Architecture Driver Initialized.
[    6.584330] PCI: Using ACPI for IRQ routing
[    6.584330] PCI: Using ACPI for IRQ routing
[    6.590024] PCI: pci_cache_line_size set to 64 bytes
[    6.590024] PCI: pci_cache_line_size set to 64 bytes
[    6.600034] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    6.600034] e820: reserve RAM buffer [mem 0x0009fc00-0x0009ffff]
[    6.601936] e820: reserve RAM buffer [mem 0x13fe0000-0x13ffffff]
[    6.601936] e820: reserve RAM buffer [mem 0x13fe0000-0x13ffffff]
[    6.650037] NET: Registered protocol family 23
[    6.650037] NET: Registered protocol family 23
[    6.651776] Bluetooth: Core ver 2.20
[    6.651776] Bluetooth: Core ver 2.20
[    6.660469] NET: Registered protocol family 31
[    6.660469] NET: Registered protocol family 31
[    6.661818] Bluetooth: HCI device and connection manager initialized
[    6.661818] Bluetooth: HCI device and connection manager initialized
[    6.670031] Bluetooth: HCI socket layer initialized
[    6.670031] Bluetooth: HCI socket layer initialized
[    6.671319] Bluetooth: L2CAP socket layer initialized
[    6.671319] Bluetooth: L2CAP socket layer initialized
[    6.680346] Bluetooth: SCO socket layer initialized
[    6.680346] Bluetooth: SCO socket layer initialized
[    6.681748] NET: Registered protocol family 8
[    6.681748] NET: Registered protocol family 8
[    6.690025] NET: Registered protocol family 20
[    6.690025] NET: Registered protocol family 20
[    6.720570] cfg80211: Calling CRDA to update world regulatory domain
[    6.720570] cfg80211: Calling CRDA to update world regulatory domain
[    6.750076] nfc: nfc_init: NFC Core ver 0.1
[    6.750076] nfc: nfc_init: NFC Core ver 0.1
[    6.752020] NET: Registered protocol family 39
[    6.752020] NET: Registered protocol family 39
[    6.823080] Switched to clocksource kvm-clock
[    6.823080] Switched to clocksource kvm-clock
[    6.827149] FS-Cache: Loaded
[    6.827149] FS-Cache: Loaded
[    6.849225] CacheFiles: Loaded
[    6.849225] CacheFiles: Loaded
[    6.851629] pnp: PnP ACPI init
[    6.851629] pnp: PnP ACPI init
[    6.855355] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3)
[    6.855355] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:3)
[    6.883477] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    6.883477] pnp 00:00: Plug and Play ACPI device, IDs PNP0b00 (active)
[    6.910579] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3)
[    6.910579] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:3)
[    6.915280] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    6.915280] pnp 00:01: Plug and Play ACPI device, IDs PNP0303 (active)
[    6.942217] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3)
[    6.942217] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:3)
[    6.967185] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    6.967185] pnp 00:02: Plug and Play ACPI device, IDs PNP0f13 (active)
[    6.982715] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3)
[    6.982715] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:3)
[    6.985111] pnp 00:03: [dma 2]
[    6.985111] pnp 00:03: [dma 2]
[    7.008570] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    7.008570] pnp 00:03: Plug and Play ACPI device, IDs PNP0700 (active)
[    7.023954] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3)
[    7.023954] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:3)
[    7.048753] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    7.048753] pnp 00:04: Plug and Play ACPI device, IDs PNP0400 (active)
[    7.064011] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3)
[    7.064011] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:3)
[    7.088302] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    7.088302] pnp 00:05: Plug and Play ACPI device, IDs PNP0501 (active)
[    7.194271] pnp: PnP ACPI: found 6 devices
[    7.194271] pnp: PnP ACPI: found 6 devices
[    7.349080] pci_bus 0000:00: resource 4 [io  0x0cf8-0x0cff]
[    7.349080] pci_bus 0000:00: resource 4 [io  0x0cf8-0x0cff]
[    7.360998] pci_bus 0000:00: resource 5 [io  0x0000-0x0cf7 window]
[    7.360998] pci_bus 0000:00: resource 5 [io  0x0000-0x0cf7 window]
[    7.362791] pci_bus 0000:00: resource 6 [io  0x0d00-0xadff window]
[    7.362791] pci_bus 0000:00: resource 6 [io  0x0d00-0xadff window]
[    7.364513] pci_bus 0000:00: resource 7 [io  0xae0f-0xaeff window]
[    7.364513] pci_bus 0000:00: resource 7 [io  0xae0f-0xaeff window]
[    7.366388] pci_bus 0000:00: resource 8 [io  0xaf20-0xafdf window]
[    7.366388] pci_bus 0000:00: resource 8 [io  0xaf20-0xafdf window]
[    7.388259] pci_bus 0000:00: resource 9 [io  0xafe4-0xffff window]
[    7.388259] pci_bus 0000:00: resource 9 [io  0xafe4-0xffff window]
[    7.400284] pci_bus 0000:00: resource 10 [mem 0x000a0000-0x000bffff window]
[    7.400284] pci_bus 0000:00: resource 10 [mem 0x000a0000-0x000bffff window]
[    7.402310] pci_bus 0000:00: resource 11 [mem 0x14000000-0xfebfffff window]
[    7.402310] pci_bus 0000:00: resource 11 [mem 0x14000000-0xfebfffff window]
[    7.404638] NET: Registered protocol family 1
[    7.404638] NET: Registered protocol family 1
[    7.406008] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    7.406008] pci 0000:00:00.0: Limiting direct PCI/PCI transfers
[    7.427859] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    7.427859] pci 0000:00:01.0: PIIX3: Enabling Passive Release
[    7.429562] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    7.429562] pci 0000:00:01.0: Activating ISA DMA hang workarounds
[    7.441595] pci 0000:00:02.0: Video device with shadowed ROM
[    7.441595] pci 0000:00:02.0: Video device with shadowed ROM
[    7.443446] PCI: CLS 0 bytes, default 64
[    7.443446] PCI: CLS 0 bytes, default 64
[    7.467559] Unpacking initramfs...
[    7.467559] Unpacking initramfs...
[   33.968444] Freeing initrd memory: 23244K (ffff880012925000 - ffff880013fd8000)
[   33.968444] Freeing initrd memory: 23244K (ffff880012925000 - ffff880013fd8000)
[   33.975487] has_svm: not amd
[   33.975487] has_svm: not amd
[   33.976186] kvm: no hardware support
[   33.976186] kvm: no hardware support
[   34.039266] cryptomgr_test (36) used greatest stack depth: 14080 bytes left
[   34.039266] cryptomgr_test (36) used greatest stack depth: 14080 bytes left
[   34.180596] sha1_ssse3: Neither AVX nor AVX2 nor SSSE3 is available/usable.
[   34.180596] sha1_ssse3: Neither AVX nor AVX2 nor SSSE3 is available/usable.
[   34.183506] PCLMULQDQ-NI instructions are not detected.
[   34.183506] PCLMULQDQ-NI instructions are not detected.
[   34.185015] sha256_ssse3: Neither AVX nor SSSE3 is available/usable.
[   34.185015] sha256_ssse3: Neither AVX nor SSSE3 is available/usable.
[   34.186763] sha512_ssse3: Neither AVX nor SSSE3 is available/usable.
[   34.186763] sha512_ssse3: Neither AVX nor SSSE3 is available/usable.
[   34.212806] AVX or AES-NI instructions are not detected.
[   34.212806] AVX or AES-NI instructions are not detected.
[   34.214365] AVX instructions are not detected.
[   34.214365] AVX instructions are not detected.
[   34.215624] AVX instructions are not detected.
[   34.215624] AVX instructions are not detected.
[   34.216860] AVX instructions are not detected.
[   34.216860] AVX instructions are not detected.
[   34.241440] AVX instructions are not detected.
[   34.241440] AVX instructions are not detected.
[   34.251069] spin_lock-torture:--- Start of test [debug]: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0
[   34.251069] spin_lock-torture:--- Start of test [debug]: nwriters_stress=4 nreaders_stress=0 stat_interval=60 verbose=1 shuffle_interval=3 stutter=5 shutdown_secs=0 onoff_interval=0 onoff_holdoff=0
[   34.269417] spin_lock-torture: Creating torture_shuffle task
[   34.269417] spin_lock-torture: Creating torture_shuffle task
[   34.281673] spin_lock-torture: torture_shuffle task started
[   34.281673] spin_lock-torture: torture_shuffle task started
[   34.283418] spin_lock-torture: Creating torture_stutter task
[   34.283418] spin_lock-torture: Creating torture_stutter task
[   34.297150] spin_lock-torture: torture_stutter task started
[   34.297150] spin_lock-torture: torture_stutter task started
[   34.298835] spin_lock-torture: Creating lock_torture_writer task
[   34.298835] spin_lock-torture: Creating lock_torture_writer task
[   34.301358] spin_lock-torture: lock_torture_writer task started
[   34.301358] spin_lock-torture: lock_torture_writer task started
[   34.317057] spin_lock-torture: Creating lock_torture_writer task
[   34.317057] spin_lock-torture: Creating lock_torture_writer task
[   34.331967] spin_lock-torture: Creating lock_torture_writer task
[   34.331967] spin_lock-torture: Creating lock_torture_writer task
[   34.333769] spin_lock-torture: lock_torture_writer task started
[   34.333769] spin_lock-torture: lock_torture_writer task started
[   34.360781] spin_lock-torture: Creating lock_torture_writer task
[   34.360781] spin_lock-torture: Creating lock_torture_writer task
[   34.362242] spin_lock-torture: lock_torture_writer task started
[   34.362242] spin_lock-torture: lock_torture_writer task started
[   34.378117] spin_lock-torture: lock_torture_writer task started
[   34.378117] spin_lock-torture: lock_torture_writer task started
[   34.386039] spin_lock-torture: Creating lock_torture_stats task
[   34.386039] spin_lock-torture: Creating lock_torture_stats task
[   34.408108] spin_lock-torture: lock_torture_stats task started
[   34.408108] spin_lock-torture: lock_torture_stats task started
[   34.496671] torture_init_begin: refusing rcu init: spin_lock running
[   34.496671] torture_init_begin: refusing rcu init: spin_lock running
[   34.496672] futex hash table entries: 16 (order: -1, 2048 bytes)
[   34.496672] futex hash table entries: 16 (order: -1, 2048 bytes)
[   61.140344] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper/0:1]
[   61.140344] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper/0:1]
[   61.140344] irq event stamp: 1434402
[   61.140344] irq event stamp: 1434402
[   61.140344] hardirqs last  enabled at (1434401): 
[   61.140344] hardirqs last  enabled at (1434401): [<ffffffff821ac5af>] mutex_lock_nested+0x651/0x745
[<ffffffff821ac5af>] mutex_lock_nested+0x651/0x745
[   61.140344] hardirqs last disabled at (1434402): 
[   61.140344] hardirqs last disabled at (1434402): [<ffffffff821b395a>] apic_timer_interrupt+0x6a/0x80
[<ffffffff821b395a>] apic_timer_interrupt+0x6a/0x80
[   61.140344] softirqs last  enabled at (1434180): 
[   61.140344] softirqs last  enabled at (1434180): [<ffffffff81130203>] __do_softirq+0x375/0x40c
[<ffffffff81130203>] __do_softirq+0x375/0x40c
[   61.140344] softirqs last disabled at (1434173): 
[   61.140344] softirqs last disabled at (1434173): [<ffffffff811306c4>] irq_exit+0x8e/0x1fb
[<ffffffff811306c4>] irq_exit+0x8e/0x1fb
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-04819-gef7f0d6 #5
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-04819-gef7f0d6 #5
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] task: ffff88000f500000 ti: ffff88000f508000 task.ti: ffff88000f508000
[   61.140344] task: ffff88000f500000 ti: ffff88000f508000 task.ti: ffff88000f508000
[   61.140344] RIP: 0010:[<ffffffff811ee644>] 
[   61.140344] RIP: 0010:[<ffffffff811ee644>]  [<ffffffff811ee644>] get_node_info+0x25/0x51
 [<ffffffff811ee644>] get_node_info+0x25/0x51
[   61.140344] RSP: 0000:ffff88000f50bd98  EFLAGS: 00000292
[   61.140344] RSP: 0000:ffff88000f50bd98  EFLAGS: 00000292
[   61.140344] RAX: ffffed00027709cf RBX: 0000000000000206 RCX: ffffffff811ef606
[   61.140344] RAX: ffffed00027709cf RBX: 0000000000000206 RCX: ffffffff811ef606
[   61.140344] RDX: dffffc0000000000 RSI: ffffffff82853516 RDI: 1ffff100027709cf
[   61.140344] RDX: dffffc0000000000 RSI: ffffffff82853516 RDI: 1ffff100027709cf
[   61.140344] RBP: ffff88000f50bda8 R08: 0000000000000001 R09: 0000000000000000
[   61.140344] RBP: ffff88000f50bda8 R08: 0000000000000001 R09: 0000000000000000
[   61.140344] R10: ffffed00025e0297 R11: 000000000000011f R12: ffff88000f500000
[   61.140344] R10: ffffed00025e0297 R11: 000000000000011f R12: ffff88000f500000
[   61.140344] R13: ffffffff821ac5af R14: ffff88000f500000 R15: ffffffff811ef602
[   61.140344] R13: ffffffff821ac5af R14: ffff88000f500000 R15: ffffffff811ef602
[   61.140344] FS:  0000000000000000(0000) GS:ffff88000f800000(0000) knlGS:0000000000000000
[   61.140344] FS:  0000000000000000(0000) GS:ffff88000f800000(0000) knlGS:0000000000000000
[   61.140344] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   61.140344] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   61.140344] CR2: 0000000000000000 CR3: 0000000002990000 CR4: 00000000000006b0
[   61.140344] CR2: 0000000000000000 CR3: 0000000002990000 CR4: 00000000000006b0
[   61.140344] Stack:
[   61.140344] Stack:
[   61.140344]  ffffffff811ef606
[   61.140344]  ffffffff811ef606 ffff880013b84e40 ffff880013b84e40 ffff88000f50bdd8 ffff88000f50bdd8 ffffffff811ee6ba ffffffff811ee6ba

[   61.140344]  ffffffff811ef606
[   61.140344]  ffffffff811ef606 ffffffff829b5000 ffffffff829b5000 ffffffff829b5000 ffffffff829b5000 0000000000000000 0000000000000000

[   61.140344]  ffff88000f50be38
[   61.140344]  ffff88000f50be38 ffffffff811ef65e ffffffff811ef65e ffff88000f50bdf8 ffff88000f50bdf8 ffffffff821af754 ffffffff821af754

[   61.140344] Call Trace:
[   61.140344] Call Trace:
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344] Code: 
[   61.140344] Code: 02 02 48 48 89 89 e5 e5 5d 5d c3 c3 55 55 48 48 89 89 e5 e5 53 53 48 48 89 89 fb fb 51 51 48 48 8d 8d 7f 7f 58 58 e8 e8 29 29 cc cc 0a 0a 00 00 83 83 7b 7b 58 58 00 00 7e 7e 21 21 48 48 8d 8d 7b 7b 38 38 e8 e8 39 39 ce ce 0a 0a 00 00 48 48 8b 8b 5b 5b 38 38 <48> <48> 89 89 df df e8 e8 2d 2d ce ce 0a 0a 00 00 48 48 8b 8b 03 03 48 48 ff ff 05 05 6a 6a 85 85 ce ce 02 02 eb eb 14 14 48 48 

[   61.140344] Kernel panic - not syncing: softlockup: hung tasks
[   61.140344] Kernel panic - not syncing: softlockup: hung tasks
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G             L  3.19.0-04819-gef7f0d6 #5
[   61.140344] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G             L  3.19.0-04819-gef7f0d6 #5
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[   61.140344]  0000000000000000
[   61.140344]  0000000000000000 0000000000000000 0000000000000000 0000000000000000 0000000000000000 ffff88000f803de8 ffff88000f803de8

[   61.140344]  ffffffff821a4805
[   61.140344]  ffffffff821a4805 dffffc0000000000 dffffc0000000000 ffffffff8286a2ed ffffffff8286a2ed ffff88000f803e68 ffff88000f803e68

[   61.140344]  ffffffff8219ce07
[   61.140344]  ffffffff8219ce07 ffff88000f803e68 ffff88000f803e68 ffffffff00000008 ffffffff00000008 ffff88000f803e78 ffff88000f803e78

[   61.140344] Call Trace:
[   61.140344] Call Trace:
[   61.140344]  <IRQ> 
[   61.140344]  <IRQ>  [<ffffffff821a4805>] dump_stack+0xa0/0xd5
 [<ffffffff821a4805>] dump_stack+0xa0/0xd5
[   61.140344]  [<ffffffff8219ce07>] panic+0x158/0x34d
[   61.140344]  [<ffffffff8219ce07>] panic+0x158/0x34d
[   61.140344]  [<ffffffff811f19d4>] watchdog_timer_fn+0x2fa/0x32e
[   61.140344]  [<ffffffff811f19d4>] watchdog_timer_fn+0x2fa/0x32e
[   61.140344]  [<ffffffff811f16da>] ? watchdog_overflow_callback+0xf6/0xf6
[   61.140344]  [<ffffffff811f16da>] ? watchdog_overflow_callback+0xf6/0xf6
[   61.140344]  [<ffffffff811b7a87>] __run_hrtimer+0x99/0x158
[   61.140344]  [<ffffffff811b7a87>] __run_hrtimer+0x99/0x158
[   61.140344]  [<ffffffff811b889e>] hrtimer_interrupt+0x171/0x301
[   61.140344]  [<ffffffff811b889e>] hrtimer_interrupt+0x171/0x301
[   61.140344]  [<ffffffff810892df>] local_apic_timer_interrupt+0x8f/0x9e
[   61.140344]  [<ffffffff810892df>] local_apic_timer_interrupt+0x8f/0x9e
[   61.140344]  [<ffffffff81089d85>] smp_apic_timer_interrupt+0x2e/0x4d
[   61.140344]  [<ffffffff81089d85>] smp_apic_timer_interrupt+0x2e/0x4d
[   61.140344]  [<ffffffff821b395f>] apic_timer_interrupt+0x6f/0x80
[   61.140344]  [<ffffffff821b395f>] apic_timer_interrupt+0x6f/0x80
[   61.140344]  <EOI> 
[   61.140344]  <EOI>  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
 [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee644>] ? get_node_info+0x25/0x51
[   61.140344]  [<ffffffff811ee644>] ? get_node_info+0x25/0x51
[   61.140344]  [<ffffffff811ee640>] ? get_node_info+0x21/0x51
[   61.140344]  [<ffffffff811ee640>] ? get_node_info+0x21/0x51
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ee6ba>] get_node_by_name+0x3b/0x94
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef606>] ? new_node+0x5c9/0x5d7
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff811ef65e>] gcov_event+0x4a/0x69f
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff821af754>] ? mutex_unlock+0x10/0x19
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff83024a3e>] ? gcov_persist_setup+0x77/0x77
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff811ee5eb>] gcov_enable_events+0x4f/0x76
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83024b36>] gcov_fs_init+0xf8/0x134
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff83002a3d>] do_one_initcall+0x192/0x268
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff8129b4e6>] ? __asan_load8+0x6d/0x10c
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff83002f40>] kernel_init_freeable+0x42d/0x53a
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff8115cf66>] ? post_schedule+0x24/0xbc
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff82192856>] kernel_init+0x10/0x16e
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff821b297c>] ret_from_fork+0x7c/0xb0
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344]  [<ffffffff82192846>] ? rest_init+0x22a/0x22a
[   61.140344] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)
[   61.140344] Kernel Offset: 0x0 from 0xffffffff81000000 (relocation range: 0xffffffff80000000-0xffffffff9fffffff)

Elapsed time: 95
qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 -append 'hung_task_panic=1 earlyprintk=ttyS0,115200 rd.udev.log-priority=err systemd.log_target=journal systemd.log_level=warning debug apic=debug sysrq_always_enabled rcupdate.rcu_cpu_stall_timeout=100 panic=-1 softlockup_panic=1 nmi_watchdog=panic oops=panic load_ramdisk=2 prompt_ramdisk=0 console=ttyS0,115200 console=tty0 vga=normal  root=/dev/ram0 rw link=/kbuild-tests/run-queue/kvm/x86_64-randconfig-n0-03220557/linux-devel:devel-lkp-nhm1-smoke-201503220558:ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2:bisect-linux/.vmlinuz-ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2-20150323040824-847-client9 branch=linux-devel/devel-lkp-nhm1-smoke-201503220558 BOOT_IMAGE=/kernel/x86_64-randconfig-n0-03220557/ef7f0d6a6ca8c9e4b27d78895af86c2fbfaeedb2/vmlinuz-3.19.0-04819-gef7f0d6 drbd.minor_count=8'  -initrd /kernel-tests/initrd/quantal-core-x86_64.cgz -m 320 -smp 2 -net nic,vlan=1,model=e1000 -net user,vlan=1 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-quantal-client9-5 -serial file:/dev/shm/kboot/serial-quantal-client9-5 -daemonize -display none -monitor null 

^ permalink raw reply	[flat|nested] 3+ messages in thread

* Re: [x86_64/KASan] RIP: 0010:[<ffffffff811ee644>] [<ffffffff811ee644>] get_node_info
  2015-03-23  1:25 [x86_64/KASan] RIP: 0010:[<ffffffff811ee644>] [<ffffffff811ee644>] get_node_info Fengguang Wu
@ 2015-03-23  7:13 ` Andrey Ryabinin
  2015-04-02 10:49 ` [PATCH] gcov: fix softlockups Andrey Ryabinin
  1 sibling, 0 replies; 3+ messages in thread
From: Andrey Ryabinin @ 2015-03-23  7:13 UTC (permalink / raw)
  To: Fengguang Wu; +Cc: LKP, linux-kernel

On 03/23/2015 04:25 AM, Fengguang Wu wrote:
> Greetings,
> 
> 0day kernel testing robot got the below dmesg and the first bad commit is
> 

Could you please send me your config ?


^ permalink raw reply	[flat|nested] 3+ messages in thread

* [PATCH] gcov: fix softlockups
  2015-03-23  1:25 [x86_64/KASan] RIP: 0010:[<ffffffff811ee644>] [<ffffffff811ee644>] get_node_info Fengguang Wu
  2015-03-23  7:13 ` Andrey Ryabinin
@ 2015-04-02 10:49 ` Andrey Ryabinin
  1 sibling, 0 replies; 3+ messages in thread
From: Andrey Ryabinin @ 2015-04-02 10:49 UTC (permalink / raw)
  To: Peter Oberparleiter, Andrew Morton
  Cc: Fengguang Wu, lkp, linux-kernel, Andrey Ryabinin

gcov profiling if enabled with other heavy compile-time
instrumentation like KASan could trigger following softlockups:

[   72.460059] NMI watchdog: BUG: soft lockup - CPU#0 stuck for 22s! [swapper/0:1]
[   72.460068] Modules linked in:
[   72.460068] irq event stamp: 22823276
[   72.460068] hardirqs last  enabled at (22823275): [<ffffffff86e8d10d>] mutex_lock_nested+0x7d9/0x930
[   72.460068] hardirqs last disabled at (22823276): [<ffffffff86e9521d>] apic_timer_interrupt+0x6d/0x80
[   72.460068] softirqs last  enabled at (22823172): [<ffffffff811ed969>] __do_softirq+0x4db/0x729
[   72.460068] softirqs last disabled at (22823167): [<ffffffff811edfcf>] irq_exit+0x7d/0x15b
[   72.460068] CPU: 0 PID: 1 Comm: swapper/0 Tainted: G        W       3.19.0-05245-gbb33326-dirty #3
[   72.460068] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.7.5.1-0-g8936dbb-20141113_115728-nilsson.home.kraxel.org 04/01/2014
[   72.460068] task: ffff88006cba8000 ti: ffff88006cbb0000 task.ti: ffff88006cbb0000
[   72.460068] RIP: 0010:[<ffffffff8146822a>]  [<ffffffff8146822a>] kasan_mem_to_shadow+0x1e/0x1f
[   72.460068] RSP: 0000:ffff88006cbb3cb0  EFLAGS: 00000207
[   72.460068] RAX: fffffbfff1331380 RBX: ffffffff81468fc2 RCX: ffff88006d600006
[   72.460068] RDX: dffffc0000000000 RSI: ffffffff89989bfa RDI: 1ffffffff1331380
[   72.460068] RBP: ffff88006cbb3cf8 R08: 00000000037178af R09: 0000000003714ae1
[   72.460068] R10: ffffed000c8d0b1f R11: 00000000000000cf R12: ffffffff8d8c2ba0
[   72.460068] R13: ffff88006d640780 R14: ffffffff81269ad5 R15: ffff88006cbb3c58
[   72.460068] FS:  0000000000000000(0000) GS:ffff88006d600000(0000) knlGS:0000000000000000
[   72.460068] CS:  0010 DS: 0000 ES: 0000 CR0: 000000008005003b
[   72.460068] CR2: 0000000000000000 CR3: 000000000a229000 CR4: 00000000000006f0
[   72.460068] Stack:
[   72.460068]  ffffffff81468a10 dffffc0000000000 ffffffff8abcfac0 ffff88006cbb3d28
[   72.460068]  ffffffff81468fc2 ffff88006cbb3d38 ffffffff81468fc2 dffffc0000000000
[   72.460068]  ffffffff89989c05 ffff88006cbb3d28 ffffffff8212fea0 ffff880063049398
[   72.460068] Call Trace:
[   72.460068]  [<ffffffff81468a10>] ? __asan_load1+0x66/0xbb
[   72.460068]  [<ffffffff81468fc2>] ? __asan_load8+0x6d/0x10c
[   72.460068]  [<ffffffff81468fc2>] ? __asan_load8+0x6d/0x10c
[   72.460068]  [<ffffffff8212fea0>] strcmp+0x28/0x70
[   72.460068]  [<ffffffff813228af>] get_node_by_name+0x66/0x99
[   72.460068]  [<ffffffff81323879>] gcov_event+0x4f/0x69e
[   72.460068]  [<ffffffff86e90477>] ? mutex_unlock+0x15/0x1e
[   72.460068]  [<ffffffff8ca18cb9>] ? gcov_persist_setup+0x77/0x77
[   72.460068]  [<ffffffff8ca18cb9>] ? gcov_persist_setup+0x77/0x77
[   72.460068]  [<ffffffff813227a6>] gcov_enable_events+0x54/0x7b
[   72.460068]  [<ffffffff8ca18db1>] gcov_fs_init+0xf8/0x134
[   72.460068]  [<ffffffff810022ca>] do_one_initcall+0x1b2/0x288
[   72.460068]  [<ffffffff81468fc2>] ? __asan_load8+0x6d/0x10c
[   72.460068]  [<ffffffff8c9c8f02>] kernel_init_freeable+0x467/0x580
[   72.460068]  [<ffffffff86dd9a48>] ? rest_init+0x23b/0x23b
[   72.460068]  [<ffffffff86dd9a5d>] kernel_init+0x15/0x18b
[   72.460068]  [<ffffffff86e93f3c>] ret_from_fork+0x7c/0xb0
[   72.460068]  [<ffffffff86dd9a48>] ? rest_init+0x23b/0x23b
[   72.460068] Code: ff 48 ff 05 61 ec c2 0c 48 89 e5 5d c3 55 48 c1 ef 03 48 ba 00 00 00 00 00 fc ff df 48 8d 04 17 48 ff 05 da f5 c2 0c 48 89 e5 5d <c3> 55 48 ff 05 f5 fe c2 0c 48 89 e5 5d c3 55 48 ff 05 f0 fe c2
[   72.460068] Kernel panic - not syncing: softlockup: hung tasks

Fix this by sticking cond_resched() in gcov_enable_events().

Signed-off-by: Andrey Ryabinin <a.ryabinin@samsung.com>
Reported-by: Fengguang Wu <fengguang.wu@intel.com>
---
 kernel/gcov/base.c | 5 ++++-
 1 file changed, 4 insertions(+), 1 deletion(-)

diff --git a/kernel/gcov/base.c b/kernel/gcov/base.c
index b358a80..a744098 100644
--- a/kernel/gcov/base.c
+++ b/kernel/gcov/base.c
@@ -18,6 +18,7 @@
 #include <linux/init.h>
 #include <linux/module.h>
 #include <linux/mutex.h>
+#include <linux/sched.h>
 #include "gcov.h"
 
 static int gcov_events_enabled;
@@ -107,8 +108,10 @@ void gcov_enable_events(void)
 	gcov_events_enabled = 1;
 
 	/* Perform event callback for previously registered entries. */
-	while ((info = gcov_info_next(info)))
+	while ((info = gcov_info_next(info))) {
 		gcov_event(GCOV_ADD, info);
+		cond_resched();
+	}
 
 	mutex_unlock(&gcov_lock);
 }
-- 
2.3.5


^ permalink raw reply	[flat|nested] 3+ messages in thread

end of thread, other threads:[~2015-04-02 10:49 UTC | newest]

Thread overview: 3+ messages (download: mbox.gz / follow: Atom feed)
-- links below jump to the message on this page --
2015-03-23  1:25 [x86_64/KASan] RIP: 0010:[<ffffffff811ee644>] [<ffffffff811ee644>] get_node_info Fengguang Wu
2015-03-23  7:13 ` Andrey Ryabinin
2015-04-02 10:49 ` [PATCH] gcov: fix softlockups Andrey Ryabinin

This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox;
as well as URLs for NNTP newsgroup(s).