[[PageOutline]] = Rutgers University Stitching Confirmation Tests = This pages capture the detailed test logs for each test defined in the [wiki:GeniNetworkStitchingConfirmationTests New Site Stitching Confirmation Tests] page. For the sites status see the [wiki:GeniNetworkStitchingConfirmationTestStatus New Site Stitching Confirmation Tests Status] page. == IG-ST-1 New Site to GPO IG topology == The Rutgers site advertises the following stitching details: {{{ protogeni chainANDTree false false 2014-12-01T20:09:27Z 2014-12-01T20:09:27Z 1000000 1000000 1000 1 urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig 10 1000000 1000000 1000 1 l2sc ethernet 1500 3730-3739 false 1000000 1000000 1000 1 urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.newy32aoa.net.internet2.edu:e15/6:rutgers-ig 10 1000000 1000000 1000 1 l2sc ethernet 1500 3740-3750 false }}} === ION Tests === Experimenter may not need any of this data, but it is helpful to reference when trying to determine how many VLANs are delegated for stitching at the site or how much bandwidth can be requested. Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-1-ion.rspec IG-ST-1-ion.rspec]: {{{ $ stitcher createsliver IG-ST-1 IG-ST-1-ion.rspec 14:25:09 INFO : Configured logging from file /home/lnevers/gcf-2.6-oess/src/gcf/stitcher_logging.conf 14:25:09 INFO : Reading slice IG-ST-1 credential... 14:25:09 INFO : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-1 expires on 2014-08-08 18:23:48 UTC 14:25:09 INFO : Calling SCS... 14:25:10 INFO : Multi-AM reservation will include resources from these aggregates: 14:25:10 INFO : 14:25:10 INFO : 14:25:10 INFO : 14:25:10 INFO : Stitcher doing createsliver at ... 14:25:26 INFO : ... Allocation at complete. 14:25:26 INFO : Stitcher doing createsliver at ... 14:25:41 INFO : ... Allocation at complete. 14:25:41 INFO : Stitcher doing createsliver at ... 14:26:26 INFO : DCN AM : must wait for status ready.... 14:26:26 INFO : Pausing 30 seconds to let circuit become ready... 14:27:03 INFO : DCN circuit 111291 is ready at 14:27:10 INFO : Getting credential from file /tmp/slice-ch-geni-net-Luisa_Nevers-Test-Proj_01-IG-ST-1-for-lnevers-cred.xml 14:27:10 INFO : ... Allocation at complete. 14:27:10 INFO : All aggregates are complete. 14:27:10 INFO : Your resources expire at 2 different times at different AMs. The first expiration is 2014-08-02 18:25:45 UTC at . Second expiration is 2014-08-08T18:23:48 UTC. 14:27:10 INFO : Saved combined reservation RSpec at 3 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-1-manifest-rspec-stitching-combined.xml' Success: Reserved resources in slice IG-ST-1 at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-1 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 31802 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc3.instageni.rutgers.edu $ readyToLogin.py IG-ST-1 -a gpo-ig .... ig-gpo's geni_status is: changing User lnevers logs in to ig-gpo using: ssh -p 37946 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc4.instageni.gpolab.bbn.com }}} ==== Measurements ==== '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-08-01'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 143 MBytes 19.9 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 6] 0.0-60.2 sec 30.2 MBytes 4.21 Mbits/sec [ 5] 0.0-60.5 sec 24.8 MBytes 3.43 Mbits/sec [ 4] 0.0-60.6 sec 34.2 MBytes 4.74 Mbits/sec [ 3] 0.0-61.2 sec 27.2 MBytes 3.73 Mbits/sec [ 7] 0.0-61.3 sec 28.4 MBytes 3.88 Mbits/sec [SUM] 0.0-61.3 sec 145 MBytes 19.8 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 8] 0.0-60.2 sec 18.4 MBytes 2.56 Mbits/sec [ 3] 0.0-60.4 sec 12.0 MBytes 1.67 Mbits/sec [ 5] 0.0-60.5 sec 9.62 MBytes 1.34 Mbits/sec [ 12] 0.0-60.6 sec 9.50 MBytes 1.32 Mbits/sec [ 6] 0.0-60.7 sec 17.9 MBytes 2.47 Mbits/sec [ 4] 0.0-60.9 sec 22.9 MBytes 3.15 Mbits/sec [ 10] 0.0-61.0 sec 15.9 MBytes 2.18 Mbits/sec [ 11] 0.0-61.4 sec 16.5 MBytes 2.26 Mbits/sec [ 7] 0.0-62.1 sec 15.9 MBytes 2.14 Mbits/sec [ 9] 0.0-62.2 sec 8.62 MBytes 1.16 Mbits/sec [SUM] 0.0-62.2 sec 147 MBytes 19.8 Mbits/sec }}} '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512813 datagrams [ 3] Server Report: [ 3] 0.0-60.2 sec 139 MBytes 19.4 Mbits/sec 12.952 ms 413453/512811 (81%) [ 3] 0.0-60.2 sec 1 datagrams received out-of-order }}} '''Ping from InstaGENI GPO VM to the InstaGENI Rutgers VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59108ms rtt min/avg/max/mdev = 15.522/15.701/15.953/0.116 ms }}} '''Iperf InstaGENI Rutgers VM to GPO InstaGENI VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-08-01'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.1 sec 143 MBytes 19.9 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 27.9 MBytes 3.89 Mbits/sec [ 7] 0.0-60.3 sec 36.6 MBytes 5.10 Mbits/sec [ 6] 0.0-60.4 sec 21.6 MBytes 3.01 Mbits/sec [ 4] 0.0-60.5 sec 30.4 MBytes 4.21 Mbits/sec [ 5] 0.0-60.7 sec 28.4 MBytes 3.92 Mbits/sec [SUM] 0.0-60.7 sec 145 MBytes 20.0 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 6] 0.0-60.4 sec 19.2 MBytes 2.68 Mbits/sec [ 10] 0.0-60.5 sec 15.4 MBytes 2.13 Mbits/sec [ 9] 0.0-60.5 sec 18.1 MBytes 2.51 Mbits/sec [ 11] 0.0-60.7 sec 13.5 MBytes 1.87 Mbits/sec [ 8] 0.0-60.8 sec 8.00 MBytes 1.10 Mbits/sec [ 12] 0.0-60.8 sec 11.0 MBytes 1.52 Mbits/sec [ 7] 0.0-60.9 sec 12.2 MBytes 1.69 Mbits/sec [ 3] 0.0-61.0 sec 20.4 MBytes 2.80 Mbits/sec [ 5] 0.0-61.1 sec 11.8 MBytes 1.61 Mbits/sec [ 4] 0.0-62.1 sec 16.9 MBytes 2.28 Mbits/sec [SUM] 0.0-62.1 sec 146 MBytes 19.8 Mbits/sec }}} '''Iperf InstaGENI Rutgers VM to GPO InstaGENI VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512757 datagrams [ 3] Server Report: [ 3] 0.0-60.0 sec 139 MBytes 19.5 Mbits/sec 0.109 ms 413391/512752 (81%) [ 3] 0.0-60.0 sec 1 datagrams received out-of-order }}} '''Ping from InstaGENI Rutgers VM to GPO InstaGENI VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59110ms rtt min/avg/max/mdev = 15.399/15.701/15.905/0.121 ms }}} === AL2S Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-1-al2s.rspec IG-ST-1-al2s.rspec]: {{{ $ stitcher.py createsliver IG-ST-1a IG-ST-1-al2s.rspec -o --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion 15:40:01 INFO : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf 15:40:02 INFO : Reading slice IG-ST-1a credential... 15:40:02 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-1a expires on 2014-12-08 20:39:54 UTC 15:40:02 INFO : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 15:40:02 INFO : Calling SCS... 15:40:04 INFO : Multi-AM reservation will include resources from these aggregates: 15:40:04 INFO : 15:40:04 INFO : 15:40:04 INFO : 15:40:04 INFO : 15:40:04 INFO : Stitcher doing createsliver at ... 15:40:22 INFO : ... Allocation at complete. 15:40:22 INFO : Stitcher doing createsliver at ... 15:40:37 INFO : ... Allocation at complete. 15:40:37 INFO : Stitcher doing createsliver at ... 15:40:41 INFO : ... Allocation at complete. 15:40:41 INFO : Stitcher doing createsliver at ... 15:41:27 INFO : DCN AM : must wait for status ready.... 15:41:27 INFO : Pausing 30 seconds to let circuit become ready... 15:42:04 INFO : DCN circuit 141491 is ready at 15:42:11 INFO : ... Allocation at complete. 15:42:11 INFO : All aggregates are complete. 15:42:11 INFO : Your resources expire at 2014-12-07T20:30:04 (UTC). 15:42:11 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-1a-manifest-rspec-multiam-combined.xml' Success: Reserved resources in slice IG-ST-1a at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-1a -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 30778 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.rutgers.edu $ readyToLogin.py IG-ST-1a -a gpo-ig .... ig-gpo's geni_status is: ready User lnevers logs in to ig-gpo using: ssh -p 32570 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc4.instageni.gpolab.bbn.com }}} ==== Measurements ==== '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-12-01'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 142 MBytes 19.9 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 5] 0.0-60.1 sec 30.4 MBytes 4.24 Mbits/sec [ 6] 0.0-60.2 sec 26.5 MBytes 3.69 Mbits/sec [ 7] 0.0-60.4 sec 34.9 MBytes 4.85 Mbits/sec [ 3] 0.0-60.4 sec 29.6 MBytes 4.11 Mbits/sec [ 4] 0.0-60.5 sec 23.1 MBytes 3.21 Mbits/sec [SUM] 0.0-60.5 sec 144 MBytes 20.0 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-59.5 sec 10.5 MBytes 1.48 Mbits/sec [ 4] 0.0-59.8 sec 18.8 MBytes 2.63 Mbits/sec [ 8] 0.0-59.9 sec 13.1 MBytes 1.84 Mbits/sec [ 9] 0.0-60.0 sec 13.4 MBytes 1.87 Mbits/sec [ 10] 0.0-60.2 sec 13.0 MBytes 1.81 Mbits/sec [ 12] 0.0-60.8 sec 10.9 MBytes 1.50 Mbits/sec [ 11] 0.0-60.8 sec 16.5 MBytes 2.28 Mbits/sec [ 6] 0.0-61.0 sec 13.1 MBytes 1.80 Mbits/sec [ 7] 0.0-61.2 sec 22.2 MBytes 3.05 Mbits/sec [ 5] 0.0-61.3 sec 14.6 MBytes 2.00 Mbits/sec [SUM] 0.0-61.3 sec 146 MBytes 20.0 Mbits/sec }}} '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512802 datagrams [ 3] Server Report: [ 3] 0.0-60.2 sec 139 MBytes 19.4 Mbits/sec 10.410 ms 413472/512799 (81%) [ 3] 0.0-60.2 sec 1 datagrams received out-of-order }}} '''Ping from InstaGENI GPO VM to the InstaGENI Rutgers VM ''' {{{ 60 packets transmitted, 59 received, 1% packet loss, time 59085ms rtt min/avg/max/mdev = 8.797/9.238/9.539/0.173 ms }}} '''Iperf InstaGENI Rutgers VM to GPO InstaGENI VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-12-01'' __One Client__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.1 sec 142 MBytes 19.8 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 29.1 MBytes 4.07 Mbits/sec [ 6] 0.0-60.2 sec 22.4 MBytes 3.12 Mbits/sec [ 7] 0.0-60.3 sec 26.2 MBytes 3.65 Mbits/sec [ 5] 0.0-60.7 sec 35.5 MBytes 4.91 Mbits/sec [ 4] 0.0-60.8 sec 31.1 MBytes 4.30 Mbits/sec [SUM] 0.0-60.8 sec 144 MBytes 19.9 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 7] 0.0-60.0 sec 20.5 MBytes 2.87 Mbits/sec [ 3] 0.0-60.1 sec 11.9 MBytes 1.66 Mbits/sec [ 12] 0.0-60.9 sec 10.6 MBytes 1.46 Mbits/sec [ 9] 0.0-61.0 sec 14.4 MBytes 1.98 Mbits/sec [ 4] 0.0-61.0 sec 20.0 MBytes 2.75 Mbits/sec [ 8] 0.0-61.1 sec 12.5 MBytes 1.72 Mbits/sec [ 11] 0.0-61.1 sec 11.9 MBytes 1.63 Mbits/sec [ 6] 0.0-61.1 sec 17.1 MBytes 2.35 Mbits/sec [ 5] 0.0-61.7 sec 13.4 MBytes 1.82 Mbits/sec [ 10] 0.0-62.2 sec 14.5 MBytes 1.96 Mbits/sec [SUM] 0.0-62.2 sec 147 MBytes 19.8 Mbits/sec }}} '''Iperf InstaGENI Rutgers VM to GPO InstaGENI VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512774 datagrams [ 3] Server Report: [ 3] 0.0-60.2 sec 139 MBytes 19.4 Mbits/sec 13.022 ms 413442/512771 (81%) [ 3] 0.0-60.2 sec 1 datagrams received out-of-order }}} '''Ping from InstaGENI Rutgers VM to GPO InstaGENI VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59086ms rtt min/avg/max/mdev = 9.098/9.290/9.702/0.143 ms }}} === Special Test Case === Because the Rutgers Site is connected to New York as is GPO, an additional test is run to introduce some hops between the endpoints. This test will connect Rutgers IG rack to the Chicago IG rack via AL2S. Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-1-chic-rut.rspec IG-ST-1-chic-rut.rspec]: {{{ omni createslice IG-ST-1a;stitcher.py createsliver IG-ST-1a IG-ST-1-chic-rut.rspec -o --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion }}} Determined login information at the Rutgers aggregate: {{{ $ readyToLogin.py IG-ST-1s -a rutgers-ig .... }}} Verify connectivity with remote site Chicago IG: {{{ ping 192.168.1.1 -c 60 -q }}} == IG-ST-2 New Site to GPO IG Loop topology == === ION Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-2.rspec IG-ST-2.rspec]: {{{ $ stitcher.py createsliver IG-ST-2 IG-ST-2.rspec -o 15:42:04 INFO : Configured logging from file /home/lnevers/gcf-2.6-oess/src/gcf/stitcher_logging.conf 15:42:04 INFO : Reading slice IG-ST-2 credential... 15:42:04 INFO : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-2 expires on 2014-08-08 19:10:48 UTC 15:42:04 INFO : Calling SCS... 15:42:06 INFO : Multi-AM reservation will include resources from these aggregates: 15:42:06 INFO : 15:42:06 INFO : 15:42:06 INFO : 15:42:06 INFO : Stitcher doing createsliver at ... 15:42:22 INFO : ... Allocation at complete. 15:42:22 INFO : Stitcher doing createsliver at ... 15:42:40 INFO : ... Allocation at complete. 15:42:40 INFO : Stitcher doing createsliver at ... 15:43:25 INFO : DCN AM : must wait for status ready.... 15:43:25 INFO : Pausing 30 seconds to let circuit become ready... 15:44:02 INFO : Pausing 30 seconds to let circuit become ready... 15:44:39 INFO : DCN circuit 111341 is ready at 15:44:39 INFO : DCN circuit 111331 is ready at 15:44:46 INFO : Getting credential from file /tmp/slice-ch-geni-net-Luisa_Nevers-Test-Proj_01-IG-ST-2-for-lnevers-cred.xml 15:44:46 INFO : ... Allocation at complete. 15:44:46 INFO : All aggregates are complete. 15:44:46 INFO : Your resources expire at 2 different times at different AMs. The first expiration is 2014-08-02 19:42:44 UTC at . Second expiration is 2014-08-08T19:10:48 UTC. 15:44:46 INFO : Saved combined reservation RSpec at 3 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-2-manifest-rspec-stitching-combined.xml' Success: Reserved resources in slice IG-ST-2 at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 2 stitched link(s). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-2 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 32058 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu $ readyToLogin.py IG-ST-2 -a gpo-ig .... ig-gpo's geni_status is: ready User lnevers logs in to ig-gpo using: ssh -p 38458 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.gpolab.bbn.com }}} Login to GPO host and ping the remote on each of the two interfaces. Below is the ping output for the GPO site: {{{ $ ping 10.10.4.2 -c 60 -q PING 10.10.4.2 (10.10.4.2) 56(84) bytes of data. --- 10.10.4.2 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59062ms rtt min/avg/max/mdev = 15.059/15.379/31.411/2.090 ms $ ping 192.168.4.2 -c 60 -q PING 192.168.4.2 (192.168.4.2) 56(84) bytes of data. --- 192.168.4.2 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59044ms rtt min/avg/max/mdev = 15.050/15.373/31.460/2.095 ms }}} === AL2S Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-2.rspec IG-ST-2.rspec]: {{{ $ stitcher.py createsliver IG-ST-2 IG-ST-2.rspec -o --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion 15:57:44 INFO : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf 15:57:45 INFO : Reading slice IG-ST-2 credential... 15:57:45 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-2 expires on 2014-12-08 20:57:38 UTC 15:57:45 INFO : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 15:57:45 INFO : Calling SCS... 15:57:48 INFO : Multi-AM reservation will include resources from these aggregates: 15:57:48 INFO : 15:57:48 INFO : 15:57:48 INFO : 15:57:48 INFO : 15:57:48 INFO : Stitcher doing createsliver at ... 15:58:06 INFO : ... Allocation at complete. 15:58:06 INFO : Stitcher doing createsliver at ... 15:58:25 INFO : ... Allocation at complete. 15:58:25 INFO : Stitcher doing createsliver at ... 15:58:29 ERROR : {'output': 'Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,e15/6 VLAN=3744', 'code': {'geni_code': 24}} 15:58:29 WARNING : Stitching failed but will retry: Circuit reservation failed at . 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.)) 15:58:29 INFO : Deleting existing reservations... 15:58:29 INFO : Doing deletesliver at ... 15:59:36 INFO : Deleted reservation at . 15:59:36 INFO : Doing deletesliver at ... 16:00:21 INFO : Deleted reservation at . 16:00:21 INFO : Calling SCS for the 2nd time... 16:00:23 INFO : Pausing for 30 seconds for Aggregates to free up resources... 16:00:53 INFO : Multi-AM reservation will include resources from these aggregates: 16:00:53 INFO : 16:00:53 INFO : 16:00:53 INFO : 16:00:53 INFO : 16:00:53 INFO : Stitcher doing createsliver at ... 16:01:10 INFO : ... Allocation at complete. 16:01:10 INFO : Stitcher doing createsliver at ... 16:01:26 INFO : ... Allocation at complete. 16:01:27 INFO : Stitcher doing createsliver at ... 16:01:33 INFO : ... Allocation at complete. 16:01:33 INFO : Stitcher doing createsliver at ... 16:02:18 INFO : DCN AM : must wait for status ready.... 16:02:18 INFO : Pausing 30 seconds to let circuit become ready... 16:02:56 INFO : Pausing 30 seconds to let circuit become ready... 16:03:33 INFO : DCN circuit 141571 is ready at 16:03:33 INFO : DCN circuit 141561 is ready at 16:03:41 INFO : ... Allocation at complete. 16:03:41 INFO : All aggregates are complete. 16:03:41 INFO : Your resources expire at 2014-12-07T20:50:53 (UTC). 16:03:41 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-2-manifest-rspec-multiam-combined.xml' Success: 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). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-2 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 30266 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu $ readyToLogin.py IG-ST-2 -a gpo-ig .... ig-gpo's geni_status is: ready User lnevers logs in to ig-gpo using: ssh -p 31802 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.gpolab.bbn.com }}} Login to GPO host and ping the remote on each of the two interfaces. Below is the ping output for the GPO site: {{{ [lnevers@ig-gpo ~]$ ping 10.10.4.2 -c 60 -q PING 10.10.4.2 (10.10.4.2) 56(84) bytes of data. --- 10.10.4.2 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59094ms rtt min/avg/max/mdev = 8.393/8.697/17.535/1.161 ms [lnevers@ig-gpo ~]$ ping 192.168.4.2 -c 60 -q PING 192.168.4.2 (192.168.4.2) 56(84) bytes of data. --- 192.168.4.2 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59095ms rtt min/avg/max/mdev = 8.391/8.725/18.114/1.229 ms [lnevers@ig-gpo ~]$ }}} == IG-ST-3 IG-ST-3 New Site 3 node linear topology == === ION Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-3.rspe IG-ST-3.rspec]: {{{ $ stitcher.py createsliver IG-ST-3 IG-ST-3.rspec -o 10:59:36 INFO : Configured logging from file /home/lnevers/gcf-2.6-oess/src/gcf/stitcher_logging.conf 10:59:37 INFO : Reading slice IG-ST-3 credential... 10:59:37 INFO : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-3 expires on 2014-08-11 11:58:32 UTC 10:59:37 INFO : Calling SCS... 10:59:39 INFO : Multi-AM reservation will include resources from these aggregates: 10:59:39 INFO : 10:59:39 INFO : 10:59:39 INFO : 10:59:39 INFO : 10:59:39 INFO : 10:59:39 INFO : Stitcher doing createsliver at ... 10:59:55 INFO : ... Allocation at complete. 10:59:55 INFO : Stitcher doing createsliver at ... 11:00:09 INFO : ... Allocation at complete. 11:00:09 INFO : Stitcher doing createsliver at ... 11:00:59 INFO : ... Allocation at complete. 11:00:59 INFO : Stitcher doing createsliver at ... 11:01:44 INFO : DCN AM : must wait for status ready.... 11:01:44 INFO : Pausing 30 seconds to let circuit become ready... 11:02:21 INFO : Pausing 30 seconds to let circuit become ready... 11:02:59 INFO : DCN circuit 111821 is ready at 11:02:59 INFO : DCN circuit 111811 is ready at 11:03:06 INFO : Getting credential from file /tmp/slice-ch-geni-net-Luisa_Nevers-Test-Proj_01-IG-ST-3-for-lnevers-cred.xml 11:03:06 INFO : ... Allocation at complete. 11:03:06 INFO : Stitcher doing createsliver at ... 11:03:21 INFO : ... Allocation at complete. 11:03:21 INFO : All aggregates are complete. 11:03:21 INFO : Your resources expire at 4 different times at different AMs. The first expiration is 2014-08-04 20:00:18 UTC at and . Second expiration is 2014-08-05T15:01:03 UTC. 11:03:21 INFO : Saved combined reservation RSpec at 5 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-3-manifest-rspec-stitching-combined.xml' Success: Reserved resources in slice IG-ST-3 at 5 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 2 stitched link(s). }}} Determined login information the Rutgers host: {{{ $ readyToLogin.py IG-ST-3 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 32314 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu }}} Login to the Rutgers host and ping each remote: {{{ #ping GPO IG [lnevers@ig-rut ~]$ ping 192.168.2.1 -c 60 -q PING 192.168.2.1 (192.168.2.1) 56(84) bytes of data. --- 192.168.2.1 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59070ms rtt min/avg/max/mdev = 15.046/15.372/30.623/1.989 ms # Utah IG [lnevers@ig-rut ~]$ ping 192.168.4.1 -c 60 -q PING 192.168.4.1 (192.168.4.1) 56(84) bytes of data. --- 192.168.4.1 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59085ms rtt min/avg/max/mdev = 87.086/87.416/87.945/0.219 ms }}} === AL2S Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-3.rspec IG-ST-3.rspec]: {{{ $ stitcher.py createsliver IG-ST-3 IG-ST-3.rspec -o --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion 16:03:48 INFO : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf 16:03:49 INFO : Reading slice IG-ST-3 credential... 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 16:03:49 INFO : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 16:03:49 INFO : Calling SCS... 16:03:54 INFO : Multi-AM reservation will include resources from these aggregates: 16:03:54 INFO : 16:03:54 INFO : 16:03:54 INFO : 16:03:54 INFO : 16:03:54 INFO : 16:03:54 INFO : 16:03:54 INFO : Stitcher doing createsliver at ... 16:04:11 INFO : ... Allocation at complete. 16:04:11 INFO : Stitcher doing createsliver at ... 16:04:30 INFO : ... Allocation at complete. 16:04:30 INFO : Stitcher doing createsliver at ... 16:05:26 INFO : ... Allocation at complete. 16:05:26 INFO : Stitcher doing createsliver at ... 16:05:31 ERROR : {'output': 'Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,e15/6 VLAN=3744', 'code': {'geni_code': 24}} 16:05:31 WARNING : Stitching failed but will retry: Circuit reservation failed at . 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.)) 16:05:31 INFO : Deleting existing reservations... 16:05:31 INFO : Doing deletesliver at ... 16:05:54 INFO : Deleted reservation at . 16:05:54 INFO : Doing deletesliver at ... 16:05:57 INFO : Deleted reservation at . 16:05:57 INFO : Doing deletesliver at ... 16:06:30 INFO : Deleted reservation at . 16:06:30 INFO : Calling SCS for the 2nd time... 16:06:33 INFO : Pausing for 30 seconds for Aggregates to free up resources... 16:07:03 INFO : Multi-AM reservation will include resources from these aggregates: 16:07:03 INFO : 16:07:03 INFO : 16:07:03 INFO : 16:07:03 INFO : 16:07:03 INFO : 16:07:03 INFO : 16:07:03 INFO : Stitcher doing createsliver at ... 16:07:19 INFO : ... Allocation at complete. 16:07:19 INFO : Stitcher doing createsliver at ... 16:07:36 INFO : ... Allocation at complete. 16:07:36 INFO : Stitcher doing createsliver at ... 16:08:34 INFO : ... Allocation at complete. 16:08:34 INFO : Stitcher doing createsliver at ... 16:08:40 INFO : ... Allocation at complete. 16:08:40 INFO : Stitcher doing createsliver at ... 16:08:57 INFO : ... Allocation at complete. 16:08:57 INFO : Stitcher doing createsliver at ... 16:09:43 INFO : DCN AM : must wait for status ready.... 16:09:43 INFO : Pausing 30 seconds to let circuit become ready... 16:10:20 INFO : Pausing 30 seconds to let circuit become ready... 16:10:58 INFO : DCN circuit 141581 is ready at 16:10:58 INFO : DCN circuit 141591 is ready at 16:11:05 INFO : ... Allocation at complete. 16:11:05 INFO : All aggregates are complete. 16:11:05 INFO : Your resources expire at 2014-12-06T20:57:03 (UTC). 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' Success: 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). }}} Determined login information the Rutgers host: {{{ $ readyToLogin.py IG-ST-3 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 31546 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.rutgers.edu }}} Login to the Rutgers host and ping each remote: {{{ #ping GPO IG [lnevers@ig-rut ~]$ ping 192.168.2.1 -c 60 -q PING 192.168.2.1 (192.168.2.1) 56(84) bytes of data. --- 192.168.2.1 ping statistics --- 60 packets transmitted, 0 received, +45 errors, 100% packet loss, time 59014ms pipe 3 XXXXX 60 packets transmitted, 60 received, 0% packet loss, time 59070ms rtt min/avg/max/mdev = 15.046/15.372/30.623/1.989 ms # Utah IG [lnevers@ig-rut ~]$ ping 192.168.4.1 -c 60 -q PING 192.168.4.1 (192.168.4.1) 56(84) bytes of data. --- 192.168.4.1 ping statistics --- 60 packets transmitted, 60 received, 0% packet loss, time 59093ms rtt min/avg/max/mdev = 90.387/92.029/181.130/11.602 ms }}} == IG-ST-4 New Site to GPO EG interoperability == === ION Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-4.rspec IG-ST-4.rspec], which specifies a 100 Mbps link capacity. This was done to get around the mismatch in link capacity units that exists between IG and EG. {{{ $ stitcher createsliver IG-ST-4-cap ./IG-ST-4.rspec 15:19:55 INFO : Configured logging from file /home/lnevers/gcf-2.6-oess/src/gcf/stitcher_logging.conf 15:19:55 INFO : Reading slice IG-ST-4-cap credential... 15:19:56 INFO : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-4-cap expires on 2014-08-11 19:19:12 UTC 15:19:56 INFO : Calling SCS... 15:19:57 INFO : Multi-AM reservation will include resources from these aggregates: 15:19:57 INFO : 15:19:57 INFO : 15:19:57 INFO : 15:19:57 INFO : Stitcher doing createsliver at ... 15:20:12 INFO : ... Allocation at complete. 15:20:12 INFO : Stitcher doing createsliver at ... 15:20:21 ERROR : {'output': 'Embedding workflow ERROR: 1:Error in building the dependency tree, probably not available vlan path OR trying to reuse a stitching tag:http://geni-orca.renci.org/owl/03ffb90c-2035-43f3-b7ae-1d162f1d1574#/8e66da0f-f7a3-4113-bda9-87fa95396ded.\n Please see https://geni-orca.renci.org/trac/wiki/orca-errors for possible solutions.', 'code': {'geni_code': 2}} 15:20:21 INFO : Got an error reserving resources in IG-ST-4-cap at 15:20:22 WARNING : Failed to delete sliver urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-4-cap at gpo-eg (got result 'False'). (no reason given) 15:20:22 INFO : Will put back in the pool to allocate. Got: VLAN was unavailable. Retry 2nd time with new suggested VLANs 15:20:22 INFO : Pausing for 30 seconds for Aggregates to free up resources... 15:20:52 INFO : Stitcher doing createsliver at ... 15:21:04 INFO : ... Allocation at complete. 15:21:04 INFO : Stitcher doing createsliver at ... 15:21:49 INFO : DCN AM : must wait for status ready.... 15:21:49 INFO : Pausing 30 seconds to let circuit become ready... 15:22:26 INFO : Pausing 30 seconds to let circuit become ready... 15:23:03 INFO : DCN circuit 112001 is ready at 15:23:10 INFO : Getting credential from file /tmp/slice-ch-geni-net-Luisa_Nevers-Test-Proj_01-IG-ST-4-cap-for-lnevers-cred.xml 15:23:10 INFO : ... Allocation at complete. 15:23:10 INFO : All aggregates are complete. 15:23:10 WARNING : Did not find stitching hop 1's link in template manifest RSpec for path link-eggpo-ig 15:23:10 INFO : Your resources expire at 2 different times at different AMs. The first expiration is 2014-08-05 19:21:09 UTC at . Second expiration is 2014-08-11T19:19:12 UTC. 15:23:10 INFO : Saved combined reservation RSpec at 3 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-4-cap-manifest-rspec-stitching-combined.xml' Success: Reserved resources in slice IG-ST-4-cap at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-4 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 32058 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc3.instageni.rutgers.edu $ readyToLogin.py IG-ST-4 -a eg-sm .... eg-gpo's geni_status is: ready User lnevers logs in to eg-gpo using: ssh -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@192.1.242.10 }}} ==== Measurements ==== '''Iperf ExoGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 85.0 KByte (default)''' ''Collected: 2014-08-04'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 675 MBytes 94.3 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 5] 0.0-60.0 sec 145 MBytes 20.2 Mbits/sec [ 7] 0.0-60.0 sec 150 MBytes 20.9 Mbits/sec [ 4] 0.0-60.1 sec 139 MBytes 19.4 Mbits/sec [ 3] 0.0-60.1 sec 143 MBytes 19.9 Mbits/sec [ 6] 0.0-60.1 sec 141 MBytes 19.7 Mbits/sec [SUM] 0.0-60.1 sec 717 MBytes 100 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 6] 0.0-60.0 sec 80.9 MBytes 11.3 Mbits/sec [ 9] 0.0-60.0 sec 67.7 MBytes 9.46 Mbits/sec [ 8] 0.0-60.0 sec 74.9 MBytes 10.5 Mbits/sec [ 3] 0.0-60.1 sec 69.6 MBytes 9.72 Mbits/sec [ 7] 0.0-60.1 sec 66.7 MBytes 9.31 Mbits/sec [ 11] 0.0-60.1 sec 74.2 MBytes 10.4 Mbits/sec [ 10] 0.0-60.2 sec 70.6 MBytes 9.84 Mbits/sec [ 12] 0.0-60.2 sec 70.3 MBytes 9.80 Mbits/sec [ 4] 0.0-60.2 sec 72.3 MBytes 10.1 Mbits/sec [ 5] 0.0-60.3 sec 72.7 MBytes 10.1 Mbits/sec [SUM] 0.0-60.3 sec 720 MBytes 100 Mbits/sec }}} '''Iperf ExoGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 208 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512822 datagrams [ 3] Server Report: [ 3] 0.0-60.0 sec 676 MBytes 94.6 Mbits/sec 0.067 ms 30506/512821 (5.9%) [ 3] 0.0-60.0 sec 1 datagrams received out-of-order }}} '''Ping from ExoGENI GPO VM to the InstaGENI Rutgers VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59104ms rtt min/avg/max/mdev = 15.118/15.835/17.994/0.335 ms }}} '''Iperf InstaGENI Rutgers VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-08-04'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 717 MBytes 100 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 144 MBytes 20.1 Mbits/sec [ 4] 0.0-60.0 sec 102 MBytes 14.2 Mbits/sec [ 5] 0.0-60.1 sec 165 MBytes 23.1 Mbits/sec [ 7] 0.0-60.1 sec 152 MBytes 21.2 Mbits/sec [ 6] 0.0-60.1 sec 145 MBytes 20.2 Mbits/sec [SUM] 0.0-60.1 sec 707 MBytes 98.7 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 5] 0.0-60.0 sec 76.9 MBytes 10.7 Mbits/sec [ 3] 0.0-60.1 sec 56.1 MBytes 7.84 Mbits/sec [ 12] 0.0-60.0 sec 86.2 MBytes 12.0 Mbits/sec [ 6] 0.0-60.1 sec 71.2 MBytes 9.95 Mbits/sec [ 10] 0.0-60.1 sec 82.0 MBytes 11.4 Mbits/sec [ 7] 0.0-60.2 sec 59.2 MBytes 8.26 Mbits/sec [ 9] 0.0-60.2 sec 57.4 MBytes 8.00 Mbits/sec [ 11] 0.0-60.2 sec 62.8 MBytes 8.75 Mbits/sec [ 8] 0.0-60.3 sec 77.6 MBytes 10.8 Mbits/sec [ 4] 0.0-60.4 sec 83.2 MBytes 11.6 Mbits/sec [SUM] 0.0-60.4 sec 713 MBytes 99.0 Mbits/sec }}} '''Iperf InstaGENI Rutgers VM to ExoGENI GPO VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512772 datagrams [ 3] Server Report: [ 3] 0.0-60.0 sec 693 MBytes 96.9 Mbits/sec 0.082 ms 18378/512771 (3.6%) [ 3] 0.0-60.0 sec 69 datagrams received out-of-order }}} '''Ping from InstaGENI Rutgers VM to ExoGENI GPO VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59112ms rtt min/avg/max/mdev = 15.493/15.820/16.102/0.107 ms }}} === AL2S Tests === Create a slice and then create the stitched slivers with the RSpec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-4.rspec IG-ST-4.rspec], which specifies a 100 Mbps link capacity. This was done to get around the mismatch in link capacity units that exists between IG and EG. {{{ $ stitcher.py createsliver IG-ST-4 IG-ST-4.rspec --excludehop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion -o 09:24:36 INFO : Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf 09:24:36 INFO : Reading slice IG-ST-4 credential... 09:24:36 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4 expires on 2014-12-10 14:23:55 UTC 09:24:36 INFO : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 09:24:36 INFO : Calling SCS... 09:24:38 INFO : Multi-AM reservation will include resources from these aggregates: 09:24:38 INFO : 09:24:38 INFO : 09:24:38 INFO : 09:24:38 INFO : 09:24:38 INFO : Stitcher doing createsliver at ... 09:24:57 INFO : ... Allocation at complete. 09:24:57 INFO : Stitcher doing createsliver at ... 09:25:05 ERROR : {'output': 'Embedding workflow ERROR: 1:Error in building the dependency tree, probably not available vlan path OR trying to reuse a stitching tag:http://geni-orca.renci.org/owl/b83c3c53-32d5-4732-a895-752e27ad4f4d#/31a6135f-3a71-4400-946b-883b8cbabc43.\n Please see https://geni-orca.renci.org/trac/wiki/orca-errors for possible solutions.', 'code': {'geni_code': 2}} 09:25:05 INFO : Got an error reserving resources in IG-ST-4 at 09:25:06 WARNING : Failed to delete sliver urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4 at gpo-eg (got result 'False'). (no reason given) 09:25:06 INFO : Will put back in the pool to allocate. Got: VLAN was unavailable. Retry 2nd time with new suggested VLANs 09:25:06 INFO : Pausing for 30 seconds for Aggregates to free up resources... 09:25:36 INFO : Stitcher doing createsliver at ... 09:25:39 ERROR : {'output': 'Exception: requested VLAN unavailable: sdn-sw.newy32aoa.net.internet2.edu,e15/6 VLAN=3744', 'code': {'geni_code': 24}} 09:25:39 WARNING : Stitching failed but will retry: Circuit reservation failed at . 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.)) 09:25:39 INFO : Deleting existing reservations... 09:25:39 INFO : Doing deletesliver at ... 09:27:08 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.rutgers.edu+log+2c868c98a5254810fa1bf5f88d847723', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://www.instageni.rutgers.edu/spewlogfile.php3?logfile=2c868c98a5254810fa1bf5f88d847723'}, 'value': 0} 09:28:25 INFO : Deleted reservation at . 09:28:25 INFO : Calling SCS for the 2nd time... 09:28:26 INFO : Pausing for 30 seconds for Aggregates to free up resources... 09:28:56 INFO : Multi-AM reservation will include resources from these aggregates: 09:28:56 INFO : 09:28:56 INFO : 09:28:56 INFO : 09:28:56 INFO : 09:28:56 INFO : Stitcher doing createsliver at ... 09:29:34 INFO : ... Allocation at complete. 09:29:34 INFO : Stitcher doing createsliver at ... 09:29:49 INFO : ... Allocation at complete. 09:29:49 INFO : Stitcher doing createsliver at ... 09:29:57 INFO : ... Allocation at complete. 09:29:57 INFO : Stitcher doing createsliver at ... 09:30:42 INFO : DCN AM : must wait for status ready.... 09:30:42 INFO : Pausing 30 seconds to let circuit become ready... 09:31:20 INFO : DCN circuit 142191 is ready at 09:31:27 INFO : ... Allocation at complete. 09:31:27 INFO : All aggregates are complete. 09:31:27 INFO : Your resources expire at 2014-12-09T14:18:56 (UTC). 09:31:27 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-4-manifest-rspec-multiam-combined.xml' 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). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-4 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 32058 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc1.instageni.rutgers.edu $ readyToLogin.py IG-ST-4 -a gpo-eg .... eg-gpo's geni_status is: ready User lnevers logs in to eg-gpo using: ssh -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@192.1.242.57 }}} ==== Measurements ==== '''Iperf ExoGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-12-XX'' __One Client_ {{{ }}} __Five Clients__ {{{ }}} __Ten Clients__ {{{ }}} '''Iperf ExoGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ }}} '''Ping from ExoGENI GPO VM to the InstaGENI Rutgers VM ''' {{{ }}} '''Iperf InstaGENI Rutgers VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-12-XX'' __One Client_ {{{ }}} __Five Clients__ {{{ }}} __Ten Clients__ {{{ }}} '''Iperf InstaGENI Rutgers VM to ExoGENI GPO VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ }}} '''Ping from InstaGENI Rutgers VM to ExoGENI GPO VM ''' {{{ }}} == IG-ST-5 Site Information == === ION Tests === Various pages include stitching information for a site. Each of the following were verified for this site: 1. Verified that stitching VLANs and Device URN information exists in the [http://groups.geni.net/geni/wiki/GeniAggregate/RutgersInstaGENI#GENINetworkStitching Rutgers Aggregate] page. 2. Verified that Delegated GENI Stitching VLANs for site are documented at the [https://wiki.maxgigapop.net/twiki/bin/view/GENI/StaticNetworksView Delegated GENI Stitching VLANs]. 3. Stitching Computation Service logs were reviewed while testing stitching to this site, no issues found. 4. Added site to the list of [wiki:GeniNetworkStitchingSites GENI Network Stitching Sites]. === AL2S Tests === 1. Verified that stitching VLANs and Device URN information exists in the [http://groups.geni.net/geni/wiki/GeniAggregate/RutgersInstaGENI#GENINetworkStitching Rutgers Aggregate] page. 2. Verified that Delegated GENI Stitching VLANs for site are documented at the [https://wiki.maxgigapop.net/twiki/bin/view/GENI/StaticNetworksView Delegated GENI Stitching VLANs]. 3. Stitching Computation Service logs were reviewed while testing stitching to this site, no issues found. 4. Already part of the list of [wiki:GeniNetworkStitchingSites GENI Network Stitching Sites]. == IG-ST-6 New Site !OpenFlow topology == === ION Tests === Create a slice and then create the stitched slivers using !OpenFlow with the RSPec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-6.rspec IG-ST-6.rspec]: {{{ $ stitcher.py createsliver IG-ST-6 IG-ST-6.rspec -o 08:55:40 INFO : Configured logging from file /home/lnevers/gcf-2.6-oess/src/gcf/stitcher_logging.conf 08:55:40 INFO : Reading slice IG-ST-6 credential... 08:55:41 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours on 2014-08-05 13:46:53 UTC 08:55:41 INFO : Calling SCS... 08:55:42 INFO : Multi-AM reservation will include resources from these aggregates: 08:55:42 INFO : 08:55:42 INFO : 08:55:42 INFO : 08:55:42 INFO : Stitcher doing createsliver at ... 08:55:42 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:56:00 INFO : ... Allocation at complete. 08:56:00 INFO : Stitcher doing createsliver at ... 08:56:00 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:56:13 INFO : ... Allocation at complete. 08:56:13 INFO : Stitcher doing createsliver at ... 08:56:14 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:56:58 INFO : DCN AM : must wait for status ready.... 08:56:58 INFO : Pausing 30 seconds to let circuit become ready... 08:57:28 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:57:35 INFO : DCN circuit 112101 is ready at 08:57:36 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:57:43 INFO : Getting credential from file /tmp/slice-ch-geni-net-Luisa_Nevers-Test-Proj_01-IG-ST-6-for-lnevers-cred.xml 08:57:43 WARNING : Slice urn:publicid:IDN+ch.geni.net:Luisa_Nevers-Test-Proj_01+slice+IG-ST-6 expires in <= 3 hours 08:57:48 INFO : ... Allocation at complete. 08:57:48 INFO : All aggregates are complete. 08:57:48 INFO : Your resources expire at 2 different times at different AMs. The first expiration is 2014-08-05 13:46:53 UTC at and . Second expiration is 2014-08-06T12:56:18 UTC. 08:57:48 INFO : Saved combined reservation RSpec at 3 AMs to file '/home/lnevers/gcf-test/stitch-test/Rutgers/IG-ST-6-manifest-rspec-stitching-combined.xml' Success: Reserved resources in slice IG-ST-6 at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-6 -a rutgers-ig .... ig-rut's geni_status is: ready User lnevers logs in to ig-rut using: ssh -p 32314 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc3.instageni.rutgers.edu $ readyToLogin.py IG-ST-6 -a gpo-ig .... ig-gpo's geni_status is: ready User lnevers logs in to ig-gpo using: ssh -p 35130 -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc5.instageni.gpolab.bbn.com }}} ==== Measurements ==== '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-08-05'' __One Client__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.4 sec 142 MBytes 19.8 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-59.6 sec 23.9 MBytes 3.36 Mbits/sec [ 7] 0.0-60.3 sec 28.9 MBytes 4.02 Mbits/sec [ 5] 0.0-60.5 sec 37.6 MBytes 5.22 Mbits/sec [ 6] 0.0-60.6 sec 24.6 MBytes 3.41 Mbits/sec [ 4] 0.0-60.7 sec 28.4 MBytes 3.92 Mbits/sec [SUM] 0.0-60.7 sec 143 MBytes 19.8 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 4] 0.0-59.5 sec 14.2 MBytes 2.01 Mbits/sec [ 3] 0.0-59.8 sec 12.2 MBytes 1.72 Mbits/sec [ 12] 0.0-60.0 sec 10.9 MBytes 1.52 Mbits/sec [ 5] 0.0-60.2 sec 19.0 MBytes 2.65 Mbits/sec [ 11] 0.0-60.4 sec 19.5 MBytes 2.71 Mbits/sec [ 10] 0.0-60.6 sec 19.0 MBytes 2.63 Mbits/sec [ 9] 0.0-60.8 sec 13.8 MBytes 1.90 Mbits/sec [ 8] 0.0-60.8 sec 10.4 MBytes 1.43 Mbits/sec [ 7] 0.0-60.8 sec 12.2 MBytes 1.69 Mbits/sec [ 6] 0.0-61.1 sec 13.9 MBytes 1.91 Mbits/sec [SUM] 0.0-61.1 sec 145 MBytes 19.9 Mbits/sec }}} '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512785 datagrams [ 3] Server Report: [ 3] 0.0-60.2 sec 139 MBytes 19.4 Mbits/sec 17.103 ms 413628/512780 (81%) [ 3] 0.0-60.2 sec 53 datagrams received out-of-order }}} '''Ping from InstaGENI GPO VM to InstaGENI Rutgers VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59113ms rtt min/avg/max/mdev = 15.607/19.550/129.258/18.010 ms }}} '''Iperf InstaGENI Rutgers VM to InstaGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-08-05'' __One Client_ {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.3 sec 142 MBytes 19.8 Mbits/sec }}} __Five Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 5] 0.0-60.1 sec 24.4 MBytes 3.40 Mbits/sec [ 3] 0.0-60.2 sec 30.4 MBytes 4.23 Mbits/sec [ 4] 0.0-60.5 sec 28.0 MBytes 3.88 Mbits/sec [ 6] 0.0-60.8 sec 36.2 MBytes 5.00 Mbits/sec [ 7] 0.0-60.8 sec 25.0 MBytes 3.45 Mbits/sec [SUM] 0.0-60.8 sec 144 MBytes 19.9 Mbits/sec }}} __Ten Clients__ {{{ [ ID] Interval Transfer Bandwidth [ 4] 0.0-59.3 sec 19.2 MBytes 2.72 Mbits/sec [ 6] 0.0-59.3 sec 17.6 MBytes 2.49 Mbits/sec [ 5] 0.0-59.4 sec 15.6 MBytes 2.21 Mbits/sec [ 8] 0.0-59.8 sec 17.1 MBytes 2.40 Mbits/sec [ 10] 0.0-59.9 sec 11.9 MBytes 1.66 Mbits/sec [ 11] 0.0-60.8 sec 11.9 MBytes 1.64 Mbits/sec [ 3] 0.0-60.8 sec 13.0 MBytes 1.79 Mbits/sec [ 9] 0.0-60.9 sec 12.2 MBytes 1.69 Mbits/sec [ 12] 0.0-61.2 sec 14.6 MBytes 2.00 Mbits/sec [ 7] 0.0-61.4 sec 14.4 MBytes 1.97 Mbits/sec [SUM] 0.0-61.4 sec 148 MBytes 20.2 Mbits/sec }}} '''Iperf InstaGENI Rutgers VM to InstaGENI GPO VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ [ ID] Interval Transfer Bandwidth [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec [ 3] Sent 512775 datagrams [ 3] Server Report: [ 3] 0.0-60.2 sec 139 MBytes 19.4 Mbits/sec 8.113 ms 413685/512774 (81%) [ 3] 0.0-60.2 sec 70 datagrams received out-of-order }}} '''Ping from InstaGENI Rutgers VM to InstaGENI GPO VM ''' {{{ 60 packets transmitted, 60 received, 0% packet loss, time 59110ms rtt min/avg/max/mdev = 15.515/19.903/167.644/21.451 ms }}} === AL2S Tests === Create a slice and then create the stitched slivers using !OpenFlow with the RSPec [http://groups.geni.net/geni/browser/trunk/stitch-examples/Rutgers/IG-ST-6.rspec IG-ST-6.rspec]: {{{ omni.py createslice IG-ST-6 stitcher.py createsliver IG-ST-6 IG-ST-6.rspec -o }}} Determined login information at each Rutgers and GPO aggregate: {{{ $ readyToLogin.py IG-ST-6 -a rutgers-ig .... $ readyToLogin.py IG-ST-6 -a gpo-ig .... }}} ==== Measurements ==== '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-xx-xx'' __One Client_ {{{ }}} __Five Clients__ {{{ }}} __Ten Clients__ {{{ }}} '''Iperf InstaGENI GPO VM to InstaGENI Rutgers VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ }}} '''Ping from InstaGENI GPO VM to InstaGENI Rutgers VM ''' {{{ }}} '''Iperf InstaGENI Rutgers VM to InstaGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' ''Collected: 2014-xx-xx'' __One Client_ {{{ }}} __Five Clients__ {{{ }}} __Ten Clients__ {{{ }}} '''Iperf InstaGENI Rutgers VM to InstaGENI GPO VM (UDP) - UDP buffer size: 224 KByte (default) ''' {{{ }}} '''Ping from InstaGENI Rutgers VM to InstaGENI GPO VM ''' {{{ }}}