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). |
| 434 | $ stitcher.py createsliver IG-ST-4a IG-ST-4.rspec -o |
| 435 | 12:41:39 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
| 436 | 12:41:40 INFO : Reading slice IG-ST-4a credential... |
| 437 | 12:41:40 INFO : Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+IG-ST-4a expires on 2015-02-18 17:41:39 UTC |
| 438 | 12:41:40 INFO : Calling SCS... |
| 439 | 12:41:42 INFO : Multi-AM reservation will include resources from these aggregates: |
| 440 | 12:41:42 INFO : <Aggregate utc-ig> |
| 441 | 12:41:42 INFO : <Aggregate al2s> |
| 442 | 12:41:42 INFO : <Aggregate gpo-eg> |
| 443 | 12:41:42 INFO : <Aggregate ion> |
| 444 | 12:41:42 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
| 445 | 12:42:02 INFO : ... Allocation at <Aggregate utc-ig> complete. |
| 446 | 12:42:02 INFO : Stitcher doing createsliver at <Aggregate gpo-eg>... |
| 447 | 12:42:14 INFO : ... Allocation at <Aggregate gpo-eg> complete. |
| 448 | 12:42:14 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
| 449 | 12:42:17 INFO : ... Allocation at <Aggregate al2s> complete. |
| 450 | 12:42:17 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 451 | 12:43:02 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 452 | 12:43:02 INFO : Pausing 30 seconds to let circuit become ready... |
| 453 | 12:43:41 INFO : Pausing 30 seconds to let circuit become ready... |
| 454 | 12:44:18 INFO : DCN circuit 153371 is ready at <Aggregate ion> |
| 455 | 12:44:25 INFO : ... Allocation at <Aggregate ion> complete. |
| 456 | 12:44:25 INFO : All aggregates are complete. |
| 457 | 12:44:25 INFO : Your resources expire at 2015-02-17T17:31:42 (UTC). |
| 458 | 12:44:25 INFO : Saved combined reservation RSpec at 4 AMs to file '/home/lnevers/gcf-test/stitch-test/UTC/IG-ST-4a-manifest-rspec-multiam-combined.xml' |
| 459 | Success: Reserved resources in slice IG-ST-4a at 4 Aggregates (including 2 intermediate aggregate(s) not in the original request), creating 1 stitched link(s). |
475 | | ''Collected: 2015-01-XX'' |
| 479 | ''Collected: 2015-02-11'' |
| 480 | |
| 481 | __One Client__ |
| 482 | {{{ |
| 483 | [ ID] Interval Transfer Bandwidth |
| 484 | [ 3] 0.0-60.1 sec 139 MBytes 19.4 Mbits/sec |
| 485 | }}} |
| 486 | |
| 487 | __Five Clients__ |
| 488 | {{{ |
| 489 | [ ID] Interval Transfer Bandwidth |
| 490 | [ 6] 0.0-60.0 sec 28.9 MBytes 4.03 Mbits/sec |
| 491 | [ 7] 0.0-60.2 sec 29.0 MBytes 4.04 Mbits/sec |
| 492 | [ 5] 0.0-60.4 sec 28.4 MBytes 3.94 Mbits/sec |
| 493 | [ 4] 0.0-60.8 sec 29.4 MBytes 4.05 Mbits/sec |
| 494 | [ 3] 0.0-61.0 sec 28.5 MBytes 3.92 Mbits/sec |
| 495 | [SUM] 0.0-61.0 sec 144 MBytes 19.8 Mbits/sec |
| 496 | }}} |
| 497 | |
| 498 | __Ten Clients__ |
| 499 | {{{ |
| 500 | [ ID] Interval Transfer Bandwidth |
| 501 | [ 4] 0.0-60.0 sec 15.6 MBytes 2.18 Mbits/sec |
| 502 | [ 6] 0.0-60.1 sec 15.9 MBytes 2.22 Mbits/sec |
| 503 | [ 10] 0.0-60.3 sec 14.5 MBytes 2.02 Mbits/sec |
| 504 | [ 11] 0.0-60.3 sec 13.5 MBytes 1.88 Mbits/sec |
| 505 | [ 7] 0.0-60.5 sec 14.8 MBytes 2.04 Mbits/sec |
| 506 | [ 8] 0.0-60.6 sec 13.8 MBytes 1.90 Mbits/sec |
| 507 | [ 9] 0.0-61.1 sec 14.1 MBytes 1.94 Mbits/sec |
| 508 | [ 5] 0.0-61.3 sec 15.0 MBytes 2.05 Mbits/sec |
| 509 | [ 12] 0.0-61.7 sec 14.9 MBytes 2.02 Mbits/sec |
| 510 | [ 3] 0.0-62.5 sec 16.2 MBytes 2.18 Mbits/sec |
| 511 | [SUM] 0.0-62.5 sec 148 MBytes 19.9 Mbits/sec |
| 512 | }}} |
| 513 | |
| 514 | '''Iperf ExoGENI GPO VM to InstaGENI UTC VM (UDP) - UDP buffer size: 208 KByte (default) ''' |
| 515 | {{{ |
| 516 | [ ID] Interval Transfer Bandwidth |
| 517 | [ 3] 0.0-60.0 sec 719 MBytes 101 Mbits/sec |
| 518 | [ 3] Sent 512808 datagrams |
| 519 | [ 3] Server Report: |
| 520 | [ 3] 0.0-60.2 sec 140 MBytes 19.4 Mbits/sec 0.139 ms 413258/512807 (81%) |
| 521 | [ 3] 0.0-60.2 sec 1 datagrams received out-of-order |
| 522 | }}} |
| 523 | |
| 524 | '''Ping from ExoGENI GPO VM to the InstaGENI UTC VM ''' |
| 525 | {{{ |
| 526 | 60 packets transmitted, 60 received, 0% packet loss, time 59079ms |
| 527 | rtt min/avg/max/mdev = 27.777/28.584/46.229/2.675 ms |
| 528 | }}} |
| 529 | |
| 530 | |
| 531 | '''Iperf InstaGENI UTC VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' |
| 532 | |
| 533 | ''Collected: 2015-02-11'' |
489 | | |
490 | | }}} |
491 | | |
492 | | '''Iperf ExoGENI GPO VM to InstaGENI UTC VM (UDP) - UDP buffer size: 208 KByte (default) ''' |
493 | | {{{ |
494 | | |
495 | | }}} |
496 | | |
497 | | '''Ping from ExoGENI GPO VM to the InstaGENI UTC VM ''' |
498 | | {{{ |
499 | | |
500 | | }}} |
501 | | |
502 | | |
503 | | '''Iperf InstaGENI UTC VM to ExoGENI GPO VM (TCP) - TCP window size: 23.5 KByte (default)''' |
504 | | |
505 | | ''Collected: 2015-01-XX'' |
506 | | |
507 | | __One Client_ |
508 | | {{{ |
509 | | |
510 | | }}} |
511 | | |
512 | | __Five Clients__ |
513 | | {{{ |
514 | | |
515 | | }}} |
516 | | |
517 | | __Ten Clients__ |
518 | | {{{ |
519 | | |
| 554 | [ ID] Interval Transfer Bandwidth |
| 555 | [ 9] 0.0-60.2 sec 20.0 MBytes 2.79 Mbits/sec |
| 556 | [ 7] 0.0-60.3 sec 13.8 MBytes 1.91 Mbits/sec |
| 557 | [ 8] 0.0-60.6 sec 9.38 MBytes 1.30 Mbits/sec |
| 558 | [ 6] 0.0-60.7 sec 15.2 MBytes 2.11 Mbits/sec |
| 559 | [ 5] 0.0-60.8 sec 11.1 MBytes 1.54 Mbits/sec |
| 560 | [ 11] 0.0-60.9 sec 10.8 MBytes 1.48 Mbits/sec |
| 561 | [ 12] 0.0-61.0 sec 16.0 MBytes 2.20 Mbits/sec |
| 562 | [ 3] 0.0-61.2 sec 21.4 MBytes 2.93 Mbits/sec |
| 563 | [ 10] 0.0-61.4 sec 11.2 MBytes 1.54 Mbits/sec |
| 564 | [ 4] 0.0-61.6 sec 13.6 MBytes 1.85 Mbits/sec |
| 565 | [SUM] 0.0-61.6 sec 142 MBytes 19.4 Mbits/sec |
548 | | omni.py createslice IG-ST-6 |
549 | | stitcher.py createsliver IG-ST-6 IG-ST-6.rspec -o |
| 600 | $ stitcher createsliver IG-ST-6 ./IG-ST-6.rspec |
| 601 | 12:42:40 INFO : Configured logging from file /home/lnevers/gcf-2.8/src/gcf/stitcher_logging.conf |
| 602 | 12:42:41 INFO : Reading slice IG-ST-6 credential... |
| 603 | 12:42:41 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:42:41 INFO : Calling SCS... |
| 605 | 12:42:43 INFO : Multi-AM reservation will include resources from these aggregates: |
| 606 | 12:42:43 INFO : <Aggregate utc-ig> |
| 607 | 12:42:43 INFO : <Aggregate gpo-ig> |
| 608 | 12:42:43 INFO : <Aggregate al2s> |
| 609 | 12:42:43 INFO : <Aggregate ion> |
| 610 | 12:42:43 INFO : Stitcher doing createsliver at <Aggregate utc-ig>... |
| 611 | 12:43:02 INFO : ... Allocation at <Aggregate utc-ig> complete. |
| 612 | 12:43:02 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 613 | 12:43:20 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 614 | 12:43:20 INFO : Stitcher doing createsliver at <Aggregate al2s>... |
| 615 | 12:43:23 INFO : ... Allocation at <Aggregate al2s> complete. |
| 616 | 12:43:23 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 617 | 12:44:08 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 618 | 12:44:08 INFO : Pausing 30 seconds to let circuit become ready... |
| 619 | 12:44:46 WARNING : sliverstatus: 153381 is (still) failed at <Aggregate ion>. Delete and retry. |
| 620 | 12:44:46 INFO : Doing deletesliver at <Aggregate ion>... |
| 621 | 12:44:51 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 | 12:44:51 INFO : Deleting some reservations to retry, avoiding failed VLAN... |
| 623 | 12:44:51 INFO : Doing deletesliver at <Aggregate gpo-ig>... |
| 624 | 12:46:19 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+c905a6d42e2a9596f6f4dede975404b4', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=c905a6d42e2a9596f6f4dede975404b4'}, 'value': 0} |
| 625 | 12:46:29 INFO : ... aggregate was busy, will retry ... |
| 626 | 12:47:55 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+b1789155aeffdf7e55575110db6c16fe', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=b1789155aeffdf7e55575110db6c16fe'}, 'value': 0} |
| 627 | 12:48:05 INFO : ... aggregate was busy, will retry ... |
| 628 | 12:49:32 ERROR : {'output': 'start/restart in progress; try again later', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+e11a0e054523dcf46194b90e5111a024', 'am_type': 'protogeni', 'geni_code': 14, 'am_code': 14, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=e11a0e054523dcf46194b90e5111a024'}, 'value': 0} |
| 629 | 12:49:42 INFO : ... aggregate was busy, will retry ... |
| 630 | 12:51:12 INFO : ... done. |
| 631 | 12:51:12 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 | 12:51:12 INFO : Pausing for 30 seconds for Aggregates to free up resources... |
| 633 | |
| 634 | |
| 635 | 12:51:42 INFO : Checking that prior reservation at <Aggregate gpo-ig> has been cleared up.... |
| 636 | 12:51:44 ERROR : {'output': 'No slice or aggregate here', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+a1b7067f79f550672d0b9cbd55257b19', 'am_type': 'protogeni', 'geni_code': 12, 'am_code': 12, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=a1b7067f79f550672d0b9cbd55257b19'}, 'value': 0} |
| 637 | 12:51:45 INFO : ... it is, so can try a new reservation. |
| 638 | 12:51:45 INFO : Stitcher doing createsliver at <Aggregate gpo-ig>... |
| 639 | 12:52:23 INFO : ... Allocation at <Aggregate gpo-ig> complete. |
| 640 | 12:52:23 INFO : Stitcher doing createsliver at <Aggregate ion>... |
| 641 | 12:53:08 INFO : DCN AM <Aggregate ion>: must wait for status ready.... |
| 642 | 12:53:08 INFO : Pausing 30 seconds to let circuit become ready... |
| 643 | 12:53:46 INFO : DCN circuit 153391 is ready at <Aggregate ion> |
| 644 | 12:53:53 INFO : ... Allocation at <Aggregate ion> complete. |
| 645 | 12:53:53 INFO : All aggregates are complete. |
| 646 | 12:53:53 INFO : Your resources expire at 2015-02-13T17:32:43 (UTC). |
| 647 | 12:53:53 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). |