[ 908.109571][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 325119 ms
[ 908.110033][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 325119000 usecs ago
[ 913.229565][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 330239 ms
[ 913.230021][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 330239000 usecs ago
[ 918.350577][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 335360 ms
[ 918.351267][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 335360000 usecs ago
[ 924.493575][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 341503 ms
[ 924.494177][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 341503000 usecs ago
[ 930.126557][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 347136 ms
[ 930.127004][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 347136000 usecs ago
[ 935.246552][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 352256 ms
[ 935.247039][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 352256000 usecs ago
[ 940.366558][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 357376 ms
[ 940.367011][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 357376000 usecs ago
[ 946.509564][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 363519 ms
[ 946.510044][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 363519000 usecs ago
[ 952.142555][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 369152 ms
[ 952.143026][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 369152000 usecs ago
[ 957.262570][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 374272 ms
[ 957.263061][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 374272000 usecs ago
[ 962.381574][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 379391 ms
[ 962.382180][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 379391000 usecs ago
[ 968.525571][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 385535 ms
[ 968.526146][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 385535000 usecs ago
[ 974.157579][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 391167 ms
[ 974.158144][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 391167000 usecs ago
[ 979.277568][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 396287 ms
[ 979.278041][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 396287000 usecs ago
[ 984.397575][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 401407 ms
[ 984.397595][ T44] INFO: task kworker/u16:0:12 blocked for more than 245 seconds.
[ 984.398065][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 401407000 usecs ago
[ 984.398319][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 984.398323][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 984.398326][ T44] task:kworker/u16:0 state:D stack:27000 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 984.398338][ T44] Workqueue: ipv6_addrconf addrconf_verify_work
[ 984.398348][ T44] Call Trace:
[ 984.398351][ T44]
[ 984.398359][ T44] __schedule+0x88d/0x1b90
[ 984.400153][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.400323][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.400492][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.400700][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.400876][ T44] schedule+0xd4/0x260
[ 984.401002][ T44] schedule_preempt_disabled+0x18/0x30
[ 984.401165][ T44] __mutex_lock+0x9ae/0x1500
[ 984.401331][ T44] ? addrconf_verify_work+0x12/0x30
[ 984.401498][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.401673][ T44] ? process_one_work+0xd16/0x1390
[ 984.401848][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.402014][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.402192][ T44] ? addrconf_verify_work+0x12/0x30
[ 984.402357][ T44] addrconf_verify_work+0x12/0x30
[ 984.402539][ T44] process_one_work+0xd57/0x1390
[ 984.402716][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 984.402884][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.403062][ T44] ? assign_work+0x152/0x380
[ 984.403232][ T44] worker_thread+0x4d6/0xd40
[ 984.403406][ T44] ? process_one_work+0x1390/0x1390
[ 984.403584][ T44] kthread+0x355/0x5b0
[ 984.403715][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.403891][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.404055][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.404230][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.404398][ T44] ret_from_fork+0x1d4/0x270
[ 984.404606][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.404773][ T44] ret_from_fork_asm+0x11/0x20
[ 984.404954][ T44]
[ 984.405127][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:4715.
[ 984.405423][ T44] INFO: task ip:4715 blocked for more than 368 seconds.
[ 984.405627][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 984.405784][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 984.406063][ T44] task:ip state:D stack:25384 pid:4715 tgid:4715 ppid:1 task_flags:0x400100 flags:0x00080003
[ 984.406445][ T44] Call Trace:
[ 984.406585][ T44]
[ 984.406672][ T44] __schedule+0x88d/0x1b90
[ 984.406851][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.407023][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.407191][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.407359][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.407542][ T44] schedule+0xd4/0x260
[ 984.407666][ T44] schedule_preempt_disabled+0x18/0x30
[ 984.407833][ T44] __mutex_lock+0x9ae/0x1500
[ 984.408010][ T44] ? napi_enable+0x3b/0x80
[ 984.408178][ T44] ? virtqueue_add_inbuf_ctx+0x1850/0x1850
[ 984.408384][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.408575][ T44] ? __asan_memset+0x27/0x50
[ 984.408751][ T44] ? sg_init_table+0x19/0x60
[ 984.408925][ T44] ? try_fill_recv+0x3ec/0x1750
[ 984.409094][ T44] ? napi_enable+0x3b/0x80
[ 984.409257][ T44] napi_enable+0x3b/0x80
[ 984.409380][ T44] __virtnet_rx_resume+0x156/0x290
[ 984.409551][ T44] ? queue_delayed_work_on+0x91/0x100
[ 984.409709][ T44] virtnet_rx_resume_all+0xe7/0x1a0
[ 984.409878][ T44] virtnet_xdp_set+0xef7/0x1aa0
[ 984.410059][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0
[ 984.410222][ T44] dev_xdp_install+0x4ba/0x8e0
[ 984.410388][ T44] ? netif_skb_check_for_xdp+0x600/0x600
[ 984.410568][ T44] dev_xdp_attach+0x50d/0x1320
[ 984.410736][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0
[ 984.410907][ T44] ? mutex_is_locked+0x1c/0x50
[ 984.411069][ T44] ? rtnl_is_locked+0x15/0x20
[ 984.411237][ T44] ? dev_change_xdp_fd+0x9f/0x240
[ 984.411405][ T44] do_setlink.isra.0+0x1c2d/0x2880
[ 984.411578][ T44] ? rtnl_newlink_create+0x900/0x900
[ 984.411743][ T44] ? rcu_lockdep_current_cpu_online+0x3e/0x1b0
[ 984.411950][ T44] ? rcu_read_lock_any_held+0x41/0xa0
[ 984.412114][ T44] ? validate_chain+0x287/0x5f0
[ 984.412277][ T44] ? rcu_read_lock_any_held+0x6b/0xa0
[ 984.412443][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 984.412628][ T44] ? __lock_acquire+0x34c/0x740
[ 984.412797][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.412963][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 984.413125][ T44] ? lock_acquire+0x31/0x80
[ 984.413286][ T44] ? rtnl_newlink+0x5df/0xb20
[ 984.413445][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.413621][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.413788][ T44] ? trace_contention_end+0xd6/0x140
[ 984.413957][ T44] ? rtnl_newlink+0x5df/0xb20
[ 984.414123][ T44] ? __mutex_lock+0x32e/0x1500
[ 984.414296][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.414456][ T44] ? nla_validate_int_range+0x5b0/0x5b0
[ 984.414628][ T44] ? __rtnl_newlink+0x3fa/0xa40
[ 984.414802][ T44] rtnl_newlink+0x637/0xb20
[ 984.414978][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.415148][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.415309][ T44] ? find_held_lock+0x2b/0x80
[ 984.415472][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.415641][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.415804][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.415985][ T44] rtnetlink_rcv_msg+0x6fe/0xb90
[ 984.416148][ T44] ? rtnl_port_fill+0x940/0x940
[ 984.416316][ T44] ? __lock_acquire+0x34c/0x740
[ 984.416486][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.416665][ T44] ? find_held_lock+0x2b/0x80
[ 984.416834][ T44] netlink_rcv_skb+0x123/0x380
[ 984.417006][ T44] ? rtnl_port_fill+0x940/0x940
[ 984.417170][ T44] ? netlink_ack+0xcc0/0xcc0
[ 984.417342][ T44] ? netlink_deliver_tap+0x13f/0x330
[ 984.417511][ T44] netlink_unicast+0x4a3/0x770
[ 984.417684][ T44] ? netlink_attachskb+0x810/0x810
[ 984.417854][ T44] ? __lock_acquire+0x34c/0x740
[ 984.418023][ T44] netlink_sendmsg+0x735/0xc60
[ 984.418188][ T44] ? netlink_unicast+0x770/0x770
[ 984.418353][ T44] ? __might_fault+0x97/0x140
[ 984.418519][ T44] ____sys_sendmsg+0x419/0x850
[ 984.418690][ T44] ? copy_msghdr_from_user+0x270/0x430
[ 984.418864][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0
[ 984.419068][ T44] ? move_addr_to_kernel+0x40/0x40
[ 984.419232][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 984.419393][ T44] ? validate_chain+0x287/0x5f0
[ 984.419568][ T44] ___sys_sendmsg+0xfd/0x180
[ 984.419731][ T44] ? __call_rcu_common.constprop.0+0xa5/0x680
[ 984.419937][ T44] ? copy_msghdr_from_user+0x430/0x430
[ 984.420130][ T44] __sys_sendmsg+0x124/0x1c0
[ 984.420296][ T44] ? __sys_sendmsg_sock+0x20/0x20
[ 984.420457][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 984.420635][ T44] ? __call_rcu_common.constprop.0+0x2fa/0x680
[ 984.420849][ T44] ? do_raw_spin_unlock+0x59/0x250
[ 984.421012][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.421190][ T44] do_syscall_64+0xbb/0xfd0
[ 984.421367][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 984.421576][ T44] RIP: 0033:0x7fbf5e924c5e
[ 984.421751][ T44] RSP: 002b:00007ffe015a5f40 EFLAGS: 00000202 ORIG_RAX: 000000000000002e
[ 984.422009][ T44] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fbf5e924c5e
[ 984.422254][ T44] RDX: 0000000000000000 RSI: 00007ffe015a6000 RDI: 0000000000000003
[ 984.422504][ T44] RBP: 00007ffe015a5f50 R08: 0000000000000000 R09: 0000000000000000
[ 984.422755][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffe015a66c0
[ 984.423002][ T44] R13: 00000000692e2fd3 R14: 0000000000000000 R15: 0000000000499600
[ 984.423256][ T44]
[ 984.423396][ T44] INFO: task ip:4715 is blocked on a mutex likely owned by task kworker/3:2:1562.
[ 984.423680][ T44] task:kworker/3:2 state:D stack:28808 pid:1562 tgid:1562 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 984.424050][ T44] Workqueue: events refill_work
[ 984.424216][ T44] Call Trace:
[ 984.424339][ T44]
[ 984.424425][ T44] __schedule+0x88d/0x1b90
[ 984.424609][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.424775][ T44] ? usleep_range_state+0xba/0x1b0
[ 984.424945][ T44] ? find_held_lock+0x2b/0x80
[ 984.425109][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.425274][ T44] ? usleep_range_state+0xba/0x1b0
[ 984.425436][ T44] schedule+0xd4/0x260
[ 984.425566][ T44] schedule_hrtimeout_range_clock+0x1da/0x2f0
[ 984.425771][ T44] ? schedule_timeout_idle+0xe0/0xe0
[ 984.425983][ T44] ? hrtimer_cb_get_time+0x90/0x90
[ 984.426150][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 984.426311][ T44] ? ktime_get+0x15e/0x220
[ 984.426473][ T44] ? kvm_clock_get_cycles+0x18/0x30
[ 984.426644][ T44] ? ktime_get+0xa9/0x220
[ 984.426769][ T44] usleep_range_state+0x133/0x1b0
[ 984.426935][ T44] ? schedule_hrtimeout+0x20/0x20
[ 984.427099][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 984.427270][ T44] napi_disable_locked+0x379/0x5f0
[ 984.427432][ T44] ? _mutex_trylock_nest_lock+0x150/0x150
[ 984.427601][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470
[ 984.427809][ T44] napi_disable+0x43/0x80
[ 984.427935][ T44] refill_work+0x116/0x210
[ 984.428101][ T44] process_one_work+0xd57/0x1390
[ 984.428272][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 984.428443][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.428630][ T44] ? assign_work+0x152/0x380
[ 984.428798][ T44] worker_thread+0x4d6/0xd40
[ 984.428972][ T44] ? process_one_work+0x1390/0x1390
[ 984.429137][ T44] ? __kthread_parkme+0xb3/0x200
[ 984.429305][ T44] ? process_one_work+0x1390/0x1390
[ 984.429470][ T44] kthread+0x355/0x5b0
[ 984.429609][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.429773][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.429943][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.430110][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.430277][ T44] ret_from_fork+0x1d4/0x270
[ 984.430441][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.430614][ T44] ret_from_fork_asm+0x11/0x20
[ 984.430790][ T44]
[ 984.430946][ T44]
[ 984.430946][ T44] Showing all locks held in the system:
[ 984.431186][ T44] 3 locks held by kworker/u16:0/12:
[ 984.431349][ T44] #0: ff11000008af4d48 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 984.431982][ T44] #1: ffa00000000c7d30 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 984.432384][ T44] #2: ffffffff8ae92ce8 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30
[ 984.432674][ T44] 3 locks held by kworker/3:0/33:
[ 984.432834][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 984.433158][ T44] #1: ffa0000000257d30 ((work_completion)(&pool->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 984.433483][ T44] #2: ffffffff8ae92ce8 (rtnl_mutex){+.+.}-{4:4}, at: xp_release_deferred+0x1b/0x1e0
[ 984.433776][ T44] 1 lock held by khungtaskd/44:
[ 984.433940][ T44] #0: ffffffff8a778fc0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2b/0x1db
[ 984.434229][ T44] 3 locks held by kworker/3:2/1562:
[ 984.434386][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 984.434733][ T44] #1: ffa00000006a7d30 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 984.435114][ T44] #2: ff110000084cac98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80
[ 984.435405][ T44] 2 locks held by ip/4715:
[ 984.435570][ T44] #0: ffffffff8ae92ce8 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5df/0xb20
[ 984.435851][ T44] #1: ff110000084cac98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80
[ 984.436121][ T44] 1 lock held by xdp_helper/4722:
[ 984.436279][ T44] #0: ffffffff8ae92ce8 (rtnl_mutex){+.+.}-{4:4}, at: xsk_bind+0x17f/0x1a10
[ 984.436565][ T44]
[ 984.436646][ T44] =============================================
[ 984.436646][ T44]
[ 990.542569][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 407552 ms
[ 990.543050][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 407552000 usecs ago
[ 996.173571][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 413183 ms
[ 996.174047][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 413183000 usecs ago
[ 1001.294571][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 418304 ms
[ 1001.295108][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 418304000 usecs ago
[ 1006.413573][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 423423 ms
[ 1006.414068][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 423423000 usecs ago
[ 1012.557570][ C2] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 2: transmit queue 2 timed out 429567 ms
[ 1012.558028][ C2] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 429567000 usecs ago