lists.openwall.net   lists  /  announce  owl-users  owl-dev  john-users  john-dev  passwdqc-users  yescrypt  popa3d-users  /  oss-security  kernel-hardening  musl  sabotage  tlsify  passwords  /  crypt-dev  xvendor  /  Bugtraq  Full-Disclosure  linux-kernel  linux-netdev  linux-ext4  linux-hardening  linux-cve-announce  PHC 
Open Source and information security mailing list archives
 
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [day] [month] [year] [list]
Message-ID: <20170928075531.GV17200@yexl-desktop>
Date:   Thu, 28 Sep 2017 15:55:32 +0800
From:   kernel test robot <xiaolong.ye@...el.com>
To:     "Paul E. McKenney" <paulmck@...ux.vnet.ibm.com>
Cc:     LKML <linux-kernel@...r.kernel.org>,
        "Paul E. McKenney" <paulmck@...ux.vnet.ibm.com>, lkp@...org
Subject: [lkp-robot] [EXP]  a74063662f:
 INFO:task_blocked_for_more_than#seconds


FYI, we noticed the following commit:

commit: a74063662f3a456a402bd40fa23e104196c78993 ("EXP: Fine-grained timer diagnostics")
https://git.kernel.org/cgit/linux/kernel/git/paulmck/linux-rcu.git dev.2017.09.21b

in testcase: trinity
with following parameters:

	runtime: 300s

test-description: Trinity is a linux system call fuzz tester.
test-url: http://codemonkey.org.uk/projects/trinity/


on test machine: qemu-system-x86_64 -enable-kvm -cpu IvyBridge -m 420M

caused below changes (please refer to attached dmesg/kmsg for entire log/backtrace):


+--------------------------------------------------+------------+------------+
|                                                  | ad6a4baa92 | a74063662f |
+--------------------------------------------------+------------+------------+
| boot_successes                                   | 24         | 1          |
| boot_failures                                    | 0          | 21         |
| BUG:kernel_hang_in_boot_stage                    | 0          | 12         |
| INFO:task_blocked_for_more_than#seconds          | 0          | 9          |
| Kernel_panic-not_syncing:hung_task:blocked_tasks | 0          | 9          |
+--------------------------------------------------+------------+------------+



[  245.387603] INFO: task swapper:1 blocked for more than 120 seconds.
[  245.391458]       Not tainted 4.14.0-rc1-00036-ga740636 #1
[  245.394939] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  245.399647] swapper         D    0     1      0 0x80000000
[  245.402789] Call Trace:
[  245.404509]  ? __schedule+0x221/0x5c0
[  245.406281]  ? schedule+0x1b/0x30
[  245.408629]  ? schedule_timeout+0x1a5/0x380
[  245.411794]  ? trace_hardirqs_on_caller+0x10c/0x190
[  245.414410]  ? wait_for_completion+0xf3/0x140
[  245.416345]  ? do_task_dead+0x40/0x40
[  245.419067]  ? kthread_stop+0x2f/0x60
[  245.421645]  ? test_rht_init+0x834/0x883
[  245.424521]  ? config_sync_direct_show+0x40/0x40
[  245.426762]  ? config_sync_direct_show+0x40/0x40
[  245.429468]  ? list_sort_test+0x1c6/0x1c6
[  245.432146]  ? do_one_initcall+0x79/0x119
[  245.434786]  ? parse_args+0xc0/0x300
[  245.436898]  ? kernel_init_freeable+0xc9/0x15b
[  245.439583]  ? kernel_init_freeable+0xe6/0x15b
[  245.442276]  ? rest_init+0x90/0x90
[  245.444435]  ? kernel_init+0x5/0xf0
[  245.446290]  ? ret_from_fork+0x19/0x30
[  245.448983] 
[  245.448983] Showing all locks held in the system:
[  245.453032] 2 locks held by khungtaskd/9:
[  245.455878]  #0:  (rcu_read_lock){....}, at: [<b10ac7eb>] watchdog+0x9b/0x390
[  245.460547]  #1:  (tasklist_lock){.+.+}, at: [<b1075d00>] debug_show_all_locks+0x30/0x1a0
[  245.466089] 
[  245.468114] =============================================
[  245.468114] 
[  245.472781] Kernel panic - not syncing: hung_task: blocked tasks
[  245.475726] CPU: 0 PID: 9 Comm: khungtaskd Not tainted 4.14.0-rc1-00036-ga740636 #1
[  245.475728] Call Trace:
[  245.475728]  ? panic+0x8a/0x1ac
[  245.475728]  ? watchdog+0x390/0x390
[  245.475728]  ? kthread+0x10b/0x140
[  245.475728]  ? hung_task_panic+0x10/0x10
[  245.475728]  ? __kthread_create_on_node+0x160/0x160
[  245.475728]  ? ret_from_fork+0x19/0x30
[  245.475728] Kernel Offset: disabled

