338 | | omni.py createslice IG-ST-3 |
339 | | stitcher.py createsliver IG-ST-3 IG-ST-3.rspec -o |
| 338 | $ stitcher createsliver IG-ST-3 ./IG-ST-3.rspec |
| 339 | 12:12:39 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
| 340 | 12:12:40 INFO : Reading slice IG-ST-3 credential... |
| 341 | 12:12:40 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-3 expires on 2015-02-18 17:10:47 UTC |
| 342 | 12:12:40 INFO : Calling SCS... |
| 343 | 12:12:47 INFO : Multi-AM reservation will include resources from these aggregates: |
| 344 | 12:12:47 INFO : <Aggregate ukypks2-ig> |
| 345 | 12:12:47 INFO : <Aggregate gpo-ig> |
| 346 | 12:12:47 INFO : <Aggregate al2s> |
| 347 | 12:12:47 INFO : <Aggregate utc-ig> |
| 348 | 12:12:47 INFO : <Aggregate ion> |
| 349 | 12:12:47 INFO : Stitcher doing createsliver at <Aggregate ukypks2-ig>... |
| 350 | 12:13:03 INFO : ... Allocation at <Aggregate ukypks2-ig> complete. |
| 351 | 12:13:03 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 352 | 12:13:23 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 353 | 12:13:23 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
| 354 | 12:13:45 INFO : ... Allocation at <Aggregate utc-ig> complete. |
| 355 | 12:13:45 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
| 356 | 12:13:50 INFO : ... Allocation at <Aggregate al2s> complete. |
| 357 | 12:13:50 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 358 | 12:14:36 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 359 | 12:14:36 INFO : Pausing 30 seconds to let circuit become ready... |
| 360 | 12:15:13 INFO : Pausing 30 seconds to let circuit become ready... |
| 361 | 12:15:51 WARNING : sliverstatus: 153331 is (still) failed at <Aggregate ion>. Delete and retry. |
| 362 | 12:15:51 WARNING : sliverstatus: 153321 is (still) failed at <Aggregate ion>. Delete and retry. |
| 363 | 12:15:51 WARNING : Status had error message: VLAN cancelled by rollback from contingent failure |
| 364 | 12:15:51 INFO : Doing deletesliver at <Aggregate ion>... |
| 365 | 12:15:56 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'> |
| 366 | 12:15:56 INFO : Deleting some reservations to retry, avoiding failed VLAN... |
| 367 | 12:15:56 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
| 368 | 12:17:28 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+0bd79116b4fa73b9c08e39ec43460254', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=0bd79116b4fa73b9c08e39ec43460254'}, 'value': 0} |
| 369 | 12:17:38 INFO : ... aggregate was busy, will retry ... |
| 370 | 12:19:08 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+beb0e5656130abb4e2f839e5b0ca9573', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=beb0e5656130abb4e2f839e5b0ca9573'}, 'value': 0} |
| 371 | 12:19:18 INFO : ... aggregate was busy, will retry ... |
| 372 | 12:20:47 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+f950395b872abdba4a8f55b94ccccb6b', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=f950395b872abdba4a8f55b94ccccb6b'}, 'value': 0} |
| 373 | 12:20:57 INFO : ... aggregate was busy, will retry ... |
| 374 | 12:22:31 INFO : ... done. |
| 375 | 12:22:31 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>.... |
| 376 | 12:22:31 INFO : Pausing for 30 seconds for Aggregates to free up resources... |
| 377 | |
| 378 | |
| 379 | 12:23:01 INFO : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up.... |
| 380 | 12:23:03 ERROR : {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+74135c5ebf3faaf27657363b356da12a', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=74135c5ebf3faaf27657363b356da12a'}, 'value': 0} |
| 381 | 12:23:03 INFO : ... it is, so can try a new reservation. |
| 382 | 12:23:03 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 383 | 12:23:44 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 384 | 12:23:44 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 385 | 12:24:29 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 386 | 12:24:29 INFO : Pausing 30 seconds to let circuit become ready... |
| 387 | 12:25:06 INFO : Pausing 30 seconds to let circuit become ready... |
| 388 | 12:25:44 INFO : DCN circuit 153361 is ready at <Aggregate ion> |
| 389 | 12:25:44 INFO : DCN circuit 153351 is ready at <Aggregate ion> |
| 390 | 12:25:51 INFO : ... Allocation at <Aggregate ion> complete. |
| 391 | 12:25:51 INFO : All aggregates are complete. |
| 392 | 12:25:51 INFO : Your resources expire at 2015-02-17T17:02:47 (UTC). |
| 393 | 12:25:51 INFO : Saved combined reservation RSpec at 5 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-3-manifest-rspec-multiam-combined.xml' |
| 394 | Success: Reserved resources in slice IG-ST-3 at 5 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 2 stitched link(s). |
362 | | omni.py createslice IG-ST-4 |
363 | | stitcher.py createsliver IG-ST-4 IG-ST-4.rspec -o |
| 434 | $ stitcher.py createsliver IG-ST-4 IG-ST-4.rspec -o |
| 435 | 12:18:23 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
| 436 | 12:18:23 INFO : Reading slice IG-ST-4 credential... |
| 437 | 12:18:23 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4 expires on 2015-02-18 17:18:22 UTC |
| 438 | 12:18:23 INFO : Calling SCS... |
| 439 | 12:18:26 INFO : Multi-AM reservation will include resources from these aggregates: |
| 440 | 12:18:26 INFO : <Aggregate utc-ig> |
| 441 | 12:18:26 INFO : <Aggregate al2s> |
| 442 | 12:18:26 INFO : <Aggregate gpo-eg> |
| 443 | 12:18:26 INFO : <Aggregate ion> |
| 444 | 12:18:26 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
| 445 | 12:18:42 INFO : ... Allocation at <Aggregate utc-ig> complete. |
| 446 | 12:18:42 INFO : Stitcher doing createsliver at <Aggregate gpo-eg>... |
| 447 | 12:18:52 INFO : ... Allocation at <Aggregate gpo-eg> complete. |
| 448 | 12:18:52 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
| 449 | 12:18:55 INFO : ... Allocation at <Aggregate al2s> complete. |
| 450 | 12:18:55 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 451 | 12:19:40 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 452 | 12:19:40 INFO : Pausing 30 seconds to let circuit become ready... |
| 453 | 12:20:19 INFO : DCN circuit 153341 is ready at <Aggregate ion> |
| 454 | 12:20:27 INFO : ... Allocation at <Aggregate ion> complete. |
| 455 | 12:20:27 INFO : All aggregates are complete. |
| 456 | 12:20:27 INFO : Your resources expire at 2015-02-17T17:08:26 (UTC). |
| 457 | 12:20:27 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-4-manifest-rspec-multiam-combined.xml' |
| 458 | Success: Reserved resources in slice IG-ST-4 at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). |