[sched/deadline] kernel BUG at kernel/sched/deadline.c:805!

From: Fengguang Wu
Date: Mon Feb 16 2015 - 02:20:56 EST


Hi Peter,

We got the below dmesg and the first bad commit is

git://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git revert-d347efeb16d3d5150cb7f8d50b05f388b572840e-1019a359d3dc4b64d0e1e5a5efcb725d5e83994d

commit 1019a359d3dc4b64d0e1e5a5efcb725d5e83994d
Author: Peter Zijlstra <peterz@xxxxxxxxxxxxx>
AuthorDate: Wed Nov 26 08:44:03 2014 +0800
Commit: Ingo Molnar <mingo@xxxxxxxxxx>
CommitDate: Wed Feb 4 07:52:26 2015 +0100

sched/deadline: Fix stale yield state

When we fail to start the deadline timer in update_curr_dl(), we
forget to clear ->dl_yielded, resulting in wrecked time keeping.

Since the natural place to clear both ->dl_yielded and ->dl_throttled
is in replenish_dl_entity(); both are after all waiting for that event;
make it so.

Luckily since 67dfa1b756f2 ("sched/deadline: Implement
cancel_dl_timer() to use in switched_from_dl()") the
task_on_rq_queued() condition in dl_task_timer() must be true, and can
therefore call enqueue_task_dl() unconditionally.

Reported-by: Wanpeng Li <wanpeng.li@xxxxxxxxxxxxxxx>
Signed-off-by: Peter Zijlstra (Intel) <peterz@xxxxxxxxxxxxx>
Cc: Kirill Tkhai <ktkhai@xxxxxxxxxxxxx>
Cc: Juri Lelli <juri.lelli@xxxxxxx>
Cc: Linus Torvalds <torvalds@xxxxxxxxxxxxxxxxxxxx>
Link: http://lkml.kernel.org/r/1416962647-76792-4-git-send-email-wanpeng.li@xxxxxxxxxxxxxxx
Signed-off-by: Ingo Molnar <mingo@xxxxxxxxxx>

===================================================
PARENT COMMIT NOT CLEAN. LOOK OUT FOR WRONG BISECT!
===================================================

+------------------------------------------------+------------+------------+------------+
| | a7bebf4887 | 1019a359d3 | 58e0e18cb8 |
+------------------------------------------------+------------+------------+------------+
| boot_successes | 886 | 54 | 10 |
| boot_failures | 314 | 246 | 4 |
| BUG:kernel_boot_crashed | 48 | 20 | 1 |
| BUG:kernel_test_crashed | 16 | 2 | |
| BUG:kernel_boot_hang | 213 | 43 | |
| Kernel_panic-not_syncing:softlockup:hung_tasks | 37 | 3 | |
| backtrace:ring_buffer_consumer_thread | 17 | 3 | |
| EIP_is_at_get_node_by_name | 4 | | |
| backtrace:gcov_enable_events | 17 | | |
| backtrace:gcov_fs_init | 17 | | |
| backtrace:kernel_init_freeable | 20 | 178 | 3 |
| EIP_is_at_strcmp | 7 | | |
| EIP_is_at__slab_alloc | 1 | | |
| backtrace:__ring_buffer_alloc | 1 | | |
| backtrace:ring_buffer_benchmark_init | 1 | | |
| EIP_is_at_lock_release | 1 | | |
| backtrace:debug_mutex_init | 1 | | |
| EIP_is_at_strncmp | 1 | | |
| EIP_is_at_do_raw_spin_unlock | 1 | | |
| EIP_is_at__do_softirq | 6 | 2 | |
| EIP_is_at_gcov_info_filename | 1 | | |
| EIP_is_at_ring_buffer_consume | 8 | 1 | |
| EIP_is_at_raw_spin_unlock_irqrestore | 1 | | |
| EIP_is_at_ftrace_ops_list_func | 1 | | |
| backtrace:event_trace_self_tests | 2 | | |
| backtrace:event_trace_self_tests_init | 2 | | |
| EIP_is_at_ftrace_likely_update | 1 | | |
| EIP_is_at_rb_reserve_next_event | 1 | | |
| EIP_is_at_raw_spin_unlock_irq | 1 | | |
| kernel_BUG_at_kernel/sched/deadline.c | 0 | 178 | 3 |
| invalid_opcode | 0 | 178 | 3 |
| EIP_is_at_enqueue_task_dl | 0 | 178 | 3 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 178 | 3 |
| backtrace:register_tracer | 0 | 178 | 3 |
| backtrace:init_wakeup_tracer | 0 | 178 | 3 |
+------------------------------------------------+------------+------------+------------+

[ 6.432010] Testing ftrace regs: PASSED
[ 6.530393] Testing tracer wakeup:
[ 7.648615] ------------[ cut here ]------------
[ 7.649000] kernel BUG at kernel/sched/deadline.c:805!
[ 7.649000] invalid opcode: 0000 [#1] SMP
[ 7.649000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-rc6-00041-g1019a35 #17
[ 7.649000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[ 7.649000] task: d3348000 ti: d3344000 task.ti: d3344000
[ 7.649000] EIP: 0060:[<c11969a1>] EFLAGS: 00010046 CPU: 0
[ 7.649000] EIP is at enqueue_task_dl+0x341/0x1860
[ 7.649000] EAX: d2940101 EBX: 00000001 ECX: 00000000 EDX: 0085a491
[ 7.649000] ESI: d2940000 EDI: d2940101 EBP: d3345e08 ESP: d3345dc0
[ 7.649000] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 7.649000] CR0: 8005003b CR2: ffffffff CR3: 04e8b000 CR4: 000006d0
[ 7.649000] Stack:
[ 7.649000] c1170353 00000002 d3345de0 c11ab53c d391ec10 00000046 d33485c0 d3348000
[ 7.649000] 00000002 d29401b8 d391ec00 d29401b8 00000046 00000005 00000000 d2940000
[ 7.649000] d391ec00 c36efc80 d3345e20 c115991a 00000005 00000000 d391ec00 00000000
[ 7.649000] Call Trace:
[ 7.649000] [<c1170353>] ? sched_clock_cpu+0x293/0x2e0
[ 7.649000] [<c11ab53c>] ? __lock_is_held+0x6c/0xa0
[ 7.649000] [<c115991a>] enqueue_task+0xba/0x220
[ 7.649000] [<c115aa4c>] activate_task+0x4c/0x80
[ 7.649000] [<c115aaa1>] ttwu_activate+0x21/0x90
[ 7.649000] [<c115bd84>] ttwu_do_activate+0x64/0x90
[ 7.649000] [<c11689b6>] try_to_wake_up+0x366/0x8b0
[ 7.649000] [<c1169000>] wake_up_process+0xa0/0xe0
[ 7.649000] [<c114095b>] kthread_stop+0x21b/0xd90
[ 7.649000] [<c12c0b27>] trace_selftest_startup_wakeup+0x3b7/0x4b0
[ 7.649000] [<c36b1690>] ? wait_for_completion+0x1f0/0x290
[ 7.649000] [<c12c1d04>] register_tracer+0x2d4/0x700
[ 7.649000] [<c4bd11a4>] init_wakeup_tracer+0x1d/0xea
[ 7.649000] [<c4b8f12e>] do_one_initcall+0x278/0x44c
[ 7.649000] [<c4bd1187>] ? init_function_trace+0x1f8/0x1f8
[ 7.649000] [<c4b8f565>] kernel_init_freeable+0x263/0x424
[ 7.649000] [<c3663b4c>] kernel_init+0x1c/0x2a0
[ 7.649000] [<c36c2181>] ret_from_kernel_thread+0x21/0x30
[ 7.649000] [<c3663b30>] ? rest_init+0x330/0x330
[ 7.649000] Code: da e8 94 96 13 00 8b 04 9d 84 f6 77 c4 83 c0 01 89 04 9d 84 f6 77 c4 89 f8 84 c0 74 4d 83 05 c8 14 f2 c4 01 83 15 cc 14 f2 c4 00 <0f> 0b 83 05 d0 14 f2 c4 01 83 15 d4 14 f2 c4 00 8d b4 26 00 00
[ 7.649000] EIP: [<c11969a1>] enqueue_task_dl+0x341/0x1860 SS:ESP 0068:d3345dc0
[ 7.649000] ---[ end trace cb4bdebe33135894 ]---
[ 7.649000] Kernel panic - not syncing: Fatal exception

git bisect start 58e0e18cb8b1c3351cb70b3cf78d4e3785a181fe bfa76d49576599a4b9f9b7a71f23d73d6dcff735 --
git bisect bad 30496a920e97b97e16d078c520eef323344ef997 # 23:32 0- 67 Merge 'amirv/for-upstream' into devel-xian-smoke-201502152116
git bisect good d170f4905ee6f93eb12f87d018dd4b9acea18f2c # 00:27 300+ 120 0day base guard for 'devel-xian-smoke-201502152116'
git bisect bad c5ce28df0e7c01a1de23c36ebdefcd803f2b6cbb # 01:11 0- 72 Merge git://git.kernel.org/pub/scm/linux/kernel/git/davem/net-next
git bisect good 57ee062e4baa9532e5a3835a2d185590a3fc8bd3 # 01:51 300+ 94 Merge branch 'r8152'
git bisect bad c08f8467939e7d2eebcba7cf2330242c4f53f2f7 # 02:10 0- 103 Merge tag 'pci-v3.20-changes' of git://git.kernel.org/pub/scm/linux/kernel/git/helgaas/pci
git bisect bad 5b9b28a63f2e47dac5ff3a2503bfe3ade8796aa0 # 02:24 0- 5 Merge branch 'sched-core-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 30d46827c2744f56bb31460007f2d16455f10720 # 02:41 300+ 107 Merge tag 'regulator-v3.20' of git://git.kernel.org/pub/scm/linux/kernel/git/broonie/regulator
git bisect good 8308756f45a12e2ff4f7749c2694fc83cdef0be9 # 03:11 300+ 101 Merge branch 'locking-core-for-linus' of git://git.kernel.org/pub/scm/linux/kernel/git/tip/tip
git bisect good 4ac30cf74b308fb01338e660d3471cd490a7958a # 03:36 300+ 25 perf tools: Do not use __perf_session__process_events() directly
git bisect bad 9659e1eeee28f7025b6545934d644d19e9c6e603 # 03:48 0- 2 sched/deadline: Remove cpu_active_mask from cpudl_find()
git bisect good 3847b272248a3a4ed70d20392cc0454917f7713b # 04:23 300+ 89 Merge branch 'sched/urgent' into sched/core
git bisect good 4c195c8a1967ff8bee13a811518a99db04618ab7 # 04:59 300+ 118 Merge branch 'sched/urgent' into sched/core, to merge fixes before applying new patches
git bisect bad 1019a359d3dc4b64d0e1e5a5efcb725d5e83994d # 05:10 39- 193 sched/deadline: Fix stale yield state
git bisect good a7bebf488791aa1036f3e6629daf01d01f705dcb # 05:34 300+ 103 sched/deadline: Fix hrtick for a non-leftmost task
# first bad commit: [1019a359d3dc4b64d0e1e5a5efcb725d5e83994d] sched/deadline: Fix stale yield state
git bisect good a7bebf488791aa1036f3e6629daf01d01f705dcb # 05:55 900+ 314 sched/deadline: Fix hrtick for a non-leftmost task
# extra tests with DEBUG_INFO
git bisect good 1019a359d3dc4b64d0e1e5a5efcb725d5e83994d # 06:11 900+ 0 sched/deadline: Fix stale yield state
# extra tests on HEAD of linux-devel/devel-xian-smoke-201502152116
git bisect bad 58e0e18cb8b1c3351cb70b3cf78d4e3785a181fe # 06:11 0- 4 0day head guard for 'devel-xian-smoke-201502152116'
# extra tests on tree/branch linus/master
git bisect bad a9724125ad014decf008d782e60447c811391326 # 06:11 0- 1 Merge tag 'tty-3.20-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/tty
# extra tests with first bad commit reverted
# extra tests on tree/branch linus/master
git bisect bad a9724125ad014decf008d782e60447c811391326 # 06:16 0- 1 Merge tag 'tty-3.20-rc1' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/tty
# extra tests on tree/branch next/master
git bisect bad b8acf73194186a5cba86812eb4ba17b897f0e13e # 06:26 0- 35 Add linux-next specific files for 20150213


This script may reproduce the error.

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

kernel=$1

kvm=(
qemu-system-x86_64
-cpu kvm64
-enable-kvm
-kernel $kernel
-m 320
-smp 1
-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
early console in setup code
early console in decompress_kernel

Decompressing Linux... Parsing ELF... No relocation needed... done.
Booting the kernel.
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.19.0-rc6-00041-g1019a35 (kbuild@xian) (gcc version 4.9.1 (Debian 4.9.1-19) ) #17 SMP Mon Feb 16 05:02:14 CST 2015
[ 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] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[ 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 = 0x100000
[ 0.000000] MTRR default type: write-back
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-BFFFF uncachable
[ 0.000000] C0000-FFFFF write-protect
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 0080000000 mask FF80000000 uncachable
[ 0.000000] 1 disabled
[ 0.000000] 2 disabled
[ 0.000000] 3 disabled
[ 0.000000] 4 disabled
[ 0.000000] 5 disabled
[ 0.000000] 6 disabled
[ 0.000000] 7 disabled
[ 0.000000] PAT configuration [0-7]: WB WC UC- UC WB WC UC- UC
[ 0.000000] initial memory mapped: [mem 0x00000000-0x067fffff]
[ 0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[ 0.000000] [mem 0x00000000-0x000fffff] page 4k
[ 0.000000] init_memory_mapping: [mem 0x13800000-0x13bfffff]
[ 0.000000] [mem 0x13800000-0x13bfffff] page 2M
[ 0.000000] init_memory_mapping: [mem 0x00100000-0x137fffff]
[ 0.000000] [mem 0x00100000-0x003fffff] page 4k
[ 0.000000] [mem 0x00400000-0x137fffff] page 2M
[ 0.000000] init_memory_mapping: [mem 0x13c00000-0x13fdffff]
[ 0.000000] [mem 0x13c00000-0x13fdffff] page 4k
[ 0.000000] BRK [0x0631c000, 0x0631cfff] PGTABLE
[ 0.000000] RAMDISK: [mem 0x13ca5000-0x13fd7fff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x000F0CF0 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x13FE1854 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x13FE0B37 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x13FE0040 000AF7 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x13FE0000 000040
[ 0.000000] ACPI: SSDT 0x13FE0BAB 000BF9 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: APIC 0x13FE17A4 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x13FE181C 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffc000 ( fee00000)
[ 0.000000] 0MB HIGHMEM available.
[ 0.000000] 319MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 13fe0000
[ 0.000000] low ram: 0 - 13fe0000
[ 0.000000] cma: dma_contiguous_reserve(limit 13fe0000)
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:13fdf001, primary cpu clock
[ 0.000000] BRK [0x0631d000, 0x0631dfff] PGTABLE
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x00001000-0x00ffffff]
[ 0.000000] Normal [mem 0x01000000-0x13fdffff]
[ 0.000000] HighMem empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x00001000-0x0009efff]
[ 0.000000] node 0: [mem 0x00100000-0x13fdffff]
[ 0.000000] Initmem setup node 0 [mem 0x00001000-0x13fdffff]
[ 0.000000] On node 0 totalpages: 81790
[ 0.000000] DMA zone: 40 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3998 pages, LIFO batch:0
[ 0.000000] Normal zone: 760 pages used for memmap
[ 0.000000] Normal zone: 77792 pages, LIFO batch:15
[ 0.000000] Using APIC driver default
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffc000 ( fee00000)
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] 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 1 CPUs, 0 hotplug CPUs
[ 0.000000] mapped IOAPIC to ffffb000 (fec00000)
[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 335 pages/cpu @d37d6000 s1349504 r0 d22656 u1372160
[ 0.000000] pcpu-alloc: s1349504 r0 d22656 u1372160 alloc=335*4096
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 137d9a40
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 80990
[ 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/i386-randconfig-x1-02152111/linux-devel:devel-xian-smoke-201502152116:1019a359d3dc4b64d0e1e5a5efcb725d5e83994d:bisect-linux-6/.vmlinuz-1019a359d3dc4b64d0e1e5a5efcb725d5e83994d-20150216050356-148-client9 branch=linux-devel/devel-xian-smoke-201502152116 BOOT_IMAGE=/kernel/i386-randconfig-x1-02152111/1019a359d3dc4b64d0e1e5a5efcb725d5e83994d/vmlinuz-3.19.0-rc6-00041-g1019a35 drbd.minor_count=8
[ 0.000000] sysrq: sysrq always enabled.
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Initializing CPU#0
[ 0.000000] Initializing HighMem for node 0 (00000000:00000000)
[ 0.000000] Memory: 233352K/327160K available (39694K kernel code, 8806K rwdata, 12408K rodata, 3008K init, 20964K bss, 93808K reserved, 0K cma-reserved, 0K highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xffe6e000 - 0xfffff000 (1604 kB)
[ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB)
[ 0.000000] vmalloc : 0xd47e0000 - 0xff7fe000 ( 688 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd3fe0000 ( 319 MB)
[ 0.000000] .init : 0xc4b8d000 - 0xc4e7d000 (3008 kB)
[ 0.000000] .data : 0xc36c3f4d - 0xc4b8b980 (21278 kB)
[ 0.000000] .text : 0xc1000000 - 0xc36c3f4d (39695 kB)
[ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.000000] Running RCU self tests
[ 0.000000]
[ 0.000000] **********************************************************
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] ** **
[ 0.000000] ** trace_printk() being used. Allocating extra memory. **
[ 0.000000] ** **
[ 0.000000] ** This means that this is a DEBUG kernel and it is **
[ 0.000000] ** unsafe for production use. **
[ 0.000000] ** **
[ 0.000000] ** If you see this message and you are not debugging **
[ 0.000000] ** the kernel, report this immediately to your vendor! **
[ 0.000000] ** **
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] **********************************************************
[ 0.000000] Testing tracer nop: PASSED
[ 0.000000] NR_IRQS:2304 nr_irqs:256 16
[ 0.000000] CPU 0 irqstacks, hard=d3264000 soft=d3266000
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] bootconsole [earlyser0] disabled
[ 0.000000] Initializing cgroup subsys cpu
[ 0.000000] Linux version 3.19.0-rc6-00041-g1019a35 (kbuild@xian) (gcc version 4.9.1 (Debian 4.9.1-19) ) #17 SMP Mon Feb 16 05:02:14 CST 2015
[ 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] Notice: NX (Execute Disable) protection cannot be enabled: non-PAE kernel!
[ 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 = 0x100000
[ 0.000000] MTRR default type: write-back
[ 0.000000] MTRR fixed ranges enabled:
[ 0.000000] 00000-9FFFF write-back
[ 0.000000] A0000-BFFFF uncachable
[ 0.000000] C0000-FFFFF write-protect
[ 0.000000] MTRR variable ranges enabled:
[ 0.000000] 0 base 0080000000 mask FF80000000 uncachable
[ 0.000000] 1 disabled
[ 0.000000] 2 disabled
[ 0.000000] 3 disabled
[ 0.000000] 4 disabled
[ 0.000000] 5 disabled
[ 0.000000] 6 disabled
[ 0.000000] 7 disabled
[ 0.000000] PAT configuration [0-7]: WB WC UC- UC WB WC UC- UC
[ 0.000000] initial memory mapped: [mem 0x00000000-0x067fffff]
[ 0.000000] Base memory trampoline at [c009b000] 9b000 size 16384
[ 0.000000] init_memory_mapping: [mem 0x00000000-0x000fffff]
[ 0.000000] [mem 0x00000000-0x000fffff] page 4k
[ 0.000000] init_memory_mapping: [mem 0x13800000-0x13bfffff]
[ 0.000000] [mem 0x13800000-0x13bfffff] page 2M
[ 0.000000] init_memory_mapping: [mem 0x00100000-0x137fffff]
[ 0.000000] [mem 0x00100000-0x003fffff] page 4k
[ 0.000000] [mem 0x00400000-0x137fffff] page 2M
[ 0.000000] init_memory_mapping: [mem 0x13c00000-0x13fdffff]
[ 0.000000] [mem 0x13c00000-0x13fdffff] page 4k
[ 0.000000] BRK [0x0631c000, 0x0631cfff] PGTABLE
[ 0.000000] RAMDISK: [mem 0x13ca5000-0x13fd7fff]
[ 0.000000] ACPI: Early table checksum verification disabled
[ 0.000000] ACPI: RSDP 0x000F0CF0 000014 (v00 BOCHS )
[ 0.000000] ACPI: RSDT 0x13FE1854 000034 (v01 BOCHS BXPCRSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACP 0x13FE0B37 000074 (v01 BOCHS BXPCFACP 00000001 BXPC 00000001)
[ 0.000000] ACPI: DSDT 0x13FE0040 000AF7 (v01 BOCHS BXPCDSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: FACS 0x13FE0000 000040
[ 0.000000] ACPI: SSDT 0x13FE0BAB 000BF9 (v01 BOCHS BXPCSSDT 00000001 BXPC 00000001)
[ 0.000000] ACPI: APIC 0x13FE17A4 000078 (v01 BOCHS BXPCAPIC 00000001 BXPC 00000001)
[ 0.000000] ACPI: HPET 0x13FE181C 000038 (v01 BOCHS BXPCHPET 00000001 BXPC 00000001)
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffc000 ( fee00000)
[ 0.000000] 0MB HIGHMEM available.
[ 0.000000] 319MB LOWMEM available.
[ 0.000000] mapped low ram: 0 - 13fe0000
[ 0.000000] low ram: 0 - 13fe0000
[ 0.000000] cma: dma_contiguous_reserve(limit 13fe0000)
[ 0.000000] kvm-clock: Using msrs 4b564d01 and 4b564d00
[ 0.000000] kvm-clock: cpu 0, msr 0:13fdf001, primary cpu clock
[ 0.000000] BRK [0x0631d000, 0x0631dfff] PGTABLE
[ 0.000000] Zone ranges:
[ 0.000000] DMA [mem 0x00001000-0x00ffffff]
[ 0.000000] Normal [mem 0x01000000-0x13fdffff]
[ 0.000000] HighMem empty
[ 0.000000] Movable zone start for each node
[ 0.000000] Early memory node ranges
[ 0.000000] node 0: [mem 0x00001000-0x0009efff]
[ 0.000000] node 0: [mem 0x00100000-0x13fdffff]
[ 0.000000] Initmem setup node 0 [mem 0x00001000-0x13fdffff]
[ 0.000000] On node 0 totalpages: 81790
[ 0.000000] DMA zone: 40 pages used for memmap
[ 0.000000] DMA zone: 0 pages reserved
[ 0.000000] DMA zone: 3998 pages, LIFO batch:0
[ 0.000000] Normal zone: 760 pages used for memmap
[ 0.000000] Normal zone: 77792 pages, LIFO batch:15
[ 0.000000] Using APIC driver default
[ 0.000000] ACPI: PM-Timer IO Port: 0x608
[ 0.000000] ACPI: Local APIC address 0xfee00000
[ 0.000000] mapped APIC to ffffc000 ( fee00000)
[ 0.000000] ACPI: LAPIC (acpi_id[0x00] lapic_id[0x00] enabled)
[ 0.000000] ACPI: LAPIC_NMI (acpi_id[0xff] dfl dfl lint[0x1])
[ 0.000000] ACPI: IOAPIC (id[0x00] address[0xfec00000] gsi_base[0])
[ 0.000000] IOAPIC[0]: apic_id 0, version 17, address 0xfec00000, GSI 0-23
[ 0.000000] ACPI: INT_SRC_OVR (bus 0 bus_irq 0 global_irq 2 dfl dfl)
[ 0.000000] 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 1 CPUs, 0 hotplug CPUs
[ 0.000000] mapped IOAPIC to ffffb000 (fec00000)
[ 0.000000] PM: Registered nosave memory: [mem 0x00000000-0x00000fff]
[ 0.000000] PM: Registered nosave memory: [mem 0x0009f000-0x0009ffff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000a0000-0x000effff]
[ 0.000000] PM: Registered nosave memory: [mem 0x000f0000-0x000fffff]
[ 0.000000] e820: [mem 0x14000000-0xfeffbfff] available for PCI devices
[ 0.000000] Booting paravirtualized kernel on KVM
[ 0.000000] setup_percpu: NR_CPUS:8 nr_cpumask_bits:8 nr_cpu_ids:1 nr_node_ids:1
[ 0.000000] PERCPU: Embedded 335 pages/cpu @d37d6000 s1349504 r0 d22656 u1372160
[ 0.000000] pcpu-alloc: s1349504 r0 d22656 u1372160 alloc=335*4096
[ 0.000000] pcpu-alloc: [0] 0
[ 0.000000] KVM setup async PF for cpu 0
[ 0.000000] kvm-stealtime: cpu 0, msr 137d9a40
[ 0.000000] Built 1 zonelists in Zone order, mobility grouping on. Total pages: 80990
[ 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/i386-randconfig-x1-02152111/linux-devel:devel-xian-smoke-201502152116:1019a359d3dc4b64d0e1e5a5efcb725d5e83994d:bisect-linux-6/.vmlinuz-1019a359d3dc4b64d0e1e5a5efcb725d5e83994d-20150216050356-148-client9 branch=linux-devel/devel-xian-smoke-201502152116 BOOT_IMAGE=/kernel/i386-randconfig-x1-02152111/1019a359d3dc4b64d0e1e5a5efcb725d5e83994d/vmlinuz-3.19.0-rc6-00041-g1019a35 drbd.minor_count=8
[ 0.000000] sysrq: sysrq always enabled.
[ 0.000000] PID hash table entries: 2048 (order: 1, 8192 bytes)
[ 0.000000] Dentry cache hash table entries: 65536 (order: 6, 262144 bytes)
[ 0.000000] Inode-cache hash table entries: 32768 (order: 5, 131072 bytes)
[ 0.000000] Initializing CPU#0
[ 0.000000] Initializing HighMem for node 0 (00000000:00000000)
[ 0.000000] Memory: 233352K/327160K available (39694K kernel code, 8806K rwdata, 12408K rodata, 3008K init, 20964K bss, 93808K reserved, 0K cma-reserved, 0K highmem)
[ 0.000000] virtual kernel memory layout:
[ 0.000000] fixmap : 0xffe6e000 - 0xfffff000 (1604 kB)
[ 0.000000] pkmap : 0xff800000 - 0xffc00000 (4096 kB)
[ 0.000000] vmalloc : 0xd47e0000 - 0xff7fe000 ( 688 MB)
[ 0.000000] lowmem : 0xc0000000 - 0xd3fe0000 ( 319 MB)
[ 0.000000] .init : 0xc4b8d000 - 0xc4e7d000 (3008 kB)
[ 0.000000] .data : 0xc36c3f4d - 0xc4b8b980 (21278 kB)
[ 0.000000] .text : 0xc1000000 - 0xc36c3f4d (39695 kB)
[ 0.000000] Checking if this processor honours the WP bit even in supervisor mode...Ok.
[ 0.000000] SLUB: HWalign=128, Order=0-3, MinObjects=0, CPUs=1, Nodes=1
[ 0.000000] Hierarchical RCU implementation.
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=16, nr_cpu_ids=1
[ 0.000000] Running RCU self tests
[ 0.000000]
[ 0.000000] **********************************************************
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] ** **
[ 0.000000] ** trace_printk() being used. Allocating extra memory. **
[ 0.000000] ** **
[ 0.000000] ** This means that this is a DEBUG kernel and it is **
[ 0.000000] ** unsafe for production use. **
[ 0.000000] ** **
[ 0.000000] ** If you see this message and you are not debugging **
[ 0.000000] ** the kernel, report this immediately to your vendor! **
[ 0.000000] ** **
[ 0.000000] ** NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE NOTICE **
[ 0.000000] **********************************************************
[ 0.000000] Testing tracer nop: PASSED
[ 0.000000] NR_IRQS:2304 nr_irqs:256 16
[ 0.000000] CPU 0 irqstacks, hard=d3264000 soft=d3266000
[ 0.000000] Console: colour VGA+ 80x25
[ 0.000000] console [tty0] enabled
[ 0.000000] bootconsole [earlyser0] disabled
[ 0.000000] console [ttyS0] enabled
[ 0.000000] Lock dependency validator: Copyright (c) 2006 Red Hat, Inc., Ingo Molnar
[ 0.000000] ... MAX_LOCKDEP_SUBCLASSES: 8
[ 0.000000] ... MAX_LOCK_DEPTH: 48
[ 0.000000] ... MAX_LOCKDEP_KEYS: 8191
[ 0.000000] ... CLASSHASH_SIZE: 4096
[ 0.000000] ... MAX_LOCKDEP_ENTRIES: 32768
[ 0.000000] ... MAX_LOCKDEP_CHAINS: 65536
[ 0.000000] ... CHAINHASH_SIZE: 32768
[ 0.000000] memory used by lock dependency info: 5167 kB
[ 0.000000] per task-struct memory footprint: 1920 bytes
[ 0.000000] hpet clockevent registered
[ 0.000000] tsc: Detected 2925.998 MHz processor
[ 0.004000] Calibrating delay loop (skipped) preset value.. 5851.99 BogoMIPS (lpj=2925998)
[ 0.005025] pid_max: default: 32768 minimum: 301
[ 0.007021] ACPI: Core revision 20141107
[ 0.306116] ACPI: All ACPI Tables successfully acquired
[ 0.310337] Mount-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.311046] Mountpoint-cache hash table entries: 1024 (order: 0, 4096 bytes)
[ 0.322903] Initializing cgroup subsys memory
[ 0.324168] Initializing cgroup subsys freezer
[ 0.325198] Initializing cgroup subsys net_cls
[ 0.326097] Initializing cgroup subsys net_prio
[ 0.327080] Initializing cgroup subsys debug
[ 0.329321] mce: CPU supports 10 MCE banks
[ 0.330106] Last level iTLB entries: 4KB 0, 2MB 0, 4MB 0
[ 0.330106] Last level dTLB entries: 4KB 0, 2MB 0, 4MB 0, 1GB 0
[ 0.375867] Freeing SMP alternatives memory: 44K (c4e7d000 - c4e88000)
[ 0.388830] ftrace: allocating 52090 entries in 102 pages
[ 0.437166] Getting VERSION: 1050014
[ 0.438044] Getting VERSION: 1050014
[ 0.439047] Getting ID: 0
[ 0.440043] Getting ID: f000000
[ 0.441048] Getting LVT0: 8700
[ 0.442041] Getting LVT1: 8400
[ 0.443021] Enabling APIC mode: Flat. Using 1 I/O APICs
[ 0.444115] enabled ExtINT on CPU#0
[ 0.447314] ENABLING IO-APIC IRQs
[ 0.448163] init IO_APIC IRQs
[ 0.449034] apic 0 pin 0 not connected
[ 0.450286] IOAPIC[0]: Set routing entry (0-1 -> 0x31 -> IRQ 1 Mode:0 Active:0 Dest:1)
[ 0.451216] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[ 0.452097] IOAPIC[0]: Set routing entry (0-2 -> 0x30 -> IRQ 0 Mode:0 Active:0 Dest:1)
[ 0.453163] IOAPIC[0]: Set routing entry (0-3 -> 0x33 -> IRQ 3 Mode:0 Active:0 Dest:1)
[ 0.454151] IOAPIC[0]: Set routing entry (0-4 -> 0x34 -> IRQ 4 Mode:0 Active:0 Dest:1)
[ 0.456029] IOAPIC[0]: Set routing entry (0-5 -> 0x35 -> IRQ 5 Mode:1 Active:0 Dest:1)
[ 0.457177] IOAPIC[0]: Set routing entry (0-6 -> 0x36 -> IRQ 6 Mode:0 Active:0 Dest:1)
[ 0.458102] IOAPIC[0]: Set routing entry (0-7 -> 0x37 -> IRQ 7 Mode:0 Active:0 Dest:1)
[ 0.459117] IOAPIC[0]: Set routing entry (0-8 -> 0x38 -> IRQ 8 Mode:0 Active:0 Dest:1)
[ 0.460152] IOAPIC[0]: Set routing entry (0-9 -> 0x39 -> IRQ 9 Mode:1 Active:0 Dest:1)
[ 0.461157] IOAPIC[0]: Set routing entry (0-10 -> 0x3a -> IRQ 10 Mode:1 Active:0 Dest:1)
[ 0.462111] IOAPIC[0]: Set routing entry (0-11 -> 0x3b -> IRQ 11 Mode:1 Active:0 Dest:1)
[ 0.463151] IOAPIC[0]: Set routing entry (0-12 -> 0x3c -> IRQ 12 Mode:0 Active:0 Dest:1)
[ 0.464155] IOAPIC[0]: Set routing entry (0-13 -> 0x3d -> IRQ 13 Mode:0 Active:0 Dest:1)
[ 0.465143] IOAPIC[0]: Set routing entry (0-14 -> 0x3e -> IRQ 14 Mode:0 Active:0 Dest:1)
[ 0.466144] IOAPIC[0]: Set routing entry (0-15 -> 0x3f -> IRQ 15 Mode:0 Active:0 Dest:1)
[ 0.467087] apic 0 pin 16 not connected
[ 0.468038] apic 0 pin 17 not connected
[ 0.469011] apic 0 pin 18 not connected
[ 0.470038] apic 0 pin 19 not connected
[ 0.471016] apic 0 pin 20 not connected
[ 0.472013] apic 0 pin 21 not connected
[ 0.473029] apic 0 pin 22 not connected
[ 0.474038] apic 0 pin 23 not connected
[ 0.476000] ..TIMER: vector=0x30 apic1=0 pin1=2 apic2=-1 pin2=-1
[ 0.476033] smpboot: CPU0: Intel Common KVM processor (fam: 0f, model: 06, stepping: 01)
[ 0.478023] Using local APIC timer interrupts.
[ 0.478023] calibrating APIC timer ...
[ 0.480000] ... lapic delta = 17124985
[ 0.480000] ... PM-Timer delta = 980796
[ 0.480000] APIC calibration not consistent with PM-Timer: 274ms instead of 100ms
[ 0.480000] APIC delta adjusted to PM-Timer: 6249981 (17124985)
[ 0.480000] TSC delta adjusted to PM-Timer: 292599777 (801725058)
[ 0.480000] ..... delta 6249981
[ 0.480000] ..... mult: 268434639
[ 0.480000] ..... calibration result: 999996
[ 0.480000] ..... CPU clock speed is 2925.0997 MHz.
[ 0.480000] ..... host bus clock speed is 999.0996 MHz.
[ 0.481000] Performance Events: unsupported Netburst CPU model 6 no PMU driver, software events only.
[ 0.491209] x86: Booted up 1 node, 1 CPUs
[ 0.492011] smpboot: Total of 1 processors activated (5851.99 BogoMIPS)
[ 0.497553] NMI watchdog: disabled (cpu0): hardware events not enabled
[ 0.536518] gcov: version magic: 0x3430392a
[ 0.559260] Testing tracer function: PASSED
[ 1.764012] Testing dynamic ftrace: PASSED
[ 2.042499] Testing dynamic ftrace ops #1: (1 0 1 0 0) (1 1 2 0 0) (2 1 3 0 203292) (2 2 4 0 203594) PASSED
[ 3.952015] Testing dynamic ftrace ops #2: (1 0 1 195224 0) (1 1 2 195595 0) (2 1 3 1 1803) (2 2 4 291 2093) PASSED
[ 6.241011] Testing ftrace recursion: PASSED
[ 6.343012] Testing ftrace recursion safe: PASSED
[ 6.432010] Testing ftrace regs: PASSED
[ 6.530393] Testing tracer wakeup:
[ 7.648615] ------------[ cut here ]------------
[ 7.649000] kernel BUG at kernel/sched/deadline.c:805!
[ 7.649000] invalid opcode: 0000 [#1] SMP
[ 7.649000] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 3.19.0-rc6-00041-g1019a35 #17
[ 7.649000] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.7.5-20140531_083030-gandalf 04/01/2014
[ 7.649000] task: d3348000 ti: d3344000 task.ti: d3344000
[ 7.649000] EIP: 0060:[<c11969a1>] EFLAGS: 00010046 CPU: 0
[ 7.649000] EIP is at enqueue_task_dl+0x341/0x1860
[ 7.649000] EAX: d2940101 EBX: 00000001 ECX: 00000000 EDX: 0085a491
[ 7.649000] ESI: d2940000 EDI: d2940101 EBP: d3345e08 ESP: d3345dc0
[ 7.649000] DS: 007b ES: 007b FS: 00d8 GS: 00e0 SS: 0068
[ 7.649000] CR0: 8005003b CR2: ffffffff CR3: 04e8b000 CR4: 000006d0
[ 7.649000] Stack:
[ 7.649000] c1170353 00000002 d3345de0 c11ab53c d391ec10 00000046 d33485c0 d3348000
[ 7.649000] 00000002 d29401b8 d391ec00 d29401b8 00000046 00000005 00000000 d2940000
[ 7.649000] d391ec00 c36efc80 d3345e20 c115991a 00000005 00000000 d391ec00 00000000
[ 7.649000] Call Trace:
[ 7.649000] [<c1170353>] ? sched_clock_cpu+0x293/0x2e0
[ 7.649000] [<c11ab53c>] ? __lock_is_held+0x6c/0xa0
[ 7.649000] [<c115991a>] enqueue_task+0xba/0x220
[ 7.649000] [<c115aa4c>] activate_task+0x4c/0x80
[ 7.649000] [<c115aaa1>] ttwu_activate+0x21/0x90
[ 7.649000] [<c115bd84>] ttwu_do_activate+0x64/0x90
[ 7.649000] [<c11689b6>] try_to_wake_up+0x366/0x8b0
[ 7.649000] [<c1169000>] wake_up_process+0xa0/0xe0
[ 7.649000] [<c114095b>] kthread_stop+0x21b/0xd90
[ 7.649000] [<c12c0b27>] trace_selftest_startup_wakeup+0x3b7/0x4b0
[ 7.649000] [<c36b1690>] ? wait_for_completion+0x1f0/0x290
[ 7.649000] [<c12c1d04>] register_tracer+0x2d4/0x700
[ 7.649000] [<c4bd11a4>] init_wakeup_tracer+0x1d/0xea
[ 7.649000] [<c4b8f12e>] do_one_initcall+0x278/0x44c
[ 7.649000] [<c4bd1187>] ? init_function_trace+0x1f8/0x1f8
[ 7.649000] [<c4b8f565>] kernel_init_freeable+0x263/0x424
[ 7.649000] [<c3663b4c>] kernel_init+0x1c/0x2a0
[ 7.649000] [<c36c2181>] ret_from_kernel_thread+0x21/0x30
[ 7.649000] [<c3663b30>] ? rest_init+0x330/0x330
[ 7.649000] Code: da e8 94 96 13 00 8b 04 9d 84 f6 77 c4 83 c0 01 89 04 9d 84 f6 77 c4 89 f8 84 c0 74 4d 83 05 c8 14 f2 c4 01 83 15 cc 14 f2 c4 00 <0f> 0b 83 05 d0 14 f2 c4 01 83 15 d4 14 f2 c4 00 8d b4 26 00 00
[ 7.649000] EIP: [<c11969a1>] enqueue_task_dl+0x341/0x1860 SS:ESP 0068:d3345dc0
[ 7.649000] ---[ end trace cb4bdebe33135894 ]---
[ 7.649000] Kernel panic - not syncing: Fatal exception

Elapsed time: 25
qemu-system-x86_64 -cpu kvm64 -enable-kvm -kernel /kernel/i386-randconfig-x1-02152111/1019a359d3dc4b64d0e1e5a5efcb725d5e83994d/vmlinuz-3.19.0-rc6-00041-g1019a35 -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/i386-randconfig-x1-02152111/linux-devel:devel-xian-smoke-201502152116:1019a359d3dc4b64d0e1e5a5efcb725d5e83994d:bisect-linux-6/.vmlinuz-1019a359d3dc4b64d0e1e5a5efcb725d5e83994d-20150216050356-148-client9 branch=linux-devel/devel-xian-smoke-201502152116 BOOT_IMAGE=/kernel/i386-randconfig-x1-02152111/1019a359d3dc4b64d0e1e5a5efcb725d5e83994d/vmlinuz-3.19.0-rc6-00041-g1019a35 drbd.minor_count=8' -initrd /kernel-tests/initrd/yocto-minimal-i386.cgz -m 320 -smp 1 -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-yocto-client9-7 -serial file:/dev/shm/kboot/serial-yocto-client9-7 -daemonize -display none -monitor null