======================================
| 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 (kernel/sched/core.c:5259 kernel/sched/core.c:6863)
[ 861.504306][ T44] ? io_schedule_timeout (kernel/sched/core.c:6719)
[ 861.504491][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.504679][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.504891][ T44] schedule (kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 861.505043][ T44] schedule_preempt_disabled (kernel/sched/core.c:7018)
[ 861.505229][ T44] __mutex_lock (kernel/locking/mutex.c:199 kernel/locking/mutex.c:694 kernel/locking/mutex.c:776)
[ 861.505421][ T44] ? addrconf_verify_work (net/ipv6/addrconf.c:4731 (discriminator 1))
[ 861.505612][ T44] ? ww_mutex_lock (kernel/locking/mutex.c:775)
[ 861.505798][ T44] ? process_one_work (kernel/workqueue.c:3233 (discriminator 1))
[ 861.505976][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.506144][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.506311][ T44] ? addrconf_verify_work (net/ipv6/addrconf.c:4731 (discriminator 1))
[ 861.506477][ T44] addrconf_verify_work (net/ipv6/addrconf.c:4731 (discriminator 1))
[ 861.506645][ T44] process_one_work (kernel/workqueue.c:3262)
[ 861.506837][ T44] ? pwq_dec_nr_in_flight (kernel/workqueue.c:3159)
[ 861.507004][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870)
[ 861.507171][ T44] ? assign_work (kernel/workqueue.c:1200)
[ 861.507340][ T44] worker_thread (kernel/workqueue.c:3334 (discriminator 2) kernel/workqueue.c:3421 (discriminator 2))
[ 861.507510][ T44] ? process_one_work (kernel/workqueue.c:3367)
[ 861.507676][ T44] kthread (kernel/kthread.c:463)
[ 861.507834][ T44] ? kthread_is_per_cpu (kernel/kthread.c:412)
[ 861.507998][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.508164][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.508331][ T44] ? kthread_is_per_cpu (kernel/kthread.c:412)
[ 861.508497][ T44] ret_from_fork (arch/x86/kernel/process.c:164)
[ 861.508663][ T44] ? arch_exit_to_user_mode_prepare.isra.0 (arch/x86/entry/syscall_64.c:37)
[ 861.508874][ T44] ? __switch_to (./include/linux/thread_info.h:140 (discriminator 2) arch/x86/kernel/process.h:16 (discriminator 2) arch/x86/kernel/process_64.c:676 (discriminator 2))
[ 861.509044][ T44] ? kthread_is_per_cpu (kernel/kthread.c:412)
[ 861.509212][ T44] ret_from_fork_asm (arch/x86/entry/entry_64.S:256)
| [ 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 (kernel/sched/core.c:5259 kernel/sched/core.c:6863)
[ 861.511144][ T44] ? io_schedule_timeout (kernel/sched/core.c:6719)
[ 861.511312][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.511477][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.511646][ T44] schedule (kernel/sched/core.c:6946 kernel/sched/core.c:6960)
[ 861.511796][ T44] schedule_preempt_disabled (kernel/sched/core.c:7018)
[ 861.511966][ T44] __mutex_lock (kernel/locking/mutex.c:199 kernel/locking/mutex.c:694 kernel/locking/mutex.c:776)
[ 861.512136][ T44] ? napi_enable (net/core/dev.c:7616)
[ 861.512306][ T44] ? ww_mutex_lock (kernel/locking/mutex.c:775)
[ 861.512471][ T44] ? __asan_memset (mm/kasan/shadow.c:84 (discriminator 2))
[ 861.512640][ T44] ? sg_init_table (lib/scatterlist.c:106)
[ 861.512830][ T44] ? try_fill_recv (drivers/net/virtio_net.c:2865 (discriminator 1))
[ 861.513000][ T44] ? napi_enable (net/core/dev.c:7616)
[ 861.513165][ T44] napi_enable (net/core/dev.c:7616)
[ 861.513305][ T44] __virtnet_rx_resume (./include/linux/bottom_half.h:20 (discriminator 1) drivers/net/virtio_net.c:2894 (discriminator 1) drivers/net/virtio_net.c:2904 (discriminator 1) drivers/net/virtio_net.c:3481 (discriminator 1))
[ 861.513473][ T44] ? queue_delayed_work_on (./arch/x86/include/asm/irqflags.h:26 ./arch/x86/include/asm/irqflags.h:109 ./arch/x86/include/asm/irqflags.h:151 kernel/workqueue.c:2559)
[ 861.513642][ T44] virtnet_rx_resume_all (drivers/net/virtio_net.c:3492 (discriminator 1))
[ 861.513830][ T44] virtnet_xdp_set (drivers/net/virtio_net.c:6140 (discriminator 1))
[ 861.513999][ T44] ? virtnet_xdp_set (drivers/net/virtio_net.c:6167)
[ 861.514166][ T44] dev_xdp_install (net/core/dev.c:10312)
[ 861.514331][ T44] ? alloc_netdev_dummy (net/core/dev.c:10280)
[ 861.514502][ T44] dev_xdp_attach (net/core/dev.c:10468)
[ 861.514691][ T44] ? bpf_xdp_link_update (net/core/dev.c:10358)
[ 861.514874][ T44] ? __bpf_prog_get (kernel/bpf/syscall.c:2622)
[ 861.515044][ T44] dev_change_xdp_fd (net/core/dev.c:10722)
[ 861.515211][ T44] do_setlink.isra.0 (net/core/rtnetlink.c:3392)
[ 861.515378][ T44] ? rtnl_newlink_create (net/core/rtnetlink.c:3036)
[ 861.515545][ T44] ? rcu_lockdep_current_cpu_online (kernel/rcu/tree.c:4028 (discriminator 2) kernel/rcu/tree.c:4020 (discriminator 2))
[ 861.515755][ T44] ? rcu_read_lock_any_held (kernel/rcu/update.c:386 (discriminator 1) kernel/rcu/update.c:380 (discriminator 1))
[ 861.515931][ T44] ? validate_chain (kernel/locking/lockdep.c:3801 (discriminator 4) kernel/locking/lockdep.c:3821 (discriminator 4) kernel/locking/lockdep.c:3876 (discriminator 4))
[ 861.516093][ T44] ? rcu_read_lock_any_held (./include/linux/lockdep.h:249 kernel/rcu/update.c:388 kernel/rcu/update.c:380)
[ 861.516258][ T44] ? stack_depot_save_flags (lib/stackdepot.c:601 (discriminator 4) lib/stackdepot.c:668 (discriminator 4))
[ 861.516424][ T44] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1))
[ 861.516594][ T44] ? perf_trace_sched_switch (kernel/sched/core.c:8785)
[ 861.516769][ T44] ? rtnl_newlink (net/core/rtnetlink.c:343 (discriminator 1) net/core/rtnetlink.c:4071 (discriminator 1))
[ 861.516940][ T44] ? lock_acquire (./include/trace/events/lock.h:24 (discriminator 33) kernel/locking/lockdep.c:5831 (discriminator 33))
[ 861.517107][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.517270][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.517435][ T44] ? trace_contention_end (./include/trace/events/lock.h:122 (discriminator 33))
[ 861.517601][ T44] ? __mutex_lock (kernel/locking/mutex.c:624 (discriminator 3) kernel/locking/mutex.c:776 (discriminator 3))
[ 861.517784][ T44] ? rtnl_newlink (net/core/rtnetlink.c:343 (discriminator 1) net/core/rtnetlink.c:4071 (discriminator 1))
[ 861.517952][ T44] ? ww_mutex_lock (kernel/locking/mutex.c:775)
[ 861.518117][ T44] ? nla_validate_int_range (lib/nlattr.c:396)
[ 861.518283][ T44] ? __rtnl_newlink (net/core/rtnetlink.c:3922)
[ 861.518456][ T44] rtnl_newlink (net/core/rtnetlink.c:351 (discriminator 1) net/core/rtnetlink.c:4073 (discriminator 1))
[ 861.518624][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.518801][ T44] ? __rtnl_newlink (net/core/rtnetlink.c:3963)
[ 861.518973][ T44] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1))
[ 861.519139][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870)
[ 861.519303][ T44] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1))
[ 861.519469][ T44] ? __rtnl_newlink (net/core/rtnetlink.c:3963)
[ 861.519634][ T44] ? __lock_release.isra.0 (kernel/locking/lockdep.c:5536)
[ 861.519824][ T44] ? __rtnl_newlink (net/core/rtnetlink.c:3963)
[ 861.519988][ T44] rtnetlink_rcv_msg (net/core/rtnetlink.c:6958)
[ 861.520155][ T44] ? rtnl_port_fill (net/core/rtnetlink.c:6861)
[ 861.520319][ T44] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1))
[ 861.520484][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870)
[ 861.520649][ T44] ? find_held_lock (kernel/locking/lockdep.c:5350 (discriminator 1))
[ 861.520833][ T44] netlink_rcv_skb (net/netlink/af_netlink.c:2550)
[ 861.521005][ T44] ? rtnl_port_fill (net/core/rtnetlink.c:6861)
[ 861.521171][ T44] ? netlink_ack (net/netlink/af_netlink.c:2527)
[ 861.521339][ T44] ? netlink_deliver_tap (./include/linux/rcupdate.h:341 (discriminator 1) ./include/linux/rcupdate.h:897 (discriminator 1) ./include/net/netns/generic.h:48 (discriminator 1) net/netlink/af_netlink.c:333 (discriminator 1))
[ 861.521501][ T44] ? netlink_deliver_tap (./include/linux/rcupdate.h:341 (discriminator 1) ./include/linux/rcupdate.h:897 (discriminator 1) net/netlink/af_netlink.c:340 (discriminator 1))
[ 861.521667][ T44] netlink_unicast (net/netlink/af_netlink.c:1319 net/netlink/af_netlink.c:1344)
[ 861.521855][ T44] ? netlink_attachskb (net/netlink/af_netlink.c:1329)
[ 861.522020][ T44] ? lock_acquire.part.0 (kernel/locking/lockdep.c:470 kernel/locking/lockdep.c:5870)
[ 861.522191][ T44] ? __lock_acquire (kernel/locking/lockdep.c:5237 (discriminator 1))
[ 861.522359][ T44] netlink_sendmsg (net/netlink/af_netlink.c:1894)
[ 861.522526][ T44] ? netlink_unicast (net/netlink/af_netlink.c:1813)
[ 861.522695][ T44] ____sys_sendmsg (net/socket.c:718 (discriminator 4) net/socket.c:733 (discriminator 4) net/socket.c:2608 (discriminator 4))
[ 861.522882][ T44] ? copy_msghdr_from_user (net/socket.c:2548)
[ 861.523049][ T44] ? get_timestamp.constprop.0 (net/socket.c:2554)
[ 861.523240][ T44] ? move_addr_to_kernel (net/socket.c:2534)
[ 861.523405][ T44] ? stack_depot_save_flags (lib/stackdepot.c:601 (discriminator 4) lib/stackdepot.c:668 (discriminator 4))
[ 861.523571][ T44] ? rcu_read_lock_any_held (kernel/rcu/update.c:386 (discriminator 1) kernel/rcu/update.c:380 (discriminator 1))
[ 861.523755][ T44] ___sys_sendmsg (net/socket.c:2664)
[ 861.523924][ T44] ? __call_rcu_common.constprop.0 (./arch/x86/include/asm/irqflags.h:26 ./arch/x86/include/asm/irqflags.h:109 ./arch/x86/include/asm/irqflags.h:127 kernel/rcu/tree.c:3121)
[ 861.524119][ T44] ? copy_msghdr_from_user (net/socket.c:2651)
[ 861.524296][ T44] __sys_sendmsg (net/socket.c:2694 (discriminator 1))
[ 861.524462][ T44] ? __sys_sendmsg_sock (net/socket.c:2679)
[ 861.524626][ T44] ? lockdep_hardirqs_on (kernel/locking/lockdep.c:4473)
[ 861.524810][ T44] ? __call_rcu_common.constprop.0 (./arch/x86/include/asm/irqflags.h:26 ./arch/x86/include/asm/irqflags.h:109 ./arch/x86/include/asm/irqflags.h:151 kernel/rcu/tree.c:3144)
[ 861.525017][ T44] ? do_raw_spin_unlock (./arch/x86/include/asm/atomic.h:23 ./include/linux/atomic/atomic-arch-fallback.h:457 ./include/linux/atomic/atomic-instrumented.h:33 ./include/asm-generic/qspinlock.h:57 kernel/locking/spinlock_debug.c:101 kernel/locking/spinlock_debug.c:141)
[ 861.525183][ T44] ? rcu_is_watching (./include/linux/context_tracking.h:128 (discriminator 1) kernel/rcu/tree.c:751 (discriminator 1))
[ 861.525350][ T44] do_syscall_64 (arch/x86/entry/syscall_64.c:63 (discriminator 1) arch/x86/entry/syscall_64.c:94 (discriminator 1))
[ 861.525516][ T44] entry_SYSCALL_64_after_hwframe (arch/x86/entry/entry_64.S:131)
[ 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
Finger prints:
__schedule:schedule:schedule_preempt_disabled:__mutex_lock:napi_enable
__schedule:schedule:schedule_preempt_disabled:__mutex_lock:addrconf_verify_work