syzbot


INFO: task hung in cec_claim_log_addrs

Status: upstream: reported C repro on 2024/02/21 06:13
Subsystems: media
[Documentation on labels]
Reported-by: syzbot+116b65a23bc791ae49a6@syzkaller.appspotmail.com
First crash: 59d, last: 8d17h
Cause bisection: the issue happens on the oldest tested release (bisect log)
Crash: INFO: task hung in cec_claim_log_addrs (log)
Repro: C syz .config
  
Discussions (4)
Title Replies (including bot) Last reply
[syzbot] Monthly media report (Apr 2024) 0 (1) 2024/04/13 14:40
[syzbot] Monthly media report (Mar 2024) 0 (1) 2024/03/13 13:39
[PATCH] media/cec/core: fix task hung in cec_claim_log_addrs 5 (5) 2024/02/22 12:16
[syzbot] [media?] INFO: task hung in cec_claim_log_addrs 3 (7) 2024/02/22 05:30
Similar bugs (1)
Kernel Title Repro Cause bisect Fix bisect Count Last Reported Patched Status
linux-5.15 INFO: task hung in cec_claim_log_addrs 1 72d 72d 0/3 upstream: reported on 2024/02/03 23:37
Last patch testing requests (7)
Created Duration User Patch Repo Result
2024/04/08 03:15 21m retest repro git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci report log
2024/04/08 03:15 22m retest repro git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci report log
2024/04/08 03:15 23m retest repro git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci report log
2024/03/12 23:52 24m retest repro upstream report log
2024/02/22 04:58 26m hdanton@sina.com patch https://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci OK log
2024/02/21 13:13 25m eadavis@qq.com patch https://git.kernel.org/pub/scm/linux/kernel/git/torvalds/linux.git master OK log
2024/02/21 12:29 20m hdanton@sina.com patch https://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci report log
Cause bisection attempts (2)
Created Duration User Patch Repo Result
2024/03/07 09:49 4h08m bisect upstream job log (0) log
2024/02/28 11:25 48m bisect upstream error job log (0)

Sample crash report:
INFO: task syz-executor198:5885 blocked for more than 143 seconds.
      Not tainted 6.8.0-rc6-syzkaller-00011-g45ec2f5f6ed3 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor198 state:D stack:25136 pid:5885  tgid:5885  ppid:5088   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5400 [inline]
 __schedule+0x17d1/0x49f0 kernel/sched/core.c:6727
 __schedule_loop kernel/sched/core.c:6802 [inline]
 schedule+0x149/0x260 kernel/sched/core.c:6817
 schedule_timeout+0xb0/0x310 kernel/time/timer.c:2159
 do_wait_for_common kernel/sched/completion.c:95 [inline]
 __wait_for_common kernel/sched/completion.c:116 [inline]
 wait_for_common kernel/sched/completion.c:127 [inline]
 wait_for_completion+0x354/0x620 kernel/sched/completion.c:148
 cec_claim_log_addrs+0x193/0x260 drivers/media/cec/core/cec-adap.c:1606
 __cec_s_log_addrs+0x1699/0x1de0 drivers/media/cec/core/cec-adap.c:1920
 cec_adap_s_log_addrs drivers/media/cec/core/cec-api.c:184 [inline]
 cec_ioctl+0x223c/0x3330 drivers/media/cec/core/cec-api.c:528
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:871 [inline]
 __se_sys_ioctl+0xfc/0x170 fs/ioctl.c:857
 do_syscall_64+0xf9/0x240
 entry_SYSCALL_64_after_hwframe+0x6f/0x77
RIP: 0033:0x7f526b32ae69
RSP: 002b:00007ffe955888a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f526b32ae69
RDX: 0000000020000040 RSI: 00000000c05c6104 RDI: 0000000000000003
RBP: 00000000000f4240 R08: 00236365632f7665 R09: 00000000000000a0
R10: 000000000000000f R11: 0000000000000246 R12: 0000000000049146
R13: 00007ffe955888bc R14: 00007ffe955888d0 R15: 00007ffe955888c0
 </TASK>
INFO: task syz-executor198:11321 blocked for more than 143 seconds.
      Not tainted 6.8.0-rc6-syzkaller-00011-g45ec2f5f6ed3 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor198 state:D stack:26968 pid:11321 tgid:11321 ppid:5092   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5400 [inline]
 __schedule+0x17d1/0x49f0 kernel/sched/core.c:6727
 __schedule_loop kernel/sched/core.c:6802 [inline]
 schedule+0x149/0x260 kernel/sched/core.c:6817
 schedule_timeout+0xb0/0x310 kernel/time/timer.c:2159
 do_wait_for_common kernel/sched/completion.c:95 [inline]
 __wait_for_common kernel/sched/completion.c:116 [inline]
 wait_for_common kernel/sched/completion.c:127 [inline]
 wait_for_completion+0x354/0x620 kernel/sched/completion.c:148
 cec_claim_log_addrs+0x193/0x260 drivers/media/cec/core/cec-adap.c:1606
 __cec_s_log_addrs+0x1699/0x1de0 drivers/media/cec/core/cec-adap.c:1920
 cec_adap_s_log_addrs drivers/media/cec/core/cec-api.c:184 [inline]
 cec_ioctl+0x223c/0x3330 drivers/media/cec/core/cec-api.c:528
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:871 [inline]
 __se_sys_ioctl+0xfc/0x170 fs/ioctl.c:857
 do_syscall_64+0xf9/0x240
 entry_SYSCALL_64_after_hwframe+0x6f/0x77
