Changes between Version 31 and Version 32 of GeniNetworkStitchingConfirmationTestStatus/Rutgers


Ignore:
Timestamp:
12/10/14 16:00:44 (9 years ago)
Author:
lnevers@bbn.com
Comment:

--

Legend:

Unmodified
Added
Removed
Modified

  • GeniNetworkStitchingConfirmationTestStatus/Rutgers

    v31 v32  
    678678{{{
    679679$ stitcher.py createsliver IG-ST-3 IG-ST-3.rspec -o --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion
    680 16:03:48 INFO    : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf
    681 16:03:49 INFO    : Reading slice IG-ST-3 credential...
    682 16:03:49 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-3 expires on 2014-12-08 21:03:43 UTC
    683 16:03:49 INFO    : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc
    684 16:03:49 INFO    : Calling SCS...
    685 16:03:54 INFO    : Multi-AM reservation will include resources from these aggregates:
    686 16:03:54 INFO    :      <Aggregate oess>
    687 16:03:54 INFO    :      <Aggregate utah-ig>
    688 16:03:54 INFO    :      <Aggregate gpo-ig>
    689 16:03:54 INFO    :      <Aggregate ion>
    690 16:03:54 INFO    :      <Aggregate rutgers-ig>
    691 16:03:54 INFO    :      <Aggregate utah-pg>
    692 16:03:54 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
    693 16:04:11 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
    694 16:04:11 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
    695 16:04:30 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
    696 16:04:30 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
    697 16:05:26 INFO    : ... Allocation at <Aggregate utah-pg> complete.
    698 16:05:26 INFO    : Stitcher doing createsliver at <Aggregate oess>...
    699 16:05:31 ERROR   :  {'output': 'Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,e15/6 VLAN=3744', 'code': {'geni_code': 24}}
    700 16:05:31 WARNING : Stitching failed but will retry: Circuit reservation failed at <Aggregate oess>. Try again from the SCS. (Error: Topology too complex for local redo - ask Stitching Service to find a VLAN tag (AMAPIError: Error from Aggregate: code 24: Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,e15/6 VLAN=3744.))
    701 16:05:31 INFO    : Deleting existing reservations...
    702 16:05:31 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
    703 16:05:54 INFO    : Deleted reservation at <Aggregate gpo-ig>.
    704 16:05:54 INFO    : Doing deletesliver at <Aggregate rutgers-ig>...
    705 16:05:57 INFO    : Deleted reservation at <Aggregate rutgers-ig>.
    706 16:05:57 INFO    : Doing deletesliver at <Aggregate utah-pg>...
    707 16:06:30 INFO    : Deleted reservation at <Aggregate utah-pg>.
    708 16:06:30 INFO    : Calling SCS for the 2nd time...
    709 16:06:33 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
    710 
    711 
    712 16:07:03 INFO    : Multi-AM reservation will include resources from these aggregates:
    713 16:07:03 INFO    :      <Aggregate oess>
    714 16:07:03 INFO    :      <Aggregate utah-ig>
    715 16:07:03 INFO    :      <Aggregate gpo-ig>
    716 16:07:03 INFO    :      <Aggregate ion>
    717 16:07:03 INFO    :      <Aggregate rutgers-ig>
    718 16:07:03 INFO    :      <Aggregate utah-pg>
    719 16:07:03 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
    720 16:07:19 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
    721 16:07:19 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
    722 16:07:36 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
    723 16:07:36 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
    724 16:08:34 INFO    : ... Allocation at <Aggregate utah-pg> complete.
    725 16:08:34 INFO    : Stitcher doing createsliver at <Aggregate oess>...
    726 16:08:40 INFO    : ... Allocation at <Aggregate oess> complete.
    727 16:08:40 INFO    : Stitcher doing createsliver at <Aggregate utah-ig>...
    728 16:08:57 INFO    : ... Allocation at <Aggregate utah-ig> complete.
    729 16:08:57 INFO    : Stitcher doing createsliver at <Aggregate ion>...
    730 16:09:43 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
    731 16:09:43 INFO    : Pausing 30 seconds to let circuit become ready...
    732 16:10:20 INFO    : Pausing 30 seconds to let circuit become ready...
    733 16:10:58 INFO    : DCN circuit 141581 is ready at <Aggregate ion>
    734 16:10:58 INFO    : DCN circuit 141591 is ready at <Aggregate ion>
    735 16:11:05 INFO    : ... Allocation at <Aggregate ion> complete.
    736 16:11:05 INFO    : All aggregates are complete.
    737 16:11:05 INFO    : Your resources expire at 2014-12-06T20:57:03 (UTC).
    738 16:11:05 INFO    : Saved combined reservation RSpec at 6 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-3-manifest-rspec-multiam-combined.xml'
     68015:39:08 INFO    : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf
     68115:39:08 INFO    : Reading slice IG-ST-3 credential...
     68215:39:08 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-3 expires on 2014-12-17 20:38:53 UTC
     68315:39:08 INFO    : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc
     68415:39:08 INFO    : Calling SCS...
     68515:39:12 INFO    : Multi-AM reservation will include resources from these aggregates:
     68615:39:12 INFO    :      <Aggregate oess>
     68715:39:12 INFO    :      <Aggregate utah-ig>
     68815:39:12 INFO    :      <Aggregate gpo-ig>
     68915:39:12 INFO    :      <Aggregate ion>
     69015:39:12 INFO    :      <Aggregate rutgers-ig>
     69115:39:12 INFO    :      <Aggregate utah-pg>
     69215:39:12 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     69315:39:30 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     69415:39:30 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
     69515:39:52 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
     69615:39:52 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
     69715:41:01 INFO    : ... Allocation at <Aggregate utah-pg> complete.
     69815:41:01 INFO    : Stitcher doing createsliver at <Aggregate oess>...
     69915:41:06 ERROR   :  {'output': 'Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,eth15/6 VLAN=3744', 'code': {'geni_code': 24}}
     70015:41:06 WARNING : Stitching failed but will retry: Circuit reservation failed at <Aggregate oess>. Try again from the SCS. (Error: Topology too complex for local redo - ask Stitching Service to find a VLAN tag (AMAPIError: Error from Aggregate: code 24: Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,eth15/6 VLAN=3744.))
     70115:41:06 INFO    : Deleting existing reservations...
     70215:41:06 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
     70315:41:30 INFO    : Deleted reservation at <Aggregate gpo-ig>.
     70415:41:30 INFO    : Doing deletesliver at <Aggregate rutgers-ig>...
     70515:41:33 INFO    : Deleted reservation at <Aggregate rutgers-ig>.
     70615:41:33 INFO    : Doing deletesliver at <Aggregate utah-pg>...
     70715:42:04 INFO    : Deleted reservation at <Aggregate utah-pg>.
     70815:42:04 INFO    : Calling SCS for the 2nd time...
     70915:42:06 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
     710
     711
     71215:42:36 INFO    : Multi-AM reservation will include resources from these aggregates:
     71315:42:36 INFO    :      <Aggregate oess>
     71415:42:36 INFO    :      <Aggregate utah-ig>
     71515:42:36 INFO    :      <Aggregate gpo-ig>
     71615:42:36 INFO    :      <Aggregate ion>
     71715:42:36 INFO    :      <Aggregate rutgers-ig>
     71815:42:36 INFO    :      <Aggregate utah-pg>
     71915:42:36 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     72015:42:54 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     72115:42:54 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
     72215:43:15 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
     72315:43:15 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
     72415:44:22 INFO    : ... Allocation at <Aggregate utah-pg> complete.
     72515:44:22 INFO    : Stitcher doing createsliver at <Aggregate oess>...
     72615:44:27 INFO    : ... Allocation at <Aggregate oess> complete.
     72715:44:27 INFO    : Stitcher doing createsliver at <Aggregate utah-ig>...
     72815:44:44 INFO    : ... Allocation at <Aggregate utah-ig> complete.
     72915:44:44 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     73015:45:29 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     73115:45:29 INFO    : Pausing 30 seconds to let circuit become ready...
     73215:46:07 INFO    : Pausing 30 seconds to let circuit become ready...
     73315:46:44 INFO    : DCN circuit 144301 is ready at <Aggregate ion>
     73415:46:44 INFO    : DCN circuit 144281 is ready at <Aggregate ion>
     73515:46:52 INFO    : ... Allocation at <Aggregate ion> complete.
     73615:46:52 INFO    : All aggregates are complete.
     73715:46:52 INFO    : Your resources expire at 2014-12-15T20:32:36 (UTC).
     73815:46:52 INFO    : Saved combined reservation RSpec at 6 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-3-manifest-rspec-multiam-combined.xml'
    739739Success: Reserved resources in slice IG-ST-3 at 6 Aggregates (including 3 intermediate aggregate(s) not in the original request), creating 2 stitched link(s).
    740740}}}
    … …  
    746746ig-rut's geni_status is: ready
    747747User lnevers logs in to ig-rut using:
    748         ssh -p 31546  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu
     748        ssh -p 30266  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu
    749749}}}
    750750
    … …  
    756756
    757757--- 192.168.2.1 ping statistics ---
    758 60 packets transmitted, 0 received, +45 errors, 100% packet loss, time 59014ms pipe 3
    759 
    760 
    761 XXXXX
    762 60 packets transmitted, 60 received, 0% packet loss, time 59070ms
    763 rtt min/avg/max/mdev = 15.046/15.372/30.623/1.989 ms
     75860 packets transmitted, 60 received, 0% packet loss, time 59095ms
     759rtt min/avg/max/mdev = 8.344/8.646/17.217/1.121 ms
    764760
    765761# Utah IG
    … …  
    768764
    769765--- 192.168.4.1 ping statistics ---
    770 60 packets transmitted, 60 received, 0% packet loss, time 59093ms
    771 rtt min/avg/max/mdev = 90.387/92.029/181.130/11.602 ms
    772 
     76660 packets transmitted, 60 received, 0% packet loss, time 59092ms
     767rtt min/avg/max/mdev = 90.385/92.024/181.220/11.615 ms
    773768}}}
    774769