$ stitcher.py createsliver lnstitchb stitch-ig-gpo-pg-utah.rspec 
10:44:18 INFO     stitcher: Loading config file /home/lnevers/.gcf/omni_config
10:44:18 INFO     stitcher: Using control framework portal
10:44:18 INFO     stitcher: Checking that slice lnstitchb is valid...
10:44:20 INFO     stitcher: Slice urn:publicid:IDN+ch.geni.net:ln-prj+slice+lnstitchb expires within 1 day on 2013-06-21 21:36:54 UTC
10:44:20 INFO     stitcher: <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> speaks AM API v3, but sticking with v2
10:44:20 INFO     stitcher: <Aggregate urn:publicid:IDN+emulab.net+authority+cm> speaks AM API v3, but sticking with v2
10:44:20 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-instageni-gpolab-bbn-com.xml'
10:44:20 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
10:44:42 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> complete.
10:44:42 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-emulab-net.xml'
10:44:42 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at https://www.emulab.net:12369/protogeni/xmlrpc/am
10:45:59 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+emulab.net+authority+cm> complete.
10:45:59 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-ion-internet2-edu.xml'
10:45:59 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at http://geni-am.net.internet2.edu:12346
10:46:44 INFO     stitch.Aggregate: DCN AM <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm>: must wait for status ready....
10:46:44 INFO     stitch.Aggregate: Pause to let circuit become ready...
10:47:24 INFO     stitch.Aggregate: Pause to let circuit become ready...
10:48:02 INFO     stitch.Aggregate: DCN circuit 21671 is ready
10:48:10 ERROR    stitch.Aggregate: <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> gave VLAN 886 for hop <Hop u'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni' on 
path u'link'> which is not in our request 983
10:48:10 ERROR    stitch.Aggregate: <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> gave VLAN 3749 for hop <Hop u'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn' on path u'link
'> which is not in our request 3748
10:48:10 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> complete.
10:48:10 WARNING  stitcher: Stitching failed but will retry: Circuit reservation failed at <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm>. Try again from the SCS
10:48:10 WARNING  stitcher: Had reservation at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
10:48:10 INFO     stitch.Aggregate: Doing deletesliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
10:49:01 WARNING  stitcher: Deleted reservation at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
10:49:01 WARNING  stitcher: Had reservation at http://geni-am.net.internet2.edu:12346
10:49:01 INFO     stitch.Aggregate: Doing deletesliver at http://geni-am.net.internet2.edu:12346
10:49:06 WARNING  stitcher: Deleted reservation at http://geni-am.net.internet2.edu:12346
10:49:06 WARNING  stitcher: Had reservation at https://www.emulab.net:12369/protogeni/xmlrpc/am
10:49:06 INFO     stitch.Aggregate: Doing deletesliver at https://www.emulab.net:12369/protogeni/xmlrpc/am
10:50:21 WARNING  stitcher: Deleted reservation at https://www.emulab.net:12369/protogeni/xmlrpc/am
10:50:21 INFO     stitcher: Calling SCS for the 2th time...
10:50:22 INFO     stitcher: Pausing for 600 seconds for Aggregates to free up resources...


11:00:22 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-21-instageni-gpolab-bbn-com.xml'
11:00:22 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
11:00:40 ERROR    omni:  {'output': 'vlan tag for link not available', 'code': {'protogeni_error_log': 'urn:publicid:IDN+instageni.gpolab.bbn.com+log+d893f58236f5fe4e29d8dee260ebee87', 'am_type': 'protog
eni', 'geni_code': 1, 'am_code': 1, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=d893f58236f5fe4e29d8dee260ebee87'}, 'value': 0}
11:01:49 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+emulab.net+authority+cm> complete.
11:01:49 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-22-instageni-gpolab-bbn-com.xml'
11:01:49 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
11:02:08 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> complete.
11:02:08 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-21-ion-internet2-edu.xml'
11:02:08 INFO     stitch.Aggregate: 
	Stitcher doing createsliver at http://geni-am.net.internet2.edu:12346
11:02:56 INFO     stitch.Aggregate: DCN AM <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm>: must wait for status ready....
11:02:56 INFO     stitch.Aggregate: Pause to let circuit become ready...
11:03:34 INFO     stitch.Aggregate: DCN circuit 21721 is ready
11:03:41 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> complete.
11:03:41 INFO     stitch.launcher: All aggregates are complete.
11:03:41 INFO     stitcher: <?xml version="1.0" ?>
11:03:41 INFO     stitcher:   <!-- Reserved resources for:
	Slice: lnstitchb
	at AM:
	URN: stitching-combined
	URL: 
 -->
