Ticket #1059: log-lnstitchb.txt

File log-lnstitchb.txt, 12.8 KB (added by lnevers@bbn.com, 11 years ago)
Line 
1$ stitcher.py createsliver lnstitchb stitch-ig-gpo-pg-utah.rspec
210:44:18 INFO     stitcher: Loading config file /home/lnevers/.gcf/omni_config
310:44:18 INFO     stitcher: Using control framework portal
410:44:18 INFO     stitcher: Checking that slice lnstitchb is valid...
510: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
610:44:20 INFO     stitcher: <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> speaks AM API v3, but sticking with v2
710:44:20 INFO     stitcher: <Aggregate urn:publicid:IDN+emulab.net+authority+cm> speaks AM API v3, but sticking with v2
810:44:20 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-instageni-gpolab-bbn-com.xml'
910:44:20 INFO     stitch.Aggregate:
10        Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
1110:44:42 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> complete.
1210:44:42 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-emulab-net.xml'
1310:44:42 INFO     stitch.Aggregate:
14        Stitcher doing createsliver at https://www.emulab.net:12369/protogeni/xmlrpc/am
1510:45:59 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+emulab.net+authority+cm> complete.
1610:45:59 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-11-ion-internet2-edu.xml'
1710:45:59 INFO     stitch.Aggregate:
18        Stitcher doing createsliver at http://geni-am.net.internet2.edu:12346
1910:46:44 INFO     stitch.Aggregate: DCN AM <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm>: must wait for status ready....
2010:46:44 INFO     stitch.Aggregate: Pause to let circuit become ready...
2110:47:24 INFO     stitch.Aggregate: Pause to let circuit become ready...
2210:48:02 INFO     stitch.Aggregate: DCN circuit 21671 is ready
2310: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
24path u'link'> which is not in our request 983
2510: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
26'> which is not in our request 3748
2710:48:10 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> complete.
2810: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
2910:48:10 WARNING  stitcher: Had reservation at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
3010:48:10 INFO     stitch.Aggregate: Doing deletesliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
3110:49:01 WARNING  stitcher: Deleted reservation at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
3210:49:01 WARNING  stitcher: Had reservation at http://geni-am.net.internet2.edu:12346
3310:49:01 INFO     stitch.Aggregate: Doing deletesliver at http://geni-am.net.internet2.edu:12346
3410:49:06 WARNING  stitcher: Deleted reservation at http://geni-am.net.internet2.edu:12346
3510:49:06 WARNING  stitcher: Had reservation at https://www.emulab.net:12369/protogeni/xmlrpc/am
3610:49:06 INFO     stitch.Aggregate: Doing deletesliver at https://www.emulab.net:12369/protogeni/xmlrpc/am
3710:50:21 WARNING  stitcher: Deleted reservation at https://www.emulab.net:12369/protogeni/xmlrpc/am
3810:50:21 INFO     stitcher: Calling SCS for the 2th time...
3910:50:22 INFO     stitcher: Pausing for 600 seconds for Aggregates to free up resources...
40
41
4211:00:22 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-21-instageni-gpolab-bbn-com.xml'
4311:00:22 INFO     stitch.Aggregate:
44        Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
4511: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
46eni', 'geni_code': 1, 'am_code': 1, 'protogeni_error_url': 'https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=d893f58236f5fe4e29d8dee260ebee87'}, 'value': 0}
4711:01:49 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+emulab.net+authority+cm> complete.
4811:01:49 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-22-instageni-gpolab-bbn-com.xml'
4911:01:49 INFO     stitch.Aggregate:
50        Stitcher doing createsliver at https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am
5111:02:08 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm> complete.
5211:02:08 INFO     stitch.Aggregate: Writing to '/tmp/lnstitchb-createsliver-request-21-ion-internet2-edu.xml'
5311:02:08 INFO     stitch.Aggregate:
54        Stitcher doing createsliver at http://geni-am.net.internet2.edu:12346
5511:02:56 INFO     stitch.Aggregate: DCN AM <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm>: must wait for status ready....
5611:02:56 INFO     stitch.Aggregate: Pause to let circuit become ready...
5711:03:34 INFO     stitch.Aggregate: DCN circuit 21721 is ready
5811:03:41 INFO     stitch.Aggregate: Allocation at <Aggregate urn:publicid:IDN+ion.internet2.edu+authority+cm> complete.
5911:03:41 INFO     stitch.launcher: All aggregates are complete.
6011:03:41 INFO     stitcher: <?xml version="1.0" ?>
6111:03:41 INFO     stitcher:   <!-- Reserved resources for:
62        Slice: lnstitchb
63        at AM:
64        URN: stitching-combined
65        URL:
66 -->
6711:03:41 INFO     stitcher:   
68<!--
69Aggregate Details
70{
71  "url": "https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am",
72  "hops_info": [
73    {
74      "path_id": "link",
75      "path_global_id": "ion.internet2.edu-21721",
76      "urn": "urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24",
77      "vlan_tag": "3747",
78      "id": "1"
79    }
80  ],
81  "urn": "urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm",
82  "user_requested": true,
83  "api_version": 2
84}
85{
86  "url": "http://geni-am.net.internet2.edu:12346",
87  "hops_info": [
88    {
89      "path_id": "link",
90      "path_global_id": "ion.internet2.edu-21721",
91      "urn": "urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni",
92      "vlan_tag": "974",
93      "id": "3"
94    },
95    {
96      "path_id": "link",
97      "path_global_id": "ion.internet2.edu-21721",
98      "urn": "urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn",
99      "vlan_tag": "3747",
100      "id": "2"
101    }
102  ],
103  "urn": "urn:publicid:IDN+ion.internet2.edu+authority+cm",
104  "user_requested": false,
105  "api_version": 2
106}
107{
108  "url": "https://www.emulab.net:12369/protogeni/xmlrpc/am",
109  "hops_info": [
110    {
111      "path_id": "link",
112      "path_global_id": "ion.internet2.edu-21721",
113      "urn": "urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21",
114      "vlan_tag": "974",
115      "id": "4"
116    }
117  ],
118  "urn": "urn:publicid:IDN+emulab.net+authority+cm",
119  "user_requested": true,
120  "api_version": 2
121}
122-->
123<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
124maLocation="http://www.geni.net/resources/rspec/3 http://www.geni.net/resources/rspec/3/manifest.xsd">
125        <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
126d="urn:publicid:IDN+instageni.gpolab.bbn.com+sliver+6333">
127                <sliver_type name="emulab-openvz"/>
128                <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
129om+sliver+6338">
130                        <ip address="192.168.4.1" netmask="255.255.255.0" type="ipv4"/>
131                </interface>
132                <rs:vnode name="pcvm1-10" xmlns:rs="http://www.protogeni.net/resources/rspec/ext/emulab/1"/>
133                <host name="ig-gpo.lnstitchb.ch-geni-net.instageni.gpolab.bbn.com"/>
134                <services>
135                        <login authentication="ssh-keys" hostname="pc1.instageni.gpolab.bbn.com" port="32570" username="lnevers"/>
136                </services>
137        </node>
138        <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
139b.net+sliver+135507">
140                <sliver_type name="emulab-openvz"/>
141                <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">
142                        <ip address="192.168.4.2" netmask="255.255.255.0" type="ipv4"/>
143                </interface>
144                <rs:vnode name="pcvm545-10" xmlns:rs="http://www.protogeni.net/resources/rspec/ext/emulab/1"/>
145                <host name="pg-utah.lnstitchb.ch-geni-net.emulab.net"/>
146                <services>
147                        <login authentication="ssh-keys" hostname="pc545.emulab.net" port="35130" username="lnevers"/>
148                </services>
149        </node>
150        <link client_id="link" sliver_id="urn:publicid:IDN+ion.internet2.edu+sliver+ion.internet2.edu-21721" vlantag="3747:974">
151                <!--AM urn:publicid:IDN+emulab.net+authority+cm: sliver_id=urn:publicid:IDN+emulab.net+sliver+135509 vlantag=974-->
152                <!--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-->
153                <!--AM urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm: sliver_id=urn:publicid:IDN+instageni.gpolab.bbn.com+sliver+6337 vlantag=3747-->
154                <component_manager name="urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm"/>
155                <component_manager name="urn:publicid:IDN+ion.internet2.edu+authority+cm"/>
156                <component_manager name="urn:publicid:IDN+emulab.net+authority+cm"/>
157                <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"/>
158                <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"/>
159                <property dest_id="pg-utah:if0" source_id="ig-gpo:if0"/>
160                <property dest_id="ig-gpo:if0" source_id="pg-utah:if0"/>
161        </link>
162        <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
163t.isi.edu/rspec/ext/stitch/0.1/ http://hpn.east.isi.edu/rspec/ext/stitch/0.1/stitch-schema.xsd">
164                <path id="link">
165                        <globalId>
166        ion.internet2.edu-21721
167                        </globalId>
168                        <lifetime id="ion.internet2.edu-21721">
169                                <start type="xgc">
170          2013-06-21T21:36:54.000Z
171                                </start>
172                                <end type="xgc">
173          2013-06-21T15:03:26.856Z
174                                </end>
175                        </lifetime>
176                        <hop id="1">
177                                <link id="urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24">
178                                        <trafficEngineeringMetric>
179                                                10
180                                        </trafficEngineeringMetric>
181                                        <capacity>
182                                                100000
183                                        </capacity>
184                                        <switchingCapabilityDescriptor>
185                                                <switchingcapType>
186                                                        l2sc
187                                                </switchingcapType>
188                                                <encodingType>
189                                                        ethernet
190                                                </encodingType>
191                                                <switchingCapabilitySpecificInfo>
192                                                        <switchingCapabilitySpecificInfo_L2sc>
193                                                                <interfaceMTU>
194                                                                        9000
195                                                                </interfaceMTU>
196                                                                <vlanRangeAvailability>
197                                                                        3747
198                                                                </vlanRangeAvailability>
199                                                                <suggestedVLANRange>
200                                                                        3747
201                                                                </suggestedVLANRange>
202                                                                <vlanTranslation>
203                                                                        false
204                                                                </vlanTranslation>
205                                                        </switchingCapabilitySpecificInfo_L2sc>
206                                                </switchingCapabilitySpecificInfo>
207                                        </switchingCapabilityDescriptor>
208                                </link>
209                                <nextHop>
210                                        2
211                                </nextHop>
212                        </hop>
213                        <hop id="4">
214                                <link id="urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21">
215                                        <trafficEngineeringMetric>
216                                                10
217                                        </trafficEngineeringMetric>
218                                        <capacity>
219                                                100000
220                                        </capacity>
221                                        <switchingCapabilityDescriptor>
222                                                <switchingcapType>
223                                                        l2sc
224                                                </switchingcapType>
225                                                <encodingType>
226                                                        ethernet
227                                                </encodingType>
228                                                <switchingCapabilitySpecificInfo>
229                                                        <switchingCapabilitySpecificInfo_L2sc>
230                                                                <interfaceMTU>
231                                                                        9000
232                                                                </interfaceMTU>
233                                                                <vlanRangeAvailability>
234                                                                        974
235                                                                </vlanRangeAvailability>
236                                                                <suggestedVLANRange>
237                                                                        974
238                                                                </suggestedVLANRange>
239                                                                <vlanTranslation>
240                                                                        false
241                                                                </vlanTranslation>
242                                                        </switchingCapabilitySpecificInfo_L2sc>
243                                                </switchingCapabilitySpecificInfo>
244                                        </switchingCapabilityDescriptor>
245                                </link>
246                                <nextHop>
247                                        null
248                                </nextHop>
249                        </hop>
250                </path>
251        </stitching>
252</rspec>
253
254Stitching success: Reserved resources in slice lnstitchb at 3 Aggregates (including 1 intermediate aggregate(s) not in the original request), creating 1 link(s).