[ 855.358358][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 264192 ms [ 855.358760][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 264192000 usecs ago [ 861.502355][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 270336 ms [ 861.502784][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 270336000 usecs ago [ 861.503152][ T44] INFO: task kworker/u16:0:12 blocked for more than 245 seconds. [ 861.503366][ T44] Not tainted 6.19.0-rc3-virtme #1 [ 861.503496][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.503722][ T44] task:kworker/u16:0 state:D stack:26352 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 861.504013][ T44] Workqueue: ipv6_addrconf addrconf_verify_work [ 861.504169][ T44] Call Trace: [ 861.504261][ T44] [ 861.504326][ T44] __schedule+0xae2/0x1720 [ 861.504461][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.504585][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.504711][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.504845][ T44] schedule+0xd4/0x260 [ 861.504935][ T44] schedule_preempt_disabled+0x18/0x30 [ 861.505052][ T44] __mutex_lock+0xd76/0x1f20 [ 861.505169][ T44] ? addrconf_verify_work+0x12/0x30 [ 861.505286][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.505412][ T44] ? process_one_work+0xd16/0x1390 [ 861.505530][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.505651][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.505768][ T44] ? addrconf_verify_work+0x12/0x30 [ 861.505882][ T44] addrconf_verify_work+0x12/0x30 [ 861.505996][ T44] process_one_work+0xd57/0x1390 [ 861.506116][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 861.506231][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.506354][ T44] ? assign_work+0x152/0x380 [ 861.506471][ T44] worker_thread+0x4d6/0xd40 [ 861.506589][ T44] ? process_one_work+0x1390/0x1390 [ 861.506704][ T44] kthread+0x355/0x5b0 [ 861.506792][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.506908][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.507024][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.507139][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.507256][ T44] ret_from_fork+0x3fb/0x510 [ 861.507386][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 [ 861.507530][ T44] ? __switch_to+0x53c/0xd00 [ 861.507649][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.507765][ T44] ret_from_fork_asm+0x11/0x20 [ 861.507886][ T44] [ 861.507991][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:5236. [ 861.508196][ T44] INFO: task ip:5236 blocked for more than 368 seconds. [ 861.508330][ T44] Not tainted 6.19.0-rc3-virtme #1 [ 861.508442][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.508631][ T44] task:ip state:D stack:25600 pid:5236 tgid:5236 ppid:1 task_flags:0x400100 flags:0x00080002 [ 861.508881][ T44] Call Trace: [ 861.508968][ T44] [ 861.509027][ T44] __schedule+0xae2/0x1720 [ 861.509143][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.509259][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.509377][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.509494][ T44] schedule+0xd4/0x260 [ 861.509582][ T44] schedule_preempt_disabled+0x18/0x30 [ 861.509709][ T44] __mutex_lock+0xd76/0x1f20 [ 861.509824][ T44] ? napi_enable+0x3b/0x80 [ 861.509940][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.510055][ T44] ? __asan_memset+0x27/0x50 [ 861.510176][ T44] ? sg_init_table+0x19/0x60 [ 861.510293][ T44] ? try_fill_recv+0x3ec/0x1750 [ 861.510422][ T44] ? napi_enable+0x3b/0x80 [ 861.510535][ T44] napi_enable+0x3b/0x80 [ 861.510623][ T44] __virtnet_rx_resume+0x156/0x290 [ 861.510740][ T44] ? queue_delayed_work_on+0x91/0x100 [ 861.510856][ T44] virtnet_rx_resume_all+0xe7/0x1a0 [ 861.510974][ T44] virtnet_xdp_set+0xef7/0x1aa0 [ 861.511091][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0 [ 861.511205][ T44] dev_xdp_install+0x4ba/0x890 [ 861.511323][ T44] ? netif_set_xps_queue+0x40/0x40 [ 861.511451][ T44] dev_xdp_attach+0x50d/0x1320 [ 861.511567][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0 [ 861.511685][ T44] ? mutex_is_locked+0x1c/0x50 [ 861.511801][ T44] ? rtnl_is_locked+0x15/0x20 [ 861.511918][ T44] do_setlink.isra.0+0x1c2d/0x2880 [ 861.512035][ T44] ? rtnl_newlink_create+0x900/0x900 [ 861.512149][ T44] ? rcu_lockdep_current_cpu_online+0x39/0x1b0 [ 861.512293][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 861.512416][ T44] ? validate_chain+0x37b/0xb70 [ 861.512531][ T44] ? rcu_read_lock_any_held+0x66/0x90 [ 861.512646][ T44] ? stack_depot_save_flags+0x3f1/0x6f0 [ 861.512764][ T44] ? __lock_acquire+0x577/0xc10 [ 861.512880][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.512996][ T44] ? rtnl_newlink+0x6fc/0xe90 [ 861.513113][ T44] ? lock_acquire+0x10a/0x150 [ 861.513230][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.513350][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.513464][ T44] ? trace_contention_end+0xd6/0x110 [ 861.513580][ T44] ? __mutex_lock+0x285/0x1f20 [ 861.513697][ T44] ? rtnl_newlink+0x6fc/0xe90 [ 861.513817][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.513932][ T44] ? nla_get_range_signed+0x3d0/0x3d0 [ 861.514052][ T44] ? __rtnl_newlink+0x3fa/0xa40 [ 861.514169][ T44] rtnl_newlink+0x75c/0xe90 [ 861.514285][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.514403][ T44] ? rtnl_setlink+0x880/0x880 [ 861.514516][ T44] ? __lock_acquire+0x577/0xc10 [ 861.514634][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.514748][ T44] ? find_held_lock+0x2b/0x80 [ 861.514863][ T44] ? rtnl_setlink+0x880/0x880 [ 861.514981][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.515095][ T44] ? rtnl_setlink+0x880/0x880 [ 861.515208][ T44] rtnetlink_rcv_msg+0x6fe/0xb90 [ 861.515323][ T44] ? rtnl_fdb_dump+0x620/0x620 [ 861.515445][ T44] ? __lock_acquire+0x577/0xc10 [ 861.515560][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.515676][ T44] ? find_held_lock+0x2b/0x80 [ 861.515792][ T44] netlink_rcv_skb+0x123/0x380 [ 861.515907][ T44] ? rtnl_fdb_dump+0x620/0x620 [ 861.516024][ T44] ? netlink_ack+0xcc0/0xcc0 [ 861.516142][ T44] ? netlink_deliver_tap+0xc5/0x330 [ 861.516259][ T44] ? netlink_deliver_tap+0x13f/0x330 [ 861.516380][ T44] netlink_unicast+0x4a3/0x770 [ 861.516495][ T44] ? netlink_attachskb+0x810/0x810 [ 861.516607][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.516726][ T44] ? __lock_acquire+0x577/0xc10 [ 861.516841][ T44] netlink_sendmsg+0x735/0xc60 [ 861.516958][ T44] ? netlink_unicast+0x770/0x770 [ 861.517076][ T44] ____sys_sendmsg+0x419/0x850 [ 861.517193][ T44] ? copy_msghdr_from_user+0x270/0x430 [ 861.517306][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 861.517453][ T44] ? move_addr_to_kernel+0x40/0x40 [ 861.517571][ T44] ? stack_depot_save_flags+0x3f1/0x6f0 [ 861.517686][ T44] ? rcu_read_lock_any_held+0x3c/0x90 [ 861.517801][ T44] ___sys_sendmsg+0xfd/0x180 [ 861.517915][ T44] ? __call_rcu_common.constprop.0+0xa6/0xa00 [ 861.518059][ T44] ? copy_msghdr_from_user+0x430/0x430 [ 861.518186][ T44] __sys_sendmsg+0x124/0x1c0 [ 861.518302][ T44] ? __sys_sendmsg_sock+0x20/0x20 [ 861.518423][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.518538][ T44] ? __call_rcu_common.constprop.0+0x3bd/0xa00 [ 861.518687][ T44] ? do_raw_spin_unlock+0x59/0x250 [ 861.518807][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.518924][ T44] do_syscall_64+0xbd/0xfc0 [ 861.519042][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 861.519185][ T44] RIP: 0033:0x7f8c69151c5e [ 861.519311][ T44] RSP: 002b:00007ffed1ea1380 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 861.519496][ T44] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007f8c69151c5e [ 861.519672][ T44] RDX: 0000000000000000 RSI: 00007ffed1ea1440 RDI: 0000000000000003 [ 861.519843][ T44] RBP: 00007ffed1ea1390 R08: 0000000000000000 R09: 0000000000000000 [ 861.520017][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffed1ea1b00 [ 861.520189][ T44] R13: 00000000695d3729 R14: 0000000000000000 R15: 0000000000499600 [ 861.520371][ T44] [ 861.520459][ T44] INFO: task ip:5236 is blocked on a mutex likely owned by task kworker/2:2:67. [ 861.520653][ T44] task:kworker/2:2 state:R running task stack:28720 pid:67 tgid:67 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 861.520931][ T44] Workqueue: events refill_work [ 861.521046][ T44] Call Trace: [ 861.521134][ T44] [ 861.521194][ T44] __schedule+0xae2/0x1720 [ 861.521312][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.521431][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.521547][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.521667][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.521780][ T44] schedule+0xd4/0x260 [ 861.521867][ T44] schedule_hrtimeout_range_clock+0x1da/0x2f0 [ 861.522009][ T44] ? schedule_timeout_idle+0xe0/0xe0 [ 861.522124][ T44] ? __hrtimer_cb_get_time+0x50/0x50 [ 861.522239][ T44] ? usleep_range_state+0x78/0x1b0 [ 861.522360][ T44] ? ktime_get+0x16e/0x220 [ 861.522475][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.522587][ T44] ? ktime_get+0x15e/0x220 [ 861.522703][ T44] ? kvm_clock_get_cycles+0x18/0x30 [ 861.522819][ T44] ? ktime_get+0xa9/0x220 [ 861.522908][ T44] usleep_range_state+0x133/0x1b0 [ 861.523023][ T44] ? schedule_hrtimeout+0x20/0x20 [ 861.523138][ T44] ? __free_object+0x2ad/0x4b0 [ 861.523256][ T44] napi_disable_locked+0x379/0x5f0 [ 861.523380][ T44] ? _mutex_trylock_nest_lock+0x340/0x340 [ 861.523496][ T44] ? netif_get_flags+0x1e0/0x1e0 [ 861.523613][ T44] napi_disable+0x43/0x80 [ 861.523704][ T44] refill_work+0x116/0x210 [ 861.523822][ T44] process_one_work+0xd57/0x1390 [ 861.523939][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 861.524055][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.524170][ T44] ? assign_work+0x152/0x380 [ 861.524285][ T44] worker_thread+0x4d6/0xd40 [ 861.524412][ T44] ? process_one_work+0x1390/0x1390 [ 861.524526][ T44] kthread+0x355/0x5b0 [ 861.524613][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.524732][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.524848][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.524963][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.525079][ T44] ret_from_fork+0x3fb/0x510 [ 861.525192][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140 [ 861.525337][ T44] ? __switch_to+0x53c/0xd00 [ 861.525457][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.525572][ T44] ret_from_fork_asm+0x11/0x20 [ 861.525694][ T44] [ 861.525790][ T44] [ 861.525790][ T44] Showing all locks held in the system: [ 861.525959][ T44] 3 locks held by kworker/u16:0/12: [ 861.526071][ T44] #0: ff110000090f7148 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.526295][ T44] #1: ffa00000000c7cc0 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.526655][ T44] #2: ffffffff8a685ba8 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30 [ 861.526853][ T44] 1 lock held by khungtaskd/44: [ 861.526962][ T44] #0: ffffffff89f710c0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire.constprop.0+0x7/0x30 [ 861.527185][ T44] 3 locks held by kworker/2:2/67: [ 861.527294][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.527519][ T44] #1: ffa0000000487cc0 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.527770][ T44] #2: ff11000008aaccb0 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80 [ 861.527969][ T44] 3 locks held by kworker/0:3/991: [ 861.528078][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.528301][ T44] #1: ffa0000000ea7cc0 ((work_completion)(&pool->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.528527][ T44] #2: ffffffff8a685ba8 (rtnl_mutex){+.+.}-{4:4}, at: xp_release_deferred+0x1b/0x1e0 [ 861.528728][ T44] 2 locks held by ip/5236: [ 861.528839][ T44] #0: ffffffff8a685ba8 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x6fc/0xe90 [ 861.529034][ T44] #1: ff11000008aaccb0 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80 [ 861.529230][ T44] 1 lock held by xdp_helper/5243: [ 861.529339][ T44] #0: ffffffff8a685ba8 (rtnl_mutex){+.+.}-{4:4}, at: xsk_bind+0x17f/0x1b10 [ 861.529550][ T44] [ 861.529607][ T44] ============================================= [ 861.529607][ T44] [ 867.134354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 275968 ms [ 867.134738][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 275968000 usecs ago [ 872.254354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 281088 ms [ 872.254732][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 281088000 usecs ago [ 877.374354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 286208 ms [ 877.374731][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 286208000 usecs ago [ 883.517353][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 292351 ms [ 883.517719][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 292351000 usecs ago [ 889.150354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 297984 ms [ 889.150714][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 297984000 usecs ago [ 894.270354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 303104 ms [ 894.270723][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 303104000 usecs ago [ 899.389351][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 308223 ms [ 899.389709][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 308223000 usecs ago [ 905.533354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 314367 ms [ 905.533725][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 314367000 usecs ago [ 911.166353][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 320000 ms [ 911.166738][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 320000000 usecs ago [ 916.285354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 325119 ms [ 916.285722][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 325119000 usecs ago [ 921.406353][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 330240 ms [ 921.406734][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 330240000 usecs ago [ 927.550354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 336384 ms [ 927.550733][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 336384000 usecs ago [ 933.182352][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 342016 ms [ 933.182722][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 342016000 usecs ago [ 938.302354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 347136 ms [ 938.302733][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 347136000 usecs ago [ 944.446354][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 353280 ms [ 944.446801][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 353280000 usecs ago [ 950.078351][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 1 timed out 358912 ms [ 950.078738][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 1, sq: output.1, vq: 0x3, name: output.1, 358912000 usecs ago