11:03:41 INFO     stitcher:   
<!--
Aggregate Details
{
  "url": "https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am", 
  "hops_info": [
    {
      "path_id": "link", 
      "path_global_id": "ion.internet2.edu-21721", 
      "urn": "urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24", 
      "vlan_tag": "3747", 
      "id": "1"
    }
  ], 
  "urn": "urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm", 
  "user_requested": true, 
  "api_version": 2
}
{
  "url": "http://geni-am.net.internet2.edu:12346", 
  "hops_info": [
    {
      "path_id": "link", 
      "path_global_id": "ion.internet2.edu-21721", 
      "urn": "urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni", 
      "vlan_tag": "974", 
      "id": "3"
    }, 
    {
      "path_id": "link", 
      "path_global_id": "ion.internet2.edu-21721", 
      "urn": "urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn", 
      "vlan_tag": "3747", 
      "id": "2"
    }
  ], 
  "urn": "urn:publicid:IDN+ion.internet2.edu+authority+cm", 
  "user_requested": false, 
  "api_version": 2
}
{
  "url": "https://www.emulab.net:12369/protogeni/xmlrpc/am", 
  "hops_info": [
    {
      "path_id": "link", 
      "path_global_id": "ion.internet2.edu-21721", 
      "urn": "urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21", 
      "vlan_tag": "974", 
      "id": "4"
    }
  ], 
  "urn": "urn:publicid:IDN+emulab.net+authority+cm", 
  "user_requested": true, 
  "api_version": 2
}
-->
<rspec expires="2013-06-21T15:03:26.856Z" generated="2013-06-21T15:03:26.856Z" type="manifest" xmlns="http://www.geni.net/resources/rspec/3" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:sche
maLocation="http://www.geni.net/resources/rspec/3 http://www.geni.net/resources/rspec/3/manifest.xsd">
	<node client_id="ig-gpo" component_id="urn:publicid:IDN+instageni.gpolab.bbn.com+node+pc1" component_manager_id="urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm" exclusive="false" sliver_i
d="urn:publicid:IDN+instageni.gpolab.bbn.com+sliver+6333">
		<sliver_type name="emulab-openvz"/>
		<interface client_id="ig-gpo:if0" component_id="urn:publicid:IDN+instageni.gpolab.bbn.com+interface+pc1:eth1" mac_address="025ac181d9a5" sliver_id="urn:publicid:IDN+instageni.gpolab.bbn.c
om+sliver+6338">
			<ip address="192.168.4.1" netmask="255.255.255.0" type="ipv4"/>
		</interface>
		<rs:vnode name="pcvm1-10" xmlns:rs="http://www.protogeni.net/resources/rspec/ext/emulab/1"/>
		<host name="ig-gpo.lnstitchb.ch-geni-net.instageni.gpolab.bbn.com"/>
		<services>
			<login authentication="ssh-keys" hostname="pc1.instageni.gpolab.bbn.com" port="32570" username="lnevers"/>
		</services>
	</node>
	<node client_id="pg-utah" component_id="urn:publicid:IDN+emulab.net+node+pc545" component_manager_id="urn:publicid:IDN+emulab.net+authority+cm" exclusive="false" sliver_id="urn:publicid:IDN+emula
