[<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