Changes between Version 3 and Version 4 of GeniNetworkStitchingConfirmationTestStatus/UTC


Ignore:
Timestamp:
02/11/15 12:19:43 (4 years ago)
Author:
lnevers@bbn.com
Comment:

--

Legend:

Unmodified
Added
Removed
Modified
  • GeniNetworkStitchingConfirmationTestStatus/UTC

    v3 v4  
    230230Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/UTC/IG-ST-2.rspec IG-ST-2.rspec]:
    231231{{{
    232 omni.py createslice IG-ST-2
    233 stitcher.py createsliver IG-ST-2 IG-ST-2.rspec -o
     232$ stitcher createsliver IG-ST-2 ./IG-ST-2.rspec
     23312:05:42 INFO    : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf
     23412:05:43 INFO    : Reading slice IG-ST-2 credential...
     23512:05:43 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-2 expires on 2015-02-18 16:50:26 UTC
     23612:05:43 INFO    : Calling SCS...
     23712:05:46 INFO    : Multi-AM reservation will include resources from these aggregates:
     23812:05:46 INFO    :      <Aggregate utc-ig>
     23912:05:46 INFO    :      <Aggregate gpo-ig>
     24012:05:46 INFO    :      <Aggregate al2s>
     24112:05:46 INFO    :      <Aggregate ion>
     24212:05:46 INFO    : Stitcher doing createsliver at <Aggregate utc-ig>...
     24312:06:03 INFO    : ... Allocation at <Aggregate utc-ig> complete.
     24412:06:03 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     24512:06:19 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     24612:06:19 INFO    : Stitcher doing createsliver at <Aggregate al2s>...
     24712:06:24 INFO    : ... Allocation at <Aggregate al2s> complete.
     24812:06:24 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     24912:07:09 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     25012:07:09 INFO    : Pausing 30 seconds to let circuit become ready...
     25112:07:46 INFO    : Pausing 30 seconds to let circuit become ready...
     25212:08:23 WARNING : sliverstatus: 153271 is (still) failed at <Aggregate ion>. Delete and retry.
     25312:08:23 WARNING : sliverstatus: 153261 is (still) failed at <Aggregate ion>. Delete and retry.
     25412:08:23 INFO    : Doing deletesliver at <Aggregate ion>...
     25512:08:29 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'>
     25612:08:29 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
     25712:08:29 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
     25812:08:31 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>....
     25912:08:31 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
     260
     261
     26212:09:01 INFO    : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up....
     26312:09:23 ERROR   :  {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+b5289d648dd066a3b9b547daf0686d84', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=b5289d648dd066a3b9b547daf0686d84'}, 'value': 0}
     26412:09:23 INFO    : ... it is, so can try a new reservation.
     26512:09:23 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     26612:09:39 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     26712:09:39 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     26812:10:24 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     26912:10:24 INFO    : Pausing 30 seconds to let circuit become ready...
     27012:11:02 INFO    : Pausing 30 seconds to let circuit become ready...
     27112:11:40 WARNING : sliverstatus: 153291 is (still) failed at <Aggregate ion>. Delete and retry.
     27212:11:40 WARNING :   Status had error message: VLAN cancelled by rollback from contingent failure
     27312:11:40 WARNING : sliverstatus: 153281 is (still) failed at <Aggregate ion>. Delete and retry.
     27412:11:40 INFO    : Doing deletesliver at <Aggregate ion>...
     27512:11:45 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'>
     27612:11:45 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
     27712:11:45 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
     27812:11:47 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>....
     27912:11:47 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
     280
     281
     28212:12:17 INFO    : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up....
     28312:12:39 ERROR   :  {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+5d447ffe50ae1110480186454c5b751c', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=5d447ffe50ae1110480186454c5b751c'}, 'value': 0}
     28412:12:39 INFO    : ... it is, so can try a new reservation.
     28512:12:39 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     28612:12:55 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     28712:12:55 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     28812:13:40 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     28912:13:40 INFO    : Pausing 30 seconds to let circuit become ready...
     29012:14:18 INFO    : Pausing 30 seconds to let circuit become ready...
     29112:14:55 INFO    : DCN circuit 153301 is ready at <Aggregate ion>
     29212:14:55 INFO    : DCN circuit 153311 is ready at <Aggregate ion>
     29312:15:02 INFO    : ... Allocation at <Aggregate ion> complete.
     29412:15:02 INFO    : All aggregates are complete.
     29512:15:02 INFO    : Your resources expire at 2015-02-17T16:55:46 (UTC).
     29612:15:02 INFO    : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-2-manifest-rspec-multiam-combined.xml'
     297Success: Reserved resources in slice IG-ST-2 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 2 stitched link(s)
    234298}}}
    235299
     
    238302$ readyToLogin.py IG-ST-2 --useSliceAggregates
    239303  ....
     304ig-utc's geni_status is: ready
     305User lnevers logs in to ig-utc using:
     306        ssh -p 30778  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc5.instageni.utc.edu
     307  ....
     308ig-gpo's geni_status is: ready
     309User lnevers logs in to ig-gpo using:
     310        ssh -p 32314  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.gpolab.bbn.com
    240311}}}
    241312
    242313Login to GPO host and ping the remote on each of the two interfaces.  Below is the ping output for the GPO site:
    243314{{{
    244 remote-execute.py IG-ST-2a -a gpo-ig -m "ping 10.10.4.2 -c 60 -q ;ping 192.168.4.2 -c 60 -q"
     315$ remote-execute.py IG-ST-2 -a gpo-ig -m "ping 10.10.4.2 -c 60 -q ;ping 192.168.4.2 -c 60 -q"
     3161. Find login Info for hosts in slice IG-ST-2
     317Key file [/home/lnevers/.ssh/arendia-id_rsa] does NOT exist.
     318Key file [/home/lnevers/.ssh/sendaria-id_rsa] does NOT exist.
     3192. Send command 'ping 10.10.4.2 -c 60 -q ;ping 192.168.4.2 -c 60 -q' to ig-gpo
     320
     321PING 10.10.4.2 (10.10.4.2) 56(84) bytes of data.
     322
     323--- 10.10.4.2 ping statistics ---
     32460 packets transmitted, 60 received, 0% packet loss, time 59079ms
     325rtt min/avg/max/mdev = 27.157/27.814/55.272/3.601 ms
     326PING 192.168.4.2 (192.168.4.2) 56(84) bytes of data.
     327
     328--- 192.168.4.2 ping statistics ---
     32960 packets transmitted, 60 received, 0% packet loss, time 59079ms
     330rtt min/avg/max/mdev = 27.136/27.768/54.951/3.550 ms
     331... Done with command 'ping 10.10.4.2 -c 60 -q ;ping 192.168.4.2 -c 60 -q' at ig-gpo
    245332}}}
    246333