syzbot


INFO: task hung in console_callback (3)

Status: auto-obsoleted due to no activity on 2022/11/22 14:53
Subsystems: serial
[Documentation on labels]
First crash: 647d, last: 603d
Similar bugs (6)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
upstream INFO: task hung in console_callback serial 7 1381d 1548d 0/26 closed as dup on 2020/08/16 15:22
upstream INFO: task hung in console_callback (2) serial 1 774d 774d 0/26 auto-closed as invalid on 2022/06/05 01:15
linux-4.14 INFO: task hung in console_callback 1 1488d 1488d 0/1 auto-closed as invalid on 2020/07/20 15:40
linux-4.19 INFO: task hung in console_callback (2) 1 1245d 1245d 0/1 auto-closed as invalid on 2021/03/20 18:26
upstream INFO: task hung in console_callback (4) serial 65 201d 208d 0/26 auto-obsoleted due to no activity on 2023/12/09 22:03
linux-4.19 INFO: task hung in console_callback 1 1560d 1560d 0/1 auto-closed as invalid on 2020/05/10 04:25

Sample crash report:
INFO: task kworker/0:7:4388 blocked for more than 143 seconds.
      Not tainted 5.19.0-syzkaller-13666-gffcf9c5700e4 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:kworker/0:7     state:D stack:22400 pid: 4388 ppid:     2 flags:0x00004000
Workqueue: events console_callback

Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5182 [inline]
 __schedule+0x93f/0x26f0 kernel/sched/core.c:6494
 schedule+0xda/0x1b0 kernel/sched/core.c:6570
 schedule_timeout+0x1db/0x2a0 kernel/time/timer.c:1911
 ___down_common kernel/locking/semaphore.c:225 [inline]
 __down_common+0x341/0x780 kernel/locking/semaphore.c:246
 down+0x70/0x90 kernel/locking/semaphore.c:63
 console_lock+0x28/0x80 kernel/printk/printk.c:2568
 console_callback+0x5f/0x4c0 drivers/tty/vt/vt.c:2980
 process_one_work+0x991/0x1610 kernel/workqueue.c:2289
 worker_thread+0x665/0x1080 kernel/workqueue.c:2436
 kthread+0x2ea/0x3a0 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>

Showing all locks held in the system:
1 lock held by rcu_tasks_kthre/11:
 #0: ffffffff87a94410 (rcu_tasks.tasks_gp_mutex){+.+.}-{3:3}, at: rcu_tasks_one_gp+0x26/0xc70 kernel/rcu/tasks.h:507
6 locks held by kworker/0:1/12:
1 lock held by khungtaskd/26:
 #0: ffffffff87a94f60 (rcu_read_lock
){....}-{1:2}
, at: debug_show_all_locks+0x53/0x260 kernel/locking/lockdep.c:6492
2 locks held by getty/1240:
 #0: ffff88810a0e6098 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x22/0x80 drivers/tty/tty_ldisc.c:244
 #1: 
ffffc900000432f0
 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0xef0/0x13e0 drivers/tty/n_tty.c:2177
2 locks held by kworker/0:7/4388:
 #0: ffff888100064d38 ((wq_completion)events
){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:636 [inline]
){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:663 [inline]
){+.+.}-{0:0}, at: process_one_work+0x87a/0x1610 kernel/workqueue.c:2260
 #1: 
ffffc9000178fda8
 (console_work){+.+.}-{0:0}, at: process_one_work+0x8ae/0x1610 kernel/workqueue.c:2264
6 locks held by kworker/0:4/7730:
5 locks held by kworker/0:3/14936:
3 locks held by kworker/1:0/21814:
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic64_set arch/x86/include/asm/atomic64_64.h:34 [inline]
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: arch_atomic_long_set include/linux/atomic/atomic-long.h:41 [inline]
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: atomic_long_set include/linux/atomic/atomic-instrumented.h:1280 [inline]
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_data kernel/workqueue.c:636 [inline]
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: set_work_pool_and_clear_pending kernel/workqueue.c:663 [inline]
 #0: ffff888109aeb138 ((wq_completion)usb_hub_wq){+.+.}-{0:0}, at: process_one_work+0x87a/0x1610 kernel/workqueue.c:2260
 #1: 
ffffc9000ce9fda8 ((work_completion)(&hub->events)){+.+.}-{0:0}, at: process_one_work+0x8ae/0x1610 kernel/workqueue.c:2264
 #2: ffff88810f34f190 (&dev->mutex){....}-{3:3}, at: device_lock include/linux/device.h:835 [inline]
 #2: ffff88810f34f190 (&dev->mutex){....}-{3:3}, at: hub_event+0x1bc/0x4610 drivers/usb/core/hub.c:5681
