Linux Kernel Performance
 help / color / mirror / Atom feed
From: kernel test robot <oliver.sang@intel.com>
To: <linux+lkp@weissschuh.net>
Cc: <oe-lkp@lists.linux.dev>, <lkp@intel.com>, <oliver.sang@intel.com>
Subject: [thomas-weissschuh:b4/restricted-pointers-final] [lib/vsprintf] f13aa632a5: WARNING:SOFTIRQ-safe->SOFTIRQ-unsafe_lock_order_detected
Date: Thu, 11 Jun 2026 09:45:17 +0800	[thread overview]
Message-ID: <202606110945.d3871219-lkp@intel.com> (raw)



Hello,

kernel test robot noticed "WARNING:SOFTIRQ-safe->SOFTIRQ-unsafe_lock_order_detected" on:

commit: f13aa632a53dc3aa2ec1a81dfbf3e52c0c26bfcd ("lib/vsprintf: Validate spinlock context during restricted pointer formatting")
https://git.kernel.org/cgit/linux/kernel/git/thomas.weissschuh/linux.git b4/restricted-pointers-final

in testcase: trinity
version: trinity-i386-abe9de86-1_20230429
with following parameters:

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



config: i386-randconfig-013-20260610
compiler: clang-22
test machine: qemu-system-x86_64 -enable-kvm -cpu SandyBridge -smp 2 -m 32G

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



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@intel.com>
| Closes: https://lore.kernel.org/oe-lkp/202606110945.d3871219-lkp@intel.com



[   92.648940][ T3827]
[   92.649789][ T3827] =====================================================
[   92.651543][ T3827] WARNING: SOFTIRQ-safe -> SOFTIRQ-unsafe lock order detected
[   92.653758][ T3827] 7.0.0-12214-gf13aa632a53d #1 Tainted: G                T
[   92.656006][ T3827] -----------------------------------------------------
[   92.659102][ T3827] trinity-c1/3827 [HC0[0]:SC0[2]:HE1:SE0] is trying to acquire:
[   92.674723][ T3827] 83aad874 (vsprintf_restricted_pointer_map-wait-type-assert){+.+.}-{3:3}, at: class_lock_map_acquire_constructor (linux/lockdep.h:557)
[   92.681173][ T3827]
[   92.681173][ T3827] and this task is already holding:
[   92.681173][ T3827]
[   92.681173][ T3827] and this task is already holding:
[   92.681173][ T3827]
[   92.681173][ T3827] and this task is already holding:
[   92.689423][ T3827] 857d2130 (&ptr[i]){+.-.}-{3:3}, at: established_get_first (linux/spinlock.h:348 ipv4/tcp_ipv4.c:2574)
[   92.693323][ T3827] which would create a new lock dependency:
[   92.695966][ T3827]  (&ptr[i]){+.-.}-{3:3} -> (vsprintf_restricted_pointer_map-wait-type-assert){+.+.}-{3:3}
[   92.700468][ T3827]
[   92.700468][ T3827] but this new dependency connects a SOFTIRQ-irq-safe lock:
[   92.700468][ T3827]
[   92.700468][ T3827] but this new dependency connects a SOFTIRQ-irq-safe lock:
[   92.700468][ T3827]
[   92.700468][ T3827] but this new dependency connects a SOFTIRQ-irq-safe lock:
[   92.706011][ T3827]  (&ptr[i]){+.-.}-{3:3}
[   92.706021][ T3827]
[   92.706021][ T3827] ... which became SOFTIRQ-irq-safe at:
[   92.706021][ T3827]
[   92.706021][ T3827] ... which became SOFTIRQ-irq-safe at:
[   92.706021][ T3827]
[   92.706021][ T3827] ... which became SOFTIRQ-irq-safe at:
[   92.711404][ T3827]   lock_acquire (locking/lockdep.c:5868)
[   92.713447][ T3827]   _raw_spin_lock (linux/spinlock_api_smp.h:158 locking/spinlock.c:158)
[   92.715506][ T3827]   inet_twsk_hashdance_schedule (linux/spinlock.h:342 ipv4/inet_timewait_sock.c:135)
[   92.717585][ T3827]   tcp_time_wait (ipv4/tcp_minisocks.c:383)
[   92.719017][ T3827]   tcp_rcv_state_process (ipv4/tcp_input.c:?)
[   92.720696][ T3827]   tcp_v4_do_rcv (ipv4/tcp_ipv4.c:1875)
[   92.722024][ T3827]   tcp_v4_rcv (ipv4/tcp_ipv4.c:2246)
[   92.723180][ T3827]   ip_protocol_deliver_rcu (ipv4/ip_input.c:207)
[   92.724843][ T3827]   ip_local_deliver (ipv4/ip_input.c:241 (discriminator 1))
[   92.726195][ T3827]   ip_sublist_rcv_finish (net/dst.h:480 (discriminator 3))
[   92.727531][ T3827]   ip_sublist_rcv (ipv4/ip_input.c:675 ipv4/ip_input.c:683)
[   92.728958][ T3827]   ip_list_rcv (ipv4/ip_input.c:717)
[   92.730196][ T3827]   __netif_receive_skb_list_core (core/dev.c:6252 core/dev.c:6299)
[   92.731740][ T3827]   netif_receive_skb_list_internal (core/dev.c:6351 (discriminator 256) core/dev.c:6442 (discriminator 256))
[   92.733677][ T3827]   napi_complete_done (net/gro.h:523 net/gro.h:531 core/dev.c:6810)
[   92.734992][ T3827]   e1000_clean (net/ethernet/intel/e1000/e1000_main.c:3812)
[   92.736271][ T3827]   __napi_poll (core/dev.c:7737)
[   92.737625][ T3827]   net_rx_action (core/dev.c:7800)
[   92.738853][ T3827]   handle_softirqs (softirq.c:622)
[   92.740184][ T3827]   __do_softirq (softirq.c:656)
[   92.741505][ T3827]   do_softirq_own_stack (x86/kernel/irq_32.c:56 x86/kernel/irq_32.c:144)
[   92.742870][ T3827]   __irq_exit_rcu (softirq.c:503)
[   92.744135][ T3827]   irq_exit_rcu (softirq.c:752)
[   92.745475][ T3827]   common_interrupt (x86/kernel/irq.c:326 (discriminator 9))
[   92.746728][ T3827]   asm_common_interrupt (x86/include/asm/idtentry.h:688)
[   92.748137][ T3827]
[   92.748137][ T3827] to a SOFTIRQ-irq-unsafe lock:
[   92.748137][ T3827]
[   92.748137][ T3827] to a SOFTIRQ-irq-unsafe lock:
[   92.748137][ T3827]
[   92.748137][ T3827] to a SOFTIRQ-irq-unsafe lock:
[   92.750164][ T3827]  (vsprintf_restricted_pointer_map-wait-type-assert){+.+.}-{3:3}
[   92.750168][ T3827]
[   92.750168][ T3827] ... which became SOFTIRQ-irq-unsafe at:
[   92.750168][ T3827]
[   92.750168][ T3827] ... which became SOFTIRQ-irq-unsafe at:
[   92.750168][ T3827]
[   92.750168][ T3827] ... which became SOFTIRQ-irq-unsafe at:
[   92.754551][ T3827] ...
[   92.754552][ T3827]   lock_acquire (locking/lockdep.c:5868)
[   92.757345][ T3827]   class_lock_map_acquire_constructor (linux/lockdep.h:557 (discriminator 1))
[   92.759895][ T3827]   restricted_pointer (vsprintf.c:874)
[   92.770231][ T3827]   pointer (vsprintf.c:2582)
[   92.772166][ T3827]   vsnprintf (vsprintf.c:2956)
[   92.774145][ T3827]   seq_printf (seq_file.c:392 seq_file.c:407)
[   92.776083][ T3827]   tcp6_seq_show (ipv6/tcp_ipv6.c:2147 (discriminator 1) ipv6/tcp_ipv6.c:2221 (discriminator 1))
[   92.778212][ T3827]   traverse (seq_file.c:112)
[   92.780125][ T3827]   seq_lseek (seq_file.c:324 (discriminator 1))
[   92.782106][ T3827]   proc_reg_llseek (proc/inode.c:283)
[   92.789616][ T3827]   __ia32_sys_lseek (read_write.c:391)
[   92.791786][ T3827]   ia32_sys_call (kbuild/obj/consumer/i386-randconfig-013-20260610/./arch/x86/include/generated/asm/syscalls_32.h:20)
[   92.793792][ T3827]   __do_fast_syscall_32 (x86/entry/syscall_32.c:83)
[   92.795973][ T3827]   do_fast_syscall_32 (x86/entry/syscall_32.c:332)
[   92.797992][ T3827]   do_SYSENTER_32 (x86/entry/syscall_32.c:370)
[   92.799987][ T3827]   entry_SYSENTER_32 (x86/entry/entry_32.S:835)
[   92.802088][ T3827]
[   92.802088][ T3827] other info that might help us debug this:
[   92.802088][ T3827]
[   92.802088][ T3827] other info that might help us debug this:
[   92.802088][ T3827]
[   92.802088][ T3827] other info that might help us debug this:
[   92.802088][ T3827]
[   92.811748][ T3827]  Possible interrupt unsafe locking scenario:
[   92.811748][ T3827]
[   92.811748][ T3827]  Possible interrupt unsafe locking scenario:
[   92.811748][ T3827]
[   92.811748][ T3827]  Possible interrupt unsafe locking scenario:
[   92.811748][ T3827]
[   92.815589][ T3827]        CPU0                    CPU1
[   92.817489][ T3827]        ----                    ----
[   92.819100][ T3827]   lock(vsprintf_restricted_pointer_map-wait-type-assert);
[   92.821380][ T3827]                                local_irq_disable();
[   92.823141][ T3827]                                lock(&ptr[i]);
[   92.824922][ T3827]                                lock(vsprintf_restricted_pointer_map-wait-type-assert);
[   92.827481][ T3827]   <Interrupt>
[   92.828503][ T3827]     lock(&ptr[i]);
[   92.829677][ T3827]
[   92.829677][ T3827]  *** DEADLOCK ***
[   92.829677][ T3827]
[   92.829677][ T3827]  *** DEADLOCK ***
[   92.829677][ T3827]
[   92.829677][ T3827]  *** DEADLOCK ***
[   92.829677][ T3827]
[   92.831788][ T3827] 3 locks held by trinity-c1/3827:
[   92.833367][ T3827]  #0: f55743fc (&f->f_pos_lock){+.+.}-{4:4}, at: fdget_pos (file.c:1261)
[   92.835457][ T3827]  #1: f630d118 (&p->lock){+.+.}-{4:4}, at: seq_lseek (seq_file.c:314)
[   92.837697][ T3827]  #2: 857d2130 (&ptr[i]){+.-.}-{3:3}, at: established_get_first (linux/spinlock.h:348 ipv4/tcp_ipv4.c:2574)
[   92.839918][ T3827]
[   92.839918][ T3827] the dependencies between SOFTIRQ-irq-safe lock and the holding lock:
[   92.839918][ T3827]
[   92.839918][ T3827] the dependencies between SOFTIRQ-irq-safe lock and the holding lock:
[   92.839918][ T3827]
[   92.839918][ T3827] the dependencies between SOFTIRQ-irq-safe lock and the holding lock:
[   92.842890][ T3827] -> (&ptr[i]){+.-.}-{3:3} {
[   92.844117][ T3827]    HARDIRQ-ON-W at:
[   92.845349][ T3827]                     lock_acquire (locking/lockdep.c:5868)
[   92.846899][ T3827]                     _raw_spin_lock (linux/spinlock_api_smp.h:158 locking/spinlock.c:158)
[   92.848615][ T3827]                     inet_ehash_insert (linux/spinlock.h:342 ipv4/inet_hashtables.c:725)
[   92.850389][ T3827]                     inet_ehash_nolisten (ipv4/inet_hashtables.c:749)
[   92.852079][ T3827]                     __inet_hash_connect (ipv4/inet_hashtables.c:1212)
[   92.854087][ T3827]                     inet_hash_connect (ipv4/inet_hashtables.c:1275)
[   92.856206][ T3827]                     tcp_v4_connect (ipv4/tcp_ipv4.c:306)
[   92.858935][ T3827]                     __inet_stream_connect (ipv4/af_inet.c:684)
[   92.861901][ T3827]                     inet_stream_connect (ipv4/af_inet.c:755)
[   92.870137][ T3827]                     __sys_connect (socket.c:2148 (discriminator 3))
[   92.872927][ T3827]                     __ia32_sys_socketcall (socket.c:3221 (discriminator 2))
[   92.875995][ T3827]                     ia32_sys_call (kbuild/obj/consumer/i386-randconfig-013-20260610/./arch/x86/include/generated/asm/syscalls_32.h:103)
[   92.878789][ T3827]                     __do_fast_syscall_32 (x86/entry/syscall_32.c:83)
[   92.881825][ T3827]                     do_fast_syscall_32 (x86/entry/syscall_32.c:332)
[   92.886058][ T3827]                     do_SYSENTER_32 (x86/entry/syscall_32.c:370)
[   92.888741][ T3827]                     entry_SYSENTER_32 (x86/entry/entry_32.S:835)
[   92.891567][ T3827]    IN-SOFTIRQ-W at:
[   92.893433][ T3827]                     lock_acquire (locking/lockdep.c:5868)
[   92.896112][ T3827]                     _raw_spin_lock (linux/spinlock_api_smp.h:158 locking/spinlock.c:158)
[   92.898846][ T3827]                     inet_twsk_hashdance_schedule (linux/spinlock.h:342 ipv4/inet_timewait_sock.c:135)
[   92.902176][ T3827]                     tcp_time_wait (ipv4/tcp_minisocks.c:383)
[   92.906283][ T3827]                     tcp_rcv_state_process (ipv4/tcp_input.c:?)
[   92.909378][ T3827]                     tcp_v4_do_rcv (ipv4/tcp_ipv4.c:1875)
[   92.912231][ T3827]                     tcp_v4_rcv (ipv4/tcp_ipv4.c:2246)
[   92.915066][ T3827]                     ip_protocol_deliver_rcu (ipv4/ip_input.c:207)
[   92.917581][ T3827]                     ip_local_deliver (ipv4/ip_input.c:241 (discriminator 1))
[   92.919549][ T3827]                     ip_sublist_rcv_finish (net/dst.h:480 (discriminator 3))
[   92.921650][ T3827]                     ip_sublist_rcv (ipv4/ip_input.c:675 ipv4/ip_input.c:683)
[   92.923332][ T3827]                     ip_list_rcv (ipv4/ip_input.c:717)


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



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


                 reply	other threads:[~2026-06-11  1:45 UTC|newest]

Thread overview: [no followups] expand[flat|nested]  mbox.gz  Atom feed

Reply instructions:

You may reply publicly to this message via plain-text email
using any one of the following methods:

* Save the following mbox file, import it into your mail client,
  and reply-to-all from there: mbox

  Avoid top-posting and favor interleaved quoting:
  https://en.wikipedia.org/wiki/Posting_style#Interleaved_style

* Reply using the --to, --cc, and --in-reply-to
  switches of git-send-email(1):

  git send-email \
    --in-reply-to=202606110945.d3871219-lkp@intel.com \
    --to=oliver.sang@intel.com \
    --cc=linux+lkp@weissschuh.net \
    --cc=lkp@intel.com \
    --cc=oe-lkp@lists.linux.dev \
    /path/to/YOUR_REPLY

  https://kernel.org/pub/software/scm/git/docs/git-send-email.html

* If your mail client supports setting the In-Reply-To header
  via mailto: links, try the mailto: link
Be sure your reply has a Subject: header at the top and a blank line before the message body.
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox