Skip to content
Permalink
Browse files

pkg/report: refine hung task processing for v4.15

  • Loading branch information...
dvyukov committed Apr 5, 2019
1 parent ba27bba commit 3635d6e6786f8dde2a460758b5d8be9428cb7e2b
Showing with 92 additions and 1 deletion.
  1. +1 −1 pkg/report/linux.go
  2. +91 −0 pkg/report/testdata/linux/report/367
@@ -1170,7 +1170,7 @@ var linuxOopses = []*oops{
parseStackTrace,
},
skip: []string{"sched", "_lock", "down", "completion", "kthread",
"wait", "synchronize"},
"wait", "synchronize", "context_switch"},
},
},
{
@@ -0,0 +1,91 @@
TITLE: INFO: task hung in mon_bin_read

[ 286.641449] INFO: task syz-executor.0:6102 blocked for more than 140 seconds.
[ 286.649215] Not tainted 4.15.0 #3
[ 286.653305] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 286.661454] syz-executor.0 D12800 6102 3860 0x30020000 115507005282
[ 286.668254] Call Trace:
[ 286.670899] [<ffffffff8144ecbf>] context_switch+0x60f/0xa60
[ 286.676752] [<ffffffff8100094b>] __schedule+0x59b/0x1d30
[ 286.682357] [<ffffffff810003b0>] ? __startup_64+0x1c0/0x1c0
[ 286.688189] [<ffffffff8100216f>] schedule+0x8f/0x1b0
[ 286.693459] [<ffffffff81002a10>] schedule_preempt_disabled+0x10/0x20
[ 286.700073] [<ffffffff8100753d>] __mutex_lock+0x82d/0x1430
[ 286.705848] [<ffffffff83320880>] ? mon_bin_read+0x60/0x640
[ 286.711627] [<ffffffff81006d10>] ? mutex_trylock+0x1c0/0x1c0
[ 286.717557] [<ffffffff816a54f6>] ? __sanitizer_cov_trace_const_cmp4+0x16/0x20
[ 286.725001] [<ffffffff81b7d077>] ? fsnotify+0x4b7/0xce0
[ 286.730519] [<ffffffff81b7cbc0>] ? fsnotify_first_mark+0x210/0x210
[ 286.731038] audit: type=1326 audit(1554431287.410:64): auid=4294967295 uid=0 gid=0 ses=4294967295 pid=15398 comm="syz-executor.3" exe="/root/syz-executor.3" sig=31 arch=40000003 syscall=265 compat=1 ip=0xf7f5cc69 code=0x0
[ 286.757096] [<ffffffff81008156>] mutex_lock_nested+0x16/0x20
[ 286.763054] [<ffffffff81008156>] ? mutex_lock_nested+0x16/0x20
[ 286.769139] [<ffffffff83320880>] mon_bin_read+0x60/0x640
[ 286.774769] [<ffffffff81aebd01>] ? __fget+0x2f1/0x6d0
[ 286.780180] [<ffffffff81a7786d>] __vfs_read+0x8d/0x110
[ 286.785708] [<ffffffff83320820>] ? copy_from_buf.isra.0+0x1c0/0x1c0
[ 286.792267] [<ffffffff81a77a82>] vfs_read+0x192/0x3c0
[ 286.797568] [<ffffffff81a789e9>] SyS_read+0xe9/0x1f0
[ 286.802834] [<ffffffff81a78900>] ? kernel_write+0x120/0x120
[ 286.808655] [<ffffffff8101d47a>] ? do_fast_syscall_32+0xca/0xcd1
[ 286.814965] [<ffffffff81a78900>] ? kernel_write+0x120/0x120
[ 286.820838] [<ffffffff8101d707>] do_fast_syscall_32+0x357/0xcd1
[ 286.827012] [<ffffffff810172ec>] ? trace_hardirqs_off_thunk+0x1a/0x1c
[ 286.833770] [<ffffffff84401a9b>] entry_SYSENTER_compat+0x8b/0x9d
[ 286.840080] RIP: 0023:0xf7f06c69
[ 286.843510] RSP: 002b:00000000f5cc00cc EFLAGS: 00000286 ORIG_RAX: 0000000000000003
[ 286.851313] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 0000000000000000
[ 286.858602] RDX: 0000000000000000 RSI: 0000000000000000 RDI: 0000000000000000
[ 286.866074] RBP: 0000000000000000 R08: 0000000000000000 R09: 0000000000000000
[ 286.873398] R10: 0000000000000000 R11: 0000000000000000 R12: 0000000000000000
[ 286.880745] R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
[ 286.888048]
[ 286.888048] Showing all locks held in the system:
[ 286.894449] 2 locks held by khungtaskd/585:
[ 286.898804] #0: (rcu_read_lock){....}, at: [<000000005e0a0171>] watchdog+0x147/0xc60
[ 286.906970] #1: (tasklist_lock){.+.+}, at: [<000000008b8d4ab8>] debug_show_all_locks+0x7f/0x230
[ 286.916227] 1 lock held by rsyslogd/3747:
[ 286.920443] #0: (&f->f_pos_lock){+.+.}, at: [<00000000fb6b1c3a>] __fdget_pos+0xe2/0x110
[ 286.928809] 2 locks held by getty/3829:
[ 286.932840] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 286.941586] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 286.950956] 2 locks held by getty/3830:
[ 286.954930] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 286.963681] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 286.973035] 2 locks held by getty/3831:
[ 286.977008] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 286.985733] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 286.995096] 2 locks held by getty/3832:
[ 286.999064] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 287.007794] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 287.017144] 2 locks held by getty/3833:
[ 287.021158] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 287.029857] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 287.039221] 2 locks held by getty/3834:
[ 287.043256] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 287.051994] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 287.061342] 2 locks held by getty/3835:
[ 287.065316] #0: (&tty->ldisc_sem){++++}, at: [<0000000040c39c58>] ldsem_down_read+0x33/0x40
[ 287.074048] #1: (&ldata->atomic_read_lock){+.+.}, at: [<00000000099475ab>] n_tty_read+0x220/0x1910
[ 287.083443] 1 lock held by syz-executor.0/6065:
[ 287.088106] #0: (&rp->fetch_lock){+.+.}, at: [<0000000032ae6fe1>] mon_bin_read+0x60/0x640
[ 287.096690] 1 lock held by syz-executor.0/6102:
[ 287.101395] #0: (&rp->fetch_lock){+.+.}, at: [<0000000032ae6fe1>] mon_bin_read+0x60/0x640
[ 287.110025]
[ 287.111701] =============================================
[ 287.111701]
[ 287.118725] NMI backtrace for cpu 1
[ 287.122409] CPU: 1 PID: 585 Comm: khungtaskd Not tainted 4.15.0 #3
[ 287.128734] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[ 287.138091] Call Trace:
[ 287.140701] [<ffffffff8427bc32>] dump_stack+0x14d/0x20b
[ 287.146181] [<ffffffff8134ac01>] ? x86_vector_activate.cold+0xb8/0xb8
[ 287.152875] [<ffffffff842881b7>] nmi_cpu_backtrace.cold+0x19/0x98
[ 287.159219] [<ffffffff8134ac80>] ? lapic_can_unplug_cpu.cold+0x38/0x38
[ 287.165998] [<ffffffff8428818a>] nmi_trigger_cpumask_backtrace+0x16a/0x17e
[ 287.173124] [<ffffffff8134ad94>] arch_trigger_cpumask_backtrace+0x14/0x20
[ 287.180180] [<ffffffff816a623d>] watchdog+0x7bd/0xc60
[ 287.185489] [<ffffffff81427a78>] kthread+0x348/0x420
[ 287.190690] [<ffffffff816a5a80>] ? reset_hung_task_detector+0x20/0x20
[ 287.197374] [<ffffffff81427730>] ? kthread_create_worker_on_cpu+0xf0/0xf0
[ 287.204398] [<ffffffff84400261>] ret_from_fork+0x61/0x70
[ 287.210065] Sending NMI from CPU 1 to CPUs 0:

0 comments on commit 3635d6e

Please sign in to comment.
You can’t perform that action at this time.