Elapsed time: 250

initrds=(
	/osimage/yocto/yocto-tiny-i386-2016-04-22.cgz
	/lkp/scheduled/vm-ivb41-yocto-ia32-21/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-a74063662f3a456a402bd40fa23e104196c78993-20170926-10223-190qn8m-0.cgz
	/lkp/lkp/lkp-i386.cgz
	/osimage/deps/debian-x86_64-2016-08-31.cgz/run-ipconfig.i386_2016-09-03.cgz
	/osimage/pkg/static/trinity-i386.cgz
)

cat "${initrds[@]}" > /fs/sda5/initrd-vm-ivb41-yocto-ia32-21

kvm=(
	qemu-system-x86_64
	-enable-kvm
	-cpu IvyBridge
	-kernel /pkg/linux/i386-randconfig-b0-09251522/gcc-5/a74063662f3a456a402bd40fa23e104196c78993/vmlinuz-4.14.0-rc1-00036-ga740636
	-initrd /fs/sda5/initrd-vm-ivb41-yocto-ia32-21
	-m 420
	-smp 1
	-device e1000,netdev=net0
	-netdev user,id=net0
	-boot order=nc
	-no-reboot
	-watchdog i6300esb
	-watchdog-action debug
	-rtc base=localtime
	-drive file=/fs/sda5/disk0-vm-ivb41-yocto-ia32-21,media=disk,if=virtio
	-pidfile /dev/shm/kboot/pid-vm-ivb41-yocto-ia32-21
	-serial file:/dev/shm/kboot/vm-ivb41-yocto-ia32-21/serial
	-serial file:/dev/shm/kboot/vm-ivb41-yocto-ia32-21/kmsg
	-daemonize
	-display none
	-monitor null
)

append=(
	ip=::::vm-ivb41-yocto-ia32-21::dhcp
	root=/dev/ram0
	user=lkp
	job=/lkp/scheduled/vm-ivb41-yocto-ia32-21/trinity-300s-yocto-tiny-i386-2016-04-22.cgz-a74063662f3a456a402bd40fa23e104196c78993-20170926-10223-190qn8m-0.yaml
	ARCH=i386
	kconfig=i386-randconfig-b0-09251522
	branch=linux-devel/devel-hourly-2017092501
	commit=a74063662f3a456a402bd40fa23e104196c78993
	BOOT_IMAGE=/pkg/linux/i386-randconfig-b0-09251522/gcc-5/a74063662f3a456a402bd40fa23e104196c78993/vmlinuz-4.14.0-rc1-00036-ga740636
	max_uptime=1500
	RESULT_ROOT=/result/trinity/300s/vm-ivb41-yocto-ia32/yocto-tiny-i386-2016-04-22.cgz/i386-randconfig-b0-09251522/gcc-5/a74063662f3a456a402bd40fa23e104196c78993/0
	LKP_SERVER=inn
	debug
	apic=debug
	sysrq_always_enabled
	rcupdate.rcu_cpu_stall_timeout=100
	net.ifnames=0
	printk.devkmsg=on
	panic=-1
	softlockup_panic=1
	nmi_watchdog=panic
	oops=panic


To reproduce:

        git clone https://github.com/intel/lkp-tests.git
        cd lkp-tests
        bin/lkp qemu -k <bzImage> job-script  # job-script is attached in this email



Thanks,
Xiaolong

View attachment "config-4.14.0-rc1-00036-ga740636" of type "text/plain" (77553 bytes)

View attachment "job-script" of type "text/plain" (3759 bytes)

Download attachment "dmesg.xz" of type "application/x-xz" (7120 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