Changes between Version 2 and Version 3 of GeniNetworkStitchingConfirmationTestStatus/UTC


Ignore:
Timestamp:
02/11/15 12:15:14 (9 years ago)
Author:
lnevers@bbn.com
Comment:

--

Legend:

Unmodified
Added
Removed
Modified

  • GeniNetworkStitchingConfirmationTestStatus/UTC

    v2 v3  
    5454{{{
    5555$ stitcher.py createsliver IG-ST-1 IG-ST-1-al2s.rspec -o
    56 
     5611:48:29 INFO    : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf
     5711:48:30 INFO    : Reading slice IG-ST-1 credential...
     5811:48:30 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-1 expires on 2015-02-18 16:48:13 UTC
     5911:48:30 INFO    : Calling SCS...
     6011:48:32 INFO    : Multi-AM reservation will include resources from these aggregates:
     6111:48:32 INFO    :      <Aggregate utc-ig>
     6211:48:32 INFO    :      <Aggregate gpo-ig>
     6311:48:32 INFO    :      <Aggregate al2s>
     6411:48:32 INFO    :      <Aggregate ion>
     6511:48:32 INFO    : Stitcher doing createsliver at <Aggregate utc-ig>...
     6611:48:50 INFO    : ... Allocation at <Aggregate utc-ig> complete.
     6711:48:50 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     6811:49:05 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     6911:49:05 INFO    : Stitcher doing createsliver at <Aggregate al2s>...
     7011:49:09 INFO    : ... Allocation at <Aggregate al2s> complete.
     7111:49:09 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     7211:49:53 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     7311:49:53 INFO    : Pausing 30 seconds to let circuit become ready...
     7411:50:31 INFO    : Pausing 30 seconds to let circuit become ready...
     7511:51:09 WARNING : sliverstatus: 153231 is (still) failed at <Aggregate ion>. Delete and retry.
     7611:51:09 INFO    : Doing deletesliver at <Aggregate ion>...
     7711:51:14 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'>
     7811:51:14 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
     7911:51:14 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
     8011:52:42 ERROR   :  {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+85ad8828d76050e327e1bd90d32c7a79', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=85ad8828d76050e327e1bd90d32c7a79'}, 'value': 0}
     8111:52:52 INFO    :  ... aggregate was busy, will retry ...
     8211:54:20 ERROR   :  {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+26af6c895a56890fa91b35c8b9002ef8', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=26af6c895a56890fa91b35c8b9002ef8'}, 'value': 0}
     8311:54:30 INFO    :  ... aggregate was busy, will retry ...
     8411:55:58 INFO    :  ... done.
     8511:55:58 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>....
     8611:55:58 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
     87
     88
     8911:56:29 INFO    : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up....
     9011:56:30 ERROR   :  {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+b915d01503bf764d3451c0dff22b9f75', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=b915d01503bf764d3451c0dff22b9f75'}, 'value': 0}
     9111:56:30 INFO    : ... it is, so can try a new reservation.
     9211:56:30 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
     9311:57:10 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
     9411:57:10 INFO    : Stitcher doing createsliver at <Aggregate ion>...
     9511:57:55 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
     9611:57:55 INFO    : Pausing 30 seconds to let circuit become ready...
     9711:58:32 INFO    : Pausing 30 seconds to let circuit become ready...
     9811:59:10 INFO    : DCN circuit 153251 is ready at <Aggregate ion>
     9911:59:17 INFO    : ... Allocation at <Aggregate ion> complete.
     10011:59:17 INFO    : All aggregates are complete.
     10111:59:17 INFO    : Your resources expire at 2015-02-17T16:38:32 (UTC).
     10211:59:17 INFO    : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-1-manifest-rspec-multiam-combined.xml'
     103Success: Reserved resources in slice IG-ST-1 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s).
    57104}}}
    58105
    … …  
    61108$ readyToLogin.py IG-ST-1 --useSliceAggregates
    62109  ....
     110ig-utc's geni_status is: ready
     111User lnevers logs in to ig-utc using:
     112        ssh -p 30522  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc2.instageni.utc.edu
     113...
     114ig-gpo's geni_status is: changing
     115User lnevers logs in to ig-gpo using:
     116        ssh -p 31802  -i /home/lnevers/.ssh/geni_cert_portal_key lnevers@pc5.instageni.gpolab.bbn.com
    63117}}}
    64118
    … …  
    67121'''Iperf InstaGENI GPO VM to InstaGENI UTC VM (TCP) - TCP window size: 23.5 KByte (default)'''
    68122
    69 ''Collected: 2015-01-XX''
     123''Collected: 2015-02-XX''
    70124
    71125__One Client_
    72126{{{
    73 
    74 }}}
    75 
    76 __Five Clients__
    77 {{{
    78 
    79 }}}
    80 
    81 __Ten Clients__
    82 {{{
    83 
     127[ ID] Interval       Transfer     Bandwidth
     128[  3]  0.0-60.1 sec   142 MBytes  19.8 Mbits/sec
     129}}}
     130
     131__Five Clients__
     132{{{
     133[ ID] Interval       Transfer     Bandwidth
     134[  4]  0.0-60.3 sec  26.1 MBytes  3.63 Mbits/sec
     135[  5]  0.0-60.4 sec  23.2 MBytes  3.23 Mbits/sec
     136[  3]  0.0-60.5 sec  36.2 MBytes  5.03 Mbits/sec
     137[  7]  0.0-60.6 sec  33.2 MBytes  4.60 Mbits/sec
     138[  6]  0.0-60.7 sec  25.8 MBytes  3.56 Mbits/sec
     139[SUM]  0.0-60.7 sec   145 MBytes  20.0 Mbits/sec
     140}}}
     141
     142__Ten Clients__
     143{{{
     144[ ID] Interval       Transfer     Bandwidth
     145[  5]  0.0-59.6 sec  11.2 MBytes  1.58 Mbits/sec
     146[  7]  0.0-60.3 sec  17.8 MBytes  2.47 Mbits/sec
     147[ 10]  0.0-60.3 sec  13.1 MBytes  1.83 Mbits/sec
     148[ 12]  0.0-60.4 sec  15.9 MBytes  2.21 Mbits/sec
     149[  4]  0.0-60.7 sec  17.6 MBytes  2.44 Mbits/sec
     150[  6]  0.0-60.9 sec  13.1 MBytes  1.81 Mbits/sec
     151[  8]  0.0-61.0 sec  12.8 MBytes  1.75 Mbits/sec
     152[  3]  0.0-61.1 sec  10.1 MBytes  1.39 Mbits/sec
     153[ 11]  0.0-61.2 sec  15.8 MBytes  2.16 Mbits/sec
     154[  9]  0.0-62.0 sec  18.9 MBytes  2.55 Mbits/sec
     155[SUM]  0.0-62.0 sec   146 MBytes  19.8 Mbits/sec
    84156}}}
    85157
    86158'''Iperf InstaGENI GPO VM to InstaGENI UTC VM (UDP) - UDP buffer size:  224 KByte (default) '''
    87159{{{
    88 
     160[ ID] Interval       Transfer     Bandwidth
     161[  3]  0.0-60.0 sec   719 MBytes   100 Mbits/sec
     162[  3] Sent 512770 datagrams
     163read failed: Connection refused
     164[  3] WARNING: did not receive ack of last datagram after 1 tries.
    89165}}}
    90166
    91167'''Ping from InstaGENI GPO VM to the InstaGENI UTC VM '''
    92168{{{
    93 
     16960 packets transmitted, 60 received, 0% packet loss, time 59093ms
     170rtt min/avg/max/mdev = 27.722/27.995/29.328/0.274 ms
    94171}}}
    95172
    … …  
    97174'''Iperf InstaGENI UTC VM to GPO InstaGENI VM (TCP) - TCP window size: 23.5 KByte (default)'''
    98175
    99 ''Collected: 2015-01-XX''
    100 
    101 __One Client_
    102 {{{
    103 
    104 }}}
    105 
    106 __Five Clients__
    107 {{{
    108 
    109 }}}
    110 
    111 __Ten Clients__
    112 {{{
    113 
     176''Collected: 2015-02-XX''
     177
     178__One Client__
     179{{{
     180[ ID] Interval       Transfer     Bandwidth
     181[  3]  0.0-60.1 sec   142 MBytes  19.8 Mbits/sec
     182}}}
     183
     184__Five Clients__
     185{{{
     186[ ID] Interval       Transfer     Bandwidth
     187[  7]  0.0-60.3 sec  29.4 MBytes  4.09 Mbits/sec
     188[  4]  0.0-60.3 sec  34.6 MBytes  4.82 Mbits/sec
     189[  3]  0.0-60.4 sec  25.5 MBytes  3.54 Mbits/sec
     190[  5]  0.0-60.8 sec  25.2 MBytes  3.49 Mbits/sec
     191[  6]  0.0-60.8 sec  29.5 MBytes  4.07 Mbits/sec
     192[SUM]  0.0-60.8 sec   144 MBytes  19.9 Mbits/sec
     193}}}
     194
     195__Ten Clients__
     196{{{
     197[ ID] Interval       Transfer     Bandwidth
     198[  5]  0.0-60.1 sec  14.6 MBytes  2.04 Mbits/sec
     199[  8]  0.0-60.2 sec  13.9 MBytes  1.93 Mbits/sec
     200[  6]  0.0-60.3 sec  14.9 MBytes  2.07 Mbits/sec
     201[ 11]  0.0-60.3 sec  13.9 MBytes  1.93 Mbits/sec
     202[  3]  0.0-60.6 sec  14.1 MBytes  1.96 Mbits/sec
     203[  7]  0.0-60.7 sec  13.4 MBytes  1.85 Mbits/sec
     204[  9]  0.0-60.9 sec  13.9 MBytes  1.91 Mbits/sec
     205[ 10]  0.0-60.9 sec  12.1 MBytes  1.67 Mbits/sec
     206[  4]  0.0-61.3 sec  12.1 MBytes  1.66 Mbits/sec
     207[ 12]  0.0-62.6 sec  22.4 MBytes  3.00 Mbits/sec
     208[SUM]  0.0-62.6 sec   145 MBytes  19.5 Mbits/sec
    114209}}}
    115210
    116211'''Iperf InstaGENI UTC VM to GPO InstaGENI VM (UDP)  - UDP buffer size:  224 KByte (default) '''
    117212{{{
    118 
     213[ ID] Interval       Transfer     Bandwidth
     214[  3]  0.0-60.0 sec   719 MBytes   101 Mbits/sec
     215[  3] Sent 512804 datagrams
     216[  3] Server Report:
     217[  3]  0.0-60.1 sec   139 MBytes  19.4 Mbits/sec   0.111 ms 413423/512799 (81%)
     218[  3]  0.0-60.1 sec  1 datagrams received out-of-order
    119219}}}
    120220
    121221'''Ping from InstaGENI UTC VM to GPO InstaGENI VM '''
    122222{{{
    123 
     22360 packets transmitted, 60 received, 0% packet loss, time 59099ms
     224rtt min/avg/max/mdev = 27.635/27.995/29.817/0.366 ms
    124225}}}
    125226