make -C tools/testing/selftests TARGETS=net/forwarding TEST_PROGS=tc_actioons.sh TEST_GEN_PROGS="" run_tests
make: Entering directory '/home/virtme/testing-4/tools/testing/selftests'
make[1]: Entering directory '/home/virtme/testing-4/tools/testing/selftests/net/forwarding'
make[1]: Nothing to be done for 'all'.
make[1]: Leaving directory '/home/virtme/testing-4/tools/testing/selftests/net/forwarding'
make[1]: Entering directory '/home/virtme/testing-4/tools/testing/selftests/net/forwarding'
TAP version 13
1..1
# timeout set to 10800
# selftests: net/forwarding: tc_actions.sh
[ 40.566543][ T330] GACT probability NOT on
# TEST: gact drop and ok (skip_hw) [ OK ]
[ 45.764981][ T392] Mirror/redirect action on
# TEST: mirred egress flower redirect (skip_hw) [ OK ]
# TEST: mirred egress flower mirror (skip_hw) [ OK ]
# TEST: mirred egress matchall mirror (skip_hw) [ OK ]
[ 53.339300][ T473] mausezahn (473) used greatest stack depth: 24264 bytes left
[ 56.346690][ T505] ping (505) used greatest stack depth: 23480 bytes left
# TEST: mirred_egress_to_ingress (skip_hw) [ OK ]
# [ 60.734291][ C0]
[ 60.734458][ C0] ============================================
[ 60.734803][ C0] WARNING: possible recursive locking detected
[ 60.735120][ C0] 6.8.0-rc2-virtme #1 Not tainted
[ 60.735401][ C0] --------------------------------------------
[ 60.735717][ C0] ncat/548 is trying to acquire lock:
[ 60.736026][ C0] ffff888009520e30 (slock-AF_INET/1){+.-.}-{2:2}, at: tcp_v4_rcv+0x221e/0x2ac0
[ 60.736499][ C0]
[ 60.736499][ C0] but task is already holding lock:
[ 60.736877][ C0] ffff888009521b70 (slock-AF_INET/1){+.-.}-{2:2}, at: tcp_v4_rcv+0x221e/0x2ac0
[ 60.737371][ C0]
[ 60.737371][ C0] other info that might help us debug this:
[ 60.737779][ C0] Possible unsafe locking scenario:
[ 60.737779][ C0]
[ 60.738167][ C0] CPU0
[ 60.738361][ C0] ----
[ 60.738534][ C0] lock(slock-AF_INET/1);
[ 60.738778][ C0] lock(slock-AF_INET/1);
[ 60.739011][ C0]
[ 60.739011][ C0] *** DEADLOCK ***
[ 60.739011][ C0]
[ 60.739445][ C0] May be due to missing lock nesting notation
[ 60.739445][ C0]
[ 60.739897][ C0] 8 locks held by ncat/548:
[ 60.740128][ C0] #0: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: process_backlog+0x1ed/0x5e0
[ 60.740630][ C0] #1: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: ip_local_deliver_finish+0x1f5/0x480
[ 60.741152][ C0] #2: ffff888009521b70 (slock-AF_INET/1){+.-.}-{2:2}, at: tcp_v4_rcv+0x221e/0x2ac0
[ 60.741656][ C0] #3: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: __ip_queue_xmit+0x65/0x1910
[ 60.742143][ C0] #4: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: ip_finish_output2+0x262/0x18e0
[ 60.742666][ C0] #5: ffffffff96d44780 (rcu_read_lock_bh){....}-{1:2}, at: __dev_queue_xmit+0x1da/0x17e0
[ 60.743180][ C0] #6: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: netif_receive_skb_internal+0x84/0x300
[ 60.743727][ C0] #7: ffffffff96d447e0 (rcu_read_lock){....}-{1:2}, at: ip_local_deliver_finish+0x1f5/0x480
[ 60.744295][ C0]
[ 60.744295][ C0] stack backtrace:
[ 60.744639][ C0] CPU: 0 PID: 548 Comm: ncat Not tainted 6.8.0-rc2-virtme #1
[ 60.745022][ C0] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 60.745688][ C0] Call Trace:
[ 60.745869][ C0]
[ 60.746047][ C0] dump_stack_lvl+0x64/0xb0
[ 60.746314][ C0] validate_chain+0x525/0xa00
[ 60.746581][ C0] ? __pfx_validate_chain+0x10/0x10
[ 60.746857][ C0] ? hlock_class+0x4e/0x130
[ 60.747125][ C0] ? mark_lock+0x38/0x3e0
[ 60.747351][ C0] __lock_acquire+0xb67/0x1610
[ 60.747620][ C0] ? lock_downgrade+0xa0/0x110
[ 60.747905][ C0] ? mark_lock+0x38/0x3e0
[ 60.748147][ C0] lock_acquire.part.0+0xe5/0x330
[ 60.748416][ C0] ? tcp_v4_rcv+0x221e/0x2ac0
[ 60.748677][ C0] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 60.748975][ C0] ? __pfx_sk_filter_trim_cap+0x10/0x10
[ 60.749287][ C0] ? lock_acquire+0x1c1/0x220
[ 60.749536][ C0] ? tcp_v4_rcv+0x221e/0x2ac0
[ 60.749798][ C0] _raw_spin_lock_nested+0x33/0x80
[ 60.750070][ C0] ? tcp_v4_rcv+0x221e/0x2ac0
[ 60.750344][ C0] tcp_v4_rcv+0x221e/0x2ac0
[ 60.750585][ C0] ? __pfx_tcp_v4_rcv+0x10/0x10
[ 60.750846][ C0] ? __pfx_raw_v4_input+0x10/0x10
[ 60.751131][ C0] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 60.751429][ C0] ip_protocol_deliver_rcu+0x93/0x360
[ 60.751720][ C0] ip_local_deliver_finish+0x2ae/0x480
[ 60.752030][ C0] ip_local_deliver+0x19d/0x480
[ 60.752305][ C0] ? __pfx_ip_local_deliver+0x10/0x10
[ 60.752583][ C0] ? tcp_v4_early_demux+0x59a/0x9d0
[ 60.752852][ C0] ? ip_rcv_finish_core.constprop.0+0x522/0x1300
[ 60.753194][ C0] ip_rcv+0x564/0x740
[ 60.753402][ C0] ? __pfx_ip_rcv+0x10/0x10
[ 60.753655][ C0] ? lock_acquire.part.0+0xe5/0x330
[ 60.753924][ C0] ? netif_receive_skb_internal+0x84/0x300
[ 60.754246][ C0] ? __pfx_ip_rcv+0x10/0x10
[ 60.754481][ C0] __netif_receive_skb_one_core+0x166/0x1b0
[ 60.754806][ C0] ? __pfx___netif_receive_skb_one_core+0x10/0x10
[ 60.755138][ C0] ? mark_held_locks+0xa5/0xf0
[ 60.755400][ C0] ? lock_acquire+0x1c1/0x220
[ 60.755641][ C0] ? netif_receive_skb_internal+0x84/0x300
[ 60.755972][ C0] netif_receive_skb_internal+0xb0/0x300
[ 60.756291][ C0] ? __pfx_netif_receive_skb_internal+0x10/0x10
[ 60.756630][ C0] ? __copy_skb_header+0xaf/0x490
[ 60.756892][ C0] ? __skb_clone+0x57a/0x760
[ 60.757148][ C0] netif_receive_skb+0x55/0x280
[ 60.757401][ C0] tcf_mirred_to_dev+0x444/0xd70 [act_mirred]
[ 60.757732][ C0] ? __pfx_tcf_skbedit_act+0x10/0x10 [act_skbedit]
[ 60.758072][ C0] tcf_mirred_act+0x338/0x780 [act_mirred]
[ 60.758405][ C0] tcf_action_exec.part.0+0x112/0x3d0
[ 60.758686][ C0] fl_classify+0x4dc/0x650 [cls_flower]
[ 60.759001][ C0] ? __pfx_fl_classify+0x10/0x10 [cls_flower]
[ 60.759331][ C0] ? __pfx_check_irq_usage+0x10/0x10
[ 60.759603][ C0] ? __bfs+0x247/0x650
[ 60.759844][ C0] ? __pfx_hlock_conflict+0x10/0x10
[ 60.760130][ C0] ? hlock_class+0x4e/0x130
[ 60.760365][ C0] ? check_path.constprop.0+0x24/0x50
[ 60.760668][ C0] ? check_noncircular+0x14e/0x3e0
[ 60.760934][ C0] ? __pfx_check_noncircular+0x10/0x10
[ 60.761234][ C0] ? hlock_class+0x4e/0x130
[ 60.761484][ C0] ? check_prev_add+0x773/0xce0
[ 60.761753][ C0] __tcf_classify+0x329/0x7d0
[ 60.761999][ C0] tcf_classify+0x283/0x930
[ 60.762250][ C0] ? __pfx_tcf_classify+0x10/0x10
[ 60.762510][ C0] ? __dev_queue_xmit+0x1da/0x17e0
[ 60.762791][ C0] ? lock_acquire.part.0+0xe5/0x330
[ 60.763060][ C0] ? __dev_queue_xmit+0x1da/0x17e0
[ 60.763350][ C0] tc_run+0x2e4/0x5d0
[ 60.763557][ C0] ? __pfx_tc_run+0x10/0x10
[ 60.763831][ C0] ? lock_acquire+0x1c1/0x220
[ 60.764106][ C0] ? __dev_queue_xmit+0x1da/0x17e0
[ 60.764392][ C0] __dev_queue_xmit+0x686/0x17e0
[ 60.764650][ C0] ? mark_held_locks+0xa5/0xf0
[ 60.764899][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x151/0x370
[ 60.765257][ C0] ? __pfx___dev_queue_xmit+0x10/0x10
[ 60.765533][ C0] ? neigh_hh_output+0x348/0x590
[ 60.765817][ C0] ip_finish_output2+0x786/0x18e0
[ 60.766084][ C0] ? __pfx_ip_finish_output2+0x10/0x10
[ 60.766380][ C0] ? __ip_finish_output+0x10f/0x770
[ 60.766650][ C0] ip_output+0x16b/0x4f0
[ 60.766873][ C0] ? __pfx_ip_output+0x10/0x10
[ 60.767141][ C0] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 60.767433][ C0] ? ip_local_out+0x114/0x3b0
[ 60.767690][ C0] __ip_queue_xmit+0x672/0x1910
[ 60.767967][ C0] ? __create_object+0x5e/0xb0
[ 60.768240][ C0] __tcp_transmit_skb+0x22b1/0x2d20
[ 60.768514][ C0] ? __pfx___tcp_transmit_skb+0x10/0x10
[ 60.768824][ C0] ? __tcp_send_ack.part.0+0x66/0x6e0
[ 60.769101][ C0] tcp_rcv_state_process+0x7ca/0x1fe0
[ 60.769391][ C0] ? tcp_v4_rcv+0x221e/0x2ac0
[ 60.769635][ C0] ? hlock_class+0x4e/0x130
[ 60.769871][ C0] ? __lock_acquired+0x18a/0x6b0
[ 60.770145][ C0] ? __pfx_tcp_rcv_state_process+0x10/0x10
[ 60.770442][ C0] ? __pfx___lock_acquired+0x10/0x10
[ 60.770742][ C0] ? __pfx_do_raw_spin_trylock+0x10/0x10
[ 60.771035][ C0] tcp_v4_do_rcv+0x154/0x860
[ 60.771296][ C0] tcp_v4_rcv+0x2449/0x2ac0
[ 60.771533][ C0] ? __pfx_tcp_v4_rcv+0x10/0x10
[ 60.771808][ C0] ? __pfx_raw_v4_input+0x10/0x10
[ 60.772081][ C0] ? __pfx_lock_acquire.part.0+0x10/0x10
[ 60.772388][ C0] ip_protocol_deliver_rcu+0x93/0x360
[ 60.772684][ C0] ip_local_deliver_finish+0x2ae/0x480
[ 60.772966][ C0] ip_local_deliver+0x19d/0x480
[ 60.773238][ C0] ? __pfx_ip_local_deliver+0x10/0x10
[ 60.773513][ C0] ? tcp_v4_early_demux+0x59a/0x9d0
[ 60.773796][ C0] ? ip_rcv_finish_core.constprop.0+0x522/0x1300
[ 60.774133][ C0] ip_rcv+0x564/0x740
[ 60.774357][ C0] ? __pfx_ip_rcv+0x10/0x10
[ 60.774593][ C0] ? lock_acquire.part.0+0xe5/0x330
[ 60.774861][ C0] ? process_backlog+0x1ed/0x5e0
[ 60.775134][ C0] ? __pfx_ip_rcv+0x10/0x10
[ 60.775371][ C0] __netif_receive_skb_one_core+0x166/0x1b0
[ 60.775695][ C0] ? __pfx___netif_receive_skb_one_core+0x10/0x10
[ 60.776057][ C0] ? __pfx_do_raw_spin_trylock+0x10/0x10
[ 60.776364][ C0] ? lock_acquire+0x1c1/0x220
[ 60.776624][ C0] ? process_backlog+0x1ed/0x5e0
[ 60.776886][ C0] process_backlog+0xd3/0x5e0
[ 60.777148][ C0] __napi_poll.constprop.0+0xa2/0x460
[ 60.777428][ C0] net_rx_action+0x440/0xb40
[ 60.777680][ C0] ? __pfx_net_rx_action+0x10/0x10
[ 60.777947][ C0] ? kvm_clock_get_cycles+0x18/0x30
[ 60.778250][ C0] ? ktime_get+0xb3/0x190
[ 60.778479][ C0] ? hrtimer_interrupt+0x30e/0x770
[ 60.778760][ C0] ? hrtimer_interrupt+0x31a/0x770
[ 60.779025][ C0] __do_softirq+0x1b9/0x7ff
[ 60.779280][ C0] ? tcp_recvmsg+0xf8/0x4f0
[ 60.779514][ C0] do_softirq+0x4d/0xa0
[ 60.779751][ C0]
[ 60.779928][ C0]
[ 60.780096][ C0] __local_bh_enable_ip+0xf6/0x120
[ 60.780379][ C0] tcp_recvmsg+0xf8/0x4f0
[ 60.780621][ C0] ? __pfx_tcp_recvmsg+0x10/0x10
[ 60.780876][ C0] ? core_sys_select+0x620/0x710
[ 60.781150][ C0] ? __pfx_core_sys_select+0x10/0x10
[ 60.781441][ C0] inet_recvmsg+0xf9/0x2c0
[ 60.781694][ C0] ? __pfx_inet_recvmsg+0x10/0x10
[ 60.781955][ C0] ? __fget_light+0x54/0x1e0
[ 60.782210][ C0] ? sockfd_lookup_light+0x1a/0x160
[ 60.782491][ C0] __sys_recvfrom+0x2fb/0x3b0
[ 60.782751][ C0] ? __pfx___sys_recvfrom+0x10/0x10
[ 60.783026][ C0] ? __pfx___lock_release+0x10/0x10
[ 60.783322][ C0] ? do_pselect.constprop.0+0x12b/0x1e0
[ 60.783629][ C0] ? __pfx_do_pselect.constprop.0+0x10/0x10
[ 60.783960][ C0] ? restore_fpregs_from_fpstate+0x99/0x160
[ 60.784294][ C0] ? __pfx_restore_fpregs_from_fpstate+0x10/0x10
[ 60.784621][ C0] __x64_sys_recvfrom+0xe0/0x1c0
[ 60.784879][ C0] ? lockdep_hardirqs_on_prepare.part.0+0x1b1/0x370
[ 60.785233][ C0] do_syscall_64+0xc9/0x1e0
[ 60.785470][ C0] entry_SYSCALL_64_after_hwframe+0x6f/0x77
[ 60.785797][ C0] RIP: 0033:0x7fa04d2304c0
[ 60.786031][ C0] Code: 84 00 00 00 00 00 0f 1f 40 00 f3 0f 1e fa 41 89 ca 64 8b 04 25 18 00 00 00 85 c0 75 1d 45 31 c9 45 31 c0 b8 2d 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 68 c3 0f 1f 80 00 00 00 00 41 54 48 83 ec 20
[ 60.787049][ C0] RSP: 002b:00007fffea051048 EFLAGS: 00000246 ORIG_RAX: 000000000000002d
[ 60.787481][ C0] RAX: ffffffffffffffda RBX: 000055d620c43a20 RCX: 00007fa04d2304c0
[ 60.787935][ C0] RDX: 0000000000002000 RSI: 00007fffea0510d0 RDI: 0000000000000008
[ 60.788339][ C0] RBP: 00007fffea0510d0 R08: 0000000000000000 R09: 0000000000000000
[ 60.788778][ C0] R10: 0000000000000000 R11: 0000000000000246 R12: 0000000000000008
[ 60.789184][ C0] R13: 000055d620c43a20 R14: 0000000000000000 R15: 00007fffea0510cc
[ 60.789615][ C0]
[ 66.136623][ C1] irq 4: nobody cared (try booting with the "irqpoll" option)
[ 66.137038][ C1] CPU: 1 PID: 222 Comm: perl Not tainted 6.8.0-rc2-virtme #1
[ 66.137438][ C1] Hardware name: QEMU Standard PC (i440FX + PIIX, 1996), BIOS rel-1.16.3-0-ga6ed6b701f0a-prebuilt.qemu.org 04/01/2014
[ 66.138093][ C1] Call Trace:
[ 66.138275][ C1]
[ 66.138432][ C1] dump_stack_lvl+0x64/0xb0
[ 66.138685][ C1] __report_bad_irq+0x5f/0x180
[ 66.138948][ C1] note_interrupt+0x6b3/0x860
[ 66.139205][ C1] handle_irq_event+0x16d/0x1c0
[ 66.139472][ C1] handle_edge_irq+0x1fa/0xb60
[ 66.139732][ C1] __common_interrupt+0x7c/0x180
[ 66.140005][ C1] common_interrupt+0x7e/0x90
[ 66.140266][ C1]
[ 66.140427][ C1]
[ 66.140589][ C1] asm_common_interrupt+0x26/0x40
[ 66.140864][ C1] RIP: 0010:_raw_spin_unlock_irqrestore+0x43/0x70
[ 66.141259][ C1] Code: 10 e8 a1 28 a6 fd 48 89 ef e8 b9 b0 a6 fd 81 e3 00 02 00 00 75 1d 9c 58 f6 c4 02 75 29 48 85 db 74 01 fb 65 ff 0d 35 a5 77 6a <74> 0e 5b 5d c3 cc cc cc cc e8 3f 71 c8 fd eb dc 0f 1f 44 00 00 5b
[ 66.142299][ C1] RSP: 0018:ffffc90000b3fa88 EFLAGS: 00000286
[ 66.142630][ C1] RAX: 0000000000000046 RBX: 0000000000000200 RCX: 1ffffffff2edc443
[ 66.143057][ C1] RDX: 0000000000000000 RSI: 0000000000000008 RDI: ffffffff95a811a1
[ 66.143483][ C1] RBP: ffffffff99827860 R08: 0000000000000000 R09: fffffbfff2edc1fa
[ 66.143909][ C1] R10: ffffffff976e0fd7 R11: 0000000000000000 R12: 00000000000009ee
[ 66.144340][ C1] R13: ffffffff99827970 R14: ffffffff99827860 R15: ffff888005780000
[ 66.144762][ C1] ? _raw_spin_unlock_irqrestore+0x51/0x70
[ 66.145085][ C1] uart_write+0x220/0x440
[ 66.145366][ C1] process_output_block+0x13e/0x790
[ 66.145646][ C1] ? trace_irq_enable.constprop.0+0xd4/0x100
[ 66.145966][ C1] n_tty_write+0x414/0x7a0
[ 66.146207][ C1] ? __pfx_n_tty_write+0x10/0x10
[ 66.146471][ C1] ? __pfx_woken_wake_function+0x10/0x10
[ 66.146771][ C1] ? lock_acquire+0x1c1/0x220
[ 66.147023][ C1] ? iterate_tty_write+0x95/0x540
[ 66.147292][ C1] iterate_tty_write+0x229/0x540
[ 66.147557][ C1] file_tty_write.constprop.0+0x1db/0x370
[ 66.147859][ C1] vfs_write+0x9e9/0x10a0
[ 66.148093][ C1] ? __run_hrtimer+0x5df/0x710
[ 66.148345][ C1] ? __pfx_vfs_write+0x10/0x10
[ 66.148601][ C1] ? lock_acquire+0x1c1/0x220
[ 66.148852][ C1] ? clockevents_program_event+0xf6/0x300
[ 66.149157][ C1] ? kvm_clock_get_cycles+0x18/0x30
[ 66.149437][ C1] ? __fget_light+0x54/0x1e0
[ 66.149684][ C1] ksys_write+0xf5/0x1e0
[ 66.149913][ C1] ? __pfx_ksys_write+0x10/0x10
[ 66.150174][ C1] ? trace_irq_enable.constprop.0+0xd4/0x100
[ 66.150492][ C1] do_syscall_64+0xc9/0x1e0
[ 66.150738][ C1] entry_SYSCALL_64_after_hwframe+0x6f/0x77
[ 66.151056][ C1] RIP: 0033:0x7faf8bf97957
[ 66.151297][ C1] Code: 0b 00 f7 d8 64 89 02 48 c7 c0 ff ff ff ff eb b7 0f 1f 00 f3 0f 1e fa 64 8b 04 25 18 00 00 00 85 c0 75 10 b8 01 00 00 00 0f 05 <48> 3d 00 f0 ff ff 77 51 c3 48 83 ec 28 48 89 54 24 18 48 89 74 24
[ 66.152333][ C1] RSP: 002b:00007ffd3c197308 EFLAGS: 00000246 ORIG_RAX: 0000000000000001
[ 66.152783][ C1] RAX: ffffffffffffffda RBX: 000055b640b6bfb0 RCX: 00007faf8bf97957
[ 66.153220][ C1] RDX: 0000000000000001 RSI: 000055b640b6bfb0 RDI: 0000000000000001
[ 66.153649][ C1] RBP: 0000000000000001 R08: 0000000000000000 R09: 0000000000002000
[ 66.154130][ C1] R10: 0000000000000001 R11: 0000000000000246 R12: 000055b640b58530
[ 66.154549][ C1] R13: 000055b640b392a0 R14: 0000000000000001 R15: 000055b640b58530
[ 66.154983][ C1]
[ 66.155152][ C1] handlers:
[ 66.155323][ C1] [] serial8250_interrupt
[ 66.155647][ C1] Disabling IRQ #4
[ 89.691309][ T549] ncat (549) used greatest stack depth: 20784 bytes left
[ 107.831016][ T548] ncat (548) used greatest stack depth: 20656 bytes left
TEST: mirred_egress_to_ingress_tcp (skip_hw) [ OK ]
# INFO: Could not test offloaded functionality
ok 1 selftests:
WAIT TIMEOUT stdout
Ctrl-C stdout
Ctrl-C stdout
WAIT TIMEOUT stdout