pkg/report: skip "rwsem" frames in task hung reports

2 files changed, 176 insertions, 0 deletions

diff --git a/pkg/report/testdata/linux/report/484 b/pkg/report/testdata/linux/report/484
new file mode 100644
index 000000000..50a101c09
--- /dev/null
+++ b/pkg/report/testdata/linux/report/484
@@ -0,0 +1,90 @@
+TITLE: INFO: task hung in register_netdevice_notifier
+TYPE: HANG
+
+[ 1353.547242][ T1145] INFO: task syz-executor.5:9850 blocked for more than 143 seconds.
+[ 1353.555551][ T1145]       Not tainted 5.7.0-rc5-syzkaller #0
+[ 1353.565574][ T1145] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
+[ 1353.583335][ T1145] syz-executor.5  D29496  9850  19518 0x00000004
+[ 1353.590397][ T1145] Call Trace:
+[ 1353.593796][ T1145]  ? __schedule+0x937/0x1ff0
+[ 1353.600226][ T1145]  ? rwsem_down_write_slowpath+0x90a/0xf90
+[ 1353.606375][ T1145]  ? __sched_text_start+0x8/0x8
+[ 1353.612526][ T1145]  ? schedule+0xd0/0x2a0
+[ 1353.617452][ T1145]  ? rwsem_down_write_slowpath+0x706/0xf90
+[ 1353.623263][ T1145]  ? rwsem_mark_wake+0x8d0/0x8d0
+[ 1353.632019][ T1145]  ? lock_acquire+0x1f2/0x8f0
+[ 1353.638252][ T1145]  ? register_netdevice_notifier+0x1e/0x270
+[ 1353.644250][ T1145]  ? _raw_spin_unlock_irqrestore+0x9b/0xe0
+[ 1353.650895][ T1145]  ? find_held_lock+0x2d/0x110
+[ 1353.655659][ T1145]  ? down_write+0x137/0x150
+[ 1353.661202][ T1145]  ? down_write+0x137/0x150
+[ 1353.665710][ T1145]  ? __down_timeout+0x2d0/0x2d0
+[ 1353.671980][ T1145]  ? lockdep_init_map_waits+0x26a/0x890
+[ 1353.678291][ T1145]  ? register_netdevice_notifier+0x1e/0x270
+[ 1353.684416][ T1145]  ? bcm_init+0x1a3/0x210
+[ 1353.689824][ T1145]  ? canbcm_pernet_init+0x90/0x90
+[ 1353.694867][ T1145]  ? can_create+0x27c/0x500
+[ 1353.700241][ T1145]  ? __sock_create+0x3cb/0x730
+[ 1353.705008][ T1145]  ? __sys_socket+0xef/0x200
+[ 1353.710767][ T1145]  ? move_addr_to_kernel+0x70/0x70
+[ 1353.715911][ T1145]  ? __x64_sys_clock_gettime+0x165/0x240
+[ 1353.722388][ T1145]  ? __ia32_sys_clock_settime+0x260/0x260
+[ 1353.728959][ T1145]  ? trace_hardirqs_off_caller+0x55/0x230
+[ 1353.735043][ T1145]  ? __x64_sys_socket+0x6f/0xb0
+[ 1353.742458][ T1145]  ? lockdep_hardirqs_on+0x463/0x620
+[ 1353.748879][ T1145]  ? do_syscall_64+0xf6/0x7d0
+[ 1353.753558][ T1145]  ? entry_SYSCALL_64_after_hwframe+0x49/0xb3
+[ 1353.760697][ T1145] INFO: task syz-executor.5:9852 blocked for more than 143 seconds.
+[ 1353.769966][ T1145]       Not tainted 5.7.0-rc5-syzkaller #0
+[ 1353.775856][ T1145] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
+[ 1353.785468][ T1145] syz-executor.5  D29064  9852  19518 0x00000004
+[ 1353.792454][ T1145] Call Trace:
+[ 1353.795745][ T1145]  ? __schedule+0x937/0x1ff0
+[ 1353.802342][ T1145]  ? rwsem_down_write_slowpath+0x90a/0xf90
+[ 1353.808690][ T1145]  ? __sched_text_start+0x8/0x8
+[ 1353.813557][ T1145]  ? schedule+0xd0/0x2a0
+[ 1353.819122][ T1145]  ? rwsem_down_write_slowpath+0x706/0xf90
+[ 1353.825014][ T1145]  ? rwsem_mark_wake+0x8d0/0x8d0
+[ 1353.831110][ T1145]  ? lock_acquire+0x1f2/0x8f0
+[ 1353.835901][ T1145]  ? register_netdevice_notifier+0x1e/0x270
+[ 1353.842701][ T1145]  ? _raw_spin_unlock_irqrestore+0x9b/0xe0
+[ 1353.849238][ T1145]  ? find_held_lock+0x2d/0x110
+[ 1353.854015][ T1145]  ? down_write+0x137/0x150
+[ 1353.860131][ T1145]  ? down_write+0x137/0x150
+[ 1353.864642][ T1145]  ? __down_timeout+0x2d0/0x2d0
+[ 1353.870803][ T1145]  ? lockdep_init_map_waits+0x26a/0x890
+[ 1353.877152][ T1145]  ? register_netdevice_notifier+0x1e/0x270
+[ 1353.883118][ T1145]  ? bcm_init+0x1a3/0x210
+[ 1353.888391][ T1145]  ? canbcm_pernet_init+0x90/0x90
+[ 1353.893422][ T1145]  ? can_create+0x27c/0x500
+[ 1353.898718][ T1145]  ? __sock_create+0x3cb/0x730
+[ 1353.903547][ T1145]  ? __sys_socket+0xef/0x200
+[ 1353.909269][ T1145]  ? move_addr_to_kernel+0x70/0x70
+[ 1353.914407][ T1145]  ? __x64_sys_clock_gettime+0x165/0x240
+[ 1353.921027][ T1145]  ? __ia32_sys_clock_settime+0x260/0x260
+[ 1353.928178][ T1145]  ? trace_hardirqs_off_caller+0x55/0x230
+[ 1353.933914][ T1145]  ? __x64_sys_socket+0x6f/0xb0
+[ 1353.939856][ T1145]  ? lockdep_hardirqs_on+0x463/0x620
+[ 1353.945163][ T1145]  ? do_syscall_64+0xf6/0x7d0
+[ 1353.951037][ T1145]  ? entry_SYSCALL_64_after_hwframe+0x49/0xb3
+[ 1353.957735][ T1145] 
+[ 1353.957735][ T1145] Showing all locks held in the system:
+[ 1353.965457][ T1145] 3 locks held by kworker/u4:7/338:
+[ 1353.972703][ T1145]  #0: ffff8880a9771938 ((wq_completion)netns){+.+.}-{0:0}, at: process_one_work+0x844/0x16a0
+[ 1353.983656][ T1145]  #1: ffffc90001fe7dc0 (net_cleanup_work){+.+.}-{0:0}, at: process_one_work+0x878/0x16a0
+[ 1353.995067][ T1145]  #2: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: cleanup_net+0x9b/0xa50
+[ 1354.004916][ T1145] 1 lock held by khungtaskd/1145:
+[ 1354.010630][ T1145]  #0: ffffffff899bea80 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260
+[ 1354.021380][ T1145] 1 lock held by in:imklog/7046:
+[ 1354.026323][ T1145]  #0: ffff88808fcab630 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0xe9/0x100
+[ 1354.037612][ T1145] 4 locks held by kworker/0:108/7015:
+[ 1354.042998][ T1145]  #0: ffff8880ae637998 (&rq->lock){-.-.}-{2:2}, at: __schedule+0x233/0x1ff0
+[ 1354.052885][ T1145]  #1: ffff8880ae622ec8 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x304/0x410
+[ 1354.065177][ T1145]  #2: ffffffff8c8707f8 (&obj_hash[i].lock){-.-.}-{2:2}, at: debug_check_no_obj_freed+0xc8/0x449
+[ 1354.075854][ T1145]  #3: ffff8880ae627598 (&base->lock){-.-.}-{2:2}, at: mod_timer+0x41f/0xc20
+[ 1354.085350][ T1145] 1 lock held by syz-executor.5/9850:
+[ 1354.090818][ T1145]  #0: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: register_netdevice_notifier+0x1e/0x270
+[ 1354.101594][ T1145] 1 lock held by syz-executor.5/9852:
+[ 1354.107309][ T1145]  #0: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: register_netdevice_notifier+0x1e/0x270
+[ 1354.118107][ T1145] 
+[ 1354.120432][ T1145] =============================================
diff --git a/pkg/report/testdata/linux/report/485 b/pkg/report/testdata/linux/report/485
new file mode 100644
index 000000000..ce61897e2
--- /dev/null
+++ b/pkg/report/testdata/linux/report/485
@@ -0,0 +1,86 @@
+TITLE: INFO: task hung in register_netdevice_notifier
+TYPE: HANG
+
+[  637.252691][ T1136] INFO: task syz-executor.3:20434 blocked for more than 143 seconds.
+[  637.260866][ T1136]       Not tainted 5.7.0-rc5-syzkaller #0
+[  637.271134][ T1136] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
+[  637.280725][ T1136] syz-executor.3  D29600 20434   7376 0x00000004
+[  637.287738][ T1136] Call Trace:
+[  637.291138][ T1136]  ? __schedule+0x937/0x1ff0
+[  637.298100][ T1136]  ? rwsem_down_write_slowpath+0x90a/0xf90
+[  637.305983][ T1136]  ? __sched_text_start+0x8/0x8
+[  637.310923][ T1136]  ? schedule+0xd0/0x2a0
+[  637.315939][ T1136]  ? rwsem_down_write_slowpath+0x706/0xf90
+[  637.321995][ T1136]  ? rwsem_mark_wake+0x8d0/0x8d0
+[  637.328350][ T1136]  ? lock_acquire+0x1f2/0x8f0
+[  637.334315][ T1136]  ? register_netdevice_notifier+0x1e/0x270
+[  637.340315][ T1136]  ? _raw_spin_unlock_irqrestore+0x9b/0xe0
+[  637.347664][ T1136]  ? find_held_lock+0x2d/0x110
+[  637.352888][ T1136]  ? down_write+0x137/0x150
+[  637.357418][ T1136]  ? down_write+0x137/0x150
+[  637.361937][ T1136]  ? __down_timeout+0x2d0/0x2d0
+[  637.366918][ T1136]  ? lockdep_init_map_waits+0x26a/0x890
+[  637.372998][ T1136]  ? register_netdevice_notifier+0x1e/0x270
+[  637.379030][ T1136]  ? bcm_init+0x1a3/0x210
+[  637.383482][ T1136]  ? canbcm_pernet_init+0x90/0x90
+[  637.388532][ T1136]  ? can_create+0x27c/0x500
+[  637.393187][ T1136]  ? __sock_create+0x3cb/0x730
+[  637.397978][ T1136]  ? __sys_socket+0xef/0x200
+[  637.403738][ T1136]  ? move_addr_to_kernel+0x70/0x70
+[  637.408913][ T1136]  ? __x64_sys_clock_gettime+0x165/0x240
+[  637.414705][ T1136]  ? __ia32_sys_clock_settime+0x260/0x260
+[  637.420474][ T1136]  ? trace_hardirqs_off_caller+0x55/0x230
+[  637.426371][ T1136]  ? __x64_sys_socket+0x6f/0xb0
+[  637.431255][ T1136]  ? lockdep_hardirqs_on+0x463/0x620
+[  637.436677][ T1136]  ? do_syscall_64+0xf6/0x7d0
+[  637.441394][ T1136]  ? entry_SYSCALL_64_after_hwframe+0x49/0xb3
+[  637.447594][ T1136] INFO: task syz-executor.3:20440 blocked for more than 143 seconds.
+[  637.455823][ T1136]       Not tainted 5.7.0-rc5-syzkaller #0
+[  637.461703][ T1136] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
+[  637.470464][ T1136] syz-executor.3  D29600 20440   7376 0x00000004
+[  637.477534][ T1136] Call Trace:
+[  637.480906][ T1136]  ? __schedule+0x937/0x1ff0
+[  637.485648][ T1136]  ? rwsem_down_write_slowpath+0x90a/0xf90
+[  637.491475][ T1136]  ? __sched_text_start+0x8/0x8
+[  637.496518][ T1136]  ? schedule+0xd0/0x2a0
+[  637.500779][ T1136]  ? rwsem_down_write_slowpath+0x706/0xf90
+[  637.506707][ T1136]  ? pcpu_alloc+0xfed/0x13b0
+[  637.511313][ T1136]  ? rwsem_mark_wake+0x8d0/0x8d0
+[  637.517337][ T1136]  ? lock_acquire+0x1f2/0x8f0
+[  637.522165][ T1136]  ? register_netdevice_notifier+0x1e/0x270
+[  637.528154][ T1136]  ? lock_release+0x800/0x800
+[  637.532976][ T1136]  ? down_write+0x137/0x150
+[  637.537490][ T1136]  ? down_write+0xb2/0x150
+[  637.541902][ T1136]  ? down_write+0x137/0x150
+[  637.546519][ T1136]  ? __down_timeout+0x2d0/0x2d0
+[  637.551382][ T1136]  ? pcpu_alloc+0x128/0x13b0
+[  637.556075][ T1136]  ? register_netdevice_notifier+0x1e/0x270
+[  637.561993][ T1136]  ? raw_init+0x296/0x340
+[  637.566421][ T1136]  ? raw_sock_no_ioctlcmd+0x10/0x10
+[  637.571630][ T1136]  ? can_create+0x27c/0x500
+[  637.576277][ T1136]  ? __sock_create+0x3cb/0x730
+[  637.581075][ T1136]  ? __sys_socket+0xef/0x200
+[  637.585759][ T1136]  ? move_addr_to_kernel+0x70/0x70
+[  637.590878][ T1136]  ? __x64_sys_clock_gettime+0x165/0x240
+[  637.596606][ T1136]  ? __ia32_sys_clock_settime+0x260/0x260
+[  637.603391][ T1136]  ? trace_hardirqs_off_caller+0x55/0x230
+[  637.609141][ T1136]  ? __x64_sys_socket+0x6f/0xb0
+[  637.614110][ T1136]  ? lockdep_hardirqs_on+0x463/0x620
+[  637.619409][ T1136]  ? do_syscall_64+0xf6/0x7d0
+[  637.625104][ T1136]  ? entry_SYSCALL_64_after_hwframe+0x49/0xb3
+[  637.631241][ T1136] 
+[  637.631241][ T1136] Showing all locks held in the system:
+[  637.639228][ T1136] 5 locks held by kworker/u4:1/21:
+[  637.644448][ T1136]  #0: ffff8880a9771938 ((wq_completion)netns){+.+.}-{0:0}, at: process_one_work+0x844/0x16a0
+[  637.656766][ T1136]  #1: ffffc90000dd7dc0 (net_cleanup_work){+.+.}-{0:0}, at: process_one_work+0x878/0x16a0
+[  637.666817][ T1136]  #2: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: cleanup_net+0x9b/0xa50
+[  637.676206][ T1136]  #3: ffffffff8a582e28 (rtnl_mutex){+.+.}-{3:3}, at: caif_exit_net+0x2c8/0x790
+[  637.685449][ T1136]  #4: ffff88803cc33680 (&caifn->caifdevs.lock){+.+.}-{3:3}, at: caif_exit_net+0x2e3/0x790
+[  637.695570][ T1136] 1 lock held by khungtaskd/1136:
+[  637.700580][ T1136]  #0: ffffffff899bea80 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260
+[  637.710563][ T1136] 1 lock held by syz-executor.3/20434:
+[  637.716135][ T1136]  #0: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: register_netdevice_notifier+0x1e/0x270
+[  637.726972][ T1136] 1 lock held by syz-executor.3/20440:
+[  637.735192][ T1136]  #0: ffffffff8a576f30 (pernet_ops_rwsem){++++}-{3:3}, at: register_netdevice_notifier+0x1e/0x270
+[  637.746054][ T1136] 
+[  637.748388][ T1136] =============================================