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: <20160419022555.GC31421@yexl-desktop>
Date:	Tue, 19 Apr 2016 10:25:56 +0800
From:	kernel test robot <xiaolong.ye@...el.com>
To:	Sebastian Andrzej Siewior <bigeasy@...utronix.de>
Cc:	0day robot <fengguang.wu@...el.com>,
	Peter Zijlstra <a.p.zijlstra@...llo.nl>,
	Frederic Weisbecker <fweisbec@...il.com>,
	Rik van Riel <riel@...hat.com>,
	LKML <linux-kernel@...r.kernel.org>, lkp@...org
Subject: [lkp] [sched/cputime] 060625d7e4: inconsistent {IN-HARDIRQ-W} ->
 {HARDIRQ-ON-W} usage.

FYI, we noticed the following changes on:
Test Machine: vm-vp-quantal-x86_64: 2 threads qemu-system-x86_64 -enable-kvm with 360M memory

https://github.com/0day-ci/linux Sebastian-Andrzej-Siewior/sched-cputime-drop-local_irq_safe-in-vtime_init_idle/20160414-203149
commit 060625d7e43e8c438380465f1958aff4403b029c ("sched/cputime: drop local_irq_safe() in vtime_init_idle()")

As below, the log "INFO: inconsistent lock state" showed with your commit.

[    0.731375] [ INFO: inconsistent lock state ]
[    0.733044] 4.6.0-rc1-00028-g060625d #1 Not tainted
[    0.734905] ---------------------------------
[    0.736578] inconsistent {IN-HARDIRQ-W} -> {HARDIRQ-ON-W} usage.
[    0.738623] swapper/0/1 [HC0[0]:SC0[0]:HE1:SE1] takes:
[    0.740473]  (&p->vtime_seqcount){?.....}, at: [<ffffffff810b9f83>] init_idle+0x1a3/0x1cf
[    0.743961] {IN-HARDIRQ-W} state was registered at:
[    0.745761]   [<ffffffff810d5cc2>] __lock_acquire+0xa22/0x1b1c
[    0.747847]   [<ffffffff810d771f>] lock_acquire+0x8a/0xb3
[    0.749883]   [<ffffffff810bcd6b>] vtime_gen_account_irq_exit+0x31/0x7c
[    0.752233]   [<ffffffff81098a6c>] irq_exit+0x10c/0x111
[    0.754194]   [<ffffffff81044fc6>] do_IRQ+0xe4/0xfc
[    0.756062]   [<ffffffff81677111>] ret_from_intr+0x0/0x20
[    0.758068]   [<ffffffff81cd4685>] apic_bsp_setup+0x8c/0x94
[    0.760114]   [<ffffffff81cd268e>] native_smp_prepare_cpus+0x2d1/0x341
[    0.762375]   [<ffffffff81cc4000>] kernel_init_freeable+0x58/0x1b6
[    0.764550]   [<ffffffff8166b388>] kernel_init+0x9/0xf0
[    0.766503]   [<ffffffff816769d2>] ret_from_fork+0x22/0x50
[    0.768571] irq event stamp: 2063
[    0.769995] hardirqs last  enabled at (2063): [<ffffffff81676121>] _raw_spin_unlock_irqrestore+0x42/0x58
[    0.773506] hardirqs last disabled at (2062): [<ffffffff81675faa>] _raw_spin_lock_irqsave+0x20/0x56
[    0.776932] softirqs last  enabled at (1368): [<ffffffff81098703>] __do_softirq+0x1e0/0x24b
[    0.780181] softirqs last disabled at (1363): [<ffffffff810989c5>] irq_exit+0x65/0x111
[    0.783333] 
[    0.783333] other info that might help us debug this:
[    0.786101]  Possible unsafe locking scenario:
[    0.786101] 
[    0.788709]        CPU0
[    0.789939]        ----
[    0.791165]   lock(&p->vtime_seqcount);
[    0.792897]   <Interrupt>
[    0.794163]     lock(&p->vtime_seqcount);
[    0.795915] 
[    0.795915]  *** DEADLOCK ***
[    0.795915] 
[    0.799093] no locks held by swapper/0/1.
[    0.800680] 
[    0.800680] stack backtrace:
[    0.802986] CPU: 0 PID: 1 Comm: swapper/0 Not tainted 4.6.0-rc1-00028-g060625d #1
[    0.805953] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS Debian-1.8.2-1 04/01/2014
[    0.809240]  0000000000000000 ffff8800140f7c78 ffffffff8139d0c3 ffffffff824d8e90
[    0.812556]  ffff8800140f0000 ffff8800140f7cb0 ffffffff8112f137 0000000000000002
[    0.815861]  ffff8800140f07d8 ffff8800140f0000 ffffffff810d3e89 0000000000000002
[    0.819170] Call Trace:
[    0.820445]  [<ffffffff8139d0c3>] dump_stack+0x85/0xbe
[    0.822298]  [<ffffffff8112f137>] print_usage_bug+0x259/0x268
[    0.824454]  [<ffffffff810d3e89>] ? print_shortest_lock_dependencies+0x180/0x180
[    0.827399]  [<ffffffff810d4b69>] mark_lock+0x381/0x5a7
[    0.829275]  [<ffffffff810d5d29>] __lock_acquire+0xa89/0x1b1c
[    0.831273]  [<ffffffff810b9f83>] ? init_idle+0x1a3/0x1cf
[    0.833191]  [<ffffffff810b9de0>] ? init_idle_bootup_task+0xe/0xe
[    0.835254]  [<ffffffff810e5652>] ? rcu_sync_lockdep_assert+0x27/0x4d
[    0.837442]  [<ffffffff810d1c1d>] ? update_fast_ctr+0x19/0x36
[    0.839433]  [<ffffffff810d771f>] lock_acquire+0x8a/0xb3
[    0.841325]  [<ffffffff810d771f>] ? lock_acquire+0x8a/0xb3
[    0.843255]  [<ffffffff810b9f83>] ? init_idle+0x1a3/0x1cf
[    0.845171]  [<ffffffff810bd009>] vtime_init_idle+0x31/0x6a
[    0.847118]  [<ffffffff810b9f83>] ? init_idle+0x1a3/0x1cf
[    0.849026]  [<ffffffff810b9f83>] init_idle+0x1a3/0x1cf
[    0.850892]  [<ffffffff81094280>] fork_idle+0x9e/0xa8
[    0.852731]  [<ffffffff81cdaef5>] idle_threads_init+0x8d/0xc5
[    0.854755]  [<ffffffff81cdde16>] smp_init+0xf/0xd6
[    0.856556]  [<ffffffff81cc4028>] kernel_init_freeable+0x80/0x1b6
[    0.858618]  [<ffffffff8166b388>] kernel_init+0x9/0xf0
[    0.860474]  [<ffffffff816769d2>] ret_from_fork+0x22/0x50
[    0.862354]  [<ffffffff8166b37f>] ? rest_init+0x136/0x136
[    0.865101] x86: Booting SMP configuration:
[    0.866753] .... node  #0, CPUs:      #1
[    0.890155] kvm-clock: cpu 1, msr 0:1499d041, secondary cpu clock


