[ 9.813402][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 9.813428][ C1] [ 9.813429][ C1] ======================================================== [ 9.813430][ C1] WARNING: possible irq lock inversion dependency detected [ 9.813432][ C1] 7.2.0-virtme #1 Tainted: G W [ 9.813434][ C1] -------------------------------------------------------- [ 9.813434][ C1] connect-deny_ip/170 just changed the state of lock: [ 9.813435][ C1] ffffffff96299660 (console_owner){..-.}-{0:0}, at: console_lock_spinning_enable+0x40/0x60 [ 9.813447][ C1] but this lock took another, SOFTIRQ-unsafe lock in the past: [ 9.813449][ C1] (fs_reclaim){+.+.}-{0:0} [ 9.813450][ C1] [ 9.813450][ C1] [ 9.813450][ C1] and interrupts could create inverse lock ordering between them. [ 9.813450][ C1] [ 9.813451][ C1] [ 9.813451][ C1] other info that might help us debug this: [ 9.813451][ C1] Possible interrupt unsafe locking scenario: [ 9.813451][ C1] [ 9.813452][ C1] CPU0 CPU1 [ 9.813452][ C1] ---- ---- [ 9.813453][ C1] lock(fs_reclaim); [ 9.813454][ C1] local_irq_disable(); [ 9.813454][ C1] lock(console_owner); [ 9.813455][ C1] lock(fs_reclaim); [ 9.813456][ C1] [ 9.813456][ C1] lock(console_owner); [ 9.813457][ C1] [ 9.813457][ C1] *** DEADLOCK *** [ 9.813457][ C1] [ 9.813458][ C1] locks held by connect-deny_ip/170: 9, last CPU#1: [ 9.813459][ C1] #0: ff1100000fdb2b10 (sk_lock-AF_INET){+.+.}-{0:0}, at: inet_stream_connect+0x44/0xb0 [ 9.813464][ C1] #1: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: __ip_queue_xmit+0x63/0x1650 [ 9.813469][ C1] #2: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: ip_output+0xa9/0x660 [ 9.813472][ C1] #3: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: ip_finish_output2+0x25c/0x1bc0 [ 9.813475][ C1] #4: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: process_backlog+0x3f1/0x14c0 [ 9.813479][ C1] #5: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: ip_local_deliver_finish+0x2ba/0x610 [ 9.813482][ C1] #6: ffffffff96390b00 (rcu_read_lock){....}-{1:3}, at: tcp_rcv_listen_state_process+0x108/0x380 [ 9.813486][ C1] #7: ffffffff96379cc0 (console_lock){+.+.}-{0:0}, at: vprintk_emit+0x320/0x3e0 [ 9.813490][ C1] #8: ffffffff96379d38 (console_srcu){....}-{0:0}, at: console_flush_one_record+0x106/0x710 [ 9.813493][ C1] [ 9.813493][ C1] the shortest dependencies between 2nd lock and 1st lock: [ 9.813498][ C1] -> (fs_reclaim){+.+.}-{0:0} { [ 9.813500][ C1] HARDIRQ-ON-W at: [ 9.813501][ C1] __lock_acquire+0x388/0xc20 [ 9.813504][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813506][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.813517][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.813519][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.813521][ C1] workqueue_init+0x2a/0x680 [ 9.813525][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.813527][ C1] kernel_init+0x21/0x150 [ 9.813530][ C1] ret_from_fork+0x474/0x6b0 [ 9.813534][ C1] ret_from_fork_asm+0x11/0x20 [ 9.813536][ C1] SOFTIRQ-ON-W at: [ 9.813537][ C1] __lock_acquire+0x388/0xc20 [ 9.813539][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813540][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.813541][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.813542][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.813544][ C1] workqueue_init+0x2a/0x680 [ 9.813545][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.813546][ C1] kernel_init+0x21/0x150 [ 9.813547][ C1] ret_from_fork+0x474/0x6b0 [ 9.813548][ C1] ret_from_fork_asm+0x11/0x20 [ 9.813550][ C1] INITIAL USE at: [ 9.813550][ C1] __lock_acquire+0x388/0xc20 [ 9.813552][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813553][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.813554][ C1] __kmalloc_cache_noprof+0x6e/0x620 [ 9.813555][ C1] kthread_create_worker_on_node+0xea/0x210 [ 9.813557][ C1] workqueue_init+0x2a/0x680 [ 9.813558][ C1] kernel_init_freeable+0x2fe/0x630 [ 9.813559][ C1] kernel_init+0x21/0x150 [ 9.813561][ C1] ret_from_fork+0x474/0x6b0 [ 9.813562][ C1] ret_from_fork_asm+0x11/0x20 [ 9.813563][ C1] } [ 9.813563][ C1] ... key at: [] __fs_reclaim_map+0x0/0xbe0 [ 9.813567][ C1] ... acquired at: [ 9.813568][ C1] __lock_acquire+0x518/0xc20 [ 9.813569][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813570][ C1] fs_reclaim_acquire+0xd5/0x120 [ 9.813571][ C1] __kmalloc_noprof+0xd3/0x760 [ 9.813573][ C1] alloc_buf.isra.0+0x4b/0x260 [ 9.813576][ C1] put_chars+0x1e1/0x2f0 [ 9.813578][ C1] hvc_console_print+0x292/0x780 [ 9.813580][ C1] console_emit_next_record+0x22f/0x4c0 [ 9.813582][ C1] console_flush_one_record+0x46f/0x710 [ 9.813583][ C1] console_unlock+0xee/0x1f0 [ 9.813585][ C1] vprintk_emit+0x37c/0x3e0 [ 9.813586][ C1] _printk+0xc7/0x100 [ 9.813589][ C1] seg6_init+0x48/0x50 [ 9.813591][ C1] inet6_init+0x2c9/0x3c0 [ 9.813594][ C1] do_one_initcall+0x124/0x4f0 [ 9.813595][ C1] kernel_init_freeable+0x596/0x630 [ 9.813597][ C1] kernel_init+0x21/0x150 [ 9.813598][ C1] ret_from_fork+0x474/0x6b0 [ 9.813599][ C1] ret_from_fork_asm+0x11/0x20 [ 9.813600][ C1] [ 9.813601][ C1] -> (console_owner){..-.}-{0:0} { [ 9.813603][ C1] IN-SOFTIRQ-W at: [ 9.813603][ C1] __lock_acquire+0x388/0xc20 [ 9.813605][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813606][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.813607][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.813609][ C1] console_flush_one_record+0x46f/0x710 [ 9.813610][ C1] console_unlock+0xee/0x1f0 [ 9.813612][ C1] vprintk_emit+0x37c/0x3e0 [ 9.813613][ C1] _printk+0xc7/0x100 [ 9.813615][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.813617][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.813620][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.813622][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.813623][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.813625][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.813626][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.813628][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.813629][ C1] ip_local_deliver+0x184/0x4c0 [ 9.813630][ C1] ip_rcv+0xdc/0x3d0 [ 9.813632][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.813634][ C1] process_backlog+0x431/0x14c0 [ 9.813635][ C1] __napi_poll+0xa7/0x3b0 [ 9.813637][ C1] net_rx_action+0x513/0xf50 [ 9.813638][ C1] handle_softirqs+0x1d3/0x900 [ 9.813641][ C1] do_softirq+0xac/0xe0 [ 9.813642][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.813643][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.813645][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.813646][ C1] ip_output+0x1ca/0x660 [ 9.813648][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.813649][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.813651][ C1] tcp_connect+0x1024/0x1e20 [ 9.813652][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.813653][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.813654][ C1] inet_stream_connect+0x59/0xb0 [ 9.813655][ C1] __sys_connect+0x1cf/0x2e0 [ 9.813658][ C1] __x64_sys_connect+0x72/0xd0 [ 9.813660][ C1] do_syscall_64+0xff/0x530 [ 9.813662][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.813663][ C1] INITIAL USE at: [ 9.813664][ C1] } [ 9.813665][ C1] ... key at: [] console_owner_dep_map+0x0/0x60 [ 9.813668][ C1] ... acquired at: [ 9.813669][ C1] mark_lock+0x1d7/0xa00 [ 9.813670][ C1] mark_usage+0x42/0x170 [ 9.813671][ C1] __lock_acquire+0x388/0xc20 [ 9.813672][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813674][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.813675][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.813677][ C1] console_flush_one_record+0x46f/0x710 [ 9.813678][ C1] console_unlock+0xee/0x1f0 [ 9.813680][ C1] vprintk_emit+0x37c/0x3e0 [ 9.813681][ C1] _printk+0xc7/0x100 [ 9.813682][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.813684][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.813685][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.813687][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.813688][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.813690][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.813691][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.813692][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.813694][ C1] ip_local_deliver+0x184/0x4c0 [ 9.813695][ C1] ip_rcv+0xdc/0x3d0 [ 9.813696][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.813698][ C1] process_backlog+0x431/0x14c0 [ 9.813700][ C1] __napi_poll+0xa7/0x3b0 [ 9.813701][ C1] net_rx_action+0x513/0xf50 [ 9.813703][ C1] handle_softirqs+0x1d3/0x900 [ 9.813704][ C1] do_softirq+0xac/0xe0 [ 9.813705][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.813706][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.813708][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.813709][ C1] ip_output+0x1ca/0x660 [ 9.813710][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.813712][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.813713][ C1] tcp_connect+0x1024/0x1e20 [ 9.813714][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.813715][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.813717][ C1] inet_stream_connect+0x59/0xb0 [ 9.813718][ C1] __sys_connect+0x1cf/0x2e0 [ 9.813719][ C1] __x64_sys_connect+0x72/0xd0 [ 9.813721][ C1] do_syscall_64+0xff/0x530 [ 9.813722][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.813723][ C1] [ 9.813723][ C1] [ 9.813723][ C1] stack backtrace: [ 9.813726][ C1] CPU: 1 UID: 0 PID: 170 Comm: connect-deny_ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.813729][ C1] Tainted: [W]=WARN [ 9.813730][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.813732][ C1] Call Trace: [ 9.813733][ C1] [ 9.813734][ C1] dump_stack_lvl+0x6f/0xa0 [ 9.813738][ C1] print_irq_inversion_bug.part.0.cold+0xe6/0x143 [ 9.813740][ C1] mark_lock_irq+0x989/0x9c0 [ 9.813743][ C1] mark_lock+0x1d7/0xa00 [ 9.813745][ C1] mark_usage+0x42/0x170 [ 9.813746][ C1] __lock_acquire+0x388/0xc20 [ 9.813749][ C1] lock_acquire.part.0+0xd4/0x280 [ 9.813750][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 9.813752][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813756][ C1] ? lock_acquire+0x13c/0x160 [ 9.813758][ C1] console_lock_spinning_enable+0x5c/0x60 [ 9.813759][ C1] ? console_lock_spinning_enable+0x40/0x60 [ 9.813761][ C1] console_emit_next_record+0x1d1/0x4c0 [ 9.813763][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 9.813764][ C1] ? console_flush_one_record+0x106/0x710 [ 9.813766][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813768][ C1] ? lock_acquire+0x13c/0x160 [ 9.813770][ C1] console_flush_one_record+0x46f/0x710 [ 9.813772][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 9.813774][ C1] ? __lock_acquire+0x518/0xc20 [ 9.813776][ C1] console_unlock+0xee/0x1f0 [ 9.813778][ C1] ? console_flush_one_record+0x710/0x710 [ 9.813779][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813781][ C1] ? lock_acquire+0x60/0x160 [ 9.813783][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.813785][ C1] ? vprintk_emit+0x320/0x3e0 [ 9.813787][ C1] vprintk_emit+0x37c/0x3e0 [ 9.813789][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 9.813790][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.813793][ C1] _printk+0xc7/0x100 [ 9.813794][ C1] ? snapshot_read.cold+0x21/0x21 [ 9.813796][ C1] ? ___ratelimit+0x4e7/0xa00 [ 9.813799][ C1] ? idr_get_free+0x830/0x830 [ 9.813801][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.813803][ C1] ? tcp_send_synack+0x1570/0x1570 [ 9.813804][ C1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 9.813806][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.813809][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.813811][ C1] ? add_timer_on+0x580/0x580 [ 9.813814][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.813816][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 9.813818][ C1] ? tcp_fastopen_add_skb+0xc0/0xc0 [ 9.813821][ C1] ? secure_tcpv6_seq_and_ts_off+0x300/0x300 [ 9.813823][ C1] ? inet_csk_route_req+0x747/0xc30 [ 9.813825][ C1] ? inet_csk_reqsk_queue_hash_add+0x4c/0x60 [ 9.813826][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.813828][ C1] ? tcp_ao_calc_traffic_key+0x2a0/0x2a0 [ 9.813833][ C1] ? tcp_get_syncookie_mss+0x300/0x300 [ 9.813835][ C1] ? __lock_acquire+0x518/0xc20 [ 9.813837][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.813838][ C1] ? __lock_acquire+0x518/0xc20 [ 9.813840][ C1] ? down_read_nested+0xe7/0x500 [ 9.813842][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813844][ C1] ? tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.813846][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.813848][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.813850][ C1] ? tcp_v4_fill_cb+0x33/0x430 [ 9.813851][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.813854][ C1] ? mark_usage+0x61/0x170 [ 9.813855][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 9.813858][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.813859][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 9.813861][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.813863][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.813865][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.813866][ C1] ip_local_deliver+0x184/0x4c0 [ 9.813868][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 9.813870][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 9.813872][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.813874][ C1] ip_rcv+0xdc/0x3d0 [ 9.813875][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 9.813877][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813878][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 9.813881][ C1] ? mark_usage+0x61/0x170 [ 9.813882][ C1] ? __lock_acquire+0x518/0xc20 [ 9.813884][ C1] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 9.813886][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.813888][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.813890][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.813891][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 9.813893][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813896][ C1] process_backlog+0x431/0x14c0 [ 9.813898][ C1] __napi_poll+0xa7/0x3b0 [ 9.813900][ C1] net_rx_action+0x513/0xf50 [ 9.813903][ C1] ? __napi_poll+0x3b0/0x3b0 [ 9.813905][ C1] ? mark_held_locks+0x40/0x70 [ 9.813907][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.813908][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.813910][ C1] ? clockevents_program_event+0x307/0x7e0 [ 9.813913][ C1] ? mark_held_locks+0x40/0x70 [ 9.813914][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813916][ C1] ? mark_held_locks+0x40/0x70 [ 9.813918][ C1] handle_softirqs+0x1d3/0x900 [ 9.813919][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.813921][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.813922][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 9.813924][ C1] do_softirq+0xac/0xe0 [ 9.813926][ C1] [ 9.813926][ C1] [ 9.813927][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.813928][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.813930][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.813933][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 9.813935][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.813937][ C1] ? find_held_lock+0x2b/0x80 [ 9.813938][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.813939][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.813941][ C1] ? mark_held_locks+0x40/0x70 [ 9.813943][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.813944][ C1] ? neigh_hh_output+0x152/0x4c0 [ 9.813946][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.813948][ C1] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 9.813950][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.813951][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 9.813954][ C1] ip_output+0x1ca/0x660 [ 9.813956][ C1] ? ip_finish_output+0x460/0x460 [ 9.813957][ C1] ? find_held_lock+0x2b/0x80 [ 9.813959][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.813960][ C1] ? ip4_dst_hoplimit+0x15b/0x320 [ 9.813962][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.813965][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.813967][ C1] ? __tcp_select_window+0xf20/0xf20 [ 9.813969][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.813970][ C1] ? ktime_get+0x18e/0x2d0 [ 9.813972][ C1] ? kvm_clock_get_cycles+0x19/0x30 [ 9.813975][ C1] ? ktime_get+0x1dd/0x2d0 [ 9.813977][ C1] tcp_connect+0x1024/0x1e20 [ 9.813979][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.813982][ C1] ? tcp_v4_ao_sign_reset+0x8c0/0x8c0 [ 9.813984][ C1] ? do_raw_spin_lock+0x131/0x280 [ 9.813985][ C1] ? find_held_lock+0x2b/0x80 [ 9.813987][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.813988][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.813990][ C1] inet_stream_connect+0x59/0xb0 [ 9.813991][ C1] __sys_connect+0x1cf/0x2e0 [ 9.813993][ C1] ? do_fcntl+0x36f/0xc50 [ 9.813996][ C1] ? __sys_connect_file+0x250/0x250 [ 9.813999][ C1] ? fput+0x1f/0xa0 [ 9.814001][ C1] __x64_sys_connect+0x72/0xd0 [ 9.814003][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.814004][ C1] ? do_syscall_64+0xa6/0x530 [ 9.814005][ C1] do_syscall_64+0xff/0x530 [ 9.814007][ C1] ? exc_page_fault+0xee/0x100 [ 9.814008][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.814010][ C1] RIP: 0033:0x7fcd6754b312 [ 9.814013][ 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.814015][ C1] RSP: 002b:00007fcd66cc8988 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 9.814018][ C1] RAX: ffffffffffffffda RBX: 000000000000000a RCX: 00007fcd6754b312 [ 9.814019][ C1] RDX: 0000000000000010 RSI: 00007fcd66cc8c40 RDI: 000000000000000a [ 9.814020][ C1] RBP: 00007fcd66cc89b0 R08: 0000000000000000 R09: 0000000000000000 [ 9.814021][ C1] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fcd66cc8c40 [ 9.814022][ C1] R13: 0000000000000010 R14: 0000000000000001 R15: 0000000000412230 [ 9.814024][ C1] [ 9.814028][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 9.814029][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 170, name: connect-deny_ip [ 9.814031][ C1] preempt_count: 302, expected: 0 [ 9.814031][ C1] RCU nest depth: 6, expected: 0 [ 9.814032][ C1] INFO: lockdep is turned off. [ 9.814032][ C1] irq event stamp: 17753 [ 9.814033][ C1] hardirqs last enabled at (17752): [] __down_trylock_console_sem+0x86/0xa0 [ 9.814035][ C1] hardirqs last disabled at (17753): [] console_emit_next_record+0x3d4/0x4c0 [ 9.814037][ C1] softirqs last enabled at (17588): [] __dev_queue_xmit+0x964/0x1b80 [ 9.814039][ C1] softirqs last disabled at (17589): [] do_softirq+0xac/0xe0 [ 9.814041][ C1] Preemption disabled at: [ 9.814041][ C1] [] __dev_queue_xmit+0x204/0x1b80 [ 9.814044][ C1] CPU: 1 UID: 0 PID: 170 Comm: connect-deny_ip Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 9.814046][ C1] Tainted: [W]=WARN [ 9.814046][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 9.814047][ C1] Call Trace: [ 9.814047][ C1] [ 9.814048][ C1] dump_stack_lvl+0x6f/0xa0 [ 9.814050][ C1] ? __dev_queue_xmit+0x204/0x1b80 [ 9.814052][ C1] __might_resched.cold+0x1fe/0x2c1 [ 9.814055][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 9.814058][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814060][ C1] __kmalloc_noprof+0x443/0x760 [ 9.814061][ C1] ? __rwlock_init+0x150/0x150 [ 9.814063][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 9.814065][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.814066][ C1] alloc_buf.isra.0+0x4b/0x260 [ 9.814068][ C1] put_chars+0x1e1/0x2f0 [ 9.814070][ C1] ? __send_to_port+0x420/0x420 [ 9.814073][ C1] hvc_console_print+0x292/0x780 [ 9.814075][ C1] ? __lock_acquire+0x388/0xc20 [ 9.814077][ C1] ? hvc_write+0x3a0/0x3a0 [ 9.814078][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814080][ C1] ? lock_acquire+0x13c/0x160 [ 9.814082][ C1] console_emit_next_record+0x22f/0x4c0 [ 9.814084][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 9.814086][ C1] ? console_flush_one_record+0x106/0x710 [ 9.814088][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814089][ C1] ? lock_acquire+0x13c/0x160 [ 9.814091][ C1] console_flush_one_record+0x46f/0x710 [ 9.814093][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 9.814095][ C1] ? __lock_acquire+0x518/0xc20 [ 9.814097][ C1] console_unlock+0xee/0x1f0 [ 9.814099][ C1] ? console_flush_one_record+0x710/0x710 [ 9.814101][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814102][ C1] ? lock_acquire+0x60/0x160 [ 9.814104][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 9.814106][ C1] ? vprintk_emit+0x320/0x3e0 [ 9.814108][ C1] vprintk_emit+0x37c/0x3e0 [ 9.814110][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 9.814112][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.814114][ C1] _printk+0xc7/0x100 [ 9.814115][ C1] ? snapshot_read.cold+0x21/0x21 [ 9.814117][ C1] ? ___ratelimit+0x4e7/0xa00 [ 9.814119][ C1] ? idr_get_free+0x830/0x830 [ 9.814121][ C1] tcp_make_synack.cold+0x36/0xb6 [ 9.814123][ C1] ? tcp_send_synack+0x1570/0x1570 [ 9.814124][ C1] ? lockdep_hardirqs_on_prepare.part.0+0x9a/0x160 [ 9.814126][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.814128][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 9.814130][ C1] ? add_timer_on+0x580/0x580 [ 9.814132][ C1] tcp_v4_send_synack+0x103/0xb20 [ 9.814134][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 9.814136][ C1] ? tcp_fastopen_add_skb+0xc0/0xc0 [ 9.814138][ C1] ? secure_tcpv6_seq_and_ts_off+0x300/0x300 [ 9.814142][ C1] ? inet_csk_route_req+0x747/0xc30 [ 9.814144][ C1] ? inet_csk_reqsk_queue_hash_add+0x4c/0x60 [ 9.814146][ C1] tcp_conn_request+0x1f15/0x2d20 [ 9.814148][ C1] ? tcp_ao_calc_traffic_key+0x2a0/0x2a0 [ 9.814150][ C1] ? tcp_get_syncookie_mss+0x300/0x300 [ 9.814152][ C1] ? __lock_acquire+0x518/0xc20 [ 9.814154][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.814156][ C1] ? __lock_acquire+0x518/0xc20 [ 9.814157][ C1] ? down_read_nested+0xe7/0x500 [ 9.814159][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814162][ C1] ? tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.814164][ C1] tcp_rcv_listen_state_process+0x1ab/0x380 [ 9.814166][ C1] tcp_v4_rcv_listen.constprop.0.isra.0+0x13f/0x4f0 [ 9.814167][ C1] ? tcp_v4_fill_cb+0x33/0x430 [ 9.814169][ C1] tcp_v4_rcv+0x253c/0x2b70 [ 9.814171][ C1] ? mark_usage+0x61/0x170 [ 9.814173][ C1] ? tcp_add_backlog+0x1e30/0x1e30 [ 9.814175][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.814176][ C1] ? ip_local_deliver_finish+0x2ba/0x610 [ 9.814178][ C1] ip_protocol_deliver_rcu+0x82/0x350 [ 9.814180][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.814182][ C1] ip_local_deliver_finish+0x36f/0x610 [ 9.814184][ C1] ip_local_deliver+0x184/0x4c0 [ 9.814185][ C1] ? ip_local_deliver_finish+0x610/0x610 [ 9.814187][ C1] ? ip_rcv_finish_core+0x6ed/0x14c0 [ 9.814189][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.814191][ C1] ip_rcv+0xdc/0x3d0 [ 9.814193][ C1] ? ip_local_deliver+0x4c0/0x4c0 [ 9.814194][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814196][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 9.814198][ C1] ? mark_usage+0x61/0x170 [ 9.814199][ C1] ? __lock_acquire+0x518/0xc20 [ 9.814200][ C1] ? trace_raw_output_rcu_invoke_kfree_bulk_callback+0x57/0x130 [ 9.814202][ C1] __netif_receive_skb_one_core+0xfc/0x180 [ 9.814204][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.814206][ C1] ? process_backlog+0x3f1/0x14c0 [ 9.814207][ C1] ? __netif_receive_skb_list_core+0x9e0/0x9e0 [ 9.814209][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814212][ C1] process_backlog+0x431/0x14c0 [ 9.814214][ C1] __napi_poll+0xa7/0x3b0 [ 9.814216][ C1] net_rx_action+0x513/0xf50 [ 9.814219][ C1] ? __napi_poll+0x3b0/0x3b0 [ 9.814221][ C1] ? mark_held_locks+0x40/0x70 [ 9.814222][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.814223][ C1] ? _raw_spin_unlock_irqrestore+0x53/0x80 [ 9.814226][ C1] ? clockevents_program_event+0x307/0x7e0 [ 9.814228][ C1] ? mark_held_locks+0x40/0x70 [ 9.814229][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814230][ C1] ? mark_held_locks+0x40/0x70 [ 9.814232][ C1] handle_softirqs+0x1d3/0x900 [ 9.814234][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.814235][ C1] ? _local_bh_enable+0xc0/0xc0 [ 9.814237][ C1] ? __dev_queue_xmit+0x964/0x1b80 [ 9.814239][ C1] do_softirq+0xac/0xe0 [ 9.814240][ C1] [ 9.814241][ C1] [ 9.814241][ C1] __local_bh_enable_ip+0x118/0x150 [ 9.814243][ C1] __dev_queue_xmit+0x979/0x1b80 [ 9.814245][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.814247][ C1] ? netdev_core_pick_tx+0x2c0/0x2c0 [ 9.814249][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.814250][ C1] ? find_held_lock+0x2b/0x80 [ 9.814251][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.814253][ C1] ? rcu_is_watching+0x16/0xd0 [ 9.814255][ C1] ? mark_held_locks+0x40/0x70 [ 9.814256][ C1] ? __asan_memcpy+0x3c/0x60 [ 9.814257][ C1] ? neigh_hh_output+0x152/0x4c0 [ 9.814260][ C1] ip_finish_output2+0xd9b/0x1bc0 [ 9.814261][ C1] ? ip_dst_mtu_maybe_forward+0x261/0x670 [ 9.814263][ C1] ? lock_acquire.part.0+0xd4/0x280 [ 9.814265][ C1] ? ip_fragment.constprop.0+0x2c0/0x2c0 [ 9.814267][ C1] ip_output+0x1ca/0x660 [ 9.814269][ C1] ? ip_finish_output+0x460/0x460 [ 9.814270][ C1] ? find_held_lock+0x2b/0x80 [ 9.814272][ C1] ? __lock_release.isra.0+0x69/0x1a0 [ 9.814274][ C1] ? ip4_dst_hoplimit+0x15b/0x320 [ 9.814276][ C1] __ip_queue_xmit+0x774/0x1650 [ 9.814278][ C1] __tcp_transmit_skb+0x1b6e/0x3c10 [ 9.814280][ C1] ? __tcp_select_window+0xf20/0xf20 [ 9.814282][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.814284][ C1] ? ktime_get+0x18e/0x2d0 [ 9.814285][ C1] ? kvm_clock_get_cycles+0x19/0x30 [ 9.814287][ C1] ? ktime_get+0x1dd/0x2d0 [ 9.814288][ C1] tcp_connect+0x1024/0x1e20 [ 9.814290][ C1] tcp_v4_connect+0x12bd/0x1960 [ 9.814293][ C1] ? tcp_v4_ao_sign_reset+0x8c0/0x8c0 [ 9.814295][ C1] ? do_raw_spin_lock+0x131/0x280 [ 9.814296][ C1] ? find_held_lock+0x2b/0x80 [ 9.814298][ C1] __inet_stream_connect+0x2ed/0xd00 [ 9.814299][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.814301][ C1] inet_stream_connect+0x59/0xb0 [ 9.814302][ C1] __sys_connect+0x1cf/0x2e0 [ 9.814304][ C1] ? do_fcntl+0x36f/0xc50 [ 9.814306][ C1] ? __sys_connect_file+0x250/0x250 [ 9.814309][ C1] ? fput+0x1f/0xa0 [ 9.814310][ C1] __x64_sys_connect+0x72/0xd0 [ 9.814312][ C1] ? lockdep_hardirqs_on+0x91/0x130 [ 9.814313][ C1] ? do_syscall_64+0xa6/0x530 [ 9.814315][ C1] do_syscall_64+0xff/0x530 [ 9.814316][ C1] ? exc_page_fault+0xee/0x100 [ 9.814317][ C1] entry_SYSCALL_64_after_hwframe+0x4b/0x53 [ 9.814319][ C1] RIP: 0033:0x7fcd6754b312 [ 9.814320][ 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.814321][ C1] RSP: 002b:00007fcd66cc8988 EFLAGS: 00000246 ORIG_RAX: 000000000000002a [ 9.814322][ C1] RAX: ffffffffffffffda RBX: 000000000000000a RCX: 00007fcd6754b312 [ 9.814323][ C1] RDX: 0000000000000010 RSI: 00007fcd66cc8c40 RDI: 000000000000000a [ 9.814324][ C1] RBP: 00007fcd66cc89b0 R08: 0000000000000000 R09: 0000000000000000 [ 9.814325][ C1] R10: 0000000000000000 R11: 0000000000000246 R12: 00007fcd66cc8c40 [ 9.814325][ C1] R13: 0000000000000010 R14: 0000000000000001 R15: 0000000000412230 [ 9.814327][ C1] [ 10.819244][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 10.819259][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 10.819261][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 10.819263][ C1] preempt_count: 102, expected: 0 [ 10.819264][ C1] RCU nest depth: 0, expected: 0 [ 10.819265][ C1] INFO: lockdep is turned off. [ 10.819266][ C1] irq event stamp: 327004 [ 10.819267][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 10.819275][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 10.819277][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 10.819281][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 10.819283][ C1] Preemption disabled at: [ 10.819283][ C1] [<0000000000000000>] 0x0 [ 10.819291][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 10.819295][ C1] Tainted: [W]=WARN [ 10.819296][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 10.819298][ C1] Call Trace: [ 10.819299][ C1] [ 10.819301][ C1] dump_stack_lvl+0x6f/0xa0 [ 10.819307][ C1] __might_resched.cold+0x1fe/0x2c1 [ 10.819310][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 10.819314][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819318][ C1] __kmalloc_noprof+0x443/0x760 [ 10.819321][ C1] ? __rwlock_init+0x150/0x150 [ 10.819324][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 10.819328][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 10.819330][ C1] alloc_buf.isra.0+0x4b/0x260 [ 10.819332][ C1] put_chars+0x1e1/0x2f0 [ 10.819334][ C1] ? __send_to_port+0x420/0x420 [ 10.819335][ C1] ? printk_get_next_message+0x240/0x650 [ 10.819339][ C1] hvc_console_print+0x292/0x780 [ 10.819343][ C1] ? hvc_write+0x3a0/0x3a0 [ 10.819344][ C1] ? __rwlock_init+0x150/0x150 [ 10.819346][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819348][ C1] ? lock_acquire+0x13c/0x160 [ 10.819350][ C1] console_emit_next_record+0x22f/0x4c0 [ 10.819353][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 10.819355][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819356][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819358][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819359][ C1] ? lock_acquire+0x13c/0x160 [ 10.819361][ C1] ? console_flush_one_record+0x111/0x710 [ 10.819363][ C1] console_flush_one_record+0x46f/0x710 [ 10.819365][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 10.819368][ C1] console_unlock+0xee/0x1f0 [ 10.819370][ C1] ? lock_acquire+0x13c/0x160 [ 10.819371][ C1] ? console_flush_one_record+0x710/0x710 [ 10.819373][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819374][ C1] ? lock_release+0x184/0x1f0 [ 10.819376][ C1] ? lock_acquire+0x60/0x160 [ 10.819377][ C1] ? __rcu_read_lock+0x30/0x70 [ 10.819379][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 10.819381][ C1] ? vprintk_emit+0x320/0x3e0 [ 10.819383][ C1] vprintk_emit+0x37c/0x3e0 [ 10.819385][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 10.819387][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 10.819389][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819390][ C1] ? lock_acquire+0x13c/0x160 [ 10.819392][ C1] _printk+0xc7/0x100 [ 10.819395][ C1] ? snapshot_read.cold+0x21/0x21 [ 10.819396][ C1] ? ___ratelimit+0x4e7/0xa00 [ 10.819399][ C1] ? trace_hardirqs_on+0x36/0x40 [ 10.819402][ C1] ? idr_get_free+0x830/0x830 [ 10.819404][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 10.819405][ C1] tcp_make_synack.cold+0x36/0xb6 [ 10.819410][ C1] ? tcp_send_synack+0x1570/0x1570 [ 10.819413][ C1] ? lock_release+0x184/0x1f0 [ 10.819414][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819416][ C1] ? inet_csk_route_req+0x747/0xc30 [ 10.819419][ C1] tcp_v4_send_synack+0x103/0xb20 [ 10.819422][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 10.819423][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 10.819426][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.819427][ C1] ? trace_hardirqs_on+0x36/0x40 [ 10.819429][ C1] ? get_random_u16+0x740/0x740 [ 10.819431][ C1] tcp_rtx_synack+0x150/0x440 [ 10.819433][ C1] ? trace_pelt_cfs_tp+0x9b/0x190 [ 10.819435][ C1] ? tcp_send_probe0+0x590/0x590 [ 10.819436][ C1] ? __update_load_avg_cfs_rq+0x36c/0x10c0 [ 10.819439][ C1] ? update_cfs_rq_load_avg+0x4b/0x660 [ 10.819441][ C1] ? common_startup_64+0x13e/0x148 [ 10.819444][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 10.819446][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 10.819447][ C1] ? lock_acquire+0x6a/0x160 [ 10.819448][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 10.819450][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 10.819451][ C1] call_timer_fn+0x160/0x4d0 [ 10.819455][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 10.819456][ C1] ? debug_object_active_state+0x430/0x430 [ 10.819461][ C1] ? trace_sched_wakeup+0x9b/0x160 [ 10.819463][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 10.819464][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819466][ C1] __run_timers+0x68f/0xaa0 [ 10.819468][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 10.819470][ C1] ? debug_object_deactivate+0x2f7/0x440 [ 10.819472][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 10.819474][ C1] ? do_raw_spin_lock+0x131/0x280 [ 10.819476][ C1] ? __rwlock_init+0x150/0x150 [ 10.819478][ C1] run_timer_softirq+0xf0/0x160 [ 10.819480][ C1] ? __run_timers+0xaa0/0xaa0 [ 10.819482][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 10.819483][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819485][ C1] handle_softirqs+0x1d3/0x900 [ 10.819487][ C1] ? _local_bh_enable+0xc0/0xc0 [ 10.819488][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819490][ C1] ? lock_release+0x184/0x1f0 [ 10.819492][ C1] __irq_exit_rcu+0x145/0x1c0 [ 10.819493][ C1] irq_exit_rcu+0xe/0x30 [ 10.819495][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 10.819497][ C1] [ 10.819497][ C1] [ 10.819498][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 10.819500][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 10.819502][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 10.819505][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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 [ 10.819506][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 10.819515][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 10.819516][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 10.819517][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 10.819518][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 10.819518][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 10.819520][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 10.819522][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.819524][ C1] default_idle+0x9/0x10 [ 10.819525][ C1] default_idle_call+0x6e/0xb0 [ 10.819527][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 10.819528][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 10.819530][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 10.819532][ C1] ? rcu_is_watching+0x16/0xd0 [ 10.819534][ C1] do_idle+0xd8/0x190 [ 10.819536][ C1] cpu_startup_entry+0x53/0x70 [ 10.819537][ C1] start_secondary+0x204/0x2b0 [ 10.819539][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 10.819540][ C1] common_startup_64+0x13e/0x148 [ 10.819543][ C1] [ 10.946320][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 11.971233][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 11.971247][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 11.971250][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 11.971251][ C1] preempt_count: 102, expected: 0 [ 11.971252][ C1] RCU nest depth: 0, expected: 0 [ 11.971253][ C1] INFO: lockdep is turned off. [ 11.971254][ C1] irq event stamp: 327004 [ 11.971255][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 11.971263][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 11.971266][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 11.971270][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 11.971272][ C1] Preemption disabled at: [ 11.971273][ C1] [<0000000000000000>] 0x0 [ 11.971280][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 11.971284][ C1] Tainted: [W]=WARN [ 11.971284][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 11.971287][ C1] Call Trace: [ 11.971288][ C1] [ 11.971290][ C1] dump_stack_lvl+0x6f/0xa0 [ 11.971296][ C1] __might_resched.cold+0x1fe/0x2c1 [ 11.971299][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 11.971303][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971307][ C1] __kmalloc_noprof+0x443/0x760 [ 11.971310][ C1] ? __rwlock_init+0x150/0x150 [ 11.971313][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 11.971317][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 11.971319][ C1] alloc_buf.isra.0+0x4b/0x260 [ 11.971321][ C1] put_chars+0x1e1/0x2f0 [ 11.971323][ C1] ? __send_to_port+0x420/0x420 [ 11.971325][ C1] ? printk_get_next_message+0x240/0x650 [ 11.971328][ C1] hvc_console_print+0x292/0x780 [ 11.971332][ C1] ? hvc_write+0x3a0/0x3a0 [ 11.971333][ C1] ? __rwlock_init+0x150/0x150 [ 11.971335][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971337][ C1] ? lock_acquire+0x13c/0x160 [ 11.971339][ C1] console_emit_next_record+0x22f/0x4c0 [ 11.971342][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 11.971344][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971345][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971347][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971348][ C1] ? lock_acquire+0x13c/0x160 [ 11.971350][ C1] ? console_flush_one_record+0x111/0x710 [ 11.971352][ C1] console_flush_one_record+0x46f/0x710 [ 11.971354][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 11.971357][ C1] console_unlock+0xee/0x1f0 [ 11.971358][ C1] ? lock_acquire+0x13c/0x160 [ 11.971360][ C1] ? console_flush_one_record+0x710/0x710 [ 11.971362][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971363][ C1] ? lock_release+0x184/0x1f0 [ 11.971365][ C1] ? lock_acquire+0x60/0x160 [ 11.971366][ C1] ? __rcu_read_lock+0x30/0x70 [ 11.971368][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 11.971370][ C1] ? vprintk_emit+0x320/0x3e0 [ 11.971372][ C1] vprintk_emit+0x37c/0x3e0 [ 11.971374][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 11.971376][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 11.971378][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971379][ C1] ? lock_acquire+0x13c/0x160 [ 11.971381][ C1] _printk+0xc7/0x100 [ 11.971384][ C1] ? snapshot_read.cold+0x21/0x21 [ 11.971386][ C1] ? ___ratelimit+0x4e7/0xa00 [ 11.971388][ C1] ? trace_hardirqs_on+0x36/0x40 [ 11.971391][ C1] ? idr_get_free+0x830/0x830 [ 11.971392][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 11.971394][ C1] tcp_make_synack.cold+0x36/0xb6 [ 11.971398][ C1] ? tcp_send_synack+0x1570/0x1570 [ 11.971401][ C1] ? lock_release+0x184/0x1f0 [ 11.971403][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971405][ C1] ? inet_csk_route_req+0x747/0xc30 [ 11.971408][ C1] tcp_v4_send_synack+0x103/0xb20 [ 11.971410][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 11.971412][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 11.971415][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.971416][ C1] ? trace_hardirqs_on+0x36/0x40 [ 11.971417][ C1] ? get_random_u16+0x740/0x740 [ 11.971420][ C1] tcp_rtx_synack+0x150/0x440 [ 11.971421][ C1] ? tcp_send_probe0+0x590/0x590 [ 11.971424][ C1] ? sched_balance_rq+0x324/0x1e30 [ 11.971426][ C1] ? do_raw_spin_lock+0x131/0x280 [ 11.971427][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 11.971429][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.971431][ C1] ? lock_acquire+0x6a/0x160 [ 11.971432][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 11.971434][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.971435][ C1] call_timer_fn+0x160/0x4d0 [ 11.971438][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 11.971440][ C1] ? debug_object_active_state+0x430/0x430 [ 11.971444][ C1] ? __rwlock_init+0x150/0x150 [ 11.971446][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 11.971447][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971450][ C1] __run_timers+0x68f/0xaa0 [ 11.971451][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 11.971453][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 11.971455][ C1] ? do_raw_spin_lock+0x131/0x280 [ 11.971458][ C1] ? __rwlock_init+0x150/0x150 [ 11.971460][ C1] run_timer_softirq+0xf0/0x160 [ 11.971462][ C1] ? __run_timers+0xaa0/0xaa0 [ 11.971463][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 11.971465][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971466][ C1] handle_softirqs+0x1d3/0x900 [ 11.971468][ C1] ? _local_bh_enable+0xc0/0xc0 [ 11.971470][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971471][ C1] ? lock_release+0x184/0x1f0 [ 11.971473][ C1] __irq_exit_rcu+0x145/0x1c0 [ 11.971475][ C1] irq_exit_rcu+0xe/0x30 [ 11.971476][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 11.971478][ C1] [ 11.971479][ C1] [ 11.971479][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 11.971481][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 11.971484][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 11.971486][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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.971488][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 11.971491][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 11.971492][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 11.971493][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 11.971493][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 11.971494][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 11.971495][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 11.971497][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.971500][ C1] default_idle+0x9/0x10 [ 11.971501][ C1] default_idle_call+0x6e/0xb0 [ 11.971502][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 11.971504][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 11.971505][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 11.971508][ C1] ? rcu_is_watching+0x16/0xd0 [ 11.971510][ C1] do_idle+0xd8/0x190 [ 11.971511][ C1] cpu_startup_entry+0x53/0x70 [ 11.971513][ C1] start_secondary+0x204/0x2b0 [ 11.971521][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 11.971523][ C1] common_startup_64+0x13e/0x148 [ 11.971527][ C1] [ 11.989300][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 12.994267][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 12.994282][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 12.994285][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 12.994286][ C1] preempt_count: 102, expected: 0 [ 12.994287][ C1] RCU nest depth: 0, expected: 0 [ 12.994288][ C1] INFO: lockdep is turned off. [ 12.994289][ C1] irq event stamp: 327004 [ 12.994290][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 12.994299][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 12.994301][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 12.994305][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 12.994307][ C1] Preemption disabled at: [ 12.994308][ C1] [<0000000000000000>] 0x0 [ 12.994316][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 12.994319][ C1] Tainted: [W]=WARN [ 12.994320][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 12.994322][ C1] Call Trace: [ 12.994324][ C1] [ 12.994327][ C1] dump_stack_lvl+0x6f/0xa0 [ 12.994332][ C1] __might_resched.cold+0x1fe/0x2c1 [ 12.994336][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 12.994340][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994344][ C1] __kmalloc_noprof+0x443/0x760 [ 12.994348][ C1] ? __rwlock_init+0x150/0x150 [ 12.994350][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 12.994354][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 12.994356][ C1] alloc_buf.isra.0+0x4b/0x260 [ 12.994358][ C1] put_chars+0x1e1/0x2f0 [ 12.994360][ C1] ? __send_to_port+0x420/0x420 [ 12.994362][ C1] ? printk_get_next_message+0x240/0x650 [ 12.994365][ C1] hvc_console_print+0x292/0x780 [ 12.994369][ C1] ? hvc_write+0x3a0/0x3a0 [ 12.994370][ C1] ? __rwlock_init+0x150/0x150 [ 12.994372][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994373][ C1] ? lock_acquire+0x13c/0x160 [ 12.994376][ C1] console_emit_next_record+0x22f/0x4c0 [ 12.994378][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 12.994380][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994382][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994383][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994385][ C1] ? lock_acquire+0x13c/0x160 [ 12.994387][ C1] ? console_flush_one_record+0x111/0x710 [ 12.994388][ C1] console_flush_one_record+0x46f/0x710 [ 12.994391][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 12.994393][ C1] console_unlock+0xee/0x1f0 [ 12.994395][ C1] ? lock_acquire+0x13c/0x160 [ 12.994396][ C1] ? console_flush_one_record+0x710/0x710 [ 12.994398][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994400][ C1] ? lock_release+0x184/0x1f0 [ 12.994401][ C1] ? lock_acquire+0x60/0x160 [ 12.994402][ C1] ? __rcu_read_lock+0x30/0x70 [ 12.994404][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 12.994406][ C1] ? vprintk_emit+0x320/0x3e0 [ 12.994408][ C1] vprintk_emit+0x37c/0x3e0 [ 12.994410][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 12.994412][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 12.994414][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994416][ C1] ? lock_acquire+0x13c/0x160 [ 12.994417][ C1] _printk+0xc7/0x100 [ 12.994420][ C1] ? snapshot_read.cold+0x21/0x21 [ 12.994422][ C1] ? ___ratelimit+0x4e7/0xa00 [ 12.994424][ C1] ? trace_hardirqs_on+0x36/0x40 [ 12.994427][ C1] ? idr_get_free+0x830/0x830 [ 12.994429][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 12.994430][ C1] tcp_make_synack.cold+0x36/0xb6 [ 12.994434][ C1] ? tcp_send_synack+0x1570/0x1570 [ 12.994438][ C1] ? lock_release+0x184/0x1f0 [ 12.994439][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994441][ C1] ? inet_csk_route_req+0x747/0xc30 [ 12.994444][ C1] tcp_v4_send_synack+0x103/0xb20 [ 12.994446][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 12.994448][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 12.994451][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.994452][ C1] ? trace_hardirqs_on+0x36/0x40 [ 12.994453][ C1] ? get_random_u16+0x740/0x740 [ 12.994456][ C1] tcp_rtx_synack+0x150/0x440 [ 12.994457][ C1] ? tcp_send_probe0+0x590/0x590 [ 12.994460][ C1] ? sched_balance_rq+0x324/0x1e30 [ 12.994461][ C1] ? do_raw_spin_lock+0x131/0x280 [ 12.994463][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 12.994465][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.994466][ C1] ? lock_acquire+0x6a/0x160 [ 12.994468][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 12.994469][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.994471][ C1] call_timer_fn+0x160/0x4d0 [ 12.994474][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 12.994475][ C1] ? debug_object_active_state+0x430/0x430 [ 12.994479][ C1] ? __rwlock_init+0x150/0x150 [ 12.994481][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 12.994483][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994485][ C1] __run_timers+0x68f/0xaa0 [ 12.994486][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 12.994488][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 12.994490][ C1] ? do_raw_spin_lock+0x131/0x280 [ 12.994493][ C1] ? __rwlock_init+0x150/0x150 [ 12.994495][ C1] run_timer_softirq+0xf0/0x160 [ 12.994497][ C1] ? __run_timers+0xaa0/0xaa0 [ 12.994498][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 12.994500][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994501][ C1] handle_softirqs+0x1d3/0x900 [ 12.994503][ C1] ? _local_bh_enable+0xc0/0xc0 [ 12.994505][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994506][ C1] ? lock_release+0x184/0x1f0 [ 12.994508][ C1] __irq_exit_rcu+0x145/0x1c0 [ 12.994509][ C1] irq_exit_rcu+0xe/0x30 [ 12.994511][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 12.994513][ C1] [ 12.994513][ C1] [ 12.994514][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 12.994515][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 12.994523][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 12.994526][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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.994528][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 12.994531][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 12.994533][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 12.994533][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 12.994534][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 12.994535][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 12.994536][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 12.994538][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.994540][ C1] default_idle+0x9/0x10 [ 12.994541][ C1] default_idle_call+0x6e/0xb0 [ 12.994543][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 12.994544][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 12.994546][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 12.994548][ C1] ? rcu_is_watching+0x16/0xd0 [ 12.994550][ C1] do_idle+0xd8/0x190 [ 12.994552][ C1] cpu_startup_entry+0x53/0x70 [ 12.994554][ C1] start_secondary+0x204/0x2b0 [ 12.994555][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 12.994557][ C1] common_startup_64+0x13e/0x148 [ 12.994562][ C1] [ 13.017350][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.018257][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 14.018272][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 14.018275][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 14.018276][ C1] preempt_count: 102, expected: 0 [ 14.018277][ C1] RCU nest depth: 0, expected: 0 [ 14.018278][ C1] INFO: lockdep is turned off. [ 14.018279][ C1] irq event stamp: 327004 [ 14.018280][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 14.018288][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 14.018290][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 14.018294][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 14.018296][ C1] Preemption disabled at: [ 14.018297][ C1] [<0000000000000000>] 0x0 [ 14.018304][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 14.018308][ C1] Tainted: [W]=WARN [ 14.018309][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 14.018311][ C1] Call Trace: [ 14.018312][ C1] [ 14.018315][ C1] dump_stack_lvl+0x6f/0xa0 [ 14.018321][ C1] __might_resched.cold+0x1fe/0x2c1 [ 14.018325][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 14.018328][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018332][ C1] __kmalloc_noprof+0x443/0x760 [ 14.018336][ C1] ? __rwlock_init+0x150/0x150 [ 14.018339][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 14.018342][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 14.018344][ C1] alloc_buf.isra.0+0x4b/0x260 [ 14.018346][ C1] put_chars+0x1e1/0x2f0 [ 14.018348][ C1] ? __send_to_port+0x420/0x420 [ 14.018350][ C1] ? printk_get_next_message+0x240/0x650 [ 14.018353][ C1] hvc_console_print+0x292/0x780 [ 14.018357][ C1] ? hvc_write+0x3a0/0x3a0 [ 14.018359][ C1] ? __rwlock_init+0x150/0x150 [ 14.018360][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018362][ C1] ? lock_acquire+0x13c/0x160 [ 14.018364][ C1] console_emit_next_record+0x22f/0x4c0 [ 14.018367][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 14.018369][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018370][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018372][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018374][ C1] ? lock_acquire+0x13c/0x160 [ 14.018375][ C1] ? console_flush_one_record+0x111/0x710 [ 14.018377][ C1] console_flush_one_record+0x46f/0x710 [ 14.018379][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 14.018382][ C1] console_unlock+0xee/0x1f0 [ 14.018384][ C1] ? lock_acquire+0x13c/0x160 [ 14.018385][ C1] ? console_flush_one_record+0x710/0x710 [ 14.018387][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018389][ C1] ? lock_release+0x184/0x1f0 [ 14.018390][ C1] ? lock_acquire+0x60/0x160 [ 14.018391][ C1] ? __rcu_read_lock+0x30/0x70 [ 14.018394][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 14.018395][ C1] ? vprintk_emit+0x320/0x3e0 [ 14.018397][ C1] vprintk_emit+0x37c/0x3e0 [ 14.018400][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 14.018401][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 14.018403][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018405][ C1] ? lock_acquire+0x13c/0x160 [ 14.018406][ C1] _printk+0xc7/0x100 [ 14.018409][ C1] ? snapshot_read.cold+0x21/0x21 [ 14.018411][ C1] ? ___ratelimit+0x4e7/0xa00 [ 14.018413][ C1] ? trace_hardirqs_on+0x36/0x40 [ 14.018416][ C1] ? idr_get_free+0x830/0x830 [ 14.018418][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 14.018420][ C1] tcp_make_synack.cold+0x36/0xb6 [ 14.018424][ C1] ? tcp_send_synack+0x1570/0x1570 [ 14.018427][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018429][ C1] ? inet_csk_route_req+0x747/0xc30 [ 14.018432][ C1] tcp_v4_send_synack+0x103/0xb20 [ 14.018434][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 14.018436][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.018438][ C1] ? trace_hardirqs_on+0x36/0x40 [ 14.018439][ C1] ? get_random_u16+0x740/0x740 [ 14.018441][ C1] tcp_rtx_synack+0x150/0x440 [ 14.018443][ C1] ? tcp_send_probe0+0x590/0x590 [ 14.018446][ C1] ? sched_balance_rq+0x324/0x1e30 [ 14.018448][ C1] ? do_raw_spin_lock+0x131/0x280 [ 14.018450][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 14.018452][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.018453][ C1] ? lock_acquire+0x6a/0x160 [ 14.018454][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 14.018456][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.018457][ C1] call_timer_fn+0x160/0x4d0 [ 14.018461][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 14.018462][ C1] ? debug_object_active_state+0x430/0x430 [ 14.018467][ C1] ? __rwlock_init+0x150/0x150 [ 14.018468][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 14.018470][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018472][ C1] __run_timers+0x68f/0xaa0 [ 14.018474][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 14.018476][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 14.018478][ C1] ? do_raw_spin_lock+0x131/0x280 [ 14.018480][ C1] ? __rwlock_init+0x150/0x150 [ 14.018482][ C1] run_timer_softirq+0xf0/0x160 [ 14.018484][ C1] ? __run_timers+0xaa0/0xaa0 [ 14.018486][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 14.018487][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018489][ C1] handle_softirqs+0x1d3/0x900 [ 14.018491][ C1] ? _local_bh_enable+0xc0/0xc0 [ 14.018493][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018494][ C1] ? lock_release+0x184/0x1f0 [ 14.018496][ C1] __irq_exit_rcu+0x145/0x1c0 [ 14.018497][ C1] irq_exit_rcu+0xe/0x30 [ 14.018499][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 14.018501][ C1] [ 14.018501][ C1] [ 14.018502][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 14.018504][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 14.018506][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 14.018509][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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.018511][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 14.018514][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 14.018515][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 14.018516][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 14.018516][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 14.018517][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 14.018518][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 14.018520][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 14.018528][ C1] default_idle+0x9/0x10 [ 14.018529][ C1] default_idle_call+0x6e/0xb0 [ 14.018531][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 14.018532][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 14.018534][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 14.018536][ C1] ? rcu_is_watching+0x16/0xd0 [ 14.018538][ C1] do_idle+0xd8/0x190 [ 14.018540][ C1] cpu_startup_entry+0x53/0x70 [ 14.018541][ C1] start_secondary+0x204/0x2b0 [ 14.018543][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 14.018545][ C1] common_startup_64+0x13e/0x148 [ 14.018550][ C1] [ 14.041865][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.042240][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 15.042255][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 15.042258][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 15.042260][ C1] preempt_count: 102, expected: 0 [ 15.042261][ C1] RCU nest depth: 0, expected: 0 [ 15.042261][ C1] INFO: lockdep is turned off. [ 15.042263][ C1] irq event stamp: 327004 [ 15.042264][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 15.042271][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 15.042273][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 15.042277][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 15.042279][ C1] Preemption disabled at: [ 15.042280][ C1] [<0000000000000000>] 0x0 [ 15.042287][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 15.042290][ C1] Tainted: [W]=WARN [ 15.042291][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 15.042293][ C1] Call Trace: [ 15.042294][ C1] [ 15.042296][ C1] dump_stack_lvl+0x6f/0xa0 [ 15.042302][ C1] __might_resched.cold+0x1fe/0x2c1 [ 15.042307][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 15.042311][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042315][ C1] __kmalloc_noprof+0x443/0x760 [ 15.042319][ C1] ? __rwlock_init+0x150/0x150 [ 15.042321][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 15.042325][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 15.042327][ C1] alloc_buf.isra.0+0x4b/0x260 [ 15.042329][ C1] put_chars+0x1e1/0x2f0 [ 15.042331][ C1] ? __send_to_port+0x420/0x420 [ 15.042333][ C1] ? printk_get_next_message+0x240/0x650 [ 15.042336][ C1] hvc_console_print+0x292/0x780 [ 15.042340][ C1] ? hvc_write+0x3a0/0x3a0 [ 15.042342][ C1] ? __rwlock_init+0x150/0x150 [ 15.042343][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042345][ C1] ? lock_acquire+0x13c/0x160 [ 15.042347][ C1] console_emit_next_record+0x22f/0x4c0 [ 15.042350][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 15.042352][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042354][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042355][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042357][ C1] ? lock_acquire+0x13c/0x160 [ 15.042359][ C1] ? console_flush_one_record+0x111/0x710 [ 15.042360][ C1] console_flush_one_record+0x46f/0x710 [ 15.042363][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 15.042365][ C1] console_unlock+0xee/0x1f0 [ 15.042367][ C1] ? lock_acquire+0x13c/0x160 [ 15.042369][ C1] ? console_flush_one_record+0x710/0x710 [ 15.042370][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042372][ C1] ? lock_release+0x184/0x1f0 [ 15.042373][ C1] ? lock_acquire+0x60/0x160 [ 15.042375][ C1] ? __rcu_read_lock+0x30/0x70 [ 15.042377][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 15.042379][ C1] ? vprintk_emit+0x320/0x3e0 [ 15.042381][ C1] vprintk_emit+0x37c/0x3e0 [ 15.042383][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 15.042385][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 15.042386][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042388][ C1] ? lock_acquire+0x13c/0x160 [ 15.042390][ C1] _printk+0xc7/0x100 [ 15.042393][ C1] ? snapshot_read.cold+0x21/0x21 [ 15.042394][ C1] ? ___ratelimit+0x4e7/0xa00 [ 15.042398][ C1] ? idr_get_free+0x830/0x830 [ 15.042399][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 15.042402][ C1] tcp_make_synack.cold+0x36/0xb6 [ 15.042406][ C1] ? tcp_send_synack+0x1570/0x1570 [ 15.042410][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042412][ C1] ? inet_csk_route_req+0x747/0xc30 [ 15.042415][ C1] tcp_v4_send_synack+0x103/0xb20 [ 15.042417][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 15.042419][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 15.042422][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.042423][ C1] ? trace_hardirqs_on+0x36/0x40 [ 15.042425][ C1] ? get_random_u16+0x740/0x740 [ 15.042427][ C1] tcp_rtx_synack+0x150/0x440 [ 15.042429][ C1] ? tcp_send_probe0+0x590/0x590 [ 15.042431][ C1] ? sched_balance_rq+0x324/0x1e30 [ 15.042433][ C1] ? do_raw_spin_lock+0x131/0x280 [ 15.042435][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 15.042437][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.042438][ C1] ? lock_acquire+0x6a/0x160 [ 15.042439][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 15.042441][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.042442][ C1] call_timer_fn+0x160/0x4d0 [ 15.042446][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 15.042447][ C1] ? debug_object_active_state+0x430/0x430 [ 15.042452][ C1] ? __rwlock_init+0x150/0x150 [ 15.042454][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 15.042456][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042460][ C1] __run_timers+0x68f/0xaa0 [ 15.042462][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 15.042464][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 15.042466][ C1] ? do_raw_spin_lock+0x131/0x280 [ 15.042469][ C1] ? __rwlock_init+0x150/0x150 [ 15.042471][ C1] run_timer_softirq+0xf0/0x160 [ 15.042473][ C1] ? __run_timers+0xaa0/0xaa0 [ 15.042475][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 15.042476][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042478][ C1] handle_softirqs+0x1d3/0x900 [ 15.042480][ C1] ? _local_bh_enable+0xc0/0xc0 [ 15.042481][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042483][ C1] ? lock_release+0x184/0x1f0 [ 15.042485][ C1] __irq_exit_rcu+0x145/0x1c0 [ 15.042486][ C1] irq_exit_rcu+0xe/0x30 [ 15.042488][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 15.042490][ C1] [ 15.042490][ C1] [ 15.042491][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 15.042493][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 15.042495][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 15.042498][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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.042499][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 15.042503][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 15.042504][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 15.042505][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 15.042505][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 15.042506][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 15.042508][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 15.042509][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.042512][ C1] default_idle+0x9/0x10 [ 15.042513][ C1] default_idle_call+0x6e/0xb0 [ 15.042514][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 15.042516][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 15.042518][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 15.042520][ C1] ? rcu_is_watching+0x16/0xd0 [ 15.042522][ C1] do_idle+0xd8/0x190 [ 15.042524][ C1] cpu_startup_entry+0x53/0x70 [ 15.042525][ C1] start_secondary+0x204/0x2b0 [ 15.042532][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 15.042534][ C1] common_startup_64+0x13e/0x148 [ 15.042539][ C1] [ 15.065191][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.067235][ C1] TCP: TCP-AO: the keyid 100 from SYN packet is not present - not sending SYNACK [ 16.067249][ C1] BUG: sleeping function called from invalid context at ./include/linux/sched/mm.h:322 [ 16.067252][ C1] in_atomic(): 1, irqs_disabled(): 1, non_block: 0, pid: 0, name: swapper/1 [ 16.067253][ C1] preempt_count: 102, expected: 0 [ 16.067254][ C1] RCU nest depth: 0, expected: 0 [ 16.067255][ C1] INFO: lockdep is turned off. [ 16.067256][ C1] irq event stamp: 327004 [ 16.067257][ C1] hardirqs last enabled at (327003): [] default_idle_call+0x98/0xb0 [ 16.067264][ C1] hardirqs last disabled at (327004): [] __schedule+0x1246/0x20e0 [ 16.067267][ C1] softirqs last enabled at (326944): [] handle_softirqs+0x67c/0x900 [ 16.067270][ C1] softirqs last disabled at (326939): [] __irq_exit_rcu+0x145/0x1c0 [ 16.067273][ C1] Preemption disabled at: [ 16.067273][ C1] [<0000000000000000>] 0x0 [ 16.067280][ C1] CPU: 1 UID: 0 PID: 0 Comm: swapper/1 Tainted: G W 7.2.0-virtme #1 PREEMPT(full) [ 16.067283][ C1] Tainted: [W]=WARN [ 16.067284][ C1] Hardware name: Bochs Bochs, BIOS Bochs 01/01/2011 [ 16.067286][ C1] Call Trace: [ 16.067288][ C1] [ 16.067290][ C1] dump_stack_lvl+0x6f/0xa0 [ 16.067295][ C1] __might_resched.cold+0x1fe/0x2c1 [ 16.067299][ C1] ? perf_trace_sched_switch+0x7c0/0x7c0 [ 16.067303][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067306][ C1] __kmalloc_noprof+0x443/0x760 [ 16.067310][ C1] ? __rwlock_init+0x150/0x150 [ 16.067313][ C1] ? alloc_buf.isra.0+0x4b/0x260 [ 16.067317][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 16.067318][ C1] alloc_buf.isra.0+0x4b/0x260 [ 16.067320][ C1] put_chars+0x1e1/0x2f0 [ 16.067322][ C1] ? __send_to_port+0x420/0x420 [ 16.067324][ C1] ? printk_get_next_message+0x240/0x650 [ 16.067327][ C1] hvc_console_print+0x292/0x780 [ 16.067331][ C1] ? hvc_write+0x3a0/0x3a0 [ 16.067332][ C1] ? __rwlock_init+0x150/0x150 [ 16.067334][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067336][ C1] ? lock_acquire+0x13c/0x160 [ 16.067338][ C1] console_emit_next_record+0x22f/0x4c0 [ 16.067340][ C1] ? devkmsg_read+0x4b0/0x4b0 [ 16.067342][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067344][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067345][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067347][ C1] ? lock_acquire+0x13c/0x160 [ 16.067349][ C1] ? console_flush_one_record+0x111/0x710 [ 16.067351][ C1] console_flush_one_record+0x46f/0x710 [ 16.067353][ C1] ? console_emit_next_record+0x4c0/0x4c0 [ 16.067355][ C1] console_unlock+0xee/0x1f0 [ 16.067357][ C1] ? lock_acquire+0x13c/0x160 [ 16.067359][ C1] ? console_flush_one_record+0x710/0x710 [ 16.067360][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067362][ C1] ? lock_release+0x184/0x1f0 [ 16.067363][ C1] ? lock_acquire+0x60/0x160 [ 16.067365][ C1] ? __rcu_read_lock+0x30/0x70 [ 16.067367][ C1] ? __down_trylock_console_sem+0x5e/0xa0 [ 16.067368][ C1] ? vprintk_emit+0x320/0x3e0 [ 16.067370][ C1] vprintk_emit+0x37c/0x3e0 [ 16.067372][ C1] ? wake_up_klogd_work_func+0x90/0x90 [ 16.067374][ C1] ? do_raw_spin_trylock+0xb6/0x180 [ 16.067376][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067378][ C1] ? lock_acquire+0x13c/0x160 [ 16.067379][ C1] _printk+0xc7/0x100 [ 16.067382][ C1] ? snapshot_read.cold+0x21/0x21 [ 16.067384][ C1] ? ___ratelimit+0x4e7/0xa00 [ 16.067387][ C1] ? trace_hardirqs_on+0x36/0x40 [ 16.067389][ C1] ? idr_get_free+0x830/0x830 [ 16.067391][ C1] ? trace_irq_enable.constprop.0+0x30/0x160 [ 16.067392][ C1] tcp_make_synack.cold+0x36/0xb6 [ 16.067397][ C1] ? tcp_send_synack+0x1570/0x1570 [ 16.067400][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067402][ C1] ? inet_csk_route_req+0x747/0xc30 [ 16.067405][ C1] tcp_v4_send_synack+0x103/0xb20 [ 16.067408][ C1] ? tcp_v4_timewait_ack+0x870/0x870 [ 16.067409][ C1] ? sched_balance_find_src_group+0xda/0x760 [ 16.067412][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.067413][ C1] ? trace_hardirqs_on+0x36/0x40 [ 16.067415][ C1] ? get_random_u16+0x740/0x740 [ 16.067417][ C1] tcp_rtx_synack+0x150/0x440 [ 16.067419][ C1] ? tcp_send_probe0+0x590/0x590 [ 16.067421][ C1] ? sched_balance_rq+0x324/0x1e30 [ 16.067423][ C1] ? do_raw_spin_lock+0x131/0x280 [ 16.067424][ C1] reqsk_timer_handler+0x8d1/0xe30 [ 16.067426][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.067428][ C1] ? lock_acquire+0x6a/0x160 [ 16.067429][ C1] ? do_raw_spin_unlock+0x59/0x250 [ 16.067431][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.067432][ C1] call_timer_fn+0x160/0x4d0 [ 16.067435][ C1] ? detach_if_pending+0x1d0/0x1d0 [ 16.067437][ C1] ? debug_object_active_state+0x430/0x430 [ 16.067441][ C1] ? __rwlock_init+0x150/0x150 [ 16.067442][ C1] ? timer_recalc_next_expiry+0xf0/0x210 [ 16.067444][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067446][ C1] __run_timers+0x68f/0xaa0 [ 16.067448][ C1] ? __inet_csk_reqsk_queue_drop+0x420/0x420 [ 16.067450][ C1] ? __bpf_trace_itimer_expire+0x10/0x10 [ 16.067452][ C1] ? do_raw_spin_lock+0x131/0x280 [ 16.067454][ C1] ? __rwlock_init+0x150/0x150 [ 16.067456][ C1] run_timer_softirq+0xf0/0x160 [ 16.067458][ C1] ? __run_timers+0xaa0/0xaa0 [ 16.067460][ C1] ? trace_irq_enable.constprop.0+0x9b/0x160 [ 16.067461][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067463][ C1] handle_softirqs+0x1d3/0x900 [ 16.067465][ C1] ? _local_bh_enable+0xc0/0xc0 [ 16.067466][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067468][ C1] ? lock_release+0x184/0x1f0 [ 16.067469][ C1] __irq_exit_rcu+0x145/0x1c0 [ 16.067471][ C1] irq_exit_rcu+0xe/0x30 [ 16.067472][ C1] sysvec_apic_timer_interrupt+0x9d/0xe0 [ 16.067474][ C1] [ 16.067474][ C1] [ 16.067475][ C1] ? _raw_spin_unlock+0x2d/0x50 [ 16.067477][ C1] asm_sysvec_apic_timer_interrupt+0x1a/0x20 [ 16.067479][ C1] RIP: 0010:pv_native_safe_halt+0xf/0x10 [ 16.067482][ C1] Code: 48 8b 3d 94 02 06 02 e8 1f 00 00 00 48 2b 05 58 03 a0 00 c3 0f 1f 80 00 00 00 00 f3 0f 1e fa eb 07 0f 00 2d 13 a6 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.067483][ C1] RSP: 0018:ffa0000000147e00 EFLAGS: 00000246 [ 16.067486][ C1] RAX: 0000000000000001 RBX: ff11000001bf2380 RCX: 0000000000000001 [ 16.067487][ C1] RDX: 0000000000000001 RSI: ffffffff9588dc60 RDI: ffffffff92efa307 [ 16.067488][ C1] RBP: 0000000000000000 R08: ffffffff9551f421 R09: 1fe2200006a563a2 [ 16.067489][ C1] R10: 0000000000000001 R11: 0000000000000000 R12: 1ff4000000028fc3 [ 16.067490][ C1] R13: 0000000000000000 R14: dffffc0000000000 R15: 0000000000000000 [ 16.067491][ C1] ? ct_kernel_exit.constprop.0+0x101/0x160 [ 16.067492][ C1] ? cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.067495][ C1] default_idle+0x9/0x10 [ 16.067496][ C1] default_idle_call+0x6e/0xb0 [ 16.067497][ C1] cpuidle_idle_call.constprop.0+0x237/0x410 [ 16.067499][ C1] ? arch_cpu_idle_exit+0x40/0x40 [ 16.067501][ C1] ? mark_tsc_async_resets+0x30/0x30 [ 16.067503][ C1] ? rcu_is_watching+0x16/0xd0 [ 16.067505][ C1] do_idle+0xd8/0x190 [ 16.067506][ C1] cpu_startup_entry+0x53/0x70 [ 16.067508][ C1] start_secondary+0x204/0x2b0 [ 16.067510][ C1] ? set_cpu_sibling_map+0x2130/0x2130 [ 16.067511][ C1] common_startup_64+0x13e/0x148 [ 16.067516][ C1]