syzbot


INFO: task hung in debug_smp_processor_id

Status: auto-closed as invalid on 2020/08/19 11:14
Subsystems: usb
[Documentation on labels]
First crash: 1410d, last: 1407d

Sample crash report:
INFO: task syz-executor.2:812 blocked for more than 143 seconds.
      Not tainted 5.7.0-rc6-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor.2  D28392   812   7310 0x00004004
Call Trace:
 context_switch kernel/sched/core.c:3367 [inline]
 __schedule+0x937/0x1ff0 kernel/sched/core.c:4083
 __sched_text_start+0x8/0x8
 __raw_spin_lock_irqsave include/linux/spinlock_api_smp.h:117 [inline]
 _raw_spin_lock_irqsave+0x94/0xbf kernel/locking/spinlock.c:159
 check_preemption_disabled lib/smp_processor_id.c:52 [inline]
 debug_smp_processor_id+0x2f/0x185 lib/smp_processor_id.c:57
 schedule+0xd0/0x2a0 kernel/sched/core.c:4158
 schedule_timeout+0x35c/0x850 kernel/time/timer.c:1898
 find_held_lock+0x2d/0x110 kernel/locking/lockdep.c:4458
 usleep_range+0x160/0x160 kernel/time/timer.c:2093
 do_wait_for_common kernel/sched/completion.c:84 [inline]
 __wait_for_common kernel/sched/completion.c:106 [inline]
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion_timeout+0x15a/0x280 kernel/sched/completion.c:157
 __next_timer_interrupt+0x190/0x190 kernel/time/timer.c:1512
 rwlock_bug.part.0+0x90/0x90 include/linux/sched.h:1329
 entry_SYSCALL_64_after_hwframe+0x49/0xb3
 __raw_spin_unlock_irq include/linux/spinlock_api_smp.h:168 [inline]
 _raw_spin_unlock_irq+0x1f/0x80 kernel/locking/spinlock.c:199
 do_wait_for_common kernel/sched/completion.c:85 [inline]
 __wait_for_common kernel/sched/completion.c:106 [inline]
 wait_for_common kernel/sched/completion.c:117 [inline]
 wait_for_completion_timeout+0x162/0x280 kernel/sched/completion.c:157
 wait_for_completion_io_timeout+0x280/0x280 kernel/sched/completion.c:89
 usb_submit_urb+0x6ed/0x13b0 drivers/usb/core/urb.c:570
 lockdep_init_map_waits+0x1d3/0x890 kernel/locking/lockdep.c:4119
 usb_start_wait_urb+0x144/0x2b0 drivers/usb/core/message.c:63
 sg_clean+0x230/0x230 drivers/usb/core/message.c:262
 memset+0x20/0x40 mm/kasan/common.c:85
 usb_bulk_msg+0x228/0x550 drivers/usb/core/message.c:254
 proc_bulk+0x41a/0x780 drivers/usb/core/devio.c:1235
 processcompl_compat+0x670/0x670 drivers/usb/core/devio.c:2120
 kfree+0x1eb/0x2b0 mm/slab.c:3758
 tomoyo_path_number_perm+0x411/0x4d0 security/tomoyo/file.c:736
 __trace_hardirqs_on_caller kernel/locking/lockdep.c:3657 [inline]
 lockdep_hardirqs_on+0x463/0x620 kernel/locking/lockdep.c:3702
 tomoyo_path_number_perm+0x238/0x4d0 security/tomoyo/file.c:721
 usbdev_do_ioctl drivers/usb/core/devio.c:2539 [inline]
 usbdev_ioctl+0x543/0x31e0 drivers/usb/core/devio.c:2705
 proc_do_submiturb+0x3530/0x3530 include/linux/list.h:70
 rcu_read_unlock include/linux/rcupdate.h:651 [inline]
 __fget_files+0x30d/0x500 fs/file.c:734
 __sanitizer_cov_trace_switch+0x45/0x70 kernel/kcov.c:310
 ioctl_fioasync fs/ioctl.c:601 [inline]
 do_vfs_ioctl+0x50c/0x1360 fs/ioctl.c:704
 ioctl_file_clone+0x180/0x180 fs/ioctl.c:253
 rcu_lock_release include/linux/rcupdate.h:213 [inline]
 rcu_read_unlock include/linux/rcupdate.h:655 [inline]
 __fget_files+0x32f/0x500 fs/file.c:734
 ksys_dup3+0x3c0/0x3c0 include/linux/compiler.h:199
 __do_sys_futex kernel/futex.c:3869 [inline]
 __se_sys_futex kernel/futex.c:3837 [inline]
 __x64_sys_futex+0x380/0x4f0 kernel/futex.c:3837
 proc_do_submiturb+0x3530/0x3530 include/linux/list.h:70
 vfs_ioctl fs/ioctl.c:47 [inline]
 ksys_ioctl+0x11a/0x180 fs/ioctl.c:771
 __do_sys_ioctl fs/ioctl.c:780 [inline]
 __se_sys_ioctl fs/ioctl.c:778 [inline]
 __x64_sys_ioctl+0x6f/0xb0 fs/ioctl.c:778
 __trace_hardirqs_on_caller kernel/locking/lockdep.c:3657 [inline]
 lockdep_hardirqs_on+0x463/0x620 kernel/locking/lockdep.c:3702
 do_syscall_64+0xf6/0x7d0 arch/x86/entry/common.c:295
 entry_SYSCALL_64_after_hwframe+0x49/0xb3

