bisecting fixing commit since fd0aa1a4567d0f09e1bfe367a950b004f99ac290 building syzkaller on aba2b2fb3544d9e42991237c13d8cada421deda5 testing commit fd0aa1a4567d0f09e1bfe367a950b004f99ac290 with gcc (GCC) 10.2.1 20210217 kernel signature: 62efb46c9d0ab0513c6f273e98c9fa3723ed533dcb1ad099698b6453aab49907 run #0: crashed: INFO: task hung in port100_probe run #1: crashed: INFO: task hung in port100_probe run #2: crashed: INFO: task hung in misc_open run #3: crashed: INFO: task hung in port100_probe run #4: crashed: INFO: task hung in port100_probe run #5: crashed: INFO: task hung in port100_probe run #6: crashed: INFO: task hung in misc_open run #7: crashed: INFO: task hung in port100_probe run #8: crashed: INFO: task hung in port100_probe run #9: crashed: INFO: task hung in misc_open run #10: crashed: INFO: task hung in port100_probe run #11: crashed: INFO: task hung in port100_probe run #12: crashed: INFO: task hung in port100_probe run #13: crashed: INFO: task hung in port100_probe run #14: crashed: INFO: task hung in port100_probe run #15: crashed: INFO: task hung in port100_probe run #16: crashed: INFO: task hung in port100_probe run #17: crashed: INFO: task hung in port100_probe run #18: crashed: INFO: task hung in port100_probe run #19: crashed: INFO: task hung in port100_probe testing current HEAD 1d67c8d993baf8ab6be8a2154b1a94ec1311c869 testing commit 1d67c8d993baf8ab6be8a2154b1a94ec1311c869 with gcc (GCC) 10.2.1 20210217 kernel signature: 7b06f4016e62fb1f1577f1ee9ac0739f0e58d07041d24ed7fb3b411aa5c66418 run #0: crashed: INFO: task hung in port100_probe run #1: crashed: INFO: task hung in port100_probe run #2: crashed: INFO: task hung in port100_probe run #3: crashed: INFO: task hung in port100_probe run #4: crashed: INFO: task hung in port100_probe run #5: crashed: INFO: task hung in misc_open run #6: crashed: INFO: task hung in misc_open run #7: crashed: INFO: task hung in port100_probe run #8: crashed: INFO: task hung in port100_probe run #9: crashed: INFO: task hung in port100_probe revisions tested: 2, total time: 28m11.189791699s (build: 14m45.641492225s, test: 12m36.562486299s) the crash still happens on HEAD commit msg: Merge tag 'soc-fixes-5.14-1' of git://git.kernel.org/pub/scm/linux/kernel/git/soc/soc crash: INFO: task hung in port100_probe INFO: task kworker/0:2:3173 blocked for more than 143 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:kworker/0:2 state:D stack:26472 pid: 3173 ppid: 2 flags:0x00004000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_timeout+0x19d/0x250 kernel/time/timer.c:1854 do_wait_for_common kernel/sched/completion.c:85 [inline] __wait_for_common kernel/sched/completion.c:106 [inline] wait_for_common kernel/sched/completion.c:117 [inline] wait_for_completion+0x176/0x280 kernel/sched/completion.c:138 port100_send_cmd_sync drivers/nfc/port100.c:923 [inline] port100_get_command_type_mask drivers/nfc/port100.c:1008 [inline] port100_probe+0x8eb/0x1180 drivers/nfc/port100.c:1554 usb_probe_interface+0x274/0x6a0 drivers/usb/core/driver.c:396 call_driver_probe drivers/base/dd.c:517 [inline] really_probe+0x1b9/0xb70 drivers/base/dd.c:595 __driver_probe_device+0x2a6/0x460 drivers/base/dd.c:747 driver_probe_device+0x44/0x110 drivers/base/dd.c:777 __device_attach_driver+0x185/0x250 drivers/base/dd.c:894 bus_for_each_drv+0x11e/0x1a0 drivers/base/bus.c:427 __device_attach+0x1db/0x410 drivers/base/dd.c:965 bus_probe_device+0x19d/0x250 drivers/base/bus.c:487 device_add+0xa2f/0x1e00 drivers/base/core.c:3352 usb_set_configuration+0x9fc/0x1750 drivers/usb/core/message.c:2170 usb_generic_driver_probe+0x74/0xa0 drivers/usb/core/generic.c:238 usb_probe_device+0x95/0x240 drivers/usb/core/driver.c:293 call_driver_probe drivers/base/dd.c:517 [inline] really_probe+0x1b9/0xb70 drivers/base/dd.c:595 __driver_probe_device+0x2a6/0x460 drivers/base/dd.c:747 driver_probe_device+0x44/0x110 drivers/base/dd.c:777 __device_attach_driver+0x185/0x250 drivers/base/dd.c:894 bus_for_each_drv+0x11e/0x1a0 drivers/base/bus.c:427 __device_attach+0x1db/0x410 drivers/base/dd.c:965 bus_probe_device+0x19d/0x250 drivers/base/bus.c:487 device_add+0xa2f/0x1e00 drivers/base/core.c:3352 usb_new_device.cold+0x5cf/0xedf drivers/usb/core/hub.c:2559 hub_port_connect drivers/usb/core/hub.c:5300 [inline] hub_port_connect_change drivers/usb/core/hub.c:5440 [inline] port_event drivers/usb/core/hub.c:5586 [inline] hub_event+0x10a3/0x36c0 drivers/usb/core/hub.c:5668 process_one_work+0x84c/0x13d0 kernel/workqueue.c:2276 worker_thread+0x598/0x1040 kernel/workqueue.c:2422 kthread+0x38b/0x460 kernel/kthread.c:319 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295 INFO: task kworker/1:7:10143 blocked for more than 143 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:kworker/1:7 state:D stack:26304 pid:10143 ppid: 2 flags:0x00004000 Workqueue: usb_hub_wq hub_event Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_timeout+0x19d/0x250 kernel/time/timer.c:1854 do_wait_for_common kernel/sched/completion.c:85 [inline] __wait_for_common kernel/sched/completion.c:106 [inline] wait_for_common kernel/sched/completion.c:117 [inline] wait_for_completion+0x176/0x280 kernel/sched/completion.c:138 port100_send_cmd_sync drivers/nfc/port100.c:923 [inline] port100_get_command_type_mask drivers/nfc/port100.c:1008 [inline] port100_probe+0x8eb/0x1180 drivers/nfc/port100.c:1554 usb_probe_interface+0x274/0x6a0 drivers/usb/core/driver.c:396 call_driver_probe drivers/base/dd.c:517 [inline] really_probe+0x1b9/0xb70 drivers/base/dd.c:595 __driver_probe_device+0x2a6/0x460 drivers/base/dd.c:747 driver_probe_device+0x44/0x110 drivers/base/dd.c:777 __device_attach_driver+0x185/0x250 drivers/base/dd.c:894 bus_for_each_drv+0x11e/0x1a0 drivers/base/bus.c:427 __device_attach+0x1db/0x410 drivers/base/dd.c:965 bus_probe_device+0x19d/0x250 drivers/base/bus.c:487 device_add+0xa2f/0x1e00 drivers/base/core.c:3352 usb_set_configuration+0x9fc/0x1750 drivers/usb/core/message.c:2170 usb_generic_driver_probe+0x74/0xa0 drivers/usb/core/generic.c:238 usb_probe_device+0x95/0x240 drivers/usb/core/driver.c:293 call_driver_probe drivers/base/dd.c:517 [inline] really_probe+0x1b9/0xb70 drivers/base/dd.c:595 __driver_probe_device+0x2a6/0x460 drivers/base/dd.c:747 driver_probe_device+0x44/0x110 drivers/base/dd.c:777 __device_attach_driver+0x185/0x250 drivers/base/dd.c:894 bus_for_each_drv+0x11e/0x1a0 drivers/base/bus.c:427 __device_attach+0x1db/0x410 drivers/base/dd.c:965 bus_probe_device+0x19d/0x250 drivers/base/bus.c:487 device_add+0xa2f/0x1e00 drivers/base/core.c:3352 usb_new_device.cold+0x5cf/0xedf drivers/usb/core/hub.c:2559 hub_port_connect drivers/usb/core/hub.c:5300 [inline] hub_port_connect_change drivers/usb/core/hub.c:5440 [inline] port_event drivers/usb/core/hub.c:5586 [inline] hub_event+0x10a3/0x36c0 drivers/usb/core/hub.c:5668 process_one_work+0x84c/0x13d0 kernel/workqueue.c:2276 worker_thread+0x598/0x1040 kernel/workqueue.c:2422 kthread+0x38b/0x460 kernel/kthread.c:319 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295 INFO: task syz-executor.0:10331 blocked for more than 144 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.0 state:D stack:28472 pid:10331 ppid: 8863 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007f51e4230040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 0000000020000140 R15: 0000000000022000 INFO: task syz-executor.3:10240 blocked for more than 144 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.3 state:D stack:27600 pid:10240 ppid: 8856 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4665d9 RSP: 002b:00007f22bba17188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 RDX: 0000000000020601 RSI: 00000000200003c0 RDI: ffffffffffffff9c RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 R13: 00007ffec8cb8f0f R14: 00007f22bba17300 R15: 0000000000022000 INFO: task syz-executor.3:10343 blocked for more than 144 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.3 state:D stack:28512 pid:10343 ppid: 8856 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007f22bb9d3040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056c0f0 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 0000000020000140 R15: 0000000000022000 INFO: task syz-executor.5:10278 blocked for more than 145 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.5 state:D stack:27600 pid:10278 ppid: 8860 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4665d9 RSP: 002b:00007f74b8a59188 EFLAGS: 00000246 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004665d9 RDX: 0000000000020601 RSI: 00000000200003c0 RDI: ffffffffffffff9c RBP: 00000000004bfcb9 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000246 R12: 000000000056bf80 R13: 00007ffcb8baff7f R14: 00007f74b8a59300 R15: 0000000000022000 INFO: task syz-executor.5:10346 blocked for more than 145 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.5 state:D stack:28512 pid:10346 ppid: 8860 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007f74b89f4040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056c1a8 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 0000000020000140 R15: 0000000000022000 INFO: task syz-executor.4:10291 blocked for more than 145 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.4 state:D stack:27600 pid:10291 ppid: 8862 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007faff20b8040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 00000000200000c0 R15: 0000000000022000 INFO: task syz-executor.2:10293 blocked for more than 146 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.2 state:D stack:27600 pid:10293 ppid: 8861 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007f0e75afb040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 00000000200000c0 R15: 0000000000022000 INFO: task syz-executor.1:10296 blocked for more than 146 seconds. Not tainted 5.14.0-rc1-syzkaller #0 "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. task:syz-executor.1 state:D stack:27600 pid:10296 ppid: 8855 flags:0x00000004 Call Trace: context_switch kernel/sched/core.c:4683 [inline] __schedule+0x907/0x26b0 kernel/sched/core.c:5940 schedule+0xd3/0x270 kernel/sched/core.c:6019 schedule_preempt_disabled+0xf/0x20 kernel/sched/core.c:6078 __mutex_lock_common kernel/locking/mutex.c:1036 [inline] __mutex_lock+0x7b6/0x10a0 kernel/locking/mutex.c:1104 misc_open+0x50/0x410 drivers/char/misc.c:107 chrdev_open+0x20f/0x650 fs/char_dev.c:414 do_dentry_open+0x42a/0xfc0 fs/open.c:826 do_open fs/namei.c:3374 [inline] path_openat+0x9ec/0x22c0 fs/namei.c:3507 do_filp_open+0x199/0x3d0 fs/namei.c:3534 do_sys_openat2+0x11e/0x360 fs/open.c:1204 do_sys_open fs/open.c:1220 [inline] __do_sys_openat fs/open.c:1236 [inline] __se_sys_openat fs/open.c:1231 [inline] __x64_sys_openat+0x11b/0x1d0 fs/open.c:1231 do_syscall_x64 arch/x86/entry/common.c:50 [inline] do_syscall_64+0x35/0xb0 arch/x86/entry/common.c:80 entry_SYSCALL_64_after_hwframe+0x44/0xae RIP: 0033:0x4196c4 RSP: 002b:00007f9a27d8f040 EFLAGS: 00000293 ORIG_RAX: 0000000000000101 RAX: ffffffffffffffda RBX: 000000000056bf80 RCX: 00000000004196c4 RDX: 0000000000000002 RSI: 00000000004bea81 RDI: 00000000ffffff9c RBP: 00000000004bea81 R08: 0000000000000000 R09: 0000000000000000 R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000002 R13: 0000000000000000 R14: 00000000200000c0 R15: 0000000000022000 Showing all locks held in the system: 2 locks held by kworker/u4:3/121: #0: ffff8880b9f35a58 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested kernel/sched/core.c:460 [inline] #0: ffff8880b9f35a58 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock kernel/sched/sched.h:1307 [inline] #0: ffff8880b9f35a58 (&rq->__lock){-.-.}-{2:2}, at: rq_lock kernel/sched/sched.h:1610 [inline] #0: ffff8880b9f35a58 (&rq->__lock){-.-.}-{2:2}, at: __schedule+0x233/0x26b0 kernel/sched/core.c:5854 #1: ffff8880b9f1f988 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x39d/0x480 kernel/sched/psi.c:880 1 lock held by khungtaskd/1657: #0: ffffffff8ab72040 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6446 5 locks held by kworker/0:2/3173: #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x771/0x13d0 kernel/workqueue.c:2247 #1: ffffc9000305fdb8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x79e/0x13d0 kernel/workqueue.c:2251 #2: ffff888146a6d220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #2: ffff888146a6d220 (&dev->mutex){....}-{3:3}, at: hub_event+0x127/0x36c0 drivers/usb/core/hub.c:5614 #3: ffff888036843220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #3: ffff888036843220 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 #4: ffff888029cd31a8 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #4: ffff888029cd31a8 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 1 lock held by in:imklog/8151: #0: ffff88802061e5f0 (&f->f_pos_lock){+.+.}-{3:3}, at: __fdget_pos+0x9c/0xb0 fs/file.c:974 5 locks held by kworker/1:7/10143: #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x771/0x13d0 kernel/workqueue.c:2247 #1: ffffc9000ad77db8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x79e/0x13d0 kernel/workqueue.c:2251 #2: ffff888020a35220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #2: ffff888020a35220 (&dev->mutex){....}-{3:3}, at: hub_event+0x127/0x36c0 drivers/usb/core/hub.c:5614 #3: ffff888017ba1220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #3: ffff888017ba1220 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 #4: ffff88802d4f01a8 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #4: ffff88802d4f01a8 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 2 locks held by syz-executor.0/10215: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 #1: ffffffff8aa42ba8 (system_transition_mutex){+.+.}-{3:3}, at: snapshot_open+0x1c/0x210 kernel/power/user.c:54 1 lock held by syz-executor.0/10331: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.3/10240: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.3/10343: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.5/10278: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.5/10346: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.4/10291: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.2/10293: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 1 lock held by syz-executor.1/10296: #0: ffffffff8b56e7c8 (misc_mtx){+.+.}-{3:3}, at: misc_open+0x50/0x410 drivers/char/misc.c:107 5 locks held by kworker/1:9/10302: #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic64_set include/asm-generic/atomic-instrumented.h:620 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/asm-generic/atomic-long.h:41 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:617 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:644 [inline] #0: ffff888141596d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x771/0x13d0 kernel/workqueue.c:2247 #1: ffffc9000af67db8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x79e/0x13d0 kernel/workqueue.c:2251 #2: ffff8881469bd220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #2: ffff8881469bd220 (&dev->mutex){....}-{3:3}, at: hub_event+0x127/0x36c0 drivers/usb/core/hub.c:5614 #3: ffff8880368c9220 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #3: ffff8880368c9220 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 #4: ffff88802dc8f1a8 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:754 [inline] #4: ffff88802dc8f1a8 (&dev->mutex){....}-{3:3}, at: __device_attach+0x74/0x410 drivers/base/dd.c:940 ============================================= NMI backtrace for cpu 0 CPU: 0 PID: 1657 Comm: khungtaskd Not tainted 5.14.0-rc1-syzkaller #0 Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/01/2011 Call Trace: __dump_stack lib/dump_stack.c:88 [inline] dump_stack_lvl+0x57/0x7d lib/dump_stack.c:105 nmi_cpu_backtrace.cold+0x30/0x99 lib/nmi_backtrace.c:105 nmi_trigger_cpumask_backtrace+0x11f/0x170 lib/nmi_backtrace.c:62 trigger_all_cpu_backtrace include/linux/nmi.h:146 [inline] check_hung_uninterruptible_tasks kernel/hung_task.c:210 [inline] watchdog+0x92e/0xc40 kernel/hung_task.c:295 kthread+0x38b/0x460 kernel/kthread.c:319 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295 Sending NMI from CPU 0 to CPUs 1: NMI backtrace for cpu 1 CPU: 1 PID: 10133 Comm: kworker/1:6 Not tainted 5.14.0-rc1-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:match_held_lock+0xe/0xc0 kernel/locking/lockdep.c:5077 Code: 48 c7 c7 00 29 cb 88 e8 d7 b5 c0 ff e8 8b 43 cf ff 31 c0 5d c3 0f 1f 80 00 00 00 00 53 48 89 fb 48 83 ec 08 48 39 77 10 74 6a <66> f7 47 22 f0 ff 74 5a 48 8b 46 08 48 89 f7 48 85 c0 74 42 8b 15 RSP: 0018:ffffc9000ad47878 EFLAGS: 00000087 RAX: 000000000000000f RBX: ffff88801a835e68 RCX: 0000000000000001 RDX: 0000000000000000 RSI: ffffffff8ab71f80 RDI: ffff88801a835e68 RBP: ffffffff8ab71f80 R08: 0000000000000000 R09: ffffffff8c7cc217 R10: fffffbfff18f9842 R11: 000000000007a088 R12: ffff88801a835400 R13: ffff88801a835df0 R14: 00000000ffffffff R15: ffff88801a835e68 FS: 0000000000000000(0000) GS:ffff8880b9f00000(0000) knlGS:0000000000000000 CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033 CR2: 00007fb338965028 CR3: 0000000035894000 CR4: 00000000001506e0 DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000 DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400 Call Trace: __lock_is_held kernel/locking/lockdep.c:5368 [inline] lock_is_held_type+0xa7/0x140 kernel/locking/lockdep.c:5668 lock_is_held include/linux/lockdep.h:283 [inline] rcu_read_lock_sched_held+0x3a/0x70 kernel/rcu/update.c:125 trace_lock_acquire include/trace/events/lock.h:13 [inline] lock_acquire+0x442/0x510 kernel/locking/lockdep.c:5596 __raw_spin_lock include/linux/spinlock_api_smp.h:142 [inline] _raw_spin_lock+0x2a/0x40 kernel/locking/spinlock.c:151 spin_lock include/linux/spinlock.h:354 [inline] get_partial_node.part.0+0x42/0x330 mm/slub.c:2037 get_partial_node mm/slub.c:2034 [inline] get_partial mm/slub.c:2142 [inline] new_slab_objects mm/slub.c:2632 [inline] ___slab_alloc+0x3bc/0x820 mm/slub.c:2800 __slab_alloc.constprop.0+0xa7/0xf0 mm/slub.c:2840 slab_alloc_node mm/slub.c:2922 [inline] __kmalloc_node_track_caller+0x2e3/0x360 mm/slub.c:4648 kmalloc_reserve net/core/skbuff.c:355 [inline] __alloc_skb+0x8b/0x280 net/core/skbuff.c:426 alloc_skb include/linux/skbuff.h:1112 [inline] nsim_dev_trap_skb_build drivers/net/netdevsim/dev.c:664 [inline] nsim_dev_trap_report drivers/net/netdevsim/dev.c:721 [inline] nsim_dev_trap_report_work+0x275/0xb50 drivers/net/netdevsim/dev.c:762 process_one_work+0x84c/0x13d0 kernel/workqueue.c:2276 worker_thread+0x598/0x1040 kernel/workqueue.c:2422 kthread+0x38b/0x460 kernel/kthread.c:319 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:295