pkg/report: preserve trackback from other CPUs

For some bug types kernel produces traceback of all CPUs
(which is presumably useful for these crashes).
However, we currently throw it away because it has context
different from the oops context.
Detect such traceback and preserve output.
This commit is contained in:
Dmitry Vyukov 2019-05-10 10:24:57 +02:00
parent e551e0ce0d
commit 4e7ecf0713
4 changed files with 873 additions and 4 deletions

View File

@ -28,6 +28,7 @@ type linux struct {
consoleOutputRe *regexp.Regexp
questionableRe *regexp.Regexp
taskContext *regexp.Regexp
cpuContext *regexp.Regexp
guiltyFileBlacklist []*regexp.Regexp
reportStartIgnores []*regexp.Regexp
infoMessagesWithStack [][]byte
@ -55,6 +56,7 @@ func ctorLinux(target *targets.Target, kernelSrc, kernelObj string, ignores []*r
ctx.consoleOutputRe = regexp.MustCompile(`^(?:\*\* [0-9]+ printk messages dropped \*\* )?(?:.* login: )?(?:\<[0-9]+\>)?\[ *[0-9]+\.[0-9]+\](\[ *(?:C|T)[0-9]+\])? `)
ctx.questionableRe = regexp.MustCompile(`(\[\<[0-9a-f]+\>\])? \? +[a-zA-Z0-9_.]+\+0x[0-9a-f]+/[0-9a-f]+`)
ctx.taskContext = regexp.MustCompile(`\[ *T[0-9]+\]`)
ctx.cpuContext = regexp.MustCompile(`\[ *C[0-9]+\]`)
ctx.eoi = []byte("<EOI>")
ctx.guiltyFileBlacklist = []*regexp.Regexp{
regexp.MustCompile(`.*\.h`),
@ -198,7 +200,7 @@ func (ctx *linux) findReport(output []byte, oops *oops, startPos int, context st
}
secondReportPos := 0
textLines := 0
skipText := false
skipText, cpuTraceback := false, false
for pos, next := 0, 0; pos < len(output); pos = next + 1 {
next = bytes.IndexByte(output[pos:], '\n')
if next != -1 {
@ -238,13 +240,19 @@ func (ctx *linux) findReport(output []byte, oops *oops, startPos int, context st
}
}
}
if !oopsLine && (context1 != context || questionable) {
if !oopsLine && (questionable ||
context1 != context && (!cpuTraceback || !ctx.cpuContext.MatchString(context1))) {
continue
}
textLines++
skipLine := skipText
if bytes.Contains(line, []byte("Disabling lock debugging due to kernel taint")) {
skipLine = true
} else if bytes.Contains(line, []byte("Sending NMI from CPU")) {
// If we are doing traceback of all CPUs, then we also need to preserve output
// from other CPUs regardless of what is the current context.
// Otherwise we will throw traceback away because it does not match the oops context.
cpuTraceback = true
} else if textLines > 25 &&
(bytes.Contains(line, []byte("Kernel panic - not syncing")) ||
bytes.Contains(line, []byte("WARNING: possible circular locking dependency detected"))) {

View File

@ -1,4 +1,3 @@
# TODO: we may want to include traceback from other CPUs into the report.
TITLE: INFO: task hung in jbd2_journal_commit_transaction
[ 1431.920513][ T1054] INFO: task jbd2/sda-8:3563 blocked for more than 140 seconds.
@ -216,3 +215,205 @@ TITLE: INFO: task hung in jbd2_journal_commit_transaction
[ 1432.425305][ T1054] Kernel Offset: disabled
[ 1432.425916][ T1054] Rebooting in 86400 seconds..
REPORT:
INFO: task jbd2/sda-8:3563 blocked for more than 140 seconds.
Not tainted 4.20.0-next-20190102+ #5
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
jbd2/sda-8 D22600 3563 2 0x80000000
Call Trace:
__schedule+0x897/0x1e60
schedule+0xfe/0x350
io_schedule+0x1c/0x70
bit_wait_io+0x17/0x90
__wait_on_bit+0xb3/0x130
out_of_line_wait_on_bit+0x1f4/0x3b0
__wait_on_buffer+0x6f/0x90
jbd2_journal_commit_transaction+0x658a/0x8ee4
kjournald2+0x20f/0xb90
kthread+0x357/0x430
ret_from_fork+0x3a/0x50
INFO: task kworker/u12:5:1980 blocked for more than 140 seconds.
Not tainted 4.20.0-next-20190102+ #5
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
kworker/u12:5 D21864 1980 2 0x80000000
Workqueue: writeback wb_workfn (flush-8:0)
Call Trace:
__schedule+0x897/0x1e60
schedule+0xfe/0x350
io_schedule+0x1c/0x70
rq_qos_wait+0x2f2/0x600
wbt_wait+0x266/0x430
__rq_qos_throttle+0x58/0xb0
blk_mq_make_request+0x424/0x1e10
generic_make_request+0x902/0x17f0
submit_bio+0xba/0x480
ext4_io_submit+0x197/0x230
ext4_writepages+0x139d/0x4180
do_writepages+0x99/0x1a0
__writeback_single_inode+0x1c5/0x1620
writeback_sb_inodes+0x762/0x1260
__writeback_inodes_wb+0x16d/0x3d0
wb_writeback+0xa2d/0xf70
wb_workfn+0xccc/0x16f0
process_one_work+0xd0c/0x1ce0
worker_thread+0x143/0x14a0
kthread+0x357/0x430
ret_from_fork+0x3a/0x50
INFO: task syz-executor2:6677 blocked for more than 140 seconds.
Not tainted 4.20.0-next-20190102+ #5
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor2 D19624 6677 22450 0x00000004
Call Trace:
__schedule+0x897/0x1e60
schedule+0xfe/0x350
io_schedule+0x1c/0x70
wait_on_page_bit_common+0xad5/0x1370
__filemap_fdatawait_range+0x614/0x890
file_write_and_wait_range+0xd1/0x100
ext4_sync_file+0x2da/0x14d0
vfs_fsync_range+0x144/0x230
ext4_file_write_iter+0x683/0x1400
do_iter_readv_writev+0x902/0xbc0
do_iter_write+0x184/0x610
vfs_iter_write+0x77/0xb0
iter_file_splice_write+0x885/0xfc0
direct_splice_actor+0x126/0x1a0
splice_direct_to_actor+0x3be/0x9d0
do_splice_direct+0x2c7/0x420
do_sendfile+0x61a/0xe60
__x64_sys_sendfile64+0x15a/0x240
do_syscall_64+0x1a3/0x800
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4576a9
Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00
RSP: 002b:00007f66fbce3c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000071bf00 RCX: 00000000004576a9
RDX: 0000000020000040 RSI: 0000000000000005 RDI: 0000000000000005
RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000
R10: 00008080fffffffe R11: 0000000000000246 R12: 00007f66fbce46d4
R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff
INFO: task syz-executor2:6693 blocked for more than 140 seconds.
Not tainted 4.20.0-next-20190102+ #5
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor2 D22200 6693 22450 0x00000004
Call Trace:
__schedule+0x897/0x1e60
schedule+0xfe/0x350
jbd2_log_wait_commit+0x320/0x4b0
jbd2_complete_transaction+0x18c/0x1e0
ext4_sync_file+0x1211/0x14d0
vfs_fsync_range+0x144/0x230
ext4_file_write_iter+0x683/0x1400
do_iter_readv_writev+0x902/0xbc0
do_iter_write+0x184/0x610
vfs_iter_write+0x77/0xb0
iter_file_splice_write+0x885/0xfc0
direct_splice_actor+0x126/0x1a0
splice_direct_to_actor+0x3be/0x9d0
do_splice_direct+0x2c7/0x420
do_sendfile+0x61a/0xe60
__x64_sys_sendfile64+0x15a/0x240
do_syscall_64+0x1a3/0x800
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x4576a9
Code: 3b 41 10 76 7d 48 81 ec 88 00 00 00 48 89 ac 24 80 00 00 00 48 8d ac 24 80 00 00 00 48 8b 59 20 48 85 db 75 67 48 8b b4 24 a0 <00> 00 00 8b 8c 24 a8 00 00 00 48 89 e7 f3 a4 48 8b 94 24 98 00 00
RSP: 002b:00007f66fbcc2c88 EFLAGS: 00000246 ORIG_RAX: 0000000000000028
RAX: ffffffffffffffda RBX: 000000000071bfa0 RCX: 00000000004576a9
RDX: 0000000020000380 RSI: 0000000000000005 RDI: 0000000000000005
RBP: 0000000000000004 R08: 0000000000000000 R09: 0000000000000000
R10: 0001000000020000 R11: 0000000000000246 R12: 00007f66fbcc36d4
R13: 00000000004abcac R14: 00000000006eb9b8 R15: 00000000ffffffff
Showing all locks held in the system:
1 lock held by khungtaskd/1054:
#0: 00000000c542499d (rcu_read_lock){....}, at: debug_show_all_locks+0xc6/0x41d
1 lock held by rsyslogd/5859:
#0: 00000000567e80cf (&f->f_pos_lock){+.+.}, at: __fdget_pos+0x1b3/0x1f0
2 locks held by getty/5951:
#0: 000000003b0dfac2 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000281ebc43 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5952:
#0: 00000000f0d48c91 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000cff3c1fb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5953:
#0: 00000000cb353a8f (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000373ded25 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5954:
#0: 000000005c8be537 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000d7aec815 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5955:
#0: 00000000eda8138a (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000f4fd60a6 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5956:
#0: 000000000745b5fb (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000a4c3442a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
2 locks held by getty/5957:
#0: 000000002cb7c49e (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 000000009258e26f (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x30a/0x1eb0
4 locks held by kworker/u12:5/1980:
#0: 000000005e902bc8 ((wq_completion)"writeback"){+.+.}, at: process_one_work+0xbc7/0x1ce0
#1: 0000000056cd520a ((work_completion)(&(&wb->dwork)->work)){+.+.}, at: process_one_work+0xc1d/0x1ce0
#2: 00000000146ba37b (&type->s_umount_key#30){++++}, at: trylock_super+0x22/0x110
#3: 00000000fec9c2c2 (&sbi->s_journal_flag_rwsem){.+.+}, at: do_writepages+0x99/0x1a0
1 lock held by syz-executor2/6677:
#0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60
1 lock held by syz-executor2/6693:
#0: 000000007b67e744 (sb_writers#3){.+.+}, at: do_sendfile+0xad7/0xe60
=============================================
NMI backtrace for cpu 3
CPU: 3 PID: 1054 Comm: khungtaskd Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
Call Trace:
dump_stack+0x1db/0x2d0
nmi_cpu_backtrace.cold+0x63/0xa4
nmi_trigger_cpumask_backtrace+0x1be/0x236
arch_trigger_cpumask_backtrace+0x14/0x20
watchdog+0xbbb/0x1170
kthread+0x357/0x430
ret_from_fork+0x3a/0x50
Sending NMI from CPU 3 to CPUs 0-2:
NMI backtrace for cpu 0 skipped: idling at native_safe_halt+0x2/0x10
NMI backtrace for cpu 2 skipped: idling at native_safe_halt+0x2/0x10
NMI backtrace for cpu 1
CPU: 1 PID: 5857 Comm: rs:main Q:Reg Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
RIP: 0010:unwind_next_frame+0x36/0x50
Code: fa 55 48 c1 ea 03 48 89 e5 48 83 ec 08 0f b6 14 02 48 89 f8 83 e0 07 83 c0 03 38 d0 7c 04 84 d2 75 0f 8b 17 31 c0 85 d2 74 05 <e8> 35 f5 ff ff c9 c3 48 89 7d f8 e8 aa 54 8e 00 48 8b 7d f8 eb e2
RSP: 0018:ffff88805f667100 EFLAGS: 00000202
RAX: 0000000000000000 RBX: ffff88805f6671b8 RCX: 000000000000000f
RDX: 0000000000000001 RSI: ffffffff8100c393 RDI: ffff88805f667118
RBP: ffff88805f667108 R08: 0000000000000001 R09: ffff88805f667168
R10: ffff88805f667140 R11: ffff88805f667150 R12: 0000000000000000
R13: 0000000000000000 R14: ffff88805c5d2500 R15: ffff8880664f37c0
FS: 00007fc30a025700(0000) GS:ffff88806c440000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f8d9477b190 CR3: 000000006300b001 CR4: 00000000001606e0
Call Trace:
__save_stack_trace+0x7a/0xf0
save_stack_trace+0x1a/0x20
save_stack+0x45/0xd0
kasan_kmalloc+0xcf/0xe0
kasan_slab_alloc+0xf/0x20
kmem_cache_alloc+0x12d/0x710
jbd2__journal_start+0x1e4/0xa80
__ext4_journal_start_sb+0x1a7/0x630
ext4_da_write_begin+0x4ee/0x12d0
generic_perform_write+0x2a0/0x6b0
__generic_file_write_iter+0x25e/0x630
ext4_file_write_iter+0x381/0x1400
__vfs_write+0x764/0xb40
vfs_write+0x20c/0x580
ksys_write+0x105/0x260
__x64_sys_write+0x73/0xb0
do_syscall_64+0x1a3/0x800
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fc30ba8319d
Code: d1 20 00 00 75 10 b8 01 00 00 00 0f 05 48 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 be fa ff ff 48 89 04 24 b8 01 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 07 fb ff ff 48 89 d0 48 83 c4 08 48 3d 01
RSP: 002b:00007fc30a023f90 EFLAGS: 00000293 ORIG_RAX: 0000000000000001
RAX: ffffffffffffffda RBX: 0000000000000400 RCX: 00007fc30ba8319d
RDX: 0000000000000400 RSI: 00000000008d3ce0 RDI: 0000000000000002
RBP: 00000000008d3ce0 R08: 00000000008d408d R09: 00007fc30b3ffe07
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000000
R13: 00007fc30a024410 R14: 00007fc304002a50 R15: 00000000008d3ac0

View File

@ -1,4 +1,3 @@
# TODO: we may want to include traceback from other CPUs into the report.
TITLE: BUG: soft lockup in perf_release
[ 1098.601132][ C2] watchdog: BUG: soft lockup - CPU#2 stuck for 136s! [syz-executor4:4888]
@ -263,3 +262,173 @@ TITLE: BUG: soft lockup in perf_release
[ 1100.153466][ C2] syz-exec-31490 2...1 413721002us : 0: }D
[ 1100.154284][ C2] syz-exec-31490 2...1 413721003us : 0: }D
REPORT:
watchdog: BUG: soft lockup - CPU#2 stuck for 136s! [syz-executor4:4888]
Modules linked in:
irq event stamp: 79894
hardirqs last enabled at (79893): [<ffffffff81007bd9>] trace_hardirqs_on_thunk+0x1a/0x1c
hardirqs last disabled at (79894): [<ffffffff81007bf5>] trace_hardirqs_off_thunk+0x1a/0x1c
softirqs last enabled at (0): [<ffffffff814aaac4>] copy_process+0x1d14/0x8720
softirqs last disabled at (0): [<0000000000000000>] (null)
CPU: 2 PID: 4888 Comm: syz-executor4 Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
RIP: 0010:__sanitizer_cov_trace_const_cmp4+0xd/0x20
Code: d6 0f b7 f7 bf 03 00 00 00 48 89 e5 48 8b 4d 08 e8 d8 fe ff ff 5d c3 66 0f 1f 44 00 00 55 89 f2 89 fe bf 05 00 00 00 48 89 e5 <48> 8b 4d 08 e8 ba fe ff ff 5d c3 0f 1f 84 00 00 00 00 00 55 48 89
RSP: 0018:ffff88803b35f218 EFLAGS: 00000246 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000004 RBX: ffffed100766be5a RCX: ffffffff81759ae5
RDX: 0000000000000001 RSI: 0000000000000000 RDI: 0000000000000005
RBP: ffff88803b35f218 R08: ffff88805a72a540 R09: ffff88805a72ae80
R10: ffff88805a72a540 R11: 0000000000000000 R12: ffff88806c437fe0
R13: 0000000000000001 R14: ffff88803b35f310 R15: 0000000000000003
FS: 0000000001b86940(0000) GS:ffff88806c480000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000001b2d423000 CR3: 000000003ce21003 CR4: 00000000001606e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000ffff0ff0 DR7: 0000000000000600
Call Trace:
smp_call_function_many+0x8d5/0xb10
smp_call_function+0x42/0x90
on_each_cpu+0x31/0x200
text_poke_bp+0x101/0x1e5
__jump_label_transform+0x33f/0x4d0
arch_jump_label_transform+0x2b/0x40
__jump_label_update+0x16a/0x210
jump_label_update+0x1ce/0x3d0
__static_key_slow_dec_cpuslocked+0xc5/0x220
static_key_slow_dec+0x60/0xa0
tracepoint_probe_unregister+0x73e/0x940
trace_event_reg+0x189/0x350
perf_trace_event_unreg.isra.0+0xbb/0x220
perf_trace_destroy+0xc1/0x100
tp_perf_event_destroy+0x16/0x20
_free_event+0x3ee/0x1640
put_event+0x47/0x60
perf_event_release_kernel+0x890/0xf70
perf_release+0x37/0x50
__fput+0x3c5/0xb10
____fput+0x16/0x20
task_work_run+0x1f4/0x2b0
exit_to_usermode_loop+0x32a/0x3b0
do_syscall_64+0x696/0x800
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x411071
Code: 75 14 b8 03 00 00 00 0f 05 48 3d 01 f0 ff ff 0f 83 74 1a 00 00 c3 48 83 ec 08 e8 0a fc ff ff 48 89 04 24 b8 03 00 00 00 0f 05 <48> 8b 3c 24 48 89 c2 e8 53 fc ff ff 48 89 d0 48 83 c4 08 48 3d 01
RSP: 002b:00007ffffa2d80d0 EFLAGS: 00000293 ORIG_RAX: 0000000000000003
RAX: 0000000000000000 RBX: 0000000000000004 RCX: 0000000000411071
RDX: 0000001b2d420000 RSI: 00007fd53b7c3510 RDI: 0000000000000003
RBP: 0000000000000000 R08: 00007fd53b7c3048 R09: 000000003df39a59
R10: 00007ffffa2d8000 R11: 0000000000000293 R12: 0000000000000001
R13: 0000000000000000 R14: 0000000000000000 R15: 00007ffffa2d8190
Sending NMI from CPU 2 to CPUs 0-1,3:
NMI backtrace for cpu 1
CPU: 1 PID: 22149 Comm: kworker/1:1 Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
Workqueue: rcu_gp process_srcu
RIP: 0010:delay_tsc+0x33/0xc0
Code: bf 01 00 00 00 41 55 41 54 53 e8 58 18 66 f9 e8 f3 ad 9f fb 41 89 c5 0f ae e8 0f 31 48 c1 e2 20 48 09 c2 49 89 d4 eb 16 f3 90 <bf> 01 00 00 00 e8 33 18 66 f9 e8 ce ad 9f fb 44 39 e8 75 36 0f ae
RSP: 0018:ffff88803ce475a0 EFLAGS: 00000286
RAX: 0000000080000000 RBX: 0000029824c8de8b RCX: 0000000000000000
RDX: 0000000000000004 RSI: ffffffff8391c828 RDI: 0000000000000001
RBP: ffff88803ce475c0 R08: ffff88806b55c240 R09: fffffbfff16b1885
R10: ffff88803ce47710 R11: ffffffff8b58c427 R12: 0000029824c8d990
R13: 0000000000000001 R14: 00000000000032ab R15: ffffffff8b58c080
FS: 0000000000000000(0000) GS:ffff88806c440000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: ffffffffff600400 CR3: 000000005ad83004 CR4: 00000000001606e0
Call Trace:
__const_udelay+0x5f/0x80
try_check_zero+0x352/0x5c0
process_srcu+0x642/0x1400
process_one_work+0xd0c/0x1ce0
worker_thread+0x143/0x14a0
kthread+0x357/0x430
ret_from_fork+0x3a/0x50
NMI backtrace for cpu 0
CPU: 0 PID: 3681 Comm: udevd Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
RIP: 0010:native_queued_spin_lock_slowpath+0x323/0x1290
Code: 00 00 00 fc ff df 49 c1 ec 03 4d 01 fc 45 84 f6 41 c6 04 24 f8 74 4f 4c 89 ee 83 e6 07 83 c6 03 f3 90 4c 89 e8 41 c6 04 24 04 <48> c1 e8 03 42 0f b6 04 38 40 38 c6 7c 08 84 c0 0f 85 f5 09 00 00
RSP: 0000:ffff88806c4077f8 EFLAGS: 00000002
RAX: ffff88806b9f4798 RBX: ffff88806c4079a0 RCX: ffff88806c4078b0
RDX: 0000000000000000 RSI: 0000000000000003 RDI: ffff88806b9f4798
RBP: ffff88806c4079c8 R08: 1ffff1100d73e8f3 R09: ffffed100d73e8f4
R10: ffffed100d73e8f3 R11: ffff88806b9f479b R12: ffffed100d880f16
R13: ffff88806b9f4798 R14: 0000000000000101 R15: dffffc0000000000
FS: 00007fdf8d22a7a0(0000) GS:ffff88806c400000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00000000023997d0 CR3: 000000006b1bd002 CR4: 00000000001606f0
Call Trace:
<IRQ>
do_raw_spin_lock+0x2af/0x360
_raw_spin_lock+0x37/0x40
drm_handle_vblank+0x154/0xc70
drm_crtc_handle_vblank+0x63/0x90
vkms_vblank_simulate+0x61/0x290
__hrtimer_run_queues+0x3a7/0x1050
hrtimer_interrupt+0x314/0x770
smp_apic_timer_interrupt+0x18d/0x760
apic_timer_interrupt+0xf/0x20
</IRQ>
RIP: 0033:0x4179c0
Code: 40 00 48 8d 3d 3a 88 00 00 b9 09 00 00 00 48 89 de f3 a6 0f 84 91 01 00 00 48 8d 3d 2d 88 00 00 b9 05 00 00 00 48 89 de f3 a6 <0f> 84 22 02 00 00 48 8d 3d 1c 88 00 00 b9 12 00 00 00 48 89 de f3
RSP: 002b:00007fff245ca880 EFLAGS: 00000297 ORIG_RAX: ffffffffffffff13
RAX: 0000000000000000 RBX: 00007fff245cae1f RCX: 0000000000000004
RDX: 0000000000000000 RSI: 00007fff245cae20 RDI: 00000000004201e4
RBP: 00000000023a3f90 R08: 00007fff245cae1f R09: 00007fdf8c98a3f0
R10: 00000000023a0920 R11: 00000000023a4ce0 R12: 00007fff245cad80
R13: 00000000000000ac R14: 00000000023872d0 R15: 00007fff245cae1f
NMI backtrace for cpu 3
CPU: 3 PID: 4874 Comm: udevd Not tainted 4.20.0-next-20190102+ #5
Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS 1.10.2-1 04/01/2014
RIP: 0010:lock_acquire+0x27d/0x570
Code: 48 ff ff ff 57 9d 0f 1f 44 00 00 48 b8 00 00 00 00 00 fc ff df 48 03 85 40 ff ff ff 48 c7 00 00 00 00 00 c7 40 08 00 00 00 00 <48> 8b 45 d0 65 48 33 04 25 28 00 00 00 0f 85 77 02 00 00 48 8d 65
RSP: 0018:ffff88806c4c75d8 EFLAGS: 00000086
RAX: ffffed100d898ec0 RBX: ffff88805034c100 RCX: 0000000000000000
RDX: dffffc0000000000 RSI: 00000000000046f9 RDI: 0000000000000086
RBP: ffff88806c4c76a8 R08: 0000000000000005 R09: ffff88805034ca68
R10: ffff88805034ca48 R11: 0000000000000001 R12: ffff88806c425d58
R13: 0000000000000000 R14: 0000000000000000 R15: 0000000000000000
FS: 00007fdf8d22a7a0(0000) GS:ffff88806c4c0000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000625208 CR3: 0000000020393002 CR4: 00000000001606e0
Call Trace:
<IRQ>
_raw_spin_lock_irqsave+0x95/0xcd
lock_hrtimer_base.isra.0+0x75/0x130
hrtimer_try_to_cancel+0xfe/0x6b0
hrtimer_cancel+0x20/0x40
vkms_disable_vblank+0x19/0x20
drm_vblank_disable_and_save+0x391/0x4a0
vblank_disable_fn+0x22c/0x280
call_timer_fn+0x254/0x900
__run_timers+0x6fc/0xd50
run_timer_softirq+0x52/0xb0
__do_softirq+0x30b/0xb11
irq_exit+0x180/0x1d0
smp_apic_timer_interrupt+0x1b7/0x760
apic_timer_interrupt+0xf/0x20
</IRQ>
RIP: 0010:handle_mm_fault+0x7f4/0xc80
Code: 48 c1 e8 03 80 3c 10 00 0f 85 c9 03 00 00 48 83 3d 70 ce db 07 00 0f 84 ff 02 00 00 e8 75 4f cb ff 48 8b bd 70 ff ff ff 57 9d <0f> 1f 44 00 00 e9 98 fb ff ff e8 5d 4f cb ff 48 ba 00 00 00 00 00
RSP: 0018:ffff88800a7ff7b0 EFLAGS: 00000293 ORIG_RAX: ffffffffffffff13
RAX: ffff88805034c100 RBX: 0000000000000200 RCX: 1ffff1100a069934
RDX: 0000000000000000 RSI: ffffffff81b6b18b RDI: 0000000000000293
RBP: ffff88800a7ff858 R08: 0000000000000006 R09: ffff88805034c9a0
R10: ffff88805034c100 R11: 0000000000000000 R12: ffff8880607ea738
R13: 0000000000000081 R14: 1ffff110014ffefa R15: 0000000000000000
__get_user_pages+0x8f7/0x1e10
get_user_pages_remote+0x21d/0x440
copy_strings.isra.0+0x3fd/0xa70
copy_strings_kernel+0xa5/0x110
__do_execve_file.isra.0+0x124e/0x2700
__x64_sys_execve+0x8f/0xc0
do_syscall_64+0x1a3/0x800
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x7fdf8c90e207
Code: 77 19 f4 48 89 d7 44 89 c0 0f 05 48 3d 00 f0 ff ff 76 e0 f7 d8 64 41 89 01 eb d8 f7 d8 64 41 89 01 eb df b8 3b 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 02 f3 c3 48 8b 15 00 8c 2d 00 f7 d8 64 89 02
RSP: 002b:00007fff245c5488 EFLAGS: 00000206 ORIG_RAX: 000000000000003b
RAX: ffffffffffffffda RBX: 00000000ffffffff RCX: 00007fdf8c90e207
RDX: 0000000002398aa0 RSI: 00007fff245c5580 RDI: 00007fff245c6590
RBP: 0000000000625500 R08: 0000000000001ca3 R09: 0000000000001ca3
R10: 0000000000000000 R11: 0000000000000206 R12: 0000000002398aa0
R13: 0000000000000007 R14: 0000000002387250 R15: 0000000000000005

491
pkg/report/testdata/linux/report/385 vendored Normal file
View File

@ -0,0 +1,491 @@
TITLE: INFO: task hung in mount_bdev
[ 767.964958][ T1042] INFO: task syz-executor013:7561 blocked for more than 143 seconds.
[ 767.973200][ T1042] Not tainted 5.1.0-rc6+ #90
[ 767.978361][ T1042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 767.987323][ T1042] syz-executor013 D29336 7561 7543 0x00000004
[ 767.993661][ T1042] Call Trace:
[ 767.997133][ T1042] __schedule+0x813/0x1cc0
[ 768.014473][ T1042] schedule+0x92/0x180
[ 768.020552][ T1042] schedule_preempt_disabled+0x13/0x20
[ 768.027010][ T1042] __mutex_lock+0x726/0x1310
[ 768.051197][ T1042] mutex_lock_nested+0x16/0x20
[ 768.061122][ T1042] mount_bdev+0x93/0x3c0
[ 768.075747][ T1042] udf_mount+0x35/0x40
[ 768.085482][ T1042] legacy_get_tree+0xf2/0x200
[ 768.096701][ T1042] vfs_get_tree+0x123/0x450
[ 768.101589][ T1042] do_mount+0x1436/0x2c40
[ 768.127663][ T1042] ksys_mount+0xdb/0x150
[ 768.132136][ T1042] __x64_sys_mount+0xbe/0x150
[ 768.136893][ T1042] do_syscall_64+0x103/0x610
[ 768.141633][ T1042] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 768.147568][ T1042] RIP: 0033:0x44a739
[ 768.151707][ T1042] Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
[ 768.172294][ T1042] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 768.180755][ T1042] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 768.188912][ T1042] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 768.196921][ T1042] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 768.205082][ T1042] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 768.213067][ T1042] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 768.221103][ T1042] INFO: task syz-executor013:7563 blocked for more than 143 seconds.
[ 768.229355][ T1042] Not tainted 5.1.0-rc6+ #90
[ 768.234452][ T1042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 768.243162][ T1042] syz-executor013 D29720 7563 7541 0x00000004
[ 768.249679][ T1042] Call Trace:
[ 768.252972][ T1042] __schedule+0x813/0x1cc0
[ 768.267246][ T1042] schedule+0x92/0x180
[ 768.271455][ T1042] schedule_preempt_disabled+0x13/0x20
[ 768.276960][ T1042] __mutex_lock+0x726/0x1310
[ 768.301820][ T1042] mutex_lock_nested+0x16/0x20
[ 768.311709][ T1042] mount_bdev+0x93/0x3c0
[ 768.326135][ T1042] udf_mount+0x35/0x40
[ 768.335859][ T1042] legacy_get_tree+0xf2/0x200
[ 768.347058][ T1042] vfs_get_tree+0x123/0x450
[ 768.351780][ T1042] do_mount+0x1436/0x2c40
[ 768.377854][ T1042] ksys_mount+0xdb/0x150
[ 768.382226][ T1042] __x64_sys_mount+0xbe/0x150
[ 768.386954][ T1042] do_syscall_64+0x103/0x610
[ 768.391686][ T1042] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 768.397620][ T1042] RIP: 0033:0x44a739
[ 768.401661][ T1042] Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
[ 768.425721][ T1042] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 768.434131][ T1042] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 768.442159][ T1042] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 768.450311][ T1042] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 768.458340][ T1042] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 768.466471][ T1042] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 768.474468][ T1042] INFO: task syz-executor013:7559 blocked for more than 143 seconds.
[ 768.482591][ T1042] Not tainted 5.1.0-rc6+ #90
[ 768.487887][ T1042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 768.496604][ T1042] syz-executor013 D29096 7559 7544 0x00000004
[ 768.503084][ T1042] Call Trace:
[ 768.506426][ T1042] __schedule+0x813/0x1cc0
[ 768.532461][ T1042] schedule+0x92/0x180
[ 768.536599][ T1042] rwsem_down_write_failed+0x774/0xc30
[ 768.552726][ T1042] call_rwsem_down_write_failed+0x17/0x30
[ 768.565335][ T1042] down_write+0x53/0x90
[ 768.574050][ T1042] grab_super+0xb4/0x290
[ 768.598508][ T1042] sget_userns+0x1ab/0x560
[ 768.618257][ T1042] sget+0x10c/0x150
[ 768.622213][ T1042] mount_bdev+0xff/0x3c0
[ 768.631400][ T1042] udf_mount+0x35/0x40
[ 768.641676][ T1042] legacy_get_tree+0xf2/0x200
[ 768.652778][ T1042] vfs_get_tree+0x123/0x450
[ 768.657331][ T1042] do_mount+0x1436/0x2c40
[ 768.683418][ T1042] ksys_mount+0xdb/0x150
[ 768.688581][ T1042] __x64_sys_mount+0xbe/0x150
[ 768.693263][ T1042] do_syscall_64+0x103/0x610
[ 768.697905][ T1042] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 768.703939][ T1042] RIP: 0033:0x44a739
[ 768.707867][ T1042] Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
[ 768.727665][ T1042] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 768.736116][ T1042] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 768.744225][ T1042] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 768.752255][ T1042] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 768.760408][ T1042] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 768.768434][ T1042] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 768.776604][ T1042] INFO: task syz-executor013:7565 blocked for more than 144 seconds.
[ 768.784665][ T1042] Not tainted 5.1.0-rc6+ #90
[ 768.789817][ T1042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 768.798628][ T1042] syz-executor013 D29720 7565 7542 0x00000004
[ 768.805023][ T1042] Call Trace:
[ 768.808446][ T1042] __schedule+0x813/0x1cc0
[ 768.824670][ T1042] schedule+0x92/0x180
[ 768.828797][ T1042] schedule_preempt_disabled+0x13/0x20
[ 768.834395][ T1042] __mutex_lock+0x726/0x1310
[ 768.858495][ T1042] mutex_lock_nested+0x16/0x20
[ 768.868373][ T1042] mount_bdev+0x93/0x3c0
[ 768.883017][ T1042] udf_mount+0x35/0x40
[ 768.892748][ T1042] legacy_get_tree+0xf2/0x200
[ 768.903904][ T1042] vfs_get_tree+0x123/0x450
[ 768.908456][ T1042] do_mount+0x1436/0x2c40
[ 768.934426][ T1042] ksys_mount+0xdb/0x150
[ 768.938706][ T1042] __x64_sys_mount+0xbe/0x150
[ 768.943534][ T1042] do_syscall_64+0x103/0x610
[ 768.949064][ T1042] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 768.955007][ T1042] RIP: 0033:0x44a739
[ 768.959046][ T1042] Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
[ 768.978703][ T1042] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 768.987283][ T1042] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 768.995519][ T1042] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 769.003491][ T1042] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 769.011744][ T1042] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 769.019970][ T1042] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 769.028216][ T1042] INFO: task syz-executor013:7562 blocked for more than 144 seconds.
[ 769.036488][ T1042] Not tainted 5.1.0-rc6+ #90
[ 769.041593][ T1042] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 769.050508][ T1042] syz-executor013 D29720 7562 7546 0x00000004
[ 769.057086][ T1042] Call Trace:
[ 769.060405][ T1042] __schedule+0x813/0x1cc0
[ 769.074730][ T1042] schedule+0x92/0x180
[ 769.079874][ T1042] schedule_preempt_disabled+0x13/0x20
[ 769.085567][ T1042] __mutex_lock+0x726/0x1310
[ 769.109790][ T1042] mutex_lock_nested+0x16/0x20
[ 769.119731][ T1042] mount_bdev+0x93/0x3c0
[ 769.134260][ T1042] udf_mount+0x35/0x40
[ 769.144069][ T1042] legacy_get_tree+0xf2/0x200
[ 769.155565][ T1042] vfs_get_tree+0x123/0x450
[ 769.160068][ T1042] do_mount+0x1436/0x2c40
[ 769.186353][ T1042] ksys_mount+0xdb/0x150
[ 769.190596][ T1042] __x64_sys_mount+0xbe/0x150
[ 769.195513][ T1042] do_syscall_64+0x103/0x610
[ 769.200101][ T1042] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 769.207055][ T1042] RIP: 0033:0x44a739
[ 769.210958][ T1042] Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
[ 769.230833][ T1042] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 769.239504][ T1042] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 769.247745][ T1042] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 769.255934][ T1042] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 769.263903][ T1042] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 769.272144][ T1042] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 769.280357][ T1042]
[ 769.280357][ T1042] Showing all locks held in the system:
[ 769.288320][ T1042] 1 lock held by khungtaskd/1042:
[ 769.293332][ T1042] #0: 000000006329251d (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e
[ 769.303003][ T1042] 1 lock held by rsyslogd/7425:
[ 769.308092][ T1042] #0: 00000000d77ddd95 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110
[ 769.316994][ T1042] 2 locks held by getty/7515:
[ 769.321658][ T1042] #0: 000000004c45b4e6 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.330857][ T1042] #1: 00000000cb1f5c30 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.341530][ T1042] 2 locks held by getty/7516:
[ 769.346430][ T1042] #0: 0000000061e5eac7 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.355629][ T1042] #1: 00000000aab03c35 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.365512][ T1042] 2 locks held by getty/7517:
[ 769.370176][ T1042] #0: 00000000205ee5b4 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.379359][ T1042] #1: 0000000002712bdb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.389227][ T1042] 2 locks held by getty/7518:
[ 769.393891][ T1042] #0: 000000000cc046b2 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.408494][ T1042] #1: 00000000d5140a4a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.418291][ T1042] 2 locks held by getty/7519:
[ 769.422966][ T1042] #0: 000000003624da6d (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.432226][ T1042] #1: 00000000f5b16893 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.442027][ T1042] 2 locks held by getty/7520:
[ 769.446931][ T1042] #0: 0000000082294f91 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.456132][ T1042] #1: 00000000870dfcb5 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.466699][ T1042] 2 locks held by getty/7521:
[ 769.472073][ T1042] #0: 000000000f72fa86 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
[ 769.481384][ T1042] #1: 00000000a044b2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
[ 769.491648][ T1042] 2 locks held by syz-executor013/7547:
[ 769.497458][ T1042] 1 lock held by syz-executor013/7561:
[ 769.502908][ T1042] #0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
[ 769.512707][ T1042] 1 lock held by syz-executor013/7563:
[ 769.518395][ T1042] #0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
[ 769.528048][ T1042] 2 locks held by syz-executor013/7559:
[ 769.533585][ T1042] #0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
[ 769.543245][ T1042] #1: 000000001ecfe564 (&type->s_umount_key#39){+.+.}, at: grab_super+0xb4/0x290
[ 769.552721][ T1042] 1 lock held by syz-executor013/7565:
[ 769.558422][ T1042] #0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
[ 769.568068][ T1042] 1 lock held by syz-executor013/7562:
[ 769.573513][ T1042] #0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
[ 769.583183][ T1042]
[ 769.585751][ T1042] =============================================
[ 769.585751][ T1042]
[ 769.594153][ T1042] NMI backtrace for cpu 1
[ 769.598535][ T1042] CPU: 1 PID: 1042 Comm: khungtaskd Not tainted 5.1.0-rc6+ #90
[ 769.606068][ T1042] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[ 769.616138][ T1042] Call Trace:
[ 769.619493][ T1042] dump_stack+0x172/0x1f0
[ 769.623825][ T1042] nmi_cpu_backtrace.cold+0x63/0xa4
[ 769.634703][ T1042] nmi_trigger_cpumask_backtrace+0x1be/0x236
[ 769.640680][ T1042] arch_trigger_cpumask_backtrace+0x14/0x20
[ 769.646575][ T1042] watchdog+0x9b7/0xec0
[ 769.650818][ T1042] kthread+0x357/0x430
[ 769.666665][ T1042] ret_from_fork+0x3a/0x50
[ 769.671151][ T1042] Sending NMI from CPU 1 to CPUs 0:
[ 769.677189][ C0] NMI backtrace for cpu 0
[ 769.677194][ C0] CPU: 0 PID: 7547 Comm: syz-executor013 Not tainted 5.1.0-rc6+ #90
[ 769.677200][ C0] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[ 769.677204][ C0] RIP: 0010:__sanitizer_cov_trace_pc+0x1/0x50
[ 769.677215][ C0] Code: 25 b4 7e ec 08 41 bc f4 ff ff ff e8 cd 5d ea ff 48 c7 05 9e 7e ec 08 00 00 00 00 e9 a4 e9 ff ff 90 90 90 90 90 90 90 90 90 55 <48> 89 e5 48 8b 75 08 65 48 8b 04 25 00 ee 01 00 65 8b 15 c8 60 91
[ 769.677219][ C0] RSP: 0018:ffff8880973ef470 EFLAGS: 00000246
[ 769.677226][ C0] RAX: 0000000000000000 RBX: ffff8880973ef568 RCX: ffffffff870d9383
[ 769.677231][ C0] RDX: 0000000000000002 RSI: 0000000000000002 RDI: 0000000000000007
[ 769.677235][ C0] RBP: ffff8880973ef4b0 R08: ffff88808c9fe080 R09: ffffed1015d05bc8
[ 769.677240][ C0] R10: ffffed1015d05bc7 R11: ffff8880ae82de3b R12: ffff8880a0dc9442
[ 769.677245][ C0] R13: ffff8880973ef580 R14: 0000000000000138 R15: ffff8880a0dc9442
[ 769.677250][ C0] FS: 00007f9541ef1700(0000) GS:ffff8880ae800000(0000) knlGS:0000000000000000
[ 769.677254][ C0] CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
[ 769.677258][ C0] CR2: 0000000000c02000 CR3: 00000000a9780000 CR4: 00000000001406f0
[ 769.677263][ C0] DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
[ 769.677268][ C0] DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
[ 769.677270][ C0] Call Trace:
[ 769.677276][ C0] xas_load+0x21/0x150
[ 769.677283][ C0] find_get_entry+0x13d/0x880
[ 769.677296][ C0] pagecache_get_page+0x4c/0x740
[ 769.677299][ C0] __find_get_block+0x501/0xcf0
[ 769.677306][ C0] __getblk_gfp+0x1b1/0x970
[ 769.677309][ C0] __bread_gfp+0x2f/0x300
[ 769.677312][ C0] udf_tread+0xf1/0x140
[ 769.677315][ C0] udf_read_tagged+0x50/0x530
[ 769.677318][ C0] udf_check_anchor_block+0x1ef/0x680
[ 769.677331][ C0] udf_scan_anchors+0x1cf/0x680
[ 769.677346][ C0] udf_load_vrs+0x8bf/0xc80
[ 769.677358][ C0] udf_fill_super+0x7d8/0x16d1
[ 769.677374][ C0] mount_bdev+0x307/0x3c0
[ 769.677379][ C0] udf_mount+0x35/0x40
[ 769.677385][ C0] legacy_get_tree+0xf2/0x200
[ 769.677391][ C0] vfs_get_tree+0x123/0x450
[ 769.677394][ C0] do_mount+0x1436/0x2c40
[ 769.677409][ C0] ksys_mount+0xdb/0x150
[ 769.677412][ C0] __x64_sys_mount+0xbe/0x150
[ 769.677415][ C0] do_syscall_64+0x103/0x610
[ 769.677418][ C0] entry_SYSCALL_64_after_hwframe+0x49/0xbe
[ 769.677421][ C0] RIP: 0033:0x44a739
[ 769.677438][ C0] Code: 4d cb fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 1b cb fb ff c3 66 2e 0f 1f 84 00 00 00 00
[ 769.677442][ C0] RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
[ 769.677454][ C0] RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
[ 769.677458][ C0] RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
[ 769.677462][ C0] RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
[ 769.677467][ C0] R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
[ 769.677471][ C0] R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
[ 769.680389][ T1042] Kernel panic - not syncing: hung_task: blocked tasks
[ 770.124041][ T1042] CPU: 1 PID: 1042 Comm: khungtaskd Not tainted 5.1.0-rc6+ #90
[ 770.131570][ T1042] Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
[ 770.141616][ T1042] Call Trace:
[ 770.144926][ T1042] dump_stack+0x172/0x1f0
[ 770.149350][ T1042] panic+0x2cb/0x65c
[ 770.193147][ T1042] watchdog+0x9c8/0xec0
[ 770.197301][ T1042] kthread+0x357/0x430
[ 770.213167][ T1042] ret_from_fork+0x3a/0x50
[ 770.219050][ T1042] Kernel Offset: disabled
[ 770.223376][ T1042] Rebooting in 86400 seconds..
REPORT:
INFO: task syz-executor013:7561 blocked for more than 143 seconds.
Not tainted 5.1.0-rc6+ #90
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor013 D29336 7561 7543 0x00000004
Call Trace:
__schedule+0x813/0x1cc0
schedule+0x92/0x180
schedule_preempt_disabled+0x13/0x20
__mutex_lock+0x726/0x1310
mutex_lock_nested+0x16/0x20
mount_bdev+0x93/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
INFO: task syz-executor013:7563 blocked for more than 143 seconds.
Not tainted 5.1.0-rc6+ #90
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor013 D29720 7563 7541 0x00000004
Call Trace:
__schedule+0x813/0x1cc0
schedule+0x92/0x180
schedule_preempt_disabled+0x13/0x20
__mutex_lock+0x726/0x1310
mutex_lock_nested+0x16/0x20
mount_bdev+0x93/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
INFO: task syz-executor013:7559 blocked for more than 143 seconds.
Not tainted 5.1.0-rc6+ #90
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor013 D29096 7559 7544 0x00000004
Call Trace:
__schedule+0x813/0x1cc0
schedule+0x92/0x180
rwsem_down_write_failed+0x774/0xc30
call_rwsem_down_write_failed+0x17/0x30
down_write+0x53/0x90
grab_super+0xb4/0x290
sget_userns+0x1ab/0x560
sget+0x10c/0x150
mount_bdev+0xff/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
INFO: task syz-executor013:7565 blocked for more than 144 seconds.
Not tainted 5.1.0-rc6+ #90
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor013 D29720 7565 7542 0x00000004
Call Trace:
__schedule+0x813/0x1cc0
schedule+0x92/0x180
schedule_preempt_disabled+0x13/0x20
__mutex_lock+0x726/0x1310
mutex_lock_nested+0x16/0x20
mount_bdev+0x93/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
INFO: task syz-executor013:7562 blocked for more than 144 seconds.
Not tainted 5.1.0-rc6+ #90
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
syz-executor013 D29720 7562 7546 0x00000004
Call Trace:
__schedule+0x813/0x1cc0
schedule+0x92/0x180
schedule_preempt_disabled+0x13/0x20
__mutex_lock+0x726/0x1310
mutex_lock_nested+0x16/0x20
mount_bdev+0x93/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 00 49 6e 76 61 6c 69 64 20 22 24 41 63 74 69 6f 6e 51 75 65 75 65 43 68 65 63 6b 70 6f 69 6e 74 49 6e 74 65 72 76 61 6c 22 2c <20> 65 72 72 6f 72 20 25 64 2e 20 49 67 6e 6f 72 65 64 2c 20 72 75
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000
Showing all locks held in the system:
1 lock held by khungtaskd/1042:
#0: 000000006329251d (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x27e
1 lock held by rsyslogd/7425:
#0: 00000000d77ddd95 (&f->f_pos_lock){+.+.}, at: __fdget_pos+0xee/0x110
2 locks held by getty/7515:
#0: 000000004c45b4e6 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000cb1f5c30 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7516:
#0: 0000000061e5eac7 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000aab03c35 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7517:
#0: 00000000205ee5b4 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 0000000002712bdb (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7518:
#0: 000000000cc046b2 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000d5140a4a (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7519:
#0: 000000003624da6d (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000f5b16893 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7520:
#0: 0000000082294f91 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000870dfcb5 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by getty/7521:
#0: 000000000f72fa86 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40
#1: 00000000a044b2e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x232/0x1b70
2 locks held by syz-executor013/7547:
1 lock held by syz-executor013/7561:
#0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
1 lock held by syz-executor013/7563:
#0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
2 locks held by syz-executor013/7559:
#0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
#1: 000000001ecfe564 (&type->s_umount_key#39){+.+.}, at: grab_super+0xb4/0x290
1 lock held by syz-executor013/7565:
#0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
1 lock held by syz-executor013/7562:
#0: 00000000d97cb347 (&bdev->bd_fsfreeze_mutex){+.+.}, at: mount_bdev+0x93/0x3c0
=============================================
NMI backtrace for cpu 1
CPU: 1 PID: 1042 Comm: khungtaskd Not tainted 5.1.0-rc6+ #90
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
Call Trace:
dump_stack+0x172/0x1f0
nmi_cpu_backtrace.cold+0x63/0xa4
nmi_trigger_cpumask_backtrace+0x1be/0x236
arch_trigger_cpumask_backtrace+0x14/0x20
watchdog+0x9b7/0xec0
kthread+0x357/0x430
ret_from_fork+0x3a/0x50
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 7547 Comm: syz-executor013 Not tainted 5.1.0-rc6+ #90
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011
RIP: 0010:__sanitizer_cov_trace_pc+0x1/0x50
Code: 25 b4 7e ec 08 41 bc f4 ff ff ff e8 cd 5d ea ff 48 c7 05 9e 7e ec 08 00 00 00 00 e9 a4 e9 ff ff 90 90 90 90 90 90 90 90 90 55 <48> 89 e5 48 8b 75 08 65 48 8b 04 25 00 ee 01 00 65 8b 15 c8 60 91
RSP: 0018:ffff8880973ef470 EFLAGS: 00000246
RAX: 0000000000000000 RBX: ffff8880973ef568 RCX: ffffffff870d9383
RDX: 0000000000000002 RSI: 0000000000000002 RDI: 0000000000000007
RBP: ffff8880973ef4b0 R08: ffff88808c9fe080 R09: ffffed1015d05bc8
R10: ffffed1015d05bc7 R11: ffff8880ae82de3b R12: ffff8880a0dc9442
R13: ffff8880973ef580 R14: 0000000000000138 R15: ffff8880a0dc9442
FS: 00007f9541ef1700(0000) GS:ffff8880ae800000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000000c02000 CR3: 00000000a9780000 CR4: 00000000001406f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
xas_load+0x21/0x150
find_get_entry+0x13d/0x880
pagecache_get_page+0x4c/0x740
__find_get_block+0x501/0xcf0
__getblk_gfp+0x1b1/0x970
__bread_gfp+0x2f/0x300
udf_tread+0xf1/0x140
udf_read_tagged+0x50/0x530
udf_check_anchor_block+0x1ef/0x680
udf_scan_anchors+0x1cf/0x680
udf_load_vrs+0x8bf/0xc80
udf_fill_super+0x7d8/0x16d1
mount_bdev+0x307/0x3c0
udf_mount+0x35/0x40
legacy_get_tree+0xf2/0x200
vfs_get_tree+0x123/0x450
do_mount+0x1436/0x2c40
ksys_mount+0xdb/0x150
__x64_sys_mount+0xbe/0x150
do_syscall_64+0x103/0x610
entry_SYSCALL_64_after_hwframe+0x49/0xbe
RIP: 0033:0x44a739
Code: 4d cb fb ff c3 66 2e 0f 1f 84 00 00 00 00 00 66 90 48 89 f8 48 89 f7 48 89 d6 48 89 ca 4d 89 c2 4d 89 c8 4c 8b 4c 24 08 0f 05 <48> 3d 01 f0 ff ff 0f 83 1b cb fb ff c3 66 2e 0f 1f 84 00 00 00 00
RSP: 002b:00007f9541ef0db8 EFLAGS: 00000246 ORIG_RAX: 00000000000000a5
RAX: ffffffffffffffda RBX: 00000000006dbc28 RCX: 000000000044a739
RDX: 0000000020000240 RSI: 0000000020000200 RDI: 0000000020000080
RBP: 00000000006dbc20 R08: 0000000000000000 R09: 0000000000000000
R10: 0000000000000000 R11: 0000000000000246 R12: 00000000006dbc2c
R13: 00007ffc6cf3cddf R14: 00007f9541ef19c0 R15: 0000000000000000