+ stitcher.py createsliver star-pks2 /home/lnevers/gcf-test/stitch-test/stitch-star-pks2.rspec --ionRetryIntervalSecs 120 --ionStatusIntervalSecs 60 -o
08:43:55 INFO    : Configured logging from file /home/lnevers/gcf-2.7-rc7/src/gcf/stitcher_logging.conf
08:43:56 INFO    : Reading slice star-pks2 credential...
08:43:56 INFO    : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 expires on 2014-10-17 12:43:55 UTC
08:43:56 INFO    : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc
08:43:56 INFO    : Calling SCS...
08:44:01 INFO    : Multi-AM reservation will include resources from these aggregates:
08:44:01 INFO    : 	<Aggregate nysernet-ig>
08:44:01 INFO    : 	<Aggregate utah-ig>
08:44:01 INFO    : 	<Aggregate gpo-ig>
08:44:01 INFO    : 	<Aggregate ion>
08:44:01 INFO    : 	<Aggregate ukypks2-ig>
08:44:01 INFO    : 	<Aggregate rutgers-ig>
08:44:01 INFO    : 	<Aggregate utah-pg>
08:44:01 INFO    : Stitcher doing createsliver at <Aggregate nysernet-ig>...
08:44:19 INFO    : ... Allocation at <Aggregate nysernet-ig> complete.
08:44:19 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
08:44:34 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
08:44:34 INFO    : Stitcher doing createsliver at <Aggregate ukypks2-ig>...
08:44:56 INFO    : ... Allocation at <Aggregate ukypks2-ig> complete.
08:44:56 INFO    : Stitcher doing createsliver at <Aggregate rutgers-ig>...
08:45:12 INFO    : ... Allocation at <Aggregate rutgers-ig> complete.
08:45:12 INFO    : Stitcher doing createsliver at <Aggregate utah-pg>...
08:46:07 INFO    : ... Allocation at <Aggregate utah-pg> complete.
08:46:07 INFO    : Stitcher doing createsliver at <Aggregate utah-ig>...
08:46:28 INFO    : ... Allocation at <Aggregate utah-ig> complete.
08:46:28 INFO    : Stitcher doing createsliver at <Aggregate ion>...
08:47:15 INFO    : DCN AM <Aggregate ion>: must wait for status ready....
08:47:15 INFO    : Pausing 60 seconds to let circuit become ready...
08:48:22 WARNING : sliverstatus: 125451 is (still) failed at <Aggregate ion>. Delete and retry.
08:48:22 WARNING : sliverstatus: 125471 is (still) changing at <Aggregate ion>. Delete and retry.
08:48:22 WARNING : sliverstatus: 125441 is (still) ready at <Aggregate ion>. Delete and retry.
08:48:22 WARNING : sliverstatus: 125461 is (still) changing at <Aggregate ion>. Delete and retry.
08: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'>
08:48:28 INFO    : Deleting some reservations to retry, avoiding failed VLAN...
08:48:28 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
08: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>....
08:49:21 INFO    : Pausing for 30 seconds for Aggregates to free up resources...


08:49:51 INFO    : Stitcher doing createsliver at <Aggregate gpo-ig>...
08:50:06 INFO    : ... Allocation at <Aggregate gpo-ig> complete.
08:50:07 INFO    : Stitcher doing createsliver at <Aggregate ion>...
08: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': ''}
08: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:...
08:50:15 INFO    : Deleting existing reservations...
08:50:15 INFO    : Doing deletesliver at <Aggregate nysernet-ig>...
08:51:07 INFO    : Deleted reservation at <Aggregate nysernet-ig>.
08:51:07 INFO    : Doing deletesliver at <Aggregate utah-ig>...
08:51:57 INFO    : Deleted reservation at <Aggregate utah-ig>.
08:51:57 INFO    : Doing deletesliver at <Aggregate gpo-ig>...
08:53:32 INFO    : Deleted reservation at <Aggregate gpo-ig>.
08:53:32 INFO    : Doing deletesliver at <Aggregate ukypks2-ig>...
08:54:20 INFO    : Deleted reservation at <Aggregate ukypks2-ig>.
08:54:20 INFO    : Doing deletesliver at <Aggregate rutgers-ig>...
08:55:13 INFO    : Deleted reservation at <Aggregate rutgers-ig>.
08:55:13 INFO    : Doing deletesliver at <Aggregate utah-pg>...
08:55:47 INFO    : Deleted reservation at <Aggregate utah-pg>.
08:55:47 INFO    : Calling SCS for the 2nd time...
08: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:...
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:...