Showing all locks held in the system:
1 lock held by khungtaskd/1143:
 #0: ffffffff899bea80 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:5754
2 locks held by in:imklog/6703:
 #0: ffff888097d71430 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100 fs/file.c:826
 #1: ffffffff899bea80 (rcu_read_lock){....}-{1:2}, at: is_bpf_text_address+0x0/0x160 kernel/bpf/core.c:691
2 locks held by syz-fuzzer/7040:
 #0: ffff8880ae637998 (&rq->lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1262 [inline]
 #0: ffff8880ae637998 (&rq->lock){-.-.}-{2:2}, at: __schedule+0x233/0x1ff0 kernel/sched/core.c:4029
 #1: ffff8880ae622ec8 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x304/0x410 kernel/sched/psi.c:817

=============================================

NMI backtrace for cpu 1
CPU: 1 PID: 1143 Comm: khungtaskd Not tainted 5.7.0-rc6-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
 __dump_stack lib/dump_stack.c:77 [inline]
 dump_stack+0x188/0x20d lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x70/0xb1 lib/nmi_backtrace.c:101
 lapic_can_unplug_cpu.cold+0x3b/0x3b
 nmi_trigger_cpumask_backtrace+0x231/0x27e lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:205 [inline]
 watchdog+0xa8c/0x1010 kernel/hung_task.c:289
 reset_hung_task_detector+0x30/0x30 kernel/hung_task.c:243
 kthread+0x388/0x470 kernel/kthread.c:268
 kthread_mod_delayed_work+0x1a0/0x1a0 kernel/kthread.c:1090
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:351
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 13718 Comm: kworker/u4:2 Not tainted 5.7.0-rc6-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Workqueue: bat_events batadv_iv_send_outstanding_bat_ogm_packet
RIP: 0010:batadv_iv_ogm_schedule_buff+0xc37/0x1240 net/batman-adv/bat_iv_ogm.c:843
Code: 04 00 00 4d 8b 24 24 49 81 fc 40 99 24 8d 0f 84 39 02 00 00 e8 ea dc c0 f9 49 8d 7c 24 70 48 89 f8 48 c1 e8 03 42 80 3c 30 00 <0f> 85 cc 04 00 00 48 8b 44 24 08 49 8b 5c 24 70 80 38 00 0f 85 c3
RSP: 0018:ffffc90016877bb0 EFLAGS: 00000246
RAX: 1ffff1101250ec0e RBX: 0000000000000000 RCX: ffffffff87b25a18
RDX: 0000000000000000 RSI: ffffffff87b25836 RDI: ffff888092876070
RBP: ffff8880a9391028 R08: ffff88808d438340 R09: ffffed1015272206
R10: ffff8880a939102b R11: ffffed1015272205 R12: ffff888092876000
R13: 0000000000000001 R14: dffffc0000000000 R15: ffffc90016877c38
FS:  0000000000000000(0000) GS:ffff8880ae600000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fddb4ba5000 CR3: 00000000a4d9d000 CR4: 00000000001426f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000600
Call Trace:
 batadv_iv_ogm_orig_dump+0xce0/0xce0 net/batman-adv/bat_iv_ogm.c:2108
 batadv_iv_ogm_schedule net/batman-adv/bat_iv_ogm.c:869 [inline]
 batadv_iv_ogm_schedule net/batman-adv/bat_iv_ogm.c:862 [inline]
 batadv_iv_send_outstanding_bat_ogm_packet+0x5c8/0x7c0 net/batman-adv/bat_iv_ogm.c:1722
 process_one_work+0x965/0x16a0 kernel/workqueue.c:2268
 lock_release+0x800/0x800 kernel/locking/lockdep.c:4689
 pwq_dec_nr_in_flight+0x310/0x310 kernel/workqueue.c:1198
 rwlock_bug.part.0+0x90/0x90 include/linux/sched.h:1329
 worker_thread+0x96/0xe20 kernel/workqueue.c:2414
 process_one_work+0x16a0/0x16a0 kernel/workqueue.c:2273
 kthread+0x388/0x470 kernel/kthread.c:268
 kthread_mod_delayed_work+0x1a0/0x1a0 kernel/kthread.c:1090
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:351

Crashes (2):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/05/21 11:05 upstream b85051e755b0 c61086ab .config console log report ci-upstream-kasan-gce
2020/05/17 23:22 upstream 5a9ffb954a39 37bccd4e .config console log report ci-upstream-kasan-gce
* Struck through repros no longer work on HEAD.