[lkp] [net] 9317bb6982: INFO: task cat-kmsg:893 blocked for more than 300 seconds.
by kernel test robot
FYI, we noticed the following commit:
git://internal_merge_and_test_tree devel-catchup-201604281529
commit 9317bb69824ec8d078b0b786b6971aedb0af3d4f ("net: SOCKWQ_ASYNC_NOSPACE optimizations")
on test machine: vm-kbuild-2G: 2 threads qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap with 2G memory
caused below changes:
+--------------------------------------------------+------------+------------+
| | 210732d16d | 9317bb6982 |
+--------------------------------------------------+------------+------------+
| boot_successes | 40 | 13 |
| boot_failures | 0 | 27 |
| INFO:task_blocked_for_more_than#seconds | 0 | 27 |
| RIP:native_safe_halt | 0 | 20 |
| RIP:native_write_msr_safe | 0 | 27 |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0 | 27 |
| backtrace:__close_fd | 0 | 27 |
| backtrace:SyS_close | 0 | 27 |
| backtrace:cpu_startup_entry | 0 | 19 |
| backtrace:watchdog | 0 | 27 |
| RIP:__lock_acquire | 0 | 2 |
| backtrace:rpc_async_schedule | 0 | 2 |
| backtrace:lock_acquire | 0 | 1 |
| RIP:delay_tsc | 0 | 1 |
| backtrace:SYSC_epoll_wait | 0 | 1 |
| backtrace:SyS_epoll_wait | 0 | 1 |
| RIP:pvclock_clocksource_read | 0 | 1 |
| RIP:xs_reclassify_socket | 0 | 1 |
| backtrace:xs_tcp_setup_socket | 0 | 2 |
| RIP:insert_work | 0 | 1 |
+--------------------------------------------------+------------+------------+
[ 21.274248] floppy: error -5 while reading block 0
[ 26.723391] mount.nfs (843) used greatest stack depth: 11280 bytes left
[ 33.585450] random: nonblocking pool is initialized
[ 601.220736] INFO: task cat-kmsg:893 blocked for more than 300 seconds.
[ 601.222889] Not tainted 4.6.0-rc5-01101-g9317bb6 #27
[ 601.226342] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 601.230600] cat-kmsg D ffff88007b4a3b78 13392 893 1 0x00000000
[ 601.234598] ffff88007b4a3b78 ffffffff8109a027 00ff88007b4a3b48 ffff88009f5d7ec0
[ 601.239234] ffff88007b655600 ffff88007b4a4000 ffff88009f5d7ec0 7fffffffffffffff
[ 601.242317] 0000000000000002 ffffffff82ead672 ffff88007b4a3b90 ffffffff82ead30e
[ 601.245525] Call Trace:
[ 601.246391] [<ffffffff8109a027>] ? kvm_sched_clock_read+0x9/0x12
[ 601.248183] [<ffffffff82ead672>] ? bit_wait+0x2f/0x2f
[ 601.249825] [<ffffffff82ead30e>] schedule+0x88/0x9d
[ 601.251331] [<ffffffff82eb0664>] schedule_timeout+0x34/0xe6
[ 601.253530] [<ffffffff8109a00a>] ? kvm_clock_read+0x25/0x2e
[ 601.255058] [<ffffffff8109a01c>] ? kvm_clock_get_cycles+0x9/0xb
[ 601.257180] [<ffffffff8114a31e>] ? timekeeping_get_ns+0xe/0x3f
[ 601.258757] [<ffffffff8114a567>] ? ktime_get+0x41/0x52
[ 601.260476] [<ffffffff82eacad8>] io_schedule_timeout+0xa0/0x102
[ 601.262305] [<ffffffff82eacad8>] ? io_schedule_timeout+0xa0/0x102
[ 601.264098] [<ffffffff82ead68d>] bit_wait_io+0x1b/0x39
[ 601.265715] [<ffffffff82ead84e>] __wait_on_bit+0x4e/0x80
[ 601.267282] [<ffffffff811b458e>] wait_on_page_bit+0x76/0x78
[ 601.269231] [<ffffffff81117129>] ? autoremove_wake_function+0x3a/0x3a
[ 601.270986] [<ffffffff811b4659>] __filemap_fdatawait_range+0xc9/0x12b
[ 601.272986] [<ffffffff811b46cf>] filemap_fdatawait_range+0x14/0x2a
[ 601.274863] [<ffffffff811b659b>] filemap_write_and_wait_range+0x42/0x5b
[ 601.284684] [<ffffffff8135760f>] nfs_file_fsync+0x104/0x391
[ 601.286764] [<ffffffff811218a2>] ? lock_acquire+0x15d/0x1d6
[ 601.288373] [<ffffffff8123f328>] vfs_fsync_range+0x8c/0x9e
[ 601.295445] [<ffffffff8123f356>] vfs_fsync+0x1c/0x1e
[ 601.297498] [<ffffffff81385f2b>] nfs4_file_flush+0x57/0x5a
[ 601.299202] [<ffffffff81212b3f>] filp_close+0x3f/0x73
[ 601.301130] [<ffffffff8122eaf9>] __close_fd+0xc8/0xe2
[ 601.302697] [<ffffffff81212b96>] SyS_close+0x23/0x4c
[ 601.304200] [<ffffffff82eb19bc>] entry_SYSCALL_64_fastpath+0x1f/0xbd
[ 601.305396] [<ffffffff8111d264>] ? trace_hardirqs_off_caller+0x3f/0xaa
[ 601.306461] no locks held by cat-kmsg/893.
[ 601.307226] Sending NMI to all CPUs:
[ 601.307980] NMI backtrace for cpu 0
[ 601.308581] CPU: 0 PID: 0 Comm: swapper/0 Not tainted 4.6.0-rc5-01101-g9317bb6 #27
[ 601.310057] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 601.311590] task: ffffffff84028540 ti: ffffffff84000000 task.ti: ffffffff84000000
[ 601.312953] RIP: 0010:[<ffffffff8109a31d>] [<ffffffff8109a31d>] native_safe_halt+0x6/0x8
[ 601.314326] RSP: 0018:ffffffff84003ec8 EFLAGS: 00000206
[ 601.315189] RAX: ffffffff84028540 RBX: 0000000000000000 RCX: 0000000000000003
[ 601.316277] RDX: 0000000000000000 RSI: 0000000000000001 RDI: ffffffff84028540
[ 601.317543] RBP: ffffffff84003ec8 R08: ffff88009f20e040 R09: 000000000000000a
[ 601.318867] R10: ffffffff84003e18 R11: ffff88007dbe8c00 R12: 0000000000000000
[ 601.320574] R13: ffffffff84004000 R14: ffffffff84000000 R15: ffffffff84004000
[ 601.322429] FS: 0000000000000000(0000) GS:ffff88009f200000(0000) knlGS:0000000000000000
[ 601.330831] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 601.332393] CR2: 00007fae4e1f1050 CR3: 000000007d766000 CR4: 00000000000406f0
[ 601.347264] Stack:
[ 601.348023] ffffffff84003ed8 ffffffff810786b4 ffffffff84003ee8 ffffffff81078ccb
[ 601.350031] ffffffff84003ef8 ffffffff8111796c ffffffff84003f40 ffffffff81117ad9
[ 601.352428] ffffffff8473c2e0 ffffffff84000000 0000000000000002 0000000000000000
[ 601.354426] Call Trace:
[ 601.355501] [<ffffffff810786b4>] default_idle+0x24/0x37
[ 601.363067] [<ffffffff81078ccb>] arch_cpu_idle+0xf/0x11
[ 601.364418] [<ffffffff8111796c>] default_idle_call+0x28/0x2f
[ 601.365842] [<ffffffff81117ad9>] cpu_startup_entry+0x166/0x274
[ 601.367280] [<ffffffff82ea579e>] rest_init+0x135/0x13b
[ 601.368590] [<ffffffff84627f3d>] start_kernel+0x42c/0x439
[ 601.369989] [<ffffffff84627120>] ? early_idt_handler_array+0x120/0x120
[ 601.371584] [<ffffffff84627346>] x86_64_start_reservations+0x2a/0x2c
[ 601.373134] [<ffffffff84627475>] x86_64_start_kernel+0x12d/0x13a
[ 601.374567] Code: 48 89 e5 0f 09 5d c3 55 48 89 e5 9c 58 5d c3 55 48 89 e5 57 9d 5d c3 55 48 89 e5 fa 5d c3 55 48 89 e5 fb 5d c3 55 48 89 e5 fb f4 <5d> c3 55 48 89 e5 f4 5d c3 55 49 89 c9 49 89 d0 8b 07 48 89 e5
[ 601.386476] NMI backtrace for cpu 1
[ 601.387939] CPU: 1 PID: 20 Comm: khungtaskd Not tainted 4.6.0-rc5-01101-g9317bb6 #27
[ 601.390371] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 601.392973] task: ffff88009b830500 ti: ffff88009b834000 task.ti: ffff88009b834000
[ 601.395037] RIP: 0010:[<ffffffff8109a624>] [<ffffffff8109a624>] native_write_msr_safe+0x6/0x33
[ 601.397793] RSP: 0018:ffff88009b837d18 EFLAGS: 00000002
[ 601.399213] RAX: 0000000000000400 RBX: 0000000000000001 RCX: 0000000000000830
[ 601.401412] RDX: 0000000000000001 RSI: 0000000000000400 RDI: 0000000000000830
[ 601.403247] RBP: ffff88009b837d20 R08: 0000000000000001 R09: 0000000000000003
[ 601.405372] R10: ffff88009b837d48 R11: ffffffff855f2a2d R12: 0000000000000001
[ 601.407149] R13: 000000000000a1f0 R14: 0000000000000002 R15: 0000000000080000
[ 601.409232] FS: 0000000000000000(0000) GS:ffff88009f400000(0000) knlGS:0000000000000000
[ 601.411719] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 601.413513] CR2: 00007fae50056000 CR3: 000000007cba6000 CR4: 00000000000406e0
[ 601.415252] Stack:
[ 601.416160] ffffffff81093c1e ffff88009b837d30 ffffffff81093df1 ffff88009b837d80
[ 601.418280] ffffffff81093efb ffffffff84437940 00000000811b3d9c 0000000000000282
[ 601.420599] 0000000000000200 0000000000000001 ffffffff81091306 00000000001d1c00
[ 601.422818] Call Trace:
[ 601.423927] [<ffffffff81093c1e>] ? paravirt_write_msr+0xf/0x13
[ 601.426709] [<ffffffff81093df1>] native_x2apic_icr_write+0x1e/0x20
[ 601.428481] [<ffffffff81093efb>] __x2apic_send_IPI_mask+0xd2/0x10e
[ 601.430267] [<ffffffff81091306>] ? irq_force_complete_move+0xd9/0xd9
[ 601.432035] [<ffffffff81093f9b>] x2apic_send_IPI_mask+0x13/0x15
[ 601.433708] [<ffffffff81091321>] nmi_raise_cpu_backtrace+0x1b/0x1d
[ 601.435341] [<ffffffff81771e98>] nmi_trigger_all_cpu_backtrace+0x123/0x24e
[ 601.437478] [<ffffffff81091371>] arch_trigger_all_cpu_backtrace+0x19/0x1b
[ 601.439228] [<ffffffff8117a3e2>] watchdog+0x338/0x3b1
[ 601.440967] [<ffffffff8117a0aa>] ? rcu_read_lock+0x68/0x68
[ 601.442586] [<ffffffff810fdaef>] kthread+0xc5/0xcd
[ 601.444216] [<ffffffff82eb1bf2>] ret_from_fork+0x22/0x50
[ 601.445877] [<ffffffff810fda2a>] ? cpumask_check+0x35/0x35
[ 601.447840] Code: d4 85 c0 7e 11 48 89 d6 31 d2 48 c1 e6 20 48 09 de e8 d3 a4 70 00 4c 89 e0 48 c1 e0 20 48 09 d8 5b 41 5c 5d c3 89 f0 89 f9 0f 30 <31> c0 8b 0d dc c0 38 03 85 c9 7e 20 55 41 89 f0 48 89 d6 48 c1
[ 601.465694] Kernel panic - not syncing: hung_task: blocked tasks
[ 601.467986] CPU: 1 PID: 20 Comm: khungtaskd Not tainted 4.6.0-rc5-01101-g9317bb6 #27
[ 601.470527] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 601.473358] 0000000000000000 ffff88009b837d78 ffffffff8176e0b6 ffffffff839dd0d7
[ 601.475206] ffff88009b837e08 ffff88009b837df8 ffffffff811b3318 0000000000000008
[ 601.477034] ffff88009b837e08 ffff88009b837da0 ffff88009b837de8 0000000000000200
[ 601.478513] Call Trace:
[ 601.479018] [<ffffffff8176e0b6>] dump_stack+0x81/0xb6
[ 601.479881] [<ffffffff811b3318>] panic+0xd7/0x21e
[ 601.480706] [<ffffffff8117a3ee>] watchdog+0x344/0x3b1
[ 601.481604] [<ffffffff8117a0aa>] ? rcu_read_lock+0x68/0x68
[ 601.482469] [<ffffffff810fdaef>] kthread+0xc5/0xcd
[ 601.483299] [<ffffffff82eb1bf2>] ret_from_fork+0x22/0x50
[ 601.484230] [<ffffffff810fda2a>] ? cpumask_check+0x35/0x35
[ 601.485306] Kernel Offset: disabled
Elapsed time: 620
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Haswell,+smep,+smap -kernel /pkg/linux/x86_64-allyesdebian/gcc-5/9317bb69824ec8d078b0b786b6971aedb0af3d4f/vmlinuz-4.6.0-rc5-01101-g9317bb6 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-2G-7/bisect_locktorture-300s-debian-x86_64-2015-02-07.cgz-x86_64-allyesdebian-9317bb69824ec8d078b0b786b6971aedb0af3d4f-20160428-101418-l87pyc-0.yaml ARCH=x86_64 kconfig=x86_64-allyesdebian branch=linux-devel/devel-catchup-201604281529 commit=9317bb69824ec8d078b0b786b6971aedb0af3d4f BOOT_IMAGE=/pkg/linux/x86_64-allyesdebian/gcc-5/9317bb69824ec8d078b0b786b6971aedb0af3d4f/vmlinuz-4.6.0-rc5-01101-g9317bb6 max_uptime=1500 RESULT_ROOT=/result/locktorture/300s/vm-kbuild-2G/debian-x86_64-2015-02-07.cgz/x86_64-allyesdebian/gcc-5/9317bb69824ec8d078b0b786b6971aedb0af3d4f/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err 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 rw ip=::::vm-kbuild-2G-7::dhcp' -initrd /fs/sdg1/initrd-vm-kbuild-2G-7 -m 2560 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23022-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk1-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk2-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk3-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk4-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk5-vm-kbuild-2G-7,media=disk,if=virtio -drive file=/fs/sdg1/disk6-vm-kbuild-2G-7,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-2G-7 -serial file:/dev/shm/kboot/serial-vm-kbuild-2G-7 -daemonize -display none -monitor null
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Kernel Test Robot
4 years, 8 months
FW: [lkp] [locking] 780f90e464: Kernel panic - not syncing: Hard LOCKUP
by Ye, Xiaolong
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git locking/rfc commit 780f90e464d9ce2fa41c25802b7fd6dd1e8491fa ("locking: Introduce cmpwait()")
on test machine: lkp-hsw-ep2: 72 threads Brickland Haswell-EP with 128G memory
caused below changes:
+--------------------------------------+------------+------------+
| | af2c7f33f9 | 780f90e464 |
+--------------------------------------+------------+------------+
| boot_successes | 4 | 11 |
| boot_failures | 0 | 4 |
| Kernel_panic-not_syncing:Hard_LOCKUP | 0 | 4 |
| backtrace:_do_fork | 0 | 4 |
| backtrace:SyS_clone | 0 | 4 |
| backtrace:do_group_exit | 0 | 2 |
| backtrace:SyS_exit_group | 0 | 2 |
+--------------------------------------+------------+------------+
[ 38.997223] Key type id_legacy registered
DEBUG: Number of directories is 1
[ 63.164979] NMI watchdog: Watchdog detected hard LOCKUP on cpu 26
[ 63.171630] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver sg sd_mod x86_pkg_temp_thermal coretemp snd_pcm kvm_intel mgag200 snd_timer ttm kvm snd drm_kms_helper irqbypass syscopyarea soundcore crct10dif_pclmul sysfillrect crc32_pclmul sysimgblt crc32c_intel fb_sys_fops ghash_clmulni_intel aesni_intel ahci libahci drm lrw pcspkr gf128mul sb_edac glue_helper ipmi_devintf ablk_helper edac_core cryptd libata ipmi_si shpchp wmi ipmi_msghandler acpi_pad acpi_power_meter
[ 63.222338] CPU: 26 PID: 2007 Comm: reaim Not tainted 4.6.0-rc5-00245-g780f90e #1
[ 63.231170] Hardware name: Intel Corporation S2600WTT/S2600WTT, BIOS SE5C610.86B.01.01.0008.021120151325 02/11/2015
[ 63.243344] 0000000000000000 ffff882034005bd0 ffffffff8142eada 0000000000000000
[ 63.252221] 0000000000000000 ffff882034005be8 ffffffff81129e5f ffff88202c578000
[ 63.261103] ffff882034005c20 ffffffff8116a7c8 0000000000000001 ffff88203400a3c0
[ 63.270008] Call Trace:
[ 63.273310] <NMI> [<ffffffff8142eada>] dump_stack+0x63/0x89
[ 63.280363] [<ffffffff81129e5f>] watchdog_overflow_callback+0xbf/0xe0
[ 63.288282] [<ffffffff8116a7c8>] __perf_event_overflow+0x88/0x210
[ 63.295826] [<ffffffff8116b304>] perf_event_overflow+0x14/0x20
[ 63.303096] [<ffffffff8100c7ee>] intel_pmu_handle_irq+0x1ce/0x470
[ 63.310672] [<ffffffff811b7c7c>] ? vunmap_page_range+0x20c/0x330
[ 63.318167] [<ffffffff811b7db1>] ? unmap_kernel_range_noflush+0x11/0x20
[ 63.326353] [<ffffffff814ff22e>] ? ghes_copy_tofrom_phys+0x11e/0x2a0
[ 63.334257] [<ffffffff8105210f>] ? native_apic_wait_icr_idle+0x1f/0x30
[ 63.342369] [<ffffffff81032bc5>] ? arch_irq_work_raise+0x35/0x40
[ 63.349913] [<ffffffff81005afd>] perf_event_nmi_handler+0x2d/0x50
[ 63.357568] [<ffffffff810311bd>] nmi_handle+0x6d/0x130
[ 63.364170] [<ffffffff81031714>] default_do_nmi+0x44/0x130
[ 63.371162] [<ffffffff810318e2>] do_nmi+0xe2/0x130
[ 63.377402] [<ffffffff818fcdf1>] end_repeat_nmi+0x1a/0x1e
[ 63.384321] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.393413] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.402505] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.411584] <<EOE>> [<ffffffff810c9e03>] queued_write_lock_slowpath+0x93/0xa0
[ 63.420603] [<ffffffff818fa4fd>] _raw_write_lock_irq+0x2d/0x30
[ 63.428068] [<ffffffff8107a2ea>] copy_process+0x115a/0x1960
[ 63.436037] [<ffffffff8107ac9c>] _do_fork+0xbc/0x3d0
[ 63.442563] [<ffffffff81088ee2>] ? __set_task_blocked+0x32/0x80
[ 63.450172] [<ffffffff8107b059>] SyS_clone+0x19/0x20
[ 63.456729] [<ffffffff81003cc6>] do_syscall_64+0x66/0x1e0
[ 63.463782] [<ffffffff818fa8e1>] entry_SYSCALL64_slow_path+0x25/0x25
[ 63.471919] Kernel panic - not syncing: Hard LOCKUP
[ 63.478331] CPU: 26 PID: 2007 Comm: reaim Not tainted 4.6.0-rc5-00245-g780f90e #1
[ 63.487684] Hardware name: Intel Corporation S2600WTT/S2600WTT, BIOS SE5C610.86B.01.01.0008.021120151325 02/11/2015
[ 63.500376] 0000000000000000 ffff882034005b40 ffffffff8142eada ffffffff81c9a7e2
[ 63.509771] ffff882034005bd0 ffff882034005bc0 ffffffff81172d93 0000000000000010
[ 63.519183] ffff882034005bd0 ffff882034005b68 ffff882034005ba8 ffffffff81c9cc39
[ 63.528583] Call Trace:
[ 63.532399] <NMI> [<ffffffff8142eada>] dump_stack+0x63/0x89
[ 63.539921] [<ffffffff81172d93>] panic+0xd0/0x217
[ 63.546361] [<ffffffff8107b74b>] nmi_panic+0x3b/0x40
[ 63.553091] [<ffffffff81129e70>] watchdog_overflow_callback+0xd0/0xe0
[ 63.561472] [<ffffffff8116a7c8>] __perf_event_overflow+0x88/0x210
[ 63.569436] [<ffffffff8116b304>] perf_event_overflow+0x14/0x20
[ 63.577077] [<ffffffff8100c7ee>] intel_pmu_handle_irq+0x1ce/0x470
[ 63.584980] [<ffffffff811b7c7c>] ? vunmap_page_range+0x20c/0x330
[ 63.592756] [<ffffffff811b7db1>] ? unmap_kernel_range_noflush+0x11/0x20
[ 63.601191] [<ffffffff814ff22e>] ? ghes_copy_tofrom_phys+0x11e/0x2a0
[ 63.609311] [<ffffffff8105210f>] ? native_apic_wait_icr_idle+0x1f/0x30
[ 63.617605] [<ffffffff81032bc5>] ? arch_irq_work_raise+0x35/0x40
[ 63.625322] [<ffffffff81005afd>] perf_event_nmi_handler+0x2d/0x50
[ 63.633133] [<ffffffff810311bd>] nmi_handle+0x6d/0x130
[ 63.639862] [<ffffffff81031714>] default_do_nmi+0x44/0x130
[ 63.646971] [<ffffffff810318e2>] do_nmi+0xe2/0x130
[ 63.653293] [<ffffffff818fcdf1>] end_repeat_nmi+0x1a/0x1e
[ 63.660295] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.669439] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.678564] [<ffffffff810c8a0f>] ? native_queued_spin_lock_slowpath+0x19f/0x1d0
[ 63.687680] <<EOE>> [<ffffffff810c9e03>] queued_write_lock_slowpath+0x93/0xa0
[ 63.696718] [<ffffffff818fa4fd>] _raw_write_lock_irq+0x2d/0x30
[ 63.704195] [<ffffffff8107a2ea>] copy_process+0x115a/0x1960
[ 63.712162] [<ffffffff8107ac9c>] _do_fork+0xbc/0x3d0
[ 63.718664] [<ffffffff81088ee2>] ? __set_task_blocked+0x32/0x80
[ 63.726237] [<ffffffff8107b059>] SyS_clone+0x19/0x20
[ 63.732738] [<ffffffff81003cc6>] do_syscall_64+0x66/0x1e0
[ 63.739725] [<ffffffff818fa8e1>] entry_SYSCALL64_slow_path+0x25/0x25
[ 64.781454] NMI watchdog: Watchdog detected hard LOCKUP on cpu 27
[ 64.785161] Shutting down cpus with NMI
[ 64.793270] Modules linked in: rpcsec_gss_krb5 auth_rpcgss nfsv4 dns_resolver
[ 64.801423] Kernel Offset: disabled
[
Primary Bios Version: SE5C610.86B.01.01.0008.021120151325
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Thanks,
Kernel Test Robot
4 years, 8 months
[lkp] [sched/core] 2159197d66: netperf.Throughput_Mbps 14.2% improvement
by kernel test robot
FYI, we noticed netperf.Throughput_Mbps 14.2% improvement due to commit:
commit 2159197d66770ec01f75c93fb11dc66df81fd45b ("sched/core: Enable increased load resolution on 64-bit kernels")
https://git.kernel.org/pub/scm/linux/kernel/git/tip/tip.git sched/core
in testcase - netperf
on test machine - lkp-hsw-d01: 8 threads Haswell with 8G memory
=========================================================================================
cluster/compiler/cpufreq_governor/ip/kconfig/nr_threads/rootfs/runtime/send_size/tbox_group/test/testcase:
cs-localhost/gcc-4.9/performance/ipv4/x86_64-rhel/200%/debian-x86_64-2015-02-07.cgz/300s/10K/lkp-hsw-d01/SCTP_STREAM_MANY/netperf
commit:
e7904a28f5331c21d17af638cb477c83662e3cb6
2159197d66770ec01f75c93fb11dc66df81fd45b
e7904a28f5331c21 2159197d66770ec01f75c93fb1
---------------- --------------------------
%stddev %change %stddev
\ | \
4657 ± 3% +14.2% 5317 ± 0% netperf.Throughput_Mbps
10987 ± 9% +3.4e+05% 37810767 ± 0% netperf.time.involuntary_context_switches
527.25 ± 0% -25.1% 395.00 ± 0% netperf.time.percent_of_cpu_this_job_got
1547 ± 0% -24.9% 1162 ± 0% netperf.time.system_time
44.45 ± 1% -32.1% 30.16 ± 1% netperf.time.user_time
16045063 ± 3% -82.8% 2757566 ± 6% netperf.time.voluntary_context_switches
10119 ± 41% +203.3% 30688 ± 38% cpuidle.C1-HSW.usage
51528 ± 8% +203.0% 156148 ± 9% softirqs.RCU
55.94 ± 1% +4.2% 58.31 ± 0% turbostat.CorWatt
64.79 ± 1% +2.6% 66.45 ± 0% turbostat.PkgWatt
21.00 ± 0% -8.3% 19.25 ± 2% vmstat.procs.r
105875 ± 3% +155.5% 270467 ± 0% vmstat.system.cs
3.535e+08 ± 3% +13.4% 4.007e+08 ± 0% proc-vmstat.numa_hit
3.535e+08 ± 3% +13.4% 4.007e+08 ± 0% proc-vmstat.numa_local
8.196e+08 ± 3% +22.1% 1.001e+09 ± 0% proc-vmstat.pgalloc_dma32
1.043e+09 ± 3% +23.0% 1.282e+09 ± 0% proc-vmstat.pgalloc_normal
1.862e+09 ± 3% +22.6% 2.283e+09 ± 0% proc-vmstat.pgfree
10987 ± 9% +3.4e+05% 37810767 ± 0% time.involuntary_context_switches
527.25 ± 0% -25.1% 395.00 ± 0% time.percent_of_cpu_this_job_got
1547 ± 0% -24.9% 1162 ± 0% time.system_time
44.45 ± 1% -32.1% 30.16 ± 1% time.user_time
16045063 ± 3% -82.8% 2757566 ± 6% time.voluntary_context_switches
727242 ± 6% -94.3% 41723 ± 17% sched_debug.cfs_rq:/.MIN_vruntime.avg
824178 ± 0% -90.6% 77484 ± 0% sched_debug.cfs_rq:/.MIN_vruntime.max
353474 ± 28% -100.0% 0.00 ± 0% sched_debug.cfs_rq:/.MIN_vruntime.min
181141 ± 29% -80.4% 35590 ± 4% sched_debug.cfs_rq:/.MIN_vruntime.stddev
215.56 ± 4% +6.5e+05% 1404265 ± 3% sched_debug.cfs_rq:/.load.avg
289.96 ± 27% +6.7e+05% 1954288 ± 3% sched_debug.cfs_rq:/.load.max
161.25 ± 6% +5.4e+05% 865368 ± 0% sched_debug.cfs_rq:/.load.min
41.64 ± 61% +1.2e+06% 503455 ± 3% sched_debug.cfs_rq:/.load.stddev
196.06 ± 2% +401.4% 983.07 ± 2% sched_debug.cfs_rq:/.load_avg.avg
241.33 ± 7% +359.5% 1108 ± 7% sched_debug.cfs_rq:/.load_avg.max
161.29 ± 0% +434.5% 862.17 ± 0% sched_debug.cfs_rq:/.load_avg.min
31.50 ± 19% +180.5% 88.36 ± 34% sched_debug.cfs_rq:/.load_avg.stddev
727242 ± 6% -94.3% 41723 ± 17% sched_debug.cfs_rq:/.max_vruntime.avg
824178 ± 0% -90.6% 77484 ± 0% sched_debug.cfs_rq:/.max_vruntime.max
353474 ± 28% -100.0% 0.00 ± 0% sched_debug.cfs_rq:/.max_vruntime.min
181141 ± 29% -80.4% 35590 ± 4% sched_debug.cfs_rq:/.max_vruntime.stddev
819702 ± 0% -90.7% 76071 ± 0% sched_debug.cfs_rq:/.min_vruntime.avg
826026 ± 0% -90.4% 79401 ± 1% sched_debug.cfs_rq:/.min_vruntime.max
815229 ± 0% -90.8% 74929 ± 0% sched_debug.cfs_rq:/.min_vruntime.min
3308 ± 26% -57.2% 1415 ± 23% sched_debug.cfs_rq:/.min_vruntime.stddev
1.66 ± 1% -18.9% 1.34 ± 3% sched_debug.cfs_rq:/.nr_running.avg
1.25 ± 6% -33.3% 0.83 ± 0% sched_debug.cfs_rq:/.nr_running.min
0.24 ± 18% +98.5% 0.48 ± 4% sched_debug.cfs_rq:/.nr_running.stddev
151.14 ± 1% +482.7% 880.75 ± 1% sched_debug.cfs_rq:/.runnable_load_avg.avg
168.83 ± 1% +502.6% 1017 ± 0% sched_debug.cfs_rq:/.runnable_load_avg.max
103.42 ± 9% +601.1% 725.04 ± 10% sched_debug.cfs_rq:/.runnable_load_avg.min
21.35 ± 17% +357.0% 97.56 ± 20% sched_debug.cfs_rq:/.runnable_load_avg.stddev
-9567 ±-36% -53.2% -4474 ±-24% sched_debug.cfs_rq:/.spread0.min
3310 ± 26% -57.2% 1416 ± 23% sched_debug.cfs_rq:/.spread0.stddev
536142 ± 16% -39.3% 325631 ± 29% sched_debug.cpu.avg_idle.avg
0.90 ± 14% +150.7% 2.26 ± 5% sched_debug.cpu.clock.stddev
0.90 ± 14% +150.7% 2.26 ± 5% sched_debug.cpu.clock_task.stddev
153.77 ± 0% +477.9% 888.63 ± 1% sched_debug.cpu.cpu_load[0].avg
169.17 ± 1% +503.3% 1020 ± 0% sched_debug.cpu.cpu_load[0].max
122.96 ± 5% +549.6% 798.71 ± 1% sched_debug.cpu.cpu_load[0].min
14.98 ± 13% +408.1% 76.13 ± 2% sched_debug.cpu.cpu_load[0].stddev
153.47 ± 0% +484.2% 896.64 ± 1% sched_debug.cpu.cpu_load[1].avg
166.75 ± 0% +502.6% 1004 ± 1% sched_debug.cpu.cpu_load[1].max
130.62 ± 2% +514.7% 802.92 ± 3% sched_debug.cpu.cpu_load[1].min
11.46 ± 8% +483.7% 66.91 ± 10% sched_debug.cpu.cpu_load[1].stddev
152.94 ± 0% +485.7% 895.80 ± 1% sched_debug.cpu.cpu_load[2].avg
165.04 ± 0% +502.6% 994.50 ± 1% sched_debug.cpu.cpu_load[2].max
136.29 ± 1% +489.4% 803.29 ± 3% sched_debug.cpu.cpu_load[2].min
9.17 ± 12% +589.1% 63.21 ± 12% sched_debug.cpu.cpu_load[2].stddev
153.46 ± 0% +483.7% 895.78 ± 1% sched_debug.cpu.cpu_load[3].avg
163.92 ± 0% +500.3% 984.04 ± 1% sched_debug.cpu.cpu_load[3].max
141.88 ± 1% +468.7% 806.79 ± 2% sched_debug.cpu.cpu_load[3].min
7.18 ± 12% +734.9% 59.98 ± 12% sched_debug.cpu.cpu_load[3].stddev
155.51 ± 0% +475.5% 894.93 ± 1% sched_debug.cpu.cpu_load[4].avg
164.58 ± 0% +489.0% 969.38 ± 0% sched_debug.cpu.cpu_load[4].max
146.58 ± 1% +452.8% 810.38 ± 2% sched_debug.cpu.cpu_load[4].min
5.76 ± 13% +858.1% 55.18 ± 12% sched_debug.cpu.cpu_load[4].stddev
215.88 ± 5% +6.3e+05% 1365964 ± 6% sched_debug.cpu.load.avg
293.25 ± 26% +6.7e+05% 1953269 ± 3% sched_debug.cpu.load.max
166.50 ± 5% +5.2e+05% 865491 ± 0% sched_debug.cpu.load.min
40.05 ± 61% +1.2e+06% 478086 ± 4% sched_debug.cpu.load.stddev
2.51 ± 1% -10.6% 2.24 ± 3% sched_debug.cpu.nr_running.avg
1.96 ± 7% -27.7% 1.42 ± 13% sched_debug.cpu.nr_running.min
0.38 ± 24% +85.2% 0.70 ± 23% sched_debug.cpu.nr_running.stddev
2001135 ± 3% +154.6% 5095769 ± 0% sched_debug.cpu.nr_switches.avg
2026432 ± 4% +158.1% 5231149 ± 0% sched_debug.cpu.nr_switches.max
1975801 ± 3% +152.2% 4983341 ± 1% sched_debug.cpu.nr_switches.min
17661 ± 48% +365.1% 82150 ± 34% sched_debug.cpu.nr_switches.stddev
To reproduce:
git clone git://git.kernel.org/pub/scm/linux/kernel/git/wfg/lkp-tests.git
cd lkp-tests
bin/lkp install job.yaml # job file is attached in this email
bin/lkp run job.yaml
Disclaimer:
Results have been estimated based on internal Intel analysis and are provided
for informational purposes only. Any difference in system hardware or software
design or configuration may affect actual performance.
Thanks,
Xiaolong
4 years, 8 months
[lkp] [sched] 0b53691d6b: kmsg.unsafe_for_production_use.**
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git sched/rfc
commit 0b53691d6bc2416eaf65d81d33bfcf7eb323451d ("sched: debug muck -- not for merging")
on test machine: vm-vp-quantal-x86_64: 2 threads qemu-system-x86_64 -enable-kvm with 360M memory
caused below changes:
[ 0.000000] RCU: Adjusting geometry for rcu_fanout_leaf=64, nr_cpu_ids=2
[ 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] **********************************************************
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-nfsroot/gcc-5/0b53691d6bc2416eaf65d81d33bfcf7eb323451d/vmlinuz-4.6.0-rc6-00899-g0b53691 -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-16/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-nfsroot-0b53691d6bc2416eaf65d81d33bfcf7eb323451d-20160510-62325-bnj9wm-0.yaml ARCH=x86_64 kconfig=x86_64-nfsroot branch=linux-devel/devel-hourly-2016051003 commit=0b53691d6bc2416eaf65d81d33bfcf7eb323451d BOOT_IMAGE=/pkg/linux/x86_64-nfsroot/gcc-5/0b53691d6bc2416eaf65d81d33bfcf7eb323451d/vmlinuz-4.6.0-rc6-00899-g0b53691 max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-nfsroot/gcc-5/0b53691d6bc2416eaf65d81d33bfcf7eb323451d/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err 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 rw ip=::::vm-vp-quantal-x86_64-16::dhcp drbd.minor_count=8' -initrd /fs/sdh1/initrd-vm-vp-quantal-x86_64-16 -m 360 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -pidfile /dev/shm/kboot/pid-vm-vp-quantal-x86_64-16 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-16 -daemonize -display none -monitor null
Thanks,
Xiaolong
4 years, 8 months
[lkp] [vfs] 58a94fa182: BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/mszeredi/vfs.git overlayfs-linus
commit 58a94fa1824f21e2e94be7caad8673a95df89d58 ("vfs: add vfs_select_inode() helper")
on test machine: vm-vp-2G: 4 threads qemu-system-x86_64 -enable-kvm -cpu Penryn with 2G memory
caused below changes:
[ 1.017580] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.019600] PCI: CLS 0 bytes, default 64
[ 1.020644] Unpacking initramfs...
[ 1.021788] BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
[ 1.023826] IP: [<ffffffff8121c450>] __mnt_want_write+0x10/0x70
[ 1.025112] PGD 0
[ 1.025935] Oops: 0000 [#1] SMP
[ 1.026978] Modules linked in:
[ 1.027923] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.6.0-rc7-00001-g58a94fa #1
[ 1.029765] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.031764] task: ffff880074028000 ti: ffff880074024000 task.ti: ffff880074024000
[ 1.033586] RIP: 0010:[<ffffffff8121c450>] [<ffffffff8121c450>] __mnt_want_write+0x10/0x70
[ 1.035600] RSP: 0000:ffff880074027a50 EFLAGS: 00010282
[ 1.036744] RAX: 0000000000000000 RBX: ffff880073e2df00 RCX: ffff880073b6b8c8
[ 1.038094] RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
[ 1.039447] RBP: ffff880074027a50 R08: ffff880073b160f8 R09: 0000000000000000
[ 1.040803] R10: 0000000000000000 R11: ffff880073b160c0 R12: ffff880074bf3000
[ 1.042161] R13: ffff880073b6b778 R14: ffff880074027b80 R15: ffff880073e2df10
[ 1.043511] FS: 0000000000000000(0000) GS:ffff880077200000(0000) knlGS:0000000000000000
[ 1.045417] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.046608] CR2: 0000000000000028 CR3: 0000000001e06000 CR4: 00000000000006f0
[ 1.047961] Stack:
[ 1.048712] ffff880074027a90 ffffffff811f8513 0000000000000000 ffff880073e2df00
[ 1.050842] ffff880074bf3000 ffff880074027c94 ffff880074027b80 0000000000000000
[ 1.052964] ffff880074027ab0 ffffffff811f99d6 0000000000008041 0000000000000000
[ 1.055090] Call Trace:
[ 1.055891] [<ffffffff811f8513>] do_dentry_open+0xb3/0x310
[ 1.057059] [<ffffffff811f99d6>] vfs_open+0x36/0x40
[ 1.058163] [<ffffffff81209766>] path_openat+0x1a6/0x13d0
[ 1.059325] [<ffffffff820239a3>] ? bunzip2+0x41f/0x41f
[ 1.060449] [<ffffffff8120ba2e>] do_filp_open+0x7e/0xe0
[ 1.061582] [<ffffffff8120ab66>] ? getname_flags+0x56/0x1f0
[ 1.062771] [<ffffffff811d87e5>] ? kmem_cache_alloc+0x1a5/0x200
[ 1.063992] [<ffffffff812196d6>] ? __alloc_fd+0x46/0x180
[ 1.065143] [<ffffffff811f9d16>] do_sys_open+0x116/0x1f0
[ 1.066290] [<ffffffff820239a3>] ? bunzip2+0x41f/0x41f
[ 1.067419] [<ffffffff811f9e0e>] SyS_open+0x1e/0x20
[ 1.068524] [<ffffffff81fdcc90>] do_name+0xc9/0x283
[ 1.069630] [<ffffffff81fdc0f7>] write_buffer+0x23/0x34
[ 1.070775] [<ffffffff81fdc133>] flush_buffer+0x2b/0x85
[ 1.071917] [<ffffffff81fdc108>] ? write_buffer+0x34/0x34
[ 1.073069] [<ffffffff82023c46>] __gunzip+0x299/0x341
[ 1.074187] [<ffffffff82023cee>] ? __gunzip+0x341/0x341
[ 1.075320] [<ffffffff82023cff>] gunzip+0x11/0x13
[ 1.076399] [<ffffffff81fdc059>] ? md_run_setup+0x9a/0x9a
[ 1.077551] [<ffffffff81fdc9af>] unpack_to_rootfs+0x171/0x275
[ 1.078758] [<ffffffff81fdc059>] ? md_run_setup+0x9a/0x9a
[ 1.079914] [<ffffffff81fdcab3>] ? unpack_to_rootfs+0x275/0x275
[ 1.081133] [<ffffffff81fdcb13>] populate_rootfs+0x60/0x114
[ 1.082317] [<ffffffff81002123>] do_one_initcall+0xb3/0x1d0
[ 1.083506] [<ffffffff81fdb11b>] kernel_init_freeable+0x192/0x21f
[ 1.084766] [<ffffffff818ee29e>] kernel_init+0xe/0x110
[ 1.085903] [<ffffffff818fb5c2>] ret_from_fork+0x22/0x40
[ 1.087055] [<ffffffff818ee290>] ? rest_init+0x90/0x90
[ 1.088184] Code: df 7e b8 f0 ff ff ff e9 55 fe ff ff b8 f5 ff ff ff e9 4b fe ff ff 0f 1f 44 00 00 66 66 66 66 90 55 65 ff 05 0b 0f df 7e 48 89 e5 <48> 8b 47 28 65 ff 40 04 0f ae f0 8b 47 10 f6 c4 02 74 0a f3 90
[ 1.096625] RIP [<ffffffff8121c450>] __mnt_want_write+0x10/0x70
[ 1.097925] RSP <ffff880074027a50>
[ 1.098852] CR2: 0000000000000028
[ 1.099757] ---[ end trace 83ea1c07e6cbf79c ]---
[ 1.100818] Kernel panic - not syncing: Fatal exception
FYI, raw QEMU command line is:
qemu-system-x86_64 -enable-kvm -cpu Penryn -kernel /pkg/linux/x86_64-rhel/gcc-4.9/58a94fa1824f21e2e94be7caad8673a95df89d58/vmlinuz-4.6.0-rc7-00001-g58a94fa -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-2G-1/bisect_boot-1-debian-x86_64-2015-02-07.cgz-x86_64-rhel-58a94fa1824f21e2e94be7caad8673a95df89d58-20160511-87678-16ow8qg-0.yaml ARCH=x86_64 kconfig=x86_64-rhel branch=linux-devel/devel-hourly-2016051109 commit=58a94fa1824f21e2e94be7caad8673a95df89d58 BOOT_IMAGE=/pkg/linux/x86_64-rhel/gcc-4.9/58a94fa1824f21e2e94be7caad8673a95df89d58/vmlinuz-4.6.0-rc7-00001-g58a94fa max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-2G/debian-x86_64-2015-02-07.cgz/x86_64-rhel/gcc-4.9/58a94fa1824f21e2e94be7caad8673a95df89d58/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err 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 rw ip=::::vm-vp-2G-1::dhcp' -initrd /fs/sdd1/initrd-vm-vp-2G-1 -m 2048 -smp 4 -device e1000,netdev=net0 -netdev user,id=net0,hostfwd=tcp::23108-:22 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdd1/disk0-vm-vp-2G-1,media=disk,if=virtio -drive file=/fs/sdd1/disk1-vm-vp-2G-1,media=disk,if=virtio -drive file=/fs/sdd1/disk2-vm-vp-2G-1,media=disk,if=virtio -drive file=/fs/sdd1/disk3-vm-vp-2G-1,media=disk,if=virtio -drive file=/fs/sdd1/disk4-vm-vp-2G-1,media=disk,if=virtio -drive file=/fs/sdd1/disk5-vm-vp-2G-1,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-vp-2G-1 -serial file:/dev/shm/kboot/serial-vm-vp-2G-1 -daemonize -display none -monitor null
Thanks,
Xiaolong
4 years, 8 months
[vfs] 58a94fa182: BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/mszeredi/vfs.git overlayfs-next
commit 58a94fa1824f21e2e94be7caad8673a95df89d58
Author: Miklos Szeredi <mszeredi(a)redhat.com>
AuthorDate: Wed May 11 01:16:37 2016 +0200
Commit: Miklos Szeredi <mszeredi(a)veci.piliscsaba.szeredi.hu>
CommitDate: Wed May 11 01:16:37 2016 +0200
vfs: add vfs_select_inode() helper
Signed-off-by: Miklos Szeredi <mszeredi(a)redhat.com>
Cc: <stable(a)vger.kernel.org> # v4.2+
+------------------------------------------+----------+------------+------------+
| | v4.6-rc7 | 58a94fa182 | a5b55f8b12 |
+------------------------------------------+----------+------------+------------+
| boot_successes | 62 | 0 | 0 |
| boot_failures | 0 | 22 | 13 |
| BUG:unable_to_handle_kernel | 0 | 22 | 13 |
| Oops | 0 | 22 | 13 |
| RIP:__mnt_want_write | 0 | 22 | 13 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 22 | 13 |
| backtrace:do_sys_open | 0 | 22 | 13 |
| backtrace:SyS_open | 0 | 22 | 13 |
| backtrace:populate_rootfs | 0 | 22 | 13 |
| backtrace:kernel_init_freeable | 0 | 22 | 13 |
+------------------------------------------+----------+------------+------------+
[ 1.286001] pci 0000:00:02.0: Video device with shadowed ROM at [mem 0x000c0000-0x000dffff]
[ 1.287887] PCI: CLS 0 bytes, default 64
[ 1.288842] Trying to unpack rootfs image as initramfs...
[ 1.290383] BUG: unable to handle kernel NULL pointer dereference at 0000000000000028
[ 1.292291] IP: [<ffffffff8124bae5>] __mnt_want_write+0x14/0x5d
[ 1.293499] PGD 0
[ 1.294265] Oops: 0000 [#1] SMP
[ 1.295237] Modules linked in:
[ 1.296118] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.6.0-rc7-00001-g58a94fa #1
[ 1.297818] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.299674] task: ffff88000ee00000 ti: ffff88000ed5c000 task.ti: ffff88000ed5c000
[ 1.301366] RIP: 0010:[<ffffffff8124bae5>] [<ffffffff8124bae5>] __mnt_want_write+0x14/0x5d
[ 1.303244] RSP: 0000:ffff88000ed5fab8 EFLAGS: 00010282
[ 1.304308] RAX: 0000000000000000 RBX: 0000000000000000 RCX: ffff88000db20c38
[ 1.305568] RDX: 0000000000000000 RSI: 0000000000000001 RDI: 0000000000000000
[ 1.306861] RBP: ffff88000ed5fac0 R08: ffff88000db0b0f8 R09: 0000000000000000
[ 1.308123] R10: ffff88000db0b0c0 R11: fefefefefefefeff R12: ffff88000db20af0
[ 1.309380] R13: ffff88000db0b0c0 R14: 0000000000000000 R15: ffff88000dc11410
[ 1.310647] FS: 0000000000000000(0000) GS:ffff88000fa00000(0000) knlGS:0000000000000000
[ 1.312401] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.313508] CR2: 0000000000000028 CR3: 0000000002206000 CR4: 00000000001406f0
[ 1.314789] Stack:
[ 1.315477] ffff88000dc11400 ffff88000ed5fb00 ffffffff8122efdb ffff88000db20c38
[ 1.317455] ffff88000dc11400 ffff88000dc11400 ffff88000db0b0c0 ffff88000db09540
[ 1.319434] 0000000000000000 ffff88000ed5fb18 ffffffff8122fb59 ffff88000ed5fbe0
[ 1.321412] Call Trace:
[ 1.322155] [<ffffffff8122efdb>] do_dentry_open+0x21d/0x2a0
[ 1.323337] [<ffffffff8122fb59>] vfs_open+0x2e/0x31
[ 1.324367] [<ffffffff8123d623>] path_openat+0x9fb/0xc8c
[ 1.325451] [<ffffffff81215aa8>] ? kmem_cache_alloc+0x45/0x1ac
[ 1.326594] [<ffffffff823d5a6d>] ? md_run_setup+0x9a/0x9a
[ 1.327683] [<ffffffff8123e4dc>] do_filp_open+0x4d/0xa3
[ 1.328747] [<ffffffff812351e6>] ? vfs_fstatat+0x51/0x8c
[ 1.329821] [<ffffffff81123355>] ? __might_sleep+0x77/0x7e
[ 1.330907] [<ffffffff81215aa8>] ? kmem_cache_alloc+0x45/0x1ac
[ 1.332044] [<ffffffff81249783>] ? __alloc_fd+0x9a/0x15c
[ 1.333118] [<ffffffff8122fe57>] do_sys_open+0x13a/0x1c9
[ 1.334188] [<ffffffff8122fe57>] ? do_sys_open+0x13a/0x1c9
[ 1.335278] [<ffffffff823d5a6d>] ? md_run_setup+0x9a/0x9a
[ 1.336364] [<ffffffff8122ff04>] SyS_open+0x1e/0x20
[ 1.337392] [<ffffffff823d6929>] do_name+0xc7/0x284
[ 1.338419] [<ffffffff823d5ba2>] write_buffer+0x23/0x34
[ 1.339486] [<ffffffff823d5bde>] flush_buffer+0x2b/0x85
[ 1.340557] [<ffffffff824194dd>] __gunzip+0x26d/0x307
[ 1.341603] [<ffffffff82419266>] ? bunzip2+0x38b/0x38b
[ 1.342658] [<ffffffff823d5bb3>] ? write_buffer+0x34/0x34
[ 1.343743] [<ffffffff82419577>] ? __gunzip+0x307/0x307
[ 1.344809] [<ffffffff82419588>] gunzip+0x11/0x13
[ 1.345818] [<ffffffff823d5a6d>] ? md_run_setup+0x9a/0x9a
[ 1.346900] [<ffffffff823d6535>] unpack_to_rootfs+0x16d/0x287
[ 1.348018] [<ffffffff823d5a6d>] ? md_run_setup+0x9a/0x9a
[ 1.349101] [<ffffffff823d675e>] ? maybe_link+0x10f/0x10f
[ 1.350248] [<ffffffff823d67be>] populate_rootfs+0x60/0x104
[ 1.351347] [<ffffffff8100041a>] do_one_initcall+0xfd/0x18f
[ 1.352446] [<ffffffff823d4095>] kernel_init_freeable+0x196/0x21e
[ 1.353608] [<ffffffff81a82a9e>] kernel_init+0xe/0xfd
[ 1.354660] [<ffffffff81a8da22>] ret_from_fork+0x22/0x50
[ 1.355730] [<ffffffff81a82a90>] ? rest_init+0x87/0x87
[ 1.356782] Code: c0 65 ff 0d 06 0a dc 7e 45 39 e5 5b 0f 9e c0 41 5c 41 5d 41 5e 5d c3 0f 1f 44 00 00 55 65 ff 05 ea 09 dc 7e 48 89 e5 53 48 89 fb <48> 8b 47 28 65 ff 40 04 0f ae f0 8b 43 10 f6 c4 02 74 04 f3 90
[ 1.364881] RIP [<ffffffff8124bae5>] __mnt_want_write+0x14/0x5d
[ 1.366094] RSP <ffff88000ed5fab8>
[ 1.366951] CR2: 0000000000000028
[ 1.367797] ---[ end trace b00291e1005ba3aa ]---
[ 1.368783] Kernel panic - not syncing: Fatal exception
git bisect start a5b55f8b1294514d13d6e26bde00f3e1d1dc1e9d 44549e8f5eea4e0a41b487b63e616cb089922b99 --
git bisect bad fe0b21e1a48218b359188c0b18bbc2a549f3a319 # 07:44 0- 22 Merge 'net-next/master' into devel-catchup-201605110723
git bisect bad 69c90614df9a6d272e64e824db4ff0aadaa7887d # 07:48 0- 22 Merge 'linux-review/Stephen-Boyd/ARM-dts-qcom-Enable-RPM-regulators-on-apq8074-dragonboard/20160511-064948' into devel-catchup-201605110723
git bisect good e64e0a33466665dbeffefe338e938be02aba7c8d # 07:54 20+ 0 0day base guard for 'devel-catchup-201605110723'
git bisect bad 983fd7b18ee515859fd2d1dc290987aad4b01a04 # 07:59 0- 22 Merge 'miklos-vfs/overlayfs-linus' into devel-catchup-201605110723
git bisect bad 984892e0b17bd04e22142362a23aa5ee4730e94e # 08:04 0- 21 vfs: rename: check backing inode being equal
git bisect bad 58a94fa1824f21e2e94be7caad8673a95df89d58 # 08:07 0- 22 vfs: add vfs_select_inode() helper
# first bad commit: [58a94fa1824f21e2e94be7caad8673a95df89d58] vfs: add vfs_select_inode() helper
git bisect good 44549e8f5eea4e0a41b487b63e616cb089922b99 # 08:09 62+ 0 Linux 4.6-rc7
# extra tests on HEAD of linux-devel/devel-catchup-201605110723
git bisect bad a5b55f8b1294514d13d6e26bde00f3e1d1dc1e9d # 08:09 0- 13 0day head guard for 'devel-catchup-201605110723'
# extra tests on tree/branch miklos-vfs/overlayfs-next
git bisect bad e3221b9b54723890c53cfcf641993dc9ab1004f9 # 08:16 0- 65 ovl: ignore permissions on underlying lookup
# extra tests with first bad commit reverted
# extra tests on tree/branch linus/master
git bisect good c5114626f33b62fa7595e57d87f33d9d1f8298a2 # 08:22 66+ 1 Merge tag 'pci-v4.6-fixes-3' of git://git.kernel.org/pub/scm/linux/kernel/git/helgaas/pci
# extra tests on tree/branch linux-next/master
git bisect good 416636ba5663bcf5a329009acdd1b27dbfe77791 # 08:24 64+ 0 Add linux-next specific files for 20160510
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
initrd=yocto-minimal-x86_64.cgz
wget --no-clobber https://github.com/fengguang/reproduce-kernel-bug/raw/master/initrd/$initrd
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu Haswell,+smep,+smap
-kernel $kernel
-initrd $initrd
-m 256
-smp 1
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
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[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 8 months
[sched] 5c124ccfe8: BUG: unable to handle kernel NULL pointer dereference at 0000000000000118
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
https://git.kernel.org/pub/scm/linux/kernel/git/peterz/queue.git sched/rfc
commit 5c124ccfe885337d038f7a4fa9ca6c5759c0c663
Author: Peter Zijlstra <peterz(a)infradead.org>
AuthorDate: Mon May 9 10:37:57 2016 +0200
Commit: Peter Zijlstra <peterz(a)infradead.org>
CommitDate: Mon May 9 12:44:25 2016 +0200
sched: Restructure destroy_sched_domain()
There is no point in doing a call_rcu() for each domain, only do a
callback for the root sched domain and clean up the entire set in one
go.
Also make the entire call chain be called destroy_sched_domain*() to
remove confusion with the free_sched_domains() call, which does an
entirely different thing.
Both cpu_attach_domain() callers of destroy_sched_domain() can live
without the call_rcu() because at those points the sched_domain hasn't
been published yet.
Signed-off-by: Peter Zijlstra (Intel) <peterz(a)infradead.org>
+------------------------------------------+------------+------------+------------+
| | 9691dc06cc | 5c124ccfe8 | 649811aff0 |
+------------------------------------------+------------+------------+------------+
| boot_successes | 86 | 0 | 0 |
| boot_failures | 2 | 22 | 13 |
| Out_of_memory:Kill_process | 2 | | |
| BUG:unable_to_handle_kernel | 0 | 22 | 13 |
| Oops | 0 | 22 | 13 |
| RIP:__call_rcu | 0 | 22 | 13 |
| Kernel_panic-not_syncing:Fatal_exception | 0 | 22 | 13 |
| backtrace:build_sched_domains | 0 | 22 | 13 |
| backtrace:sched_init_smp | 0 | 22 | 13 |
| backtrace:kernel_init_freeable | 0 | 22 | 13 |
+------------------------------------------+------------+------------+------------+
[ 1.521848] Good, all 2 testcases passed! |
[ 1.523533] ---------------------------------
[ 1.525212] smpboot: Total of 2 processors activated (10639.99 BogoMIPS)
[ 1.541881] BUG: unable to handle kernel NULL pointer dereference at 0000000000000118
[ 1.545273] IP: [<ffffffff8110f1bd>] __call_rcu+0x49/0x5b7
[ 1.547513] PGD 0
[ 1.548839] Oops: 0002 [#1] SMP
[ 1.550265] Modules linked in:
[ 1.551829] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.6.0-rc6-00894-g5c124cc #2
[ 1.554809] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[ 1.558076] task: ffff88000f428040 ti: ffff88000f430000 task.ti: ffff88000f430000
[ 1.561059] RIP: 0010:[<ffffffff8110f1bd>] [<ffffffff8110f1bd>] __call_rcu+0x49/0x5b7
[ 1.564493] RSP: 0000:ffff88000f433d88 EFLAGS: 00010246
[ 1.566350] RAX: ffff8800103d6400 RBX: 0000000000000000 RCX: 0000000000000000
[ 1.568660] RDX: ffffffff8246d0c0 RSI: ffffffff810c5fd5 RDI: 0000000000000100
[ 1.570911] RBP: ffff88000f433dd0 R08: 00000000c07b34dc R09: 0000000000000006
[ 1.573135] R10: ffff88000f433cc8 R11: ffff8800103d6cc0 R12: ffffffff8246d0c0
[ 1.575340] R13: 0000000000000110 R14: 0000000000000000 R15: ffffffff810c5fd5
[ 1.577558] FS: 0000000000000000(0000) GS:ffff880010200000(0000) knlGS:0000000000000000
[ 1.580660] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 1.582615] CR2: 0000000000000118 CR3: 0000000002418000 CR4: 00000000000006f0
[ 1.584828] Stack:
[ 1.586041] 0000000000000246 ffff8800103d6400 0000000000000000 ffff88000f433de0
[ 1.589478] 0000000000000000 ffff88000fdd27c8 0000000000000000 ffff88000fdd27c8
[ 1.592938] 0000000000000000 ffff88000f433de0 ffffffff8110f777 ffff88000f433e40
[ 1.596371] Call Trace:
[ 1.597674] [<ffffffff8110f777>] call_rcu_sched+0x17/0x19
[ 1.599564] [<ffffffff810c6808>] cpu_attach_domain+0x38c/0x5bd
[ 1.601558] [<ffffffff810ce0cd>] build_sched_domains+0xca7/0xd7c
[ 1.603572] [<ffffffff827cee20>] sched_init_smp+0x62/0xf4
[ 1.605458] [<ffffffff827b0013>] kernel_init_freeable+0x134/0x252
[ 1.607476] [<ffffffff81caa012>] kernel_init+0xe/0xf5
[ 1.609286] [<ffffffff81cb816f>] ret_from_fork+0x1f/0x40
[ 1.611164] [<ffffffff81caa004>] ? rest_init+0x13b/0x13b
[ 1.613051] Code: 48 83 ec 20 40 80 e7 01 74 21 80 3d 17 f5 49 01 00 75 18 be 2b 0c 00 00 48 c7 c7 f5 55 1f 82 c6 05 02 f5 49 01 01 e8 40 04 f9 ff <4d> 89 7d 08 49 c7 45 00 00 00 00 00 9c 58 0f 1f 44 00 00 48 89
[ 1.626638] RIP [<ffffffff8110f1bd>] __call_rcu+0x49/0x5b7
[ 1.628880] RSP <ffff88000f433d88>
[ 1.630381] CR2: 0000000000000118
[ 1.631892] ---[ end trace 87edb9116ad41f9d ]---
[ 1.633608] Kernel panic - not syncing: Fatal exception
git bisect start 649811aff0e72d73fc0ffdd22cca83f2239ed8b8 04974df8049fc4240d22759a91e035082ccd18b4 --
git bisect bad 86d9995a84af39c1cc807a17ef24fae0a5018175 # 19:14 0- 22 Merge 'pinctrl/for-next' into devel-catchup-201605091849
git bisect bad e0964da65b65b8350f9bd1feadd856e3736802d3 # 19:19 0- 22 Merge 'peterz-queue/locking/rfc' into devel-catchup-201605091849
git bisect good 314ea3aa21b12ee8a9a95ec95242704d8425ce71 # 19:26 22+ 0 Merge 'linux-review/Peter-Ujfalusi/ASoC-davinci-mcasp-Use-defines-for-clkdiv-IDs/20160509-184705' into devel-catchup-201605091849
git bisect good f2f6f044e15d969fd257b5881a8e1852ba479e7f # 19:31 22+ 5 Merge 'peterz-queue/locking/atomic' into devel-catchup-201605091849
git bisect bad f31464d96dd6d75db24ff70a3e6253ce55cba25e # 19:36 0- 22 Merge 'peterz-queue/sched/rfc' into devel-catchup-201605091849
git bisect good 1ac7f06982e5d949d2813f40af25ee9c8149567d # 19:42 22+ 0 Merge branch 'sched/urgent'
git bisect good df9f17497431a073c33aea6994f3b5b53fd89806 # 19:47 22+ 8 manual merge of x86/asm
git bisect good 8aacbdbbde6cb2b9c29690da6c47b441d50cbdf4 # 19:56 22+ 0 Merge branch 'x86/cpu'
git bisect good 3cd0b53553ce28da6fc828c601041c974e1c4dde # 20:05 22+ 2 Merge branch 'x86/urgent' into x86/platform, to resolve conflict
git bisect good 0629bbf2ed50ca4e1a49c86d7e5b9d94a198c3f7 # 20:07 21+ 1 Merge branch 'x86/urgent'
git bisect good 9691dc06cce901e70414102f859f4475d4cc3a99 # 20:13 22+ 1 sched: Remove unused @cpu argument from destroy_sched_domain*()
git bisect bad a77597455f844ca4f2503fd6a449880d8d88daeb # 20:16 0- 22 sched: Replace sd_busy/nr_busy_cpus with sched_domain_shared
git bisect bad ec677c335e77e6cdbf92893120ec1d5b1688f948 # 20:20 0- 22 sched: Introduce struct sched_domain_shared
git bisect bad 5c124ccfe885337d038f7a4fa9ca6c5759c0c663 # 20:23 0- 22 sched: Restructure destroy_sched_domain()
# first bad commit: [5c124ccfe885337d038f7a4fa9ca6c5759c0c663] sched: Restructure destroy_sched_domain()
git bisect good 9691dc06cce901e70414102f859f4475d4cc3a99 # 20:26 66+ 2 sched: Remove unused @cpu argument from destroy_sched_domain*()
# extra tests with DEBUG_INFO
git bisect bad 5c124ccfe885337d038f7a4fa9ca6c5759c0c663 # 20:32 0- 66 sched: Restructure destroy_sched_domain()
# extra tests on HEAD of linux-devel/devel-catchup-201605091849
git bisect bad 649811aff0e72d73fc0ffdd22cca83f2239ed8b8 # 20:32 0- 13 0day head guard for 'devel-catchup-201605091849'
# extra tests on tree/branch peterz-queue/sched/rfc
git bisect bad 2edc84378eb57fcbac61c2833b62b4b28f2cc8d2 # 20:39 0- 9 sched: debug muck -- not for merging
# extra tests with first bad commit reverted
git bisect good 8c8f1cbce8375c8a800bb8ad1a7c1f4a332c2c66 # 20:45 66+ 0 Revert "sched: Restructure destroy_sched_domain()"
# extra tests on tree/branch linus/master
git bisect good 44549e8f5eea4e0a41b487b63e616cb089922b99 # 20:45 74+ 0 Linux 4.6-rc7
# extra tests on tree/branch linux-next/master
git bisect good 2f91cb029bf43b17f52a76661d555a5147bfd806 # 20:49 65+ 6 Add linux-next specific files for 20160509
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
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[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 8 months
[lkp] [string_helpers] 726ecfc321: test_string_helpers: Test 'test_string_get_size_one' failed!
by kernel test robot
FYI, we noticed the following commit:
https://git.kernel.org/pub/scm/linux/kernel/git/stable/linux-stable.git linux-4.4.y
commit 726ecfc321994ec6ab044c1e3e5886408de991ac ("string_helpers: fix precision loss for some inputs")
on test machine: vm-kbuild-yocto-i386: 2 threads qemu-system-i386 -enable-kvm with 320M memory
caused below changes:
[ 3.739370] test_string_helpers: Running tests...
[ 3.739370] test_string_helpers: Running tests...
[ 3.742931] test_string_helpers: Test 'test_string_get_size_one' failed!
[ 3.742931] test_string_helpers: Test 'test_string_get_size_one' failed!
[ 3.745727] test_string_helpers: string_get_size(size = 8192, blk_size = 4096, units = 0
[ 3.745727] test_string_helpers: string_get_size(size = 8192, blk_size = 4096, units = 0
[ 3.749051] test_string_helpers: expected: '32.7 MB', got '33.6 MB'
[ 3.749051] test_string_helpers: expected: '32.7 MB', got '33.6 MB'
FYI, raw QEMU command line is:
qemu-system-i386 -enable-kvm -kernel /pkg/linux/i386-randconfig-w1-201616/gcc-5/726ecfc321994ec6ab044c1e3e5886408de991ac/vmlinuz-4.4.2-00096-g726ecfc -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-kbuild-yocto-i386-43/rand_boot-1-yocto-minimal-i386.cgz-i386-randconfig-w1-201616-726ecfc321994ec6ab044c1e3e5886408de991ac-20160420-34890-1vs11no-0.yaml ARCH=i386 kconfig=i386-randconfig-w1-201616 branch=internal-chrome-os/chromeos-4.4 commit=726ecfc321994ec6ab044c1e3e5886408de991ac BOOT_IMAGE=/pkg/linux/i386-randconfig-w1-201616/gcc-5/726ecfc321994ec6ab044c1e3e5886408de991ac/vmlinuz-4.4.2-00096-g726ecfc max_uptime=600 RESULT_ROOT=/result/boot/1/vm-kbuild-yocto-i386/yocto-minimal-i386.cgz/i386-randconfig-w1-201616/gcc-5/726ecfc321994ec6ab044c1e3e5886408de991ac/0 LKP_SERVER=inn earlyprintk=ttyS0,115200 systemd.log_level=err 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 rw ip=::::vm-kbuild-yocto-i386-43::dhcp drbd.minor_count=8' -initrd /fs/sdg1/initrd-vm-kbuild-yocto-i386-43 -m 320 -smp 2 -device e1000,netdev=net0 -netdev user,id=net0 -boot order=nc -no-reboot -watchdog i6300esb -rtc base=localtime -drive file=/fs/sdg1/disk0-vm-kbuild-yocto-i386-43,media=disk,if=virtio -pidfile /dev/shm/kboot/pid-vm-kbuild-yocto-i386-43 -serial file:/dev/shm/kboot/serial-vm-kbuild-yocto-i386-43 -daemonize -display none -monitor null
Thanks,
Xiaolong
4 years, 8 months
737e43bb50: BUG: KASAN: stack-out-of-bounds in cgroup_init_early at addr ffffffff854fff40
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_merge_and_test_tree devel-hourly-2016050817
commit 737e43bb50f4288c63424317c592db1eb15a2505
Merge: c3a4b49 e2d1248
Author: 0day robot <fengguang.wu(a)intel.com>
AuthorDate: Sun May 8 19:13:11 2016 +0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Sun May 8 19:13:11 2016 +0800
Merge 'rusty/modules-next' into devel-hourly-2016050817
+--------------------------------------------------------------+------------+------------+------------+-----------------+
| | c3a4b49421 | e2d1248432 | 737e43bb50 | v4.6-rc6_050817 |
+--------------------------------------------------------------+------------+------------+------------+-----------------+
| boot_successes | 0 | 63 | 0 | 0 |
| boot_failures | 88 | 0 | 22 | 13 |
| BUG:kernel_boot_hang | 88 | | | |
| BUG:KASAN:stack-out-of-bounds_in_cgroup_init_early_at_addr | 0 | 0 | 22 | 13 |
| BUG:KASAN:stack-out-of-bounds_in_fpu__init_system_at_addr | 0 | 0 | 22 | 13 |
| BUG:KASAN:stack-out-of-bounds_in_early_ioremap_setup_at_addr | 0 | 0 | 22 | 13 |
| backtrace:cgroup_init_early | 0 | 0 | 22 | 13 |
| backtrace:__asan_load8 | 0 | 0 | 22 | 13 |
| backtrace:fpu__init_system | 0 | 0 | 22 | 13 |
| backtrace:__asan_loadN | 0 | 0 | 22 | 13 |
| backtrace:early_cpu_init | 0 | 0 | 22 | 13 |
| backtrace:early_ioremap_setup | 0 | 0 | 22 | 13 |
| backtrace:early_ioremap_init | 0 | 0 | 22 | 13 |
+--------------------------------------------------------------+------------+------------+------------+-----------------+
Decompressing Linux... Parsing ELF... Performing relocations... done.
Booting the kernel.
[ 0.000000] ==================================================================
[ 0.000000] BUG: KASAN: stack-out-of-bounds in cgroup_init_early+0x6c/0x269 at addr ffffffff854fff40
[ 0.000000] Read of size 8 by task swapper/0
[ 0.000000] Address belongs to variable 0xffffffff854fff40
[ 0.000000] CPU: 0 PID: 0 Comm: swapper Not tainted 4.6.0-rc6-wt-ath-08928-g737e43b #1
[ 0.000000] 0000000000000000 ffffffff85407e18 ffffffff8472214c ffffffff854fff40
[ 0.000000] fffffbfff0a9ffe8 ffffffff85407e90 ffffffff84239932 ffffffff85a11e9d
[ 0.000000] 0000000000000046 1ffffffff0a9ffe8 ffffffff8423910f 0000000000000028
[ 0.000000] Call Trace:
[ 0.000000] [<ffffffff8472214c>] dump_stack+0x85/0xbe
[ 0.000000] [<ffffffff84239932>] kasan_report+0x3b8/0x4e9
[ 0.000000] [<ffffffff85a11e9d>] ? cgroup_init_early+0x6c/0x269
[ 0.000000] [<ffffffff8423910f>] ? memset+0x29/0x2f
[ 0.000000] [<ffffffff84196901>] ? ftrace_likely_update+0x22f/0x251
[ 0.000000] [<ffffffff84238b6f>] __asan_load8+0x23/0x65
[ 0.000000] [<ffffffff85a11e9d>] cgroup_init_early+0x6c/0x269
[ 0.000000] [<ffffffff859ecdb1>] start_kernel+0x6e/0x57c
[ 0.000000] [<ffffffff859ecd43>] ? thread_info_cache_init+0x6/0x6
[ 0.000000] [<ffffffff85a65a3f>] ? memblock_reserve+0x4a/0x4f
[ 0.000000] [<ffffffff859ec120>] ? early_idt_handler_array+0x120/0x120
[ 0.000000] [<ffffffff859ec318>] x86_64_start_reservations+0x2f/0x31
[ 0.000000] [<ffffffff859ec452>] x86_64_start_kernel+0x138/0x145
[ 0.000000] Memory state around the buggy address:
[ 0.000000] ffffffff854ffe00: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
[ 0.000000] ffffffff854ffe80: 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00
git bisect start 3727e698af8bb2608c71345b6485bb59dc815205 04974df8049fc4240d22759a91e035082ccd18b4 --
git bisect good e27260fb3684c728ec65795327be922723284d20 # 21:47 22+ 22 Merge 'linux-review/Liu-Bo/Btrfs-fix-unexpected-balance-crash-due-to-BUG_ON/20160503-070310' into devel-hourly-2016050817
git bisect good 1acb78206f1c747efbb978b78c44d3d25e2c52e5 # 21:51 22+ 22 Merge 'mvebu/mvebu/dt64' into devel-hourly-2016050817
git bisect good fca57810dedd3ba48181ccda3479339ccfcbefe2 # 22:02 22+ 22 Merge 'linux-review/John-Youn/usb-dwc2-gadget-Check-for-ep0-in-enable/20160428-112250' into devel-hourly-2016050817
git bisect bad 08429dff945e6b60d5d91f493f50ce8bac80ad15 # 22:06 0- 22 Merge 'linux-review/robert-foss-collabora-com/drm-docs-Move-scaling-mode-property/20160503-031617' into devel-hourly-2016050817
git bisect bad da2176b543fa519e30ebbe1c0b281cd519452066 # 22:10 0- 22 Merge 'linux-review/Nicolas-Pitre/usb-host-const-data-must-use-__initconst-not-__initdata/20160428-013026' into devel-hourly-2016050817
git bisect bad f9b36d3290cb8c83f3e91cfe49cef95446a9685c # 22:17 0- 22 Merge 'linux-review/Kyeongmin-Cho/Documentation-Fix-typos-on-several-lines/20160428-034307' into devel-hourly-2016050817
git bisect bad 737e43bb50f4288c63424317c592db1eb15a2505 # 22:24 0- 22 Merge 'rusty/modules-next' into devel-hourly-2016050817
git bisect good 31e562cf9e943582f236874bedd2d6209856179a # 22:34 20+ 20 Merge 'linux-review/Kuninori-Morimoto/ASoC-rsnd-count-probe-remove-for-rsnd_mod_call/20160428-095114' into devel-hourly-2016050817
git bisect good 3abbfe0f4978a3f6b110279027199de58541638a # 22:44 22+ 22 Merge 'linux-review/David-Rivshin-Allworx/drivers-net-cpsw-phy-handle-fixes/20160428-094818' into devel-hourly-2016050817
git bisect good c3a4b494213771eff39a18350e6a17056a64e6e9 # 22:56 22+ 22 Merge 'bpf/refcnt_fix' into devel-hourly-2016050817
git bisect good 13771c666003d75f31f03023010878272053f893 # 23:01 20+ 0 module: Issue warnings when tainting kernel
git bisect good 468c2e75a6447685f7b03b4312485060d0bdbf81 # 23:11 22+ 0 Documentation/module-signing.txt: Note need for version info if reusing a key
git bisect good e2d1248432c4bc8b3e4cdfc13074acbce3a749cd # 23:26 22+ 0 module: Disable MODULE_FORCE_LOAD when MODULE_SIG_FORCE is enabled
# first bad commit: [737e43bb50f4288c63424317c592db1eb15a2505] Merge 'rusty/modules-next' into devel-hourly-2016050817
git bisect good c3a4b494213771eff39a18350e6a17056a64e6e9 # 23:35 66+ 88 Merge 'bpf/refcnt_fix' into devel-hourly-2016050817
git bisect good e2d1248432c4bc8b3e4cdfc13074acbce3a749cd # 23:39 63+ 0 module: Disable MODULE_FORCE_LOAD when MODULE_SIG_FORCE is enabled
# extra tests with DEBUG_INFO
git bisect bad 737e43bb50f4288c63424317c592db1eb15a2505 # 23:47 0- 66 Merge 'rusty/modules-next' into devel-hourly-2016050817
# extra tests on HEAD of linux-devel/devel-hourly-2016050817
git bisect bad 3727e698af8bb2608c71345b6485bb59dc815205 # 23:47 0- 13 0day head guard for 'devel-hourly-2016050817'
# extra tests on tree/branch linux-devel/devel-hourly-2016050817
git bisect bad 3727e698af8bb2608c71345b6485bb59dc815205 # 23:47 0- 13 0day head guard for 'devel-hourly-2016050817'
# extra tests on tree/branch linus/master
git bisect good 32cf95db22d49cf4a3b421ba9fd156bb5f920ebb # 23:57 66+ 0 Merge tag 'char-misc-4.6-rc7' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/char-misc
# extra tests on tree/branch linux-next/master
git bisect good 41b4d7e361f4a0a24cc5ad89a9555c311cfa774c # 00:10 66+ 0 Add linux-next specific files for 20160506
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
kvm=(
qemu-system-x86_64
-enable-kvm
-cpu kvm64
-kernel $kernel
-m 300
-smp 2
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
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[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 8 months
a698ec76e4: BUG: recent printk recursion!
by kernel test robot
Greetings,
0day kernel testing robot got the below dmesg and the first bad commit is
git://internal_merge_and_test_tree devel-hourly-2016050813
commit a698ec76e45a1082c3a63ba34ec5b6000e422881
Merge: 128fbd0 c13cbab
Author: 0day robot <fengguang.wu(a)intel.com>
AuthorDate: Sun May 8 14:01:48 2016 +0800
Commit: 0day robot <fengguang.wu(a)intel.com>
CommitDate: Sun May 8 14:01:48 2016 +0800
Merge 'jolsa-perf/perf/c2c_58' into devel-hourly-2016050813
+---------------------------------------------------------------+------------+------------+------------+-----------------+
| | 128fbd0c6a | c13cbab3aa | a698ec76e4 | v4.6-rc6_050813 |
+---------------------------------------------------------------+------------+------------+------------+-----------------+
| boot_successes | 0 | 87 | 0 | 0 |
| boot_failures | 87 | 1 | 22 | 13 |
| BUG:kernel_boot_hang | 87 | | | |
| Out_of_memory:Kill_process | 0 | 1 | | |
| BUG:recent_printk_recursion | 0 | 0 | 22 | 13 |
| BUG:KASAN:stack-out-of-bounds_in_pat_init_cache_modes_at_addr | 0 | 0 | 20 | 12 |
| BUG:KASAN:stack-out-of-bounds_in_memcmp_at_addr | 0 | 0 | 22 | 13 |
| PANIC:early_exception | 0 | 0 | 22 | 13 |
| backtrace:pat_init_cache_modes | 0 | 0 | 20 | 12 |
| backtrace:__asan_report_load1_noabort | 0 | 0 | 20 | |
| backtrace:pat_init | 0 | 0 | 20 | 12 |
| backtrace:get_mtrr_state | 0 | 0 | 20 | 12 |
| backtrace:mtrr_bp_init | 0 | 0 | 20 | 12 |
| backtrace:__asan_report_load8_noabort | 0 | 0 | 20 | |
| backtrace:__asan_report_load_n_noabort | 0 | 0 | 20 | 12 |
+---------------------------------------------------------------+------------+------------+------------+-----------------+
Booting the kernel.
[ 0.000000] Linux version 4.6.0-rc6-wt-ath-05833-ga698ec7 (kbuild@roam) (gcc version 5.3.1 20160409 (Debian 5.3.1-14) ) #1 PREEMPT Sun May 8 17:54:02 CST 2016
[ 0.000000] Command line: hung_task_panic=1 earlyprintk=ttyS0,115200 systemd.log_level=err 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-s4-05081559/linux-devel:devel-hourly-2016050813:a698ec76e45a1082c3a63ba34ec5b6000e422881:bisect-linux-1/.vmlinuz-a698ec76e45a1082c3a63ba34ec5b6000e422881-20160508175604-11-client-x5355-4 branch=linux-devel/devel-hourly-2016050813 BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s4-05081559/gcc-5/a698ec76e45a1082c3a63ba34ec5b6000e422881/vmlinuz-4.6.0-rc6-wt-ath-05833-ga698ec7 drbd.minor_count=8
[ 0.000000] BUG: recent printk recursion!
[ 0.000000] x86/fpu: Legacy x87 FPU detected.
[ 0.000000] x86/fpu: Using 'eager' FPU context switches.
[ 0.000000] e820: BIOS-provided physical RAM map:
git bisect start 4a54e015f1c1a2d83cb7c85d6728ddae0ab0de98 04974df8049fc4240d22759a91e035082ccd18b4 --
git bisect good 5d86f4ab17cba597119cc5a388aba8079e20f755 # 16:32 17+ 17 Merge 'linux-review/Amritha-Nambiar/net-ixgbe-Fix-deleting-link-filters-for-cls_u32-offloads/20160507-102548' into devel-hourly-2016050813
git bisect bad db29e0d9c08905ed7390379d9b0af2695d3503d1 # 16:40 0- 17 Merge 'mvebu/mvebu/dt64' into devel-hourly-2016050813
git bisect bad 288ff9f3328e3ffd1f631fd0c3866d4220c5331c # 16:44 0- 22 Merge 'linux-review/Richard-W-M-Jones/crypto-Add-a-flag-allowing-the-self-tests-to-be-disabled-at-runtime/20160503-170433' into devel-hourly-2016050813
git bisect bad d9205a50c5ef74ab784c1ef15099c6adf6d11a7c # 16:49 0- 22 Merge 'linux-review/Javier-Martinez-Canillas/s5p-mfc-Set-device-name-for-reserved-memory-region-devs/20160504-043420' into devel-hourly-2016050813
git bisect good 5043a528b1e78a91aff4a6a2f014897288e2c521 # 17:01 22+ 22 Merge 'linux-review/Chuck-Lever/NFS-RDMA-server-patches-proposed-for-v4-7/20160504-225625' into devel-hourly-2016050813
git bisect bad c1b2545c30f2856f30d931725399e964dd2d4c24 # 17:05 0- 22 Merge 'linux-review/Jose-Abreu/clk-axs10x-Add-I2S-PLL-clock-driver/20160502-174613' into devel-hourly-2016050813
git bisect good 6079ad4048ab04ba76b02edbe7d6c3872eb76229 # 17:19 22+ 22 Merge 'linux-review/Sudip-Mukherjee/rtc-stmp3xxx-print-message-on-error/20160501-031319' into devel-hourly-2016050813
git bisect good 1ca4795332465b01cdbdc1548b15a8059976b38f # 17:35 22+ 22 Merge 'linux-review/Qiang-Huang/Documentation-memcg-remove-restriction-of-setting-kmem-limit/20160505-162933' into devel-hourly-2016050813
git bisect good 128fbd0c6a7bb65847acdc06076d9364f15fbae8 # 17:47 22+ 22 Merge 'linux-review/Phil-Turnbull/netfilter-nfnetlink_acct-validate-NFACCT_QUOTA-parameter/20160504-044335' into devel-hourly-2016050813
git bisect bad a698ec76e45a1082c3a63ba34ec5b6000e422881 # 17:57 0- 22 Merge 'jolsa-perf/perf/c2c_58' into devel-hourly-2016050813
git bisect good 0083a0a35fdc3ec08a9dd5ecb7507f7f34c14c60 # 18:05 22+ 0 perf tools: Introduce struct hist_browser::title callback
git bisect good fc6a1a5cf03cad64deffc81db83b2b822dc2495a # 18:12 22+ 0 perf c2c report: Add stdio output support
git bisect good 41f636a5e364ba41405d392e76d73fc6dc444860 # 18:22 21+ 0 perf c2c report: Add stores related dimension keys
git bisect good 9f91b40c32ad658181c77d03deb5b9dd2deb299c # 18:32 20+ 0 perf c2c report: Add hitm percent sort key
git bisect good 1ec44b9458e569493442cff235977585ff56c353 # 18:48 20+ 1 perf c2c report: Add hitm/store percent related sort keys
git bisect good c13cbab3aae182c024181ead5dc0dc924797eacd # 18:57 22+ 1 fix
# first bad commit: [a698ec76e45a1082c3a63ba34ec5b6000e422881] Merge 'jolsa-perf/perf/c2c_58' into devel-hourly-2016050813
git bisect good 128fbd0c6a7bb65847acdc06076d9364f15fbae8 # 19:06 65+ 87 Merge 'linux-review/Phil-Turnbull/netfilter-nfnetlink_acct-validate-NFACCT_QUOTA-parameter/20160504-044335' into devel-hourly-2016050813
git bisect good c13cbab3aae182c024181ead5dc0dc924797eacd # 19:12 66+ 1 fix
# extra tests with DEBUG_INFO
git bisect bad a698ec76e45a1082c3a63ba34ec5b6000e422881 # 19:19 0- 66 Merge 'jolsa-perf/perf/c2c_58' into devel-hourly-2016050813
# extra tests on HEAD of linux-devel/devel-hourly-2016050813
git bisect bad 4a54e015f1c1a2d83cb7c85d6728ddae0ab0de98 # 19:19 0- 13 0day head guard for 'devel-hourly-2016050813'
# extra tests on tree/branch linux-devel/devel-hourly-2016050813
git bisect bad 4a54e015f1c1a2d83cb7c85d6728ddae0ab0de98 # 19:19 0- 13 0day head guard for 'devel-hourly-2016050813'
# extra tests on tree/branch linus/master
git bisect good 32cf95db22d49cf4a3b421ba9fd156bb5f920ebb # 19:25 66+ 0 Merge tag 'char-misc-4.6-rc7' of git://git.kernel.org/pub/scm/linux/kernel/git/gregkh/char-misc
# extra tests on tree/branch linux-next/master
git bisect good 41b4d7e361f4a0a24cc5ad89a9555c311cfa774c # 19:35 66+ 9 Add linux-next specific files for 20160506
This script may reproduce the error.
----------------------------------------------------------------------------
#!/bin/bash
kernel=$1
kvm=(
qemu-system-x86_64
-enable-kvm
-kernel $kernel
-m 256
-smp 1
-device e1000,netdev=net0
-netdev user,id=net0
-boot order=nc
-no-reboot
-watchdog i6300esb
-rtc base=localtime
-serial stdio
-display none
-monitor null
)
append=(
hung_task_panic=1
earlyprintk=ttyS0,115200
systemd.log_level=err
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[*]}"
----------------------------------------------------------------------------
---
0-DAY kernel test infrastructure Open Source Technology Center
https://lists.01.org/pipermail/lkp Intel Corporation
4 years, 8 months