1 | + stitcher.py createsliver star-pks2 /home/lnevers/gcf-test/stitch-test/stitch-star-pks2.rspec --ionRetryIntervalSecs 120 --ionStatusIntervalSecs 60 -o |
---|
2 | 08:43:55 INFO : Configured logging from file /home/lnevers/gcf-2.7-rc7/src/gcf/stitcher_logging.conf |
---|
3 | 08:43:56 INFO : Reading slice star-pks2 credential... |
---|
4 | 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 |
---|
5 | 08:43:56 INFO : Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc |
---|
6 | 08:43:56 INFO : Calling SCS... |
---|
7 | 08:44:01 INFO : Multi-AM reservation will include resources from these aggregates: |
---|
8 | 08:44:01 INFO : <Aggregate nysernet-ig> |
---|
9 | 08:44:01 INFO : <Aggregate utah-ig> |
---|
10 | 08:44:01 INFO : <Aggregate gpo-ig> |
---|
11 | 08:44:01 INFO : <Aggregate ion> |
---|
12 | 08:44:01 INFO : <Aggregate ukypks2-ig> |
---|
13 | 08:44:01 INFO : <Aggregate rutgers-ig> |
---|
14 | 08:44:01 INFO : <Aggregate utah-pg> |
---|
15 | 08:44:01 INFO : Stitcher doing createsliver at <Aggregate nysernet-ig>... |
---|
16 | 08:44:19 INFO : ... Allocation at <Aggregate nysernet-ig> complete. |
---|
17 | 08:44:19 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
---|
18 | 08:44:34 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
---|
19 | 08:44:34 INFO : Stitcher doing createsliver at <Aggregate ukypks2-ig>... |
---|
20 | 08:44:56 INFO : ... Allocation at <Aggregate ukypks2-ig> complete. |
---|
21 | 08:44:56 INFO : Stitcher doing createsliver at <Aggregate rutgers-ig>... |
---|
22 | 08:45:12 INFO : ... Allocation at <Aggregate rutgers-ig> complete. |
---|
23 | 08:45:12 INFO : Stitcher doing createsliver at <Aggregate utah-pg>... |
---|
24 | 08:46:07 INFO : ... Allocation at <Aggregate utah-pg> complete. |
---|
25 | 08:46:07 INFO : Stitcher doing createsliver at <Aggregate utah-ig>... |
---|
26 | 08:46:28 INFO : ... Allocation at <Aggregate utah-ig> complete. |
---|
27 | 08:46:28 INFO : Stitcher doing createsliver at <Aggregate ion>... |
---|
28 | 08:47:15 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
---|
29 | 08:47:15 INFO : Pausing 60 seconds to let circuit become ready... |
---|
30 | 08:48:22 WARNING : sliverstatus: 125451 is (still) failed at <Aggregate ion>. Delete and retry. |
---|
31 | 08:48:22 WARNING : sliverstatus: 125471 is (still) changing at <Aggregate ion>. Delete and retry. |
---|
32 | 08:48:22 WARNING : sliverstatus: 125441 is (still) ready at <Aggregate ion>. Delete and retry. |
---|
33 | 08:48:22 WARNING : sliverstatus: 125461 is (still) changing at <Aggregate ion>. Delete and retry. |
---|
34 | 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'> |
---|
35 | 08:48:28 INFO : Deleting some reservations to retry, avoiding failed VLAN... |
---|
36 | 08:48:28 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
---|
37 | 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>.... |
---|
38 | 08:49:21 INFO : Pausing for 30 seconds for Aggregates to free up resources... |
---|
39 | |
---|
40 | |
---|
41 | 08:49:51 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
---|
42 | 08:50:06 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
---|
43 | 08:50:07 INFO : Stitcher doing createsliver at <Aggregate ion>... |
---|
44 | 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': ''} |
---|
45 | 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:... |
---|
46 | 08:50:15 INFO : Deleting existing reservations... |
---|
47 | 08:50:15 INFO : Doing deletesliver at <Aggregate nysernet-ig>... |
---|
48 | 08:51:07 INFO : Deleted reservation at <Aggregate nysernet-ig>. |
---|
49 | 08:51:07 INFO : Doing deletesliver at <Aggregate utah-ig>... |
---|
50 | 08:51:57 INFO : Deleted reservation at <Aggregate utah-ig>. |
---|
51 | 08:51:57 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
---|
52 | 08:53:32 INFO : Deleted reservation at <Aggregate gpo-ig>. |
---|
53 | 08:53:32 INFO : Doing deletesliver at <Aggregate ukypks2-ig>... |
---|
54 | 08:54:20 INFO : Deleted reservation at <Aggregate ukypks2-ig>. |
---|
55 | 08:54:20 INFO : Doing deletesliver at <Aggregate rutgers-ig>... |
---|
56 | 08:55:13 INFO : Deleted reservation at <Aggregate rutgers-ig>. |
---|
57 | 08:55:13 INFO : Doing deletesliver at <Aggregate utah-pg>... |
---|
58 | 08:55:47 INFO : Deleted reservation at <Aggregate utah-pg>. |
---|
59 | 08:55:47 INFO : Calling SCS for the 2nd time... |
---|
60 | 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:... |
---|
61 | 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:... |
---|