* [thomas-weissschuh:b4/auxclock-nanosleep] 3f30076c1d: WARNING:possible_circular_locking_dependency_detected
@ 2026-02-18 4:06 kernel test robot
0 siblings, 0 replies; only message in thread
From: kernel test robot @ 2026-02-18 4:06 UTC (permalink / raw)
To: linux+lkp; +Cc: oe-lkp, lkp, oliver.sang
Hello,
this change looks like a debug commit to us. but we don't have enough knowledge
what's the purpose of this. just report this to you FYI in case it could supply
some useful information.
kernel test robot noticed "WARNING:possible_circular_locking_dependency_detected" on:
commit: 3f30076c1d2735a7650c49350a7040d9dda57cb9 ("count wakeups")
https://git.kernel.org/cgit/linux/kernel/git/thomas.weissschuh/linux.git b4/auxclock-nanosleep
in testcase: boot
config: x86_64-randconfig-014-20260217
compiler: gcc-14
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/202602181128.ac178dea-lkp@intel.com
[ 33.863984][ C0] ======================================================
[ 33.864539][ C0] WARNING: possible circular locking dependency detected
[ 33.865113][ C0] 6.19.0-10451-g3f30076c1d27 #1 Tainted: G W T
[ 33.865703][ C0] ------------------------------------------------------
[ 33.866154][ C0] lkp-setup-rootf/57 is trying to acquire lock:
[ 33.866565][ C0] ffff9a71009b98e0 (&tty->write_wait){-...}-{3:3}, at: __wake_up_common_lock+0x20/0x80
[ 33.867945][ C0]
[ 33.867945][ C0] but task is already holding lock:
[ 33.868660][ C0] ffffffffa970b578 (&port_lock_key){-.-.}-{3:3}, at: uart_port_lock_irqsave+0xf/0x40
[ 33.869710][ C0]
[ 33.869710][ C0] which lock already depends on the new lock.
[ 33.869710][ C0]
[ 33.876111][ C0]
[ 33.876111][ C0] the existing dependency chain (in reverse order) is:
[ 33.877113][ C0]
[ 33.877113][ C0] -> #5 (&port_lock_key){-.-.}-{3:3}:
[ 33.878065][ C0] validate_chain+0x202/0x2c0
[ 33.878663][ C0] __lock_acquire+0x5fd/0x780
[ 33.879268][ C0] lock_acquire+0x105/0x240
[ 33.879861][ C0] _raw_spin_lock_irqsave+0x40/0x80
[ 33.880513][ C0] uart_port_lock_irqsave+0xf/0x40
[ 33.881173][ C0] serial8250_console_write+0xcb/0x580
[ 33.881951][ C0] univ8250_console_write+0x4d/0x80
[ 33.882641][ C0] console_emit_next_record+0x177/0x200
[ 33.883349][ C0] console_flush_all+0x114/0x200
[ 33.884027][ C0] console_unlock+0x8e/0x140
[ 33.884598][ C0] vprintk_emit+0x2e2/0x380
[ 33.885201][ C0] vprintk_default+0x12/0x40
[ 33.885883][ C0] vprintk+0x5/0x40
[ 33.886421][ C0] _printk+0x50/0x80
[ 33.886943][ C0] register_console+0x5ef/0x6c0
[ 33.887579][ C0] univ8250_console_init+0x2b/0x40
[ 33.888287][ C0] console_init+0x10b/0x1c0
[ 33.888867][ C0] start_kernel+0x3ed/0x5c0
[ 33.889459][ C0] x86_64_start_reservations+0x20/0x40
[ 33.890223][ C0] x86_64_start_kernel+0x11c/0x140
[ 33.890887][ C0] common_startup_64+0xbd/0xe8
[ 33.891514][ C0]
[ 33.891514][ C0] -> #4 (console_owner){-.-.}-{0:0}:
[ 33.892384][ C0] validate_chain+0x202/0x2c0
[ 33.892957][ C0] __lock_acquire+0x5fd/0x780
[ 33.893619][ C0] lock_acquire+0x105/0x240
[ 33.894195][ C0] console_lock_spinning_enable+0x5e/0x80
[ 33.894898][ C0] console_emit_next_record+0x12d/0x200
[ 33.895636][ C0] console_flush_all+0x114/0x200
[ 33.896282][ C0] console_unlock+0x8e/0x140
[ 33.896888][ C0] vprintk_emit+0x2e2/0x380
[ 33.897504][ C0] vprintk_default+0x12/0x40
[ 33.900681][ C0] vprintk+0x5/0x40
[ 33.901213][ C0] _printk+0x50/0x80
[ 33.901809][ C0] __hrtimer_run_queues+0x78/0x140
[ 33.902599][ C0] hrtimer_run_queues+0xeb/0x140
[ 33.903237][ C0] update_process_times+0x2d/0x100
[ 33.903893][ C0] tick_nohz_handler+0x298/0x300
[ 33.904522][ C0] tick_nohz_lowres_handler+0x27/0x80
[ 33.905226][ C0] __sysvec_apic_timer_interrupt+0x110/0x1c0
[ 33.906049][ C0] sysvec_apic_timer_interrupt+0x78/0xc0
[ 33.906764][ C0] asm_sysvec_apic_timer_interrupt+0x16/0x40
[ 33.907535][ C0] kcsan_setup_watchpoint+0x374/0x3c0
[ 33.908248][ C0] plist_check_prev_next+0x20/0xc0
[ 33.908908][ C0] plist_check_list+0x3f/0x80
[ 33.909507][ C0] plist_check_head+0x25/0x40
[ 33.910172][ C0] plist_add+0x1f/0x1c0
[ 33.910717][ C0] plist_test+0x16d/0x3c0
[ 33.911287][ C0] do_one_initcall+0xb7/0x2c0
[ 33.911904][ C0] do_initcalls+0x18d/0x1c0
[ 33.912536][ C0] kernel_init_freeable+0x113/0x1c0
[ 33.913215][ C0] kernel_init+0x12/0x180
[ 33.913875][ C0] ret_from_fork+0x23/0x280
[ 33.914487][ C0] ret_from_fork_asm+0x19/0x80
[ 33.915068][ C0]
[ 33.915068][ C0] -> #3 (hrtimer_bases.lock){-.-.}-{2:2}:
[ 33.915865][ C0] validate_chain+0x202/0x2c0
[ 33.916436][ C0] __lock_acquire+0x5fd/0x780
[ 33.916963][ C0] lock_acquire+0x105/0x240
[ 33.917460][ C0] _raw_spin_lock_irqsave+0x40/0x80
[ 33.918144][ C0] lock_hrtimer_base+0x17/0x40
[ 33.918725][ C0] hrtimer_start_range_ns+0x5e/0x1c0
[ 33.919316][ C0] start_dl_timer+0xa8/0xc0
[ 33.919828][ C0] enqueue_dl_entity+0x1f7/0x340
[ 33.920381][ C0] dl_server_start+0x59/0xc0
[ 33.920898][ C0] enqueue_task_fair+0x383/0x400
[ 33.921454][ C0] enqueue_task+0x37/0xc0
[ 33.921986][ C0] activate_task+0x21/0x80
[ 33.922459][ C0] ttwu_do_activate+0x6a/0x100
[ 33.923096][ C0] try_to_wake_up+0x27c/0x300
[ 33.923630][ C0] process_timeout+0x16/0x40
[ 33.924150][ C0] call_timer_fn+0x114/0x200
[ 33.924655][ C0] __run_timers+0x33d/0x380
[ 33.925136][ C0] __run_timer_base+0x49/0x80
[ 33.925663][ C0] run_timer_softirq+0x18/0x40
[ 33.926087][ C0] handle_softirqs+0x170/0x340
[ 33.926530][ C0] __irq_exit_rcu+0x40/0x100
[ 33.926949][ C0] irq_exit_rcu+0x5/0x40
[ 33.927360][ C0] sysvec_apic_timer_interrupt+0x7d/0xc0
[ 33.927882][ C0] asm_sysvec_apic_timer_interrupt+0x16/0x40
[ 33.928516][ C0] pv_native_safe_halt+0xb/0x40
[ 33.929050][ C0] default_idle+0x5/0x40
[ 33.929495][ C0] default_idle_call+0x74/0xc0
[ 33.929955][ C0] cpuidle_idle_call+0x51/0x100
[ 33.930385][ C0] do_idle+0x6a/0xc0
[ 33.930740][ C0] cpu_startup_entry+0x18/0x40
[ 33.931178][ C0] rest_init+0x1d1/0x200
[ 33.931562][ C0] start_kernel+0x585/0x5c0
[ 33.932006][ C0] x86_64_start_reservations+0x20/0x40
[ 33.932549][ C0] x86_64_start_kernel+0x11c/0x140
[ 33.933000][ C0] common_startup_64+0xbd/0xe8
[ 33.933400][ C0]
[ 33.933400][ C0] -> #2 (&rq->__lock){-.-.}-{2:2}:
[ 33.933909][ C0] validate_chain+0x202/0x2c0
[ 33.934271][ C0] __lock_acquire+0x5fd/0x780
[ 33.934636][ C0] lock_acquire+0x105/0x240
[ 33.934971][ C0] _raw_spin_lock_nested+0x35/0x80
[ 33.935343][ C0] raw_spin_rq_lock_nested+0x22/0x40
[ 33.935742][ C0] ___task_rq_lock+0x36/0x80
[ 33.936155][ C0] wake_up_new_task+0x5b/0x140
[ 33.936582][ C0] kernel_clone+0x29a/0x380
[ 33.936989][ C0] user_mode_thread+0x90/0xc0
[ 33.937395][ C0] rest_init+0x1c/0x200
[ 33.937748][ C0] start_kernel+0x585/0x5c0
[ 33.938081][ C0] x86_64_start_reservations+0x20/0x40
[ 33.938488][ C0] x86_64_start_kernel+0x11c/0x140
[ 33.938877][ C0] common_startup_64+0xbd/0xe8
[ 33.939227][ C0]
[ 33.939227][ C0] -> #1 (&p->pi_lock){-.-.}-{2:2}:
[ 33.939734][ C0] validate_chain+0x202/0x2c0
[ 33.940118][ C0] __lock_acquire+0x5fd/0x780
[ 33.940533][ C0] lock_acquire+0x105/0x240
[ 33.940948][ C0] _raw_spin_lock_irqsave+0x40/0x80
[ 33.941389][ C0] try_to_wake_up+0xa0/0x300
[ 33.941769][ C0] pollwake+0x9a/0x100
[ 33.942081][ C0] __wake_up_common+0x6c/0xc0
[ 33.942453][ C0] __wake_up_common_lock+0x39/0x80
[ 33.942838][ C0] pty_close+0x1a1/0x280
[ 33.943160][ C0] tty_release+0x1ee/0x640
[ 33.943487][ C0] __fput+0x272/0x400
[ 33.943790][ C0] ____fput+0x9/0x40
[ 33.944136][ C0] task_work_run+0xea/0x140
[ 33.944545][ C0] do_exit+0x499/0x580
[ 33.944921][ C0] do_group_exit+0x10c/0x140
[ 33.945341][ C0] __x64_sys_exit_group+0x1c/0x40
[ 33.945793][ C0] x64_sys_call+0x16f7/0x1b40
[ 33.946136][ C0] do_syscall_64+0x168/0x13c0
[ 33.946481][ C0] entry_SYSCALL_64_after_hwframe+0x75/0x7d
[ 33.946899][ C0]
[ 33.946899][ C0] -> #0 (&tty->write_wait){-...}-{3:3}:
[ 33.947419][ C0] check_noncircular+0xa9/0xc0
[ 33.947787][ C0] check_prev_add+0xec/0x300
[ 33.948179][ C0] validate_chain+0x202/0x2c0
[ 33.948598][ C0] __lock_acquire+0x5fd/0x780
[ 33.949011][ C0] lock_acquire+0x105/0x240
[ 33.949397][ C0] _raw_spin_lock_irqsave+0x40/0x80
[ 33.949807][ C0] __wake_up_common_lock+0x20/0x80
[ 33.950196][ C0] tty_wakeup+0x8e/0xc0
[ 33.950506][ C0] tty_port_default_wakeup+0x1e/0x40
[ 33.950890][ C0] tty_port_tty_wakeup+0x2c/0x40
[ 33.951268][ C0] uart_write_wakeup+0x24/0x40
[ 33.951612][ C0] serial8250_tx_chars+0x21b/0x340
[ 33.952029][ C0] serial8250_handle_irq+0x14e/0x200
[ 33.952496][ C0] serial8250_default_handle_irq+0x1a/0x40
[ 33.953008][ C0] serial8250_interrupt+0x63/0x100
[ 33.953436][ C0] __handle_irq_event_percpu+0x13c/0x380
[ 33.953880][ C0] handle_irq_event_percpu+0xa/0x40
[ 33.954268][ C0] handle_irq_event+0x70/0xc0
[ 33.954624][ C0] handle_edge_irq+0xe7/0x140
[ 33.954973][ C0] __common_interrupt+0xe7/0x180
[ 33.955348][ C0] common_interrupt+0x8e/0xc0
[ 33.955692][ C0] asm_common_interrupt+0x22/0x40
[ 33.956087][ C0] _raw_spin_unlock_irqrestore+0x2f/0x80
[ 33.956586][ C0] uart_port_unlock_irqrestore+0x20/0x40
[ 33.957086][ C0] uart_port_unlock_deref+0xf/0x40
[ 33.957511][ C0] uart_write+0xac/0xc0
[ 33.957871][ C0] process_output_block+0x229/0x280
[ 33.958267][ C0] n_tty_write+0x172/0x400
[ 33.958596][ C0] iterate_tty_write+0x1ce/0x280
[ 33.958970][ C0] file_tty_write+0x120/0x140
[ 33.959350][ C0] tty_write+0x1a/0x40
[ 33.959655][ C0] vfs_write+0x1bd/0x240
[ 33.959991][ C0] ksys_write+0x8d/0x100
[ 33.960401][ C0] __x64_sys_write+0x38/0x80
[ 33.960845][ C0] x64_sys_call+0x10e4/0x1b40
[ 33.961278][ C0] do_syscall_64+0x168/0x13c0
[ 33.961667][ C0] entry_SYSCALL_64_after_hwframe+0x75/0x7d
[ 33.962108][ C0]
[ 33.962108][ C0] other info that might help us debug this:
[ 33.962108][ C0]
[ 33.962759][ C0] Chain exists of:
[ 33.962759][ C0] &tty->write_wait --> console_owner --> &port_lock_key
[ 33.962759][ C0]
[ 33.963599][ C0] Possible unsafe locking scenario:
[ 33.963599][ C0]
[ 33.964128][ C0] CPU0 CPU1
[ 33.964561][ C0] ---- ----
[ 33.964982][ C0] lock(&port_lock_key);
[ 33.965334][ C0] lock(console_owner);
[ 33.965825][ C0] lock(&port_lock_key);
[ 33.966271][ C0] lock(&tty->write_wait);
[ 33.966590][ C0]
[ 33.966590][ C0] *** DEADLOCK ***
[ 33.966590][ C0]
[ 33.967113][ C0] 6 locks held by lkp-setup-rootf/57:
[ 33.967462][ C0] #0: ffff9a71009b9d60 (&tty->ldisc_sem){++++}-{0:0}, at: ldsem_down_read+0x26/0x40
[ 33.968137][ C0] #1: ffff9a71009b9ef0 (&tty->atomic_write_lock){+.+.}-{4:4}, at: tty_write_lock+0x15/0x80
[ 33.969010][ C0] #2: ffff9a71009b99b0 (&tty->termios_rwsem){++++}-{4:4}, at: n_tty_write+0x9c/0x400
[ 33.976613][ C0] #3: ffffa6e8c0c33388 (&ldata->output_lock){+.+.}-{4:4}, at: process_output_block+0x3e/0x280
[ 33.977944][ C0] #4: ffff9a7100146cb0 (&i->lock){-...}-{3:3}, at: serial8250_interrupt+0x26/0x100
[ 33.979050][ C0] #5: ffffffffa970b578 (&port_lock_key){-.-.}-{3:3}, at: uart_port_lock_irqsave+0xf/0x40
[ 33.980234][ C0]
[ 33.980234][ C0] stack backtrace:
[ 33.980860][ C0] CPU: 0 UID: 0 PID: 57 Comm: lkp-setup-rootf Tainted: G W T 6.19.0-10451-g3f30076c1d27 #1 PREEMPT
[ 33.982261][ C0] Tainted: [W]=WARN, [T]=RANDSTRUCT
[ 33.982840][ C0] Call Trace:
[ 33.983196][ C0] <IRQ>
[ 33.983548][ C0] dump_stack_lvl+0x3e/0x80
[ 33.984133][ C0] dump_stack+0xc/0x40
[ 33.984629][ C0] print_circular_bug+0xb4/0x100
[ 33.985253][ C0] check_noncircular+0xa9/0xc0
[ 33.985882][ C0] ? add_chain_block+0x8c/0x100
[ 33.986499][ C0] check_prev_add+0xec/0x300
[ 33.987071][ C0] validate_chain+0x202/0x2c0
[ 33.987649][ C0] __lock_acquire+0x5fd/0x780
[ 33.988214][ C0] ? __wake_up_common_lock+0x20/0x80
[ 33.988829][ C0] lock_acquire+0x105/0x240
[ 33.989384][ C0] ? __wake_up_common_lock+0x20/0x80
[ 33.990089][ C0] ? lock_acquire+0x105/0x240
[ 33.990663][ C0] ? tty_port_tty_get+0x54/0x80
[ 33.991263][ C0] _raw_spin_lock_irqsave+0x40/0x80
[ 33.991897][ C0] ? __wake_up_common_lock+0x20/0x80
[ 33.992550][ C0] __wake_up_common_lock+0x20/0x80
[ 33.993188][ C0] tty_wakeup+0x8e/0xc0
[ 33.993763][ C0] tty_port_default_wakeup+0x1e/0x40
[ 33.994400][ C0] tty_port_tty_wakeup+0x2c/0x40
[ 33.994990][ C0] uart_write_wakeup+0x24/0x40
[ 33.995560][ C0] serial8250_tx_chars+0x21b/0x340
[ 33.996221][ C0] serial8250_handle_irq+0x14e/0x200
[ 33.996870][ C0] serial8250_default_handle_irq+0x1a/0x40
[ 33.997631][ C0] serial8250_interrupt+0x63/0x100
[ 33.998288][ C0] ? __pfx_serial8250_interrupt+0x40/0x40
[ 33.998949][ C0] __handle_irq_event_percpu+0x13c/0x380
[ 33.999643][ C0] ? handle_irq_event+0x68/0xc0
[ 34.000246][ C0] handle_irq_event_percpu+0xa/0x40
[ 34.000858][ C0] handle_irq_event+0x70/0xc0
[ 34.001424][ C0] handle_edge_irq+0xe7/0x140
[ 34.002040][ C0] __common_interrupt+0xe7/0x180
[ 34.002618][ C0] common_interrupt+0x8e/0xc0
[ 34.003166][ C0] </IRQ>
[ 34.003510][ C0] <TASK>
[ 34.003897][ C0] asm_common_interrupt+0x22/0x40
[ 34.004488][ C0] RIP: 0010:_raw_spin_unlock_irqrestore+0x2f/0x80
[ 34.005210][ C0] Code: 48 89 fb 48 8b 74 24 10 48 83 c7 18 e8 6a 73 f1 fe 48 89 df e8 62 bb f1 fe 0f ba e5 09 73 06 e8 57 fb 02 ff fb bf 01 00 00 00 <e8> 0c 9d ec fe 48 c7 c7 20 c0 81 a7 e8 00 d3 14 ff 83 3d 59 6e d6
[ 34.007409][ C0] RSP: 0018:ffffa6e8c0c0fc08 EFLAGS: 00000206
[ 34.008112][ C0] RAX: 0000000000003922 RBX: ffffffffa970b560 RCX: 0000000080000001
[ 34.008999][ C0] RDX: 0000000000000040 RSI: ffffffffa766155a RDI: 0000000000000001
[ 34.009931][ C0] RBP: 0000000000000286 R08: ffffffffa95a9860 R09: 0000000000000000
[ 34.010814][ C0] R10: 0001ffffa7deddc0 R11: 0001ffffffffffff R12: ffffffffa970b560
[ 34.011696][ C0] R13: 0000000000000040 R14: 0000000000000286 R15: ffff9a71029d8000
[ 34.012682][ C0] ? _raw_spin_unlock_irqrestore+0x29/0x80
[ 34.013366][ C0] uart_port_unlock_irqrestore+0x20/0x40
[ 34.014078][ C0] uart_port_unlock_deref+0xf/0x40
[ 34.014672][ C0] uart_write+0xac/0xc0
[ 34.015172][ C0] process_output_block+0x229/0x280
[ 34.015796][ C0] n_tty_write+0x172/0x400
[ 34.016316][ C0] ? __pfx_woken_wake_function+0x40/0x40
[ 34.016962][ C0] iterate_tty_write+0x1ce/0x280
[ 34.017656][ C0] ? __pfx_n_tty_write+0x40/0x40
[ 34.018094][ C0] file_tty_write+0x120/0x140
[ 34.018545][ C0] ? tty_write+0xe/0x40
[ 34.019010][ C0] ? __pfx_tty_write+0x40/0x40
[ 34.019426][ C0] tty_write+0x1a/0x40
[ 34.019816][ C0] vfs_write+0x1bd/0x240
[ 34.020298][ C0] ksys_write+0x8d/0x100
[ 34.020822][ C0] __x64_sys_write+0x38/0x80
[ 34.021411][ C0] x64_sys_call+0x10e4/0x1b40
[ 34.022087][ C0] do_syscall_64+0x168/0x13c0
[ 34.022649][ C0] entry_SYSCALL_64_after_hwframe+0x75/0x7d
[ 34.023333][ C0] RIP: 0033:0x7f183e443100
[ 34.023996][ C0] Code: 73 01 c3 48 8b 0d 40 0d 2d 00 31 d2 48 29 c2 64 89 11 48 83 c8 ff eb ea 90 90 83 3d dd 70 2d 00 00 75 10 b8 01 00 00 00 0f 05 <48> 3d 01 f0 ff ff 73 31 c3 48 83 ec 08 e8 de b0 01 00 48 89 04 24
[ 34.025514][ C0] RSP: 002b:00007ffe98b9a688 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[ 34.026085][ C0] RAX: ffffffffffffffda RBX: 0000000000000041 RCX: 00007f183e443100
[ 34.026597][ C0] RDX: 0000000000000041 RSI: 0000000023196808 RDI: 0000000000000001
[ 34.027113][ C0] RBP: 0000000023196808 R08: 000000000000000a R09: 00007f183ed5b700
[ 34.027618][ C0] R10: 6465696669636570 R11: 0000000000000246 R12: 00007f183e715260
[ 34.028182][ C0] R13: 0000000000000041 R14: 0000000000000000 R15: 000000002327bb08
[ 34.028826][ C0] </TASK>
The kernel config and materials to reproduce are available at:
https://download.01.org/0day-ci/archive/20260218/202602181128.ac178dea-lkp@intel.com
--
0-DAY CI Kernel Test Service
https://github.com/intel/lkp-tests/wiki
^ permalink raw reply [flat|nested] only message in thread
only message in thread, other threads:[~2026-02-18 4:06 UTC | newest]
Thread overview: (only message) (download: mbox.gz follow: Atom feed
-- links below jump to the message on this page --
2026-02-18 4:06 [thomas-weissschuh:b4/auxclock-nanosleep] 3f30076c1d: WARNING:possible_circular_locking_dependency_detected kernel test robot
This is a public inbox, see mirroring instructions
for how to clone and mirror all data and code used for this inbox