3 locks held by kworker/0:10/26098:

=============================================

NMI backtrace for cpu 0
CPU: 0 PID: 26 Comm: khungtaskd Not tainted 5.19.0-syzkaller-13666-gffcf9c5700e4 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0xcd/0x134 lib/dump_stack.c:106
 nmi_cpu_backtrace.cold+0x46/0x14f lib/nmi_backtrace.c:111
 nmi_trigger_cpumask_backtrace+0x206/0x250 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:148 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:212 [inline]
 watchdog+0xc18/0xf50 kernel/hung_task.c:369
 kthread+0x2ea/0x3a0 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>
Sending NMI from CPU 0 to CPUs 1:
NMI backtrace for cpu 1
CPU: 1 PID: 31154 Comm: kworker/1:6 Not tainted 5.19.0-syzkaller-13666-gffcf9c5700e4 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 07/22/2022
Workqueue: events legacy_dvb_usb_read_remote_control
RIP: 0010:write_comp_data+0x7/0x90 kernel/kcov.c:223
Code: ff 00 75 10 65 48 8b 04 25 c0 6e 02 00 48 8b 80 b0 14 00 00 c3 66 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 65 8b 05 f9 69 bc 7e <49> 89 f1 89 c6 49 89 d2 81 e6 00 01 00 00 49 89 f8 65 48 8b 14 25
RSP: 0018:ffffc9000e087568 EFLAGS: 00000206
RAX: 0000000000000001 RBX: 000000000000000a RCX: ffffffff8228190a
RDX: 0000000000000009 RSI: 0000000000000012 RDI: 0000000000000001
RBP: ffffffff86647280 R08: 0000000000000001 R09: 0000000000000011
R10: 0000000000000009 R11: 0000000000000000 R12: 0000000000000009
R13: 0000000000000001 R14: 000000000000000a R15: ffffc9000e0876a0
FS:  0000000000000000(0000) GS:ffff8881f6900000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007fe58d74cfd8 CR3: 000000013360a000 CR4: 00000000003506e0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <TASK>
 __sanitizer_cov_trace_switch+0x50/0x90 kernel/kcov.c:333
 vsnprintf+0x8ca/0x14f0 lib/vsprintf.c:2751
 sprintf+0xc0/0x100 lib/vsprintf.c:2986
 print_time kernel/printk/printk.c:1277 [inline]
 info_print_prefix+0x248/0x340 kernel/printk/printk.c:1303
 record_print_text+0x14d/0x3e0 kernel/printk/printk.c:1352
 console_emit_next_record.constprop.0+0x6bf/0x840 kernel/printk/printk.c:2715
 console_flush_all kernel/printk/printk.c:2794 [inline]
 console_unlock+0x37a/0x5a0 kernel/printk/printk.c:2861
 vprintk_emit+0x1b9/0x5f0 kernel/printk/printk.c:2271
 vprintk+0x80/0x90 kernel/printk/printk_safe.c:50
 _printk+0xba/0xed kernel/printk/printk.c:2292
 legacy_dvb_usb_read_remote_control.cold+0x11/0x16 drivers/media/usb/dvb-usb/dvb-usb-remote.c:124
 process_one_work+0x991/0x1610 kernel/workqueue.c:2289
 worker_thread+0x665/0x1080 kernel/workqueue.c:2436
 kthread+0x2ea/0x3a0 kernel/kthread.c:376
 ret_from_fork+0x1f/0x30 arch/x86/entry/entry_64.S:306
 </TASK>

Crashes (11):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2022/08/24 14:49 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing ffcf9c5700e4 514514f6 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/08/24 14:46 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing ffcf9c5700e4 514514f6 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/08/19 21:14 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing ffcf9c5700e4 26a13b38 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/08/11 19:49 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing ffcf9c5700e4 787ed7e0 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/08/07 03:12 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing b2a88c212e65 88e3a122 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/08/06 02:49 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing b2a88c212e65 e853abd9 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/07/31 23:48 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 8288c99fc263 fef302b1 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/07/25 16:02 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing d5e22360e907 664c519c .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/07/23 01:14 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 32f02a211b0a 22343af4 .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/07/20 05:24 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 32f02a211b0a 775344bc .config console log report info ci2-upstream-usb INFO: task hung in console_callback
2022/07/11 10:10 https://git.kernel.org/pub/scm/linux/kernel/git/gregkh/usb.git usb-testing 8affe37c525d f3f217ff .config console log report info ci2-upstream-usb INFO: task hung in console_callback
* Struck through repros no longer work on HEAD.