Table of Contents
Introduction
As part of the collaboration between VSPERF and ETSI NFV TST working group, we developed and tested new methods to characterize the limits of data plane resources. The new methods are comprised of new search algorithm(s) to discover true resource limits (RFC 2544 Throughput, for example) while avoiding the effects of transient loss in the NFVI platform. The current search algorithm is called Binary Search with Loss Verification. A key aspect of each measurement completed during the search (a trial) is the duration of the test. The Trial Duration must be considerably less than the average time interval between transient events that cause frame loss. The details are described in ETSI GS NFV-TST 009 .
...
Loss characteristics are a topic for further investigation, and likely expansion of the ETSI NFV specification TST009, clause 11.
Next steps:
- OVS-DPDK with isolcpu rcu_nocbs
- and taskset the iPerf process to qa core with no interrupts.
iPerf3 example Output
As an example of the iPerf3 loss counting capability, we have the results of one 60 second test below (where each second would be a trial):
-----------------------------------------------------------
Server listening on 5201
...
First tests with PROX monitoring
As a first step, PROX was started on Node 4 after reboot, with nothing else running:
There is fairly regular interrupt activity in the 5-10usec bin for all cores, but nothing in the 10-50usec range after 60 sec.
Next, the Node 4 and 5 configurations above were built (after stopping PROX on Node 4, VSPERF could not install ovs-vanilla with PROX running). Once the Node 4 ovs-vanilla data path was instantiated by VSPERF, we re-started and viewed interrupt activity with PROX again (with no traffic running).
Then, the Node 5 iPerf3 traffic was started (after a date timestamp), and PROX counts were zeroed-out as the traffic began.
Recall the host config is VSWITCH_VHOST_CPU_MAP = [4,5,8,11]
and here PROX observed interrupts on NUMA 0 cores 2, 7, 14, 16, (many) 17 and 18 in the 10-50usec range. Eventually, all cores 1-15 experienced 2 events in the 10-50usec range (when PROX was allowed to run for about 30 minutes after the 60 second test).
The data plane measurements indicate one second with 45 frame losses (see the very bottom of the figure). Message logging on Nodes 4 and 5 indicated nothing during the 60 sec iPerf3 test. Below we see an except of the iPerf3 output from the Server, showing the single second with 45 packet losses.
...
[ 5] 10.00-11.00 sec 59.6 MBytes 500 Mbits/sec 0.011 ms 0/43128 (0%)
[ 5] 11.00-12.00 sec 59.8 MBytes 501 Mbits/sec 0.010 ms 0/43269 (0%)
[ 5] 12.00-13.00 sec 59.6 MBytes 500 Mbits/sec 0.008 ms 45/43220 (0.1%)
[ 5] 13.00-14.00 sec 59.5 MBytes 499 Mbits/sec 0.018 ms 0/43068 (0%)
So, we haven't yet seen correlation between PROX interrupt counts and the single loss event, but it would be good to have second by second results from PROX in a flat file, and to confirm that we have the correct cores identified in Node 4.
In a follow-up round of testing (Jan 20), we increased the iPerf3 sending rate to 750Mbps, and found good correlation between:
Losses near the beginning of the test:
[ 5] local 10.10.122.25 port 5201 connected to 10.10.124.25 port 59043
[ ID] Interval Transfer Bandwidth Jitter Lost/Total Datagrams
[ 5] 0.00-1.00 sec 81.9 MBytes 687 Mbits/sec 0.005 ms 918/60210 (1.5%)
[ 5] 1.00-2.00 sec 89.5 MBytes 751 Mbits/sec 0.011 ms 0/64798 (0%)
[ 5] 2.00-3.00 sec 87.1 MBytes 731 Mbits/sec 0.008 ms 1701/64770 (2.6%)
[ 5] 3.00-4.00 sec 88.2 MBytes 740 Mbits/sec 0.008 ms 891/64735 (1.4%)
[ 5] 4.00-5.00 sec 88.4 MBytes 741 Mbits/sec 0.007 ms 888/64883 (1.4%)
[ 5] 5.00-6.00 sec 89.5 MBytes 751 Mbits/sec 0.008 ms 41/64869 (0.063%)
[ 5] 6.00-7.00 sec 89.2 MBytes 748 Mbits/sec 0.006 ms 16/64618 (0.025%)
[ 5] 7.00-8.00 sec 88.2 MBytes 740 Mbits/sec 0.007 ms 1427/65283 (2.2%)
[ 5] 8.00-9.00 sec 90.5 MBytes 759 Mbits/sec 0.005 ms 0/65554 (0%)
... remainder of the test had 1 second with 3 losses.
Node 4 log entries (much Network Manager activity)
Jan 20 10:40:01 pod12-node4 systemd: Removed slice User Slice of root.
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5382] policy: auto-activating connection 'Wired connection 1' (f7b61226-727b-39a3-ba0e-c5eecae22c32)
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5387] policy: auto-activating connection 'Wired connection 2' (9fd53d60-d93d-354d-8132-e97e39496541)
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5394] device (ens801f2): Activation: starting connection 'Wired connection 1' (f7b61226-727b-39a3-ba0e-c5eecae22c32)
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5397] device (ens801f3): Activation: starting connection 'Wired connection 2' (9fd53d60-d93d-354d-8132-e97e39496541)
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5398] device (ens801f2): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5404] device (ens801f3): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5409] device (ens801f2): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Jan 20 10:43:43 pod12-node4 NetworkManager[13205]: <info> [1548009823.5415] device (ens801f3): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
and PROX interrupts on Core 5 (two observed over the 60 second test)
Next steps:
- Obtain second-by-second output from PROX, and confirm Core occupation of OVS-Vanilla, etc.
- OVS-DPDK with isolcpu rcu_nocbs
- and taskset the iPerf process to qa core with no interrupts.
iPerf3 example Output
As an example of the iPerf3 loss counting capability, we have the results of one 60 second test below (where each second would be a trial):
-----------------------------------------------------------
Server listening on 5201
------------------------------------------------------------
Accepted connection from 10.10.124.25, port 39851
[ 5] local 10.10.122.25 port 5201 connected to 10.10.124.25 port 35449
[ ID] Interval Transfer Bandwidth Jitter Lost/Total Datagrams
------------------------
Accepted connection from 10.10.124.25, port 39851
[ 5] local 10.10.122.25 port 5201 connected to 10.10.124.25 port 35449
[ ID] Interval Transfer Bandwidth Jitter Lost/Total Datagrams
[ 5] 0.00-1.00 sec 55.7 MBytes 467 Mbits/sec 0.004 ms 0/40351 (0%)
[ 5] 1.00-2.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43161 (0%)
[ 5] 2.00-3.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43152 (0%)
[ 5] 3.00-4.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43166 (0%)
[ 5] 4.00-5.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43139 (0%)
[ 5] 5.00-6.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43181 (0%)
[ 5] 06.00-17.00 sec 5559.7 6 MBytes 467 500 Mbits/sec 0.004 005 ms 0/40351 43157 (0%)
[ 5] 17.00-28.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43161 43172 (0%)
[ 5]
28.00-
39.00 sec 59.6 MBytes 500 Mbits/sec 0.
004 006 ms
046/
43152 43211 (
0%0.11%)
[ 5] 39.00-410.00 00 sec 59.6 MBytes 500 Mbits/sec 0.005 006 ms 0/43166 43128 (0%)
[ 5]
4 10.00-
511.
00 00 sec 59.
6 5 MBytes
500 499 Mbits/sec 0.005 ms
0124/
43139 43196 (
0%0.29%)
[ 5] 5 11.00-612.00 00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43181 43126 (0%)
[ 5] 6 12.00-713.00 00 sec 59.6 7 MBytes 500 501 Mbits/sec 0.005 011 ms 0/43157 43214 (0%)
[ 5] 7 13.00-814.00 00 sec 59.6 5 MBytes 500 499 Mbits/sec 0.004 ms 0/43172 43106 (0%)
[ 5]
8 14.00-
915.
00 00 sec 59.
6 7 MBytes 500 Mbits/sec 0.
006 005 ms
4632/
43211 43237 (0.
11%074%)
[ 5] 9 15.00-1016.00 sec 59.6 5 MBytes 500 499 Mbits/sec 0.006 005 ms 0/43128 43095 (0%)
[ 5] 1016.00-1117.00 sec 59.5 7 MBytes 499 501 Mbits/sec 0.005 ms 1240/43196 43210 (0.29%0%)
[ 5] 1117.00-1218.00 sec 59.6 MBytes 500 Mbits/sec 0.004 006 ms 0/43126 43127 (0%)
[ 5] 1218.00-1319.00 sec 59.7 6 MBytes 501 500 Mbits/sec 0.011 004 ms 0/43214 43189 (0%)
[ 5] 1319.00-1420.00 sec 59.5 6 MBytes 499 500 Mbits/sec 0.004 ms 0/43106 43140 (0%)
[ 5] 1420.00-1521.00 sec 59.7 6 MBytes 500 Mbits/sec 0.005 ms 320/43237 43158 (0.074%0%)
[ 5] 1521.00-1622.00 sec 59.5 6 MBytes 499 500 Mbits/sec 0.005 ms 0/43095 43142 (0%)
[ 5] 1622.00-1723.00 sec 59.7 6 MBytes 501 500 Mbits/sec 0.005 004 ms 0/43210 43185 (0%)
[ 5] 1723.00-1824.00 sec 59.6 MBytes 500 Mbits/sec 0.006 ms 0/43127 43173 (0%)
[ 5] 1824.00-1925.00 sec 59.6 MBytes 500 Mbits/sec 0.004 006 ms 0/43189 43159 (0%)
[ 5] 1925.00-2026.00 sec 59.6 MBytes 500 Mbits/sec 0.004 006 ms 0/43140 43195 (0%)
[ 5] 2026.00-2127.00 sec 59.6 MBytes 500 Mbits/sec 0.005 006 ms 0/43158 43152 (0%)
[ 5] 2127.00-2228.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43142 43155 (0%)
[ 5] 2228.00-2329.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43185 43143 (0%)
[ 5] 2329.00-2430.00 sec 59.6 MBytes 500 Mbits/sec 0.006 005 ms 0/43173 43160 (0%)
[ 5] 2430.00-2531.00 sec 59.6 MBytes 500 Mbits/sec 0.006 004 ms 0/43159 43141 (0%)
[ 5] 2531.00-2632.00 sec 59.6 MBytes 500 Mbits/sec 0.006 005 ms 0/43195 43172 (0%)
[ 5]
2632.00-
2733.00 sec 59.6 MBytes 500 Mbits/sec 0.
006 004 ms
019/
43152 43178 (
0%0.044%)
[ 5] 2733.00-2834.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43155 43166 (0%)
[ 5]
2834.00-
2935.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms
07/
43143 43155 (
0%0.016%)
[ 5] 2935.00-3036.00 sec 59.6 MBytes 500 Mbits/sec 0.005 004 ms 0/43160 43171 (0%)
[ 5] 3036.00-3137.00 sec 59.6 MBytes 500 Mbits/sec 0.004 005 ms 0/43141 43131 (0%)
[ 5] 3137.00-3238.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43172 43168 (0%)
[ 5] 3238.00-3339.00 sec 59.6 MBytes 500 Mbits/sec 0.004 005 ms 190/43178 43185 (0.044%0%)
[ 5] 3339.00-3440.00 sec 59.6 MBytes 500 Mbits/sec 0.005 004 ms 0/43166 43169 (0%)
[ 5] 3440.00-3541.00 sec 59.6 MBytes 500 Mbits/sec 0.004 005 ms 70/43155 43160 (0.016%0%)
[ 5] 3541.00-3642.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43171 43148 (0%)
[ 5] 3642.00-3743.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43131 43162 (0%)
[ 5] 3743.00-3844.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43168 43176 (0%)
[ 5] 3844.00-3945.00 sec 59.6 MBytes 500 Mbits/sec 0.005 004 ms 0/43185 43178 (0%)
[ 5] 3945.00-4046.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43169 43141 (0%)
[ 5] 4046.00-4147.00 sec 59.6 MBytes 500 Mbits/sec 0.005 006 ms 0/43160 43156 (0%)
[ 5] 4147.00-4248.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43148 43189 (0%)
[ 5] 4248.00-4349.00 sec 59.6 MBytes 500 Mbits/sec 0.005 004 ms 0/43162 43142 (0%)
[ 5] 4349.00-4450.00 sec 59.6 MBytes 500 Mbits/sec 0.005 ms 0/43176 43153 (0%)
[ 5]
4450.00-
4551.00 sec 59.
6 5 MBytes
500 499 Mbits/sec 0.
004 005 ms
045/
43178 43150 (
0%0.1%)
[ 5] 4551.00-4652.00 sec 59.6 7 MBytes 500 501 Mbits/sec 0.004 005 ms 0/43141 43210 (0%)
[ 5] 4652.00-4753.00 sec 59.6 MBytes 500 Mbits/sec 0.006 005 ms 0/43156 43168 (0%)
[ 5] 4753.00-4854.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43189 43158 (0%)
[ 5] 4854.00-4955.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43142 43160 (0%)
[ 5] 4955.00-5056.00 sec 59.6 MBytes 500 Mbits/sec 0.005 004 ms 0/43153 43177 (0%)
[ 5] 5056.00-5157.00 sec 59.5 6 MBytes 499 500 Mbits/sec 0.005 004 ms 450/43150 (0.1%0%)
[ 5] 5157.00-5258.00 sec 59.7 6 MBytes 501 500 Mbits/sec 0.005 004 ms 0/43210 43152 (0%)
[ 5] 5258.00-5359.00 sec 59.6 5 MBytes 500 499 Mbits/sec 0.005 003 ms 0/43168 43055 (0%)
[ 5] 5359.00-5460.00 sec 59.6 7 MBytes 500 501 Mbits/sec 0.004 ms 0/43158 43267 (0%)
[ 5] 5460.00-5560.00 sec 59.6 MBytes 500 Mbits04 sec 0.00 Bytes 0.00 bits/sec 0.004 ms 0/43160 (0%)0/0 (0%)
- - - - - - - - - - - - - - - - - - - - - - - - -
[ ID] Interval Transfer Bandwidth Jitter Lost/Total Datagrams
[ 5] 55 0.00-5660.00 sec 59.6 MBytes 500 Mbits04 sec 0.00 Bytes 0.00 bits/sec 0.004 ms 0273/43177 2586968 (0%)
[ 5] 56.00-57.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43150 (0%)
[ 5] 57.00-58.00 sec 59.6 MBytes 500 Mbits/sec 0.004 ms 0/43152 (0%)
[ 5] 58.00-59.00 sec 59.5 MBytes 499 Mbits/sec 0.003 ms 0/43055 (0%)
[ 5] 59.00-60.00 sec 59.7 MBytes 501 Mbits/sec 0.004 ms 0/43267 (0%)
[ 5] 60.00-60.04 sec 0.00 Bytes 0.00 bits/sec 0.004 ms 0/0 (0%)
- - - - - - - - - - - - - - - - - - - - - - - - -
[ ID] Interval Transfer Bandwidth Jitter Lost/Total Datagrams
[ 5] 0.00-60.04 sec 0.00 Bytes 0.00 bits/sec 0.004 ms 273/2586968 (0.011%)
Related papers:
https://www.net.in.tum.de/fileadmin/bibtex/publications/papers/SPECTS15NAPIoptimization.pdf
https://www.net.in.tum.de/fileadmin/bibtex/publications/papers/NetSys2015.pdf
...
0.011%)
This particular test exhibited 6 seconds (Trials) with loss during a 60 second Test, or one loss event every 10 seconds on average. However, many of the Trials with loss occur in a pattern of Loss, no-Loss, Loss over three consecutive Trials. Loss-free intervals were 9, 15, 17, 3, and 8 seconds in length. This testing was conducted at about 70% of the RFC2544 Throughput level.
Related papers:
https://www.net.in.tum.de/fileadmin/bibtex/publications/papers/SPECTS15NAPIoptimization.pdf
https://www.net.in.tum.de/fileadmin/bibtex/publications/papers/NetSys2015.pdf
PROX config:
cd ./samplevnf/VNFs/DPPD-PROX/build
export RTE_SDK='/home/opnfv/dpdk/build'
export RTE_SDK='/home/opnfv/dpdk'
export RTE_TARGET=build
and
sudo ./prox -k -f ../config/irq.cfg
-=-=-=-=-=-
./samplevnf/VNFs/DPPD-PROX/config/irq.cfg
...