linux-kernel - Re: [PATCH v2] kprobes: Use dedicated kthread for kprobe optimizer

lists.openwall.net		lists / announce owl-users owl-dev john-users john-dev passwdqc-users yescrypt popa3d-users / oss-security kernel-hardening musl sabotage tlsify passwords / crypt-dev xvendor / Bugtraq Full-Disclosure linux-kernel linux-netdev linux-ext4 linux-hardening linux-cve-announce PHC
Open Source and information security mailing list archives
Hash Suite: Windows password security audit tool. GUI, reports in PDF.
[<prev] [next>] [<thread-prev] [day] [month] [year] [list]
Message-ID: <202601191507.74fccd0c-lkp@intel.com>
Date: Mon, 19 Jan 2026 15:29:58 +0800
From: kernel test robot <oliver.sang@...el.com>
To: "Masami Hiramatsu (Google)" <mhiramat@...nel.org>
CC: <oe-lkp@...ts.linux.dev>, <lkp@...el.com>, Steven Rostedt
	<rostedt@...dmis.org>, <linux-kernel@...r.kernel.org>,
	<linux-trace-kernel@...r.kernel.org>, Naveen N Rao <naveen@...nel.org>,
	"David S . Miller" <davem@...emloft.net>, Masami Hiramatsu
	<mhiramat@...nel.org>, <oliver.sang@...el.com>
Subject: Re: [PATCH v2] kprobes: Use dedicated kthread for kprobe optimizer



Hello,

kernel test robot noticed "INFO:task_blocked_for_more_than#seconds" on:

commit: 62f65c2531ad66e84c1a5ef91389322357ef6db4 ("[PATCH v2] kprobes: Use dedicated kthread for kprobe optimizer")
url: https://github.com/intel-lab-lkp/linux/commits/Masami-Hiramatsu-Google/kprobes-Use-dedicated-kthread-for-kprobe-optimizer/20260113-094928
base: https://git.kernel.org/cgit/linux/kernel/git/trace/linux-trace for-next
patch link: https://lore.kernel.org/all/176826884613.429923.16578111751623731056.stgit@devnote2/
patch subject: [PATCH v2] kprobes: Use dedicated kthread for kprobe optimizer

in testcase: trinity
version: trinity-x86_64-294c4652-1_20251011
with following parameters:

	runtime: 300s
	group: group-03
	nr_groups: 5



config: x86_64-kexec
compiler: clang-20
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 32G

(please refer to attached dmesg/kmsg for entire log/backtrace)


+-----------------------------------------+------------+------------+
|                                         | 78a419b44e | 62f65c2531 |
+-----------------------------------------+------------+------------+
| INFO:task_blocked_for_more_than#seconds | 0          | 6          |
+-----------------------------------------+------------+------------+


If you fix the issue in a separate patch/commit (i.e. not just a new version of
the same patch/commit), kindly add following tags
| Reported-by: kernel test robot <oliver.sang@...el.com>
| Closes: https://lore.kernel.org/oe-lkp/202601191507.74fccd0c-lkp@intel.com



