====================================== | | Ctrl-C stdout | ^Cmake[1]: *** [../lib.mk:142: run_tests] Interrupt | make: *** [Makefile:221: run_tests] Interrupt | xx__-> [ 1967.537445][ T44] INFO: task modprobe:6196 blocked for more than 122 seconds. [ 1967.538013][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 1967.538203][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1967.538522][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 1967.538900][ T44] Call Trace: [ 1967.539059][ T44] [ 1967.539176][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 1967.539379][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 1967.539604][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 1967.539782][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1967.539968][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 1967.540112][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 1967.540289][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1967.540498][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1967.540680][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 1967.540863][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 1967.541031][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 1967.541190][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 1967.541375][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1967.541508][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1967.541697][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 1967.541830][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 1967.542006][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 1967.542166][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 1967.542330][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 1967.542504][ T44] do_one_initcall (init/main.c:1284) [ 1967.543007][ T44] ? trace_initcall_finish (init/main.c:1274) [ 1967.543170][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1967.543356][ T44] do_init_module (kernel/module/main.c:3039) [ 1967.543527][ T44] ? free_module (kernel/module/main.c:3011) [ 1967.543688][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 1967.543850][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 1967.544045][ T44] init_module_from_file (kernel/module/main.c:3701) [ 1967.544206][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1967.544405][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1967.544568][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 1967.544735][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 1967.544894][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 1967.545060][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 1967.545223][ T44] ? cap_capable (security/commoncap.c:132) [ 1967.545401][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 1967.545562][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1967.545732][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1967.545924][ T44] RIP: 0033:0x7f225775f34d [ 1967.546092][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1967.546312][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 1967.546544][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 1967.546764][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 1967.546986][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 1967.547202][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 1967.549543][ T44] 1 lock held by modprobe/6196: | [ 1967.549696][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 1967.549971][ T44] | [ 1967.550074][ T44] ============================================= | [ 1967.550074][ T44] [ 2090.418441][ T44] INFO: task modprobe:6196 blocked for more than 245 seconds. [ 2090.418861][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2090.419061][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2090.419387][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2090.419757][ T44] Call Trace: [ 2090.419910][ T44] [ 2090.420036][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2090.420228][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2090.420418][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2090.420597][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2090.420781][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2090.420925][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2090.421093][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2090.421278][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2090.421490][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2090.421660][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2090.421830][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2090.422008][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2090.422189][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2090.422323][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2090.422528][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2090.422661][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2090.422823][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2090.422986][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2090.423166][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2090.423327][ T44] do_one_initcall (init/main.c:1284) [ 2090.423501][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2090.423661][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2090.423825][ T44] do_init_module (kernel/module/main.c:3039) [ 2090.423989][ T44] ? free_module (kernel/module/main.c:3011) [ 2090.424157][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2090.424326][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2090.424526][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2090.424686][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2090.424851][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2090.425012][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2090.425178][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2090.425379][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2090.425538][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2090.425702][ T44] ? cap_capable (security/commoncap.c:132) [ 2090.425863][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2090.426025][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2090.426193][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2090.426397][ T44] RIP: 0033:0x7f225775f34d [ 2090.426570][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2090.426787][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2090.426999][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2090.427217][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2090.427441][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2090.427661][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2090.429990][ T44] 1 lock held by modprobe/6196: | [ 2090.430144][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2090.430435][ T44] | [ 2090.430536][ T44] ============================================= | [ 2090.430536][ T44] [ 2213.297439][ T44] INFO: task modprobe:6196 blocked for more than 368 seconds. [ 2213.297803][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2213.297990][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2213.298261][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2213.298615][ T44] Call Trace: [ 2213.298749][ T44] [ 2213.298859][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2213.299030][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2213.299194][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2213.299373][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2213.299539][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2213.299671][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2213.299833][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2213.299992][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2213.300156][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2213.300313][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2213.300505][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2213.300663][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2213.300830][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2213.300972][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2213.301161][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2213.301292][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2213.301469][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2213.301628][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2213.301790][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2213.301950][ T44] do_one_initcall (init/main.c:1284) [ 2213.302119][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2213.302281][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2213.302458][ T44] do_init_module (kernel/module/main.c:3039) [ 2213.302619][ T44] ? free_module (kernel/module/main.c:3011) [ 2213.302775][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2213.302928][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2213.303104][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2213.303265][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2213.303435][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2213.303582][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2213.303726][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2213.303872][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2213.304016][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2213.304172][ T44] ? cap_capable (security/commoncap.c:132) [ 2213.304319][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2213.304504][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2213.304651][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2213.304820][ T44] RIP: 0033:0x7f225775f34d [ 2213.304970][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2213.305165][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2213.305379][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2213.305578][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2213.305767][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2213.305959][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2213.308107][ T44] 1 lock held by modprobe/6196: | [ 2213.308244][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2213.308498][ T44] | [ 2213.308589][ T44] ============================================= | [ 2213.308589][ T44] [ 2336.177394][ T44] INFO: task modprobe:6196 blocked for more than 491 seconds. [ 2336.177731][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2336.177902][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2336.178166][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2336.178533][ T44] Call Trace: [ 2336.178671][ T44] [ 2336.178777][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2336.178953][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2336.179118][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2336.179280][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2336.179459][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2336.179594][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2336.179757][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2336.179916][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2336.180084][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2336.180238][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2336.180424][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2336.180579][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2336.180751][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2336.180883][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2336.181067][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2336.181197][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2336.181376][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2336.181535][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2336.181701][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2336.181858][ T44] do_one_initcall (init/main.c:1284) [ 2336.182022][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2336.182180][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2336.182369][ T44] do_init_module (kernel/module/main.c:3039) [ 2336.182521][ T44] ? free_module (kernel/module/main.c:3011) [ 2336.182674][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2336.182821][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2336.182991][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2336.183138][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2336.183290][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2336.183455][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2336.183601][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2336.183757][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2336.183902][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2336.184053][ T44] ? cap_capable (security/commoncap.c:132) [ 2336.184201][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2336.184387][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2336.184535][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2336.184706][ T44] RIP: 0033:0x7f225775f34d [ 2336.184857][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2336.185053][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2336.185245][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2336.185456][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2336.185657][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2336.185849][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2336.187998][ T44] 1 lock held by modprobe/6196: | [ 2336.188137][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2336.188394][ T44] | [ 2336.188487][ T44] ============================================= | [ 2336.188487][ T44] [ 2459.057376][ T44] INFO: task modprobe:6196 blocked for more than 614 seconds. [ 2459.057692][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2459.057877][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2459.058169][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2459.058551][ T44] Call Trace: [ 2459.058705][ T44] [ 2459.058822][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2459.058984][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2459.059147][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2459.059319][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2459.059511][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2459.059640][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2459.059799][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2459.059955][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2459.060114][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2459.060272][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2459.060451][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2459.060602][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2459.060766][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2459.060906][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2459.061094][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2459.061225][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2459.061397][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2459.061565][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2459.061726][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2459.061877][ T44] do_one_initcall (init/main.c:1284) [ 2459.062033][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2459.062187][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2459.062357][ T44] do_init_module (kernel/module/main.c:3039) [ 2459.062510][ T44] ? free_module (kernel/module/main.c:3011) [ 2459.062669][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2459.062815][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2459.062992][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2459.063137][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2459.063287][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2459.063466][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2459.063618][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2459.063765][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2459.063914][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2459.064064][ T44] ? cap_capable (security/commoncap.c:132) [ 2459.064211][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2459.064371][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2459.064514][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2459.064688][ T44] RIP: 0033:0x7f225775f34d [ 2459.064836][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2459.065036][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2459.065227][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2459.065432][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2459.065632][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2459.065828][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2459.067881][ T44] 1 lock held by modprobe/6196: | [ 2459.068027][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2459.068262][ T44] | [ 2459.068374][ T44] ============================================= | [ 2459.068374][ T44] [ 2581.937381][ T44] INFO: task modprobe:6196 blocked for more than 737 seconds. [ 2581.937700][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2581.937881][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2581.938153][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2581.938530][ T44] Call Trace: [ 2581.938674][ T44] [ 2581.938793][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2581.938974][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2581.939139][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2581.939310][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2581.939502][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2581.939639][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2581.939799][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2581.939964][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2581.940130][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2581.940292][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2581.940469][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2581.940636][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2581.940799][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2581.940928][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2581.941112][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2581.941247][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2581.941414][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2581.941567][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2581.941725][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2581.941898][ T44] do_one_initcall (init/main.c:1284) [ 2581.942057][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2581.942214][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2581.942383][ T44] do_init_module (kernel/module/main.c:3039) [ 2581.942537][ T44] ? free_module (kernel/module/main.c:3011) [ 2581.942683][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2581.942835][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2581.943016][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2581.943168][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2581.943319][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2581.943498][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2581.943646][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2581.943795][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2581.943947][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2581.944098][ T44] ? cap_capable (security/commoncap.c:132) [ 2581.944253][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2581.944411][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2581.944560][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2581.944729][ T44] RIP: 0033:0x7f225775f34d [ 2581.944878][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2581.945075][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2581.945275][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2581.945477][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2581.945666][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2581.945860][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2581.947913][ T44] 1 lock held by modprobe/6196: | [ 2581.948052][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2581.948294][ T44] | [ 2581.948400][ T44] ============================================= | [ 2581.948400][ T44] [ 2704.817377][ T44] INFO: task modprobe:6196 blocked for more than 860 seconds. [ 2704.817694][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2704.817868][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2704.818133][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2704.818468][ T44] Call Trace: [ 2704.818604][ T44] [ 2704.818719][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2704.818888][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2704.819058][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2704.819220][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2704.819394][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2704.819532][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2704.819689][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2704.819858][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2704.820017][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2704.820175][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2704.820367][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2704.820526][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2704.820689][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2704.820825][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2704.821035][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2704.821175][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2704.821361][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2704.821527][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2704.821689][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2704.821851][ T44] do_one_initcall (init/main.c:1284) [ 2704.822014][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2704.822185][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2704.822366][ T44] do_init_module (kernel/module/main.c:3039) [ 2704.822531][ T44] ? free_module (kernel/module/main.c:3011) [ 2704.822689][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2704.822853][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2704.823032][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2704.823183][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2704.823361][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2704.823518][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2704.823667][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2704.823817][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2704.823978][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2704.824133][ T44] ? cap_capable (security/commoncap.c:132) [ 2704.824290][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2704.824483][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2704.824634][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2704.824805][ T44] RIP: 0033:0x7f225775f34d [ 2704.824958][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2704.825156][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2704.825371][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2704.825570][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2704.825764][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2704.825959][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2704.828133][ T44] 1 lock held by modprobe/6196: | [ 2704.828280][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2704.828535][ T44] | [ 2704.828631][ T44] ============================================= | [ 2704.828631][ T44] [ 2827.697382][ T44] INFO: task modprobe:6196 blocked for more than 983 seconds. [ 2827.697689][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2827.697881][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2827.698140][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2827.698487][ T44] Call Trace: [ 2827.698616][ T44] [ 2827.698727][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2827.698906][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2827.699083][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2827.699252][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2827.699434][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2827.699572][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2827.699729][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2827.699905][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2827.700073][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2827.700236][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2827.700429][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2827.700591][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2827.700751][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2827.700890][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2827.701076][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2827.701211][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2827.701390][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2827.701548][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2827.701710][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2827.701883][ T44] do_one_initcall (init/main.c:1284) [ 2827.702039][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2827.702196][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2827.702379][ T44] do_init_module (kernel/module/main.c:3039) [ 2827.702536][ T44] ? free_module (kernel/module/main.c:3011) [ 2827.702686][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2827.702841][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2827.703012][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2827.703162][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2827.703315][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2827.703485][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2827.703635][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2827.703790][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2827.703937][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2827.704092][ T44] ? cap_capable (security/commoncap.c:132) [ 2827.704241][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2827.704423][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2827.704578][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2827.704748][ T44] RIP: 0033:0x7f225775f34d [ 2827.704913][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2827.705120][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2827.705315][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2827.705530][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2827.705725][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2827.705926][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2827.708096][ T44] 1 lock held by modprobe/6196: | [ 2827.708237][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2827.708495][ T44] | [ 2827.708590][ T44] ============================================= | [ 2827.708590][ T44] [ 2950.578454][ T44] INFO: task modprobe:6196 blocked for more than 1105 seconds. [ 2950.578881][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2950.579084][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2950.579442][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 2950.579820][ T44] Call Trace: [ 2950.579963][ T44] [ 2950.580078][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2950.580264][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2950.580461][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2950.580642][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2950.580832][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2950.580973][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2950.581146][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2950.581320][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2950.581537][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2950.581710][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2950.581876][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2950.582043][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2950.582212][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2950.582364][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2950.582557][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 2950.582692][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 2950.582854][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2950.583017][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 2950.583191][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 2950.583369][ T44] do_one_initcall (init/main.c:1284) [ 2950.583533][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2950.583697][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2950.583861][ T44] do_init_module (kernel/module/main.c:3039) [ 2950.584025][ T44] ? free_module (kernel/module/main.c:3011) [ 2950.584186][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2950.584369][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2950.584562][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2950.584723][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2950.584891][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2950.585054][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 2950.585213][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2950.585423][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2950.585583][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2950.585750][ T44] ? cap_capable (security/commoncap.c:132) [ 2950.585916][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 2950.586077][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2950.586239][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2950.586443][ T44] RIP: 0033:0x7f225775f34d [ 2950.586620][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2950.586834][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 2950.587047][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 2950.587258][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 2950.587501][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e [ 2950.587713][ T44] R13: 0000000000040000 R14: 00005627d7263f10 R15: 00005627d7263570 | [ 2950.590063][ T44] 1 lock held by modprobe/6196: | [ 2950.590220][ T44] #0: ffffffff9b4909d0 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2950.590504][ T44] | [ 2950.590606][ T44] ============================================= | [ 2950.590606][ T44] [ 3073.458438][ T44] INFO: task modprobe:6196 blocked for more than 1228 seconds. [ 3073.458843][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 3073.459042][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3073.459372][ T44] task:modprobe state:D stack:25656 pid:6196 tgid:6196 ppid:36 task_flags:0x400100 flags:0x00080001 [ 3073.459770][ T44] Call Trace: [ 3073.459934][ T44] [ 3073.460053][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 3073.460238][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 3073.460435][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 3073.460620][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3073.460802][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 3073.460946][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 3073.461115][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 3073.461293][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 3073.461509][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 3073.461682][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 3073.461861][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 3073.462023][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 3073.462190][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 3073.462329][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 3073.462540][ T44] ? static_obj (kernel/locking/lockdep.c:858 (discriminator 1)) [ 3073.462676][ T44] ? lockdep_init_map_type (kernel/locking/lockdep.c:4973 (discriminator 1)) [ 3073.462841][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 3073.463004][ T44] nf_nat_init (nf_nat.mod.c:?) nf_nat [ 3073.463171][ T44] ? _sub_I_65535_1 (./include/linux/siphash.h:86 net/netfilter/nf_nat_core.c:177) nf_nat [ 3073.463352][ T44] do_one_initcall (init/main.c:1284) [ 3073.463516][ T44] ? trace_initcall_finish (init/main.c:1274) [ 3073.463678][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 3073.463843][ T44] do_init_module (kernel/module/main.c:3039) [ 3073.464007][ T44] ? free_module (kernel/module/main.c:3011) [ 3073.464166][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 3073.464327][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 3073.464533][ T44] init_module_from_file (kernel/module/main.c:3701) [ 3073.464702][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 3073.464867][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3073.465027][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141) [ 3073.465187][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 3073.465382][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 3073.465544][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 3073.465719][ T44] ? cap_capable (security/commoncap.c:132) [ 3073.465880][ T44] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3736 (discriminator 1) kernel/module/main.c:3723 (discriminator 1) kernel/module/main.c:3723 (discriminator 1)) [ 3073.466042][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 3073.466201][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 3073.466403][ T44] RIP: 0033:0x7f225775f34d [ 3073.466575][ T44] RSP: 002b:00007ffe72f62b08 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 3073.466797][ T44] RAX: ffffffffffffffda RBX: 00005627d7263f60 RCX: 00007f225775f34d [ 3073.467008][ T44] RDX: 0000000000000000 RSI: 00005627c5418f2e RDI: 0000000000000000 [ 3073.467220][ T44] RBP: 00007ffe72f62ba0 R08: 0000000000000000 R09: 0000000000000000 [ 3073.467448][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005627c5418f2e Finger prints: __schedule:schedule:schedule_preempt_disabled:rwsem_down_write_slowpath:down_write