syzbot


INFO: task hung in rdma_destroy_id

Status: fixed on 2021/01/14 15:11
Reported-by: syzbot+fa44367615d6e8d12311@syzkaller.appspotmail.com
Fix commit: 014133611851 usb: uas: Add PNY USB Portable SSD to unusual_uas
First crash: 1467d, last: 1437d
Fix bisection: fixed by (bisect log) :
commit 01413361185139f3c7b37f5bd92d1badfb46930d
Author: Thinh Nguyen <Thinh.Nguyen@synopsys.com>
Date: Tue Jan 5 04:07:15 2021 +0000

  usb: uas: Add PNY USB Portable SSD to unusual_uas

  
Similar bugs (2)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-4.19 INFO: task hung in rdma_destroy_id (2) C error 3 663d 715d 0/1 upstream: reported C repro on 2022/12/07 05:36
upstream INFO: task hung in rdma_destroy_id rdma 6 1695d 1724d 0/28 closed as dup on 2020/03/09 17:21
Fix bisection attempts (2)
Created Duration User Patch Repo Result
2021/01/14 10:45 3h32m bisect fix linux-4.19.y OK (1) job log
2020/12/15 10:11 33m bisect fix linux-4.19.y OK (0) job log log

Sample crash report:
INFO: task syz-executor444:23528 blocked for more than 140 seconds.
      Not tainted 4.19.157-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor444 D28368 23528   8128 0x00000004
Call Trace:
 context_switch kernel/sched/core.c:2828 [inline]
 __schedule+0x887/0x2040 kernel/sched/core.c:3517
 schedule+0x8d/0x1b0 kernel/sched/core.c:3561
 schedule_timeout+0x92d/0xfe0 kernel/time/timer.c:1801
 do_wait_for_common kernel/sched/completion.c:83 [inline]
 __wait_for_common kernel/sched/completion.c:104 [inline]
 wait_for_common+0x29c/0x470 kernel/sched/completion.c:115
 rdma_destroy_id+0x4c9/0x950 drivers/infiniband/core/cma.c:1750
 ucma_close+0x140/0x360 drivers/infiniband/core/ucma.c:1819
 __fput+0x2ce/0x890 fs/file_table.c:278
 task_work_run+0x148/0x1c0 kernel/task_work.c:113
 tracehook_notify_resume include/linux/tracehook.h:193 [inline]
 exit_to_usermode_loop+0x251/0x2a0 arch/x86/entry/common.c:167
 prepare_exit_to_usermode arch/x86/entry/common.c:198 [inline]
 syscall_return_slowpath arch/x86/entry/common.c:271 [inline]
 do_syscall_64+0x538/0x620 arch/x86/entry/common.c:296
 entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x401b50
Code: 01 f0 ff ff 0f 83 40 0d 00 00 c3 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 44 00 00 83 3d dd 9b 2d 00 00 75 14 b8 03 00 00 00 0f 05 <48> 3d 01 f0 ff ff 0f 83 14 0d 00 00 c3 48 83 ec 08 e8 7a 02 00 00
RSP: 002b:00007ffdd784f318 EFLAGS: 00000246 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 0000000000401b50
RDX: 0000000000000048 RSI: 0000000020000140 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00000000bb1414ac R09: 00000000bb1414ac
R10: 00000000bb1414ac R11: 0000000000000246 R12: 00000000000b5422
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000

Showing all locks held in the system:
1 lock held by khungtaskd/1566:
 #0: 00000000170bded2 (rcu_read_lock){....}, at: debug_show_all_locks+0x53/0x265 kernel/locking/lockdep.c:4440
1 lock held by in:imklog/7836:
 #0: 000000001f329f29 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x26f/0x310 fs/file.c:767
3 locks held by syz-executor444/8135:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
 #2: 00000000128d0a77 (pool_lock){-.-.}, at: __free_object+0x17/0x1e0 lib/debugobjects.c:251
1 lock held by syz-executor444/31283:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
5 locks held by syz-executor444/31286:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
 #2: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #2: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
 #3: 00000000170bded2 (rcu_read_lock){....}, at: lock_page_memcg+0x0/0x220 include/linux/page_counter.h:64
 #4: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #4: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
1 lock held by syz-executor444/31327:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: idle_balance kernel/sched/fair.c:9701 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: pick_next_task_fair+0x556/0x1570 kernel/sched/fair.c:6840
1 lock held by syz-executor444/31330:
 #0: 000000005a8efe0b (misc_mtx){+.+.}, at: misc_open+0x55/0x4a0 drivers/char/misc.c:107
1 lock held by syz-executor444/31340:
 #0: 000000005a8efe0b (misc_mtx){+.+.}, at: misc_open+0x55/0x4a0 drivers/char/misc.c:107
1 lock held by syz-executor444/31342:
1 lock held by syz-executor444/31346:
1 lock held by syz-executor444/31352:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31360:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31373:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31378:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31386:
 #0: 000000005a8efe0b (misc_mtx){+.+.}, at: misc_open+0x55/0x4a0 drivers/char/misc.c:107
2 locks held by syz-executor444/31390:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
1 lock held by syz-executor444/31406:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31414:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31429:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31434:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31438:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31445:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31450:
1 lock held by syz-executor444/31454:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31461:
1 lock held by syz-executor444/31462:
 #0: 000000005a8efe0b (misc_mtx){+.+.}, at: misc_open+0x55/0x4a0 drivers/char/misc.c:107
1 lock held by syz-executor444/31471:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31490:
 #0: 000000005a8efe0b (misc_mtx){+.+.}, at: misc_open+0x55/0x4a0 drivers/char/misc.c:107
1 lock held by syz-executor444/31504:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
1 lock held by syz-executor444/31510:
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000df536fb9 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
3 locks held by syz-executor444/31514:
 #0: 00000000be972154 (&rq->lock){-.-.}, at: rq_lock kernel/sched/sched.h:1830 [inline]
 #0: 00000000be972154 (&rq->lock){-.-.}, at: __schedule+0x1f9/0x2040 kernel/sched/core.c:3455
 #1: 00000000170bded2 (rcu_read_lock){....}, at: trace_sched_stat_runtime include/trace/events/sched.h:428 [inline]
 #1: 00000000170bded2 (rcu_read_lock){....}, at: update_curr+0x2c3/0x870 kernel/sched/fair.c:857
 #2: 00000000117ff563 (&sig->wait_chldexit){....}, at: __wake_up_common_lock+0xb0/0x170 kernel/sched/wait.c:120

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

NMI backtrace for cpu 1
CPU: 1 PID: 1566 Comm: khungtaskd Not tainted 4.19.157-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+0x1fc/0x2fe lib/dump_stack.c:118
 nmi_cpu_backtrace.cold+0x63/0xa2 lib/nmi_backtrace.c:101
 nmi_trigger_cpumask_backtrace+0x1a6/0x1eb lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:203 [inline]
 watchdog+0x991/0xe60 kernel/hung_task.c:287
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 9 Comm: ksoftirqd/0 Not tainted 4.19.157-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:unwind_next_frame+0x450/0x1c60 arch/x86/kernel/unwind_orc.c:427
Code: b8 00 00 00 00 00 fc ff df 4c 89 c2 48 c1 ea 03 0f b6 04 02 4c 89 c2 83 e2 07 38 d0 7f 08 84 c0 0f 85 80 10 00 00 0f b6 41 04 <a8> 0f 0f 85 a9 00 00 00 48 8d 79 05 48 b8 00 00 00 00 00 fc ff df
RSP: 0018:ffff8880b5a6f850 EFLAGS: 00000046
RAX: 0000000000000015 RBX: 1ffff11016b4df12 RCX: ffffffff8b99b8ea
RDX: 0000000000000006 RSI: ffffffff8b99b8ea RDI: ffffffff8b38eee0
RBP: 0000000000000001 R08: ffffffff8b99b8ee R09: ffffffff8b99b8ea
R10: 0000000000074071 R11: 0000000000023617 R12: ffff8880b5a6f980
R13: ffff8880b5a6f96d R14: ffff8880b5a6f970 R15: ffff8880b5a6f938
FS:  0000000000000000(0000) GS:ffff8880ba000000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00000000006d3090 CR3: 000000009c816000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 __save_stack_trace+0xd6/0x190 arch/x86/kernel/stacktrace.c:44
 save_stack mm/kasan/kasan.c:448 [inline]
 set_track mm/kasan/kasan.c:460 [inline]
 __kasan_slab_free+0x126/0x1f0 mm/kasan/kasan.c:521
 __cache_free mm/slab.c:3503 [inline]
 kfree+0xcc/0x210 mm/slab.c:3822
 aa_free_task_ctx security/apparmor/include/task.h:61 [inline]
 apparmor_task_free+0x143/0x1e0 security/apparmor/lsm.c:93
 security_task_free+0x3e/0x70 security/security.c:992
 __put_task_struct+0xea/0x340 kernel/fork.c:688
 put_task_struct include/linux/sched/task.h:98 [inline]
 delayed_put_task_struct+0x1dc/0x320 kernel/exit.c:181
 __rcu_reclaim kernel/rcu/rcu.h:236 [inline]
 rcu_do_batch kernel/rcu/tree.c:2584 [inline]
 invoke_rcu_callbacks kernel/rcu/tree.c:2897 [inline]
 __rcu_process_callbacks kernel/rcu/tree.c:2864 [inline]
 rcu_process_callbacks+0x8ff/0x18b0 kernel/rcu/tree.c:2881
 __do_softirq+0x26c/0x9a0 kernel/softirq.c:292
 run_ksoftirqd+0x57/0x110 kernel/softirq.c:653
 smpboot_thread_fn+0x655/0x9e0 kernel/smpboot.c:164
 kthread+0x33f/0x460 kernel/kthread.c:259
 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:415

Crashes (2):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2020/11/15 10:11 linux-4.19.y 31acccdc8774 1bf9a662 .config console log report syz C ci2-linux-4-19
2020/11/15 06:53 linux-4.19.y 31acccdc8774 1bf9a662 .config console log report info ci2-linux-4-19
* Struck through repros no longer work on HEAD.