andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 1 | #TopoPerfNext |
| 2 | # |
| 3 | #Topology Performance test for ONOS-next |
| 4 | # |
| 5 | #andrew@onlab.us |
| 6 | |
| 7 | import time |
| 8 | import sys |
| 9 | import os |
| 10 | import re |
| 11 | |
| 12 | class TopoPerfNext: |
| 13 | def __init__(self): |
| 14 | self.default = '' |
| 15 | |
| 16 | def CASE1(self, main): |
| 17 | ''' |
| 18 | ONOS startup sequence |
| 19 | ''' |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 20 | import time |
| 21 | |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 22 | cell_name = main.params['ENV']['cellName'] |
| 23 | |
| 24 | git_pull = main.params['GIT']['autoPull'] |
| 25 | checkout_branch = main.params['GIT']['checkout'] |
| 26 | |
| 27 | ONOS1_ip = main.params['CTRL']['ip1'] |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 28 | ONOS2_ip = main.params['CTRL']['ip2'] |
| 29 | ONOS3_ip = main.params['CTRL']['ip3'] |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 30 | MN1_ip = main.params['MN']['ip1'] |
| 31 | BENCH_ip = main.params['BENCH']['ip'] |
| 32 | |
| 33 | main.case("Setting up test environment") |
| 34 | |
| 35 | main.step("Creating cell file") |
| 36 | cell_file_result = main.ONOSbench.create_cell_file( |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 37 | BENCH_ip, cell_name, MN1_ip, "onos-core", |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 38 | ONOS1_ip, ONOS2_ip, ONOS3_ip) |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 39 | |
| 40 | main.step("Applying cell file to environment") |
| 41 | cell_apply_result = main.ONOSbench.set_cell(cell_name) |
| 42 | verify_cell_result = main.ONOSbench.verify_cell() |
| 43 | |
| 44 | main.step("Git checkout and pull "+checkout_branch) |
| 45 | if git_pull == 'on': |
| 46 | checkout_result = \ |
| 47 | main.ONOSbench.git_checkout(checkout_branch) |
| 48 | pull_result = main.ONOSbench.git_pull() |
| 49 | else: |
| 50 | checkout_result = main.TRUE |
| 51 | pull_result = main.TRUE |
| 52 | main.log.info("Skipped git checkout and pull") |
| 53 | |
| 54 | main.step("Using mvn clean & install") |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 55 | #mvn_result = main.ONOSbench.clean_install() |
| 56 | mvn_result = main.TRUE |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 57 | |
| 58 | main.step("Creating ONOS package") |
| 59 | package_result = main.ONOSbench.onos_package() |
| 60 | |
| 61 | main.step("Installing ONOS package") |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 62 | install1_result = main.ONOSbench.onos_install(node=ONOS1_ip) |
| 63 | install2_result = main.ONOSbench.onos_install(node=ONOS2_ip) |
| 64 | install3_result = main.ONOSbench.onos_install(node=ONOS3_ip) |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 65 | |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 66 | #NOTE: This step may be unnecessary |
| 67 | #main.step("Starting ONOS service") |
| 68 | #start_result = main.ONOSbench.onos_start(ONOS1_ip) |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 69 | |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 70 | main.step("Set cell for ONOS cli env") |
| 71 | main.ONOS1cli.set_cell(cell_name) |
| 72 | main.ONOS2cli.set_cell(cell_name) |
| 73 | main.ONOS3cli.set_cell(cell_name) |
| 74 | |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 75 | time.sleep(10) |
| 76 | |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 77 | main.step("Start onos cli") |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 78 | cli1 = main.ONOS1cli.start_onos_cli(ONOS1_ip) |
| 79 | cli2 = main.ONOS2cli.start_onos_cli(ONOS2_ip) |
| 80 | cli3 = main.ONOS3cli.start_onos_cli(ONOS3_ip) |
| 81 | |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 82 | main.step("Enable metrics feature") |
| 83 | main.ONOS1cli.feature_install("onos-app-metrics-topology") |
| 84 | main.ONOS2cli.feature_install("onos-app-metrics-topology") |
| 85 | main.ONOS3cli.feature_install("onos-app-metrics-topology") |
| 86 | |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 87 | utilities.assert_equals(expect=main.TRUE, |
| 88 | actual= cell_file_result and cell_apply_result and\ |
| 89 | verify_cell_result and checkout_result and\ |
| 90 | pull_result and mvn_result and\ |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 91 | install1_result and install2_result and\ |
| 92 | install3_result, |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 93 | onpass="ONOS started successfully", |
| 94 | onfail="Failed to start ONOS") |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 95 | |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 96 | def CASE2(self, main): |
| 97 | ''' |
| 98 | Assign s1 to ONOS1 and measure latency |
andrewonlab | 3a7c3c7 | 2014-10-24 17:21:03 -0400 | [diff] [blame^] | 99 | |
| 100 | There are 4 levels of latency measurements to this test: |
| 101 | 1) End-to-end measurement: Complete end-to-end measurement |
| 102 | from TCP (SYN/ACK) handshake to Graph change |
| 103 | 2) OFP-to-graph measurement: 'ONOS processing' snippet of |
| 104 | measurement from OFP Vendor message to Graph change |
| 105 | 3) OFP-to-device measurement: 'ONOS processing without |
| 106 | graph change' snippet of measurement from OFP vendor |
| 107 | message to Device change timestamp |
| 108 | 4) T0-to-device measurement: Measurement that includes |
| 109 | the switch handshake to devices timestamp without |
| 110 | the graph view change. (TCP handshake -> Device |
| 111 | change) |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 112 | ''' |
| 113 | import time |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 114 | import subprocess |
| 115 | import json |
| 116 | import requests |
| 117 | import os |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 118 | |
| 119 | ONOS1_ip = main.params['CTRL']['ip1'] |
| 120 | ONOS2_ip = main.params['CTRL']['ip2'] |
| 121 | ONOS3_ip = main.params['CTRL']['ip3'] |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 122 | ONOS_user = main.params['CTRL']['user'] |
| 123 | |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 124 | default_sw_port = main.params['CTRL']['port1'] |
| 125 | |
| 126 | #Number of iterations of case |
| 127 | num_iter = main.params['TEST']['numIter'] |
| 128 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 129 | #Timestamp 'keys' for json metrics output. |
| 130 | #These are subject to change, hence moved into params |
| 131 | deviceTimestamp = main.params['JSON']['deviceTimestamp'] |
| 132 | graphTimestamp = main.params['JSON']['graphTimestamp'] |
| 133 | |
| 134 | #List of switch add latency collected from |
| 135 | #all iterations |
| 136 | latency_end_to_end_list = [] |
| 137 | latency_ofp_to_graph_list = [] |
| 138 | latency_ofp_to_device_list = [] |
| 139 | latency_t0_to_device_list = [] |
| 140 | |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 141 | #Directory/file to store tshark results |
| 142 | tshark_of_output = "/tmp/tshark_of_topo.txt" |
| 143 | tshark_tcp_output = "/tmp/tshark_tcp_topo.txt" |
| 144 | |
| 145 | #String to grep in tshark output |
| 146 | tshark_tcp_string = "TCP 74 "+default_sw_port |
| 147 | tshark_of_string = "OFP 86 Vendor" |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 148 | |
| 149 | #Initialize assertion to TRUE |
| 150 | assertion = main.TRUE |
| 151 | |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 152 | main.log.report("Latency of adding one switch") |
| 153 | |
| 154 | for i in range(0, int(num_iter)): |
| 155 | main.log.info("Starting tshark capture") |
| 156 | |
| 157 | #* TCP [ACK, SYN] is used as t0_a, the |
| 158 | # very first "exchange" between ONOS and |
| 159 | # the switch for end-to-end measurement |
| 160 | #* OFP [Stats Reply] is used for t0_b |
| 161 | # the very last OFP message between ONOS |
| 162 | # and the switch for ONOS measurement |
| 163 | main.ONOS1.tshark_grep(tshark_tcp_string, |
| 164 | tshark_tcp_output) |
| 165 | main.ONOS1.tshark_grep(tshark_of_string, |
| 166 | tshark_of_output) |
| 167 | |
| 168 | #Wait and ensure tshark is started and |
| 169 | #capturing |
| 170 | time.sleep(10) |
| 171 | |
| 172 | main.log.info("Assigning s1 to controller") |
| 173 | |
| 174 | main.Mininet1.assign_sw_controller(sw="1", |
| 175 | ip1=ONOS1_ip, port1=default_sw_port) |
| 176 | |
| 177 | #Wait and ensure switch is assigned |
| 178 | #before stopping tshark |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 179 | time.sleep(30) |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 180 | |
| 181 | main.log.info("Stopping all Tshark processes") |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 182 | main.ONOS1.stop_tshark() |
| 183 | |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 184 | #tshark output is saved in ONOS. Use subprocess |
| 185 | #to copy over files to TestON for parsing |
| 186 | main.log.info("Copying over tshark files") |
| 187 | |
| 188 | #TCP CAPTURE **** |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 189 | #Copy the tshark output from ONOS machine to |
| 190 | #TestON machine in tshark_tcp_output directory>file |
| 191 | os.system("scp "+ONOS_user+"@"+ONOS1_ip+":"+ |
| 192 | tshark_tcp_output+" /tmp/") |
| 193 | tcp_file = open(tshark_tcp_output, 'r') |
| 194 | temp_text = tcp_file.readline() |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 195 | temp_text = temp_text.split(" ") |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 196 | |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 197 | main.log.info("Object read in from TCP capture: "+ |
| 198 | str(temp_text)) |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 199 | if len(temp_text) > 1: |
andrewonlab | 3a7c3c7 | 2014-10-24 17:21:03 -0400 | [diff] [blame^] | 200 | t0_tcp = float(temp_text[1])*1000.0 |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 201 | else: |
| 202 | main.log.error("Tshark output file for TCP"+ |
| 203 | " returned unexpected results") |
| 204 | t0_tcp = 0 |
| 205 | assertion = main.FALSE |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 206 | |
| 207 | tcp_file.close() |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 208 | #**************** |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 209 | |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 210 | #OF CAPTURE **** |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 211 | os.system("scp "+ONOS_user+"@"+ONOS1_ip+":"+ |
| 212 | tshark_of_output+" /tmp/") |
| 213 | of_file = open(tshark_of_output, 'r') |
| 214 | |
| 215 | line_ofp = "" |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 216 | #Read until last line of file |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 217 | while True: |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 218 | temp_text = of_file.readline() |
| 219 | if temp_text !='': |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 220 | line_ofp = temp_text |
| 221 | else: |
| 222 | break |
| 223 | obj = line_ofp.split(" ") |
| 224 | |
| 225 | main.log.info("Object read in from OFP capture: "+ |
| 226 | str(line_ofp)) |
| 227 | |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 228 | if len(line_ofp) > 1: |
andrewonlab | 3a7c3c7 | 2014-10-24 17:21:03 -0400 | [diff] [blame^] | 229 | t0_ofp = float(obj[1])*1000.0 |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 230 | else: |
| 231 | main.log.error("Tshark output file for OFP"+ |
| 232 | " returned unexpected results") |
| 233 | t0_ofp = 0 |
| 234 | assertion = main.FALSE |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 235 | |
| 236 | of_file.close() |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 237 | #**************** |
| 238 | |
| 239 | #TODO: |
| 240 | #Get json object from all 3 ONOS instances |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 241 | json_str_1 = main.ONOS1cli.topology_events_metrics() |
| 242 | json_str_2 = main.ONOS2cli.topology_events_metrics() |
| 243 | json_str_3 = main.ONOS3cli.topology_events_metrics() |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 244 | |
| 245 | json_obj_1 = json.loads(json_str_1) |
| 246 | json_obj_2 = json.loads(json_str_2) |
| 247 | json_obj_3 = json.loads(json_str_3) |
| 248 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 249 | #Obtain graph timestamp. This timestsamp captures |
| 250 | #the epoch time at which the topology graph was updated. |
| 251 | graph_timestamp_1 = \ |
| 252 | json_obj_1[graphTimestamp]['value'] |
| 253 | graph_timestamp_2 = \ |
| 254 | json_obj_2[graphTimestamp]['value'] |
| 255 | graph_timestamp_3 = \ |
| 256 | json_obj_3[graphTimestamp]['value'] |
andrewonlab | 867212a | 2014-10-22 20:13:38 -0400 | [diff] [blame] | 257 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 258 | #Obtain device timestamp. This timestamp captures |
| 259 | #the epoch time at which the device event happened |
| 260 | device_timestamp_1 = \ |
| 261 | json_obj_1[deviceTimestamp]['value'] |
| 262 | device_timestamp_2 = \ |
| 263 | json_obj_2[deviceTimestamp]['value'] |
| 264 | device_timestamp_3 = \ |
| 265 | json_obj_3[deviceTimestamp]['value'] |
andrewonlab | e9fb672 | 2014-10-24 12:20:35 -0400 | [diff] [blame] | 266 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 267 | #t0 to device processing latency |
| 268 | delta_device_1 = int(device_timestamp_1) - int(t0_tcp) |
| 269 | delta_device_2 = int(device_timestamp_2) - int(t0_tcp) |
| 270 | delta_device_3 = int(device_timestamp_3) - int(t0_tcp) |
| 271 | |
| 272 | #Get average of delta from all instances |
| 273 | avg_delta_device = \ |
| 274 | (int(delta_device_1)+\ |
| 275 | int(delta_device_2)+\ |
| 276 | int(delta_device_3)) / 3 |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 277 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 278 | #Ensure avg delta meets the threshold before appending |
| 279 | if avg_delta_device > 0.0 and avg_delta_device < 10000: |
| 280 | latency_t0_to_device_list.append(avg_delta_device) |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 281 | else: |
| 282 | main.log.info("Results ignored due to excess in "+\ |
| 283 | "threshold") |
| 284 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 285 | #t0 to graph processing latency (end-to-end) |
| 286 | delta_graph_1 = int(graph_timestamp_1) - int(t0_tcp) |
| 287 | delta_graph_2 = int(graph_timestamp_2) - int(t0_tcp) |
| 288 | delta_graph_3 = int(graph_timestamp_3) - int(t0_tcp) |
| 289 | |
| 290 | #Get average of delta from all instances |
| 291 | avg_delta_graph = \ |
| 292 | (int(delta_graph_1)+\ |
| 293 | int(delta_graph_2)+\ |
| 294 | int(delta_graph_3)) / 3 |
| 295 | |
| 296 | latency_end_to_end_list.append(avg_delta_graph) |
| 297 | |
| 298 | #Ensure avg delta meets the threshold before appending |
| 299 | if avg_delta_graph > 0.0 and avg_delta_graph < 10000: |
| 300 | latency_t0_to_device_list.append(avg_delta_graph) |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 301 | else: |
| 302 | main.log.info("Results ignored due to excess in "+\ |
| 303 | "threshold") |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 304 | |
| 305 | #ofp to graph processing latency (ONOS processing) |
| 306 | delta_ofp_graph_1 = int(graph_timestamp_1) - int(t0_ofp) |
| 307 | delta_ofp_graph_2 = int(graph_timestamp_2) - int(t0_ofp) |
| 308 | delta_ofp_graph_3 = int(graph_timestamp_3) - int(t0_ofp) |
| 309 | |
| 310 | avg_delta_ofp_graph = \ |
| 311 | (int(delta_ofp_graph_1)+\ |
| 312 | int(delta_ofp_graph_2)+\ |
| 313 | int(delta_ofp_graph_3)) / 3 |
| 314 | |
| 315 | if avg_delta_ofp_graph > 0.0 and avg_delta_ofp_graph < 10000: |
| 316 | latency_ofp_to_graph_list.append(avg_delta_ofp_graph) |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 317 | else: |
| 318 | main.log.info("Results ignored due to excess in "+\ |
| 319 | "threshold") |
| 320 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 321 | #ofp to device processing latency (ONOS processing) |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 322 | delta_ofp_device_1 = float(device_timestamp_1) - float(t0_ofp) |
| 323 | delta_ofp_device_2 = float(device_timestamp_2) - float(t0_ofp) |
| 324 | delta_ofp_device_3 = float(device_timestamp_3) - float(t0_ofp) |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 325 | |
| 326 | avg_delta_ofp_device = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 327 | (float(delta_ofp_device_1)+\ |
| 328 | float(delta_ofp_device_2)+\ |
| 329 | float(delta_ofp_device_3)) / 3.0 |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 330 | |
andrewonlab | 3a7c3c7 | 2014-10-24 17:21:03 -0400 | [diff] [blame^] | 331 | #NOTE: ofp - delta measurements are occasionally negative. |
| 332 | # consider changing or purging the measurement |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 333 | |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 334 | #TODO: |
| 335 | #Fetch logs upon threshold excess |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 336 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 337 | main.log.info("ONOS1 delta end-to-end: "+ |
| 338 | str(delta_graph_1)) |
| 339 | main.log.info("ONOS2 delta end-to-end: "+ |
| 340 | str(delta_graph_2)) |
| 341 | main.log.info("ONSO3 delta end-to-end: "+ |
| 342 | str(delta_graph_3)) |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 343 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 344 | main.log.info("ONOS1 delta OFP - graph: "+ |
| 345 | str(delta_ofp_graph_1)) |
| 346 | main.log.info("ONOS2 delta OFP - graph: "+ |
| 347 | str(delta_ofp_graph_2)) |
| 348 | main.log.info("ONOS3 delta OFP - graph: "+ |
| 349 | str(delta_ofp_graph_3)) |
andrewonlab | e674534 | 2014-10-17 14:29:13 -0400 | [diff] [blame] | 350 | |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 351 | main.log.info("ONOS1 delta device - t0: "+ |
| 352 | str(delta_device_1)) |
| 353 | main.log.info("ONOS2 delta device - t0: "+ |
| 354 | str(delta_device_2)) |
| 355 | main.log.info("ONOS3 delta device - t0: "+ |
| 356 | str(delta_device_3)) |
| 357 | |
| 358 | main.log.info("ONOS1 delta OFP - device: "+ |
| 359 | str(delta_ofp_device_1)) |
| 360 | main.log.info("ONOS2 delta OFP - device: "+ |
| 361 | str(delta_ofp_device_2)) |
| 362 | main.log.info("ONOS3 delta OFP - device: "+ |
| 363 | str(delta_ofp_device_3)) |
| 364 | |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 365 | main.step("Remove switch from controller") |
| 366 | main.Mininet1.delete_sw_controller("s1") |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 367 | |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 368 | time.sleep(5) |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 369 | |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 370 | #If there is at least 1 element in each list, |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 371 | #pass the test case |
| 372 | if len(latency_end_to_end_list) > 0 and\ |
| 373 | len(latency_ofp_to_graph_list) > 0 and\ |
| 374 | len(latency_ofp_to_device_list) > 0 and\ |
| 375 | len(latency_t0_to_device_list) > 0: |
| 376 | assertion = main.TRUE |
| 377 | |
| 378 | #Calculate min, max, avg of latency lists |
| 379 | latency_end_to_end_max = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 380 | int(max(latency_end_to_end_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 381 | latency_end_to_end_min = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 382 | int(min(latency_end_to_end_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 383 | latency_end_to_end_avg = \ |
andrewonlab | c90667c | 2014-10-24 16:48:28 -0400 | [diff] [blame] | 384 | (int(sum(latency_end_to_end_list)) / \ |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 385 | len(latency_end_to_end_list)) |
| 386 | |
| 387 | latency_ofp_to_graph_max = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 388 | int(max(latency_ofp_to_graph_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 389 | latency_ofp_to_graph_min = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 390 | int(min(latency_ofp_to_graph_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 391 | latency_ofp_to_graph_avg = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 392 | (int(sum(latency_ofp_to_graph_list)) / \ |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 393 | len(latency_ofp_to_graph_list)) |
| 394 | |
| 395 | latency_ofp_to_device_max = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 396 | int(max(latency_ofp_to_device_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 397 | latency_ofp_to_device_min = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 398 | int(min(latency_ofp_to_device_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 399 | latency_ofp_to_device_avg = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 400 | (int(sum(latency_ofp_to_device_list)) / \ |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 401 | len(latency_ofp_to_device_list)) |
| 402 | |
| 403 | latency_t0_to_device_max = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 404 | float(max(latency_t0_to_device_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 405 | latency_t0_to_device_min = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 406 | float(min(latency_t0_to_device_list)) |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 407 | latency_t0_to_device_avg = \ |
andrewonlab | ee4efeb | 2014-10-24 16:44:51 -0400 | [diff] [blame] | 408 | (float(sum(latency_t0_to_device_list)) / \ |
andrewonlab | c15c958 | 2014-10-24 16:35:52 -0400 | [diff] [blame] | 409 | len(latency_ofp_to_device_list)) |
| 410 | |
| 411 | main.log.report("Switch add - End-to-end latency: \n"+\ |
| 412 | "Min: "+str(latency_end_to_end_min)+"\n"+\ |
| 413 | "Max: "+str(latency_end_to_end_max)+"\n"+\ |
| 414 | "Avg: "+str(latency_end_to_end_avg)) |
| 415 | main.log.report("Switch add - OFP-to-Graph latency: \n"+\ |
| 416 | "Min: "+str(latency_ofp_to_graph_min)+"\n"+\ |
| 417 | "Max: "+str(latency_ofp_to_graph_max)+"\n"+\ |
| 418 | "Avg: "+str(latency_ofp_to_graph_avg)) |
| 419 | main.log.report("Switch add - OFP-to-Device latency: \n"+\ |
| 420 | "Min: "+str(latency_ofp_to_device_min)+"\n"+\ |
| 421 | "Max: "+str(latency_ofp_to_device_max)+"\n"+\ |
| 422 | "Avg: "+str(latency_ofp_to_device_avg)) |
| 423 | main.log.report("Switch add - t0-to-Device latency: \n"+\ |
| 424 | "Min: "+str(latency_t0_to_device_min)+"\n"+\ |
| 425 | "Max: "+str(latency_t0_to_device_max)+"\n"+\ |
| 426 | "Avg: "+str(latency_t0_to_device_avg)) |
andrewonlab | 226024e | 2014-10-24 16:01:32 -0400 | [diff] [blame] | 427 | |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 428 | utilities.assert_equals(expect=main.TRUE, actual=assertion, |
| 429 | onpass="Switch latency test successful", |
| 430 | onfail="Switch latency test failed") |
andrewonlab | ba44bcf | 2014-10-16 16:54:41 -0400 | [diff] [blame] | 431 | |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 432 | def CASE3(self, main): |
| 433 | ''' |
| 434 | Bring port up / down and measure latency. |
| 435 | Port enable / disable is simulated by ifconfig up / down |
| 436 | ''' |
| 437 | import time |
| 438 | import subprocess |
| 439 | import os |
| 440 | import requests |
| 441 | import json |
andrewonlab | 2a6c934 | 2014-10-16 13:40:15 -0400 | [diff] [blame] | 442 | |
andrewonlab | 8d29f12 | 2014-10-22 17:15:04 -0400 | [diff] [blame] | 443 | ONOS1_ip = main.params['CTRL']['ip1'] |
| 444 | default_sw_port = main.params['CTRL']['port1'] |
| 445 | ONOS_user = main.params['CTRL']['user'] |
| 446 | num_iter = main.params['TEST']['numIter'] |
| 447 | |
| 448 | tshark_port_status = "OFP 130 Port Status" |
| 449 | |
| 450 | tshark_port_up = "/tmp/tshark_port_up.txt" |
| 451 | tshark_port_down = "/tmp/tshark_port_down.txt" |
| 452 | |
| 453 | main.log.report("Port enable / disable latency") |
| 454 | |
| 455 | main.step("Assign switch to controller") |
| 456 | main.Mininet1.assign_sw_controller(sw="1",ip1=ONOS1_ip, |
| 457 | port1=default_sw_port) |
| 458 | |
| 459 | main.step("Verify switch is assigned correctly") |
| 460 | result_s1 = main.Mininet1.get_sw_controller(sw="s1") |
| 461 | if result_s1 == main.FALSE: |
| 462 | main.log.info("Switch s1 was not assigned correctly") |
| 463 | assertion = main.FALSE |
| 464 | else: |
| 465 | main.log.info("Switch s1 was assigned correctly") |
| 466 | |
| 467 | for i in range(0, int(num_iter)): |
| 468 | main.step("Starting wireshark capture for port status down") |
| 469 | main.ONOS1.tshark_grep(tshark_port_status, |
| 470 | tshark_port_down) |
| 471 | |
| 472 | time.sleep(10) |
| 473 | |
| 474 | main.step("Disable port (interface s1-eth2)") |
| 475 | main.Mininet2.handle.sendline("sudo ifconfig s1-eth2 down") |
| 476 | main.Mininet2.handle.expect("\$") |
| 477 | time.sleep(20) |
| 478 | |
| 479 | main.ONOS1.tshark_stop() |
| 480 | time.sleep(5) |
| 481 | |
| 482 | #Copy tshark output file from ONOS to TestON instance |
| 483 | #/tmp directory |
| 484 | os.system("scp "+ONOS_user+"@"+ONOS1_ip+":"+ |
| 485 | tshark_port_down+" /tmp/") |
| 486 | |
| 487 | f_port_down = open(tshark_port_down, 'r') |
| 488 | f_line = f_port_down.readline() |
| 489 | obj_down = f_line.split(" ") |
| 490 | if len(f_line) > 0: |
| 491 | timestamp_begin_pt_down = int(float(obj_down[1])*1000) |
| 492 | else: |
| 493 | main.log.info("Tshark output file returned unexpected"+ |
| 494 | " results") |
| 495 | timestamp_begin_pt_down = 0 |
| 496 | |
| 497 | main.step("Obtain t1 by REST call") |
| 498 | #TODO: Implement json object parsing here |
| 499 | |
| 500 | timestamp_end_pt_down_1 = 0 |
| 501 | timestamp_end_pt_down_2 = 0 |
| 502 | timestamp_end_pt_down_3 = 0 |
| 503 | |
| 504 | delta_pt_down_1 = int(timestamp_end_pt_down_1) - \ |
| 505 | int(timestamp_begin_pt_down) |
| 506 | delta_pt_down_2 = int(timestamp_end_pt_down_2) - \ |
| 507 | int(timestamp_begin_pt_down) |
| 508 | delta_pt_down_3 = int(timestamp_end_pt_down_3) - \ |
| 509 | int(timestamp_begin_pt_down) |
| 510 | |
| 511 | #TODO: Remove these logs. For test purposes only |
| 512 | main.log.info("Delta1: "+str(delta_pt_down_1)) |
| 513 | main.log.info("Delta2: "+str(delta_pt_down_2)) |
| 514 | main.log.info("Delta3: "+str(delta_pt_down_3)) |
| 515 | |
| 516 | #Port up events |
| 517 | main.step("Enable port and obtain timestamp") |
| 518 | main.step("Starting wireshark capture for port status up") |
| 519 | main.ONOS1.tshark_grep("OFP 130 Port Status", tshark_port_up) |
| 520 | time.sleep(10) |
| 521 | |
| 522 | main.Mininet2.handle.sendline("sudo ifconfig s1-eth2 up") |
| 523 | main.Mininet2.handle.expect("\$") |
| 524 | time.sleep(20) |
| 525 | |
| 526 | os.system("scp "+ONOS_user+"@"+ONOS1_ip+":"+ |
| 527 | tshark_port_up+" /tmp/") |
| 528 | |
| 529 | f_port_up = open(tshark_port_up, 'r') |
| 530 | f_line = f_port_down.readline() |
| 531 | obj_up = f_line.split(" ") |
| 532 | if len(f_line) > 0: |
| 533 | timestamp_begin_pt_up = int(float(obj_up[1])*1000) |
| 534 | else: |
| 535 | main.log.info("Tshark output file returned unexpected"+ |
| 536 | " results.") |
| 537 | timestamp_begin_pt_up = 0 |
| 538 | |
| 539 | main.step("Obtain t1 by REST call") |
| 540 | #TODO: Implement json object parsing here |
| 541 | |
| 542 | timestamp_end_pt_up_1 = 0 |
| 543 | timestamp_end_pt_up_2 = 0 |
| 544 | timestamp_end_pt_up_3 = 0 |
| 545 | |
| 546 | delta_pt_up_1 = int(timestamp_end_pt_up_1) - \ |
| 547 | int(timestamp_begin_pt_up) |
| 548 | delta_pt_up_2 = int(timestamp_end_pt_up_2) - \ |
| 549 | int(timestamp_begin_pt_up) |
| 550 | delta_pt_up_3 = int(timestamp_end_pt_up_3) - \ |
| 551 | int(timestamp_begin_pt_up) |
| 552 | |
| 553 | #TODO: Remove these logs. For test purposes only |
| 554 | main.log.info("Delta1: "+str(delta_pt_up_1)) |
| 555 | main.log.info("Delta2: "+str(delta_pt_up_2)) |
| 556 | main.log.info("Delta3: "+str(delta_pt_up_3)) |
| 557 | |
| 558 | |
| 559 | |
| 560 | |
| 561 | |
| 562 | |
| 563 | |
| 564 | |
| 565 | |
| 566 | |
| 567 | |
| 568 | |
| 569 | |
| 570 | |
| 571 | |