Ticket #1346: star-pks2-lot.txt

File star-pks2-lot.txt, 5.3 KB (added by lnevers@bbn.com, 5 years ago)
Line 
1+ stitcher.py createsliver star-pks2 /home/lnevers/gcf-test/stitch-test/stitch-star-pks2.rspec --ionRetryIntervalSecs 120 --ionStatusIntervalSecs 60 -o
208:43:55 INFO    : Configured logging from file /home/lnevers/gcf-2.7-rc7/src/gcf/stitcher_logging.conf
308:43:56 INFO    : Reading slice star-pks2 credential...
408:43:56 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 expires on 2014-10-17 12:43:55 UTC
508:43:56 INFO    : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc
608:43:56 INFO    : Calling SCS...
708:44:01 INFO    : Multi-AM reservation will include resources from these aggregates:
808:44:01 INFO    :      <Aggregate nysernet-ig>
908:44:01 INFO    :      <Aggregate utah-ig>
1008:44:01 INFO    :      <Aggregate gpo-ig>
1108:44:01 INFO    :      <Aggregate ion>
1208:44:01 INFO    :      <Aggregate ukypks2-ig>
1308:44:01 INFO    :      <Aggregate rutgers-ig>
1408:44:01 INFO    :      <Aggregate utah-pg>
1508:44:01 INFO    : Stitcher doing createsliver at <Aggregate nysernet-ig>...
1608:44:19 INFO    : ... Allocation at <Aggregate nysernet-ig> complete.
1708:44:19 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
1808:44:34 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
1908:44:34 INFO    : Stitcher doing createsliver at <Aggregate ukypks2-ig>...
2008:44:56 INFO    : ... Allocation at <Aggregate ukypks2-ig> complete.
2108:44:56 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
2208:45:12 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
2308:45:12 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
2408:46:07 INFO    : ... Allocation at <Aggregate utah-pg> complete.
2508:46:07 INFO    : Stitcher doing createsliver at <Aggregate utah-ig>...
2608:46:28 INFO    : ... Allocation at <Aggregate utah-ig> complete.
2708:46:28 INFO    : Stitcher doing createsliver at <Aggregate ion>...
2808:47:15 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
2908:47:15 INFO    : Pausing 60 seconds to let circuit become ready...
3008:48:22 WARNING : sliverstatus: 125451 is (still) failed at <Aggregate ion>. Delete and retry.
3108:48:22 WARNING : sliverstatus: 125471 is (still) changing at <Aggregate ion>. Delete and retry.
3208:48:22 WARNING : sliverstatus: 125441 is (still) ready at <Aggregate ion>. Delete and retry.
3308:48:22 WARNING : sliverstatus: 125461 is (still) changing at <Aggregate ion>. Delete and retry.
3408:48:28 WARNING : <Aggregate ion> says requested VLAN was unavailable at <Hop u'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn' on path u'link-2-iggpo'>
3508:48:28 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
3608:48:28 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
3708:49:21 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>....
3808:49:21 INFO    : Pausing for 30 seconds for Aggregates to free up resources...
39
40
4108:49:51 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
4208:50:06 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
4308:50:07 INFO    : Stitcher doing createsliver at <Aggregate ion>...
4408:50:15 ERROR   :  {'output': ': CreateSliver: Existing record: urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2, ', 'geni_api': 2, 'code': {'am_type': 'sfa', 'geni_code': 7, 'am_code': 7}, 'value': ''}
4508:50:15 WARNING : Stitching failed but will retry: Reservation request impossible at <Aggregate ion>. You already have a reservation here in this slice: AMAPIError: Error from Aggregate: code 7. sfa AM code: 7: : CreateSliver: Existing record: urn:publicid:IDN+ch.geni.net:...
4608:50:15 INFO    : Deleting existing reservations...
4708:50:15 INFO    : Doing deletesliver at <Aggregate nysernet-ig>...
4808:51:07 INFO    : Deleted reservation at <Aggregate nysernet-ig>.
4908:51:07 INFO    : Doing deletesliver at <Aggregate utah-ig>...
5008:51:57 INFO    : Deleted reservation at <Aggregate utah-ig>.
5108:51:57 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
5208:53:32 INFO    : Deleted reservation at <Aggregate gpo-ig>.
5308:53:32 INFO    : Doing deletesliver at <Aggregate ukypks2-ig>...
5408:54:20 INFO    : Deleted reservation at <Aggregate ukypks2-ig>.
5508:54:20 INFO    : Doing deletesliver at <Aggregate rutgers-ig>...
5608:55:13 INFO    : Deleted reservation at <Aggregate rutgers-ig>.
5708:55:13 INFO    : Doing deletesliver at <Aggregate utah-pg>...
5808:55:47 INFO    : Deleted reservation at <Aggregate utah-pg>.
5908:55:47 INFO    : Calling SCS for the 2nd time...
6008:55:48 ERROR   : Stitching Failed. StitchingServiceFailedError: Error from Stitching Service: code 3: MxTCE ComputeWorker return error message ' Action_ProcessRequestTopology_MP2P::Finish() Cannot find the set of paths for the RequestTopology. '.. Root cause error: Reservation request impossible at <Aggregate ion>. You already have a reservation here in this slice: AMAPIError: Error from Aggregate: code 7. sfa AM code: 7: : CreateSliver: Existing record: urn:publicid:IDN+ch.geni.net:...
61Stitching Failed. StitchingServiceFailedError: Error from Stitching Service: code 3: MxTCE ComputeWorker return error message ' Action_ProcessRequestTopology_MP2P::Finish() Cannot find the set of paths for the RequestTopology. '.. Root cause error: Reservation request impossible at <Aggregate ion>. You already have a reservation here in this slice: AMAPIError: Error from Aggregate: code 7. sfa AM code: 7: : CreateSliver: Existing record: urn:publicid:IDN+ch.geni.net:...