[ 859.456570][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 358913 ms [ 859.457000][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 358913000 usecs ago [ 861.503596][ T44] INFO: task kworker/u16:1:69 blocked for more than 245 seconds. [ 861.503933][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 861.504142][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.504431][ T44] task:kworker/u16:1 state:D stack:26544 pid:69 tgid:69 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 861.504838][ T44] Workqueue: ipv6_addrconf addrconf_verify_work [ 861.505055][ T44] Call Trace: [ 861.505198][ T44] [ 861.505314][ T44] __schedule+0x88d/0x1b90 [ 861.505499][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.505692][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.505872][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.506048][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.506220][ T44] schedule+0xd4/0x260 [ 861.506361][ T44] schedule_preempt_disabled+0x18/0x30 [ 861.506535][ T44] __mutex_lock+0x9ae/0x1500 [ 861.506720][ T44] ? addrconf_verify_work+0x12/0x30 [ 861.506892][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.507070][ T44] ? process_one_work+0xd16/0x1390 [ 861.507241][ T44] ? trace_lock_acquire+0x88/0xd0 [ 861.507407][ T44] ? trace_lock_acquire+0x88/0xd0 [ 861.507590][ T44] ? addrconf_verify_work+0x12/0x30 [ 861.507753][ T44] addrconf_verify_work+0x12/0x30 [ 861.507924][ T44] process_one_work+0xd57/0x1390 [ 861.508101][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 861.508266][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.508433][ T44] ? assign_work+0x152/0x380 [ 861.508610][ T44] worker_thread+0x4d6/0xd40 [ 861.508779][ T44] ? process_one_work+0x1390/0x1390 [ 861.508943][ T44] ? __kthread_parkme+0xb3/0x200 [ 861.509121][ T44] ? process_one_work+0x1390/0x1390 [ 861.509288][ T44] kthread+0x355/0x5b0 [ 861.509425][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.509605][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.509771][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.509940][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.510110][ T44] ret_from_fork+0x1d4/0x270 [ 861.510277][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.510444][ T44] ret_from_fork_asm+0x11/0x20 [ 861.510634][ T44] [ 861.510787][ T44] INFO: task kworker/u16:1:69 is blocked on a mutex likely owned by task ip:5200. [ 861.511042][ T44] INFO: task ip:5200 blocked for more than 245 seconds. [ 861.511226][ T44] Not tainted 6.18.0-rc6-virtme #1 [ 861.511381][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message. [ 861.511634][ T44] task:ip state:D stack:25592 pid:5200 tgid:5200 ppid:1 task_flags:0x400100 flags:0x00080003 [ 861.511935][ T44] Call Trace: [ 861.512075][ T44] [ 861.512183][ T44] __schedule+0x88d/0x1b90 [ 861.512352][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.512519][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.512706][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.512874][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.513049][ T44] schedule+0xd4/0x260 [ 861.513187][ T44] schedule_preempt_disabled+0x18/0x30 [ 861.513350][ T44] __mutex_lock+0x9ae/0x1500 [ 861.513516][ T44] ? napi_enable+0x3b/0x80 [ 861.513698][ T44] ? virtqueue_add_inbuf_ctx+0x1850/0x1850 [ 861.513903][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.514074][ T44] ? __asan_memset+0x27/0x50 [ 861.514243][ T44] ? sg_init_table+0x19/0x60 [ 861.514410][ T44] ? try_fill_recv+0x3ec/0x1750 [ 861.514593][ T44] ? napi_enable+0x3b/0x80 [ 861.514757][ T44] napi_enable+0x3b/0x80 [ 861.514896][ T44] __virtnet_rx_resume+0x156/0x290 [ 861.515064][ T44] ? queue_delayed_work_on+0x91/0x100 [ 861.515234][ T44] virtnet_rx_resume_all+0xe7/0x1a0 [ 861.515402][ T44] virtnet_xdp_set+0xef7/0x1aa0 [ 861.515586][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0 [ 861.515750][ T44] dev_xdp_install+0x4ba/0x8e0 [ 861.515917][ T44] ? netif_skb_check_for_xdp+0x600/0x600 [ 861.516093][ T44] dev_xdp_attach+0x50d/0x1320 [ 861.516264][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0 [ 861.516435][ T44] ? mutex_is_locked+0x1c/0x50 [ 861.516612][ T44] ? rtnl_is_locked+0x15/0x20 [ 861.516777][ T44] ? dev_change_xdp_fd+0x9f/0x240 [ 861.516944][ T44] do_setlink.isra.0+0x1c2d/0x2880 [ 861.517116][ T44] ? rtnl_newlink_create+0x900/0x900 [ 861.517281][ T44] ? rcu_lockdep_current_cpu_online+0x3e/0x1b0 [ 861.517476][ T44] ? rcu_read_lock_any_held+0x41/0xa0 [ 861.517660][ T44] ? validate_chain+0x287/0x5f0 [ 861.517827][ T44] ? rcu_read_lock_any_held+0x6b/0xa0 [ 861.517991][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 861.518162][ T44] ? __lock_acquire+0x34c/0x740 [ 861.518335][ T44] ? trace_lock_acquire+0x88/0xd0 [ 861.518498][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 861.518677][ T44] ? lock_acquire+0x31/0x80 [ 861.518845][ T44] ? rtnl_newlink+0x5df/0xb20 [ 861.519010][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.519181][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.519348][ T44] ? trace_contention_end+0xd6/0x140 [ 861.519513][ T44] ? rtnl_newlink+0x5df/0xb20 [ 861.519690][ T44] ? __mutex_lock+0x32e/0x1500 [ 861.519859][ T44] ? ww_mutex_lock+0x160/0x160 [ 861.520024][ T44] ? nla_validate_int_range+0x5b0/0x5b0 [ 861.520203][ T44] ? __rtnl_newlink+0x3fa/0xa40 [ 861.520371][ T44] rtnl_newlink+0x637/0xb20 [ 861.520538][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.520719][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.520882][ T44] ? find_held_lock+0x2b/0x80 [ 861.521053][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.521218][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.521389][ T44] ? __rtnl_newlink+0xa40/0xa40 [ 861.521555][ T44] rtnetlink_rcv_msg+0x6fe/0xb90 [ 861.521735][ T44] ? rtnl_port_fill+0x940/0x940 [ 861.521902][ T44] ? __lock_acquire+0x34c/0x740 [ 861.522075][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.522239][ T44] ? find_held_lock+0x2b/0x80 [ 861.522407][ T44] netlink_rcv_skb+0x123/0x380 [ 861.522589][ T44] ? rtnl_port_fill+0x940/0x940 [ 861.522762][ T44] ? netlink_ack+0xcc0/0xcc0 [ 861.522931][ T44] ? netlink_deliver_tap+0x13f/0x330 [ 861.523106][ T44] netlink_unicast+0x4a3/0x770 [ 861.523274][ T44] ? netlink_attachskb+0x810/0x810 [ 861.523442][ T44] ? __lock_acquire+0x34c/0x740 [ 861.523623][ T44] netlink_sendmsg+0x735/0xc60 [ 861.523790][ T44] ? netlink_unicast+0x770/0x770 [ 861.523956][ T44] ? __might_fault+0x97/0x140 [ 861.524135][ T44] ____sys_sendmsg+0x419/0x850 [ 861.524301][ T44] ? copy_msghdr_from_user+0x270/0x430 [ 861.524466][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0 [ 861.524668][ T44] ? move_addr_to_kernel+0x40/0x40 [ 861.524834][ T44] ? stack_depot_save_flags+0x3e1/0x6e0 [ 861.524999][ T44] ? validate_chain+0x287/0x5f0 [ 861.525171][ T44] ___sys_sendmsg+0xfd/0x180 [ 861.525340][ T44] ? __call_rcu_common.constprop.0+0xa5/0x680 [ 861.525534][ T44] ? copy_msghdr_from_user+0x430/0x430 [ 861.525726][ T44] __sys_sendmsg+0x124/0x1c0 [ 861.525892][ T44] ? __sys_sendmsg_sock+0x20/0x20 [ 861.526061][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.526226][ T44] ? __call_rcu_common.constprop.0+0x2fa/0x680 [ 861.526423][ T44] ? do_raw_spin_unlock+0x59/0x250 [ 861.526606][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.526772][ T44] do_syscall_64+0xbb/0xfd0 [ 861.526939][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 861.527133][ T44] RIP: 0033:0x7fb0654a7c5e [ 861.527304][ T44] RSP: 002b:00007ffd889539d0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e [ 861.527527][ T44] RAX: ffffffffffffffda RBX: 0000000000000004 RCX: 00007fb0654a7c5e [ 861.527761][ T44] RDX: 0000000000000000 RSI: 00007ffd88953a90 RDI: 0000000000000003 [ 861.527981][ T44] RBP: 00007ffd889539e0 R08: 0000000000000000 R09: 0000000000000000 [ 861.528209][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffd88954150 [ 861.528429][ T44] R13: 0000000069280ad6 R14: 0000000000000000 R15: 0000000000499600 [ 861.528672][ T44] [ 861.528812][ T44] INFO: task ip:5200 is blocked on a mutex likely owned by task kworker/3:2:171. [ 861.529055][ T44] task:kworker/3:2 state:D stack:26808 pid:171 tgid:171 ppid:2 task_flags:0x4208060 flags:0x00080000 [ 861.529360][ T44] Workqueue: events refill_work [ 861.529525][ T44] Call Trace: [ 861.529674][ T44] [ 861.529782][ T44] __schedule+0x88d/0x1b90 [ 861.529957][ T44] ? io_schedule_timeout+0x130/0x130 [ 861.530131][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.530301][ T44] ? find_held_lock+0x2b/0x80 [ 861.530469][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.530650][ T44] ? usleep_range_state+0xba/0x1b0 [ 861.530815][ T44] schedule+0xd4/0x260 [ 861.530953][ T44] schedule_hrtimeout_range_clock+0x1da/0x2f0 [ 861.531156][ T44] ? schedule_timeout_idle+0xe0/0xe0 [ 861.531321][ T44] ? hrtimer_cb_get_time+0x90/0x90 [ 861.531489][ T44] ? lockdep_hardirqs_on+0x84/0x130 [ 861.531664][ T44] ? ktime_get+0x15e/0x220 [ 861.531829][ T44] ? kvm_clock_get_cycles+0x18/0x30 [ 861.531994][ T44] ? ktime_get+0xa9/0x220 [ 861.532141][ T44] usleep_range_state+0x133/0x1b0 [ 861.532307][ T44] ? schedule_hrtimeout+0x20/0x20 [ 861.532474][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0 [ 861.532657][ T44] napi_disable_locked+0x379/0x5f0 [ 861.532823][ T44] ? _mutex_trylock_nest_lock+0x150/0x150 [ 861.532989][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470 [ 861.533191][ T44] napi_disable+0x43/0x80 [ 861.533333][ T44] refill_work+0x116/0x210 [ 861.533501][ T44] process_one_work+0xd57/0x1390 [ 861.533687][ T44] ? pwq_dec_nr_in_flight+0x700/0x700 [ 861.533852][ T44] ? lock_acquire.part.0+0xbc/0x260 [ 861.534021][ T44] ? assign_work+0x152/0x380 [ 861.534196][ T44] worker_thread+0x4d6/0xd40 [ 861.534366][ T44] ? process_one_work+0x1390/0x1390 [ 861.534531][ T44] ? __kthread_parkme+0xb3/0x200 [ 861.534718][ T44] ? process_one_work+0x1390/0x1390 [ 861.534882][ T44] kthread+0x355/0x5b0 [ 861.535019][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.535194][ T44] ? __lock_release.isra.0+0x59/0x170 [ 861.535359][ T44] ? rcu_is_watching+0x15/0xd0 [ 861.535525][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.535702][ T44] ret_from_fork+0x1d4/0x270 [ 861.535872][ T44] ? kthread_is_per_cpu+0xe0/0xe0 [ 861.536042][ T44] ret_from_fork_asm+0x11/0x20 [ 861.536214][ T44] [ 861.536349][ T44] [ 861.536349][ T44] Showing all locks held in the system: [ 861.536581][ T44] 3 locks held by kworker/1:0/23: [ 861.536741][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.537016][ T44] #1: ffa00000001a7d30 ((work_completion)(&pool->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.537299][ T44] #2: ffffffffb7c92428 (rtnl_mutex){+.+.}-{4:4}, at: xp_release_deferred+0x1b/0x1e0 [ 861.537546][ T44] 1 lock held by khungtaskd/44: [ 861.537718][ T44] #0: ffffffffb7578fc0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2b/0x1db [ 861.537964][ T44] 3 locks held by kworker/u16:1/69: [ 861.538129][ T44] #0: ff1100000919cd48 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.538399][ T44] #1: ffa00000004a7d30 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.538741][ T44] #2: ffffffffb7c92428 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30 [ 861.538992][ T44] 3 locks held by kworker/3:2/171: [ 861.539158][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390 [ 861.539432][ T44] #1: ffa00000005e7d30 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390 [ 861.539742][ T44] #2: ff11000008b42c98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80 [ 861.539996][ T44] 2 locks held by ip/5200: [ 861.540159][ T44] #0: ffffffffb7c92428 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5df/0xb20 [ 861.540404][ T44] #1: ff11000008b42c98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80 [ 861.540757][ T44] 1 lock held by xdp_helper/5207: [ 861.540916][ T44] #0: ffffffffb7c92428 (rtnl_mutex){+.+.}-{4:4}, at: xsk_bind+0x17f/0x1a10 [ 861.541226][ T44] [ 861.541330][ T44] ============================================= [ 861.541330][ T44] [ 865.088566][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 364545 ms [ 865.088964][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 364545000 usecs ago [ 870.208571][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 369665 ms [ 870.208997][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 369665000 usecs ago [ 875.328570][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 374785 ms [ 875.328987][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 374785000 usecs ago [ 881.471569][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 380928 ms [ 881.471968][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 380928000 usecs ago [ 887.103569][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 386560 ms [ 887.103967][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 386560000 usecs ago [ 892.223572][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 391680 ms [ 892.224048][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 391680000 usecs ago [ 897.344567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 396801 ms [ 897.345008][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 396801000 usecs ago [ 903.488567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 402945 ms [ 903.489002][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 402945000 usecs ago [ 909.120565][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 408577 ms [ 909.120975][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 408577000 usecs ago [ 914.239565][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 413696 ms [ 914.239978][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 413696000 usecs ago [ 919.360573][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 418817 ms [ 919.361071][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 418817000 usecs ago [ 925.503567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 424960 ms [ 925.504087][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 424960000 usecs ago [ 931.136568][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 430593 ms [ 931.137008][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 430593000 usecs ago [ 936.255567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 435712 ms [ 936.256008][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 435712000 usecs ago [ 941.376569][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 440833 ms [ 941.377044][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 440833000 usecs ago [ 947.519566][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 446976 ms [ 947.519972][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 446976000 usecs ago [ 953.152574][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 452609 ms [ 953.153061][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 452609000 usecs ago [ 958.272567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 457729 ms [ 958.273053][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 457729000 usecs ago [ 963.391567][ C1] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 1: transmit queue 0 timed out 462848 ms [ 963.391970][ C1] virtio_net virtio2 enp0s1: TX timeout on queue: 0, sq: output.0, vq: 0x1, name: output.0, 462848000 usecs ago