Gitiles
Code Review Sign In
gerrit.onosproject.org / OnosSystemTest / 7c10f6497de2c26f9fc62696cbeb4c8159fe3216 / . / TestON / logs / OnosSanity4nodes_20_Nov_2013_00_33_20 / ONOS3.session
blob: 9bb1e0fe4adf07a1de59651a7342b91a27ce5da8 [file] [log] [blame]
admin9529d112013-11-22 15:00:05 -0800[diff] [blame]12013-11-20 00:33:32,510 ONOS3 : INFO :
2 +----------------+
3------------------------------ { Script And Files } ------------------------------
4 +----------------+
5
6 Script Log File : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/OnosSanity4nodes_20_Nov_2013_00_33_20.log
7 Report Log File : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/OnosSanity4nodes_20_Nov_2013_00_33_20.rpt
8 ONOS4 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/ONOS4.session
9 ONOS2 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/ONOS2.session
10 ONOS3 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/ONOS3.session
11 ONOS1 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/ONOS1.session
12 Zookeeper4 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Zookeeper4.session
13 Cassandra1 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Cassandra1.session
14 Cassandra2 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Cassandra2.session
15 Cassandra3 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Cassandra3.session
16 Cassandra4 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Cassandra4.session
17 Zookeeper1 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Zookeeper1.session
18 Zookeeper2 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Zookeeper2.session
19 Zookeeper3 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Zookeeper3.session
20 Mininet1 Session Log : /home/admin/ONLabTest/TestON/logs/OnosSanity4nodes_20_Nov_2013_00_33_20/Mininet1.session
21 Test Script :/home/admin/ONLabTest/TestON/Tests/OnosSanity4nodes.py
22 Test Params : /home/admin/ONLabTest/TestON/Tests/OnosSanity4nodes.params
23 Topology : /home/admin/ONLabTest/TestON/Tests/OnosSanity4nodes.tpl
24 +------------------+
25--------------------------- { Script Exec Params } ---------------------------
26 +------------------+
27
28
29 'CTRL':
30 'ip2': '10.128.100.4'
31 'ip3': '10.128.100.5'
32 'ip1': '10.128.100.1'
33 'ip4': '10.128.100.6'
34 'port4': '6633'
35 'port2': '6633'
36 'port3': '6633'
37 'port1': '6633'
38
39 'NR_Links': '50'
40 'LINK':
41 'begin': 's1'
42 'end': 's2'
43
44 'RESTCALL':
45 'restIP1': '10.128.100.1'
46 'restIP2': '10.128.100.5'
47 'restPort': '8080'
48 'restURL': '/wm/device/'
49
50 'CASE1':
51 'destination': 'h6'
52
53 'PLUG':
54 'sw1': 's1'
55 'intf': 's1-eth1'
56 'sw6': 's6'
57
58 'PING':
59 'source2': 'h8'
60 'source1': 'h6'
61 'target2': 'h33'
62 'target1': 'h31'
63
64 'FLOWDEF': '~/flowdef_files/Center_Triangle/flowdef_20.txt'
65 'testcases': '1
66 2
67 21
68 3
69 4
70 5
71 6
72 7'
73 'RestIP': '10.128.100.1'
74 'NR_Switches': '25'
75 'YANK':
76 'hostmac': '00:00:00:00:00:01'
77 'sw1': 's1'
78 'hostname': 'h1'
79 'intf': 's1-eth1'
80 'sw6': 's6'
81
82
83
84 +---------------+
85----------------------------- { Components Used } -----------------------------
86 +---------------+
87 ONOS4
88 ONOS2
89 ONOS3
90 ONOS1
91 Zookeeper4
92 Cassandra1
93 Cassandra2
94 Cassandra3
95 Cassandra4
96 Zookeeper1
97 Zookeeper2
98 Zookeeper3
99 Mininet1
100
101
102 +--------+
103---------------------------- { Topology } ----------------------------
104 +--------+
105
106
107 'ONOS4':
108 'connect_order': '12'
109 'host': '10.128.100.6'
110 'user': 'admin'
111 'COMPONENTS': ''
112 'password': None
113 'type': 'OnosCliDriver'
114
115 'ONOS2':
116 'connect_order': '10'
117 'host': '10.128.100.4'
118 'user': 'admin'
119 'COMPONENTS': ''
120 'password': None
121 'type': 'OnosCliDriver'
122
123 'ONOS3':
124 'connect_order': '11'
125 'host': '10.128.100.5'
126 'user': 'admin'
127 'COMPONENTS': ''
128 'password': None
129 'type': 'OnosCliDriver'
130
131 'ONOS1':
132 'connect_order': '9'
133 'host': '10.128.100.1'
134 'user': 'admin'
135 'COMPONENTS': ''
136 'password': None
137 'type': 'OnosCliDriver'
138
139 'Zookeeper4':
140 'connect_order': '4'
141 'host': '10.128.100.6'
142 'user': 'admin'
143 'COMPONENTS': ''
144 'password': None
145 'type': 'ZookeeperCliDriver'
146
147 'Cassandra1':
148 'connect_order': '5'
149 'host': '10.128.100.1'
150 'user': 'admin'
151 'COMPONENTS': ''
152 'password': None
153 'type': 'CassandraCliDriver'
154
155 'Cassandra2':
156 'connect_order': '6'
157 'host': '10.128.100.4'
158 'user': 'admin'
159 'COMPONENTS': ''
160 'password': None
161 'type': 'CassandraCliDriver'
162
163 'Cassandra3':
164 'connect_order': '7'
165 'host': '10.128.100.5'
166 'user': 'admin'
167 'COMPONENTS': ''
168 'password': None
169 'type': 'CassandraCliDriver'
170
171 'Cassandra4':
172 'connect_order': '8'
173 'host': '10.128.100.6'
174 'user': 'admin'
175 'COMPONENTS': ''
176 'password': None
177 'type': 'CassandraCliDriver'
178
179 'Zookeeper1':
180 'connect_order': '1'
181 'host': '10.128.100.1'
182 'user': 'admin'
183 'COMPONENTS': ''
184 'password': None
185 'type': 'ZookeeperCliDriver'
186
187 'Zookeeper2':
188 'connect_order': '2'
189 'host': '10.128.100.4'
190 'user': 'admin'
191 'COMPONENTS': ''
192 'password': None
193 'type': 'ZookeeperCliDriver'
194
195 'Zookeeper3':
196 'connect_order': '3'
197 'host': '10.128.100.5'
198 'user': 'admin'
199 'COMPONENTS': ''
200 'password': None
201 'type': 'ZookeeperCliDriver'
202
203 'Mininet1':
204 'connect_order': '13'
205 'host': '10.128.100.11'
206 'user': 'admin'
207 'COMPONENTS':
208 'arg1': '--custom ~/mininet/custom/topo-onos4node.py'
209 'arg2': '--topo mytopo --arp'
210 'controller': 'remote'
211
212 'password': None
213 'type': 'MininetCliDriver'
214
215
216------------------------------------------------------------
217
218 teston>
219
220
2212013-11-20 00:33:59,396 ONOS3 : INFO :
222****************************************
223Start of Test Case1 :
224
225Welcome to Ubuntu 12.04.2 LTS (GNU/Linux 3.2.0-44-virtual x86_64)
226
227 * Documentation: https://help.ubuntu.com/
228
229 System information as of Wed Nov 20 00:33:33 PST 2013
230
231 System load: 0.0 Processes: 95
232 Usage of /: 24.1% of 19.67GB Users logged in: 1
233 Memory usage: 23% IP address for eth0: 10.128.100.5
234 Swap usage: 0%
235
236 Graph this data and manage this system at https://landscape.canonical.com/
237
238 Get cloud support with Ubuntu Advantage Cloud Guest:
239 http://www.ubuntu.com/business/services/cloud
240
241 Use Juju to deploy your cloud instances and workloads:
242 https://juju.ubuntu.com/#cloud-precise
243
244129 packages can be updated.
24565 updates are security updates.
246
247Last login: Wed Nov 20 00:33:33 2013 from 10.128.7.7
248
249
250admin@autoONOS3:~$
251admin@autoONOS3:~$
252admin@autoONOS3:~$ ~/ONOS/start-onos.sh stop
253
254admin@autoONOS3:~$ ~/ONOS/start-onos.sh stop
255admin@autoONOS3:~$ cd ~/ONOS
256cd ~/ONOS
257admin@autoONOS3:~/ONOS$ git pull
258git pull
259remote: Counting objects: 39, done.
260remote: Compressing objects: 9% (1/11) remote: Compressing objects: 18% (2/11) remote: Compressing objects: 27% (3/11) remote: Compressing objects: 36% (4/11) remote: Compressing objects: 45% (5/11) remote: Compressing objects: 54% (6/11) remote: Compressing objects: 63% (7/11) remote: Compressing objects: 72% (8/11) remote: Compressing objects: 81% (9/11) remote: Compressing objects: 90% (10/11) remote: Compressing objects: 100% (11/11) remote: Compressing objects: 100% (11/11), done.
261remote: Total 22 (delta 8), reused 22 (delta 8)
262Unpacking objects: 4% (1/22) Unpacking objects: 9% (2/22) Unpacking objects: 13% (3/22) Unpacking objects: 18% (4/22) Unpacking objects: 22% (5/22) Unpacking objects: 27% (6/22) Unpacking objects: 31% (7/22) Unpacking objects: 36% (8/22) Unpacking objects: 40% (9/22) Unpacking objects: 45% (10/22) Unpacking objects: 50% (11/22) Unpacking objects: 54% (12/22) Unpacking objects: 59% (13/22) Unpacking objects: 63% (14/22) Unpacking objects: 68% (15/22) Unpacking objects: 72% (16/22) Unpacking objects: 77% (17/22) Unpacking objects: 81% (18/22) Unpacking objects: 86% (19/22) Unpacking objects: 90% (20/22) Unpacking objects: 95% (21/22) Unpacking objects: 100% (22/22) Unpacking objects: 100% (22/22), done.
263From github.com:OPENNETWORKINGLAB/ONOS
264 7462869..089eaf3 master -> origin/master
265Updating 7462869..089eaf3
266Fast-forward
267 .../internal/DeviceManagerImplTest.java | 2 ++
268 .../core/internal/SwitchStorageImplTest.java | 4 +++-
269 .../internal/DeviceStorageImplTest.java | 3 +++
270 3 files changed, 8 insertions(+), 1 deletion(-)
271admin@autoONOS3:~/ONOS$ mvn clean
272mvn clean
273[INFO] Scanning for projects...
274[INFO]
275[INFO] ------------------------------------------------------------------------
276[INFO] Building ONOS 0.1.0
277[INFO] ------------------------------------------------------------------------
278[INFO]
279[INFO] --- maven-clean-plugin:2.3:clean (default-clean) @ onos ---
280[INFO] Deleting file set: /home/admin/ONOS/target (included: [**], excluded: [])
281[INFO] ------------------------------------------------------------------------
282[INFO] BUILD SUCCESS
283[INFO] ------------------------------------------------------------------------
284[INFO] Total time: 1.663s
285[INFO] Finished at: Wed Nov 20 00:35:06 PST 2013
286[INFO] Final Memory: 5M/105M
287[INFO] ------------------------------------------------------------------------
288admin@autoONOS3:~/ONOS$ mvn compile
289mvn compile
290[INFO] Scanning for projects...
291[INFO]
292[INFO] ------------------------------------------------------------------------
293[INFO] Building ONOS 0.1.0
294[INFO] ------------------------------------------------------------------------
295Downloading: file:///home/admin/ONOS/repo/com/netflix/curator/curator-framework/1.3.5-SNAPSHOT/maven-metadata.xml
296Downloading: http://maven.restlet.org/com/netflix/curator/curator-framework/1.3.5-SNAPSHOT/maven-metadata.xml
297Downloading: http://repo1.maven.org/maven2/com/netflix/curator/curator-framework/1.3.5-SNAPSHOT/maven-metadata.xml
298 Downloading: http://repo1.maven.org/maven2/com/netflix/curator/curator-client/1.3.5-SNAPSHOT/maven-metadata.xml
299Downloading: http://maven.restlet.org/com/netflix/curator/curator-client/1.3.5-SNAPSHOT/maven-metadata.xml
300Downloading: file:///home/admin/ONOS/repo/com/netflix/curator/curator-client/1.3.5-SNAPSHOT/maven-metadata.xml
301 Downloading: http://repo1.maven.org/maven2/com/netflix/curator/curator-recipes/1.3.5-SNAPSHOT/maven-metadata.xml
302Downloading: file:///home/admin/ONOS/repo/com/netflix/curator/curator-recipes/1.3.5-SNAPSHOT/maven-metadata.xml
303Downloading: http://maven.restlet.org/com/netflix/curator/curator-recipes/1.3.5-SNAPSHOT/maven-metadata.xml
304 Downloading: http://repo1.maven.org/maven2/com/netflix/curator/curator-x-discovery/1.3.5-SNAPSHOT/maven-metadata.xml
305Downloading: file:///home/admin/ONOS/repo/com/netflix/curator/curator-x-discovery/1.3.5-SNAPSHOT/maven-metadata.xml
306Downloading: http://maven.restlet.org/com/netflix/curator/curator-x-discovery/1.3.5-SNAPSHOT/maven-metadata.xml
307 [INFO]
308[INFO] --- jacoco-maven-plugin:0.6.3.201306030806:prepare-agent (jacoco-initialize) @ onos ---
309[INFO] argLine set to -javaagent:/home/admin/.m2/repository/org/jacoco/org.jacoco.agent/0.6.3.201306030806/org.jacoco.agent-0.6.3.201306030806-runtime.jar=destfile=/home/admin/ONOS/target/jacoco.exec
310[INFO]
311[INFO] --- maven-resources-plugin:2.3:resources (default-resources) @ onos ---
312[INFO] Using 'UTF-8' encoding to copy filtered resources.
313[INFO] Copying 52 resources
314[INFO]
315[INFO] --- maven-compiler-plugin:2.3.2:compile (default-compile) @ onos ---
316[INFO] Compiling 461 source files to /home/admin/ONOS/target/classes
317[INFO] ------------------------------------------------------------------------
318[INFO] BUILD SUCCESS
319[INFO] ------------------------------------------------------------------------
320[INFO] Total time: 17.081s
321[INFO] Finished at: Wed Nov 20 00:35:26 PST 2013
322[INFO] Final Memory: 26M/347M
323[INFO] ------------------------------------------------------------------------
324admin@autoONOS3:~/ONOS$
325
326admin@autoONOS3:~/ONOS$ ~/ONOS/start-onos.sh start
327~/ONOS/start-onos.sh start
328rotate log: ./onos-logs/onos.autoONOS3.log
329rotate log: ./onos-logs/onos.autoONOS3.pcap
330Starting ONOS controller ...
331
332/home/admin/ONOS
333/home/admin/ONOS
334mvn -o exec:exec -Dexec.executable="java" -Dexec.args=" -javaagent:/home/admin/ONOS/lib/jacocoagent.jar=dumponexit=true,output=file,destfile=./onos-logs/jacoco.exec -server -d64 -Xmx1g -Xms1g -Xmn800m -XX:+UseConcMarkSweepGC -XX:+UseAdaptiveSizePolicy -XX:+AggressiveOpts -XX:+UseFastAccessorMethods -XX:MaxInlineSize=8192 -XX:FreqInlineSize=8192 -XX:CompileThreshold=1500 -XX:PreBlockSpin=8 -XX:OnError=crash-logger -XX:CompileThreshold=1500 -XX:PreBlockSpin=8 -XX:+UseThreadPriorities -XX:ThreadPriorityPolicy=42 -XX:+UseCompressedOops -Dcom.sun.management.jmxremote.port=7189 -Dcom.sun.management.jmxremote.ssl=false -Dcom.sun.management.jmxremote.authenticate=false -Dhazelcast.logging.type=slf4j -Dlogback.configurationFile=./logback.autoONOS3.xml -cp %classpath net.onrc.onos.ofcontroller.core.Main -cf ./conf/onos.properties"
335Waiting for ONOS to start...
336......
337admin@autoONOS3:~/ONOS$ 2013-11-20 00:37:45,053 ONOS3 : INFO : Checking if the startup was clean...
338****************************************
339
3402013-11-20 00:37:45,056 ONOS3 : INFO :
341---------------------------------------------
342Start of Step INIT : Initializing the test case :Checking if the startup was clean...
343---------------------------------------------
344
3452013-11-20 00:37:45,060 ONOS3 : INFO :
346---------------------------------------------
347Start of Step 1.1: Testing startup Zookeeper
348---------------------------------------------
349
3502013-11-20 00:37:46,430 ONOS3 : INFO :
351---------------------------------------------
352End of Step 1.1: Testing startup Zookeeper
353---------------------------------------------
354
355---------------------------------------------
356Start of Step 1.2: Testing startup Cassandra
357---------------------------------------------
358
3592013-11-20 00:37:48,158 ONOS3 : INFO :
360---------------------------------------------
361End of Step 1.2: Testing startup Cassandra
362---------------------------------------------
363
364---------------------------------------------
365Start of Step 1.3: Testing startup ONOS
366---------------------------------------------
367
3682013-11-20 00:37:51,375 ONOS3 : INFO :
369****************************************
370Start of Test Case2 :
3712013-11-20 00:37:51,376 ONOS3 : INFO : Checking if one MN host exists
372****************************************
373
3742013-11-20 00:37:51,377 ONOS3 : INFO :
375---------------------------------------------
376Start of Step INIT : Initializing the test case :Checking if one MN host exists
377---------------------------------------------
378
3792013-11-20 00:37:51,379 ONOS3 : INFO :
380---------------------------------------------
381Start of Step 2.1: Host IP Checking using checkIP
382---------------------------------------------
383
3842013-11-20 00:37:51,510 ONOS3 : INFO :
385---------------------------------------------
386End of Step 2.1: Host IP Checking using checkIP
387---------------------------------------------
388
389---------------------------------------------
390Start of Step 2.2: Verifying the result
391---------------------------------------------
392
3932013-11-20 00:37:51,518 ONOS3 : INFO :
394---------------------------------------------
395End of Step 2.2: Verifying the result
396---------------------------------------------
397
398---------------------------------------------
399Start of Step 2.3: assigning ONOS controllers to switches
400---------------------------------------------
401
4022013-11-20 00:38:24,658 ONOS3 : INFO :
403****************************************
404Start of Test Case21 :
4052013-11-20 00:38:28,508 ONOS3 : INFO : Yankout s6-eth1 (link to h1) from s1
406****************************************
407
4082013-11-20 00:38:35,569 ONOS3 : INFO : Plug s1-eth1 to s6
409****************************************
410
4112013-11-20 00:38:42,427 ONOS3 : INFO : Move s1-eth1 back to s1
412****************************************
413
4142013-11-20 00:38:49,232 ONOS3 : INFO :
415****************************************
416Start of Test Case3 :
4172013-11-20 00:39:44,321 ONOS3 : INFO : Taking care of these flows!
418****************************************
419
4202013-11-20 00:39:44,325 ONOS3 : INFO :
421---------------------------------------------
422Start of Step INIT : Initializing the test case :Taking care of these flows!
423---------------------------------------------
424
4252013-11-20 00:39:44,331 ONOS3 : INFO :
426---------------------------------------------
427Start of Step 3.1: Cleaning out any leftover flows...
428---------------------------------------------
429
4302013-11-20 00:39:57,346 ONOS3 : INFO : Checking flows
431****************************************
432
4332013-11-20 00:39:57,350 ONOS3 : INFO :
434---------------------------------------------
435Start of Step 3.1: Waiting
436---------------------------------------------
437
4382013-11-20 00:40:02,364 ONOS3 : INFO :
439---------------------------------------------
440Start of Step 3.1: Checking
441---------------------------------------------
442
4432013-11-20 00:40:05,433 ONOS3 : INFO :
444---------------------------------------------
445Start of Step 3.1: Waiting
446---------------------------------------------
447
4482013-11-20 00:40:10,441 ONOS3 : INFO :
449---------------------------------------------
450Start of Step 3.1: Checking
451---------------------------------------------
452
4532013-11-20 00:40:13,299 ONOS3 : INFO :
454---------------------------------------------
455Start of Step 3.1: Waiting
456---------------------------------------------
457
4582013-11-20 00:40:18,308 ONOS3 : INFO :
459---------------------------------------------
460Start of Step 3.1: Checking
461---------------------------------------------
462
4632013-11-20 00:40:21,190 ONOS3 : INFO :
464---------------------------------------------
465Start of Step 3.1: Waiting
466---------------------------------------------
467
4682013-11-20 00:40:26,198 ONOS3 : INFO :
469---------------------------------------------
470Start of Step 3.1: Checking
471---------------------------------------------
472
4732013-11-20 00:40:29,093 ONOS3 : INFO :
474---------------------------------------------
475Start of Step 3.1: Waiting
476---------------------------------------------
477
4782013-11-20 00:40:34,102 ONOS3 : INFO :
479---------------------------------------------
480Start of Step 3.1: Checking
481---------------------------------------------
482
4832013-11-20 00:40:36,940 ONOS3 : INFO :
484---------------------------------------------
485Start of Step 3.1: Waiting
486---------------------------------------------
487
4882013-11-20 00:40:41,949 ONOS3 : INFO :
489---------------------------------------------
490Start of Step 3.1: Checking
491---------------------------------------------
492
4932013-11-20 00:41:15,083 ONOS3 : INFO :
494---------------------------------------------
495End of Step 3.1: Checking
496---------------------------------------------
497
498---------------------------------------------
499Start of Step 3.2: Verifying the result
500---------------------------------------------
501
5022013-11-20 00:41:15,094 ONOS3 : INFO :
503****************************************
504Start of Test Case4 :
5052013-11-20 00:42:43,648 ONOS3 : INFO :
506****************************************
507Start of Test Case5 :
5082013-11-20 00:42:51,580 ONOS3 : INFO :
509****************************************
510Start of Test Case6 :
5112013-11-20 00:42:51,587 ONOS3 : INFO : Bringing Link down...
512****************************************
513
5142013-11-20 00:43:25,747 ONOS3 : INFO :
515****************************************
516Start of Test Case7 :
5172013-11-20 00:43:25,754 ONOS3 : INFO : Bringing Link up...
518****************************************
519
520exit
521exit
522logout
523Connection to 10.128.100.5 closed.