INFO: task syz-executor.1:14473 can't die for more than 143 seconds. syz-executor.1 R running task 24568 14473 9662 0x00004006 Call Trace: context_switch kernel/sched/core.c:3385 [inline] __schedule+0x934/0x1f90 kernel/sched/core.c:4081 INFO: task syz-executor.1:14597 can't die for more than 145 seconds. syz-executor.1 D29712 14597 9662 0x00000004 Call Trace: context_switch kernel/sched/core.c:3385 [inline] __schedule+0x934/0x1f90 kernel/sched/core.c:4081 schedule+0xdc/0x2b0 kernel/sched/core.c:4155 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:4214 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x7ab/0x13c0 kernel/locking/mutex.c:1103 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1118 blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 blkdev_ioctl+0xc3/0x670 block/ioctl.c:710 block_ioctl+0xee/0x130 fs/block_dev.c:1983 vfs_ioctl fs/ioctl.c:47 [inline] ksys_ioctl+0x123/0x180 fs/ioctl.c:747 __do_sys_ioctl fs/ioctl.c:756 [inline] __se_sys_ioctl fs/ioctl.c:754 [inline] __x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:754 do_syscall_64+0xfa/0x790 arch/x86/entry/common.c:294 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45a919 Code: Bad RIP value. RSP: 002b:00007f4cc09adc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a919 RDX: 0000000020000080 RSI: 00000000c0481273 RDI: 0000000000000008 RBP: 000000000075c268 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f4cc09ae6d4 R13: 00000000004c22d9 R14: 00000000004d74a8 R15: 00000000ffffffff INFO: task syz-executor.1:14597 blocked for more than 146 seconds. Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.1 D29712 14597 9662 0x00000004 Call Trace: context_switch kernel/sched/core.c:3385 [inline] __schedule+0x934/0x1f90 kernel/sched/core.c:4081 schedule+0xdc/0x2b0 kernel/sched/core.c:4155 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:4214 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x7ab/0x13c0 kernel/locking/mutex.c:1103 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1118 blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 blkdev_ioctl+0xc3/0x670 block/ioctl.c:710 block_ioctl+0xee/0x130 fs/block_dev.c:1983 vfs_ioctl fs/ioctl.c:47 [inline] ksys_ioctl+0x123/0x180 fs/ioctl.c:747 __do_sys_ioctl fs/ioctl.c:756 [inline] __se_sys_ioctl fs/ioctl.c:754 [inline] __x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:754 do_syscall_64+0xfa/0x790 arch/x86/entry/common.c:294 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45a919 Code: Bad RIP value. RSP: 002b:00007f4cc09adc78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a919 RDX: 0000000020000080 RSI: 00000000c0481273 RDI: 0000000000000008 RBP: 000000000075c268 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007f4cc09ae6d4 R13: 00000000004c22d9 R14: 00000000004d74a8 R15: 00000000ffffffff INFO: task syz-executor.4:14922 can't die for more than 147 seconds. syz-executor.4 D28160 14922 15046 0x00004004 Call Trace: context_switch kernel/sched/core.c:3385 [inline] __schedule+0x934/0x1f90 kernel/sched/core.c:4081 schedule+0xdc/0x2b0 kernel/sched/core.c:4155 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:4214 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x7ab/0x13c0 kernel/locking/mutex.c:1103 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1118 blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 blkdev_ioctl+0xc3/0x670 block/ioctl.c:710 block_ioctl+0xee/0x130 fs/block_dev.c:1983 vfs_ioctl fs/ioctl.c:47 [inline] ksys_ioctl+0x123/0x180 fs/ioctl.c:747 __do_sys_ioctl fs/ioctl.c:756 [inline] __se_sys_ioctl fs/ioctl.c:754 [inline] __x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:754 do_syscall_64+0xfa/0x790 arch/x86/entry/common.c:294 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45a919 Code: Bad RIP value. RSP: 002b:00007fd15093ac78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a919 RDX: 0000000020000080 RSI: 00000000c0481273 RDI: 0000000000000008 RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007fd15093b6d4 R13: 00000000004c22d9 R14: 00000000004d74a8 R15: 00000000ffffffff INFO: task syz-executor.4:14922 blocked for more than 147 seconds. Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. syz-executor.4 D28160 14922 15046 0x00004004 Call Trace: context_switch kernel/sched/core.c:3385 [inline] __schedule+0x934/0x1f90 kernel/sched/core.c:4081 schedule+0xdc/0x2b0 kernel/sched/core.c:4155 schedule_preempt_disabled+0x13/0x20 kernel/sched/core.c:4214 __mutex_lock_common kernel/locking/mutex.c:1033 [inline] __mutex_lock+0x7ab/0x13c0 kernel/locking/mutex.c:1103 mutex_lock_nested+0x16/0x20 kernel/locking/mutex.c:1118 blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 blkdev_ioctl+0xc3/0x670 block/ioctl.c:710 block_ioctl+0xee/0x130 fs/block_dev.c:1983 vfs_ioctl fs/ioctl.c:47 [inline] ksys_ioctl+0x123/0x180 fs/ioctl.c:747 __do_sys_ioctl fs/ioctl.c:756 [inline] __se_sys_ioctl fs/ioctl.c:754 [inline] __x64_sys_ioctl+0x73/0xb0 fs/ioctl.c:754 do_syscall_64+0xfa/0x790 arch/x86/entry/common.c:294 entry_SYSCALL_64_after_hwframe+0x49/0xbe RIP: 0033:0x45a919 Code: Bad RIP value. RSP: 002b:00007fd15093ac78 EFLAGS: 00000246 ORIG_RAX: 0000000000000010 RAX: ffffffffffffffda RBX: 0000000000000003 RCX: 000000000045a919 RDX: 0000000020000080 RSI: 00000000c0481273 RDI: 0000000000000008 RBP: 000000000075bfc8 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 00007fd15093b6d4 R13: 00000000004c22d9 R14: 00000000004d74a8 R15: 00000000ffffffff Showing all locks held in the system: 3 locks held by init/1: 1 lock held by khungtaskd/1115: #0: ffffffff899af980 (rcu_read_lock){....}, at: debug_show_all_locks+0x5f/0x279 kernel/locking/lockdep.c:5334 3 locks held by rsyslogd/9529: 2 locks held by cron/9569: 2 locks held by getty/9619: #0: ffff888095cf5090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019b32e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9620: #0: ffff88809ea09090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019d32e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9621: #0: ffff8880a6a17090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019532e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9622: #0: ffff88808ded7090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019432e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9623: #0: ffff8880a482e090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019c32e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9624: #0: ffff888093113090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900019932e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by getty/9625: #0: ffff8880a8d5f090 (&tty->ldisc_sem){++++}, at: ldsem_down_read+0x33/0x40 drivers/tty/tty_ldsem.c:340 #1: ffffc900011552e0 (&ldata->atomic_read_lock){+.+.}, at: n_tty_read+0x220/0x1bf0 drivers/tty/n_tty.c:2156 2 locks held by syz-fuzzer/9643: 2 locks held by syz-fuzzer/9657: 3 locks held by syz-executor.1/14473: 1 lock held by syz-executor.1/14597: #0: ffff8880a21ff638 (&q->blk_trace_mutex){+.+.}, at: blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 1 lock held by syz-executor.4/14922: #0: ffff8880a21ff638 (&q->blk_trace_mutex){+.+.}, at: blk_trace_ioctl+0xe3/0x300 kernel/trace/blktrace.c:704 ============================================= NMI backtrace for cpu 1 CPU: 1 PID: 1115 Comm: khungtaskd Not tainted 5.5.0-rc2-next-20191220-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+0x197/0x210 lib/dump_stack.c:118 nmi_cpu_backtrace.cold+0x70/0xb2 lib/nmi_backtrace.c:101 nmi_trigger_cpumask_backtrace+0x23b/0x28b lib/nmi_backtrace.c:62 arch_trigger_cpumask_backtrace+0x14/0x20 arch/x86/kernel/apic/hw_nmi.c:38 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:249 [inline] watchdog+0xdf1/0x14e0 kernel/hung_task.c:333 kthread+0x361/0x430 kernel/kthread.c:255 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352 Sending NMI from CPU 1 to CPUs 0: NMI backtrace for cpu 0 CPU: 0 PID: 26842 Comm: kworker/0:3 Not tainted 5.5.0-rc2-next-20191220-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Workqueue: events nsim_dev_trap_report_work RIP: 0010:__kasan_check_read+0x12/0x20 mm/kasan/common.c:96 Code: 48 c7 c7 38 34 41 89 f7 da e8 7e 79 ab ff e9 3e ef ff ff cc cc cc cc cc 55 89 f6 31 d2 48 89 e5 48 8b 4d 08 e8 1f 2a 00 00 5d 0f 1f 00 66 2e 0f 1f 84 00 00 00 00 00 55 89 f6 ba 01 00 00 00 RSP: 0000:ffffc90003ca7528 EFLAGS: 00000046 RAX: 0000000000000001 RBX: dffffc0000000000 RCX: ffffffff81aa3e0b RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffff88812fffbd20 RBP: ffffc90003ca76d8 R08: 1ffff11025fff7a4 R09: ffffed1025fff7a5 R10: ffffed1025fff7a4 R11: ffff88812fffbd27 R12: 0000000000002342 R13: 0000000000000002 R14: 0000000000000d25 R15: ffff88812fffbd20 FS: 0000000000000000(0000) GS:ffff8880ae800000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 000000000045a8ef CR3: 00000000a399c000 CR4: 00000000001406f0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __alloc_pages_slowpath+0x34e/0x2900 mm/page_alloc.c:4455 __alloc_pages_nodemask+0x646/0x910 mm/page_alloc.c:4762 __alloc_pages include/linux/gfp.h:496 [inline] __alloc_pages_node include/linux/gfp.h:509 [inline] kmem_getpages mm/slab.c:1367 [inline] cache_grow_begin+0x90/0xc60 mm/slab.c:2600 fallback_alloc+0x1f8/0x2d0 mm/slab.c:3147 ____cache_alloc_node+0x1bc/0x1d0 mm/slab.c:3215 slab_alloc_node mm/slab.c:3255 [inline] kmem_cache_alloc_node_trace+0xec/0x750 mm/slab.c:3593 __do_kmalloc_node mm/slab.c:3615 [inline] __kmalloc_node_track_caller+0x3d/0x70 mm/slab.c:3630 __kmalloc_reserve.isra.0+0x40/0xf0 net/core/skbuff.c:141 __alloc_skb+0x10b/0x5e0 net/core/skbuff.c:209 alloc_skb include/linux/skbuff.h:1049 [inline] nsim_dev_trap_skb_build drivers/net/netdevsim/dev.c:321 [inline] nsim_dev_trap_report drivers/net/netdevsim/dev.c:373 [inline] nsim_dev_trap_report_work+0x25c/0xaf0 drivers/net/netdevsim/dev.c:412 process_one_work+0x9af/0x1740 kernel/workqueue.c:2264 worker_thread+0x98/0xe40 kernel/workqueue.c:2410 kthread+0x361/0x430 kernel/kthread.c:255 ret_from_fork+0x24/0x30 arch/x86/entry/entry_64.S:352