[ 9.939505][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 9.939534][ C1] [ 9.939535][ C1] ======================================================== [ 9.939536][ C1] WARNING: possible irq lock inversion dependency detected [ 9.939538][ C1] 7.2.0-virtme #1 Tainted: G W [ 9.939539][ C1] -------------------------------------------------------- [ 9.939540][ C1] connect-deny_ip/169 just changed the state of lock: [ 9.939541][ C1] ffffffff9b499660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.939555][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 9.939556][ C1] (fs_reclaim){+.+.}-{0:0} [ 9.939557][ C1] [ 9.939557][ C1] [ 9.939557][ C1] and interrupts could create inverse lock ordering between them. [ 9.939557][ C1] [ 9.939558][ C1] [ 9.939558][ C1] other info that might help us debug this: [ 9.939559][ C1] Possible interrupt unsafe locking scenario: [ 9.939559][ C1] [ 9.939560][ C1] CPU0 CPU1 [ 9.939560][ C1] ---- ---- [ 9.939561][ C1] lock(fs_reclaim); [ 9.939562][ C1] local_irq_disable(); [ 9.939562][ C1] lock(console_owner); [ 9.939563][ C1] lock(fs_reclaim); [ 9.939564][ C1] [ 9.939564][ C1] lock(console_owner); [ 9.939565][ C1] [ 9.939565][ C1] *** DEADLOCK *** [ 9.939565][ C1] [ 9.939566][ C1] locks held by connect-deny_ip/169: 9, last CPU#1: [ 9.939567][ C1] #0: ff1100000f999d90 (sk_lock-AF_INET){+.+.}-{0:0}, at: inet_stream_connect+0x44/0xb0 [ 9.939573][ C1] #1: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 9.939578][ C1] #2: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 9.939581][ C1] #3: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 9.939584][ C1] #4: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 9.939589][ C1] #5: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: ip_local_deliver_finish+0x2ba/0x610 [ 9.939592][ C1] #6: ffffffff9b590b00 (rcu_read_lock){....}-{1:3}, at: tcp_rcv_listen_state_process+0x108/0x380 [ 9.939597][ C1] #7: ffffffff9b579cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.939601][ C1] #8: ffffffff9b579d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.939604][ C1] [ 9.939604][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 9.939609][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 9.939611][ C1] HARDIRQ-ON-W at: [ 9.939613][ C1] __lock_acquire+0x388/0xc20 [ 9.939616][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939617][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.939620][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.939623][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.939626][ C1] workqueue_init+0x2a/0x680 [ 9.939630][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.939632][ C1] kernel_init+0x21/0x150 [ 9.939635][ C1] ret_from_fork+0x474/0x6b0 [ 9.939638][ C1] ret_from_fork_asm+0x11/0x20 [ 9.939641][ C1] SOFTIRQ-ON-W at: [ 9.939642][ C1] __lock_acquire+0x388/0xc20 [ 9.939643][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939645][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.939646][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.939647][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.939648][ C1] workqueue_init+0x2a/0x680 [ 9.939649][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.939651][ C1] kernel_init+0x21/0x150 [ 9.939652][ C1] ret_from_fork+0x474/0x6b0 [ 9.939653][ C1] ret_from_fork_asm+0x11/0x20 [ 9.939655][ C1] INITIAL USE at: [ 9.939655][ C1] __lock_acquire+0x388/0xc20 [ 9.939657][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939658][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.939659][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.939660][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.939662][ C1] workqueue_init+0x2a/0x680 [ 9.939663][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.939664][ C1] kernel_init+0x21/0x150 [ 9.939666][ C1] ret_from_fork+0x474/0x6b0 [ 9.939667][ C1] ret_from_fork_asm+0x11/0x20 [ 9.939668][ C1] } [ 9.939668][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 9.939672][ C1] ... acquired at: [ 9.939673][ C1] __lock_acquire+0x518/0xc20 [ 9.939674][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939675][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.939677][ C1] __kmalloc_noprof+0xd3/0x760 [ 9.939678][ C1] alloc_buf.isra.0+0x4b/0x260 [ 9.939681][ C1] put_chars+0x1e1/0x2f0 [ 9.939683][ C1] hvc_console_print+0x292/0x780 [ 9.939685][ C1] console_emit_next_record+0x22f/0x4c0 [ 9.939687][ C1] console_flush_one_record+0x46f/0x710 [ 9.939688][ C1] console_unlock+0xee/0x1f0 [ 9.939690][ C1] vprintk_emit+0x37c/0x3e0 [ 9.939691][ C1] _printk+0xc7/0x100 [ 9.939694][ C1] print_ipi_mode+0x2e/0x40 [ 9.939696][ C1] do_one_initcall+0x124/0x4f0 [ 9.939698][ C1] kernel_init_freeable+0x596/0x630 [ 9.939700][ C1] kernel_init+0x21/0x150 [ 9.939701][ C1] ret_from_fork+0x474/0x6b0 [ 9.939702][ C1] ret_from_fork_asm+0x11/0x20 [ 9.939703][ C1] [ 9.939703][ C1] -> (console_owner){..-.}-{0:0} { [ 9.939705][ C1] IN-SOFTIRQ-W at: [ 9.939706][ C1] __lock_acquire+0x388/0xc20 [ 9.939707][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939708][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.939710][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.939712][ C1] console_flush_one_record+0x46f/0x710 [ 9.939713][ C1] console_unlock+0xee/0x1f0 [ 9.939715][ C1] vprintk_emit+0x37c/0x3e0 [ 9.939716][ C1] _printk+0xc7/0x100 [ 9.939718][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.939720][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.939723][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.939724][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.939726][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.939728][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.939729][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.939731][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.939732][ C1] ip_local_deliver+0x184/0x4c0 [ 9.939734][ C1] ip_rcv+0xdc/0x3d0 [ 9.939735][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.939737][ C1] process_backlog+0x431/0x14c0 [ 9.939738][ C1] __napi_poll+0xa7/0x3b0 [ 9.939740][ C1] net_rx_action+0x513/0xf50 [ 9.939742][ C1] handle_softirqs+0x1d3/0x900 [ 9.939745][ C1] do_softirq+0xac/0xe0 [ 9.939746][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.939747][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.939749][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.939750][ C1] ip_output+0x1ca/0x660 [ 9.939752][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.939753][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.939754][ C1] tcp_connect+0x1024/0x1e20 [ 9.939755][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.939757][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.939758][ C1] inet_stream_connect+0x59/0xb0 [ 9.939759][ C1] __sys_connect+0x1cf/0x2e0 [ 9.939762][ C1] __x64_sys_connect+0x72/0xd0 [ 9.939763][ C1] do_syscall_64+0xff/0x530 [ 9.939766][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.939767][ C1] INITIAL USE at: [ 9.939768][ C1] } [ 9.939769][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 9.939772][ C1] ... acquired at: [ 9.939773][ C1] mark_lock+0x1d7/0xa00 [ 9.939774][ C1] mark_usage+0x42/0x170 [ 9.939775][ C1] __lock_acquire+0x388/0xc20 [ 9.939776][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939778][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.939779][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.939781][ C1] console_flush_one_record+0x46f/0x710 [ 9.939782][ C1] console_unlock+0xee/0x1f0 [ 9.939784][ C1] vprintk_emit+0x37c/0x3e0 [ 9.939785][ C1] _printk+0xc7/0x100 [ 9.939787][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.939788][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.939790][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.939791][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.939793][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.939795][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.939796][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.939797][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.939798][ C1] ip_local_deliver+0x184/0x4c0 [ 9.939800][ C1] ip_rcv+0xdc/0x3d0 [ 9.939801][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.939803][ C1] process_backlog+0x431/0x14c0 [ 9.939804][ C1] __napi_poll+0xa7/0x3b0 [ 9.939806][ C1] net_rx_action+0x513/0xf50 [ 9.939808][ C1] handle_softirqs+0x1d3/0x900 [ 9.939809][ C1] do_softirq+0xac/0xe0 [ 9.939810][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.939811][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.939813][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.939814][ C1] ip_output+0x1ca/0x660 [ 9.939815][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.939817][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.939818][ C1] tcp_connect+0x1024/0x1e20 [ 9.939819][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.939820][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.939821][ C1] inet_stream_connect+0x59/0xb0 [ 9.939822][ C1] __sys_connect+0x1cf/0x2e0 [ 9.939824][ C1] __x64_sys_connect+0x72/0xd0 [ 9.939825][ C1] do_syscall_64+0xff/0x530 [ 9.939827][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.939828][ C1] [ 9.939828][ C1] [ 9.939828][ C1] stack backtrace: [ 9.939831][ C1] CPU: 1 UID: 0 PID: 169 Comm: connect-deny_ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.939835][ C1] Tainted: [W]=WARN [ 9.939836][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.939837][ C1] Call Trace: [ 9.939838][ C1] [ 9.939840][ C1] dump_stack_lvl+0x6f/0xa0 [ 9.939844][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 9.939846][ C1] mark_lock_irq+0x989/0x9c0 [ 9.939849][ C1] mark_lock+0x1d7/0xa00 [ 9.939851][ C1] mark_usage+0x42/0x170 [ 9.939852][ C1] __lock_acquire+0x388/0xc20 [ 9.939855][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.939857][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 9.939859][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.939862][ C1] ? lock_acquire+0x13c/0x160 [ 9.939864][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.939866][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 9.939868][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.939870][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 9.939872][ C1] ? console_flush_one_record+0x106/0x710 [ 9.939874][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.939875][ C1] ? lock_acquire+0x13c/0x160 [ 9.939877][ C1] console_flush_one_record+0x46f/0x710 [ 9.939880][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 9.939881][ C1] ? __lock_acquire+0x518/0xc20 [ 9.939884][ C1] console_unlock+0xee/0x1f0 [ 9.939886][ C1] ? console_flush_one_record+0x710/0x710 [ 9.939887][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.939889][ C1] ? lock_acquire+0x60/0x160 [ 9.939891][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.939893][ C1] ? vprintk_emit+0x320/0x3e0 [ 9.939895][ C1] vprintk_emit+0x37c/0x3e0 [ 9.939897][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 9.939899][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.939901][ C1] _printk+0xc7/0x100 [ 9.939903][ C1] ? snapshot_read.cold+0x21/0x21 [ 9.939905][ C1] ? ___ratelimit+0x4e7/0xa00 [ 9.939908][ C1] ? idr_get_free+0x830/0x830 [ 9.939910][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.939912][ C1] ? tcp_send_synack+0x1570/0x1570 [ 9.939914][ C1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 9.939916][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.939918][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.939920][ C1] ? add_timer_on+0x580/0x580 [ 9.939924][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.939926][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 9.939928][ C1] ? tcp_fastopen_add_skb+0xc0/0xc0 [ 9.939931][ C1] ? secure_tcpv6_seq_and_ts_off+0x300/0x300 [ 9.939934][ C1] ? inet_csk_route_req+0x747/0xc30 [ 9.939936][ C1] ? inet_csk_reqsk_queue_hash_add+0x4c/0x60 [ 9.939937][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.939939][ C1] ? tcp_ao_calc_traffic_key+0x2a0/0x2a0 [ 9.939950][ C1] ? tcp_get_syncookie_mss+0x300/0x300 [ 9.939952][ C1] ? __lock_acquire+0x518/0xc20 [ 9.939954][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.939956][ C1] ? __lock_acquire+0x518/0xc20 [ 9.939957][ C1] ? down_read_nested+0xe7/0x500 [ 9.939959][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.939962][ C1] ? tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.939964][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.939966][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.939968][ C1] ? tcp_v4_fill_cb+0x33/0x430 [ 9.939969][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.939972][ C1] ? mark_usage+0x61/0x170 [ 9.939973][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 9.939976][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.939977][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 9.939979][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.939981][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.939983][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.939985][ C1] ip_local_deliver+0x184/0x4c0 [ 9.939986][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 9.939988][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 9.939990][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.939992][ C1] ip_rcv+0xdc/0x3d0 [ 9.939994][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 9.939995][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.939997][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 9.940000][ C1] ? mark_usage+0x61/0x170 [ 9.940001][ C1] ? __lock_acquire+0x518/0xc20 [ 9.940003][ C1] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 9.940005][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.940007][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940009][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.940011][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 9.940013][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940015][ C1] process_backlog+0x431/0x14c0 [ 9.940018][ C1] __napi_poll+0xa7/0x3b0 [ 9.940020][ C1] net_rx_action+0x513/0xf50 [ 9.940023][ C1] ? __napi_poll+0x3b0/0x3b0 [ 9.940025][ C1] ? find_held_lock+0x2b/0x80 [ 9.940027][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940029][ C1] ? mark_held_locks+0x40/0x70 [ 9.940031][ C1] handle_softirqs+0x1d3/0x900 [ 9.940033][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.940034][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940036][ C1] ? trace_csd_function_exit+0xb3/0x180 [ 9.940041][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 9.940043][ C1] do_softirq+0xac/0xe0 [ 9.940045][ C1] [ 9.940045][ C1] [ 9.940046][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.940047][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.940049][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.940052][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 9.940054][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940056][ C1] ? find_held_lock+0x2b/0x80 [ 9.940057][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.940059][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940060][ C1] ? mark_held_locks+0x40/0x70 [ 9.940062][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.940063][ C1] ? neigh_hh_output+0x152/0x4c0 [ 9.940066][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.940067][ C1] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 9.940069][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940071][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 9.940073][ C1] ip_output+0x1ca/0x660 [ 9.940075][ C1] ? ip_finish_output+0x460/0x460 [ 9.940077][ C1] ? find_held_lock+0x2b/0x80 [ 9.940078][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.940080][ C1] ? ip4_dst_hoplimit+0x15b/0x320 [ 9.940082][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.940084][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.940087][ C1] ? __tcp_select_window+0xf20/0xf20 [ 9.940089][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940090][ C1] ? ktime_get+0x18e/0x2d0 [ 9.940092][ C1] ? kvm_clock_get_cycles+0x19/0x30 [ 9.940095][ C1] ? ktime_get+0x1dd/0x2d0 [ 9.940097][ C1] tcp_connect+0x1024/0x1e20 [ 9.940099][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.940102][ C1] ? tcp_v4_ao_sign_reset+0x8c0/0x8c0 [ 9.940103][ C1] ? do_raw_spin_lock+0x131/0x280 [ 9.940105][ C1] ? find_held_lock+0x2b/0x80 [ 9.940107][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.940108][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940110][ C1] inet_stream_connect+0x59/0xb0 [ 9.940111][ C1] __sys_connect+0x1cf/0x2e0 [ 9.940113][ C1] ? do_fcntl+0x36f/0xc50 [ 9.940116][ C1] ? __sys_connect_file+0x250/0x250 [ 9.940119][ C1] ? fput+0x1f/0xa0 [ 9.940121][ C1] __x64_sys_connect+0x72/0xd0 [ 9.940123][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940124][ C1] ? do_syscall_64+0xa6/0x530 [ 9.940126][ C1] do_syscall_64+0xff/0x530 [ 9.940127][ C1] ? exc_page_fault+0xee/0x100 [ 9.940129][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.940130][ C1] RIP: 0033:0x7f9a48676312 [ 9.940133][ C1] Code: 08 0f 85 71 41 ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 bf 01 00 [ 9.940135][ C1] RSP: 002b:00007f9a47df3988 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 9.940138][ C1] RAX: ffffffffffffffda RBX: 000000000000000a RCX: 00007f9a48676312 [ 9.940139][ C1] RDX: 0000000000000010 RSI: 00007f9a47df3c40 RDI: 000000000000000a [ 9.940140][ C1] RBP: 00007f9a47df39b0 R08: 0000000000000000 R09: 0000000000000000 [ 9.940141][ C1] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f9a47df3c40 [ 9.940142][ C1] R13: 0000000000000010 R14: 0000000000000001 R15: 0000000000412230 [ 9.940144][ C1] [ 9.940148][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.940149][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 169, name: connect-deny_ip [ 9.940150][ C1] preempt_count: 302, expected: 0 [ 9.940151][ C1] RCU nest depth: 6, expected: 0 [ 9.940152][ C1] INFO: lockdep is turned off. [ 9.940152][ C1] irq event stamp: 18093 [ 9.940153][ C1] hardirqs last enabled at (18092): [] __down_trylock_console_sem+0x86/0xa0 [ 9.940155][ C1] hardirqs last disabled at (18093): [] console_emit_next_record+0x3d4/0x4c0 [ 9.940157][ C1] softirqs last enabled at (17930): [] __dev_queue_xmit+0x964/0x1b80 [ 9.940159][ C1] softirqs last disabled at (17931): [] do_softirq+0xac/0xe0 [ 9.940160][ C1] Preemption disabled at: [ 9.940161][ C1] [] __dev_queue_xmit+0x204/0x1b80 [ 9.940164][ C1] CPU: 1 UID: 0 PID: 169 Comm: connect-deny_ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.940166][ C1] Tainted: [W]=WARN [ 9.940166][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.940167][ C1] Call Trace: [ 9.940168][ C1] [ 9.940168][ C1] dump_stack_lvl+0x6f/0xa0 [ 9.940170][ C1] ? __dev_queue_xmit+0x204/0x1b80 [ 9.940172][ C1] __might_resched.cold+0x1fe/0x2c1 [ 9.940175][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.940178][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940180][ C1] __kmalloc_noprof+0x443/0x760 [ 9.940182][ C1] ? __rwlock_init+0x150/0x150 [ 9.940183][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 9.940186][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.940187][ C1] alloc_buf.isra.0+0x4b/0x260 [ 9.940189][ C1] put_chars+0x1e1/0x2f0 [ 9.940191][ C1] ? __send_to_port+0x420/0x420 [ 9.940194][ C1] hvc_console_print+0x292/0x780 [ 9.940196][ C1] ? __lock_acquire+0x388/0xc20 [ 9.940198][ C1] ? hvc_write+0x3a0/0x3a0 [ 9.940199][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940201][ C1] ? lock_acquire+0x13c/0x160 [ 9.940203][ C1] console_emit_next_record+0x22f/0x4c0 [ 9.940205][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 9.940207][ C1] ? console_flush_one_record+0x106/0x710 [ 9.940209][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940211][ C1] ? lock_acquire+0x13c/0x160 [ 9.940213][ C1] console_flush_one_record+0x46f/0x710 [ 9.940215][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 9.940216][ C1] ? __lock_acquire+0x518/0xc20 [ 9.940219][ C1] console_unlock+0xee/0x1f0 [ 9.940221][ C1] ? console_flush_one_record+0x710/0x710 [ 9.940222][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940224][ C1] ? lock_acquire+0x60/0x160 [ 9.940226][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.940227][ C1] ? vprintk_emit+0x320/0x3e0 [ 9.940229][ C1] vprintk_emit+0x37c/0x3e0 [ 9.940231][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 9.940233][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.940235][ C1] _printk+0xc7/0x100 [ 9.940237][ C1] ? snapshot_read.cold+0x21/0x21 [ 9.940239][ C1] ? ___ratelimit+0x4e7/0xa00 [ 9.940241][ C1] ? idr_get_free+0x830/0x830 [ 9.940243][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.940245][ C1] ? tcp_send_synack+0x1570/0x1570 [ 9.940246][ C1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 9.940248][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.940250][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.940252][ C1] ? add_timer_on+0x580/0x580 [ 9.940254][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.940256][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 9.940258][ C1] ? tcp_fastopen_add_skb+0xc0/0xc0 [ 9.940261][ C1] ? secure_tcpv6_seq_and_ts_off+0x300/0x300 [ 9.940262][ C1] ? inet_csk_route_req+0x747/0xc30 [ 9.940264][ C1] ? inet_csk_reqsk_queue_hash_add+0x4c/0x60 [ 9.940265][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.940267][ C1] ? tcp_ao_calc_traffic_key+0x2a0/0x2a0 [ 9.940270][ C1] ? tcp_get_syncookie_mss+0x300/0x300 [ 9.940272][ C1] ? __lock_acquire+0x518/0xc20 [ 9.940274][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940276][ C1] ? __lock_acquire+0x518/0xc20 [ 9.940277][ C1] ? down_read_nested+0xe7/0x500 [ 9.940279][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940282][ C1] ? tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.940284][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.940286][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.940287][ C1] ? tcp_v4_fill_cb+0x33/0x430 [ 9.940289][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.940292][ C1] ? mark_usage+0x61/0x170 [ 9.940293][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 9.940296][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940297][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 9.940299][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.940301][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.940303][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.940305][ C1] ip_local_deliver+0x184/0x4c0 [ 9.940306][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 9.940308][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 9.940310][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.940312][ C1] ip_rcv+0xdc/0x3d0 [ 9.940314][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 9.940315][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940317][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 9.940319][ C1] ? mark_usage+0x61/0x170 [ 9.940320][ C1] ? __lock_acquire+0x518/0xc20 [ 9.940321][ C1] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 9.940323][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.940325][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940327][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.940329][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 9.940331][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940333][ C1] process_backlog+0x431/0x14c0 [ 9.940336][ C1] __napi_poll+0xa7/0x3b0 [ 9.940338][ C1] net_rx_action+0x513/0xf50 [ 9.940340][ C1] ? __napi_poll+0x3b0/0x3b0 [ 9.940343][ C1] ? find_held_lock+0x2b/0x80 [ 9.940346][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940348][ C1] ? mark_held_locks+0x40/0x70 [ 9.940350][ C1] handle_softirqs+0x1d3/0x900 [ 9.940352][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.940354][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940355][ C1] ? trace_csd_function_exit+0xb3/0x180 [ 9.940357][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 9.940359][ C1] do_softirq+0xac/0xe0 [ 9.940360][ C1] [ 9.940361][ C1] [ 9.940361][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.940363][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.940365][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.940367][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 9.940369][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940370][ C1] ? find_held_lock+0x2b/0x80 [ 9.940372][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.940373][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.940375][ C1] ? mark_held_locks+0x40/0x70 [ 9.940376][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.940378][ C1] ? neigh_hh_output+0x152/0x4c0 [ 9.940380][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.940382][ C1] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 9.940383][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.940385][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 9.940387][ C1] ip_output+0x1ca/0x660 [ 9.940389][ C1] ? ip_finish_output+0x460/0x460 [ 9.940391][ C1] ? find_held_lock+0x2b/0x80 [ 9.940392][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.940394][ C1] ? ip4_dst_hoplimit+0x15b/0x320 [ 9.940396][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.940399][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.940401][ C1] ? __tcp_select_window+0xf20/0xf20 [ 9.940403][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940404][ C1] ? ktime_get+0x18e/0x2d0 [ 9.940406][ C1] ? kvm_clock_get_cycles+0x19/0x30 [ 9.940407][ C1] ? ktime_get+0x1dd/0x2d0 [ 9.940409][ C1] tcp_connect+0x1024/0x1e20 [ 9.940411][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.940414][ C1] ? tcp_v4_ao_sign_reset+0x8c0/0x8c0 [ 9.940415][ C1] ? do_raw_spin_lock+0x131/0x280 [ 9.940417][ C1] ? find_held_lock+0x2b/0x80 [ 9.940419][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.940420][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940422][ C1] inet_stream_connect+0x59/0xb0 [ 9.940423][ C1] __sys_connect+0x1cf/0x2e0 [ 9.940425][ C1] ? do_fcntl+0x36f/0xc50 [ 9.940427][ C1] ? __sys_connect_file+0x250/0x250 [ 9.940430][ C1] ? fput+0x1f/0xa0 [ 9.940431][ C1] __x64_sys_connect+0x72/0xd0 [ 9.940433][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.940434][ C1] ? do_syscall_64+0xa6/0x530 [ 9.940436][ C1] do_syscall_64+0xff/0x530 [ 9.940437][ C1] ? exc_page_fault+0xee/0x100 [ 9.940439][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.940440][ C1] RIP: 0033:0x7f9a48676312 [ 9.940442][ C1] Code: 08 0f 85 71 41 ff ff 49 89 fb 48 89 f0 48 89 d7 48 89 ce 4c 89 c2 4d 89 ca 4c 8b 44 24 08 4c 8b 4c 24 10 4c 89 5c 24 08 0f 05 66 2e 0f 1f 84 00 00 00 00 00 0f 1f 00 f3 0f 1e fa 55 bf 01 00 [ 9.940443][ C1] RSP: 002b:00007f9a47df3988 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 9.940444][ C1] RAX: ffffffffffffffda RBX: 000000000000000a RCX: 00007f9a48676312 [ 9.940445][ C1] RDX: 0000000000000010 RSI: 00007f9a47df3c40 RDI: 000000000000000a [ 9.940445][ C1] RBP: 00007f9a47df39b0 R08: 0000000000000000 R09: 0000000000000000 [ 9.940446][ C1] R10: 0000000000000000 R11: 0000000000000246 R12: 00007f9a47df3c40 [ 9.940447][ C1] R13: 0000000000000010 R14: 0000000000000001 R15: 0000000000412230 [ 9.940449][ C1] [ 11.000179][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 11.000198][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.000200][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 11.000202][ C1] preempt_count: 102, expected: 0 [ 11.000203][ C1] RCU nest depth: 0, expected: 0 [ 11.000204][ C1] INFO: lockdep is turned off. [ 11.000205][ C1] irq event stamp: 291720 [ 11.000206][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 11.000214][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 11.000217][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 11.000221][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 11.000223][ C1] Preemption disabled at: [ 11.000224][ C1] [<0000000000000000>] 0x0 [ 11.000231][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.000235][ C1] Tainted: [W]=WARN [ 11.000236][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.000238][ C1] Call Trace: [ 11.000239][ C1] [ 11.000241][ C1] dump_stack_lvl+0x6f/0xa0 [ 11.000247][ C1] __might_resched.cold+0x1fe/0x2c1 [ 11.000250][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.000254][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000258][ C1] __kmalloc_noprof+0x443/0x760 [ 11.000262][ C1] ? __rwlock_init+0x150/0x150 [ 11.000264][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 11.000268][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 11.000270][ C1] alloc_buf.isra.0+0x4b/0x260 [ 11.000272][ C1] put_chars+0x1e1/0x2f0 [ 11.000275][ C1] ? __send_to_port+0x420/0x420 [ 11.000276][ C1] ? printk_get_next_message+0x240/0x650 [ 11.000280][ C1] hvc_console_print+0x292/0x780 [ 11.000284][ C1] ? hvc_write+0x3a0/0x3a0 [ 11.000286][ C1] ? __rwlock_init+0x150/0x150 [ 11.000287][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000289][ C1] ? lock_acquire+0x13c/0x160 [ 11.000291][ C1] console_emit_next_record+0x22f/0x4c0 [ 11.000294][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 11.000296][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000298][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000299][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000301][ C1] ? lock_acquire+0x13c/0x160 [ 11.000303][ C1] ? console_flush_one_record+0x111/0x710 [ 11.000305][ C1] console_flush_one_record+0x46f/0x710 [ 11.000307][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 11.000310][ C1] console_unlock+0xee/0x1f0 [ 11.000311][ C1] ? lock_acquire+0x13c/0x160 [ 11.000313][ C1] ? console_flush_one_record+0x710/0x710 [ 11.000315][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000316][ C1] ? lock_release+0x184/0x1f0 [ 11.000318][ C1] ? lock_acquire+0x60/0x160 [ 11.000319][ C1] ? __rcu_read_lock+0x30/0x70 [ 11.000321][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.000323][ C1] ? vprintk_emit+0x320/0x3e0 [ 11.000325][ C1] vprintk_emit+0x37c/0x3e0 [ 11.000328][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 11.000330][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 11.000331][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000333][ C1] ? lock_acquire+0x13c/0x160 [ 11.000335][ C1] _printk+0xc7/0x100 [ 11.000338][ C1] ? snapshot_read.cold+0x21/0x21 [ 11.000339][ C1] ? ___ratelimit+0x4e7/0xa00 [ 11.000342][ C1] ? trace_hardirqs_on+0x36/0x40 [ 11.000345][ C1] ? idr_get_free+0x830/0x830 [ 11.000348][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 11.000350][ C1] tcp_make_synack.cold+0x36/0xb6 [ 11.000354][ C1] ? tcp_send_synack+0x1570/0x1570 [ 11.000357][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000360][ C1] ? inet_csk_route_req+0x747/0xc30 [ 11.000362][ C1] tcp_v4_send_synack+0x103/0xb20 [ 11.000365][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 11.000366][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 11.000369][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.000371][ C1] ? trace_hardirqs_on+0x36/0x40 [ 11.000372][ C1] ? get_random_u16+0x740/0x740 [ 11.000374][ C1] tcp_rtx_synack+0x150/0x440 [ 11.000376][ C1] ? tcp_send_probe0+0x590/0x590 [ 11.000379][ C1] ? sched_balance_rq+0x324/0x1e30 [ 11.000380][ C1] ? do_raw_spin_lock+0x131/0x280 [ 11.000382][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 11.000384][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.000386][ C1] ? lock_acquire+0x6a/0x160 [ 11.000387][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 11.000389][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.000391][ C1] call_timer_fn+0x160/0x4d0 [ 11.000394][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 11.000395][ C1] ? debug_object_active_state+0x430/0x430 [ 11.000399][ C1] ? __rwlock_init+0x150/0x150 [ 11.000401][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 11.000403][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000405][ C1] __run_timers+0x68f/0xaa0 [ 11.000407][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.000409][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.000411][ C1] ? do_raw_spin_lock+0x131/0x280 [ 11.000413][ C1] ? __rwlock_init+0x150/0x150 [ 11.000416][ C1] run_timer_softirq+0xf0/0x160 [ 11.000418][ C1] ? __run_timers+0xaa0/0xaa0 [ 11.000419][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.000421][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000423][ C1] handle_softirqs+0x1d3/0x900 [ 11.000425][ C1] ? _local_bh_enable+0xc0/0xc0 [ 11.000426][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000428][ C1] ? lock_release+0x184/0x1f0 [ 11.000430][ C1] __irq_exit_rcu+0x145/0x1c0 [ 11.000431][ C1] irq_exit_rcu+0xe/0x30 [ 11.000433][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.000435][ C1] [ 11.000435][ C1] [ 11.000436][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 11.000438][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.000440][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 11.000442][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 11.000444][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 11.000448][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 11.000449][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 11.000450][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 11.000451][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 11.000452][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 11.000453][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 11.000455][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.000457][ C1] default_idle+0x9/0x10 [ 11.000459][ C1] default_idle_call+0x6e/0xb0 [ 11.000460][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.000462][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 11.000464][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 11.000466][ C1] ? default_idle_call+0x98/0xb0 [ 11.000467][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.000470][ C1] do_idle+0xd8/0x190 [ 11.000471][ C1] cpu_startup_entry+0x53/0x70 [ 11.000473][ C1] start_secondary+0x204/0x2b0 [ 11.000474][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 11.000476][ C1] common_startup_64+0x13e/0x148 [ 11.000480][ C1] [ 11.064236][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 12.089156][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 12.089175][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.089177][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 12.089179][ C1] preempt_count: 102, expected: 0 [ 12.089180][ C1] RCU nest depth: 0, expected: 0 [ 12.089181][ C1] INFO: lockdep is turned off. [ 12.089181][ C1] irq event stamp: 291720 [ 12.089182][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 12.089191][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 12.089193][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 12.089198][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 12.089200][ C1] Preemption disabled at: [ 12.089201][ C1] [<0000000000000000>] 0x0 [ 12.089208][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.089211][ C1] Tainted: [W]=WARN [ 12.089212][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.089214][ C1] Call Trace: [ 12.089216][ C1] [ 12.089218][ C1] dump_stack_lvl+0x6f/0xa0 [ 12.089224][ C1] __might_resched.cold+0x1fe/0x2c1 [ 12.089228][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.089231][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089235][ C1] __kmalloc_noprof+0x443/0x760 [ 12.089239][ C1] ? __rwlock_init+0x150/0x150 [ 12.089242][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 12.089245][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 12.089247][ C1] alloc_buf.isra.0+0x4b/0x260 [ 12.089250][ C1] put_chars+0x1e1/0x2f0 [ 12.089252][ C1] ? __send_to_port+0x420/0x420 [ 12.089254][ C1] ? printk_get_next_message+0x240/0x650 [ 12.089257][ C1] hvc_console_print+0x292/0x780 [ 12.089261][ C1] ? hvc_write+0x3a0/0x3a0 [ 12.089263][ C1] ? __rwlock_init+0x150/0x150 [ 12.089264][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089266][ C1] ? lock_acquire+0x13c/0x160 [ 12.089268][ C1] console_emit_next_record+0x22f/0x4c0 [ 12.089271][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 12.089273][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089275][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089276][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089278][ C1] ? lock_acquire+0x13c/0x160 [ 12.089280][ C1] ? console_flush_one_record+0x111/0x710 [ 12.089282][ C1] console_flush_one_record+0x46f/0x710 [ 12.089284][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 12.089287][ C1] console_unlock+0xee/0x1f0 [ 12.089289][ C1] ? lock_acquire+0x13c/0x160 [ 12.089290][ C1] ? console_flush_one_record+0x710/0x710 [ 12.089292][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089294][ C1] ? lock_release+0x184/0x1f0 [ 12.089295][ C1] ? lock_acquire+0x60/0x160 [ 12.089297][ C1] ? __rcu_read_lock+0x30/0x70 [ 12.089299][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.089301][ C1] ? vprintk_emit+0x320/0x3e0 [ 12.089303][ C1] vprintk_emit+0x37c/0x3e0 [ 12.089306][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 12.089307][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 12.089309][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089311][ C1] ? lock_acquire+0x13c/0x160 [ 12.089313][ C1] _printk+0xc7/0x100 [ 12.089316][ C1] ? snapshot_read.cold+0x21/0x21 [ 12.089318][ C1] ? ___ratelimit+0x4e7/0xa00 [ 12.089321][ C1] ? trace_hardirqs_on+0x36/0x40 [ 12.089324][ C1] ? idr_get_free+0x830/0x830 [ 12.089326][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 12.089328][ C1] tcp_make_synack.cold+0x36/0xb6 [ 12.089332][ C1] ? tcp_send_synack+0x1570/0x1570 [ 12.089335][ C1] ? lock_release+0x184/0x1f0 [ 12.089337][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089339][ C1] ? inet_csk_route_req+0x747/0xc30 [ 12.089342][ C1] tcp_v4_send_synack+0x103/0xb20 [ 12.089345][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 12.089347][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 12.089350][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.089352][ C1] ? trace_hardirqs_on+0x36/0x40 [ 12.089353][ C1] ? get_random_u16+0x740/0x740 [ 12.089355][ C1] tcp_rtx_synack+0x150/0x440 [ 12.089357][ C1] ? tcp_send_probe0+0x590/0x590 [ 12.089360][ C1] ? sched_balance_rq+0x324/0x1e30 [ 12.089362][ C1] ? do_raw_spin_lock+0x131/0x280 [ 12.089363][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 12.089365][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.089367][ C1] ? lock_acquire+0x6a/0x160 [ 12.089368][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 12.089370][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.089372][ C1] call_timer_fn+0x160/0x4d0 [ 12.089375][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 12.089376][ C1] ? debug_object_active_state+0x430/0x430 [ 12.089381][ C1] ? __rwlock_init+0x150/0x150 [ 12.089383][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 12.089384][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089387][ C1] __run_timers+0x68f/0xaa0 [ 12.089389][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.089391][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.089393][ C1] ? do_raw_spin_lock+0x131/0x280 [ 12.089396][ C1] ? __rwlock_init+0x150/0x150 [ 12.089398][ C1] run_timer_softirq+0xf0/0x160 [ 12.089400][ C1] ? __run_timers+0xaa0/0xaa0 [ 12.089402][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.089403][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089405][ C1] handle_softirqs+0x1d3/0x900 [ 12.089407][ C1] ? _local_bh_enable+0xc0/0xc0 [ 12.089409][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089410][ C1] ? lock_release+0x184/0x1f0 [ 12.089412][ C1] __irq_exit_rcu+0x145/0x1c0 [ 12.089414][ C1] irq_exit_rcu+0xe/0x30 [ 12.089416][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.089418][ C1] [ 12.089418][ C1] [ 12.089419][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 12.089421][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.089424][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.089426][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 12.089428][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 12.089431][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 12.089433][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 12.089433][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 12.089434][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 12.089435][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 12.089437][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 12.089438][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.089441][ C1] default_idle+0x9/0x10 [ 12.089442][ C1] default_idle_call+0x6e/0xb0 [ 12.089444][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.089445][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 12.089447][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 12.089450][ C1] ? default_idle_call+0x98/0xb0 [ 12.089451][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.089453][ C1] do_idle+0xd8/0x190 [ 12.089455][ C1] cpu_startup_entry+0x53/0x70 [ 12.089456][ C1] start_secondary+0x204/0x2b0 [ 12.089458][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 12.089460][ C1] common_startup_64+0x13e/0x148 [ 12.089465][ C1] [ 12.109750][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 13.113146][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 13.113164][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 13.113167][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 13.113168][ C1] preempt_count: 102, expected: 0 [ 13.113169][ C1] RCU nest depth: 0, expected: 0 [ 13.113170][ C1] INFO: lockdep is turned off. [ 13.113171][ C1] irq event stamp: 291720 [ 13.113172][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 13.113180][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 13.113183][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 13.113187][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 13.113189][ C1] Preemption disabled at: [ 13.113190][ C1] [<0000000000000000>] 0x0 [ 13.113197][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 13.113201][ C1] Tainted: [W]=WARN [ 13.113202][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 13.113204][ C1] Call Trace: [ 13.113205][ C1] [ 13.113208][ C1] dump_stack_lvl+0x6f/0xa0 [ 13.113213][ C1] __might_resched.cold+0x1fe/0x2c1 [ 13.113217][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 13.113220][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113224][ C1] __kmalloc_noprof+0x443/0x760 [ 13.113228][ C1] ? __rwlock_init+0x150/0x150 [ 13.113231][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 13.113234][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 13.113236][ C1] alloc_buf.isra.0+0x4b/0x260 [ 13.113238][ C1] put_chars+0x1e1/0x2f0 [ 13.113241][ C1] ? __send_to_port+0x420/0x420 [ 13.113242][ C1] ? printk_get_next_message+0x240/0x650 [ 13.113246][ C1] hvc_console_print+0x292/0x780 [ 13.113250][ C1] ? hvc_write+0x3a0/0x3a0 [ 13.113251][ C1] ? __rwlock_init+0x150/0x150 [ 13.113253][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113255][ C1] ? lock_acquire+0x13c/0x160 [ 13.113257][ C1] console_emit_next_record+0x22f/0x4c0 [ 13.113260][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 13.113262][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113264][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113265][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113267][ C1] ? lock_acquire+0x13c/0x160 [ 13.113269][ C1] ? console_flush_one_record+0x111/0x710 [ 13.113271][ C1] console_flush_one_record+0x46f/0x710 [ 13.113273][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 13.113276][ C1] console_unlock+0xee/0x1f0 [ 13.113277][ C1] ? lock_acquire+0x13c/0x160 [ 13.113279][ C1] ? console_flush_one_record+0x710/0x710 [ 13.113281][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113282][ C1] ? lock_release+0x184/0x1f0 [ 13.113284][ C1] ? lock_acquire+0x60/0x160 [ 13.113286][ C1] ? __rcu_read_lock+0x30/0x70 [ 13.113288][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 13.113290][ C1] ? vprintk_emit+0x320/0x3e0 [ 13.113292][ C1] vprintk_emit+0x37c/0x3e0 [ 13.113294][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 13.113296][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 13.113298][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113300][ C1] ? lock_acquire+0x13c/0x160 [ 13.113301][ C1] _printk+0xc7/0x100 [ 13.113305][ C1] ? snapshot_read.cold+0x21/0x21 [ 13.113306][ C1] ? ___ratelimit+0x4e7/0xa00 [ 13.113309][ C1] ? trace_hardirqs_on+0x36/0x40 [ 13.113312][ C1] ? idr_get_free+0x830/0x830 [ 13.113313][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 13.113315][ C1] tcp_make_synack.cold+0x36/0xb6 [ 13.113319][ C1] ? tcp_send_synack+0x1570/0x1570 [ 13.113323][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113325][ C1] ? inet_csk_route_req+0x747/0xc30 [ 13.113328][ C1] tcp_v4_send_synack+0x103/0xb20 [ 13.113330][ C1] ? enqueue_task_fair+0x44a/0xa50 [ 13.113333][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 13.113335][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.113336][ C1] ? trace_hardirqs_on+0x36/0x40 [ 13.113337][ C1] ? lock_release+0x184/0x1f0 [ 13.113339][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 13.113341][ C1] ? get_random_u16+0x740/0x740 [ 13.113343][ C1] tcp_rtx_synack+0x150/0x440 [ 13.113345][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113347][ C1] ? tcp_send_probe0+0x590/0x590 [ 13.113349][ C1] ? lock_release+0x184/0x1f0 [ 13.113351][ C1] ? __queue_work+0x3ab/0xd30 [ 13.113354][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 13.113356][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 13.113357][ C1] ? lock_acquire+0x6a/0x160 [ 13.113359][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 13.113361][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 13.113362][ C1] call_timer_fn+0x160/0x4d0 [ 13.113365][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 13.113367][ C1] ? debug_object_active_state+0x430/0x430 [ 13.113371][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113374][ C1] __run_timers+0x68f/0xaa0 [ 13.113375][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 13.113377][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 13.113380][ C1] ? do_raw_spin_lock+0x131/0x280 [ 13.113382][ C1] ? __rwlock_init+0x150/0x150 [ 13.113384][ C1] run_timer_softirq+0xf0/0x160 [ 13.113386][ C1] ? __run_timers+0xaa0/0xaa0 [ 13.113388][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 13.113389][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113391][ C1] handle_softirqs+0x1d3/0x900 [ 13.113393][ C1] ? _local_bh_enable+0xc0/0xc0 [ 13.113395][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113397][ C1] ? lock_release+0x184/0x1f0 [ 13.113398][ C1] __irq_exit_rcu+0x145/0x1c0 [ 13.113400][ C1] irq_exit_rcu+0xe/0x30 [ 13.113402][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 13.113404][ C1] [ 13.113404][ C1] [ 13.113405][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 13.113407][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 13.113409][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 13.113411][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 13.113413][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 13.113416][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 13.113418][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 13.113418][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 13.113419][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 13.113420][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 13.113421][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 13.113423][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.113426][ C1] default_idle+0x9/0x10 [ 13.113427][ C1] default_idle_call+0x6e/0xb0 [ 13.113428][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 13.113430][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 13.113432][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 13.113434][ C1] ? default_idle_call+0x98/0xb0 [ 13.113436][ C1] ? rcu_is_watching+0x16/0xd0 [ 13.113438][ C1] do_idle+0xd8/0x190 [ 13.113439][ C1] cpu_startup_entry+0x53/0x70 [ 13.113441][ C1] start_secondary+0x204/0x2b0 [ 13.113442][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 13.113444][ C1] common_startup_64+0x13e/0x148 [ 13.113449][ C1] [ 13.142343][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.201155][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.201171][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.201174][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 14.201175][ C1] preempt_count: 102, expected: 0 [ 14.201177][ C1] RCU nest depth: 0, expected: 0 [ 14.201178][ C1] INFO: lockdep is turned off. [ 14.201179][ C1] irq event stamp: 291720 [ 14.201179][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 14.201188][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 14.201191][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 14.201195][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 14.201198][ C1] Preemption disabled at: [ 14.201198][ C1] [<0000000000000000>] 0x0 [ 14.201205][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.201209][ C1] Tainted: [W]=WARN [ 14.201210][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.201212][ C1] Call Trace: [ 14.201213][ C1] [ 14.201216][ C1] dump_stack_lvl+0x6f/0xa0 [ 14.201221][ C1] __might_resched.cold+0x1fe/0x2c1 [ 14.201225][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.201229][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201233][ C1] __kmalloc_noprof+0x443/0x760 [ 14.201236][ C1] ? __rwlock_init+0x150/0x150 [ 14.201239][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 14.201243][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 14.201245][ C1] alloc_buf.isra.0+0x4b/0x260 [ 14.201247][ C1] put_chars+0x1e1/0x2f0 [ 14.201249][ C1] ? __send_to_port+0x420/0x420 [ 14.201251][ C1] ? printk_get_next_message+0x240/0x650 [ 14.201255][ C1] hvc_console_print+0x292/0x780 [ 14.201259][ C1] ? hvc_write+0x3a0/0x3a0 [ 14.201261][ C1] ? __rwlock_init+0x150/0x150 [ 14.201262][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201264][ C1] ? lock_acquire+0x13c/0x160 [ 14.201267][ C1] console_emit_next_record+0x22f/0x4c0 [ 14.201270][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 14.201272][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201274][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201275][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201277][ C1] ? lock_acquire+0x13c/0x160 [ 14.201279][ C1] ? console_flush_one_record+0x111/0x710 [ 14.201281][ C1] console_flush_one_record+0x46f/0x710 [ 14.201283][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 14.201286][ C1] console_unlock+0xee/0x1f0 [ 14.201288][ C1] ? lock_acquire+0x13c/0x160 [ 14.201290][ C1] ? console_flush_one_record+0x710/0x710 [ 14.201291][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201293][ C1] ? lock_release+0x184/0x1f0 [ 14.201295][ C1] ? lock_acquire+0x60/0x160 [ 14.201296][ C1] ? __rcu_read_lock+0x30/0x70 [ 14.201298][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.201300][ C1] ? vprintk_emit+0x320/0x3e0 [ 14.201302][ C1] vprintk_emit+0x37c/0x3e0 [ 14.201305][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 14.201307][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 14.201309][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201310][ C1] ? lock_acquire+0x13c/0x160 [ 14.201312][ C1] _printk+0xc7/0x100 [ 14.201316][ C1] ? snapshot_read.cold+0x21/0x21 [ 14.201318][ C1] ? ___ratelimit+0x4e7/0xa00 [ 14.201320][ C1] ? trace_hardirqs_on+0x36/0x40 [ 14.201323][ C1] ? idr_get_free+0x830/0x830 [ 14.201325][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 14.201327][ C1] tcp_make_synack.cold+0x36/0xb6 [ 14.201331][ C1] ? tcp_send_synack+0x1570/0x1570 [ 14.201334][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201337][ C1] ? inet_csk_route_req+0x747/0xc30 [ 14.201340][ C1] tcp_v4_send_synack+0x103/0xb20 [ 14.201342][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 14.201344][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.201346][ C1] ? trace_hardirqs_on+0x36/0x40 [ 14.201348][ C1] ? get_random_u16+0x740/0x740 [ 14.201350][ C1] tcp_rtx_synack+0x150/0x440 [ 14.201352][ C1] ? tcp_send_probe0+0x590/0x590 [ 14.201354][ C1] ? sched_balance_rq+0x324/0x1e30 [ 14.201357][ C1] ? do_raw_spin_lock+0x131/0x280 [ 14.201359][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 14.201361][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.201362][ C1] ? lock_acquire+0x6a/0x160 [ 14.201364][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 14.201366][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.201367][ C1] call_timer_fn+0x160/0x4d0 [ 14.201370][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 14.201372][ C1] ? debug_object_active_state+0x430/0x430 [ 14.201376][ C1] ? __rwlock_init+0x150/0x150 [ 14.201378][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 14.201380][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201382][ C1] __run_timers+0x68f/0xaa0 [ 14.201384][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.201386][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 14.201388][ C1] ? do_raw_spin_lock+0x131/0x280 [ 14.201391][ C1] ? __rwlock_init+0x150/0x150 [ 14.201393][ C1] run_timer_softirq+0xf0/0x160 [ 14.201395][ C1] ? __run_timers+0xaa0/0xaa0 [ 14.201397][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.201398][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201400][ C1] handle_softirqs+0x1d3/0x900 [ 14.201403][ C1] ? _local_bh_enable+0xc0/0xc0 [ 14.201404][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201406][ C1] ? lock_release+0x184/0x1f0 [ 14.201408][ C1] __irq_exit_rcu+0x145/0x1c0 [ 14.201409][ C1] irq_exit_rcu+0xe/0x30 [ 14.201411][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 14.201413][ C1] [ 14.201414][ C1] [ 14.201414][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 14.201416][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.201418][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 14.201421][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 14.201423][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 14.201426][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 14.201427][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 14.201428][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 14.201429][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 14.201430][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 14.201431][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 14.201433][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 14.201436][ C1] default_idle+0x9/0x10 [ 14.201437][ C1] default_idle_call+0x6e/0xb0 [ 14.201439][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 14.201440][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 14.201442][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 14.201444][ C1] ? default_idle_call+0x98/0xb0 [ 14.201446][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.201448][ C1] do_idle+0xd8/0x190 [ 14.201450][ C1] cpu_startup_entry+0x53/0x70 [ 14.201451][ C1] start_secondary+0x204/0x2b0 [ 14.201453][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 14.201455][ C1] common_startup_64+0x13e/0x148 [ 14.201460][ C1] [ 14.221798][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.224114][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.224133][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.224135][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 15.224137][ C1] preempt_count: 102, expected: 0 [ 15.224138][ C1] RCU nest depth: 0, expected: 0 [ 15.224139][ C1] INFO: lockdep is turned off. [ 15.224140][ C1] irq event stamp: 291720 [ 15.224141][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 15.224150][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 15.224153][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 15.224157][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 15.224160][ C1] Preemption disabled at: [ 15.224161][ C1] [<0000000000000000>] 0x0 [ 15.224168][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.224171][ C1] Tainted: [W]=WARN [ 15.224172][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.224174][ C1] Call Trace: [ 15.224176][ C1] [ 15.224178][ C1] dump_stack_lvl+0x6f/0xa0 [ 15.224184][ C1] __might_resched.cold+0x1fe/0x2c1 [ 15.224188][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.224191][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224195][ C1] __kmalloc_noprof+0x443/0x760 [ 15.224199][ C1] ? __rwlock_init+0x150/0x150 [ 15.224202][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 15.224206][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 15.224208][ C1] alloc_buf.isra.0+0x4b/0x260 [ 15.224210][ C1] put_chars+0x1e1/0x2f0 [ 15.224213][ C1] ? __send_to_port+0x420/0x420 [ 15.224214][ C1] ? printk_get_next_message+0x240/0x650 [ 15.224218][ C1] hvc_console_print+0x292/0x780 [ 15.224221][ C1] ? hvc_write+0x3a0/0x3a0 [ 15.224223][ C1] ? __rwlock_init+0x150/0x150 [ 15.224225][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224227][ C1] ? lock_acquire+0x13c/0x160 [ 15.224229][ C1] console_emit_next_record+0x22f/0x4c0 [ 15.224232][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 15.224234][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224235][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224237][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224239][ C1] ? lock_acquire+0x13c/0x160 [ 15.224241][ C1] ? console_flush_one_record+0x111/0x710 [ 15.224243][ C1] console_flush_one_record+0x46f/0x710 [ 15.224245][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 15.224248][ C1] console_unlock+0xee/0x1f0 [ 15.224249][ C1] ? lock_acquire+0x13c/0x160 [ 15.224251][ C1] ? console_flush_one_record+0x710/0x710 [ 15.224253][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224254][ C1] ? lock_release+0x184/0x1f0 [ 15.224256][ C1] ? lock_acquire+0x60/0x160 [ 15.224257][ C1] ? __rcu_read_lock+0x30/0x70 [ 15.224259][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.224261][ C1] ? vprintk_emit+0x320/0x3e0 [ 15.224263][ C1] vprintk_emit+0x37c/0x3e0 [ 15.224266][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 15.224268][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 15.224269][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224271][ C1] ? lock_acquire+0x13c/0x160 [ 15.224273][ C1] _printk+0xc7/0x100 [ 15.224276][ C1] ? snapshot_read.cold+0x21/0x21 [ 15.224278][ C1] ? ___ratelimit+0x4e7/0xa00 [ 15.224281][ C1] ? idr_get_free+0x830/0x830 [ 15.224283][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 15.224286][ C1] tcp_make_synack.cold+0x36/0xb6 [ 15.224290][ C1] ? tcp_send_synack+0x1570/0x1570 [ 15.224293][ C1] ? lock_release+0x184/0x1f0 [ 15.224295][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224297][ C1] ? inet_csk_route_req+0x747/0xc30 [ 15.224300][ C1] tcp_v4_send_synack+0x103/0xb20 [ 15.224302][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 15.224304][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 15.224307][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.224309][ C1] ? trace_hardirqs_on+0x36/0x40 [ 15.224310][ C1] ? get_random_u16+0x740/0x740 [ 15.224312][ C1] tcp_rtx_synack+0x150/0x440 [ 15.224314][ C1] ? trace_pelt_cfs_tp+0x9b/0x190 [ 15.224316][ C1] ? tcp_send_probe0+0x590/0x590 [ 15.224317][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 15.224320][ C1] ? update_cfs_rq_load_avg+0x4b/0x660 [ 15.224322][ C1] ? common_startup_64+0x13e/0x148 [ 15.224325][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 15.224327][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.224329][ C1] ? lock_acquire+0x6a/0x160 [ 15.224330][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 15.224332][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.224333][ C1] call_timer_fn+0x160/0x4d0 [ 15.224336][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 15.224338][ C1] ? debug_object_active_state+0x430/0x430 [ 15.224342][ C1] ? trace_sched_wakeup+0x9b/0x160 [ 15.224345][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 15.224346][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224349][ C1] __run_timers+0x68f/0xaa0 [ 15.224351][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.224353][ C1] ? debug_object_deactivate+0x2f7/0x440 [ 15.224354][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 15.224357][ C1] ? do_raw_spin_lock+0x131/0x280 [ 15.224359][ C1] ? __rwlock_init+0x150/0x150 [ 15.224361][ C1] run_timer_softirq+0xf0/0x160 [ 15.224363][ C1] ? __run_timers+0xaa0/0xaa0 [ 15.224365][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.224366][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224368][ C1] handle_softirqs+0x1d3/0x900 [ 15.224370][ C1] ? _local_bh_enable+0xc0/0xc0 [ 15.224372][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224374][ C1] ? lock_release+0x184/0x1f0 [ 15.224376][ C1] __irq_exit_rcu+0x145/0x1c0 [ 15.224377][ C1] irq_exit_rcu+0xe/0x30 [ 15.224379][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 15.224381][ C1] [ 15.224382][ C1] [ 15.224382][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 15.224384][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 15.224386][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 15.224389][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 15.224391][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 15.224395][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 15.224396][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 15.224397][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 15.224398][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 15.224399][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 15.224400][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 15.224402][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.224404][ C1] default_idle+0x9/0x10 [ 15.224405][ C1] default_idle_call+0x6e/0xb0 [ 15.224407][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.224409][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 15.224410][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 15.224413][ C1] ? default_idle_call+0x98/0xb0 [ 15.224414][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.224416][ C1] do_idle+0xd8/0x190 [ 15.224418][ C1] cpu_startup_entry+0x53/0x70 [ 15.224419][ C1] start_secondary+0x204/0x2b0 [ 15.224421][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 15.224423][ C1] common_startup_64+0x13e/0x148 [ 15.224426][ C1] [ 15.248802][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.313169][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.313186][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.313189][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 16.313190][ C1] preempt_count: 102, expected: 0 [ 16.313191][ C1] RCU nest depth: 0, expected: 0 [ 16.313192][ C1] INFO: lockdep is turned off. [ 16.313193][ C1] irq event stamp: 291720 [ 16.313194][ C1] hardirqs last enabled at (291719): [] default_idle_call+0x98/0xb0 [ 16.313203][ C1] hardirqs last disabled at (291720): [] __schedule+0x1246/0x20e0 [ 16.313206][ C1] softirqs last enabled at (291628): [] handle_softirqs+0x67c/0x900 [ 16.313210][ C1] softirqs last disabled at (290799): [] __irq_exit_rcu+0x145/0x1c0 [ 16.313212][ C1] Preemption disabled at: [ 16.313213][ C1] [<0000000000000000>] 0x0 [ 16.313220][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.313224][ C1] Tainted: [W]=WARN [ 16.313225][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.313227][ C1] Call Trace: [ 16.313229][ C1] [ 16.313231][ C1] dump_stack_lvl+0x6f/0xa0 [ 16.313237][ C1] __might_resched.cold+0x1fe/0x2c1 [ 16.313241][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.313245][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313249][ C1] __kmalloc_noprof+0x443/0x760 [ 16.313252][ C1] ? __rwlock_init+0x150/0x150 [ 16.313255][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 16.313259][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 16.313261][ C1] alloc_buf.isra.0+0x4b/0x260 [ 16.313263][ C1] put_chars+0x1e1/0x2f0 [ 16.313266][ C1] ? __send_to_port+0x420/0x420 [ 16.313268][ C1] ? printk_get_next_message+0x240/0x650 [ 16.313271][ C1] hvc_console_print+0x292/0x780 [ 16.313275][ C1] ? hvc_write+0x3a0/0x3a0 [ 16.313277][ C1] ? __rwlock_init+0x150/0x150 [ 16.313278][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313280][ C1] ? lock_acquire+0x13c/0x160 [ 16.313283][ C1] console_emit_next_record+0x22f/0x4c0 [ 16.313285][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 16.313288][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313289][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313291][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313293][ C1] ? lock_acquire+0x13c/0x160 [ 16.313295][ C1] ? console_flush_one_record+0x111/0x710 [ 16.313297][ C1] console_flush_one_record+0x46f/0x710 [ 16.313299][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 16.313302][ C1] console_unlock+0xee/0x1f0 [ 16.313304][ C1] ? lock_acquire+0x13c/0x160 [ 16.313306][ C1] ? console_flush_one_record+0x710/0x710 [ 16.313307][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313309][ C1] ? lock_release+0x184/0x1f0 [ 16.313311][ C1] ? lock_acquire+0x60/0x160 [ 16.313312][ C1] ? __rcu_read_lock+0x30/0x70 [ 16.313315][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.313317][ C1] ? vprintk_emit+0x320/0x3e0 [ 16.313319][ C1] vprintk_emit+0x37c/0x3e0 [ 16.313321][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 16.313323][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 16.313325][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313327][ C1] ? lock_acquire+0x13c/0x160 [ 16.313329][ C1] _printk+0xc7/0x100 [ 16.313332][ C1] ? snapshot_read.cold+0x21/0x21 [ 16.313333][ C1] ? ___ratelimit+0x4e7/0xa00 [ 16.313336][ C1] ? trace_hardirqs_on+0x36/0x40 [ 16.313339][ C1] ? idr_get_free+0x830/0x830 [ 16.313341][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 16.313343][ C1] tcp_make_synack.cold+0x36/0xb6 [ 16.313347][ C1] ? tcp_send_synack+0x1570/0x1570 [ 16.313351][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313353][ C1] ? inet_csk_route_req+0x747/0xc30 [ 16.313356][ C1] tcp_v4_send_synack+0x103/0xb20 [ 16.313358][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 16.313360][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 16.313363][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.313365][ C1] ? trace_hardirqs_on+0x36/0x40 [ 16.313366][ C1] ? get_random_u16+0x740/0x740 [ 16.313368][ C1] tcp_rtx_synack+0x150/0x440 [ 16.313370][ C1] ? trace_pelt_cfs_tp+0x9b/0x190 [ 16.313373][ C1] ? tcp_send_probe0+0x590/0x590 [ 16.313374][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 16.313377][ C1] ? update_cfs_rq_load_avg+0x4b/0x660 [ 16.313379][ C1] ? do_raw_spin_lock+0x131/0x280 [ 16.313381][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 16.313383][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.313384][ C1] ? lock_acquire+0x6a/0x160 [ 16.313386][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 16.313387][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.313389][ C1] call_timer_fn+0x160/0x4d0 [ 16.313392][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 16.313394][ C1] ? debug_object_active_state+0x430/0x430 [ 16.313398][ C1] ? trace_sched_wakeup+0x9b/0x160 [ 16.313400][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 16.313402][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313405][ C1] __run_timers+0x68f/0xaa0 [ 16.313407][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.313408][ C1] ? debug_object_deactivate+0x2f7/0x440 [ 16.313411][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 16.313413][ C1] ? do_raw_spin_lock+0x131/0x280 [ 16.313415][ C1] ? __rwlock_init+0x150/0x150 [ 16.313418][ C1] run_timer_softirq+0xf0/0x160 [ 16.313420][ C1] ? __run_timers+0xaa0/0xaa0 [ 16.313422][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.313423][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313425][ C1] handle_softirqs+0x1d3/0x900 [ 16.313427][ C1] ? _local_bh_enable+0xc0/0xc0 [ 16.313429][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313431][ C1] ? lock_release+0x184/0x1f0 [ 16.313433][ C1] __irq_exit_rcu+0x145/0x1c0 [ 16.313434][ C1] irq_exit_rcu+0xe/0x30 [ 16.313436][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.313438][ C1] [ 16.313439][ C1] [ 16.313439][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 16.313441][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.313444][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 16.313446][ C1] Code: 48 8b 3d 94 12 06 02 e8 1f 00 00 00 48 2b 05 58 13 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 b6 17 00 fb f4 0f 1f 40 d6 48 83 ec 20 8b 17 49 89 f8 83 e2 fe 41 89 d2 0f 01 [ 16.313448][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 16.313452][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 16.313453][ C1] RDX: 0000000000000001 RSI: ffffffff9aa8dc60 RDI: ffffffff980fa307 [ 16.313454][ C1] RBP: 0000000000000000 R08: ffffffff9a71e421 R09: 1fe2200005cd63a2 [ 16.313455][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 16.313455][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 16.313457][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 16.313459][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.313461][ C1] default_idle+0x9/0x10 [ 16.313462][ C1] default_idle_call+0x6e/0xb0 [ 16.313464][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.313465][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 16.313467][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 16.313469][ C1] ? default_idle_call+0x98/0xb0 [ 16.313471][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.313473][ C1] do_idle+0xd8/0x190 [ 16.313475][ C1] cpu_startup_entry+0x53/0x70 [ 16.313477][ C1] start_secondary+0x204/0x2b0 [ 16.313478][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 16.313480][ C1] common_startup_64+0x13e/0x148 [ 16.313485][ C1]