11/06 08:36:51 INFO oscript.py:917 Configured logging from file /home/lnevers/gcf-2.7/src/gcf/stitcher_logging.conf 11/06 08:36:51 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:51 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:51 INFO oscript.py:348 Using control framework portal 11/06 08:36:51 DEBUG stitcher.py:336 Using SCS URL from omni_config: http://nutshell.maxgigapop.net:8081/geni/xmlrpc 11/06 08:36:51 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 11/06 08:36:51 DEBUG stitcher.py:396 Running stitcher ... Options as run: framework: portal logconfig: None output: True project: ln-test scsURL: http://nutshell.maxgigapop.net:8081/geni/xmlrpc Args: createsliver 6sites ./stitch-6sites.rspec 11/06 08:36:51 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:51 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:51 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:51 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:52 DEBUG stitchhandler.py:185 Valid GENI v3 request RSpec 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-ucd-1' interface 'interface-2' found on node 'pks2-4' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-ucd-1' interface 'interface-3' found on node 'xoucd-8' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-xobbn-2' interface 'interface-4' found on node 'xobbn-6' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-xobbn-2' interface 'interface-5' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-chic-3' interface 'interface-6' found on node 'pks2-4' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-chic-3' interface 'interface-7' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-chic-6' interface 'interface-12' found on node 'stan-1' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-chic-6' interface 'interface-13' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-rut-7' interface 'interface-14' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-rut-7' interface 'interface-15' found on node 'rut-5' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-rut-8' interface 'interface-16' found on node 'stan-1' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-rut-8' interface 'interface-17' found on node 'rut-5' 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'pks-ucd-1' properties will be from 'interface-2' to 'interface-3' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'pks-ucd-1' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'pks-ucd-1' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'chic-xobbn-2' properties will be from 'interface-4' to 'interface-5' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'chic-xobbn-2' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'chic-xobbn-2' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'pks-chic-3' properties will be from 'interface-6' to 'interface-7' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'pks-chic-3' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'pks-chic-3' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'stan-chic-6' properties will be from 'interface-12' to 'interface-13' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'stan-chic-6' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'stan-chic-6' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'chic-rut-7' properties will be from 'interface-14' to 'interface-15' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'chic-rut-7' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'chic-rut-7' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1214 Link 'stan-rut-8' properties will be from 'interface-16' to 'interface-17' 11/06 08:36:52 DEBUG stitchhandler.py:1218 Link 'stan-rut-8' had no properties - must add them 11/06 08:36:52 DEBUG stitchhandler.py:1367 Requested link 'stan-rut-8' is stitching 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-ucd-1' interface 'interface-2' found on node 'pks2-4' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-ucd-1' interface 'interface-3' found on node 'xoucd-8' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-xobbn-2' interface 'interface-4' found on node 'xobbn-6' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-xobbn-2' interface 'interface-5' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-chic-3' interface 'interface-6' found on node 'pks2-4' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'pks-chic-3' interface 'interface-7' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-chic-6' interface 'interface-12' found on node 'stan-1' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-chic-6' interface 'interface-13' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-rut-7' interface 'interface-14' found on node 'chic-3' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'chic-rut-7' interface 'interface-15' found on node 'rut-5' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-rut-8' interface 'interface-16' found on node 'stan-1' 11/06 08:36:52 DEBUG stitchhandler.py:1291 Link 'stan-rut-8' interface 'interface-17' found on node 'rut-5' 11/06 08:36:52 DEBUG stitchhandler.py:2371 Request RSpec binds nodes to 6 AMs 11/06 08:36:52 DEBUG stitchhandler.py:200 Request appears to be fully bound 11/06 08:36:52 INFO stitchhandler.py:1097 Reading slice 6sites credential... 11/06 08:36:52 DEBUG framework_chapi.py:1001 Using project from commandline. 11/06 08:36:52 DEBUG handler_utils.py:397 No filename provided for credential 11/06 08:36:52 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+6sites'}} 11/06 08:36:52 INFO stitchhandler.py:1168 Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites expires on 2014-11-12 18:45:33 UTC 11/06 08:36:52 INFO stitchhandler.py:381 Using SCS at http://nutshell.maxgigapop.net:8081/geni/xmlrpc 11/06 08:36:52 INFO stitchhandler.py:678 Calling SCS... 11/06 08:36:52 DEBUG stitchhandler.py:1586 Sending SCS options {'geni_workflow_paths_merged': True} 11/06 08:36:52 DEBUG stitchhandler.py:1405 Calling SCS with options {'geni_workflow_paths_merged': True} 11/06 08:36:58 DEBUG stitchhandler.py:1417 SCS successfully returned. 11/06 08:36:58 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' 11/06 08:36:58 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' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/3/0:al2s suggested VLAN 3993, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.atla.net.internet2.edu:e15/1:* suggested VLAN 3993, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:ucdavis-eg suggested VLAN 1655, avail: '1650-1669' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet suggested VLAN 1655, avail: '1650-1669' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:ExoGeni:1:ethernet suggested VLAN 1655, avail: '2-4094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:ucdvmsite+interface+UCD:ExoGeni:TenGigabitEthernet:1:ethernet suggested VLAN 1655, avail: '2-4094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:bbnvmsite+interface+Bbn:ExoGeni:TenGigabitEthernet:1:ethernet suggested VLAN 3741, avail: '2-4094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet suggested VLAN 3741, avail: '2-4094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:GigabitEthernet:1:2:ethernet suggested VLAN 3741, avail: '3736-3739,3741' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:gpo-eg suggested VLAN 3741, avail: '3736-3739,3741' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:et-5/0/0:al2s suggested VLAN 3948, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.newy32aoa.net.internet2.edu:e3/2:* suggested VLAN 3998, avail: '3950-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig suggested VLAN 3090, avail: '2-4089' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s suggested VLAN 3090, avail: '3075-3094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+pks2.sdn.uky.edu+interface+procurve2:1.18.ion suggested VLAN 1844, avail: '1830-1849' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/1/3:ukypks2-ig suggested VLAN 1844, avail: '1830-1849' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/3/0:al2s suggested VLAN 3999, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.atla.net.internet2.edu:e15/1:* suggested VLAN 3999, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig suggested VLAN 3093, avail: '2-4089' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s suggested VLAN 3093, avail: '3075-3094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.al2s.sunn.e5_1 suggested VLAN 1643, avail: '1640-1644' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:* suggested VLAN 1643, avail: '1640-1644' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig suggested VLAN 3093, avail: '2-4089' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s suggested VLAN 3093, avail: '3075-3094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s suggested VLAN 3084, avail: '3075-3094' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig suggested VLAN 3084, avail: '2-4089' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.wash.net.internet2.edu:e5/2:* suggested VLAN 3996, avail: '3950-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:et-9/0/0:al2s suggested VLAN 3996, avail: '3900-4000' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig suggested VLAN 3732, avail: '3730-3739' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion suggested VLAN 3732, avail: '3730-3739' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.ion.salt.xe-0_1_1 suggested VLAN 1637, avail: '1630-1639' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.salt:xe-0/1/1:stanford-ig suggested VLAN 1637, avail: '1630-1639' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:xe-0/2/2:rutgers-ig suggested VLAN 3736, avail: '3730-3739' 11/06 08:36:58 DEBUG stitchhandler.py:1649 SCS gave hop urn:publicid:IDN+instageni.rutgers.edu+interface+procurve2:1.19.ion suggested VLAN 3736, avail: '3730-3739' 11/06 08:36:58 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.instageni.stanford.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.stanford.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.ion.salt.xe-0_1_1', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.salt:xe-0/1/1:stanford-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://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.stanford.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.al2s.sunn.e5_1', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:*', 'import_vlans': True}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+geni.uchicago.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-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.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', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/3/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.atla.net.internet2.edu:e15/1:*', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.wash.net.internet2.edu:e5/2:*', 'import_vlans': False}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:et-9/0/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:ExoGeni:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdvmsite+interface+UCD:ExoGeni:TenGigabitEthernet:1:ethernet', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:ucdavis-eg', '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://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:GigabitEthernet:1:2:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnvmsite+interface+Bbn:ExoGeni:TenGigabitEthernet:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:gpo-eg', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:et-5/0/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.newy32aoa.net.internet2.edu:e3/2:*', 'import_vlans': False}]}, 'chic-rut-7': { 'dependencies': [ { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+geni.uchicago.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig', 'import_vlans': True}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.wash.net.internet2.edu:e5/2:*', 'import_vlans': False}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.wash:et-9/0/0:al2s', 'import_vlans': False}, { '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}]}, 'chic-xobbn-2': { 'dependencies': [ { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:GigabitEthernet:1:2:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnvmsite+interface+Bbn:ExoGeni:TenGigabitEthernet:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://bbn-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:bbnNet+interface+BbnNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:ae0:gpo-eg', 'import_vlans': True}, { 'aggregate_url': 'http://geni-am.net.internet2.edu:12346', 'aggregate_urn': 'urn:publicid:IDN+ion.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.newy:et-5/0/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.newy32aoa.net.internet2.edu:e3/2:*', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+geni.uchicago.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig', 'import_vlans': True}]}, 'pks-chic-3': { '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', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/3/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.atla.net.internet2.edu:e15/1:*', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+geni.uchicago.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig', 'import_vlans': True}]}, 'pks-ucd-1': { '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', 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.atla:xe-0/3/0:al2s', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.atla.net.internet2.edu:e15/1:*', 'import_vlans': False}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:1:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+interface+ucdNet:IBM:G8052:TenGigabitEthernet:ExoGeni:1:ethernet', 'import_vlans': False}, { 'aggregate_url': 'https://ucd-hn.exogeni.net:11443/orca/xmlrpc', 'aggregate_urn': 'urn:publicid:IDN+exogeni.net:ucdNet+authority+am', 'hop_urn': 'urn:publicid:IDN+exogeni.net:ucdvmsite+interface+UCD:ExoGeni:TenGigabitEthernet:1:ethernet', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:ucdavis-eg', 'import_vlans': True}]}, 'stan-chic-6': { 'dependencies': [ { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.stanford.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.al2s.sunn.e5_1', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.sunn.net.internet2.edu:e5/1:*', 'import_vlans': True}, { 'aggregate_url': 'http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2', 'aggregate_urn': 'urn:publicid:IDN+al2s.internet2.edu+authority+am', 'dependencies': [ { 'aggregate_url': 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+geni.uchicago.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+geni.uchicago.edu+interface+procurve2:1.16.al2s', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+al2s.internet2.edu+interface+sdn-sw.star.net.internet2.edu:eth7/1:uchicago-ig', 'import_vlans': True}]}, 'stan-rut-8': { '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.instageni.stanford.edu:12369/protogeni/xmlrpc/am', 'aggregate_urn': 'urn:publicid:IDN+instageni.stanford.edu+authority+cm', 'hop_urn': 'urn:publicid:IDN+instageni.stanford.edu+interface+procurve2:1.24.ion.salt.xe-0_1_1', 'import_vlans': False}], 'hop_urn': 'urn:publicid:IDN+ion.internet2.edu+interface+rtr.salt:xe-0/1/1:stanford-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}]}} 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:260 depends on 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:241 has dependency , so their AMs are dependencies 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG workflow.py:168 will import vlan tags from 11/06 08:36:58 DEBUG stitchhandler.py:744 SCS workflow said to include resources from these aggregates: 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:746 11/06 08:36:58 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:58 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:58 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:58 INFO oscript.py:348 Using control framework portal 11/06 08:36:58 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:58 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:58 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:58 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:58 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:58 DEBUG handler_utils.py:170 Supplied AM URL https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+geni.uchicago.edu+authority+cm according to configured aggregate nicknames (nick chicago-ig T1) 11/06 08:36:58 DEBUG handler_utils.py:352 Adding aggregate https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am (urn:publicid:IDN+geni.uchicago.edu+authority+cm) to query list 11/06 08:36:58 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.geni.uchicago.edu/spewlogfile.php3?logfile=44380e0f8dbdcb42033ea94ac69ed840) 11/06 08:36:58 INFO amhandler.py:459 Writing result of getversion at AM chicago-ig to file 'getversion-geni-uchicago-edu.json' 11/06 08:36:58 INFO handler_utils.py:822 Writing to 'getversion-geni-uchicago-edu.json' 11/06 08:36:58 INFO handler_utils.py:834 AM chicago-ig URN: urn:publicid:IDN+geni.uchicago.edu+authority+cm (url: https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-11-03 19:10:00.586313) 11/06 08:36:58 DEBUG stitchhandler.py:2028 does NOT say it supports stitch schema v1 11/06 08:36:58 DEBUG stitchhandler.py:2033 doesn't say whether it supports either stitching schema, so assume v1 11/06 08:36:58 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:58 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:58 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:58 INFO oscript.py:348 Using control framework portal 11/06 08:36:58 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:58 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:58 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:58 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:58 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:58 DEBUG handler_utils.py:170 Supplied AM URL https://ucd-hn.exogeni.net:11443/orca/xmlrpc is URN urn:publicid:IDN+exogeni.net:ucdvmsite+authority+am according to configured aggregate nicknames (nick ucdavis-eg T1) 11/06 08:36:58 DEBUG handler_utils.py:352 Adding aggregate https://ucd-hn.exogeni.net:11443/orca/xmlrpc (urn:publicid:IDN+exogeni.net:ucdvmsite+authority+am) to query list 11/06 08:36:58 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for https://ucd-hn.exogeni.net:11443/orca/xmlrpc 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for https://ucd-hn.exogeni.net:11443/orca/xmlrpc 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 INFO amhandler.py:459 Writing result of getversion at AM ucdavis-eg to file 'getversion-exogeni-net-ucdvmsite.json' 11/06 08:36:58 INFO handler_utils.py:822 Writing to 'getversion-exogeni-net-ucdvmsite.json' 11/06 08:36:58 INFO handler_utils.py:834 AM ucdavis-eg URN: urn:publicid:IDN+exogeni.net:ucdvmsite+authority+am (url: https://ucd-hn.exogeni.net:11443/orca/xmlrpc) has version: (From cached result from 2014-11-04 14:57:07.479219) 11/06 08:36:58 DEBUG stitchhandler.py:1935 AM is Orca 11/06 08:36:58 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:58 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:58 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:58 INFO oscript.py:348 Using control framework portal 11/06 08:36:58 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:58 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:58 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:58 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:58 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:58 DEBUG handler_utils.py:201 Supplied AM URL http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2 is URN urn:publicid:IDN+al2s.internet2.edu+authority+am according to configured aggregate nicknames (nick oess T4) 11/06 08:36:58 DEBUG handler_utils.py:352 Adding aggregate http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2 (urn:publicid:IDN+al2s.internet2.edu+authority+am) to query list 11/06 08:36:58 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:58 DEBUG amhandler.py:557 Checking cache for http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2 11/06 08:36:58 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:58 INFO amhandler.py:459 Writing result of getversion at AM oess to file 'getversion-al2s-internet2-edu.json' 11/06 08:36:58 INFO handler_utils.py:822 Writing to 'getversion-al2s-internet2-edu.json' 11/06 08:36:58 INFO handler_utils.py:834 AM oess URN: urn:publicid:IDN+al2s.internet2.edu+authority+am (url: http://foam-oess-stage.grnoc.iu.edu:3626/foam/gapi/2) has version: (From cached result from 2014-11-03 19:09:52.160566) 11/06 08:36:58 DEBUG stitchhandler.py:2010 AM is OESS 11/06 08:36:58 DEBUG stitchhandler.py:2028 does NOT say it supports stitch schema v1 11/06 08:36:58 DEBUG stitchhandler.py:2033 doesn't say whether it supports either stitching schema, so assume v1 11/06 08:36:58 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:58 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:58 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:58 INFO oscript.py:348 Using control framework portal 11/06 08:36:58 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:58 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:58 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:58 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:58 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:58 DEBUG handler_utils.py:170 Supplied AM URL https://bbn-hn.exogeni.net:11443/orca/xmlrpc is URN urn:publicid:IDN+exogeni.net:bbnvmsite+authority+am according to configured aggregate nicknames (nick gpo-eg T1) 11/06 08:36:58 DEBUG handler_utils.py:352 Adding aggregate https://bbn-hn.exogeni.net:11443/orca/xmlrpc (urn:publicid:IDN+exogeni.net:bbnvmsite+authority+am) to query list 11/06 08:36:59 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://bbn-hn.exogeni.net:11443/orca/xmlrpc 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://bbn-hn.exogeni.net:11443/orca/xmlrpc 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 INFO amhandler.py:459 Writing result of getversion at AM gpo-eg to file 'getversion-exogeni-net-bbnvmsite.json' 11/06 08:36:59 INFO handler_utils.py:822 Writing to 'getversion-exogeni-net-bbnvmsite.json' 11/06 08:36:59 INFO handler_utils.py:834 AM gpo-eg URN: urn:publicid:IDN+exogeni.net:bbnvmsite+authority+am (url: https://bbn-hn.exogeni.net:11443/orca/xmlrpc) has version: (From cached result from 2014-11-05 16:53:06.263451) 11/06 08:36:59 DEBUG stitchhandler.py:1935 AM is Orca 11/06 08:36:59 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:59 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:59 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:59 INFO oscript.py:348 Using control framework portal 11/06 08:36:59 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:59 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:59 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:59 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:59 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:59 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) 11/06 08:36:59 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 11/06 08:36:59 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for http://geni-am.net.internet2.edu:12346 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for http://geni-am.net.internet2.edu:12346 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 INFO amhandler.py:459 Writing result of getversion at AM ion to file 'getversion-ion-internet2-edu.json' 11/06 08:36:59 INFO handler_utils.py:822 Writing to 'getversion-ion-internet2-edu.json' 11/06 08:36:59 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-11-03 15:17:24.986725) 11/06 08:36:59 DEBUG stitchhandler.py:1923 AM is DCN 11/06 08:36:59 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:59 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:59 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:59 INFO oscript.py:348 Using control framework portal 11/06 08:36:59 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:59 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:59 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:59 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:59 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:59 DEBUG handler_utils.py:212 Supplied AM URL https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+instageni.stanford.edu+authority+cm according to configured aggregate nicknames (nick stanford-ig T5) 11/06 08:36:59 DEBUG handler_utils.py:352 Adding aggregate https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am (urn:publicid:IDN+instageni.stanford.edu+authority+cm) to query list 11/06 08:36:59 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.instageni.stanford.edu/spewlogfile.php3?logfile=8f7798e4049241493eaf8cc5ec356382) 11/06 08:36:59 INFO amhandler.py:459 Writing result of getversion at AM stanford-ig to file 'getversion-instageni-stanford-edu.json' 11/06 08:36:59 INFO handler_utils.py:822 Writing to 'getversion-instageni-stanford-edu.json' 11/06 08:36:59 INFO handler_utils.py:834 AM stanford-ig URN: urn:publicid:IDN+instageni.stanford.edu+authority+cm (url: https://www.instageni.stanford.edu:12369/protogeni/xmlrpc/am) has version: (From cached result from 2014-11-03 19:09:58.150447) 11/06 08:36:59 DEBUG stitchhandler.py:2028 does NOT say it supports stitch schema v1 11/06 08:36:59 DEBUG stitchhandler.py:2033 doesn't say whether it supports either stitching schema, so assume v1 11/06 08:36:59 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:59 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:59 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:59 INFO oscript.py:348 Using control framework portal 11/06 08:36:59 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:59 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:59 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:59 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:59 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:59 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) 11/06 08:36:59 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 11/06 08:36:59 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.pks2.sdn.uky.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.pks2.sdn.uky.edu/spewlogfile.php3?logfile=7ee4445c7365b6863c94b4ac82593144) 11/06 08:36:59 INFO amhandler.py:459 Writing result of getversion at AM ukypks2-ig to file 'getversion-pks2-sdn-uky-edu.json' 11/06 08:36:59 INFO handler_utils.py:822 Writing to 'getversion-pks2-sdn-uky-edu.json' 11/06 08:36:59 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-11-04 14:57:11.064840) 11/06 08:36:59 DEBUG stitchhandler.py:2028 does NOT say it supports stitch schema v1 11/06 08:36:59 DEBUG stitchhandler.py:2033 doesn't say whether it supports either stitching schema, so assume v1 11/06 08:36:59 DEBUG stitchhandler.py:1916 Getting extra AM info from Omni for AM 11/06 08:36:59 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:59 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:59 INFO oscript.py:348 Using control framework portal 11/06 08:36:59 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:59 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:59 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:59 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:59 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:59 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) 11/06 08:36:59 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 11/06 08:36:59 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:36:59 DEBUG amhandler.py:557 Checking cache for https://www.instageni.rutgers.edu:12369/protogeni/xmlrpc/am 11/06 08:36:59 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:36:59 INFO amhandler.py:5372 (PG log url - look here for details on any failures: https://www.instageni.rutgers.edu/spewlogfile.php3?logfile=3704b750605d7d8e731a27df0942dc09) 11/06 08:36:59 INFO amhandler.py:459 Writing result of getversion at AM rutgers-ig to file 'getversion-instageni-rutgers-edu.json' 11/06 08:36:59 INFO handler_utils.py:822 Writing to 'getversion-instageni-rutgers-edu.json' 11/06 08:36:59 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-11-04 14:57:09.206366) 11/06 08:36:59 DEBUG stitchhandler.py:2028 does NOT say it supports stitch schema v1 11/06 08:36:59 DEBUG stitchhandler.py:2033 doesn't say whether it supports either stitching schema, so assume v1 11/06 08:36:59 DEBUG stitchhandler.py:1706 Request includes more than one ExoGENI AM. Must go through the ExoSM. 11/06 08:36:59 DEBUG stitchhandler.py:1714 Making the ExoSM 11/06 08:36:59 DEBUG stitchhandler.py:1714 Making the ExoSM 11/06 08:36:59 DEBUG handler_utils.py:170 Supplied AM URL https://geni.renci.org:11443/orca/xmlrpc is URN urn:publicid:IDN+exogeni.net+authority+am according to configured aggregate nicknames (nick eg-sm T1) 11/06 08:36:59 DEBUG stitchhandler.py:1746 Merge AM urn:publicid:IDN+exogeni.net:bbnvmsite+authority+am (https://geni.renci.org:11443/orca/xmlrpc, https://bbn-hn.exogeni.net:11443/orca/xmlrpc) into (https://geni.renci.org:11443/orca/xmlrpc, https://ucd-hn.exogeni.net:11443/orca/xmlrpc) 11/06 08:36:59 DEBUG stitchhandler.py:1771 Removing dup ExoSM from .dependsOn 11/06 08:36:59 DEBUG stitchhandler.py:1774 Adding real ExoSM to .dependsOn 11/06 08:36:59 DEBUG stitchhandler.py:1799 Merging in path 11/06 08:36:59 DEBUG stitchhandler.py:1809 Merging in hop 11/06 08:36:59 DEBUG stitchhandler.py:1809 Merging in hop 11/06 08:36:59 DEBUG stitchhandler.py:1809 Merging in hop 11/06 08:36:59 DEBUG stitchhandler.py:2075 ===== Hops ===== 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path pks-ucd-1 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1842 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1830-1849 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1842 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1830-1849 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3993 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3993 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1655 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1650-1669 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1655 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1650-1669 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1655 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1655 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path chic-xobbn-2 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3741 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3741 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3741 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3736-3739,3741 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3741 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3736-3739,3741 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3948 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3998 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3950-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3090 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4089 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3090 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3075-3094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path pks-chic-3 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1844 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1830-1849 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1844 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1830-1849 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3999 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3999 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3093 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4089 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3093 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3075-3094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path stan-chic-6 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1643 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1640-1644 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1643 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1640-1644 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3093 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4089 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3093 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3075-3094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path chic-rut-7 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3084 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3075-3094 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3084 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 2-4089 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3996 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3950-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3996 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3900-4000 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3732 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3730-3739 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3732 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3730-3739 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2077 Path stan-rut-8 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1637 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1630-1639 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 1637 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 1630-1639 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3736 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3730-3739 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: 11/06 08:36:59 DEBUG stitchhandler.py:2102 Dependencies: 11/06 08:36:59 DEBUG stitchhandler.py:2104 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2079 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2091 VLAN Suggested (requested): 3736 11/06 08:36:59 DEBUG stitchhandler.py:2092 VLAN Available Range (requested): 3730-3739 11/06 08:36:59 DEBUG stitchhandler.py:2099 Import VLANs From: None 11/06 08:36:59 DEBUG stitchhandler.py:2107 ===== Aggregates ===== 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2111 (User requested) 11/06 08:36:59 DEBUG stitchhandler.py:2117 A ProtoGENI Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2113 (SCS added) 11/06 08:36:59 DEBUG stitchhandler.py:2121 An OESS Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2113 (SCS added) 11/06 08:36:59 DEBUG stitchhandler.py:2115 A DCN Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2149 Depends on 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2111 (User requested) 11/06 08:36:59 DEBUG stitchhandler.py:2117 A ProtoGENI Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2111 (User requested) 11/06 08:36:59 DEBUG stitchhandler.py:2117 A ProtoGENI Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2111 (User requested) 11/06 08:36:59 DEBUG stitchhandler.py:2117 A ProtoGENI Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2109 Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2111 (User requested) 11/06 08:36:59 DEBUG stitchhandler.py:2123 An Orca Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2125 The ExoSM Aggregate 11/06 08:36:59 DEBUG stitchhandler.py:2127 Alternate URL: https://ucd-hn.exogeni.net:11443/orca/xmlrpc 11/06 08:36:59 DEBUG stitchhandler.py:2128 Using AM API version 2 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 DEBUG stitchhandler.py:2147 Hop 11/06 08:36:59 INFO stitchhandler.py:826 Multi-AM reservation will include resources from these aggregates: 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 INFO stitchhandler.py:828 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3084 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3093 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3093 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3090 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:963 doesn't support requesting 'any' VLAN tag - move on 11/06 08:36:59 DEBUG stitchhandler.py:963 doesn't support requesting 'any' VLAN tag - move on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1637 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1643 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1842 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 1844 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3736 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:1009 doesn't import and not marked as either a VLAN producer or consumer. Assuming 'any' is OK. 11/06 08:36:59 DEBUG stitchhandler.py:1034 Changing suggested request tag from 3732 to 'any' on 11/06 08:36:59 DEBUG stitchhandler.py:963 doesn't support requesting 'any' VLAN tag - move on 11/06 08:36:59 DEBUG launcher.py:55 There are 5 ready aggregates: [Aggregate('chicago-ig'), Aggregate('stanford-ig'), Aggregate('ukypks2-ig'), Aggregate('rutgers-ig'), Aggregate('eg-sm')] 11/06 08:36:59 DEBUG objects.py:495 Starting allocate on ... 11/06 08:36:59 DEBUG objects.py:573 : Request: Suggested: any. Range: '3075-3094'. Unavail: '' 11/06 08:36:59 DEBUG objects.py:573 : Request: Suggested: any. Range: '3075-3094'. Unavail: '' 11/06 08:36:59 DEBUG objects.py:573 : Request: Suggested: any. Range: '3075-3094'. Unavail: '' 11/06 08:36:59 DEBUG objects.py:573 : Request: Suggested: any. Range: '3075-3094'. Unavail: '' 11/06 08:36:59 DEBUG objects.py:732 Starting newExpires at slice expiration 2014-11-12 18:45:33+00:00, so init minDays to 6 11/06 08:36:59 DEBUG objects.py:753 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:765 After checking own rules, minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=14 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:792 policy says expDays=90 11/06 08:36:59 DEBUG objects.py:806 After , minDays=6, newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 DEBUG objects.py:810 Will request newExpires=2014-11-12 13:26:59.832117 11/06 08:36:59 INFO handler_utils.py:822 Writing to '/tmp/6sites-createsliver-request-11-geni-uchicago-edu.xml' 11/06 08:36:59 DEBUG objects.py:1544 Saved AM urn:publicid:IDN+geni.uchicago.edu+authority+cm new request RSpec to file /tmp/6sites-createsliver-request-11-geni-uchicago-edu.xml 11/06 08:36:59 INFO objects.py:1555 Stitcher doing createsliver at ... 11/06 08:36:59 DEBUG objects.py:1556 omniargs: ['-o', '--raise-error-on-v2-amapi-error', '-V2', '-a', 'https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am', 'createsliver', '6sites', u'/tmp/6sites-createsliver-request-11-geni-uchicago-edu.xml'] 11/06 08:36:59 INFO oscript.py:201 Loading agg_nick_cache file '/home/lnevers/.gcf/agg_nick_cache' 11/06 08:36:59 INFO oscript.py:266 Loading config file '/home/lnevers/.gcf/omni_config' 11/06 08:36:59 INFO oscript.py:348 Using control framework portal 11/06 08:36:59 DEBUG oscript.py:453 Using framework type chapi 11/06 08:36:59 INFO framework_chapi.py:95 Member Authority is https://ch.geni.net/MA (from config) 11/06 08:36:59 INFO framework_chapi.py:101 Slice Authority is https://ch.geni.net/SA (from config) 11/06 08:36:59 DEBUG framework_chapi.py:131 CH speaks CHAPI v1 11/06 08:36:59 DEBUG oscript.py:533 User Cert File: /home/lnevers/.ssl/geni_cert_portal.pem 11/06 08:36:59 DEBUG handler_utils.py:170 Supplied AM URL https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am is URN urn:publicid:IDN+geni.uchicago.edu+authority+cm according to configured aggregate nicknames (nick chicago-ig T1) 11/06 08:36:59 DEBUG handler_utils.py:352 Adding aggregate https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am (urn:publicid:IDN+geni.uchicago.edu+authority+cm) to query list 11/06 08:37:00 DEBUG amhandler.py:503 Read GetVersionCache from /home/lnevers/.gcf/get_version_cache.json 11/06 08:37:00 DEBUG amhandler.py:557 Checking cache for https://www.geni.uchicago.edu:12369/protogeni/xmlrpc/am 11/06 08:37:00 DEBUG amhandler.py:430 Pulling GetVersion from cache 11/06 08:37:00 DEBUG amhandler.py:226 Config version spoken here by all AMs 11/06 08:37:00 DEBUG framework_chapi.py:1001 Using project from commandline. 11/06 08:37:00 INFO handler_utils.py:403 Getting credential from file /tmp/slice-ch-geni-net-ln-test-6sites-for-lnevers-cred.xml 11/06 08:37:00 DEBUG handler_utils.py:413 Failed to get a JSON struct from cred in file /tmp/slice-ch-geni-net-ln-test-6sites-for-lnevers-cred.xml. Treat as a string. 11/06 08:37:00 DEBUG handler_utils.py:424 Using APIv2 and got cred seemingly in right form, return it 11/06 08:37:00 INFO handler_utils.py:449 Read slice cred from /tmp/slice-ch-geni-net-ln-test-6sites-for-lnevers-cred.xml for slice urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites 11/06 08:37:00 INFO handler_utils.py:528 Slice urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites expires on 2014-11-12 18:45:33 UTC 11/06 08:37:00 INFO amhandler.py:1810 Creating sliver(s) from rspec file /tmp/6sites-createsliver-request-11-geni-uchicago-edu.xml for slice urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites 11/06 08:37:00 DEBUG amhandler.py:5186 Getting users and SSH keys from the Clearinghouse list of slice members 11/06 08:37:00 DEBUG framework_chapi.py:1001 Using project from commandline. 11/06 08:37:00 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+6sites'}} 11/06 08:37:00 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+6sites'}} 11/06 08:37:00 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+6sites'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Get members for GENI Clearinghouse slice urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites 11/06 08:37:00 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'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 11/06 08:37:00 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'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers1 SSH keys 11/06 08:37:00 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'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 11/06 08:37:00 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'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers2 SSH keys 11/06 08:37:00 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'}} 11/06 08:37:00 DEBUG framework_chapi.py:1536 Successfully completed Lookup member email 11/06 08:37:00 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'}} 11/06 08:37:01 DEBUG framework_chapi.py:1536 Successfully completed Lookup member urn:publicid:IDN+ch.geni.net+user+lnevers SSH keys 11/06 08:37:01 DEBUG amhandler.py:5228 From Clearinghouse got 3 users whose SSH keys will be set 11/06 08:37:01 DEBUG amhandler.py:5237 Reading users and keys to install from your omni_config 11/06 08:37:01 DEBUG amhandler.py:5272 User urn:publicid:IDN+ch.geni.net+user+lnevers Newkeys: "['ssh-rsa AAAAB3NzaC1yc2EAAAADAQABAAAAgQDtJ2K35BxWRtGOCNsvEbWcDL03bojbm6tsAOpHAvt1DC0RURNE3hBPZjaGSl8NIbFmEFErPbz3snNuzLpxfVKvmsf6HdMqw+RY6/GahChANxibJjDp5dyf1y"... 11/06 08:37:01 DEBUG amhandler.py:5293 After reading omni_config, 3 users will have SSH keys set 11/06 08:37:01 DEBUG amhandler.py:1830 Doing createsliver with urn urn:publicid:IDN+ch.geni.net:ln-test+slice+6sites, 1 creds, rspec of length 30457 starting '