[ 793.128617][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 5311 ms [ 793.129436][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 5311000 usecs ago [ 798.568591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 10751 ms [ 798.569014][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 10751000 usecs ago [ 804.200590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 16383 ms [ 804.201020][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 16383000 usecs ago [ 809.320590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 21503 ms [ 809.321006][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 21503000 usecs ago [ 814.440596][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 26623 ms [ 814.441189][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 26623000 usecs ago [ 820.584600][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 32767 ms [ 820.585230][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 32767000 usecs ago [ 826.216590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 38399 ms [ 826.217027][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 38399000 usecs ago [ 831.336591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 43519 ms [ 831.337022][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 43519000 usecs ago [ 836.456591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 48639 ms [ 836.457141][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 48639000 usecs ago [ 842.600593][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 54783 ms [ 842.601021][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 54783000 usecs ago [ 848.232590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 60415 ms [ 848.233143][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 60415000 usecs ago [ 853.352595][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 65535 ms [ 853.353232][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 65535000 usecs ago [ 859.496590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 71679 ms [ 859.497024][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 71679000 usecs ago [ 861.544665][ T44] INFO: task ip:6029 blocked for more than 122 seconds. [ 861.545189][ T44] Not tainted 6.18.0-virtme #1 [ 861.545432][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.545829][ T44] task:ip state:D stack:25008 pid:6029 tgid:6029 ppid:6024 task_flags:0x400100 flags:0x00080800 [ 861.546328][ T44] Call Trace: [ 861.546525][ T44] [ 861.546700][ T44] __schedule+0xae2/0x1720 [ 861.546971][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.547221][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.547468][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.547717][ T44] schedule+0xd4/0x260 [ 861.547923][ T44] schedule_preempt_disabled+0x18/0x30 [ 861.548164][ T44] __mutex_lock+0xd76/0x1f20 [ 861.548400][ T44] ? napi_enable+0x3b/0x80 [ 861.548653][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.548896][ T44] ? __asan_memset+0x27/0x50 [ 861.549142][ T44] ? sg_init_table+0x19/0x60 [ 861.549393][ T44] ? try_fill_recv+0x3ec/0x1750 [ 861.549654][ T44] ? napi_enable+0x3b/0x80 [ 861.549895][ T44] napi_enable+0x3b/0x80 [ 861.550091][ T44] __virtnet_rx_resume+0x156/0x290 [ 861.550334][ T44] ? queue_delayed_work_on+0x91/0x100 [ 861.550592][ T44] virtnet_rx_resume_all+0xe7/0x1a0 [ 861.550829][ T44] virtnet_xdp_set+0xef7/0x1aa0 [ 861.551155][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0 [ 861.551386][ T44] dev_xdp_install+0x3e4/0x890 [ 861.551625][ T44] ? alloc_netdev_dummy+0x30/0x30 [ 861.551869][ T44] dev_xdp_attach+0x50d/0x1310 [ 861.552106][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0 [ 861.552749][ T44] ? __bpf_prog_get+0x320/0x320 [ 861.552990][ T44] dev_change_xdp_fd+0x176/0x210 [ 861.553237][ T44] do_setlink.isra.0+0x1c2d/0x2880 [ 861.553474][ T44] ? rtnl_newlink_create+0x900/0x900 [ 861.553717][ T44] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 861.554004][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 861.554240][ T44] ? validate_chain+0x30f/0x8e0 [ 861.554466][ T44] ? rcu_read_lock_any_held+0x66/0x90 [ 861.554710][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 861.554952][ T44] ? __lock_acquire+0x577/0xc10 [ 861.555195][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 861.555421][ T44] ? rtnl_newlink+0x5f9/0xb40 [ 861.555671][ T44] ? lock_acquire+0x10a/0x150 [ 861.555905][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.556133][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.556360][ T44] ? trace_contention_end+0xd6/0x110 [ 861.556614][ T44] ? __mutex_lock+0x285/0x1f20 [ 861.556842][ T44] ? rtnl_newlink+0x5f9/0xb40 [ 861.557075][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.557304][ T44] ? nla_validate_int_range+0x5b0/0x5b0 [ 861.557541][ T44] ? __rtnl_newlink+0x3fa/0xa40 [ 861.557794][ T44] rtnl_newlink+0x649/0xb40 [ 861.558044][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.558277][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.558517][ T44] ? __lock_acquire+0x577/0xc10 [ 861.558767][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.559002][ T44] ? find_held_lock+0x2b/0x80 [ 861.559232][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.559463][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.559713][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.559953][ T44] rtnetlink_rcv_msg+0x6fe/0xb90 [ 861.560184][ T44] ? rtnl_port_fill+0x940/0x940 [ 861.560418][ T44] ? __lock_acquire+0x577/0xc10 [ 861.560670][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.560900][ T44] ? find_held_lock+0x2b/0x80 [ 861.561130][ T44] netlink_rcv_skb+0x123/0x380 [ 861.561360][ T44] ? rtnl_port_fill+0x940/0x940 [ 861.561611][ T44] ? netlink_ack+0xcc0/0xcc0 [ 861.561848][ T44] ? netlink_deliver_tap+0xc5/0x330 [ 861.562095][ T44] ? netlink_deliver_tap+0x13f/0x330 [ 861.562330][ T44] netlink_unicast+0x4a3/0x770 [ 861.562562][ T44] ? netlink_attachskb+0x810/0x810 [ 861.562807][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.563048][ T44] ? __lock_acquire+0x577/0xc10 [ 861.563283][ T44] netlink_sendmsg+0x735/0xc60 [ 861.563517][ T44] ? netlink_unicast+0x770/0x770 [ 861.563770][ T44] ____sys_sendmsg+0x419/0x850 [ 861.564023][ T44] ? copy_msghdr_from_user+0x270/0x430 [ 861.564257][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 861.564532][ T44] ? move_addr_to_kernel+0x40/0x40 [ 861.564776][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 861.565018][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 861.565256][ T44] ___sys_sendmsg+0xfd/0x180 [ 861.565493][ T44] ? __call_rcu_common.constprop.0+0xa6/0xa00 [ 861.565780][ T44] ? copy_msghdr_from_user+0x430/0x430 [ 861.566050][ T44] __sys_sendmsg+0x124/0x1c0 [ 861.566286][ T44] ? __sys_sendmsg_sock+0x20/0x20 [ 861.566516][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.566762][ T44] ? __call_rcu_common.constprop.0+0x3bd/0xa00 [ 861.567050][ T44] ? do_raw_spin_unlock+0x59/0x250 [ 861.567293][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.567528][ T44] do_syscall_64+0xbd/0xfc0 [ 861.567774][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 861.568048][ T44] RIP: 0033:0x7fca6ae83c5e [ 861.568235][ T44] RSP: 002b:00007ffc61499a60 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 861.568468][ T44] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007fca6ae83c5e [ 861.568717][ T44] RDX: 0000000000000000 RSI: 00007ffc61499b20 RDI: 0000000000000003 [ 861.568973][ T44] RBP: 00007ffc61499a70 R08: 0000000000000000 R09: 0000000000000000 [ 861.569197][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffc6149a1e0 [ 861.569423][ T44] R13: 00000000693cb235 R14: 0000000000000000 R15: 0000000000499600 [ 861.569674][ T44] [ 861.569874][ T44] INFO: task ip:6029 is blocked on a mutex likely owned by task kworker/3:2:68. [ 861.570133][ T44] task:kworker/3:2 state:D stack:28112 pid:68 tgid:68 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 861.570441][ T44] Workqueue: events refill_work [ 861.570622][ T44] Call Trace: [ 861.570776][ T44] [ 861.570889][ T44] __schedule+0xae2/0x1720 [ 861.571070][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.571237][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.571407][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.571591][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.571768][ T44] schedule+0xd4/0x260 [ 861.571912][ T44] schedule_hrtimeout_range_clock+0x1da/0x2f0 [ 861.572111][ T44] ? schedule_timeout_idle+0xe0/0xe0 [ 861.572283][ T44] ? __hrtimer_cb_get_time+0x50/0x50 [ 861.572452][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.572628][ T44] ? ktime_get+0x15e/0x220 [ 861.572806][ T44] ? kvm_clock_get_cycles+0x18/0x30 [ 861.572981][ T44] ? ktime_get+0xa9/0x220 [ 861.573181][ T44] usleep_range_state+0x133/0x1b0 [ 861.573398][ T44] ? schedule_hrtimeout+0x20/0x20 [ 861.573607][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 861.573800][ T44] ? refill_work+0x149/0x210 [ 861.573995][ T44] napi_disable_locked+0x379/0x5f0 [ 861.574180][ T44] ? _mutex_trylock_nest_lock+0x340/0x340 [ 861.574369][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470 [ 861.574605][ T44] napi_disable+0x43/0x80 [ 861.574769][ T44] refill_work+0x116/0x210 [ 861.575015][ T44] process_one_work+0xd57/0x1390 [ 861.575248][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 861.575498][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.575759][ T44] ? assign_work+0x152/0x380 [ 861.576001][ T44] worker_thread+0x4d6/0xd40 [ 861.576243][ T44] ? process_one_work+0x1390/0x1390 [ 861.576462][ T44] ? __kthread_parkme+0xb3/0x200 [ 861.576722][ T44] ? process_one_work+0x1390/0x1390 [ 861.576974][ T44] kthread+0x355/0x5b0 [ 861.577169][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.577409][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.577668][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.577917][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.578161][ T44] ret_from_fork+0x3fb/0x510 [ 861.578409][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 [ 861.578712][ T44] ? __switch_to+0x602/0xd00 [ 861.578953][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.579189][ T44] ret_from_fork_asm+0x11/0x20 [ 861.579434][ T44] [ 861.579637][ T44] [ 861.579637][ T44] Showing all locks held in the system: [ 861.579951][ T44] 3 locks held by kworker/u16:0/12: [ 861.580173][ T44] #0: ff110000090f6548 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.580589][ T44] #1: ffa00000000c7cc0 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.581067][ T44] #2: ffffffffb8e78aa8 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30 [ 861.581426][ T44] 1 lock held by khungtaskd/44: [ 861.581669][ T44] #0: ffffffffb8770ba0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire.constprop.0+0x7/0x30 [ 861.582074][ T44] 3 locks held by kworker/3:2/68: [ 861.582293][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.582702][ T44] #1: ffa0000000497cc0 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.583148][ T44] #2: ff11000008adac98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80 [ 861.583515][ T44] 2 locks held by ip/6029: [ 861.583749][ T44] #0: ffffffffb8e78aa8 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5f9/0xb40 [ 861.584108][ T44] #1: ff11000008adac98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80 [ 861.584452][ T44] [ 861.584606][ T44] ============================================= [ 861.584606][ T44] [ 865.128594][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 77311 ms [ 865.129262][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 77311000 usecs ago [ 870.248590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 82431 ms [ 870.249219][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 82431000 usecs ago [ 875.368590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 87551 ms [ 875.369012][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 87551000 usecs ago [ 881.512590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 93695 ms [ 881.513011][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 93695000 usecs ago [ 887.144591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 99327 ms [ 887.145013][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 99327000 usecs ago [ 892.264597][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 104447 ms [ 892.265281][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 104447000 usecs ago [ 897.384595][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 109567 ms [ 897.385268][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 109567000 usecs ago [ 903.528589][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 115711 ms [ 903.529061][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 115711000 usecs ago [ 909.160591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 121343 ms [ 909.161057][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 121343000 usecs ago [ 914.280590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 126463 ms [ 914.281051][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 126463000 usecs ago [ 919.400589][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 131583 ms [ 919.401046][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 131583000 usecs ago [ 925.544595][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 137727 ms [ 925.545255][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 137727000 usecs ago [ 931.176590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 143359 ms [ 931.177064][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 143359000 usecs ago [ 936.296590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 148479 ms [ 936.297048][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 148479000 usecs ago [ 941.416591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 153599 ms [ 941.417058][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 153599000 usecs ago [ 947.560617][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 159743 ms [ 947.561258][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 159743000 usecs ago [ 953.192590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 165375 ms [ 953.193069][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 165375000 usecs ago [ 958.312595][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 170495 ms [ 958.313277][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 170495000 usecs ago [ 963.432598][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 175615 ms [ 963.433300][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 175615000 usecs ago [ 969.576594][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 181759 ms [ 969.577256][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 181759000 usecs ago [ 975.208588][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 187391 ms [ 975.209083][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 187391000 usecs ago [ 980.328591][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 192511 ms [ 980.329073][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 192511000 usecs ago [ 984.425601][ T44] INFO: task kworker/u16:0:12 blocked for more than 122 seconds. [ 984.425945][ T44] Not tainted 6.18.0-virtme #1 [ 984.426126][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 984.426406][ T44] task:kworker/u16:0 state:D stack:26832 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 984.426803][ T44] Workqueue: ipv6_addrconf addrconf_verify_work [ 984.427024][ T44] Call Trace: [ 984.427171][ T44] [ 984.427290][ T44] __schedule+0xae2/0x1720 [ 984.427497][ T44] ? io_schedule_timeout+0x130/0x130 [ 984.427694][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.427897][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.428081][ T44] schedule+0xd4/0x260 [ 984.428228][ T44] schedule_preempt_disabled+0x18/0x30 [ 984.428402][ T44] __mutex_lock+0xd76/0x1f20 [ 984.428600][ T44] ? addrconf_verify_work+0x12/0x30 [ 984.428785][ T44] ? ww_mutex_lock+0x160/0x160 [ 984.428966][ T44] ? process_one_work+0xd16/0x1390 [ 984.429150][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.429331][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.429507][ T44] ? addrconf_verify_work+0x12/0x30 [ 984.429691][ T44] addrconf_verify_work+0x12/0x30 [ 984.429871][ T44] process_one_work+0xd57/0x1390 [ 984.430041][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 984.430206][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 984.430381][ T44] ? assign_work+0x152/0x380 [ 984.430548][ T44] worker_thread+0x4d6/0xd40 [ 984.430739][ T44] ? process_one_work+0x1390/0x1390 [ 984.430907][ T44] kthread+0x355/0x5b0 [ 984.431046][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.431211][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.431375][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.431539][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.431729][ T44] ret_from_fork+0x3fb/0x510 [ 984.431899][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 [ 984.432093][ T44] ? __switch_to+0x602/0xd00 [ 984.432258][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.432424][ T44] ret_from_fork_asm+0x11/0x20 [ 984.432610][ T44] [ 984.432788][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:6029. [ 984.433051][ T44] INFO: task ip:6029 blocked for more than 245 seconds. [ 984.433231][ T44] Not tainted 6.18.0-virtme #1 [ 984.433383][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 984.433625][ T44] task:ip state:D stack:25008 pid:6029 tgid:6029 ppid:6024 task_flags:0x400100 flags:0x00080800 [ 984.433953][ T44] Call Trace: [ 984.434090][ T44] [ 984.434196][ T44] __schedule+0xae2/0x1720 [ 984.434365][ T44] ? io_schedule_timeout+0x130/0x130 [ 984.434531][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.434715][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.434888][ T44] schedule+0xd4/0x260 [ 984.435025][ T44] schedule_preempt_disabled+0x18/0x30 [ 984.435193][ T44] __mutex_lock+0xd76/0x1f20 [ 984.435360][ T44] ? napi_enable+0x3b/0x80 [ 984.435527][ T44] ? ww_mutex_lock+0x160/0x160 [ 984.435717][ T44] ? __asan_memset+0x27/0x50 [ 984.435886][ T44] ? sg_init_table+0x19/0x60 [ 984.436055][ T44] ? try_fill_recv+0x3ec/0x1750 [ 984.436223][ T44] ? napi_enable+0x3b/0x80 [ 984.436386][ T44] napi_enable+0x3b/0x80 [ 984.436764][ T44] __virtnet_rx_resume+0x156/0x290 [ 984.436931][ T44] ? queue_delayed_work_on+0x91/0x100 [ 984.437098][ T44] virtnet_rx_resume_all+0xe7/0x1a0 [ 984.437263][ T44] virtnet_xdp_set+0xef7/0x1aa0 [ 984.437431][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0 [ 984.437608][ T44] dev_xdp_install+0x3e4/0x890 [ 984.437784][ T44] ? alloc_netdev_dummy+0x30/0x30 [ 984.437960][ T44] dev_xdp_attach+0x50d/0x1310 [ 984.438127][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0 [ 984.438291][ T44] ? __bpf_prog_get+0x320/0x320 [ 984.438459][ T44] dev_change_xdp_fd+0x176/0x210 [ 984.438639][ T44] do_setlink.isra.0+0x1c2d/0x2880 [ 984.438819][ T44] ? rtnl_newlink_create+0x900/0x900 [ 984.438984][ T44] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 984.439183][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 984.439349][ T44] ? validate_chain+0x30f/0x8e0 [ 984.439515][ T44] ? rcu_read_lock_any_held+0x66/0x90 [ 984.439695][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 984.439874][ T44] ? __lock_acquire+0x577/0xc10 [ 984.440048][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 984.440212][ T44] ? rtnl_newlink+0x5f9/0xb40 [ 984.440382][ T44] ? lock_acquire+0x10a/0x150 [ 984.440545][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.440726][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.440893][ T44] ? trace_contention_end+0xd6/0x110 [ 984.441060][ T44] ? __mutex_lock+0x285/0x1f20 [ 984.441226][ T44] ? rtnl_newlink+0x5f9/0xb40 [ 984.441393][ T44] ? ww_mutex_lock+0x160/0x160 [ 984.441558][ T44] ? nla_validate_int_range+0x5b0/0x5b0 [ 984.441756][ T44] ? __rtnl_newlink+0x3fa/0xa40 [ 984.441931][ T44] rtnl_newlink+0x649/0xb40 [ 984.442101][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.442265][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 984.442432][ T44] ? __lock_acquire+0x577/0xc10 [ 984.442614][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 984.442792][ T44] ? find_held_lock+0x2b/0x80 [ 984.442959][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 984.443130][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.443296][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 984.443460][ T44] rtnetlink_rcv_msg+0x6fe/0xb90 [ 984.443643][ T44] ? rtnl_port_fill+0x940/0x940 [ 984.443821][ T44] ? __lock_acquire+0x577/0xc10 [ 984.443986][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 984.444150][ T44] ? find_held_lock+0x2b/0x80 [ 984.444326][ T44] netlink_rcv_skb+0x123/0x380 [ 984.444492][ T44] ? rtnl_port_fill+0x940/0x940 [ 984.444671][ T44] ? netlink_ack+0xcc0/0xcc0 [ 984.444850][ T44] ? netlink_deliver_tap+0xc5/0x330 [ 984.445013][ T44] ? netlink_deliver_tap+0x13f/0x330 [ 984.445178][ T44] netlink_unicast+0x4a3/0x770 [ 984.445344][ T44] ? netlink_attachskb+0x810/0x810 [ 984.445506][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 984.445685][ T44] ? __lock_acquire+0x577/0xc10 [ 984.445861][ T44] netlink_sendmsg+0x735/0xc60 [ 984.446026][ T44] ? netlink_unicast+0x770/0x770 [ 984.446194][ T44] ____sys_sendmsg+0x419/0x850 [ 984.446361][ T44] ? copy_msghdr_from_user+0x270/0x430 [ 984.446524][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 984.446738][ T44] ? move_addr_to_kernel+0x40/0x40 [ 984.446911][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 984.447073][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 984.447238][ T44] ___sys_sendmsg+0xfd/0x180 [ 984.447401][ T44] ? __call_rcu_common.constprop.0+0xa6/0xa00 [ 984.447610][ T44] ? copy_msghdr_from_user+0x430/0x430 [ 984.447797][ T44] __sys_sendmsg+0x124/0x1c0 [ 984.447962][ T44] ? __sys_sendmsg_sock+0x20/0x20 [ 984.448133][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 984.448296][ T44] ? __call_rcu_common.constprop.0+0x3bd/0xa00 [ 984.448492][ T44] ? do_raw_spin_unlock+0x59/0x250 [ 984.448671][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.448845][ T44] do_syscall_64+0xbd/0xfc0 [ 984.449012][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 984.449204][ T44] RIP: 0033:0x7fca6ae83c5e [ 984.449379][ T44] RSP: 002b:00007ffc61499a60 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 984.449617][ T44] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007fca6ae83c5e [ 984.449851][ T44] RDX: 0000000000000000 RSI: 00007ffc61499b20 RDI: 0000000000000003 [ 984.450070][ T44] RBP: 00007ffc61499a70 R08: 0000000000000000 R09: 0000000000000000 [ 984.450295][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffc6149a1e0 [ 984.450516][ T44] R13: 00000000693cb235 R14: 0000000000000000 R15: 0000000000499600 [ 984.450767][ T44] [ 984.450913][ T44] INFO: task ip:6029 is blocked on a mutex likely owned by task kworker/3:2:68. [ 984.451151][ T44] task:kworker/3:2 state:D stack:28112 pid:68 tgid:68 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 984.451456][ T44] Workqueue: events refill_work [ 984.451637][ T44] Call Trace: [ 984.451783][ T44] [ 984.451891][ T44] ? __schedule+0xae2/0x1720 [ 984.452057][ T44] ? usleep_range_state+0xba/0x1b0 [ 984.452221][ T44] ? io_schedule_timeout+0x130/0x130 [ 984.452387][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.452559][ T44] ? usleep_range_state+0xba/0x1b0 [ 984.452748][ T44] ? schedule+0xd4/0x260 [ 984.452887][ T44] ? schedule_hrtimeout_range_clock+0x1da/0x2f0 [ 984.453083][ T44] ? schedule_timeout_idle+0xe0/0xe0 [ 984.453249][ T44] ? __hrtimer_cb_get_time+0x50/0x50 [ 984.453416][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 984.453592][ T44] ? ktime_get+0x15e/0x220 [ 984.453774][ T44] ? kvm_clock_get_cycles+0x18/0x30 [ 984.453938][ T44] ? ktime_get+0xa9/0x220 [ 984.454074][ T44] ? usleep_range_state+0x133/0x1b0 [ 984.454238][ T44] ? schedule_hrtimeout+0x20/0x20 [ 984.454402][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 984.454566][ T44] ? refill_work+0x149/0x210 [ 984.454754][ T44] ? napi_disable_locked+0x379/0x5f0 [ 984.454924][ T44] ? _mutex_trylock_nest_lock+0x340/0x340 [ 984.455089][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470 [ 984.455285][ T44] ? napi_disable+0x43/0x80 [ 984.455449][ T44] ? refill_work+0x116/0x210 [ 984.455627][ T44] ? process_one_work+0xd57/0x1390 [ 984.455812][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 984.455977][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 984.456148][ T44] ? assign_work+0x152/0x380 [ 984.456316][ T44] ? worker_thread+0x4d6/0xd40 [ 984.456482][ T44] ? process_one_work+0x1390/0x1390 [ 984.456656][ T44] ? __kthread_parkme+0xb3/0x200 [ 984.456842][ T44] ? process_one_work+0x1390/0x1390 [ 984.457007][ T44] ? kthread+0x355/0x5b0 [ 984.457144][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.457311][ T44] ? __lock_release.isra.0+0x59/0x170 [ 984.457475][ T44] ? rcu_is_watching+0x15/0xd0 [ 984.457654][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.457833][ T44] ? ret_from_fork+0x3fb/0x510 [ 984.457997][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 [ 984.458191][ T44] ? __switch_to+0x602/0xd00 [ 984.458355][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 984.458523][ T44] ? ret_from_fork_asm+0x11/0x20 [ 984.458719][ T44] [ 984.458860][ T44] [ 984.458860][ T44] Showing all locks held in the system: [ 984.459073][ T44] 3 locks held by kworker/u16:0/12: [ 984.459231][ T44] #0: ff110000090f6548 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 984.459503][ T44] #1: ffa00000000c7cc0 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 984.459855][ T44] #2: ffffffffb8e78aa8 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30 [ 984.460104][ T44] 1 lock held by khungtaskd/44: [ 984.460259][ T44] #0: ffffffffb8770ba0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire.constprop.0+0x7/0x30 [ 984.460530][ T44] 3 locks held by kworker/3:2/68: [ 984.460712][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 984.460986][ T44] #1: ffa0000000497cc0 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 984.461285][ T44] #2: ff11000008adac98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80 [ 984.461536][ T44] 2 locks held by ip/6029: [ 984.461718][ T44] #0: ffffffffb8e78aa8 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5f9/0xb40 [ 984.461969][ T44] #1: ff11000008adac98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80 [ 984.462212][ T44] [ 984.462314][ T44] ============================================= [ 984.462314][ T44] [ 985.448583][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 197631 ms [ 985.449011][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 197631000 usecs ago [ 991.592581][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 203775 ms [ 991.592993][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 203775000 usecs ago [ 997.224588][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 209407 ms [ 997.225068][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 209407000 usecs ago [ 1002.344583][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 214527 ms [ 1002.345031][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 214527000 usecs ago [ 1007.464588][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 219647 ms [ 1007.465064][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 219647000 usecs ago [ 1013.608583][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 225791 ms [ 1013.609040][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 225791000 usecs ago [ 1019.240588][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 231423 ms [ 1019.241046][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 231423000 usecs ago [ 1024.360584][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 236543 ms [ 1024.361023][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 236543000 usecs ago [ 1030.504590][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 242687 ms [ 1030.505065][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 242687000 usecs ago [ 1036.136587][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 248319 ms [ 1036.137072][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 248319000 usecs ago [ 1041.256584][ C0] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 0: transmit queue 2 timed out 253439 ms [ 1041.257004][ C0] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 253439000 usecs ago