WAIT TIMEOUT stderr
Ctrl-C stderr
Ctrl-C stderr
[ 861.501742][ T44] INFO: task kworker/u16:0:12 blocked for more than 122 seconds.
[ 861.502253][ T44] Not tainted 6.18.0-virtme #1
[ 861.502465][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 861.502845][ T44] task:kworker/u16:0 state:D stack:26224 pid:12 tgid:12 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 861.503224][ T44] Workqueue: ipv6_addrconf addrconf_verify_work
[ 861.503838][ T44] Call Trace:
[ 861.503999][ T44]
[ 861.504119][ T44] __schedule+0xae2/0x1720
[ 861.504306][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.504491][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.504679][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.504891][ T44] schedule+0xd4/0x260
[ 861.505043][ T44] schedule_preempt_disabled+0x18/0x30
[ 861.505229][ T44] __mutex_lock+0xd76/0x1f20
[ 861.505421][ T44] ? addrconf_verify_work+0x12/0x30
[ 861.505612][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.505798][ T44] ? process_one_work+0xd16/0x1390
[ 861.505976][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.506144][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.506311][ T44] ? addrconf_verify_work+0x12/0x30
[ 861.506477][ T44] addrconf_verify_work+0x12/0x30
[ 861.506645][ T44] process_one_work+0xd57/0x1390
[ 861.506837][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 861.507004][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.507171][ T44] ? assign_work+0x152/0x380
[ 861.507340][ T44] worker_thread+0x4d6/0xd40
[ 861.507510][ T44] ? process_one_work+0x1390/0x1390
[ 861.507676][ T44] kthread+0x355/0x5b0
[ 861.507834][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.507998][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.508164][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.508331][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.508497][ T44] ret_from_fork+0x3fb/0x510
[ 861.508663][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140
[ 861.508874][ T44] ? __switch_to+0x602/0xd00
[ 861.509044][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.509212][ T44] ret_from_fork_asm+0x11/0x20
[ 861.509385][ T44]
[ 861.509552][ T44] INFO: task kworker/u16:0:12 is blocked on a mutex likely owned by task ip:6122.
[ 861.509836][ T44] INFO: task ip:6122 blocked for more than 122 seconds.
[ 861.510020][ T44] Not tainted 6.18.0-virtme #1
[ 861.510178][ T44] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[ 861.510412][ T44] task:ip state:D stack:24976 pid:6122 tgid:6122 ppid:1 task_flags:0x400100 flags:0x00080002
[ 861.510714][ T44] Call Trace:
[ 861.510868][ T44]
[ 861.510975][ T44] __schedule+0xae2/0x1720
[ 861.511144][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.511312][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.511477][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.511646][ T44] schedule+0xd4/0x260
[ 861.511796][ T44] schedule_preempt_disabled+0x18/0x30
[ 861.511966][ T44] __mutex_lock+0xd76/0x1f20
[ 861.512136][ T44] ? napi_enable+0x3b/0x80
[ 861.512306][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.512471][ T44] ? __asan_memset+0x27/0x50
[ 861.512640][ T44] ? sg_init_table+0x19/0x60
[ 861.512830][ T44] ? try_fill_recv+0x3ec/0x1750
[ 861.513000][ T44] ? napi_enable+0x3b/0x80
[ 861.513165][ T44] napi_enable+0x3b/0x80
[ 861.513305][ T44] __virtnet_rx_resume+0x156/0x290
[ 861.513473][ T44] ? queue_delayed_work_on+0x91/0x100
[ 861.513642][ T44] virtnet_rx_resume_all+0xe7/0x1a0
[ 861.513830][ T44] virtnet_xdp_set+0xef7/0x1aa0
[ 861.513999][ T44] ? virtnet_xdp_set+0x1aa0/0x1aa0
[ 861.514166][ T44] dev_xdp_install+0x3e4/0x890
[ 861.514331][ T44] ? alloc_netdev_dummy+0x30/0x30
[ 861.514502][ T44] dev_xdp_attach+0x50d/0x1310
[ 861.514691][ T44] ? bpf_xdp_link_update+0x6e0/0x6e0
[ 861.514874][ T44] ? __bpf_prog_get+0x320/0x320
[ 861.515044][ T44] dev_change_xdp_fd+0x176/0x210
[ 861.515211][ T44] do_setlink.isra.0+0x1c2d/0x2880
[ 861.515378][ T44] ? rtnl_newlink_create+0x900/0x900
[ 861.515545][ T44] ? rcu_lockdep_current_cpu_online+0x39/0x1b0
[ 861.515755][ T44] ? rcu_read_lock_any_held+0x3c/0x90
[ 861.515931][ T44] ? validate_chain+0x30f/0x8e0
[ 861.516093][ T44] ? rcu_read_lock_any_held+0x66/0x90
[ 861.516258][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 861.516424][ T44] ? __lock_acquire+0x577/0xc10
[ 861.516594][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 861.516769][ T44] ? rtnl_newlink+0x5f9/0xb40
[ 861.516940][ T44] ? lock_acquire+0x10a/0x150
[ 861.517107][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.517270][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.517435][ T44] ? trace_contention_end+0xd6/0x110
[ 861.517601][ T44] ? __mutex_lock+0x285/0x1f20
[ 861.517784][ T44] ? rtnl_newlink+0x5f9/0xb40
[ 861.517952][ T44] ? ww_mutex_lock+0x160/0x160
[ 861.518117][ T44] ? nla_validate_int_range+0x5b0/0x5b0
[ 861.518283][ T44] ? __rtnl_newlink+0x3fa/0xa40
[ 861.518456][ T44] rtnl_newlink+0x649/0xb40
[ 861.518624][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.518801][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.518973][ T44] ? __lock_acquire+0x577/0xc10
[ 861.519139][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.519303][ T44] ? find_held_lock+0x2b/0x80
[ 861.519469][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.519634][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.519824][ T44] ? __rtnl_newlink+0xa40/0xa40
[ 861.519988][ T44] rtnetlink_rcv_msg+0x6fe/0xb90
[ 861.520155][ T44] ? rtnl_port_fill+0x940/0x940
[ 861.520319][ T44] ? __lock_acquire+0x577/0xc10
[ 861.520484][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.520649][ T44] ? find_held_lock+0x2b/0x80
[ 861.520833][ T44] netlink_rcv_skb+0x123/0x380
[ 861.521005][ T44] ? rtnl_port_fill+0x940/0x940
[ 861.521171][ T44] ? netlink_ack+0xcc0/0xcc0
[ 861.521339][ T44] ? netlink_deliver_tap+0xc5/0x330
[ 861.521501][ T44] ? netlink_deliver_tap+0x13f/0x330
[ 861.521667][ T44] netlink_unicast+0x4a3/0x770
[ 861.521855][ T44] ? netlink_attachskb+0x810/0x810
[ 861.522020][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.522191][ T44] ? __lock_acquire+0x577/0xc10
[ 861.522359][ T44] netlink_sendmsg+0x735/0xc60
[ 861.522526][ T44] ? netlink_unicast+0x770/0x770
[ 861.522695][ T44] ____sys_sendmsg+0x419/0x850
[ 861.522882][ T44] ? copy_msghdr_from_user+0x270/0x430
[ 861.523049][ T44] ? get_timestamp.constprop.0+0x3a0/0x3a0
[ 861.523240][ T44] ? move_addr_to_kernel+0x40/0x40
[ 861.523405][ T44] ? stack_depot_save_flags+0x3e1/0x6e0
[ 861.523571][ T44] ? rcu_read_lock_any_held+0x3c/0x90
[ 861.523755][ T44] ___sys_sendmsg+0xfd/0x180
[ 861.523924][ T44] ? __call_rcu_common.constprop.0+0xa6/0xa00
[ 861.524119][ T44] ? copy_msghdr_from_user+0x430/0x430
[ 861.524296][ T44] __sys_sendmsg+0x124/0x1c0
[ 861.524462][ T44] ? __sys_sendmsg_sock+0x20/0x20
[ 861.524626][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 861.524810][ T44] ? __call_rcu_common.constprop.0+0x3bd/0xa00
[ 861.525017][ T44] ? do_raw_spin_unlock+0x59/0x250
[ 861.525183][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.525350][ T44] do_syscall_64+0xbd/0xfc0
[ 861.525516][ T44] entry_SYSCALL_64_after_hwframe+0x4b/0x53
[ 861.525708][ T44] RIP: 0033:0x7f9c62946c5e
[ 861.525899][ T44] RSP: 002b:00007ffe9efa9fc0 EFLAGS: 00000202 ORIG_RAX: 000000000000002e
[ 861.526126][ T44] RAX: ffffffffffffffda RBX: 0000000000000007 RCX: 00007f9c62946c5e
[ 861.526348][ T44] RDX: 0000000000000000 RSI: 00007ffe9efaa080 RDI: 0000000000000003
[ 861.526569][ T44] RBP: 00007ffe9efa9fd0 R08: 0000000000000000 R09: 0000000000000000
[ 861.526806][ T44] R10: 0000000000000000 R11: 0000000000000202 R12: 00007ffe9efaa740
[ 861.527039][ T44] R13: 00000000693458bb R14: 0000000000000000 R15: 0000000000499600
[ 861.527270][ T44]
[ 861.527405][ T44] INFO: task ip:6122 is blocked on a mutex likely owned by task kworker/0:1:10.
[ 861.527637][ T44] task:kworker/0:1 state:D stack:27592 pid:10 tgid:10 ppid:2 task_flags:0x4208060 flags:0x00080000
[ 861.527966][ T44] Workqueue: events refill_work
[ 861.528135][ T44] Call Trace:
[ 861.528271][ T44]
[ 861.528381][ T44] __schedule+0xae2/0x1720
[ 861.528551][ T44] ? usleep_range_state+0xba/0x1b0
[ 861.528730][ T44] ? io_schedule_timeout+0x130/0x130
[ 861.528903][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.529071][ T44] ? usleep_range_state+0xba/0x1b0
[ 861.529240][ T44] schedule+0xd4/0x260
[ 861.529378][ T44] schedule_hrtimeout_range_clock+0x1da/0x2f0
[ 861.529573][ T44] ? schedule_timeout_idle+0xe0/0xe0
[ 861.529757][ T44] ? __hrtimer_cb_get_time+0x50/0x50
[ 861.529934][ T44] ? lockdep_hardirqs_on+0x84/0x130
[ 861.530097][ T44] ? ktime_get+0x15e/0x220
[ 861.530263][ T44] ? kvm_clock_get_cycles+0x18/0x30
[ 861.530433][ T44] ? ktime_get+0xa9/0x220
[ 861.530570][ T44] usleep_range_state+0x133/0x1b0
[ 861.530753][ T44] ? schedule_hrtimeout+0x20/0x20
[ 861.530922][ T44] ? perf_trace_sched_switch+0x6d0/0x6d0
[ 861.531088][ T44] ? refill_work+0x149/0x210
[ 861.531257][ T44] napi_disable_locked+0x379/0x5f0
[ 861.531424][ T44] ? _mutex_trylock_nest_lock+0x340/0x340
[ 861.531595][ T44] ? netdev_bind_sb_channel_queue+0x470/0x470
[ 861.531802][ T44] napi_disable+0x43/0x80
[ 861.531947][ T44] refill_work+0x116/0x210
[ 861.532116][ T44] process_one_work+0xd57/0x1390
[ 861.532286][ T44] ? pwq_dec_nr_in_flight+0x700/0x700
[ 861.532451][ T44] ? lock_acquire.part.0+0xbc/0x260
[ 861.532618][ T44] ? assign_work+0x152/0x380
[ 861.532801][ T44] worker_thread+0x4d6/0xd40
[ 861.532979][ T44] ? process_one_work+0x1390/0x1390
[ 861.533144][ T44] kthread+0x355/0x5b0
[ 861.533283][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.533447][ T44] ? __lock_release.isra.0+0x59/0x170
[ 861.533615][ T44] ? rcu_is_watching+0x15/0xd0
[ 861.533796][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.533974][ T44] ret_from_fork+0x3fb/0x510
[ 861.534145][ T44] ? arch_exit_to_user_mode_prepare.isra.0+0x140/0x140
[ 861.534341][ T44] ? __switch_to+0x602/0xd00
[ 861.534505][ T44] ? kthread_is_per_cpu+0xe0/0xe0
[ 861.534671][ T44] ret_from_fork_asm+0x11/0x20
[ 861.534862][ T44]
[ 861.534999][ T44]
[ 861.534999][ T44] Showing all locks held in the system:
[ 861.535213][ T44] 3 locks held by kworker/0:1/10:
[ 861.535377][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 861.535652][ T44] #1: ffa00000000a7cc0 ((work_completion)(&(&vi->refill)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 861.535964][ T44] #2: ff110000083fac98 (&dev->lock){+.+.}-{4:4}, at: napi_disable+0x3b/0x80
[ 861.536207][ T44] 3 locks held by kworker/u16:0/12:
[ 861.536367][ T44] #0: ff11000008ad6548 ((wq_completion)ipv6_addrconf){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 861.536635][ T44] #1: ffa00000000c7cc0 ((work_completion)(&(&net->ipv6.addr_chk_work)->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 861.536973][ T44] #2: ffffffffad278aa8 (rtnl_mutex){+.+.}-{4:4}, at: addrconf_verify_work+0x12/0x30
[ 861.537223][ T44] 1 lock held by khungtaskd/44:
[ 861.537380][ T44] #0: ffffffffacb70ba0 (rcu_read_lock){....}-{1:3}, at: rcu_lock_acquire.constprop.0+0x7/0x30
[ 861.537649][ T44] 3 locks held by kworker/2:1/53:
[ 861.537825][ T44] #0: ff11000001139b48 ((wq_completion)events){+.+.}-{0:0}, at: process_one_work+0xcb4/0x1390
[ 861.538094][ T44] #1: ffa000000039fcc0 ((work_completion)(&pool->work)){+.+.}-{0:0}, at: process_one_work+0xd16/0x1390
[ 861.538364][ T44] #2: ffffffffad278aa8 (rtnl_mutex){+.+.}-{4:4}, at: xp_release_deferred+0x1b/0x1e0
[ 861.538615][ T44] 2 locks held by ip/6122:
[ 861.538784][ T44] #0: ffffffffad278aa8 (rtnl_mutex){+.+.}-{4:4}, at: rtnl_newlink+0x5f9/0xb40
[ 861.539035][ T44] #1: ff110000083fac98 (&dev->lock){+.+.}-{4:4}, at: napi_enable+0x3b/0x80
[ 861.539281][ T44]
[ 861.539385][ T44] =============================================
[ 861.539385][ T44]