[  972.359932][   T32] INFO: task kprobe-optimize:18 blocked for more than 491 seconds.
[  972.361221][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[  972.362516][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[  972.364322][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[  972.366628][   T32] Call Trace:
[  972.367378][   T32]  <TASK>
[  972.368066][   T32]  __schedule (kernel/sched/core.c:5259)
[  972.370837][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[  972.371679][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[  972.373219][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[  972.374017][   T32]  kthread (kernel/kthread.c:465)
[  972.374573][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[  972.375364][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[  972.376005][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[  972.376679][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[  972.377305][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[  972.377958][   T32]  </TASK>
[ 1463.879952][   T32] INFO: task kprobe-optimize:18 blocked for more than 983 seconds.
[ 1463.882239][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 1463.884371][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1463.887443][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 1463.891867][   T32] Call Trace:
[ 1463.893396][   T32]  <TASK>
[ 1463.894468][   T32]  __schedule (kernel/sched/core.c:5259)
[ 1463.903984][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 1463.904926][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 1463.905949][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 1463.907111][   T32]  kthread (kernel/kthread.c:465)
[ 1463.907954][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 1463.909157][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 1463.910045][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 1463.910939][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 1463.911933][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 1463.912947][   T32]  </TASK>
[ 1955.399991][   T32] INFO: task kprobe-optimize:18 blocked for more than 1474 seconds.
[ 1955.404266][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 1955.407452][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 1955.414557][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 1955.416961][   T32] Call Trace:
[ 1955.417705][   T32]  <TASK>
[ 1955.418382][   T32]  __schedule (kernel/sched/core.c:5259)
[ 1955.419274][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 1955.419929][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 1955.421071][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 1955.422303][   T32]  kthread (kernel/kthread.c:465)
[ 1955.423217][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 1955.424452][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 1955.425189][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 1955.425913][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 1955.426645][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 1955.427357][   T32]  </TASK>
[ 2446.919964][   T32] INFO: task kprobe-optimize:18 blocked for more than 1966 seconds.
[ 2446.922105][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 2446.925697][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2446.926569][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 2446.927775][   T32] Call Trace:
[ 2446.928242][   T32]  <TASK>
[ 2446.928609][   T32]  __schedule (kernel/sched/core.c:5259)
[ 2446.929827][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 2446.930256][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 2446.930789][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 2446.931434][   T32]  kthread (kernel/kthread.c:465)
[ 2446.931926][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 2446.932624][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 2446.933079][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 2446.933534][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 2446.933983][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 2446.934450][   T32]  </TASK>
[ 2938.439916][   T32] INFO: task kprobe-optimize:18 blocked for more than 2457 seconds.
[ 2938.441278][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 2938.442281][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 2938.444599][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 2938.446316][   T32] Call Trace:
[ 2938.446892][   T32]  <TASK>
[ 2938.447449][   T32]  __schedule (kernel/sched/core.c:5259)
[ 2938.449286][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 2938.450230][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 2938.451347][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 2938.452611][   T32]  kthread (kernel/kthread.c:465)
[ 2938.453462][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 2938.454622][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 2938.455583][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 2938.456604][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 2938.457614][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 2938.458645][   T32]  </TASK>
[ 3429.960057][   T32] INFO: task kprobe-optimize:18 blocked for more than 2949 seconds.
[ 3429.962853][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 3429.965133][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3429.967986][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 3429.971731][   T32] Call Trace:
[ 3429.973021][   T32]  <TASK>
[ 3429.974169][   T32]  __schedule (kernel/sched/core.c:5259)
[ 3429.975713][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 3429.977206][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 3429.978375][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 3429.979623][   T32]  kthread (kernel/kthread.c:465)
[ 3429.980602][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 3429.981810][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 3429.982797][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 3429.983793][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 3429.984838][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 3429.985864][   T32]  </TASK>
[ 3921.480064][   T32] INFO: task kprobe-optimize:18 blocked for more than 3440 seconds.
[ 3921.482942][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 3921.485260][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 3921.488269][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 3921.491811][   T32] Call Trace:
[ 3921.493488][   T32]  <TASK>
[ 3921.494960][   T32]  __schedule (kernel/sched/core.c:5259)
[ 3921.508616][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 3921.509430][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 3921.510413][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 3921.511467][   T32]  kthread (kernel/kthread.c:465)
[ 3921.512331][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 3921.513388][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 3921.514202][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 3921.514976][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 3921.515773][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 3921.516817][   T32]  </TASK>
[ 4413.000017][   T32] INFO: task kprobe-optimize:18 blocked for more than 3932 seconds.
[ 4413.004653][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 4413.007745][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 4413.010446][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 4413.013906][   T32] Call Trace:
[ 4413.015027][   T32]  <TASK>
[ 4413.016134][   T32]  __schedule (kernel/sched/core.c:5259)
[ 4413.017465][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 4413.018294][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 4413.019366][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 4413.020594][   T32]  kthread (kernel/kthread.c:465)
[ 4413.021350][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 4413.022331][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 4413.023124][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 4413.023925][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 4413.025015][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 4413.026074][   T32]  </TASK>
[ 4904.520028][   T32] INFO: task kprobe-optimize:18 blocked for more than 4423 seconds.
[ 4904.521949][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 4904.528612][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 4904.529924][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 4904.531461][   T32] Call Trace:
[ 4904.531896][   T32]  <TASK>
[ 4904.532406][   T32]  __schedule (kernel/sched/core.c:5259)
[ 4904.532947][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 4904.533381][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 4904.533944][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 4904.534547][   T32]  kthread (kernel/kthread.c:465)
[ 4904.534985][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 4904.535670][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 4904.536359][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 4904.536831][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 4904.537310][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 4904.537788][   T32]  </TASK>
[ 5396.039936][   T32] INFO: task kprobe-optimize:18 blocked for more than 4915 seconds.
[ 5396.040939][   T32]       Not tainted 6.19.0-rc5-00040-g62f65c2531ad #1
[ 5396.041631][   T32] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 5396.042742][   T32] task:kprobe-optimize state:D stack:0     pid:18    tgid:18    ppid:2      task_flags:0x208040 flags:0x00080000
[ 5396.045182][   T32] Call Trace:
[ 5396.045723][   T32]  <TASK>
[ 5396.046266][   T32]  __schedule (kernel/sched/core.c:5259)
[ 5396.046928][   T32]  schedule (arch/x86/include/asm/preempt.h:85 kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 5396.047542][   T32]  kprobe_optimizer_thread (kernel/kprobes.c:656)
[ 5396.048329][   T32]  ? __pfx_autoremove_wake_function (kernel/sched/wait.c:402)
[ 5396.049182][   T32]  kthread (kernel/kthread.c:465)
[ 5396.049803][   T32]  ? __pfx_kprobe_optimizer_thread (kernel/kprobes.c:654)
[ 5396.050654][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 5396.051353][   T32]  ret_from_fork (arch/x86/kernel/process.c:164)
[ 5396.052048][   T32]  ? __pfx_kthread (kernel/kthread.c:412)
[ 5396.052723][   T32]  ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
[ 5396.053187][   T32]  </TASK>
[ 5396.053524][   T32] Future hung task reports are suppressed, see sysctl kernel.hung_task_warnings
BUG: kernel hang in test stage



The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20260119/202601191507.74fccd0c-lkp@intel.com



-- 
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki