601 | | 13:15:24 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
602 | | 13:15:25 INFO : Reading slice IG-ST-6 credential... |
603 | | 13:15:25 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-6 expires on 2015-02-14 00:00:00 UTC |
604 | | 13:15:25 INFO : Calling SCS... |
605 | | 13:15:27 INFO : Multi-AM reservation will include resources from these aggregates: |
606 | | 13:15:27 INFO : <Aggregate utc-ig> |
607 | | 13:15:27 INFO : <Aggregate gpo-ig> |
608 | | 13:15:27 INFO : <Aggregate al2s> |
609 | | 13:15:27 INFO : <Aggregate ion> |
610 | | 13:15:27 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
611 | | 13:15:43 INFO : ... Allocation at <Aggregate utc-ig> complete. |
612 | | 13:15:43 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
613 | | 13:15:59 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
614 | | 13:15:59 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
615 | | 13:16:02 INFO : ... Allocation at <Aggregate al2s> complete. |
616 | | 13:16:02 INFO : Stitcher doing createsliver at <Aggregate ion>... |
617 | | 13:16:47 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
618 | | 13:16:47 INFO : Pausing 30 seconds to let circuit become ready... |
619 | | 13:17:25 WARNING : sliverstatus: 153401 is (still) failed at <Aggregate ion>. Delete and retry. |
620 | | 13:17:25 INFO : Doing deletesliver at <Aggregate ion>... |
621 | | 13:17:30 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'> |
622 | | 13:17:30 INFO : Deleting some reservations to retry, avoiding failed VLAN... |
623 | | 13:17:30 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
624 | | 13:18:56 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+21a926163fe00f8af0dac119a5a8ddf2', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=21a926163fe00f8af0dac119a5a8ddf2'}, 'value': 0} |
625 | | 13:19:06 INFO : ... aggregate was busy, will retry ... |
626 | | 13:20:36 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+edba31bffa1ceec97db4fd88fc1d8ee0', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=edba31bffa1ceec97db4fd88fc1d8ee0'}, 'value': 0} |
627 | | 13:20:46 INFO : ... aggregate was busy, will retry ... |
628 | | 13:22:13 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+57f24bde8b5e708252b19f744f8ef19d', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=57f24bde8b5e708252b19f744f8ef19d'}, 'value': 0} |
629 | | 13:22:23 INFO : ... aggregate was busy, will retry ... |
630 | | 13:23:29 INFO : ... done. |
631 | | 13:23:29 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>.... |
632 | | 13:23:29 INFO : Pausing for 30 seconds for Aggregates to free up resources... |
633 | | |
634 | | |
635 | | 13:23:59 INFO : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up.... |
636 | | 13:24:00 ERROR : {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+c15fcaa28e576d5c77be7edc426334d3', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=c15fcaa28e576d5c77be7edc426334d3'}, 'value': 0} |
637 | | 13:24:00 INFO : ... it is, so can try a new reservation. |
638 | | 13:24:00 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
639 | | 13:24:39 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
640 | | 13:24:39 INFO : Stitcher doing createsliver at <Aggregate ion>... |
641 | | 13:25:24 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
642 | | 13:25:24 INFO : Pausing 30 seconds to let circuit become ready... |
643 | | 13:26:01 INFO : DCN circuit 153411 is ready at <Aggregate ion> |
644 | | 13:26:08 INFO : ... Allocation at <Aggregate ion> complete. |
645 | | 13:26:08 INFO : All aggregates are complete. |
646 | | 13:26:08 INFO : Your resources expire at 2015-02-13T18:05:27 (UTC). |
647 | | 13:26:08 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' |
648 | | 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). |