[ 748.094399][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 5376 ms
[ 748.095026][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 5376000 usecs ago
[ 753.470391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 10752 ms
[ 753.470753][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 10752000 usecs ago
[ 759.101439][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 16383 ms
[ 759.101818][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 16383000 usecs ago
[ 764.222392][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 21504 ms
[ 764.222757][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 21504000 usecs ago
[ 769.342391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 26624 ms
[ 769.342754][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 26624000 usecs ago
[ 775.485408][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 32767 ms
[ 775.485765][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 32767000 usecs ago
[ 781.117395][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 38399 ms
[ 781.117776][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 38399000 usecs ago
[ 786.237394][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 43519 ms
[ 786.237770][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 43519000 usecs ago
[ 791.357395][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 48639 ms
[ 791.357756][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 48639000 usecs ago
[ 797.502393][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 54784 ms
[ 797.502788][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 54784000 usecs ago
[ 803.133394][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 60415 ms
[ 803.133761][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 60415000 usecs ago
[ 808.254391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 65536 ms
[ 808.254748][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 65536000 usecs ago
[ 813.373390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 70655 ms
[ 813.373736][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 70655000 usecs ago
[ 819.517392][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 76799 ms
[ 819.517747][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 76799000 usecs ago
[ 825.150390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 82432 ms
[ 825.150733][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 82432000 usecs ago
[ 830.270390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 87552 ms
[ 830.270739][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 87552000 usecs ago
[ 835.390391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 92672 ms
[ 835.390732][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 92672000 usecs ago
[ 841.534392][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 98816 ms
[ 841.534746][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 98816000 usecs ago
[ 847.166390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 104448 ms
[ 847.166781][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 104448000 usecs ago
[ 852.286390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 109568 ms
[ 852.286788][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 109568000 usecs ago
[ 857.406394][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 114688 ms
[ 857.406907][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 114688000 usecs ago
[ 861.501410][ T44] INFO: task kworker/u16:0:12 blocked for more than 122 seconds.
[ 861.501882][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 861.502037][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 861.502326][ T44] task:kworker/u16:0 state:D stack:26920 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 861.502681][ T44] Workqueue: ipv6_addrconf addrconf_verify_work
[ 861.502869][ T44] Call Trace:
[ 861.502981][ T44]
[ 861.503057][ T44] __schedule+0x88d/0x1b90
[ 861.503212][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.503358][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.503517][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.503652][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.503785][ T44] schedule+0xd4/0x260
[ 861.503884][ T44] schedule_preempt_disabled+0x18/0x30
[ 861.504015][ T44] __mutex_lock+0x9ae/0x1500
[ 861.504151][ T44] ? addrconf_verify_work+0x12/0x30
[ 861.504298][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.504428][ T44] ? process_one_work+0xd16/0x1390
[ 861.504546][ T44] ? trace_lock_acquire+0x88/0xd0
[ 861.504663][ T44] ? trace_lock_acquire+0x88/0xd0
[ 861.504782][ T44] ? addrconf_verify_work+0x12/0x30
[ 861.504897][ T44] addrconf_verify_work+0x12/0x30
[ 861.505012][ T44] process_one_work+0xd57/0x1390
[ 861.505132][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 861.505260][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.505392][ T44] ? assign_work+0x152/0x380
[ 861.505509][ T44] worker_thread+0x4d6/0xd40
[ 861.505967][ T44] ? process_one_work+0x1390/0x1390
[ 861.506082][ T44] kthread+0x355/0x5b0
[ 861.506169][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.506289][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.506419][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.506535][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.506650][ T44] ret_from_fork+0x1d4/0x270
[ 861.506767][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.506882][ T44] ret_from_fork_asm+0x11/0x20
[ 861.507007][ T44]
[ 861.507113][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:5895.
[ 861.507323][ T44] INFO: task ip:5895 blocked for more than 122 seconds.
[ 861.507471][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 861.507579][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 861.507764][ T44] task:ip state:D stack:25496 pid:5895 tgid:5895 ppid:5890 task_flags:0x400100 flags:0x00080001
[ 861.508016][ T44] Call Trace:
[ 861.508102][ T44]
[ 861.508164][ T44] __schedule+0x88d/0x1b90
[ 861.508286][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.508410][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.508525][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.508639][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.508757][ T44] schedule+0xd4/0x260
[ 861.508847][ T44] schedule_preempt_disabled+0x18/0x30
[ 861.508996][ T44] __mutex_lock+0x9ae/0x1500
[ 861.509111][ T44] ? napi_enable+0x3b/0x80
[ 861.509229][ T44] ? virtqueue_add_inbuf_ctx+0x1850/0x1850
[ 861.509376][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.509500][ T44] ? __asan_memset+0x27/0x50
[ 861.509616][ T44] ? sg_init_table+0x19/0x60
[ 861.509733][ T44] ? try_fill_recv+0x3ec/0x1750
[ 861.509853][ T44] ? napi_enable+0x3b/0x80
[ 861.509970][ T44] napi_enable+0x3b/0x80
[ 861.510057][ T44] __virtnet_rx_resume+0x156/0x290
[ 861.510172][ T44] ? queue_delayed_work_on+0x91/0x100
[ 861.510291][ T44] virtnet_rx_resume_all+0xe7/0x1a0
[ 861.510412][ T44] virtnet_xdp_set+0xef7/0x1aa0
[ 861.510530][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0
[ 861.510647][ T44] dev_xdp_install+0x3e4/0x8e0
[ 861.510772][ T44] ? netif_skb_check_for_xdp+0x600/0x600
[ 861.510896][ T44] dev_xdp_attach+0x50d/0x1320
[ 861.511013][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0
[ 861.511128][ T44] ? __bpf_prog_get+0x320/0x320
[ 861.511246][ T44] dev_change_xdp_fd+0x182/0x240
[ 861.511363][ T44] do_setlink.isra.0+0x1c2d/0x2880
[ 861.511487][ T44] ? rtnl_newlink_create+0x900/0x900
[ 861.511610][ T44] ? rcu_lockdep_current_cpu_online+0x3e/0x1b0
[ 861.511757][ T44] ? rcu_read_lock_any_held+0x41/0xa0
[ 861.511872][ T44] ? validate_chain+0x287/0x5f0
[ 861.511991][ T44] ? rcu_read_lock_any_held+0x6b/0xa0
[ 861.512105][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 861.512222][ T44] ? __lock_acquire+0x34c/0x740
[ 861.512346][ T44] ? trace_lock_acquire+0x88/0xd0
[ 861.512472][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 861.512587][ T44] ? lock_acquire+0x31/0x80
[ 861.512702][ T44] ? rtnl_newlink+0x5df/0xb20
[ 861.512817][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.512931][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.513046][ T44] ? trace_contention_end+0xd6/0x140
[ 861.513163][ T44] ? rtnl_newlink+0x5df/0xb20
[ 861.513286][ T44] ? __mutex_lock+0x32e/0x1500
[ 861.513412][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.513526][ T44] ? nla_validate_int_range+0x5b0/0x5b0
[ 861.513646][ T44] ? __rtnl_newlink+0x3fa/0xa40
[ 861.513767][ T44] rtnl_newlink+0x637/0xb20
[ 861.513891][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.514009][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.514122][ T44] ? find_held_lock+0x2b/0x80
[ 861.514241][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.514356][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.514483][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.514599][ T44] rtnetlink_rcv_msg+0x6fe/0xb90
[ 861.514714][ T44] ? rtnl_port_fill+0x940/0x940
[ 861.514826][ T44] ? __lock_acquire+0x34c/0x740
[ 861.514948][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.515062][ T44] ? find_held_lock+0x2b/0x80
[ 861.515184][ T44] netlink_rcv_skb+0x123/0x380
[ 861.515313][ T44] ? rtnl_port_fill+0x940/0x940
[ 861.515435][ T44] ? netlink_ack+0xcc0/0xcc0
[ 861.515554][ T44] ? netlink_deliver_tap+0x13f/0x330
[ 861.515672][ T44] netlink_unicast+0x4a3/0x770
[ 861.515789][ T44] ? netlink_attachskb+0x810/0x810
[ 861.515904][ T44] ? __lock_acquire+0x34c/0x740
[ 861.516027][ T44] netlink_sendmsg+0x735/0xc60
[ 861.516146][ T44] ? netlink_unicast+0x770/0x770
[ 861.516270][ T44] ? __might_fault+0x97/0x140
[ 861.516396][ T44] ____sys_sendmsg+0x419/0x850
[ 861.516512][ T44] ? copy_msghdr_from_user+0x270/0x430
[ 861.516630][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0
[ 861.516776][ T44] ? move_addr_to_kernel+0x40/0x40
[ 861.516895][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 861.517013][ T44] ? validate_chain+0x287/0x5f0
[ 861.517129][ T44] ___sys_sendmsg+0xfd/0x180
[ 861.517246][ T44] ? __call_rcu_common.constprop.0+0xa5/0x680
[ 861.517396][ T44] ? copy_msghdr_from_user+0x430/0x430
[ 861.517524][ T44] __sys_sendmsg+0x124/0x1c0
[ 861.517641][ T44] ? __sys_sendmsg_sock+0x20/0x20
[ 861.517757][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 861.517877][ T44] ? __call_rcu_common.constprop.0+0x2fa/0x680
[ 861.518023][ T44] ? do_raw_spin_unlock+0x59/0x250
[ 861.518139][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.518258][ T44] do_syscall_64+0xbb/0xfd0
[ 861.518376][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 861.518526][ T44] RIP: 0033:0x7f786239bc5e
[ 861.518650][ T44] RSP: 002b:00007ffec2cdb800 EFLAGS: 00000202 ORIG_RAX: 000000000000002e
[ 861.518831][ T44] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007f786239bc5e
[ 861.519007][ T44] RDX: 0000000000000000 RSI: 00007ffec2cdb8c0 RDI: 0000000000000003
[ 861.519182][ T44] RBP: 00007ffec2cdb810 R08: 0000000000000000 R09: 0000000000000000
[ 861.519359][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffec2cdbf80
[ 861.519541][ T44] R13: 000000006929cc79 R14: 0000000000000000 R15: 0000000000499600
[ 861.519724][ T44]
[ 861.519812][ T44] INFO: task ip:5895 is blocked on a mutex likely owned by task kworker/2:1:51.
[ 861.520008][ T44] task:kworker/2:1 state:D stack:27512 pid:51 tgid:51 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 861.520271][ T44] Workqueue: events refill_work
[ 861.520395][ T44] Call Trace:
[ 861.520483][ T44]
[ 861.520544][ T44] ? __schedule+0x88d/0x1b90
[ 861.520661][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.520777][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.520891][ T44] ? find_held_lock+0x2b/0x80
[ 861.521005][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.521122][ T44] ? usleep_range_state+0xba/0x1b0
[ 861.521241][ T44] ? find_held_lock+0x2b/0x80
[ 861.521356][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.521480][ T44] ? usleep_range_state+0xba/0x1b0
[ 861.521592][ T44] ? schedule+0xd4/0x260
[ 861.521680][ T44] ? schedule_hrtimeout_range_clock+0x1da/0x2f0
[ 861.521823][ T44] ? schedule_timeout_idle+0xe0/0xe0
[ 861.521941][ T44] ? hrtimer_cb_get_time+0x90/0x90
[ 861.522059][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 861.522173][ T44] ? ktime_get+0x15e/0x220
[ 861.522292][ T44] ? kvm_clock_get_cycles+0x18/0x30
[ 861.522414][ T44] ? ktime_get+0xa9/0x220
[ 861.522502][ T44] ? usleep_range_state+0x133/0x1b0
[ 861.522619][ T44] ? schedule_hrtimeout+0x20/0x20
[ 861.522743][ T44] ? __free_object+0x2ad/0x4b0
[ 861.522864][ T44] ? napi_disable_locked+0x379/0x5f0
[ 861.522977][ T44] ? _mutex_trylock_nest_lock+0x150/0x150
[ 861.523091][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470
[ 861.523239][ T44] ? napi_disable+0x43/0x80
[ 861.523355][ T44] ? refill_work+0x116/0x210
[ 861.523478][ T44] ? process_one_work+0xd57/0x1390
[ 861.523598][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 861.523715][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.523832][ T44] ? assign_work+0x152/0x380
[ 861.523948][ T44] ? worker_thread+0x4d6/0xd40
[ 861.524064][ T44] ? process_one_work+0x1390/0x1390
[ 861.524178][ T44] ? __kthread_parkme+0xb3/0x200
[ 861.524299][ T44] ? process_one_work+0x1390/0x1390
[ 861.524423][ T44] ? kthread+0x355/0x5b0
[ 861.524511][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.524624][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.524740][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.524855][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.524971][ T44] ? ret_from_fork+0x1d4/0x270
[ 861.525085][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.525200][ T44] ? ret_from_fork_asm+0x11/0x20
[ 861.525327][ T44]
[ 861.525428][ T44]
[ 861.525428][ T44] Showing all locks held in the system:
[ 861.525599][ T44] 3 locks held by kworker/u16:0/12:
[ 861.525713][ T44] #0: ff1100000913cd48 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 861.525942][ T44] #1: ffa00000000c7d30 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 861.526221][ T44] #2: ffffffff8e892368 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30
[ 861.526521][ T44] 1 lock held by khungtaskd/44:
[ 861.526632][ T44] #0: ffffffff8e178fc0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2b/0x1db
[ 861.526835][ T44] 3 locks held by kworker/2:1/51:
[ 861.526945][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 861.527172][ T44] #1: ffa000000037fd30 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 861.527437][ T44] #2: ff11000008b12c98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80
[ 861.527651][ T44] 2 locks held by ip/5895:
[ 861.527763][ T44] #0: ffffffff8e892368 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5df/0xb20
[ 861.527966][ T44] #1: ff11000008b12c98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80
[ 861.528164][ T44]
[ 861.528223][ T44] =============================================
[ 861.528223][ T44]
[ 863.549390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 120831 ms
[ 863.549816][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 120831000 usecs ago
[ 869.181391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 126463 ms
[ 869.181806][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 126463000 usecs ago
[ 874.302396][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 131584 ms
[ 874.302848][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 131584000 usecs ago
[ 880.446393][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 137728 ms
[ 880.446809][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 137728000 usecs ago
[ 886.078390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 143360 ms
[ 886.078803][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 143360000 usecs ago
[ 891.197390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 148479 ms
[ 891.197791][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 148479000 usecs ago
[ 896.318390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 153600 ms
[ 896.318798][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 153600000 usecs ago
[ 902.462392][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 159744 ms
[ 902.462810][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 159744000 usecs ago
[ 908.094395][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 165376 ms
[ 908.094793][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 165376000 usecs ago
[ 913.214391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 170496 ms
[ 913.214790][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 170496000 usecs ago
[ 918.334395][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 175616 ms
[ 918.334822][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 175616000 usecs ago
[ 924.478390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 181760 ms
[ 924.478790][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 181760000 usecs ago
[ 930.109390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 187391 ms
[ 930.109789][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 187391000 usecs ago
[ 935.230390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 192512 ms
[ 935.230783][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 192512000 usecs ago
[ 940.350390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 197632 ms
[ 940.350776][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 197632000 usecs ago
[ 946.494391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 203776 ms
[ 946.494797][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 203776000 usecs ago
[ 952.126391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 209408 ms
[ 952.126783][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 209408000 usecs ago
[ 957.246391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 214528 ms
[ 957.246795][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 214528000 usecs ago
[ 962.365394][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 219647 ms
[ 962.365786][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 219647000 usecs ago
[ 968.510390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 225792 ms
[ 968.510788][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 225792000 usecs ago
[ 974.142390][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 231424 ms
[ 974.142777][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 231424000 usecs ago
[ 979.261393][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 236543 ms
[ 979.261812][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 236543000 usecs ago
[ 984.381389][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 241663 ms
[ 984.381411][ T44] INFO: task kworker/u16:0:12 blocked for more than 245 seconds.
[ 984.381793][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 241663000 usecs ago
[ 984.382098][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 984.382660][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 984.382905][ T44] task:kworker/u16:0 state:D stack:26920 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 984.383221][ T44] Workqueue: ipv6_addrconf addrconf_verify_work
[ 984.383403][ T44] Call Trace:
[ 984.383505][ T44]
[ 984.383579][ T44] __schedule+0x88d/0x1b90
[ 984.383717][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.383857][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.383991][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.384128][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.384262][ T44] schedule+0xd4/0x260
[ 984.384370][ T44] schedule_preempt_disabled+0x18/0x30
[ 984.384519][ T44] __mutex_lock+0x9ae/0x1500
[ 984.384636][ T44] ? addrconf_verify_work+0x12/0x30
[ 984.384761][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.384879][ T44] ? process_one_work+0xd16/0x1390
[ 984.384997][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.385114][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.385231][ T44] ? addrconf_verify_work+0x12/0x30
[ 984.385347][ T44] addrconf_verify_work+0x12/0x30
[ 984.385473][ T44] process_one_work+0xd57/0x1390
[ 984.385593][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 984.385715][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.385832][ T44] ? assign_work+0x152/0x380
[ 984.385952][ T44] worker_thread+0x4d6/0xd40
[ 984.386074][ T44] ? process_one_work+0x1390/0x1390
[ 984.386188][ T44] kthread+0x355/0x5b0
[ 984.386275][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.386399][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.386521][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.386638][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.386756][ T44] ret_from_fork+0x1d4/0x270
[ 984.386872][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.386989][ T44] ret_from_fork_asm+0x11/0x20
[ 984.387110][ T44]
[ 984.387214][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:5895.
[ 984.387431][ T44] INFO: task ip:5895 blocked for more than 245 seconds.
[ 984.387567][ T44] Not tainted 6.18.0-rc7-virtme #1
[ 984.387675][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 984.387863][ T44] task:ip state:D stack:25496 pid:5895 tgid:5895 ppid:5890 task_flags:0x400100 flags:0x00080001
[ 984.388116][ T44] Call Trace:
[ 984.388208][ T44]
[ 984.388267][ T44] __schedule+0x88d/0x1b90
[ 984.388395][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.388512][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.388629][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.388743][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.388861][ T44] schedule+0xd4/0x260
[ 984.388948][ T44] schedule_preempt_disabled+0x18/0x30
[ 984.389066][ T44] __mutex_lock+0x9ae/0x1500
[ 984.389181][ T44] ? napi_enable+0x3b/0x80
[ 984.389297][ T44] ? virtqueue_add_inbuf_ctx+0x1850/0x1850
[ 984.389461][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.389577][ T44] ? __asan_memset+0x27/0x50
[ 984.389693][ T44] ? sg_init_table+0x19/0x60
[ 984.389810][ T44] ? try_fill_recv+0x3ec/0x1750
[ 984.389931][ T44] ? napi_enable+0x3b/0x80
[ 984.390046][ T44] napi_enable+0x3b/0x80
[ 984.390139][ T44] __virtnet_rx_resume+0x156/0x290
[ 984.390259][ T44] ? queue_delayed_work_on+0x91/0x100
[ 984.390379][ T44] virtnet_rx_resume_all+0xe7/0x1a0
[ 984.390502][ T44] virtnet_xdp_set+0xef7/0x1aa0
[ 984.390621][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0
[ 984.390738][ T44] dev_xdp_install+0x3e4/0x8e0
[ 984.390859][ T44] ? netif_skb_check_for_xdp+0x600/0x600
[ 984.390982][ T44] dev_xdp_attach+0x50d/0x1320
[ 984.391098][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0
[ 984.391213][ T44] ? __bpf_prog_get+0x320/0x320
[ 984.391333][ T44] dev_change_xdp_fd+0x182/0x240
[ 984.391454][ T44] do_setlink.isra.0+0x1c2d/0x2880
[ 984.391572][ T44] ? rtnl_newlink_create+0x900/0x900
[ 984.391689][ T44] ? rcu_lockdep_current_cpu_online+0x3e/0x1b0
[ 984.391832][ T44] ? rcu_read_lock_any_held+0x41/0xa0
[ 984.391946][ T44] ? validate_chain+0x287/0x5f0
[ 984.392060][ T44] ? rcu_read_lock_any_held+0x6b/0xa0
[ 984.392174][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 984.392293][ T44] ? __lock_acquire+0x34c/0x740
[ 984.392426][ T44] ? trace_lock_acquire+0x88/0xd0
[ 984.392539][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 984.392654][ T44] ? lock_acquire+0x31/0x80
[ 984.392768][ T44] ? rtnl_newlink+0x5df/0xb20
[ 984.392882][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.392996][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.393110][ T44] ? trace_contention_end+0xd6/0x140
[ 984.393224][ T44] ? rtnl_newlink+0x5df/0xb20
[ 984.393345][ T44] ? __mutex_lock+0x32e/0x1500
[ 984.393469][ T44] ? ww_mutex_lock+0x160/0x160
[ 984.393584][ T44] ? nla_validate_int_range+0x5b0/0x5b0
[ 984.393706][ T44] ? __rtnl_newlink+0x3fa/0xa40
[ 984.393824][ T44] rtnl_newlink+0x637/0xb20
[ 984.393941][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.394059][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.394174][ T44] ? find_held_lock+0x2b/0x80
[ 984.394294][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.394420][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.394537][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 984.394654][ T44] rtnetlink_rcv_msg+0x6fe/0xb90
[ 984.394768][ T44] ? rtnl_port_fill+0x940/0x940
[ 984.394882][ T44] ? __lock_acquire+0x34c/0x740
[ 984.395004][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.395122][ T44] ? find_held_lock+0x2b/0x80
[ 984.395236][ T44] netlink_rcv_skb+0x123/0x380
[ 984.395353][ T44] ? rtnl_port_fill+0x940/0x940
[ 984.395474][ T44] ? netlink_ack+0xcc0/0xcc0
[ 984.395592][ T44] ? netlink_deliver_tap+0x13f/0x330
[ 984.395708][ T44] netlink_unicast+0x4a3/0x770
[ 984.395823][ T44] ? netlink_attachskb+0x810/0x810
[ 984.395939][ T44] ? __lock_acquire+0x34c/0x740
[ 984.396061][ T44] netlink_sendmsg+0x735/0xc60
[ 984.396179][ T44] ? netlink_unicast+0x770/0x770
[ 984.396295][ T44] ? __might_fault+0x97/0x140
[ 984.396423][ T44] ____sys_sendmsg+0x419/0x850
[ 984.396537][ T44] ? copy_msghdr_from_user+0x270/0x430
[ 984.396652][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0
[ 984.396792][ T44] ? move_addr_to_kernel+0x40/0x40
[ 984.396913][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 984.397027][ T44] ? validate_chain+0x287/0x5f0
[ 984.397141][ T44] ___sys_sendmsg+0xfd/0x180
[ 984.397260][ T44] ? __call_rcu_common.constprop.0+0xa5/0x680
[ 984.397418][ T44] ? copy_msghdr_from_user+0x430/0x430
[ 984.397545][ T44] __sys_sendmsg+0x124/0x1c0
[ 984.397661][ T44] ? __sys_sendmsg_sock+0x20/0x20
[ 984.397774][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 984.397893][ T44] ? __call_rcu_common.constprop.0+0x2fa/0x680
[ 984.398043][ T44] ? do_raw_spin_unlock+0x59/0x250
[ 984.398158][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.398274][ T44] do_syscall_64+0xbb/0xfd0
[ 984.398401][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 984.398545][ T44] RIP: 0033:0x7f786239bc5e
[ 984.398666][ T44] RSP: 002b:00007ffec2cdb800 EFLAGS: 00000202 ORIG_RAX: 000000000000002e
[ 984.398845][ T44] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007f786239bc5e
[ 984.399016][ T44] RDX: 0000000000000000 RSI: 00007ffec2cdb8c0 RDI: 0000000000000003
[ 984.399187][ T44] RBP: 00007ffec2cdb810 R08: 0000000000000000 R09: 0000000000000000
[ 984.399364][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffec2cdbf80
[ 984.399551][ T44] R13: 000000006929cc79 R14: 0000000000000000 R15: 0000000000499600
[ 984.399730][ T44]
[ 984.399818][ T44] INFO: task ip:5895 is blocked on a mutex likely owned by task kworker/2:1:51.
[ 984.400010][ T44] task:kworker/2:1 state:R running task stack:27512 pid:51 tgid:51 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 984.400295][ T44] Workqueue: events refill_work
[ 984.400419][ T44] Call Trace:
[ 984.400505][ T44]
[ 984.400564][ T44] __schedule+0x88d/0x1b90
[ 984.400679][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.400794][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.400907][ T44] ? io_schedule_timeout+0x130/0x130
[ 984.401023][ T44] ? free_object+0x9/0x80
[ 984.401114][ T44] ? debug_object_assert_init+0x340/0x340
[ 984.401227][ T44] ? usleep_range_state+0xba/0x1b0
[ 984.401342][ T44] ? find_held_lock+0x2b/0x80
[ 984.401466][ T44] ? validate_chain+0x287/0x5f0
[ 984.401583][ T44] ? _raw_spin_unlock_irqrestore+0x53/0x80
[ 984.401726][ T44] ? usleep_range_state+0xba/0x1b0
[ 984.401840][ T44] ? schedule_hrtimeout_range_clock+0x1ca/0x2f0
[ 984.401989][ T44] ? schedule_timeout_idle+0xe0/0xe0
[ 984.402104][ T44] ? hrtimer_cb_get_time+0x90/0x90
[ 984.402219][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 984.402336][ T44] ? ktime_get+0x15e/0x220
[ 984.402456][ T44] ? kvm_clock_get_cycles+0x18/0x30
[ 984.402570][ T44] ? ktime_get+0xa9/0x220
[ 984.402656][ T44] ? usleep_range_state+0x133/0x1b0
[ 984.402773][ T44] ? schedule_hrtimeout+0x20/0x20
[ 984.402888][ T44] ? __free_object+0x2ad/0x4b0
[ 984.403004][ T44] ? napi_disable_locked+0x379/0x5f0
[ 984.403118][ T44] ? _mutex_trylock_nest_lock+0x150/0x150
[ 984.403233][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470
[ 984.403379][ T44] ? napi_disable+0x43/0x80
[ 984.403499][ T44] ? refill_work+0x116/0x210
[ 984.403617][ T44] ? process_one_work+0xd57/0x1390
[ 984.403736][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 984.403852][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 984.403971][ T44] ? assign_work+0x152/0x380
[ 984.404088][ T44] ? worker_thread+0x4d6/0xd40
[ 984.404204][ T44] ? process_one_work+0x1390/0x1390
[ 984.404319][ T44] ? __kthread_parkme+0xb3/0x200
[ 984.404443][ T44] ? process_one_work+0x1390/0x1390
[ 984.404557][ T44] ? kthread+0x355/0x5b0
[ 984.404643][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.404759][ T44] ? __lock_release.isra.0+0x59/0x170
[ 984.404874][ T44] ? rcu_is_watching+0x15/0xd0
[ 984.404994][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.405109][ T44] ? ret_from_fork+0x1d4/0x270
[ 984.405232][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 984.405349][ T44] ? ret_from_fork_asm+0x11/0x20
[ 984.405475][ T44]
[ 984.405567][ T44]
[ 984.405567][ T44] Showing all locks held in the system:
[ 984.405730][ T44] 3 locks held by kworker/u16:0/12:
[ 984.405842][ T44] #0: ff1100000913cd48 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 984.406070][ T44] #1: ffa00000000c7d30 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 984.406352][ T44] #2: ffffffff8e892368 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30
[ 984.406586][ T44] 1 lock held by khungtaskd/44:
[ 984.406697][ T44] #0: ffffffff8e178fc0 (rcu_read_lock){....}-{1:3}, at: debug_show_all_locks+0x2b/0x1db
[ 984.406896][ T44] 3 locks held by kworker/2:1/51:
[ 984.407006][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 984.407228][ T44] #1: ffa000000037fd30 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 984.407489][ T44] #2: ff11000008b12c98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80
[ 984.407691][ T44] 2 locks held by ip/5895:
[ 984.407806][ T44] #0: ffffffff8e892368 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5df/0xb20
[ 984.408009][ T44] #1: ff11000008b12c98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80
[ 984.408206][ T44]
[ 984.408262][ T44] =============================================
[ 984.408262][ T44]
[ 990.525391][ C3] virtio_net virtio2 enp0s1: NETDEV WATCHDOG: CPU: 3: transmit queue 2 timed out 247807 ms
[ 990.525816][ C3] virtio_net virtio2 enp0s1: TX timeout on queue: 2, sq: output.2, vq: 0x5, name: output.2, 247807000 usecs ago