RIP: 0033:0x7f526b32ae69
RSP: 002b:00007ffe955888a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f526b32ae69
RDX: 0000000020000040 RSI: 00000000c05c6104 RDI: 0000000000000003
RBP: 00000000000f4240 R08: 00236365632f7665 R09: 00000000000000a0
R10: 000000000000000f R11: 0000000000000246 R12: 000000000004d213
R13: 00007ffe955888bc R14: 00007ffe955888d0 R15: 00007ffe955888c0
 </TASK>
INFO: task syz-executor198:15810 blocked for more than 144 seconds.
      Not tainted 6.8.0-rc6-syzkaller-00011-g45ec2f5f6ed3 #0
"echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
task:syz-executor198 state:D stack:26160 pid:15810 tgid:15810 ppid:5090   flags:0x00004006
Call Trace:
 <TASK>
 context_switch kernel/sched/core.c:5400 [inline]
 __schedule+0x17d1/0x49f0 kernel/sched/core.c:6727
 __schedule_loop kernel/sched/core.c:6802 [inline]
 schedule+0x149/0x260 kernel/sched/core.c:6817
 schedule_timeout+0xb0/0x310 kernel/time/timer.c:2159
 do_wait_for_common kernel/sched/completion.c:95 [inline]
 __wait_for_common kernel/sched/completion.c:116 [inline]
 wait_for_common kernel/sched/completion.c:127 [inline]
 wait_for_completion+0x354/0x620 kernel/sched/completion.c:148
 cec_claim_log_addrs+0x193/0x260 drivers/media/cec/core/cec-adap.c:1606
 __cec_s_log_addrs+0x1699/0x1de0 drivers/media/cec/core/cec-adap.c:1920
 cec_adap_s_log_addrs drivers/media/cec/core/cec-api.c:184 [inline]
 cec_ioctl+0x223c/0x3330 drivers/media/cec/core/cec-api.c:528
 vfs_ioctl fs/ioctl.c:51 [inline]
 __do_sys_ioctl fs/ioctl.c:871 [inline]
 __se_sys_ioctl+0xfc/0x170 fs/ioctl.c:857
 do_syscall_64+0xf9/0x240
 entry_SYSCALL_64_after_hwframe+0x6f/0x77
RIP: 0033:0x7f526b32ae69
RSP: 002b:00007ffe955888a8 EFLAGS: 00000246 ORIG_RAX: 0000000000000010
RAX: ffffffffffffffda RBX: 0000000000000000 RCX: 00007f526b32ae69
RDX: 0000000020000040 RSI: 00000000c05c6104 RDI: 0000000000000003
RBP: 00000000000f4240 R08: 00236365632f7665 R09: 00000000000000a0
R10: 000000000000000f R11: 0000000000000246 R12: 00000000000504ac
R13: 00007ffe955888bc R14: 00007ffe955888d0 R15: 00007ffe955888c0
 </TASK>

Showing all locks held in the system:
2 locks held by ksoftirqd/1/22:
 #0: ffff8880b953c958 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 kernel/sched/core.c:559
 #1: ffff8880b9528988 (&per_cpu_ptr(group->pcpu, cpu)->seq){-.-.}-{0:0}, at: psi_task_switch+0x441/0x770 kernel/sched/psi.c:988
1 lock held by khungtaskd/29:
 #0: ffffffff8e130be0 (rcu_read_lock){....}-{1:2}, at: rcu_lock_acquire include/linux/rcupdate.h:298 [inline]
 #0: ffffffff8e130be0 (rcu_read_lock){....}-{1:2}, at: rcu_read_lock include/linux/rcupdate.h:750 [inline]
 #0: ffffffff8e130be0 (rcu_read_lock){....}-{1:2}, at: debug_show_all_locks+0x55/0x2a0 kernel/locking/lockdep.c:6614
2 locks held by getty/4827:
 #0: ffff88802afed0a0 (&tty->ldisc_sem){++++}-{0:0}, at: tty_ldisc_ref_wait+0x25/0x70 drivers/tty/tty_ldisc.c:243
 #1: ffffc90002f062f0 (&ldata->atomic_read_lock){+.+.}-{3:3}, at: n_tty_read+0x6b4/0x1e10 drivers/tty/n_tty.c:2201
