WARNING in dev_watchdog (2)

[syzbot]

Hello,

syzbot found the following crash on:

HEAD commit: 81b6b964 Merge branch 'master' of git://git.kernel.org/pub..
git tree: upstream
console output: https://syzkaller.appspot.com/x/log.txt?x=16ca42dae00000
kernel config: https://syzkaller.appspot.com/x/.config?x=773597fe8d7cb41a
dashboard link: https://syzkaller.appspot.com/bug?extid=5ced6397837125ce2a5a
compiler: gcc (GCC) 9.0.0 20181231 (experimental)
CC: [da...@davemloft.net j...@mojatatu.com ji...@resnulli.us
linux-...@vger.kernel.org net...@vger.kernel.org
xiyou.w...@gmail.com]

Unfortunately, I don't have any reproducer for this crash yet.

IMPORTANT: if you fix the bug, please add the following tag to the commit:
Reported-by: syzbot+5ced63...@syzkaller.appspotmail.com

------------[ cut here ]------------
NETDEV WATCHDOG: eth0 (e1000): transmit queue 0 timed out
WARNING: CPU: 2 PID: 0 at net/sched/sch_generic.c:442
dev_watchdog+0xaf5/0xca0 net/sched/sch_generic.c:442
Kernel panic - not syncing: panic_on_warn set ...
CPU: 2 PID: 0 Comm: swapper/2 Not tainted 5.4.0-syzkaller #0
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1
04/01/2014
Call Trace:
<IRQ>
__dump_stack lib/dump_stack.c:77 [inline]
dump_stack+0x197/0x210 lib/dump_stack.c:118
panic+0x2e3/0x75c kernel/panic.c:221
__warn.cold+0x2f/0x3e kernel/panic.c:582
report_bug+0x289/0x300 lib/bug.c:195
fixup_bug arch/x86/kernel/traps.c:174 [inline]
fixup_bug arch/x86/kernel/traps.c:169 [inline]
do_error_trap+0x11b/0x200 arch/x86/kernel/traps.c:267
do_invalid_op+0x37/0x50 arch/x86/kernel/traps.c:286
invalid_op+0x23/0x30 arch/x86/entry/entry_64.S:1027
RIP: 0010:dev_watchdog+0xaf5/0xca0 net/sched/sch_generic.c:442
Code: ff e8 5f 9e 8d fb 4c 89 e7 c6 05 c8 4b ff 03 01 e8 00 32 e8 ff 44 89
e9 4c 89 e6 48 c7 c7 c0 13 6d 88 48 89 c2 e8 ba 52 5e fb <0f> 0b e9 06 fd
ff ff 48 8b 7d d0 e8 cb cb ca fb e9 7d f8 ff ff 48
RSP: 0018:ffff88802d709c98 EFLAGS: 00010286
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: 0000000000000100 RSI: ffffffff815e4316 RDI: ffffed1005ae1385
RBP: ffff88802d709d00 R08: ffff88802c868380 R09: fffffbfff15377b3
R10: fffffbfff15377b2 R11: ffffffff8a9bbd97 R12: ffff888028de4000
R13: 0000000000000000 R14: ffff888028de44f8 R15: fffffffffffffecc
call_timer_fn+0x1ac/0x780 kernel/time/timer.c:1404
expire_timers kernel/time/timer.c:1449 [inline]
__run_timers kernel/time/timer.c:1773 [inline]
__run_timers kernel/time/timer.c:1740 [inline]
run_timer_softirq+0x6c3/0x1790 kernel/time/timer.c:1786
__do_softirq+0x262/0x98c kernel/softirq.c:292
invoke_softirq kernel/softirq.c:373 [inline]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x1a3/0x610 arch/x86/kernel/apic/apic.c:1137
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
</IRQ>
RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61
Code: e8 54 35 fa eb 8a cc cc cc cc cc cc e9 07 00 00 00 0f 00 2d f4 bd 4b
00 f4 c3 66 90 e9 07 00 00 00 0f 00 2d e4 bd 4b 00 fb f4 <c3> cc 55 48 89
e5 41 57 41 56 41 55 41 54 53 e8 ae b8 e5 f9 e8 c9
RSP: 0018:ffff88802c877d68 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff122668e RBX: ffff88802c868380 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: 0000000000000006 RDI: ffff88802c868c14
RBP: ffff88802c877d98 R08: ffff88802c868380 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: dffffc0000000000
R13: ffffffff89ea72c0 R14: 0000000000000000 R15: 0000000000000002
arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:690
default_idle_call+0x84/0xb0 kernel/sched/idle.c:94
cpuidle_idle_call kernel/sched/idle.c:154 [inline]
do_idle+0x3c8/0x6e0 kernel/sched/idle.c:269
cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:361
start_secondary+0x2f4/0x410 arch/x86/kernel/smpboot.c:264
secondary_startup_64+0xa4/0xb0 arch/x86/kernel/head_64.S:242
------------[ cut here ]------------
WARNING: CPU: 2 PID: 0 at kernel/locking/mutex.c:1419
mutex_trylock+0x279/0x2f0 kernel/locking/mutex.c:1427
Modules linked in:
CPU: 2 PID: 0 Comm: swapper/2 Not tainted 5.4.0-syzkaller #0
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1
04/01/2014
RIP: 0010:mutex_trylock+0x279/0x2f0 kernel/locking/mutex.c:1419
Code: c9 41 b8 01 00 00 00 31 c9 ba 01 00 00 00 31 f6 e8 fc d2 de f9 58 48
8d 65 d8 b8 01 00 00 00 5b 41 5c 41 5d 41 5e 41 5f 5d c3 <0f> 0b e9 0c fe
ff ff 48 c7 c7 e0 94 d2 8a 48 89 4d d0 e8 00 17 36
RSP: 0018:ffff88802d709858 EFLAGS: 00010006
RAX: 0000000000000103 RBX: 1ffff11005ae1313 RCX: 0000000000000004
RDX: 0000000000000100 RSI: ffffffff816bc9c5 RDI: ffffffff891c25a0
RBP: ffff88802d709888 R08: 0000000000000001 R09: fffffbfff123381d
R10: fffffbfff123381c R11: ffffffff8919c0e3 R12: ffffffff8ad294e0
R13: 0000000000000000 R14: ffffffff85e73f00 R15: ffffffff891c25a0
FS: 0000000000000000(0000) GS:ffff88802d700000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 000000007a592000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
__crash_kexec+0x91/0x200 kernel/kexec_core.c:948
panic+0x308/0x75c kernel/panic.c:241
__warn.cold+0x2f/0x3e kernel/panic.c:582
report_bug+0x289/0x300 lib/bug.c:195
fixup_bug arch/x86/kernel/traps.c:174 [inline]
fixup_bug arch/x86/kernel/traps.c:169 [inline]
do_error_trap+0x11b/0x200 arch/x86/kernel/traps.c:267
do_invalid_op+0x37/0x50 arch/x86/kernel/traps.c:286
invalid_op+0x23/0x30 arch/x86/entry/entry_64.S:1027
RIP: 0010:dev_watchdog+0xaf5/0xca0 net/sched/sch_generic.c:442
Code: ff e8 5f 9e 8d fb 4c 89 e7 c6 05 c8 4b ff 03 01 e8 00 32 e8 ff 44 89
e9 4c 89 e6 48 c7 c7 c0 13 6d 88 48 89 c2 e8 ba 52 5e fb <0f> 0b e9 06 fd
ff ff 48 8b 7d d0 e8 cb cb ca fb e9 7d f8 ff ff 48
RSP: 0018:ffff88802d709c98 EFLAGS: 00010286
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: 0000000000000100 RSI: ffffffff815e4316 RDI: ffffed1005ae1385
RBP: ffff88802d709d00 R08: ffff88802c868380 R09: fffffbfff15377b3
R10: fffffbfff15377b2 R11: ffffffff8a9bbd97 R12: ffff888028de4000
R13: 0000000000000000 R14: ffff888028de44f8 R15: fffffffffffffecc
call_timer_fn+0x1ac/0x780 kernel/time/timer.c:1404
expire_timers kernel/time/timer.c:1449 [inline]
__run_timers kernel/time/timer.c:1773 [inline]
__run_timers kernel/time/timer.c:1740 [inline]
run_timer_softirq+0x6c3/0x1790 kernel/time/timer.c:1786
__do_softirq+0x262/0x98c kernel/softirq.c:292
invoke_softirq kernel/softirq.c:373 [inline]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x1a3/0x610 arch/x86/kernel/apic/apic.c:1137
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
</IRQ>
RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61
Code: e8 54 35 fa eb 8a cc cc cc cc cc cc e9 07 00 00 00 0f 00 2d f4 bd 4b
00 f4 c3 66 90 e9 07 00 00 00 0f 00 2d e4 bd 4b 00 fb f4 <c3> cc 55 48 89
e5 41 57 41 56 41 55 41 54 53 e8 ae b8 e5 f9 e8 c9
RSP: 0018:ffff88802c877d68 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff122668e RBX: ffff88802c868380 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: 0000000000000006 RDI: ffff88802c868c14
RBP: ffff88802c877d98 R08: ffff88802c868380 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: dffffc0000000000
R13: ffffffff89ea72c0 R14: 0000000000000000 R15: 0000000000000002
arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:690
default_idle_call+0x84/0xb0 kernel/sched/idle.c:94
cpuidle_idle_call kernel/sched/idle.c:154 [inline]
do_idle+0x3c8/0x6e0 kernel/sched/idle.c:269
cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:361
start_secondary+0x2f4/0x410 arch/x86/kernel/smpboot.c:264
secondary_startup_64+0xa4/0xb0 arch/x86/kernel/head_64.S:242
irq event stamp: 475427
hardirqs last enabled at (475426): [<ffffffff815ddc23>]
console_unlock+0x7b3/0xf00 kernel/printk/printk.c:2502
hardirqs last disabled at (475427): [<ffffffff8100675f>]
trace_hardirqs_off_thunk+0x1a/0x1c arch/x86/entry/thunk_64.S:42
softirqs last enabled at (475166): [<ffffffff8147202c>]
_local_bh_enable+0x1c/0x30 kernel/softirq.c:162
softirqs last disabled at (475167): [<ffffffff81474a2b>] invoke_softirq
kernel/softirq.c:373 [inline]
softirqs last disabled at (475167): [<ffffffff81474a2b>]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
---[ end trace 006876d121933684 ]---
------------[ cut here ]------------
WARNING: CPU: 2 PID: 0 at kernel/locking/mutex.c:737 mutex_unlock+0x1d/0x30
kernel/locking/mutex.c:744
Modules linked in:
CPU: 2 PID: 0 Comm: swapper/2 Tainted: G W 5.4.0-syzkaller #0
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1
04/01/2014
RIP: 0010:mutex_unlock+0x1d/0x30 kernel/locking/mutex.c:737
Code: 4c 89 ff e8 55 22 36 fa e9 8c fb ff ff 55 65 8b 05 10 35 86 78 a9 00
ff 1f 00 48 89 e5 75 0b 48 8b 75 08 e8 45 f9 ff ff 5d c3 <0f> 0b 48 8b 75
08 e8 38 f9 ff ff 5d c3 66 0f 1f 44 00 00 48 b8 00
RSP: 0018:ffff88802d709888 EFLAGS: 00010006
RAX: 0000000000000103 RBX: 1ffff11005ae1313 RCX: ffffffff816bc9dd
RDX: 0000000000000100 RSI: ffffffff816bca3f RDI: ffffffff891c25a0
RBP: ffff88802d709888 R08: ffff88802c868380 R09: fffffbfff15377bd
R10: ffff88802c868c68 R11: ffff88802c868380 R12: 0000000000000001
R13: 0000000000000000 R14: ffffffff85e73f00 R15: 00000000000001ba
FS: 0000000000000000(0000) GS:ffff88802d700000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 000000007a592000 CR4: 00000000003406e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
<IRQ>
__crash_kexec+0x10b/0x200 kernel/kexec_core.c:957
panic+0x308/0x75c kernel/panic.c:241
__warn.cold+0x2f/0x3e kernel/panic.c:582
report_bug+0x289/0x300 lib/bug.c:195
fixup_bug arch/x86/kernel/traps.c:174 [inline]
fixup_bug arch/x86/kernel/traps.c:169 [inline]
do_error_trap+0x11b/0x200 arch/x86/kernel/traps.c:267
do_invalid_op+0x37/0x50 arch/x86/kernel/traps.c:286
invalid_op+0x23/0x30 arch/x86/entry/entry_64.S:1027
RIP: 0010:dev_watchdog+0xaf5/0xca0 net/sched/sch_generic.c:442
Code: ff e8 5f 9e 8d fb 4c 89 e7 c6 05 c8 4b ff 03 01 e8 00 32 e8 ff 44 89
e9 4c 89 e6 48 c7 c7 c0 13 6d 88 48 89 c2 e8 ba 52 5e fb <0f> 0b e9 06 fd
ff ff 48 8b 7d d0 e8 cb cb ca fb e9 7d f8 ff ff 48
RSP: 0018:ffff88802d709c98 EFLAGS: 00010286
RAX: 0000000000000000 RBX: 0000000000000000 RCX: 0000000000000000
RDX: 0000000000000100 RSI: ffffffff815e4316 RDI: ffffed1005ae1385
RBP: ffff88802d709d00 R08: ffff88802c868380 R09: fffffbfff15377b3
R10: fffffbfff15377b2 R11: ffffffff8a9bbd97 R12: ffff888028de4000
R13: 0000000000000000 R14: ffff888028de44f8 R15: fffffffffffffecc
call_timer_fn+0x1ac/0x780 kernel/time/timer.c:1404
expire_timers kernel/time/timer.c:1449 [inline]
__run_timers kernel/time/timer.c:1773 [inline]
__run_timers kernel/time/timer.c:1740 [inline]
run_timer_softirq+0x6c3/0x1790 kernel/time/timer.c:1786
__do_softirq+0x262/0x98c kernel/softirq.c:292
invoke_softirq kernel/softirq.c:373 [inline]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
exiting_irq arch/x86/include/asm/apic.h:536 [inline]
smp_apic_timer_interrupt+0x1a3/0x610 arch/x86/kernel/apic/apic.c:1137
apic_timer_interrupt+0xf/0x20 arch/x86/entry/entry_64.S:829
</IRQ>
RIP: 0010:native_safe_halt+0xe/0x10 arch/x86/include/asm/irqflags.h:61
Code: e8 54 35 fa eb 8a cc cc cc cc cc cc e9 07 00 00 00 0f 00 2d f4 bd 4b
00 f4 c3 66 90 e9 07 00 00 00 0f 00 2d e4 bd 4b 00 fb f4 <c3> cc 55 48 89
e5 41 57 41 56 41 55 41 54 53 e8 ae b8 e5 f9 e8 c9
RSP: 0018:ffff88802c877d68 EFLAGS: 00000286 ORIG_RAX: ffffffffffffff13
RAX: 1ffffffff122668e RBX: ffff88802c868380 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: 0000000000000006 RDI: ffff88802c868c14
RBP: ffff88802c877d98 R08: ffff88802c868380 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000000 R12: dffffc0000000000
R13: ffffffff89ea72c0 R14: 0000000000000000 R15: 0000000000000002
arch_cpu_idle+0xa/0x10 arch/x86/kernel/process.c:690
default_idle_call+0x84/0xb0 kernel/sched/idle.c:94
cpuidle_idle_call kernel/sched/idle.c:154 [inline]
do_idle+0x3c8/0x6e0 kernel/sched/idle.c:269
cpu_startup_entry+0x1b/0x20 kernel/sched/idle.c:361
start_secondary+0x2f4/0x410 arch/x86/kernel/smpboot.c:264
secondary_startup_64+0xa4/0xb0 arch/x86/kernel/head_64.S:242
irq event stamp: 475427
hardirqs last enabled at (475426): [<ffffffff815ddc23>]
console_unlock+0x7b3/0xf00 kernel/printk/printk.c:2502
hardirqs last disabled at (475427): [<ffffffff8100675f>]
trace_hardirqs_off_thunk+0x1a/0x1c arch/x86/entry/thunk_64.S:42
softirqs last enabled at (475166): [<ffffffff8147202c>]
_local_bh_enable+0x1c/0x30 kernel/softirq.c:162
softirqs last disabled at (475167): [<ffffffff81474a2b>] invoke_softirq
kernel/softirq.c:373 [inline]
softirqs last disabled at (475167): [<ffffffff81474a2b>]
irq_exit+0x19b/0x1e0 kernel/softirq.c:413
---[ end trace 006876d121933685 ]---
Kernel Offset: disabled
Rebooting in 86400 seconds..


---
This bug is generated by a bot. It may contain errors.
See https://goo.gl/tpsmEJ for more information about syzbot.
syzbot engineers can be reached at syzk...@googlegroups.com.

syzbot will keep track of this bug report. See:
https://goo.gl/tpsmEJ#status for how to communicate with syzbot.

[Dmitry Vyukov]

This looks like qemu/virtualization timing issue. Somehow the VM is too slow.

> --
> You received this message because you are subscribed to the Google Groups "syzkaller-upstream-moderation" group.
> To unsubscribe from this group and stop receiving emails from it, send an email to syzkaller-upstream-m...@googlegroups.com.
> To view this discussion on the web visit https://groups.google.com/d/msgid/syzkaller-upstream-moderation/0000000000008eb38f0598cb1609%40google.com.

[Dmitry Vyukov]

#syz upstream

On Tue, Dec 3, 2019 at 12:35 PM syzbot
<syzbot+5ced63...@syzkaller.appspotmail.com> wrote:
>