10/10 08:43:55 INFO oscript.py:917 Configured logging from file /home/lnevers/gcf-2.7-rc7/src/gcf/stitcher_logging.conf 10/10 08:43:55 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:43:55 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:43:55 INFO oscript.py:348 Using control framework portal 10/10 08:43:55 DEBUG stitcher.py:336 Using SCS URL from omni_config: http://nutshell.maxgigapop.net:8081/geni/xmlrpc 10/10 08:43:55 DEBUG stitcher.py:395 Python: CPython 2.6.5 OS: Linux-2.6.32-64-generic-pae-i686-with-Ubuntu-10.04-lucid Stitcher: GENI Omni Command Line Aggregate Manager Tool Version 2.7 Copyright (c) 2014 Raytheon BBN Technologies 10/10 08:43:55 DEBUG stitcher.py:396 Running stitcher ... Options as run: framework: portal ionRetryIntervalSecs: 120 ionStatusIntervalSecs: 60 logconfig: None output: True project: ln-test scsURL: http://nutshell.maxgigapop.net:8081/geni/xmlrpc Args: createsliver star-pks2 /home/lnevers/gcf-test/stitch-test/stitch-star-pks2.rspec 10/10 08:43:55 DEBUG oscript.py:453 Using framework type chapi 10/10 08:43:55 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:43:55 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:43:55 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:43:56 DEBUG stitchhandler.py:185 Valid GENI v3 request RSpec 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-pgky' interface 'ig-pks2:if0' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-pgky' interface 'ig-nyse:if0' found on node 'ig-nyse' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-iggpo' interface 'ig-gpo:if0' found on node 'ig-gpo' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-iggpo' interface 'ig-pks2:if1' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-rut' interface 'ig-pks2:if2' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-rut' interface 'ig-rutgers:if0' found on node 'ig-rutgers' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-igutah' interface 'ig-pks2:if3' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-igutah' interface 'ig-utah:if0' found on node 'ig-utah' 10/10 08:43:56 DEBUG stitchhandler.py:1214 Link 'link-2-pgky' properties will be from 'ig-pks2:if0' to 'ig-nyse:if0' 10/10 08:43:56 DEBUG stitchhandler.py:1367 Requested link 'link-2-pgky' is stitching 10/10 08:43:56 DEBUG stitchhandler.py:1214 Link 'link-2-iggpo' properties will be from 'ig-gpo:if0' to 'ig-pks2:if1' 10/10 08:43:56 DEBUG stitchhandler.py:1367 Requested link 'link-2-iggpo' is stitching 10/10 08:43:56 DEBUG stitchhandler.py:1214 Link 'link-2-rut' properties will be from 'ig-pks2:if2' to 'ig-rutgers:if0' 10/10 08:43:56 DEBUG stitchhandler.py:1367 Requested link 'link-2-rut' is stitching 10/10 08:43:56 DEBUG stitchhandler.py:1214 Link 'link-2-igutah' properties will be from 'ig-pks2:if3' to 'ig-utah:if0' 10/10 08:43:56 DEBUG stitchhandler.py:1367 Requested link 'link-2-igutah' is stitching 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-pgky' interface 'ig-pks2:if0' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-pgky' interface 'ig-nyse:if0' found on node 'ig-nyse' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-iggpo' interface 'ig-gpo:if0' found on node 'ig-gpo' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-iggpo' interface 'ig-pks2:if1' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-rut' interface 'ig-pks2:if2' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-rut' interface 'ig-rutgers:if0' found on node 'ig-rutgers' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-igutah' interface 'ig-pks2:if3' found on node 'ig-pks2' 10/10 08:43:56 DEBUG stitchhandler.py:1291 Link 'link-2-igutah' interface 'ig-utah:if0' found on node 'ig-utah' 10/10 08:43:56 DEBUG stitchhandler.py:2350 Request RSpec binds nodes to 5 AMs 10/10 08:43:56 DEBUG stitchhandler.py:200 Request appears to be fully bound 10/10 08:43:56 INFO stitchhandler.py:1097 Reading slice star-pks2 credential... 10/10 08:43:56 DEBUG framework_chapi.py:1001 Using project from commandline. 10/10 08:43:56 DEBUG handler_utils.py:397 No filename provided for credential 10/10 08:43:56 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'match': {'SLICE_EXPIRED': 'f', 'SLICE_URN': 'urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2'}} 10/10 08:43:56 INFO stitchhandler.py:1168 Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 expires on 2014-10-17 12:43:55 UTC 10/10 08:43:56 INFO stitchhandler.py:381 Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 10/10 08:43:56 INFO stitchhandler.py:678 Calling SCS... 10/10 08:43:56 DEBUG stitchhandler.py:1586 Sending SCS options {'geni_workflow_paths_merged': True} 10/10 08:43:56 DEBUG stitchhandler.py:1405 Calling SCS with options {'geni_workflow_paths_merged': True} 10/10 08:44:00 DEBUG stitchhandler.py:1417 SCS successfully returned. 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion suggested VLAN 1842, avail: '1830-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig suggested VLAN 1842, avail: '1830-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:xe-0/2/0:nysernet-ig suggested VLAN 1714, avail: '1700-1719' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.nysernet.org+interface+procurve2:1.19:ion.newy.xe-0_2_0 suggested VLAN 1714, avail: '1700-1719' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24.ion.newy.ae0 suggested VLAN 3748, avail: '3706-3732,3746-3749' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn suggested VLAN 3748, avail: '670,3706-3750' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig suggested VLAN 1840, avail: '1830-1841,1843-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion suggested VLAN 1840, avail: '1830-1841,1843-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion suggested VLAN 1848, avail: '1830-1839,1841,1843-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig suggested VLAN 1848, avail: '1830-1839,1841,1843-1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig suggested VLAN 3738, avail: '3730-3739' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion suggested VLAN 3738, avail: '3730-3739' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion suggested VLAN 1849, avail: '1830-1839,1841,1843-1847,1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig suggested VLAN 1849, avail: '1830-1839,1841,1843-1847,1849' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni suggested VLAN 790, avail: '2-4094' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21 suggested VLAN 790, avail: '750-1000' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+emulab.net+interface+procurveA:3.19 suggested VLAN 790, avail: '750-1000' 10/10 08:44:00 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+utah.geniracks.net+interface+procurve2:1.19 suggested VLAN 790, avail: '256-1000' 10/10 08:44:00 DEBUG stitchhandler.py:1664 SCS workflow: { '##all_paths_merged##': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.rutgers.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.nysernet.org+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.nysernet.org+interface+procurve2:1.19:ion.newy.xe-0_2_0', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:xe-0/2/0:nysernet-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24.ion.newy.ae0', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21', 'import_vlans': False}, { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurveA:3.19', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni', 'import_vlans': True}, { 'aggregate_url': 'https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+utah.geniracks.net+authority+cm', 'dependencies': [ { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurveA:3.19', 'import_vlans': False}, { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+utah.geniracks.net+interface+procurve2:1.19', 'import_vlans': True}]}, 'link-2-iggpo': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.gpolab.bbn.com+interface+procurve2:5.24.ion.newy.ae0', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:bbn', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig', 'import_vlans': True}]}, 'link-2-igutah': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21', 'import_vlans': False}, { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurveA:3.19', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:ge-10/3/2:protogeni', 'import_vlans': True}, { 'aggregate_url': 'https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+utah.geniracks.net+authority+cm', 'dependencies': [ { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurveA:3.19', 'import_vlans': False}, { 'aggregate_url': 'https://www.emulab.net:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+emulab.net+authority+cm', 'hop_urn': 'urn:publicid:IDN+emulab.net+interface+procurve-pgeni-atla:3.21', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+utah.geniracks.net+interface+procurve2:1.19', 'import_vlans': True}]}, 'link-2-pgky': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.nysernet.org+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.nysernet.org+interface+procurve2:1.19:ion.newy.xe-0_2_0', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:xe-0/2/0:nysernet-ig', 'import_vlans': True}]}, 'link-2-rut': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.rutgers.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig', 'import_vlans': True}]}} 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 10/10 08:44:00 DEBUG workflow.py:260 depends on 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG workflow.py:168 will import vlan tags from 10/10 08:44:00 DEBUG stitchhandler.py:744 SCS workflow said to include resources from these aggregates: 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:746 10/10 08:44:00 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:00 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:00 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:00 INFO oscript.py:348 Using control framework portal 10/10 08:44:00 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:00 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:00 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:00 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:00 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:00 DEBUG handler_utils.py:212 Supplied AM URL https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+instageni.nysernet.org+authority+cm according to configured aggregate nicknames (nick nysernet-ig T5) 10/10 08:44:00 DEBUG handler_utils.py:352 Adding aggregate https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am (urn:publicid:IDN+instageni.nysernet.org+authority+cm) to query list 10/10 08:44:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.instageni.nysernet.org/spewlogfile.php3?logfile=bd15e38183f5ae29074b6c32cb070813) 10/10 08:44:00 INFO amhandler.py:459 Writing result of getversion at AM nysernet-ig to file 'getversion-instageni-nysernet-org.json' 10/10 08:44:00 INFO handler_utils.py:822 Writing to 'getversion-instageni-nysernet-org.json' 10/10 08:44:00 INFO handler_utils.py:834 AM nysernet-ig URN: urn:publicid:IDN+instageni.nysernet.org+authority+cm (url: https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-03 17:13:36.649209) 10/10 08:44:00 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:00 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:00 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:00 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:00 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:00 INFO oscript.py:348 Using control framework portal 10/10 08:44:00 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:00 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:00 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:00 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:00 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:00 DEBUG handler_utils.py:212 Supplied AM URL https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+utah.geniracks.net+authority+cm according to configured aggregate nicknames (nick utah-ig T5) 10/10 08:44:00 DEBUG handler_utils.py:352 Adding aggregate https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am (urn:publicid:IDN+utah.geniracks.net+authority+cm) to query list 10/10 08:44:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://boss.utah.geniracks.net/spewlogfile.php3?logfile=4c21e0299e988702af51269221085e3d) 10/10 08:44:00 INFO amhandler.py:459 Writing result of getversion at AM utah-ig to file 'getversion-utah-geniracks-net.json' 10/10 08:44:00 INFO handler_utils.py:822 Writing to 'getversion-utah-geniracks-net.json' 10/10 08:44:00 INFO handler_utils.py:834 AM utah-ig URN: urn:publicid:IDN+utah.geniracks.net+authority+cm (url: https://boss.utah.geniracks.net:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-06 17:21:07.358120) 10/10 08:44:00 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:00 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:00 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:00 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:00 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:00 INFO oscript.py:348 Using control framework portal 10/10 08:44:00 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:00 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:00 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:00 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:00 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:00 DEBUG handler_utils.py:212 Supplied AM URL https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm according to configured aggregate nicknames (nick gpo-ig T5) 10/10 08:44:00 DEBUG handler_utils.py:352 Adding aggregate https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am (urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm) to query list 10/10 08:44:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://boss.instageni.gpolab.bbn.com/spewlogfile.php3?logfile=234bcd052aa16ed9a37aad68c1c76184) 10/10 08:44:00 INFO amhandler.py:459 Writing result of getversion at AM gpo-ig to file 'getversion-instageni-gpolab-bbn-com.json' 10/10 08:44:00 INFO handler_utils.py:822 Writing to 'getversion-instageni-gpolab-bbn-com.json' 10/10 08:44:00 INFO handler_utils.py:834 AM gpo-ig URN: urn:publicid:IDN+instageni.gpolab.bbn.com+authority+cm (url: https://boss.instageni.gpolab.bbn.com:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-09 08:49:29.018612) 10/10 08:44:00 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:00 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:00 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:00 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:00 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:00 INFO oscript.py:348 Using control framework portal 10/10 08:44:00 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:00 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:00 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:00 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:00 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:00 DEBUG handler_utils.py:201 Supplied AM URL http://geni-am.net.internet2.edu:12346 is URN urn:publicid:IDN+ion.internet2.edu+authority+am according to configured aggregate nicknames (nick ion T4) 10/10 08:44:00 DEBUG handler_utils.py:352 Adding aggregate http://geni-am.net.internet2.edu:12346 (urn:publicid:IDN+ion.internet2.edu+authority+am) to query list 10/10 08:44:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for http://geni-am.net.internet2.edu:12346 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for http://geni-am.net.internet2.edu:12346 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 INFO amhandler.py:459 Writing result of getversion at AM ion to file 'getversion-ion-internet2-edu.json' 10/10 08:44:00 INFO handler_utils.py:822 Writing to 'getversion-ion-internet2-edu.json' 10/10 08:44:00 INFO handler_utils.py:834 AM ion URN: urn:publicid:IDN+ion.internet2.edu+authority+am (url: http://geni-am.net.internet2.edu:12346) has version: (From cached result from 2014-10-08 13:06:22.043885) 10/10 08:44:00 DEBUG stitchhandler.py:1902 AM is DCN 10/10 08:44:00 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:00 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:00 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:00 INFO oscript.py:348 Using control framework portal 10/10 08:44:00 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:00 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:00 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:00 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:00 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:00 DEBUG handler_utils.py:212 Supplied AM URL https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm according to configured aggregate nicknames (nick ukypks2-ig T5) 10/10 08:44:00 DEBUG handler_utils.py:352 Adding aggregate https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am (urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm) to query list 10/10 08:44:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:00 DEBUG amhandler.py:557 Checking cache for https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am 10/10 08:44:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:00 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.pks2.sdn.uky.edu/spewlogfile.php3?logfile=5e6416877470ab4dc0c7da4444ee5b5e) 10/10 08:44:00 INFO amhandler.py:459 Writing result of getversion at AM ukypks2-ig to file 'getversion-pks2-sdn-uky-edu.json' 10/10 08:44:00 INFO handler_utils.py:822 Writing to 'getversion-pks2-sdn-uky-edu.json' 10/10 08:44:00 INFO handler_utils.py:834 AM ukypks2-ig URN: urn:publicid:IDN+pks2.sdn.uky.edu+authority+cm (url: https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-03 19:15:12.587172) 10/10 08:44:00 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:00 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:01 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:01 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:01 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:01 INFO oscript.py:348 Using control framework portal 10/10 08:44:01 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:01 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:01 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:01 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:01 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:01 DEBUG handler_utils.py:212 Supplied AM URL https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+instageni.rutgers.edu+authority+cm according to configured aggregate nicknames (nick rutgers-ig T5) 10/10 08:44:01 DEBUG handler_utils.py:352 Adding aggregate https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am (urn:publicid:IDN+instageni.rutgers.edu+authority+cm) to query list 10/10 08:44:01 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:01 DEBUG amhandler.py:557 Checking cache for https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am 10/10 08:44:01 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:01 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:01 DEBUG amhandler.py:557 Checking cache for https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am 10/10 08:44:01 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:01 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.instageni.rutgers.edu/spewlogfile.php3?logfile=c9d55dfa13ea11a3a9cd68c79628169d) 10/10 08:44:01 INFO amhandler.py:459 Writing result of getversion at AM rutgers-ig to file 'getversion-instageni-rutgers-edu.json' 10/10 08:44:01 INFO handler_utils.py:822 Writing to 'getversion-instageni-rutgers-edu.json' 10/10 08:44:01 INFO handler_utils.py:834 AM rutgers-ig URN: urn:publicid:IDN+instageni.rutgers.edu+authority+cm (url: https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-09 14:40:14.843413) 10/10 08:44:01 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:01 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:01 DEBUG stitchhandler.py:1895 Getting extra AM info from Omni for AM 10/10 08:44:01 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:01 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:01 INFO oscript.py:348 Using control framework portal 10/10 08:44:01 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:01 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:01 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:01 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:01 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:01 DEBUG handler_utils.py:212 Supplied AM URL https://www.emulab.net:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+emulab.net+authority+cm according to configured aggregate nicknames (nick utah-pg T5) 10/10 08:44:01 DEBUG handler_utils.py:352 Adding aggregate https://www.emulab.net:12369/protogeni/xmlrpc/am (urn:publicid:IDN+emulab.net+authority+cm) to query list 10/10 08:44:01 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:01 DEBUG amhandler.py:557 Checking cache for https://www.emulab.net:12369/protogeni/xmlrpc/am 10/10 08:44:01 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:01 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:01 DEBUG amhandler.py:557 Checking cache for https://www.emulab.net:12369/protogeni/xmlrpc/am 10/10 08:44:01 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:01 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.emulab.net/spewlogfile.php3?logfile=84ed9e711827e51d26f395cffb09770c) 10/10 08:44:01 INFO amhandler.py:459 Writing result of getversion at AM utah-pg to file 'getversion-emulab-net.json' 10/10 08:44:01 INFO handler_utils.py:822 Writing to 'getversion-emulab-net.json' 10/10 08:44:01 INFO handler_utils.py:834 AM utah-pg URN: urn:publicid:IDN+emulab.net+authority+cm (url: https://www.emulab.net:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-10-06 17:21:10.884564) 10/10 08:44:01 DEBUG stitchhandler.py:2007 does NOT say it supports stitch schema v1 10/10 08:44:01 DEBUG stitchhandler.py:2012 doesn't say whether it supports either stitching schema, so assume v1 10/10 08:44:01 DEBUG stitchhandler.py:2054 ===== Hops ===== 10/10 08:44:01 DEBUG stitchhandler.py:2056 Path link-2-pgky 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1842 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1842 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1714 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1700-1719 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1714 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1700-1719 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2056 Path link-2-iggpo 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 3748 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 3706-3732,3746-3749 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 3748 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 670,3706-3750 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1840 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1841,1843-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1840 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1841,1843-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2056 Path link-2-rut 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1848 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1839,1841,1843-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1848 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1839,1841,1843-1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 3738 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 3730-3739 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 3738 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 3730-3739 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2056 Path link-2-igutah 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1849 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1839,1841,1843-1847,1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 1849 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 1830-1839,1841,1843-1847,1849 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 790 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 2-4094 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 790 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 750-1000 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 790 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 750-1000 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: None 10/10 08:44:01 DEBUG stitchhandler.py:2058 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2070 VLAN Suggested (requested): 790 10/10 08:44:01 DEBUG stitchhandler.py:2071 VLAN Available Range (requested): 256-1000 10/10 08:44:01 DEBUG stitchhandler.py:2078 Import VLANs From: 10/10 08:44:01 DEBUG stitchhandler.py:2081 Dependencies: 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2083 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2086 ===== Aggregates ===== 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2090 (User requested) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2090 (User requested) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2090 (User requested) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2092 (SCS added) 10/10 08:44:01 DEBUG stitchhandler.py:2094 A DCN Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2128 Depends on 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2090 (User requested) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2090 (User requested) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2088 Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2092 (SCS added) 10/10 08:44:01 DEBUG stitchhandler.py:2096 A ProtoGENI Aggregate 10/10 08:44:01 DEBUG stitchhandler.py:2107 Using AM API version 2 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 DEBUG stitchhandler.py:2126 Hop 10/10 08:44:01 INFO stitchhandler.py:826 Multi-AM reservation will include resources from these aggregates: 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 INFO stitchhandler.py:828 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1714 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:982 imports VLANs from another AM, . Don't request 'any'. 10/10 08:44:01 DEBUG stitchhandler.py:1016 does not say it is a vlan producer. Still not requesting 'any'. 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3748 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:963 doesn't support requesting 'any' VLAN tag - move on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1849 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1842 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1840 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1848 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3738 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 790 to 'any' on 10/10 08:44:01 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 10/10 08:44:01 DEBUG stitchhandler.py:1034 Changing suggested request tag from 790 to 'any' on 10/10 08:44:01 DEBUG launcher.py:48 There are 5 ready aggregates: [Aggregate('nysernet-ig'), Aggregate('gpo-ig'), Aggregate('ukypks2-ig'), Aggregate('rutgers-ig'), Aggregate('utah-pg')] 10/10 08:44:01 DEBUG objects.py:495 Starting allocate on ... 10/10 08:44:01 DEBUG objects.py:573 : Request: Suggested: any. Range: '1700-1719'. Unavail: '' 10/10 08:44:01 DEBUG objects.py:733 Starting newExpires at slice expiration 2014-10-17 12:43:55+00:00, so init minDays to 6 10/10 08:44:01 DEBUG objects.py:754 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:766 After checking own rules, minDays=6, newExpires=2014-10-16 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:807 After , minDays=6, newExpires=2014-10-16 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:807 After , minDays=6, newExpires=2014-10-16 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:807 After , minDays=6, newExpires=2014-10-16 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:807 After , minDays=6, newExpires=2014-10-16 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:784 AM's path includes which is Utah PG or DDC - 5 day sliver expiration 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=5 10/10 08:44:01 DEBUG objects.py:807 After , minDays=5, newExpires=2014-10-15 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:807 After , minDays=5, newExpires=2014-10-15 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:793 policy says expDays=90 10/10 08:44:01 DEBUG objects.py:807 After , minDays=5, newExpires=2014-10-15 12:34:01.352785 10/10 08:44:01 DEBUG objects.py:811 Will request newExpires=2014-10-15 12:34:01.352785 10/10 08:44:01 INFO handler_utils.py:822 Writing to '/tmp/star-pks2-createsliver-request-11-instageni-nysernet-org.xml' 10/10 08:44:01 DEBUG objects.py:1540 Saved AM urn:publicid:IDN+instageni.nysernet.org+authority+cm new request RSpec to file /tmp/star-pks2-createsliver-request-11-instageni-nysernet-org.xml 10/10 08:44:01 INFO objects.py:1551 Stitcher doing createsliver at ... 10/10 08:44:01 DEBUG objects.py:1552 omniargs: ['-o', '--raise-error-on-v2-amapi-error', '-V2', '-a', 'https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am', 'createsliver', 'star-pks2', u'/tmp/star-pks2-createsliver-request-11-instageni-nysernet-org.xml'] 10/10 08:44:01 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 10/10 08:44:01 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 10/10 08:44:01 INFO oscript.py:348 Using control framework portal 10/10 08:44:01 DEBUG oscript.py:453 Using framework type chapi 10/10 08:44:01 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 10/10 08:44:01 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 10/10 08:44:01 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 10/10 08:44:01 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 10/10 08:44:01 DEBUG handler_utils.py:212 Supplied AM URL https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+instageni.nysernet.org+authority+cm according to configured aggregate nicknames (nick nysernet-ig T5) 10/10 08:44:01 DEBUG handler_utils.py:352 Adding aggregate https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am (urn:publicid:IDN+instageni.nysernet.org+authority+cm) to query list 10/10 08:44:01 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 10/10 08:44:01 DEBUG amhandler.py:557 Checking cache for https://www.instageni.nysernet.org:12369/protogeni/xmlrpc/am 10/10 08:44:01 DEBUG amhandler.py:430 Pulling GetVersion from cache 10/10 08:44:01 DEBUG amhandler.py:226 Config version spoken here by all AMs 10/10 08:44:01 DEBUG framework_chapi.py:1001 Using project from commandline. 10/10 08:44:01 INFO handler_utils.py:403 Getting credential from file /tmp/slice-ch-geni-net-ln-test-star-pks2-for-lnevers-cred.xml 10/10 08:44:01 DEBUG handler_utils.py:413 Failed to get a JSON struct from cred in file /tmp/slice-ch-geni-net-ln-test-star-pks2-for-lnevers-cred.xml. Treat as a string. 10/10 08:44:01 DEBUG handler_utils.py:424 Using APIv2 and got cred seemingly in right form, return it 10/10 08:44:01 INFO handler_utils.py:449 Read slice cred from /tmp/slice-ch-geni-net-ln-test-star-pks2-for-lnevers-cred.xml for slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 10/10 08:44:01 INFO handler_utils.py:528 Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 expires on 2014-10-17 12:43:55 UTC 10/10 08:44:01 INFO amhandler.py:1810 Creating sliver(s) from rspec file /tmp/star-pks2-createsliver-request-11-instageni-nysernet-org.xml for slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 10/10 08:44:01 DEBUG amhandler.py:5186 Getting users and SSH keys from the Clearinghouse list of slice members 10/10 08:44:01 DEBUG framework_chapi.py:1001 Using project from commandline. 10/10 08:44:01 DEBUG framework_chapi.py:1095 Submitting lookup_slices with options: {'filter': ['SLICE_URN', 'SLICE_EXPIRATION', 'SLICE_EXPIRED'], 'match': {'SLICE_EXPIRED': 'f', 'SLICE_URN': 'urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2'}} 10/10 08:44:01 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['SLICE_URN', 'SLICE_EXPIRATION', 'SLICE_EXPIRED'], 'match': {'SLICE_EXPIRED': 'f', 'SLICE_URN': 'urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2'}} 10/10 08:44:01 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'match': {'SLICE_EXPIRED': 'f', 'SLICE_URN': 'urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2'}} 10/10 08:44:01 DEBUG framework_chapi.py:1536 Successfully completed Get members for GENI Clearinghouse slice urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2 10/10 08:44:01 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['MEMBER_EMAIL'], 'match': {'MEMBER_URN': 'urn:publicid:IDN+ch.geni.net+user+lnevers1'}} 10/10 08:44:01 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 10/10 08:44:01 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['KEY_PUBLIC'], 'match': {'KEY_MEMBER': 'urn:publicid:IDN+ch.geni.net+user+lnevers1'}} 10/10 08:44:02 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers1 SSH keys 10/10 08:44:02 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['MEMBER_EMAIL'], 'match': {'MEMBER_URN': 'urn:publicid:IDN+ch.geni.net+user+lnevers2'}} 10/10 08:44:02 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 10/10 08:44:02 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['KEY_PUBLIC'], 'match': {'KEY_MEMBER': 'urn:publicid:IDN+ch.geni.net+user+lnevers2'}} 10/10 08:44:02 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers2 SSH keys 10/10 08:44:02 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['MEMBER_EMAIL'], 'match': {'MEMBER_URN': 'urn:publicid:IDN+ch.geni.net+user+lnevers'}} 10/10 08:44:02 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 10/10 08:44:02 DEBUG framework_chapi.py:323 add_c_n_spkfor new_creds = []; new_options = {'filter': ['KEY_PUBLIC'], 'match': {'KEY_MEMBER': 'urn:publicid:IDN+ch.geni.net+user+lnevers'}} 10/10 08:44:02 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers SSH keys 10/10 08:44:02 DEBUG amhandler.py:5228 From Clearinghouse got 3 users whose SSH keys will be set 10/10 08:44:02 DEBUG amhandler.py:5237 Reading users and keys to install from your omni_config 10/10 08:44:02 DEBUG amhandler.py:5272 User urn:publicid:IDN+ch.geni.net+user+lnevers Newkeys: "['ssh-rsa AAAAB3NzaC1yc2EAAAABIwAAAQEAxy0LvixSvXoDM3UfhPPLi5ua6ZiX8eGbao7X/VDfO+uhHNRpjBvaWImXRsF4WA7Wvj9gvGwBBBhL7GkTu9dURX36udWA6Hf2NA97yTT81grrgTzzuughfgSxXL"... 10/10 08:44:02 DEBUG amhandler.py:5293 After reading omni_config, 3 users will have SSH keys set 10/10 08:44:02 DEBUG amhandler.py:1830 Doing createsliver with urn urn:publicid:IDN+ch.geni.net:ln-test+slice+star-pks2, 1 creds, rspec of length 17211 starting '