FYI, raw QEMU command line is:

	qemu-system-x86_64 -enable-kvm -kernel /pkg/linux/x86_64-randconfig-s2-04150337/gcc-5/060625d7e43e8c438380465f1958aff4403b029c/vmlinuz-4.6.0-rc1-00028-g060625d -append 'root=/dev/ram0 user=lkp job=/lkp/scheduled/vm-vp-quantal-x86_64-24/bisect_boot-1-quantal-core-x86_64.cgz-x86_64-randconfig-s2-04150337-060625d7e43e8c438380465f1958aff4403b029c-20160415-120614-172jrut-0.yaml ARCH=x86_64 kconfig=x86_64-randconfig-s2-04150337 branch=linux-devel/devel-spot-201604150319 commit=060625d7e43e8c438380465f1958aff4403b029c BOOT_IMAGE=/pkg/linux/x86_64-randconfig-s2-04150337/gcc-5/060625d7e43e8c438380465f1958aff4403b029c/vmlinuz-4.6.0-rc1-00028-g060625d max_uptime=600 RESULT_ROOT=/result/boot/1/vm-vp-quantal-x86_64/quantal-core-x86_64.cgz/x86_64-randconfig-s2-04150337/gcc-5/060625d7e43e8c438380465f1958aff4403b029c/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-24::dhcp drbd.minor_count=8'  -initrd /fs/sdc1/initrd-vm-vp-quantal-x86_64-24 -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-24 -serial file:/dev/shm/kboot/serial-vm-vp-quantal-x86_64-24 -daemonize -display none -monitor null 





Thanks,
Xiaolong Ye

View attachment "config-4.6.0-rc1-00028-g060625d" of type "text/plain" (97795 bytes)

Download attachment "dmesg.xz" of type "application/octet-stream" (12140 bytes)

Powered by blists - more mailing lists

Powered by Openwall GNU/*/Linux Powered by OpenVZ