1 lock held by syz-executor198/11013:
 #0: ffff8880b953c958 (&rq->__lock){-.-.}-{2:2}, at: raw_spin_rq_lock_nested+0x2a/0x140 kernel/sched/core.c:559

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

NMI backtrace for cpu 1
CPU: 1 PID: 29 Comm: khungtaskd Not tainted 6.8.0-rc6-syzkaller-00011-g45ec2f5f6ed3 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/25/2024
Call Trace:
 <TASK>
 __dump_stack lib/dump_stack.c:88 [inline]
 dump_stack_lvl+0x1e7/0x2e0 lib/dump_stack.c:106
 nmi_cpu_backtrace+0x49c/0x4d0 lib/nmi_backtrace.c:113
 nmi_trigger_cpumask_backtrace+0x198/0x320 lib/nmi_backtrace.c:62
 trigger_all_cpu_backtrace include/linux/nmi.h:160 [inline]
 check_hung_uninterruptible_tasks kernel/hung_task.c:222 [inline]
 watchdog+0xfaf/0xff0 kernel/hung_task.c:379
 kthread+0x2ef/0x390 kernel/kthread.c:388
 ret_from_fork+0x4b/0x80 arch/x86/kernel/process.c:147
 ret_from_fork_asm+0x1b/0x30 arch/x86/entry/entry_64.S:243
 </TASK>
Sending NMI from CPU 1 to CPUs 0:
NMI backtrace for cpu 0
CPU: 0 PID: 5087 Comm: syz-executor198 Not tainted 6.8.0-rc6-syzkaller-00011-g45ec2f5f6ed3 #0
Hardware name: Google Google Compute Engine/Google Compute Engine, BIOS Google 01/25/2024
RIP: 0010:__switch_to_asm+0x5a/0x70 arch/x86/entry/entry_64.S:208
Code: 00 00 00 e8 01 00 00 00 cc e8 01 00 00 00 cc 48 83 c4 10 49 ff cc 75 eb 0f ae e8 65 48 c7 04 25 10 bf 03 00 ff ff ff ff 41 5f <41> 5e 41 5d 41 5c 5b 5d e9 79 5e 31 00 66 0f 1f 84 00 00 00 00 00
RSP: 0018:ffffc9000313f830 EFLAGS: 00000046
RAX: 1ffff1101728792a RBX: 3d37e8c7d8682900 RCX: dffffc0000000000
RDX: 0000000000000001 RSI: ffff888073e71dc0 RDI: ffff88801e115940
RBP: ffffc90003aaf8b0 R08: ffffffff8f8568af R09: 1ffffffff1f0ad15
R10: dffffc0000000000 R11: fffffbfff1f0ad16 R12: 0000000000000000
R13: 0000607f4681d9e0 R14: dffffc0000000000 R15: ffff888073f14c00
FS:  0000555556d3f380(0000) GS:ffff8880b9400000(0000) knlGS:0000000000000000
CS:  0010 DS: 0000 ES: 0000 CR0: 0000000080050033
CR2: 00007f526b3a5210 CR3: 000000002dddc000 CR4: 00000000003506f0
DR0: 0000000000000000 DR1: 0000000000000000 DR2: 0000000000000000
DR3: 0000000000000000 DR6: 00000000fffe0ff0 DR7: 0000000000000400
Call Trace:
 <NMI>
 </NMI>

Crashes (6):
Time Kernel Commit Syzkaller Config Log Report Syz repro C repro VM info Assets (help?) Manager Title
2024/02/27 23:39 upstream 45ec2f5f6ed3 05e69c83 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: task hung in cec_claim_log_addrs
2024/02/23 03:03 git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci 9abbc24128bc 8d446f15 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-gce-arm64 INFO: task hung in cec_claim_log_addrs
2024/02/20 11:09 git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci 83d49ede4b18 3af7dd65 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-gce-arm64 INFO: task hung in cec_claim_log_addrs
2024/02/17 06:05 git://git.kernel.org/pub/scm/linux/kernel/git/arm64/linux.git for-kernelci 905b00721763 578f7538 .config console log report syz C [disk image] [vmlinux] [kernel image] ci-upstream-gce-arm64 INFO: task hung in cec_claim_log_addrs
2024/03/24 12:09 upstream 480e035fc4c7 0ea90952 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-kasan-gce INFO: task hung in cec_claim_log_addrs
2024/02/18 04:53 linux-next 2c3b09aac00d 578f7538 .config console log report info [disk image] [vmlinux] [kernel image] ci-upstream-linux-next-kasan-gce-root INFO: task hung in cec_claim_log_addrs
* Struck through repros no longer work on HEAD.