ci starts bisection 2022-12-28 07:56:15.929522594 +0000 UTC m=+54926.472228182
bisecting fixing commit since 6e764bcd1cf72a2846c0e53d3975a09b242c04c9
building syzkaller on b599f2fcc734e2183016a340d4f6fc2891d8e41f
ensuring issue is reproducible on original commit 6e764bcd1cf72a2846c0e53d3975a09b242c04c9
testing commit 6e764bcd1cf72a2846c0e53d3975a09b242c04c9 gcc
compiler: gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 4b13c99e4588d7697076ea73ea5619c15fccaf6251611bc480256e36d37efef5
all runs: crashed: INFO: task hung in pn533_finalize_setup
testing current HEAD 1b929c02afd37871d5afb9d498426f83432e71c2
testing commit 1b929c02afd37871d5afb9d498426f83432e71c2 gcc
compiler: gcc (Debian 10.2.1-6) 10.2.1 20210110, GNU ld (GNU Binutils for Debian) 2.35.2
kernel signature: 4a76de60b393f625124757b02e0bb76c23fb0cc888c26c3fc76e8dae9bae904a
run #0: basic kernel testing failed: BUG: program execution failed: executor NUM: EOF
run #1: basic kernel testing failed: BUG: program execution failed: executor NUM: EOF
run #2: basic kernel testing failed: BUG: program execution failed: executor NUM: EOF
run #3: crashed: INFO: task hung in pn533_finalize_setup
run #4: crashed: INFO: task hung in pn533_finalize_setup
run #5: crashed: INFO: task hung in pn533_finalize_setup
run #6: crashed: INFO: task hung in pn533_finalize_setup
run #7: crashed: INFO: task hung in pn533_finalize_setup
run #8: crashed: INFO: task hung in pn533_finalize_setup
run #9: crashed: INFO: task hung in pn533_finalize_setup
revisions tested: 2, total time: 30m42.326120059s (build: 17m41.593788093s, test: 11m39.46501667s)
the crash still happens on HEAD
commit msg: Linux 6.2-rc1
crash: INFO: task hung in pn533_finalize_setup
INFO: task kworker/0:0:7 blocked for more than 143 seconds.
Not tainted 6.2.0-rc1-syzkaller #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/0:0 state:D stack:25448 pid:7 ppid:2 flags:0x00004000
Workqueue: usb_hub_wq hub_event
Call Trace:
context_switch kernel/sched/core.c:5244 [inline]
__schedule+0x9f7/0x2890 kernel/sched/core.c:6555
schedule+0xde/0x1b0 kernel/sched/core.c:6631
schedule_timeout+0x1a3/0x260 kernel/time/timer.c:2143
do_wait_for_common kernel/sched/completion.c:85 [inline]
__wait_for_common+0x1ce/0x5c0 kernel/sched/completion.c:106
pn533_send_cmd_sync drivers/nfc/pn533/pn533.c:631 [inline]
pn533_get_firmware_version drivers/nfc/pn533/pn533.c:2523 [inline]
pn533_finalize_setup+0xf0/0x140 drivers/nfc/pn533/pn533.c:2720
pn533_usb_probe+0x84a/0xf10 drivers/nfc/pn533/usb.c:544
usb_probe_interface+0x278/0x6a0 drivers/usb/core/driver.c:396
call_driver_probe drivers/base/dd.c:560 [inline]
really_probe+0x5a6/0xa50 drivers/base/dd.c:639
__driver_probe_device+0x186/0x460 drivers/base/dd.c:778
driver_probe_device+0x44/0x110 drivers/base/dd.c:808
__device_attach_driver+0x14e/0x270 drivers/base/dd.c:936
bus_for_each_drv+0x122/0x1a0 drivers/base/bus.c:427
__device_attach+0x19e/0x440 drivers/base/dd.c:1008
bus_probe_device+0x1a1/0x250 drivers/base/bus.c:487
device_add+0xa18/0x1b90 drivers/base/core.c:3479
usb_set_configuration+0xa05/0x18a0 drivers/usb/core/message.c:2171
usb_generic_driver_probe+0x78/0xa0 drivers/usb/core/generic.c:238
usb_probe_device+0x98/0x240 drivers/usb/core/driver.c:293
call_driver_probe drivers/base/dd.c:560 [inline]
really_probe+0x5a6/0xa50 drivers/base/dd.c:639
__driver_probe_device+0x186/0x460 drivers/base/dd.c:778
driver_probe_device+0x44/0x110 drivers/base/dd.c:808
__device_attach_driver+0x14e/0x270 drivers/base/dd.c:936
bus_for_each_drv+0x122/0x1a0 drivers/base/bus.c:427
__device_attach+0x19e/0x440 drivers/base/dd.c:1008
bus_probe_device+0x1a1/0x250 drivers/base/bus.c:487
device_add+0xa18/0x1b90 drivers/base/core.c:3479
usb_new_device.cold+0x600/0xf02 drivers/usb/core/hub.c:2573
hub_port_connect drivers/usb/core/hub.c:5405 [inline]
hub_port_connect_change drivers/usb/core/hub.c:5549 [inline]
port_event drivers/usb/core/hub.c:5709 [inline]
hub_event+0x2450/0x3ce0 drivers/usb/core/hub.c:5791
process_one_work+0x8ba/0x14c0 kernel/workqueue.c:2289
worker_thread+0x59c/0xec0 kernel/workqueue.c:2436
kthread+0x298/0x340 kernel/kthread.c:376
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:308
Showing all locks held in the system:
5 locks held by kworker/0:0/7:
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:636 [inline]
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:663 [inline]
#0: ffff888015b80d38 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x7a0/0x14c0 kernel/workqueue.c:2260
#1: ffffc900000c7db8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x7cf/0x14c0 kernel/workqueue.c:2264
#2: ffff88801f3fa190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:831 [inline]
#2: ffff88801f3fa190 (&dev->mutex){....}-{3:3}, at: hub_event+0x129/0x3ce0 drivers/usb/core/hub.c:5737
#3: ffff88807cc1e190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:831 [inline]
#3: ffff88807cc1e190 (&dev->mutex){....}-{3:3}, at: __device_attach+0x71/0x440 drivers/base/dd.c:983
#4: ffff88807ba7b118 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:831 [inline]
#4: ffff88807ba7b118 (&dev->mutex){....}-{3:3}, at: __device_attach+0x71/0x440 drivers/base/dd.c:983
1 lock held by rcu_tasks_kthre/12:
#0: ffffffff8b17dbd0 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x26/0xc70 kernel/rcu/tasks.h:507
1 lock held by rcu_tasks_trace/13:
#0: ffffffff8b17d8d0 (rcu_tasks_trace.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x26/0xc70 kernel/rcu/tasks.h:507
1 lock held by khungtaskd/28:
#0: ffffffff8b17e720 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x57/0x264 kernel/locking/lockdep.c:6494
1 lock held by syslogd/4405:
#0: ffff8880b9e3b4d8 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x22/0x30 kernel/sched/core.c:537
2 locks held by getty/4736:
#0: ffff888028dc0098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x23/0x80 drivers/tty/tty_ldisc.c:244
#1: ffffc900015902f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xb73/0x1040 drivers/tty/n_tty.c:2177
3 locks held by udevd/5457:
#0: ffff88807c7a7488 (&of->mutex){+.+.}-{3:3}, at: kernfs_file_read_iter fs/kernfs/file.c:240 [inline]
#0: ffff88807c7a7488 (&of->mutex){+.+.}-{3:3}, at: kernfs_fop_read_iter+0x163/0x6e0 fs/kernfs/file.c:280
#1: ffff88801b774bd0 (kn->active#27){.+.+}-{0:0}, at: kernfs_file_read_iter fs/kernfs/file.c:241 [inline]
#1: ffff88801b774bd0 (kn->active#27){.+.+}-{0:0}, at: kernfs_fop_read_iter+0x186/0x6e0 fs/kernfs/file.c:280
#2: ffff88807cc1e190 (&dev->mutex){....}-{3:3}, at: device_lock_interruptible include/linux/device.h:836 [inline]
#2: ffff88807cc1e190 (&dev->mutex){....}-{3:3}, at: read_descriptors+0x3a/0x310 drivers/usb/core/sysfs.c:874
=============================================
NMI backtrace for cpu 0
CPU: 0 PID: 28 Comm: khungtaskd Not tainted 6.2.0-rc1-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
Call Trace:
__dump_stack lib/dump_stack.c:88 [inline]
dump_stack_lvl+0x5b/0x81 lib/dump_stack.c:106
nmi_cpu_backtrace.cold+0x18/0x106 lib/nmi_backtrace.c:111
nmi_trigger_cpumask_backtrace+0x259/0x2d0 lib/nmi_backtrace.c:62
trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
check_hung_uninterruptible_tasks kernel/hung_task.c:220 [inline]
watchdog+0x8c8/0xc60 kernel/hung_task.c:377
kthread+0x298/0x340 kernel/kthread.c:376
ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:308
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 5537 Comm: syz-executor.0 Not tainted 6.2.0-rc1-syzkaller #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 10/26/2022
RIP: 0010:lock_is_held_type+0x108/0x140 kernel/locking/lockdep.c:5716
Code: ff ff 65 0f c1 05 30 ef 30 77 83 f8 01 75 29 9c 58 f6 c4 02 75 3d 48 f7 04 24 00 02 00 00 74 01 fb 48 83 c4 08 44 89 e8 5b 5d <41> 5c 41 5d 41 5e 41 5f c3 45 31 ed eb b9 0f 0b 48 c7 c7 40 49 0b
RSP: 0018:ffffc90004d27ea0 EFLAGS: 00000096
RAX: 0000000000000000 RBX: 0000000000000003 RCX: 0000000000000001
RDX: 0000000000000000 RSI: ffffffff890b4ca0 RDI: ffffffff896389a0
RBP: 0000000000000001 R08: 0000000000000000 R09: ffffffff8cf06a57
R10: fffffbfff19e0d4a R11: 0000000000000001 R12: ffff88807d01b980
R13: 0000000000000000 R14: 00000000ffffffff R15: 0000000000000000
FS: 0000000002b69400(0000) GS:ffff8880b9f00000(0000) knlGS:0000000000000000
CS: 0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 0000000002b69708 CR3: 0000000075d72000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
lock_is_held include/linux/lockdep.h:283 [inline]
rcu_read_lock_sched_held+0x3e/0x70 kernel/rcu/update.c:125
trace_user_exit.constprop.0+0x141/0x1d0 include/trace/events/context_tracking.h:48
__ct_user_exit+0xb9/0x100 kernel/context_tracking.c:623
user_exit_irqoff include/linux/context_tracking.h:48 [inline]
__enter_from_user_mode kernel/entry/common.c:25 [inline]
syscall_enter_from_user_mode+0x52/0xb0 kernel/entry/common.c:108
do_syscall_64+0x1a/0x80 arch/x86/entry/common.c:76
entry_SYSCALL_64_after_hwframe+0x63/0xcd
RIP: 0033:0x48a811
Code: 24 0c 89 3c 24 48 89 4c 24 18 e8 aa e7 ff ff 4c 8b 54 24 18 48 8b 54 24 10 41 89 c0 8b 74 24 0c 8b 3c 24 b8 e6 00 00 00 0f 05 <44> 89 c7 48 89 04 24 e8 e3 e7 ff ff 48 8b 04 24 eb 97 66 2e 0f 1f
RSP: 002b:00007ffcad1197a0 EFLAGS: 00000293 ORIG_RAX: 00000000000000e6
RAX: ffffffffffffffda RBX: 000000000000035d RCX: 000000000048a811
RDX: 00007ffcad1197e0 RSI: 0000000000000000 RDI: 0000000000000000
RBP: 00007ffcad11987c R08: 0000000000000000 R09: 00007ffcad195080
R10: 0000000000000000 R11: 0000000000000293 R12: 0000000000000032
R13: 000000000003ca9d R14: 0000000000000003 R15: 00007ffcad1198e0