====================================== | | Ctrl-C stdout | ^Cmake[1]: *** [../lib.mk:142: run_tests] Interrupt | make: *** [Makefile:221: run_tests] Interrupt | xx__-> [ 2213.284173][ T44] INFO: task modprobe:2687 blocked for more than 122 seconds. [ 2213.284622][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2213.284762][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2213.285008][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2213.285330][ T44] Call Trace: [ 2213.285435][ T44] [ 2213.285508][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2213.285648][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2213.285774][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2213.285898][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2213.286036][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2213.286151][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2213.286275][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2213.286398][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2213.286522][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2213.286652][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2213.286771][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2213.286884][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2213.287005][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2213.287095][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2213.287260][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2213.287407][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2213.287523][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2213.287664][ T44] do_one_initcall (init/main.c:1284) [ 2213.287778][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2213.287896][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2213.288011][ T44] do_init_module (kernel/module/main.c:3039) [ 2213.288139][ T44] ? free_module (kernel/module/main.c:3011) [ 2213.288587][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2213.288699][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2213.288839][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2213.288958][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2213.289080][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2213.289205][ 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.289318][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2213.289429][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2213.289544][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2213.289661][ T44] ? cap_capable (security/commoncap.c:132) [ 2213.289774][ 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.289890][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2213.290002][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2213.290160][ T44] RIP: 0033:0x7f1f486ec34d [ 2213.290281][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2213.290451][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2213.290630][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2213.290798][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2213.290965][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2213.291141][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2213.292748][ T44] 1 lock held by modprobe/2687: | [ 2213.292858][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2213.293072][ T44] | [ 2213.293140][ T44] ============================================= | [ 2213.293140][ T44] [ 2336.164209][ T44] INFO: task modprobe:2687 blocked for more than 245 seconds. [ 2336.164525][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2336.164656][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2336.164887][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2336.165194][ T44] Call Trace: [ 2336.165293][ T44] [ 2336.165362][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2336.165496][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2336.165634][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2336.165769][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2336.165898][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2336.165995][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2336.166119][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2336.166275][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2336.166399][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2336.166528][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2336.166651][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2336.166775][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2336.166907][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2336.166997][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2336.167147][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2336.167291][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2336.167410][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2336.167551][ T44] do_one_initcall (init/main.c:1284) [ 2336.167667][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2336.167781][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2336.167897][ T44] do_init_module (kernel/module/main.c:3039) [ 2336.168022][ T44] ? free_module (kernel/module/main.c:3011) [ 2336.168141][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2336.168258][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2336.168397][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2336.168509][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2336.168629][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2336.168740][ 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.168853][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2336.168972][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2336.169085][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2336.169206][ T44] ? cap_capable (security/commoncap.c:132) [ 2336.169321][ 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.169434][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2336.169551][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2336.169691][ T44] RIP: 0033:0x7f1f486ec34d [ 2336.169818][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2336.170000][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2336.170182][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2336.170356][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2336.170528][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2336.170702][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2336.172339][ T44] 1 lock held by modprobe/2687: | [ 2336.172442][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2336.172660][ T44] | [ 2336.172717][ T44] ============================================= | [ 2336.172717][ T44] [ 2459.043184][ T44] INFO: task modprobe:2687 blocked for more than 368 seconds. [ 2459.043473][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2459.043603][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2459.043820][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2459.044115][ T44] Call Trace: [ 2459.044230][ T44] [ 2459.044300][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2459.044434][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2459.044560][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2459.044689][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2459.044826][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2459.044934][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2459.045063][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2459.045208][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2459.045335][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2459.045451][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2459.045572][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2459.045689][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2459.045826][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2459.045922][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2459.046073][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2459.046246][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2459.046365][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2459.046513][ T44] do_one_initcall (init/main.c:1284) [ 2459.046628][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2459.046753][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2459.046868][ T44] do_init_module (kernel/module/main.c:3039) [ 2459.046986][ T44] ? free_module (kernel/module/main.c:3011) [ 2459.047103][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2459.047220][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2459.047359][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2459.047475][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2459.047598][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2459.047709][ 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.047820][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2459.047931][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2459.048041][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2459.048166][ T44] ? cap_capable (security/commoncap.c:132) [ 2459.048277][ 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.048396][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2459.048508][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2459.048646][ T44] RIP: 0033:0x7f1f486ec34d [ 2459.048779][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2459.048948][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2459.049118][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2459.049293][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2459.049460][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2459.049624][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2459.051225][ T44] 1 lock held by modprobe/2687: | [ 2459.051330][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2459.051549][ T44] | [ 2459.051602][ T44] ============================================= | [ 2459.051602][ T44] [ 2581.924202][ T44] INFO: task modprobe:2687 blocked for more than 491 seconds. [ 2581.924551][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2581.924689][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2581.924934][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2581.925308][ T44] Call Trace: [ 2581.925419][ T44] [ 2581.925495][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2581.925630][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2581.925754][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2581.925892][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2581.926086][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2581.926187][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2581.926306][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2581.926426][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2581.926551][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2581.926669][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2581.926787][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2581.926908][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2581.927035][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2581.927156][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2581.927307][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2581.927454][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2581.927566][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2581.927712][ T44] do_one_initcall (init/main.c:1284) [ 2581.927827][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2581.927945][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2581.928070][ T44] do_init_module (kernel/module/main.c:3039) [ 2581.928196][ T44] ? free_module (kernel/module/main.c:3011) [ 2581.928312][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2581.928422][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2581.928559][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2581.928680][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2581.928797][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2581.928908][ 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.929022][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2581.929141][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2581.929256][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2581.929376][ T44] ? cap_capable (security/commoncap.c:132) [ 2581.929493][ 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.929604][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2581.929713][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2581.929853][ T44] RIP: 0033:0x7f1f486ec34d [ 2581.929970][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2581.930151][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2581.930314][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2581.930478][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2581.930642][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2581.930807][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2581.932419][ T44] 1 lock held by modprobe/2687: | [ 2581.932524][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2581.932758][ T44] | [ 2581.932814][ T44] ============================================= | [ 2581.932814][ T44] [ 2704.803203][ T44] INFO: task modprobe:2687 blocked for more than 614 seconds. [ 2704.803555][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2704.803694][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2704.803931][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2704.804280][ T44] Call Trace: [ 2704.804390][ T44] [ 2704.804470][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2704.804633][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2704.804766][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2704.804892][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2704.805016][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2704.805110][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2704.805271][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2704.805403][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2704.805534][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2704.805653][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2704.805772][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2704.805891][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2704.806014][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2704.806114][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2704.806272][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2704.806433][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2704.806552][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2704.806709][ T44] do_one_initcall (init/main.c:1284) [ 2704.806824][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2704.806943][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2704.807061][ T44] do_init_module (kernel/module/main.c:3039) [ 2704.807192][ T44] ? free_module (kernel/module/main.c:3011) [ 2704.807310][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2704.807431][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2704.807573][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2704.807684][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2704.807813][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2704.807925][ 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.808041][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2704.808162][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2704.808277][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2704.808394][ T44] ? cap_capable (security/commoncap.c:132) [ 2704.808508][ 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.808622][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2704.808735][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2704.808880][ T44] RIP: 0033:0x7f1f486ec34d [ 2704.809008][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2704.809198][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2704.809367][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2704.809535][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2704.809708][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2704.809877][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2704.811506][ T44] 1 lock held by modprobe/2687: | [ 2704.811611][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2704.811838][ T44] | [ 2704.811894][ T44] ============================================= | [ 2704.811894][ T44] [ 2827.684187][ T44] INFO: task modprobe:2687 blocked for more than 737 seconds. [ 2827.684515][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2827.684662][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2827.684895][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2827.685229][ T44] Call Trace: [ 2827.685331][ T44] [ 2827.685411][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2827.685553][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2827.685692][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2827.685842][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2827.685979][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2827.686086][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2827.686213][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2827.686335][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2827.686457][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2827.686575][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2827.686698][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2827.686819][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2827.686945][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2827.687032][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2827.687208][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2827.687361][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2827.687481][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2827.687634][ T44] do_one_initcall (init/main.c:1284) [ 2827.687765][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2827.687886][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2827.688000][ T44] do_init_module (kernel/module/main.c:3039) [ 2827.688117][ T44] ? free_module (kernel/module/main.c:3011) [ 2827.688247][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2827.688368][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2827.688523][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2827.688654][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2827.688772][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2827.688884][ 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.688996][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2827.689106][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2827.689222][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2827.689336][ T44] ? cap_capable (security/commoncap.c:132) [ 2827.689456][ 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.689567][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2827.689687][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2827.689825][ T44] RIP: 0033:0x7f1f486ec34d [ 2827.689943][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2827.690112][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2827.690289][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2827.690456][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2827.690622][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2827.690788][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2827.692410][ T44] 1 lock held by modprobe/2687: | [ 2827.692514][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2827.692731][ T44] | [ 2827.692787][ T44] ============================================= | [ 2827.692787][ T44] [ 2950.563164][ T44] INFO: task modprobe:2687 blocked for more than 860 seconds. [ 2950.563472][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 2950.563627][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 2950.563875][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 2950.564201][ T44] Call Trace: [ 2950.564290][ T44] [ 2950.564361][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 2950.564485][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 2950.564615][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 2950.564736][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2950.564866][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 2950.564959][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 2950.565079][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 2950.565202][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 2950.565325][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 2950.565443][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 2950.565558][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 2950.565672][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 2950.565790][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 2950.565878][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 2950.566030][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 2950.566209][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 2950.566321][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 2950.566465][ T44] do_one_initcall (init/main.c:1284) [ 2950.566577][ T44] ? trace_initcall_finish (init/main.c:1274) [ 2950.566693][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 2950.566807][ T44] do_init_module (kernel/module/main.c:3039) [ 2950.566921][ T44] ? free_module (kernel/module/main.c:3011) [ 2950.567043][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 2950.567162][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 2950.567309][ T44] init_module_from_file (kernel/module/main.c:3701) [ 2950.567426][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 2950.567541][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 2950.567655][ 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.567776][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 2950.567892][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 2950.568001][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 2950.568116][ T44] ? cap_capable (security/commoncap.c:132) [ 2950.568236][ 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.568355][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 2950.568472][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 2950.568611][ T44] RIP: 0033:0x7f1f486ec34d [ 2950.568728][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 2950.568894][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 2950.569061][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 2950.569238][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 2950.569409][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 2950.569581][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 2950.571177][ T44] 1 lock held by modprobe/2687: | [ 2950.571287][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 2950.571506][ T44] | [ 2950.571560][ T44] ============================================= | [ 2950.571560][ T44] [ 3073.443174][ T44] INFO: task modprobe:2687 blocked for more than 983 seconds. [ 3073.443480][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 3073.443611][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3073.443832][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 3073.444159][ T44] Call Trace: [ 3073.444262][ T44] [ 3073.444334][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 3073.444461][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 3073.444582][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 3073.444703][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3073.444825][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 3073.444920][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 3073.445040][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 3073.445167][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 3073.445286][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 3073.445404][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 3073.445516][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 3073.445633][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 3073.445756][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 3073.445843][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 3073.445985][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 3073.446151][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 3073.446271][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 3073.446420][ T44] do_one_initcall (init/main.c:1284) [ 3073.446531][ T44] ? trace_initcall_finish (init/main.c:1274) [ 3073.446652][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 3073.446765][ T44] do_init_module (kernel/module/main.c:3039) [ 3073.446878][ T44] ? free_module (kernel/module/main.c:3011) [ 3073.446990][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 3073.447102][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 3073.447252][ T44] init_module_from_file (kernel/module/main.c:3701) [ 3073.447364][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 3073.447487][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3073.447597][ 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.447705][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 3073.447815][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 3073.447925][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 3073.448040][ T44] ? cap_capable (security/commoncap.c:132) [ 3073.448159][ 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.448268][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 3073.448377][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 3073.448527][ T44] RIP: 0033:0x7f1f486ec34d [ 3073.448640][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 3073.448817][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 3073.448990][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 3073.449156][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 3073.449319][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 3073.449485][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 3073.451084][ T44] 1 lock held by modprobe/2687: | [ 3073.451196][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 3073.451411][ T44] | [ 3073.451467][ T44] ============================================= | [ 3073.451467][ T44] [ 3196.323203][ T44] INFO: task modprobe:2687 blocked for more than 1105 seconds. [ 3196.323546][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 3196.323699][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3196.323929][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 3196.324253][ T44] Call Trace: [ 3196.324360][ T44] [ 3196.324436][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 3196.324589][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 3196.324717][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 3196.324840][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3196.324962][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 3196.325056][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 3196.325181][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 3196.325300][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 3196.325422][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 3196.325556][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 3196.325671][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 3196.325788][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 3196.325908][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 3196.325995][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 3196.326161][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 3196.326312][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 3196.326438][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 3196.326584][ T44] do_one_initcall (init/main.c:1284) [ 3196.326698][ T44] ? trace_initcall_finish (init/main.c:1274) [ 3196.326810][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 3196.326930][ T44] do_init_module (kernel/module/main.c:3039) [ 3196.327052][ T44] ? free_module (kernel/module/main.c:3011) [ 3196.327171][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 3196.327285][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 3196.327422][ T44] init_module_from_file (kernel/module/main.c:3701) [ 3196.327539][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 3196.327655][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3196.327766][ 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) [ 3196.327877][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 3196.327991][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 3196.328100][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 3196.328221][ T44] ? cap_capable (security/commoncap.c:132) [ 3196.328333][ 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)) [ 3196.328445][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 3196.328564][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 3196.328700][ T44] RIP: 0033:0x7f1f486ec34d [ 3196.328822][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 3196.328987][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 3196.329158][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 3196.329322][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 3196.329495][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e [ 3196.329661][ T44] R13: 0000000000040000 R14: 0000557a048cdd90 R15: 0000557a048cd570 | [ 3196.331310][ T44] 1 lock held by modprobe/2687: | [ 3196.331412][ T44] #0: ffffffff99090550 (pernet_ops_rwsem){++++}-{4:4}, at: register_pernet_subsys (net/core/net_namespace.c:1430) | [ 3196.331637][ T44] | [ 3196.331692][ T44] ============================================= | [ 3196.331692][ T44] [ 3319.204200][ T44] INFO: task modprobe:2687 blocked for more than 1228 seconds. [ 3319.204540][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 3319.204692][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 3319.204950][ T44] task:modprobe state:D stack:25656 pid:2687 tgid:2687 ppid:38 task_flags:0x400100 flags:0x00080001 [ 3319.205310][ T44] Call Trace: [ 3319.205425][ T44] [ 3319.205503][ T44] __schedule (kernel/sched/core.c:5328 kernel/sched/core.c:6929) [ 3319.205658][ T44] ? io_schedule_timeout (kernel/sched/core.c:6785) [ 3319.205801][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870) [ 3319.205921][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3319.206039][ T44] schedule (kernel/sched/core.c:7012 kernel/sched/core.c:7026) [ 3319.206136][ T44] schedule_preempt_disabled (kernel/sched/core.c:7084) [ 3319.206259][ T44] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 3319.206377][ T44] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 3319.206495][ T44] ? trace_lock_acquire (./include/trace/events/lock.h:24 (discriminator 33)) [ 3319.206609][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8882) [ 3319.206727][ T44] ? lock_acquire (kernel/locking/lockdep.c:5833) [ 3319.206839][ T44] ? register_pernet_subsys (net/core/net_namespace.c:1430) [ 3319.206961][ T44] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 3319.207045][ T44] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 3319.207215][ T44] ? _sub_I_65535_1 (net/netfilter/nf_flow_table_core.c:618 net/netfilter/nf_flow_table_core.c:631 net/netfilter/nf_flow_table_core.c:684) nf_flow_table [ 3319.207358][ T44] register_pernet_subsys (net/core/net_namespace.c:1430) [ 3319.207472][ T44] nf_flow_table_module_init (nf_flow_table.mod.c:?) nf_flow_table [ 3319.207612][ T44] do_one_initcall (init/main.c:1284) [ 3319.207730][ T44] ? trace_initcall_finish (init/main.c:1274) [ 3319.207848][ T44] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 3319.207963][ T44] do_init_module (kernel/module/main.c:3039) [ 3319.208076][ T44] ? free_module (kernel/module/main.c:3011) [ 3319.208192][ T44] ? backing_file_read_iter (fs/backing-file.c:206) [ 3319.208302][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4032 (discriminator 2) kernel/rcu/tree.c:4024 (discriminator 2)) [ 3319.208437][ T44] init_module_from_file (kernel/module/main.c:3701) [ 3319.208546][ T44] ? __do_sys_init_module (kernel/module/main.c:3677) [ 3319.208671][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 3319.208786][ 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) [ 3319.208895][ T44] idempotent_init_module (kernel/module/main.c:3713) [ 3319.209008][ T44] ? init_module_from_file (kernel/module/main.c:3705) [ 3319.209117][ T44] ? rseq_update_cpu_node_id (kernel/rseq.c:189) [ 3319.209237][ T44] ? cap_capable (security/commoncap.c:132) [ 3319.209354][ 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)) [ 3319.209464][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 3319.209575][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 3319.209713][ T44] RIP: 0033:0x7f1f486ec34d [ 3319.209830][ T44] RSP: 002b:00007ffe5a521408 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 3319.209995][ T44] RAX: ffffffffffffffda RBX: 0000557a048cdde0 RCX: 00007f1f486ec34d [ 3319.210166][ T44] RDX: 0000000000000000 RSI: 00005579d6a0ff2e RDI: 0000000000000000 [ 3319.210329][ T44] RBP: 00007ffe5a5214a0 R08: 0000000000000000 R09: 0000000000000000 [ 3319.210492][ T44] R10: 0000000000000000 R11: 0000000000000246 R12: 00005579d6a0ff2e Finger prints: __schedule:schedule:schedule_preempt_disabled:rwsem_down_write_slowpath:down_write