| 601 | 12:04:35 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
| 602 | 12:04:35 INFO : Reading slice IG-ST-6 credential... |
| 603 | 12:04:36 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-6 expires on 2015-02-14 00:00:00 UTC |
| 604 | 12:04:36 INFO : Using SCS at https://nutshell.maxgigapop.net:8443/geni/xmlrpc |
| 605 | 12:04:36 INFO : Calling SCS... |
| 606 | 12:04:38 INFO : Multi-AM reservation will include resources from these aggregates: |
| 607 | 12:04:38 INFO : <Aggregate utc-ig> |
| 608 | 12:04:38 INFO : <Aggregate gpo-ig> |
| 609 | 12:04:38 INFO : <Aggregate al2s> |
| 610 | 12:04:38 INFO : <Aggregate ion> |
| 611 | 12:04:38 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
| 612 | 12:04:54 INFO : ... Allocation at <Aggregate utc-ig> complete. |
| 613 | 12:04:54 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 614 | 12:05:09 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 615 | 12:05:09 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
| 616 | 12:05:14 INFO : ... Allocation at <Aggregate al2s> complete. |
| 617 | 12:05:14 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 618 | 12:05:59 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 619 | 12:05:59 INFO : Pausing 30 seconds to let circuit become ready... |
| 620 | 12:06:36 WARNING : sliverstatus: 153501 is (still) failed at <Aggregate ion>. Delete and retry. |
| 621 | 12:06:36 INFO : Doing deletesliver at <Aggregate ion>... |
| 622 | 12:06:41 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'> |
| 623 | 12:06:41 INFO : Deleting some reservations to retry, avoiding failed VLAN... |
| 624 | 12:06:41 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
| 625 | 12:08:08 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+ca5cb88288906653c19b01c9b8117b7a', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=ca5cb88288906653c19b01c9b8117b7a'}, 'value': 0} |
| 626 | 12:08:18 INFO : ... aggregate was busy, will retry ... |
| 627 | 12:09:47 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+1f35326eb67e8326c41088f676307159', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=1f35326eb67e8326c41088f676307159'}, 'value': 0} |
| 628 | 12:09:57 INFO : ... aggregate was busy, will retry ... |
| 629 | 12:11:26 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+ec1278ff7b084d527ca8dda1d0d657ae', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=ec1278ff7b084d527ca8dda1d0d657ae'}, 'value': 0} |
| 630 | 12:11:36 INFO : ... aggregate was busy, will retry ... |
| 631 | 12:13:11 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+67ca2acd35e81447a536195f8f597e19', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=67ca2acd35e81447a536195f8f597e19'}, 'value': 0} |
| 632 | 12:13:21 INFO : ... aggregate was busy, will retry ... |
| 633 | 12:13:46 INFO : ... done. |
| 634 | 12:13:46 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>.... |
| 635 | 12:13:46 INFO : Pausing for 30 seconds for Aggregates to free up resources... |
| 636 | |
| 637 | |
| 638 | 12:14:16 INFO : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up.... |
| 639 | 12:14:18 ERROR : {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+322fde85c34543fc1045bfd9753ce726', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=322fde85c34543fc1045bfd9753ce726'}, 'value': 0} |
| 640 | 12:14:18 INFO : ... it is, so can try a new reservation. |
| 641 | 12:14:18 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 642 | 12:14:54 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 643 | 12:14:54 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 644 | 12:15:39 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 645 | 12:15:39 INFO : Pausing 30 seconds to let circuit become ready... |
| 646 | 12:16:16 INFO : DCN circuit 153521 is ready at <Aggregate ion> |
| 647 | 12:16:23 INFO : ... Allocation at <Aggregate ion> complete. |
| 648 | 12:16:23 INFO : All aggregates are complete. |
| 649 | 12:16:23 INFO : Your resources expire at 2015-02-13T16:54:38 (UTC). |
| 650 | 12:16:23 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-6-manifest-rspec-multiam-combined.xml' |
| 651 | Success: Reserved resources in slice IG-ST-6 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). |