b.net+sliver+135507">
		<sliver_type name="emulab-openvz"/>
		<interface client_id="pg-utah:if0" component_id="urn:publicid:IDN+emulab.net+interface+pc545:eth3" mac_address="020b16e0ef00" sliver_id="urn:publicid:IDN+emulab.net+sliver+135510">
			<ip address="192.168.4.2" netmask="255.255.255.0" type="ipv4"/>
		</interface>
		<rs:vnode name="pcvm545-10" xmlns:rs="http://www.protogeni.net/resources/rspec/ext/emulab/1"/>
		<host name="pg-utah.lnstitchb.ch-geni-net.emulab.net"/>
		<services>
			<login authentication="ssh-keys" hostname="pc545.emulab.net" port="35130" username="lnevers"/>
		</services>
	</node>
	<link client_id="link" sliver_id="urn:publicid:IDN+ion.internet2.edu+sliver+ion.internet2.edu-21721" vlantag="3747:974">
		<!--AM urn:publicid:IDN+emulab.net+authority+cm: sliver_id=urn:publicid:IDN+emulab.net+sliver+135509 vlantag=974-->
		<!--AM urn:publicid:IDN+ion.internet2.edu+authority+cm: sliver_id=urn:publicid:IDN+ion.internet2.edu+sliver+ion.internet2.edu-21721 vlantag=3747:974-->
		<!--AM urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm: sliver_id=urn:publicid:IDN+instageni.gpolab.bbn.com+sliver+6337 vlantag=3747-->
		<component_manager name="urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm"/>
		<component_manager name="urn:publicid:IDN+ion.internet2.edu+authority+cm"/>
		<component_manager name="urn:publicid:IDN+emulab.net+authority+cm"/>
		<interface_ref client_id="ig-gpo:if0" component_id="urn:publicid:IDN+instageni.gpolab.bbn.com+interface+pc1:eth1" sliver_id="urn:publicid:IDN+instageni.gpolab.bbn.com+sliver+6338"/>
		<interface_ref client_id="pg-utah:if0" component_id="urn:publicid:IDN+emulab.net+interface+pc545:eth3" sliver_id="urn:publicid:IDN+emulab.net+sliver+135510"/>
		<property dest_id="pg-utah:if0" source_id="ig-gpo:if0"/>
		<property dest_id="ig-gpo:if0" source_id="pg-utah:if0"/>
	</link>
	<stitching lastUpdateTime="2013-06-21T15:03:26.856Z" xmlns="http://hpn.east.isi.edu/rspec/ext/stitch/0.1/" xmlns:xsi="http://www.w3.org/2001/XMLSchema-instance" xsi:schemaLocation="http://hpn.eas
t.isi.edu/rspec/ext/stitch/0.1/ http://hpn.east.isi.edu/rspec/ext/stitch/0.1/stitch-schema.xsd">
		<path id="link">
			<globalId>
        ion.internet2.edu-21721
			</globalId>
			<lifetime id="ion.internet2.edu-21721">
				<start type="xgc">
          2013-06-21T21:36:54.000Z
				</start>
				<end type="xgc">
          2013-06-21T15:03:26.856Z
				</end>
			</lifetime>
			<hop id="1">
				<link id="urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24">
					<trafficEngineeringMetric>
						10
					</trafficEngineeringMetric>
					<capacity>
						100000
					</capacity>
					<switchingCapabilityDescriptor>
						<switchingcapType>
							l2sc
						</switchingcapType>
						<encodingType>
							ethernet
						</encodingType>
						<switchingCapabilitySpecificInfo>
							<switchingCapabilitySpecificInfo_L2sc>
								<interfaceMTU>
									9000
								</interfaceMTU>
								<vlanRangeAvailability>
									3747
								</vlanRangeAvailability>
								<suggestedVLANRange>
									3747
								</suggestedVLANRange>
								<vlanTranslation>
									false
								</vlanTranslation>
							</switchingCapabilitySpecificInfo_L2sc>
						</switchingCapabilitySpecificInfo>
					</switchingCapabilityDescriptor>
				</link>
				<nextHop>
					2
				</nextHop>
			</hop>
			<hop id="4">
				<link id="urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21">
					<trafficEngineeringMetric>
						10
					</trafficEngineeringMetric>
					<capacity>
						100000
					</capacity>
					<switchingCapabilityDescriptor>
						<switchingcapType>
							l2sc
						</switchingcapType>
						<encodingType>
							ethernet
						</encodingType>
						<switchingCapabilitySpecificInfo>
							<switchingCapabilitySpecificInfo_L2sc>
								<interfaceMTU>
									9000
								</interfaceMTU>
								<vlanRangeAvailability>
									974
								</vlanRangeAvailability>
								<suggestedVLANRange>
									974
								</suggestedVLANRange>
								<vlanTranslation>
									false
								</vlanTranslation>
							</switchingCapabilitySpecificInfo_L2sc>
						</switchingCapabilitySpecificInfo>
					</switchingCapabilityDescriptor>
				</link>
				<nextHop>
					null
				</nextHop>
			</hop>
		</path>
	</stitching>
</rspec>

Stitching success: Reserved resources in slice lnstitchb at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 1 link(s).