Changes between Version 5 and Version 6 of GeniNetworkStitchingConfirmationTestStatus/UTC


Ignore:
Timestamp:
02/11/15 13:00:05 (4 years ago)
Author:
lnevers@bbn.com
Comment:

--

Legend:

Unmodified
Added
Removed
Modified
  • GeniNetworkStitchingConfirmationTestStatus/UTC

    v5 v6  
    432432
    433433{{{
    434 $ stitcher.py createsliver IG-ST-4 IG-ST-4.rspec -o
    435 12:18:23 INFO    : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf
    436 12:18:23 INFO    : Reading slice IG-ST-4 credential...
    437 12:18:23 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4 expires on 2015-02-18 17:18:22 UTC
    438 12:18:23 INFO    : Calling SCS...
    439 12:18:26 INFO    : Multi-AM reservation will include resources from these aggregates:
    440 12:18:26 INFO    :      <Aggregate utc-ig>
    441 12:18:26 INFO    :      <Aggregate al2s>
    442 12:18:26 INFO    :      <Aggregate gpo-eg>
    443 12:18:26 INFO    :      <Aggregate ion>
    444 12:18:26 INFO    : Stitcher doing createsliver at <Aggregate utc-ig>...
    445 12:18:42 INFO    : ... Allocation at <Aggregate utc-ig> complete.
    446 12:18:42 INFO    : Stitcher doing createsliver at <Aggregate gpo-eg>...
    447 12:18:52 INFO    : ... Allocation at <Aggregate gpo-eg> complete.
    448 12:18:52 INFO    : Stitcher doing createsliver at <Aggregate al2s>...
    449 12:18:55 INFO    : ... Allocation at <Aggregate al2s> complete.
    450 12:18:55 INFO    : Stitcher doing createsliver at <Aggregate ion>...
    451 12:19:40 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
    452 12:19:40 INFO    : Pausing 30 seconds to let circuit become ready...
    453 12:20:19 INFO    : DCN circuit 153341 is ready at <Aggregate ion>
    454 12:20:27 INFO    : ... Allocation at <Aggregate ion> complete.
    455 12:20:27 INFO    : All aggregates are complete.
    456 12:20:27 INFO    : Your resources expire at 2015-02-17T17:08:26 (UTC).
    457 12:20:27 INFO    : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-4-manifest-rspec-multiam-combined.xml'
    458 Success: Reserved resources in slice IG-ST-4 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s).
     434$ stitcher.py createsliver IG-ST-4a IG-ST-4.rspec -o
     43512:41:39 INFO    : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf
     43612:41:40 INFO    : Reading slice IG-ST-4a credential...
     43712:41:40 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4a expires on 2015-02-18 17:41:39 UTC
     43812:41:40 INFO    : Calling SCS...
     43912:41:42 INFO    : Multi-AM reservation will include resources from these aggregates:
     44012:41:42 INFO    :      <Aggregate utc-ig>
     44112:41:42 INFO    :      <Aggregate al2s>
     44212:41:42 INFO    :      <Aggregate gpo-eg>
     44312:41:42 INFO    :      <Aggregate ion>
     44412:41:42 INFO    : Stitcher doing createsliver at <Aggregate utc-ig>...
     44512:42:02 INFO    : ... Allocation at <Aggregate utc-ig> complete.
     44612:42:02 INFO    : Stitcher doing createsliver at <Aggregate gpo-eg>...
     44712:42:14 INFO    : ... Allocation at <Aggregate gpo-eg> complete.
     44812:42:14 INFO    : Stitcher doing createsliver at <Aggregate al2s>...
     44912:42:17 INFO    : ... Allocation at <Aggregate al2s> complete.
     45012:42:17 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     45112:43:02 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     45212:43:02 INFO    : Pausing 30 seconds to let circuit become ready...
     45312:43:41 INFO    : Pausing 30 seconds to let circuit become ready...
     45412:44:18 INFO    : DCN circuit 153371 is ready at <Aggregate ion>
     45512:44:25 INFO    : ... Allocation at <Aggregate ion> complete.
     45612:44:25 INFO    : All aggregates are complete.
     45712:44:25 INFO    : Your resources expire at 2015-02-17T17:31:42 (UTC).
     45812:44:25 INFO    : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-4a-manifest-rspec-multiam-combined.xml'
     459Success: Reserved resources in slice IG-ST-4a at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s).
    459460}}}
    460461
     
    462463{{{
    463464$ readyToLogin.py IG-ST-4 --useSliceAggregates
     465  ....
     466eg-gpo's geni_status is: ready
     467User lnevers logs in to eg-gpo using:
     468        ssh -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@192.1.242.9
    464469  ....
    465470ig-utc's geni_status is: ready
    466471User lnevers logs in to ig-utc using:
    467472        ssh -p 30522  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.utc.edu
    468 
    469473}}}
    470474
     
    473477'''Iperf ExoGENI GPO VM to InstaGENI UTC VM (TCP) - TCP window size: 85.0 KByte (default)'''
    474478
    475 ''Collected: 2015-01-XX''
     479''Collected: 2015-02-11''
     480
     481__One Client__
     482{{{
     483[ ID] Interval       Transfer     Bandwidth
     484[  3]  0.0-60.1 sec   139 MBytes  19.4 Mbits/sec
     485}}}
     486
     487__Five Clients__
     488{{{
     489[ ID] Interval       Transfer     Bandwidth
     490[  6]  0.0-60.0 sec  28.9 MBytes  4.03 Mbits/sec
     491[  7]  0.0-60.2 sec  29.0 MBytes  4.04 Mbits/sec
     492[  5]  0.0-60.4 sec  28.4 MBytes  3.94 Mbits/sec
     493[  4]  0.0-60.8 sec  29.4 MBytes  4.05 Mbits/sec
     494[  3]  0.0-61.0 sec  28.5 MBytes  3.92 Mbits/sec
     495[SUM]  0.0-61.0 sec   144 MBytes  19.8 Mbits/sec
     496}}}
     497
     498__Ten Clients__
     499{{{
     500[ ID] Interval       Transfer     Bandwidth
     501[  4]  0.0-60.0 sec  15.6 MBytes  2.18 Mbits/sec
     502[  6]  0.0-60.1 sec  15.9 MBytes  2.22 Mbits/sec
     503[ 10]  0.0-60.3 sec  14.5 MBytes  2.02 Mbits/sec
     504[ 11]  0.0-60.3 sec  13.5 MBytes  1.88 Mbits/sec
     505[  7]  0.0-60.5 sec  14.8 MBytes  2.04 Mbits/sec
     506[  8]  0.0-60.6 sec  13.8 MBytes  1.90 Mbits/sec
     507[  9]  0.0-61.1 sec  14.1 MBytes  1.94 Mbits/sec
     508[  5]  0.0-61.3 sec  15.0 MBytes  2.05 Mbits/sec
     509[ 12]  0.0-61.7 sec  14.9 MBytes  2.02 Mbits/sec
     510[  3]  0.0-62.5 sec  16.2 MBytes  2.18 Mbits/sec
     511[SUM]  0.0-62.5 sec   148 MBytes  19.9 Mbits/sec
     512}}}
     513
     514'''Iperf ExoGENI GPO VM to InstaGENI UTC VM (UDP) - UDP buffer size:  208 KByte (default) '''
     515{{{
     516[ ID] Interval       Transfer     Bandwidth
     517[  3]  0.0-60.0 sec   719 MBytes   101 Mbits/sec
     518[  3] Sent 512808 datagrams
     519[  3] Server Report:
     520[  3]  0.0-60.2 sec   140 MBytes  19.4 Mbits/sec   0.139 ms 413258/512807 (81%)
     521[  3]  0.0-60.2 sec  1 datagrams received out-of-order
     522}}}
     523
     524'''Ping from ExoGENI GPO VM to the InstaGENI UTC VM '''
     525{{{
     52660 packets transmitted, 60 received, 0% packet loss, time 59079ms
     527rtt min/avg/max/mdev = 27.777/28.584/46.229/2.675 ms
     528}}}
     529
     530
     531'''Iperf InstaGENI UTC VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)'''
     532
     533''Collected: 2015-02-11''
    476534
    477535__One Client_
    478536{{{
    479 
     537[ ID] Interval       Transfer     Bandwidth
     538[  3]  0.0-60.1 sec   143 MBytes  20.0 Mbits/sec
    480539}}}
    481540
    482541__Five Clients__
    483542{{{
    484 
     543[ ID] Interval       Transfer     Bandwidth
     544[  5]  0.0-60.0 sec  21.4 MBytes  2.99 Mbits/sec
     545[  7]  0.0-60.1 sec  42.1 MBytes  5.88 Mbits/sec
     546[  3]  0.0-60.2 sec  32.8 MBytes  4.56 Mbits/sec
     547[  4]  0.0-60.2 sec  23.0 MBytes  3.20 Mbits/sec
     548[  6]  0.0-60.2 sec  25.1 MBytes  3.50 Mbits/sec
     549[SUM]  0.0-60.2 sec   144 MBytes  20.1 Mbits/sec
    485550}}}
    486551
    487552__Ten Clients__
    488553{{{
    489 
    490 }}}
    491 
    492 '''Iperf ExoGENI GPO VM to InstaGENI UTC VM (UDP) - UDP buffer size:  208 KByte (default) '''
    493 {{{
    494 
    495 }}}
    496 
    497 '''Ping from ExoGENI GPO VM to the InstaGENI UTC VM '''
    498 {{{
    499 
    500 }}}
    501 
    502 
    503 '''Iperf InstaGENI UTC VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)'''
    504 
    505 ''Collected: 2015-01-XX''
    506 
    507 __One Client_
    508 {{{
    509 
    510 }}}
    511 
    512 __Five Clients__
    513 {{{
    514 
    515 }}}
    516 
    517 __Ten Clients__
    518 {{{
    519 
     554[ ID] Interval       Transfer     Bandwidth
     555[  9]  0.0-60.2 sec  20.0 MBytes  2.79 Mbits/sec
     556[  7]  0.0-60.3 sec  13.8 MBytes  1.91 Mbits/sec
     557[  8]  0.0-60.6 sec  9.38 MBytes  1.30 Mbits/sec
     558[  6]  0.0-60.7 sec  15.2 MBytes  2.11 Mbits/sec
     559[  5]  0.0-60.8 sec  11.1 MBytes  1.54 Mbits/sec
     560[ 11]  0.0-60.9 sec  10.8 MBytes  1.48 Mbits/sec
     561[ 12]  0.0-61.0 sec  16.0 MBytes  2.20 Mbits/sec
     562[  3]  0.0-61.2 sec  21.4 MBytes  2.93 Mbits/sec
     563[ 10]  0.0-61.4 sec  11.2 MBytes  1.54 Mbits/sec
     564[  4]  0.0-61.6 sec  13.6 MBytes  1.85 Mbits/sec
     565[SUM]  0.0-61.6 sec   142 MBytes  19.4 Mbits/sec
    520566}}}
    521567
    522568'''Iperf InstaGENI UTC VM to ExoGENI GPO VM (UDP)  - UDP buffer size:  224 KByte (default) '''
    523569{{{
    524 
     570[ ID] Interval       Transfer     Bandwidth
     571[  3]  0.0-60.0 sec   719 MBytes   101 Mbits/sec
     572[  3] Sent 512765 datagrams
     573[  3] Server Report:
     574[  3]  0.0-60.2 sec   139 MBytes  19.4 Mbits/sec  14.610 ms 413662/512764 (81%)
     575[  3]  0.0-60.2 sec  1 datagrams received out-of-order
    525576}}}
    526577
    527578'''Ping from InstaGENI UTC VM to ExoGENI GPO VM '''
    528579{{{
    529 
     58060 packets transmitted, 60 received, 0% packet loss, time 59082ms
     581rtt min/avg/max/mdev = 27.813/28.031/28.601/0.254 ms
    530582}}}
    531583
     
    546598Create a slice and then create the stitched slivers using !OpenFlow with the RSPec [http://groups.geni.net/geni/browser/trunk/stitch-examples/UTC/IG-ST-6.rspec IG-ST-6.rspec]:
    547599{{{
    548 omni.py createslice IG-ST-6
    549 stitcher.py createsliver IG-ST-6 IG-ST-6.rspec -o
     600$ stitcher createsliver IG-ST-6 ./IG-ST-6.rspec
     60112:42:40 INFO    : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf
     60212:42:41 INFO    : Reading slice IG-ST-6 credential...
     60312:42:41 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-6 expires on 2015-02-14 00:00:00 UTC
     60412:42:41 INFO    : Calling SCS...
     60512:42:43 INFO    : Multi-AM reservation will include resources from these aggregates:
     60612:42:43 INFO    :      <Aggregate utc-ig>
     60712:42:43 INFO    :      <Aggregate gpo-ig>
     60812:42:43 INFO    :      <Aggregate al2s>
     60912:42:43 INFO    :      <Aggregate ion>
     61012:42:43 INFO    : Stitcher doing createsliver at <Aggregate utc-ig>...
     61112:43:02 INFO    : ... Allocation at <Aggregate utc-ig> complete.
     61212:43:02 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     61312:43:20 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     61412:43:20 INFO    : Stitcher doing createsliver at <Aggregate al2s>...
     61512:43:23 INFO    : ... Allocation at <Aggregate al2s> complete.
     61612:43:23 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     61712:44:08 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     61812:44:08 INFO    : Pausing 30 seconds to let circuit become ready...
     61912:44:46 WARNING : sliverstatus: 153381 is (still) failed at <Aggregate ion>. Delete and retry.
     62012:44:46 INFO    : Doing deletesliver at <Aggregate ion>...
     62112:44:51 WARNING : <Aggregate ion> says requested VLAN was unavailable at <Hop u'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:et-5/0/0:gpo-ig' on path u'link'>
     62212:44:51 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
     62312:44:51 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
     62412:46:19 ERROR   :  {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+c905a6d42e2a9596f6f4dede975404b4', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=c905a6d42e2a9596f6f4dede975404b4'}, 'value': 0}
     62512:46:29 INFO    :  ... aggregate was busy, will retry ...
     62612:47:55 ERROR   :  {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+b1789155aeffdf7e55575110db6c16fe', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=b1789155aeffdf7e55575110db6c16fe'}, 'value': 0}
     62712:48:05 INFO    :  ... aggregate was busy, will retry ...
     62812:49:32 ERROR   :  {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+e11a0e054523dcf46194b90e5111a024', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=e11a0e054523dcf46194b90e5111a024'}, 'value': 0}
     62912:49:42 INFO    :  ... aggregate was busy, will retry ...
     63012:51:12 INFO    :  ... done.
     63112:51:12 INFO    : Will put <Aggregate ion> back in the pool to allocate. Got: Retrying reservations at earlier AMs to avoid unavailable VLAN tag at <Aggregate ion>....
     63212:51:12 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
     633
     634
     63512:51:42 INFO    : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up....
     63612:51:44 ERROR   :  {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+a1b7067f79f550672d0b9cbd55257b19', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=a1b7067f79f550672d0b9cbd55257b19'}, 'value': 0}
     63712:51:45 INFO    : ... it is, so can try a new reservation.
     63812:51:45 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     63912:52:23 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     64012:52:23 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     64112:53:08 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     64212:53:08 INFO    : Pausing 30 seconds to let circuit become ready...
     64312:53:46 INFO    : DCN circuit 153391 is ready at <Aggregate ion>
     64412:53:53 INFO    : ... Allocation at <Aggregate ion> complete.
     64512:53:53 INFO    : All aggregates are complete.
     64612:53:53 INFO    : Your resources expire at 2015-02-13T17:32:43 (UTC).
     64712:53:53 INFO    : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-6-manifest-rspec-multiam-combined.xml'
     648Success: Reserved resources in slice IG-ST-6 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s).
    550649}}}
    551650
     
    553652{{{
    554653$ readyToLogin.py IG-ST-6 --useSliceAggregates
     654 ....
     655ig-utc's geni_status is: ready
     656User lnevers logs in to ig-utc using:
     657        ssh -p 30778  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.utc.edu
     658 ....
     659ig-gpo's geni_status is: ready
     660User lnevers logs in to ig-gpo using:
     661        ssh -p 31802  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc5.instageni.gpolab.bbn.com
    555662}}}
    556663