====================================== | [ 689.911894][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 700.158893][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 710.406895][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 720.654932][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 730.902922][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 738.654987][ T45] INFO: task modprobe:18236 blocked for more than 122 seconds. [ 738.655591][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 738.655816][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 738.656204][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 738.656662][ T45] Call Trace: [ 738.656864][ T45] [ 738.657040][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 738.657303][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 738.657531][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 738.657771][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 738.658000][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 738.658229][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 738.658469][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 738.658704][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 738.658963][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 738.659200][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 738.659527][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 738.659719][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 738.660011][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 738.660249][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 738.660570][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 738.660849][ T45] do_one_initcall (init/main.c:1382) [ 738.661093][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 738.661379][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 738.661615][ T45] do_init_module (kernel/module/main.c:3039) [ 738.661851][ T45] ? free_module (kernel/module/main.c:3011) [ 738.662103][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 738.662747][ T45] init_module_from_file (kernel/module/main.c:3712) [ 738.662993][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 738.663232][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 738.663467][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 738.663701][ T45] ? 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) [ 738.663946][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 738.664178][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 738.664421][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 738.664649][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 738.664878][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 738.665123][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 738.665406][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 738.665637][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 738.665863][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 738.666154][ T45] RIP: 0033:0x7f54a2cc838d [ 738.666406][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 738.666731][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 738.667059][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 738.667383][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 738.667760][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 738.668086][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 812.886920][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 823.134908][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 833.382922][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 843.630948][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 853.879937][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 861.534983][ T45] INFO: task modprobe:18236 blocked for more than 245 seconds. [ 861.535429][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 861.535652][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.536042][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 861.536503][ T45] Call Trace: [ 861.536695][ T45] [ 861.536846][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 861.537142][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 861.537395][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 861.537658][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 861.537855][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 861.538106][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 861.538292][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 861.538466][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 861.538638][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 861.538813][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 861.539099][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 861.539241][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 861.539449][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 861.539624][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 861.539853][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 861.540075][ T45] do_one_initcall (init/main.c:1382) [ 861.540254][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 861.540466][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 861.540640][ T45] do_init_module (kernel/module/main.c:3039) [ 861.540813][ T45] ? free_module (kernel/module/main.c:3011) [ 861.541003][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 861.541177][ T45] init_module_from_file (kernel/module/main.c:3712) [ 861.541351][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 861.541530][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 861.541697][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 861.541870][ T45] ? 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) [ 861.542060][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 861.542232][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 861.542414][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 861.542603][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 861.542775][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 861.542971][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 861.543174][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 861.543350][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 861.543521][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 861.543720][ T45] RIP: 0033:0x7f54a2cc838d [ 861.543914][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 861.544158][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 861.544395][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 861.544622][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 861.544848][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 861.545098][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 935.862893][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 946.111892][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 956.359894][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 966.607930][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 976.855915][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 984.415959][ T45] INFO: task modprobe:18236 blocked for more than 368 seconds. [ 984.416312][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 984.416476][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 984.416726][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 984.417072][ T45] Call Trace: [ 984.417206][ T45] [ 984.417324][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 984.417499][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 984.417664][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 984.417832][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 984.417996][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 984.418158][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 984.418327][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 984.418493][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 984.418658][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 984.418825][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 984.419087][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 984.419231][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 984.419432][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 984.419598][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 984.419813][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 984.420030][ T45] do_one_initcall (init/main.c:1382) [ 984.420195][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 984.420406][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 984.420573][ T45] do_init_module (kernel/module/main.c:3039) [ 984.420740][ T45] ? free_module (kernel/module/main.c:3011) [ 984.420917][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 984.421089][ T45] init_module_from_file (kernel/module/main.c:3712) [ 984.421262][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 984.421430][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 984.421598][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 984.421762][ T45] ? 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) [ 984.421943][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 984.422110][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 984.422282][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 984.422446][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 984.422611][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 984.422777][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 984.422991][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 984.423154][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 984.423327][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 984.423527][ T45] RIP: 0033:0x7f54a2cc838d [ 984.423698][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 984.423943][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 984.424165][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 984.424392][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 984.424613][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 984.424835][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1058.838951][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1069.086944][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1079.334940][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1089.582948][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1099.830917][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1107.295969][ T45] INFO: task modprobe:18236 blocked for more than 491 seconds. [ 1107.296288][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1107.296446][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1107.296683][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1107.297041][ T45] Call Trace: [ 1107.297176][ T45] [ 1107.297295][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1107.297473][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1107.297643][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1107.297813][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1107.297977][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1107.298147][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1107.298339][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1107.298503][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1107.298673][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1107.298840][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1107.299109][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1107.299252][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1107.299454][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1107.299625][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1107.299851][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1107.300073][ T45] do_one_initcall (init/main.c:1382) [ 1107.300257][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1107.300447][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1107.300624][ T45] do_init_module (kernel/module/main.c:3039) [ 1107.300790][ T45] ? free_module (kernel/module/main.c:3011) [ 1107.300973][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1107.301139][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1107.301303][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1107.301465][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1107.301627][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1107.301796][ T45] ? 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) [ 1107.301989][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1107.302152][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1107.302324][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1107.302484][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1107.302650][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1107.302808][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1107.303030][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1107.303197][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1107.303361][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1107.303546][ T45] RIP: 0033:0x7f54a2cc838d [ 1107.303714][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1107.303954][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1107.304182][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1107.304408][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1107.304624][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1107.304839][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1181.823917][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1192.071957][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1202.319923][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1212.567899][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1222.814927][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1230.175968][ T45] INFO: task modprobe:18236 blocked for more than 614 seconds. [ 1230.176318][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1230.176488][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1230.176723][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1230.177075][ T45] Call Trace: [ 1230.177232][ T45] [ 1230.177343][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1230.177518][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1230.177682][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1230.177853][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1230.178020][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1230.178183][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1230.178358][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1230.178533][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1230.178699][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1230.178869][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1230.179214][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1230.179356][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1230.179553][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1230.179722][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1230.179974][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1230.180169][ T45] do_one_initcall (init/main.c:1382) [ 1230.180346][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1230.180541][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1230.180709][ T45] do_init_module (kernel/module/main.c:3039) [ 1230.180877][ T45] ? free_module (kernel/module/main.c:3011) [ 1230.181070][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1230.181248][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1230.181414][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1230.181584][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1230.181746][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1230.181928][ T45] ? 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) [ 1230.182096][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1230.182279][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1230.182449][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1230.182615][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1230.182782][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1230.182968][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1230.183169][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1230.183342][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1230.183513][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1230.183710][ T45] RIP: 0033:0x7f54a2cc838d [ 1230.183895][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1230.184133][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1230.184360][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1230.184589][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1230.184812][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1230.185052][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1304.799899][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1315.047932][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1325.295932][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1335.543893][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1345.790937][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1353.055959][ T45] INFO: task modprobe:18236 blocked for more than 737 seconds. [ 1353.056281][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1353.056434][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1353.056663][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1353.057001][ T45] Call Trace: [ 1353.057145][ T45] [ 1353.057253][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1353.057436][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1353.057595][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1353.057769][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1353.057920][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1353.058084][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1353.058252][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1353.058414][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1353.058583][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1353.058749][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1353.059011][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1353.059153][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1353.059346][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1353.059508][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1353.059724][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1353.059937][ T45] do_one_initcall (init/main.c:1382) [ 1353.060102][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1353.060300][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1353.060463][ T45] do_init_module (kernel/module/main.c:3039) [ 1353.060626][ T45] ? free_module (kernel/module/main.c:3011) [ 1353.060786][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1353.060979][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1353.061147][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1353.061313][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1353.061471][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1353.061633][ T45] ? 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) [ 1353.061793][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1353.061970][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1353.062147][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1353.062308][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1353.062468][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1353.062626][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1353.062817][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1353.063008][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1353.063182][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1353.063377][ T45] RIP: 0033:0x7f54a2cc838d [ 1353.063546][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1353.063763][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1353.063995][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1353.064219][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1353.064439][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1353.064652][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1427.774930][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1438.023894][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1448.270943][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1458.518932][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1468.767893][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1475.935004][ T45] INFO: task modprobe:18236 blocked for more than 860 seconds. [ 1475.935439][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1475.935664][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1475.936057][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1475.936520][ T45] Call Trace: [ 1475.936715][ T45] [ 1475.936866][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1475.937129][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1475.937361][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1475.937608][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1475.937798][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1475.938086][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1475.938323][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1475.938564][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1475.938804][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1475.939054][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1475.939374][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1475.939558][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1475.939818][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1475.940057][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1475.940298][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1475.940494][ T45] do_one_initcall (init/main.c:1382) [ 1475.940671][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1475.940865][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1475.941108][ T45] do_init_module (kernel/module/main.c:3039) [ 1475.941351][ T45] ? free_module (kernel/module/main.c:3011) [ 1475.941574][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1475.941779][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1475.941959][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1475.942136][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1475.942292][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1475.942451][ T45] ? 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) [ 1475.942610][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1475.942767][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1475.942942][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1475.943112][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1475.943272][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1475.943426][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1475.943616][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1475.943777][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1475.943962][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1475.944236][ T45] RIP: 0033:0x7f54a2cc838d [ 1475.944466][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1475.944700][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1475.944931][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1475.945151][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1475.945363][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1475.945580][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1550.527916][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1560.774937][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1571.022928][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1581.271914][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1591.518936][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1598.815962][ T45] INFO: task modprobe:18236 blocked for more than 983 seconds. [ 1598.816311][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1598.816476][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1598.816726][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1598.817104][ T45] Call Trace: [ 1598.817250][ T45] [ 1598.817363][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1598.817545][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1598.817716][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1598.817921][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1598.818077][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1598.818251][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1598.818426][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1598.818596][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1598.818769][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1598.818983][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1598.819220][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1598.819360][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1598.819577][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1598.819754][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1598.820003][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1598.820199][ T45] do_one_initcall (init/main.c:1382) [ 1598.820367][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1598.820562][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1598.820735][ T45] do_init_module (kernel/module/main.c:3039) [ 1598.820928][ T45] ? free_module (kernel/module/main.c:3011) [ 1598.821089][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1598.821251][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1598.821407][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1598.821569][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1598.821727][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1598.821913][ T45] ? 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) [ 1598.822073][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1598.822230][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1598.822390][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1598.822548][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1598.822709][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1598.822875][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1598.823086][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1598.823244][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1598.823401][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1598.823585][ T45] RIP: 0033:0x7f54a2cc838d [ 1598.823747][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1598.823991][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1598.824208][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1598.824419][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1598.824629][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1598.824847][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1673.502908][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1683.751904][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1693.999893][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1704.246934][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1714.495904][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1721.695960][ T45] INFO: task modprobe:18236 blocked for more than 1105 seconds. [ 1721.696307][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1721.696472][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1721.696752][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1721.697120][ T45] Call Trace: [ 1721.697268][ T45] [ 1721.697381][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1721.697558][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1721.697738][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1721.697937][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1721.698079][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1721.698251][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1721.698422][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1721.698604][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1721.698783][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1721.698992][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1721.699226][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1721.699360][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1721.699566][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1721.699761][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1721.699997][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1721.700201][ T45] do_one_initcall (init/main.c:1382) [ 1721.700374][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1721.700562][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1721.700745][ T45] do_init_module (kernel/module/main.c:3039) [ 1721.700930][ T45] ? free_module (kernel/module/main.c:3011) [ 1721.701116][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1721.701282][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1721.701439][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1721.701601][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1721.701764][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1721.701945][ T45] ? 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) [ 1721.702111][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1721.702269][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1721.702441][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1721.702599][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1721.702767][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1721.702938][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1721.703124][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1721.703288][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1721.703450][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1721.703638][ T45] RIP: 0033:0x7f54a2cc838d [ 1721.703802][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1721.704037][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1721.704248][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1721.704466][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1721.704680][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e [ 1721.704902][ T45] R13: 0000000000040000 R14: 0000559f96b31000 R15: 0000559f96b30740 | [ 1796.478928][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1806.727954][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1816.974943][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1827.222955][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 | [ 1837.470952][ T70] unregister_netdevice: waiting for veth0 to become free. Usage count = 2 [ 1844.574991][ T45] INFO: task modprobe:18236 blocked for more than 1228 seconds. [ 1844.575396][ T45] Not tainted 7.0.0-rc1-virtme #1 [ 1844.575635][ T45] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 1844.575990][ T45] task:modprobe state:D stack:25120 pid:18236 tgid:18236 ppid:18227 task_flags:0x400100 flags:0x00080000 [ 1844.576405][ T45] Call Trace: [ 1844.576586][ T45] [ 1844.576723][ T45] __schedule (kernel/sched/core.c:5298 kernel/sched/core.c:6907) [ 1844.576987][ T45] ? io_schedule_timeout (kernel/sched/core.c:6762) [ 1844.577204][ T45] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1)) [ 1844.577438][ T45] schedule (kernel/sched/core.c:6990 kernel/sched/core.c:7004) [ 1844.577618][ T45] schedule_preempt_disabled (kernel/sched/core.c:7062) [ 1844.577830][ T45] rwsem_down_write_slowpath (kernel/locking/rwsem.c:1187 (discriminator 7)) [ 1844.578086][ T45] ? rwsem_down_read_slowpath (kernel/locking/rwsem.c:1112) [ 1844.578308][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1844.578548][ T45] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33)) [ 1844.578765][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1844.579085][ T45] down_write (kernel/locking/rwsem.c:1317 kernel/locking/rwsem.c:1326 kernel/locking/rwsem.c:1591) [ 1844.579261][ T45] ? rwsem_down_write_slowpath (kernel/locking/rwsem.c:1588) [ 1844.579534][ T45] register_netdevice_notifier (net/core/dev.c:1977) [ 1844.579758][ T45] ? udp_tunnel_nic_cleanup_module (net/ipv4/udp_tunnel_core.c:82) udp_tunnel [ 1844.580077][ T45] udp_tunnel_nic_init_module (net/ipv4/udp_tunnel_core.c:14) udp_tunnel [ 1844.580340][ T45] do_one_initcall (init/main.c:1382) [ 1844.580571][ T45] ? trace_event_raw_event_initcall_level (init/main.c:1373) [ 1844.580831][ T45] ? kasan_unpoison (mm/kasan/shadow.c:156 mm/kasan/shadow.c:182) [ 1844.581075][ T45] do_init_module (kernel/module/main.c:3039) [ 1844.581301][ T45] ? free_module (kernel/module/main.c:3011) [ 1844.581535][ T45] ? backing_file_read_iter (fs/backing-file.c:210) [ 1844.581770][ T45] init_module_from_file (kernel/module/main.c:3712) [ 1844.582008][ T45] ? __do_sys_init_module (kernel/module/main.c:3677) [ 1844.582233][ T45] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536) [ 1844.582459][ T45] ? __rwlock_init (kernel/locking/spinlock_debug.c:114) [ 1844.582680][ T45] ? 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) [ 1844.582921][ T45] idempotent_init_module (kernel/module/main.c:3724) [ 1844.583145][ T45] ? init_module_from_file (kernel/module/main.c:3716) [ 1844.583379][ T45] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:752 (discriminator 1)) [ 1844.583601][ T45] ? cap_capable (./include/trace/events/capability.h:26 (discriminator 33) security/commoncap.c:130 (discriminator 33)) [ 1844.583824][ T45] __x64_sys_finit_module (./include/linux/file.h:62 (discriminator 1) ./include/linux/file.h:83 (discriminator 1) kernel/module/main.c:3747 (discriminator 1) kernel/module/main.c:3734 (discriminator 1) kernel/module/main.c:3734 (discriminator 1)) [ 1844.584055][ T45] ? trace_irq_enable.constprop.0 (./include/trace/events/preemptirq.h:40 (discriminator 33)) [ 1844.584319][ T45] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1)) [ 1844.584549][ T45] ? exc_page_fault (arch/x86/mm/fault.c:1480 (discriminator 3) arch/x86/mm/fault.c:1527 (discriminator 3)) [ 1844.584767][ T45] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:130) [ 1844.585043][ T45] RIP: 0033:0x7f54a2cc838d [ 1844.585269][ T45] RSP: 002b:00007ffd0da80d88 EFLAGS: 00000246 ORIG_RAX: 0000000000000139 [ 1844.585581][ T45] RAX: ffffffffffffffda RBX: 0000559f96b31050 RCX: 00007f54a2cc838d [ 1844.585898][ T45] RDX: 0000000000000000 RSI: 0000559f8c94ef2e RDI: 0000000000000005 [ 1844.586192][ T45] RBP: 00007ffd0da80e20 R08: 0000000000000000 R09: 0000000000000000 [ 1844.586504][ T45] R10: 0000000000000000 R11: 0000000000000246 R12: 0000559f8c94ef2e Finger prints: __schedule:schedule:schedule_preempt_disabled:rwsem_down_write_slowpath:down_write