testpeers-m.ref

   1 1 sites, 1 persons, 1 nodes & 1 slices
   2 1 keys/person, 1 nodes/slice & 1 persons/slice
   3 fast_flag True
   4 ==================== ('INIT',)
   5 +++ timer start
   6 initializing s[1]=>https://planetlab-devbox.inria.fr:443/PLCAPI/
   7 initializing s[2]=>https://lurch.cs.princeton.edu:443/PLCAPI/
   8 ==================== s[1]
   9 root@plc1.org connected using password authentication
  10 ==================== s[2]
  11 root@plc2.org connected using password authentication
  12 ====================
  13 01:== created peer admin account 3, peer1@planet-lab.org - peer
  14 02:== created peer admin account 3, peer2@planet-lab.org - peer
  15 01:== enabled+admin on account 3:peer1@planet-lab.org
  16 02:== enabled+admin on account 3:peer2@planet-lab.org
  17 01:== Created person 4 as the auth peer person
  18 02:== Created person 4 as the auth peer person
  19 01:Created peer 1
  20 PLEASE manually set password for person_id=4 in DB1
  21 02:Created peer 1
  22 PLEASE manually set password for person_id=4 in DB2
  23 Attempting to remotely set passwd for person_id=4 in DB1UPDATE 1
  24  -> system returns 0
  25 Attempting to remotely set passwd for person_id=4 in DB2UPDATE 1
  26  -> system returns 0
  27 +++ 12.65 seconds ellapsed (12.65)
  28 01:== Created site 2 with max_slices=1
  29 02:== Created site 2 with max_slices=1
  30 === refresh after site creation
  31 +++ 15.45 seconds ellapsed (2.81)
  32 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 1 new_sites 2 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 1 peername Thierry plc2 }
  33 +++ ellapsed: { process-keys 0.00187206268311 process-nodes 0.0131161212921 process-persons 0.0228447914124 process-sa 0.0442531108856 process-sat 0.00443816184998 process-sites 0.0391249656677 process-slices 0.0585858821869 time_all 1.35947585106 time_gather 0.0363368988037 time_process 0.184247016907 time_transmit 1.13889193535 }
  34 +++ 17.77 seconds ellapsed (2.32)
  35 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 1 new_sites 2 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 1 peername Thierry plc1 }
  36 +++ ellapsed: { process-keys 0.00152492523193 process-nodes 0.0102491378784 process-persons 0.0173819065094 process-sa 0.035835981369 process-sat 0.00392007827759 process-sites 0.0281548500061 process-slices 0.0454080104828 time_all 1.44377303123 time_gather 0.047375202179 time_process 0.142483949661 time_transmit 1.25391387939 }
  37 +++ 20.83 seconds ellapsed (3.05)
  38 +++ 20.83 seconds ellapsed (0.00)
  39 === refresh before persons&keys creation
  40 +++ 21.73 seconds ellapsed (0.91)
  41 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
  42 +++ ellapsed: { process-keys 0.00185799598694 process-nodes 0.0127828121185 process-persons 0.00935196876526 process-sa 0.050724029541 process-sat 0.00481915473938 process-sites 0.00845813751221 process-slices 0.0203249454498 time_all 1.39590501785 time_gather 0.0357558727264 time_process 0.108330965042 time_transmit 1.25181818008 }
  43 +++ 24.09 seconds ellapsed (2.35)
  44 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
  45 +++ ellapsed: { process-keys 0.0015561580658 process-nodes 0.0101759433746 process-persons 0.0075409412384 process-sa 0.0445561408997 process-sat 0.00391793251038 process-sites 0.0042610168457 process-slices 0.0164890289307 time_all 1.37915301323 time_gather 0.0441029071808 time_process 0.0885071754456 time_transmit 1.2465429306 }
  46 +++ 27.08 seconds ellapsed (2.99)
  47 01: Checking keys: got 0 local (e=0) & 0 foreign (e=0)
  48 02: Checking keys: got 0 local (e=0) & 0 foreign (e=0)
  49 01: Checking persons: got 4 local (e=4) & 1 foreign (e=1)
  50 02: Checking persons: got 4 local (e=4) & 1 foreign (e=1)
  51 ==================== ('Creating persons&keys',)
  52 01:== created user account 6, user1-1@plc1.org - password1
  53 01:== added key ssh-rsa 11key4plc11 user1-key1 to person user1-1@plc1.org
  54 02:== created user account 6, user2-1@plc2.org - password2
  55 02:== added key ssh-rsa 22key4plc22 user1-key1 to person user2-1@plc2.org
  56 01: Checking keys: got 1 local (e=1) & 0 foreign (e=0)
  57 02: Checking keys: got 1 local (e=1) & 0 foreign (e=0)
  58 01: Checking persons: got 5 local (e=5) & 1 foreign (e=1)
  59 02: Checking persons: got 5 local (e=5) & 1 foreign (e=1)
  60 === refresh after persons&keys creation
  61 +++ 35.42 seconds ellapsed (8.34)
  62 01:== Refreshing peer Result: { new_keys 1 new_nodes 0 new_persons 1 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
  63 +++ ellapsed: { process-keys 0.0169279575348 process-nodes 0.00791501998901 process-persons 0.0219900608063 process-sa 0.0430068969727 process-sat 0.00294184684753 process-sites 0.00481414794922 process-slices 0.0186290740967 time_all 1.39798283577 time_gather 0.0351798534393 time_process 0.116235971451 time_transmit 1.24656701088 }
  64 +++ 37.78 seconds ellapsed (2.36)
  65 02:== Refreshing peer Result: { new_keys 1 new_nodes 0 new_persons 1 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
  66 +++ ellapsed: { process-keys 0.0234339237213 process-nodes 0.010241985321 process-persons 0.0201289653778 process-sa 0.0357789993286 process-sat 0.00359702110291 process-sites 0.00632905960083 process-slices 0.0169849395752 time_all 1.40414118767 time_gather 0.0353991985321 time_process 0.116506099701 time_transmit 1.25223588943 }
  67 +++ 40.80 seconds ellapsed (3.02)
  68 01: Checking keys: got 1 local (e=1) & 1 foreign (e=1)
  69 02: Checking keys: got 1 local (e=1) & 1 foreign (e=1)
  70 01: Checking persons: got 5 local (e=5) & 2 foreign (e=2)
  71 02: Checking persons: got 5 local (e=5) & 2 foreign (e=2)
  72 +++ 42.83 seconds ellapsed (2.03)
  73 ==================== ('RESETTING NODES',)
  74 01:== Cleaning all nodes
  75 02:== Cleaning all nodes
  76 === refresh cleaned nodes
  77 +++ 43.74 seconds ellapsed (0.92)
  78 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
  79 +++ ellapsed: { process-keys 0.00171685218811 process-nodes 0.0079231262207 process-persons 0.00981497764587 process-sa 0.0465297698975 process-sat 0.00292110443115 process-sites 0.00483512878418 process-slices 0.0179870128632 time_all 1.37383198738 time_gather 0.028048992157 time_process 0.0917401313782 time_transmit 1.25404286385 }
  80 +++ 46.05 seconds ellapsed (2.30)
  81 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
  82 +++ ellapsed: { process-keys 0.00127005577087 process-nodes 0.00630617141724 process-persons 0.00773787498474 process-sa 0.0441401004791 process-sat 0.00231099128723 process-sites 0.00371384620667 process-slices 0.0141839981079 time_all 1.362429142 time_gather 0.0338530540466 time_process 0.0796730518341 time_transmit 1.24890303612 }
  83 +++ 48.99 seconds ellapsed (2.95)
  84 01: Checking nodes: got 0 local (e=0) & 0 foreign (e=0)
  85 02: Checking nodes: got 0 local (e=0) & 0 foreign (e=0)
  86 ==================== ('CREATING NODES',)
  87 01:== Added node 1 n1-001.plc1.org
  88 02:== Added node 1 n2-001.plc2.org
  89 01: Checking nodes: got 1 local (e=1) & 0 foreign (e=0)
  90 02: Checking nodes: got 1 local (e=1) & 0 foreign (e=0)
  91 === refresh after node creation
  92 +++ 52.69 seconds ellapsed (3.69)
  93 01:== Refreshing peer Result: { new_keys 0 new_nodes 1 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
  94 +++ ellapsed: { process-keys 0.00156712532043 process-nodes 0.0392558574677 process-persons 0.0105230808258 process-sa 0.0451200008392 process-sat 0.00488495826721 process-sites 0.00787591934204 process-slices 0.0221281051636 time_all 1.42973208427 time_gather 0.0377390384674 time_process 0.131366014481 time_transmit 1.26062703133 }
  95 +++ 55.08 seconds ellapsed (2.39)
  96 02:== Refreshing peer Result: { new_keys 0 new_nodes 1 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
  97 +++ ellapsed: { process-keys 0.00122404098511 process-nodes 0.0358860492706 process-persons 0.00814986228943 process-sa 0.0351011753082 process-sat 0.00236320495605 process-sites 0.00373101234436 process-slices 0.0153248310089 time_all 1.4273250103 time_gather 0.0468180179596 time_process 0.101790189743 time_transmit 1.2787168026 }
  98 +++ 58.11 seconds ellapsed (3.04)
  99 01: Checking nodes: got 1 local (e=1) & 1 foreign (e=1)
 100 02: Checking nodes: got 1 local (e=1) & 1 foreign (e=1)
 101 02:== Deleted node 1, returns 1
 102 02: Checking nodes: got 0 local (e=0) & 1 foreign (e=1)
 103 === refresh after deletion on plc2
 104 +++ 61.39 seconds ellapsed (3.28)
 105 01:== Refreshing peer Result: { new_keys 0 new_nodes -1 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
 106 +++ ellapsed: { process-keys 0.00149607658386 process-nodes 0.0296859741211 process-persons 0.0103340148926 process-sa 0.0472400188446 process-sat 0.00487303733826 process-sites 0.00460696220398 process-slices 0.0209500789642 time_all 1.42121005058 time_gather 0.0345249176025 time_process 0.119199037552 time_transmit 1.26748609543 }
 107 +++ 63.77 seconds ellapsed (2.38)
 108 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
 109 +++ ellapsed: { process-keys 0.00124406814575 process-nodes 0.00670099258423 process-persons 0.0107979774475 process-sa 0.0376420021057 process-sat 0.00233602523804 process-sites 0.0038149356842 process-slices 0.014878988266 time_all 1.37179398537 time_gather 0.0401089191437 time_process 0.0774250030518 time_transmit 1.25426006317 }
 110 +++ 66.76 seconds ellapsed (2.99)
 111 01: Checking nodes: got 1 local (e=1) & 0 foreign (e=0)
 112 02: Checking nodes: got 0 local (e=0) & 1 foreign (e=1)
 113 ==================== ('ADD on plc2 for different indexes',)
 114 02:== Added node 3 n2-001.plc2.org
 115 01: Checking nodes: got 1 local (e=1) & 0 foreign (e=0)
 116 02: Checking nodes: got 1 local (e=1) & 1 foreign (e=1)
 117 === refresh after re-creation on plc2
 118 +++ 70.15 seconds ellapsed (3.40)
 119 01:== Refreshing peer Result: { new_keys 0 new_nodes 1 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
 120 +++ ellapsed: { process-keys 0.00150299072266 process-nodes 0.0358688831329 process-persons 0.00979900360107 process-sa 0.0419690608978 process-sat 0.00292706489563 process-sites 0.00445508956909 process-slices 0.0184469223022 time_all 1.39348506927 time_gather 0.0272309780121 time_process 0.114979028702 time_transmit 1.25127506256 }
 121 +++ 72.47 seconds ellapsed (2.32)
 122 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
 123 +++ ellapsed: { process-keys 0.00122117996216 process-nodes 0.0062530040741 process-persons 0.00767397880554 process-sa 0.0404908657074 process-sat 0.00227689743042 process-sites 0.00377798080444 process-slices 0.0144591331482 time_all 1.38321280479 time_gather 0.0347759723663 time_process 0.0761640071869 time_transmit 1.27227282524 }
 124 +++ 75.45 seconds ellapsed (2.98)
 125 01: Checking nodes: got 1 local (e=1) & 1 foreign (e=1)
 126 02: Checking nodes: got 1 local (e=1) & 1 foreign (e=1)
 127 +++ 76.40 seconds ellapsed (0.94)
 128 ==================== ('RESETTING SLICES TEST',)
 129 01:== Cleaning all nodes
 130 01:==== Cleaning node 1
 131 02:== Cleaning all nodes
 132 02:==== Cleaning node 3
 133 01:== Added node 4 n1-001.plc1.org
 134 02:== Added node 4 n2-001.plc2.org
 135 01:== Cleaning all slices
 136 02:== Cleaning all slices
 137 === refresh After slices init
 138 +++ 81.06 seconds ellapsed (4.66)
 139 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
 140 +++ ellapsed: { process-keys 0.00150895118713 process-nodes 0.00855898857117 process-persons 0.0101561546326 process-sa 0.0523529052734 process-sat 0.00292491912842 process-sites 0.00475001335144 process-slices 0.0189361572266 time_all 1.38092088699 time_gather 0.0270409584045 time_process 0.0992200374603 time_transmit 1.25465989113 }
 141 +++ 83.39 seconds ellapsed (2.33)
 142 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
 143 +++ ellapsed: { process-keys 0.00127601623535 process-nodes 0.00647497177124 process-persons 0.00792694091797 process-sa 0.0410261154175 process-sat 0.00231099128723 process-sites 0.00358891487122 process-slices 0.0150990486145 time_all 1.36357712746 time_gather 0.0342109203339 time_process 0.0777130126953 time_transmit 1.25165319443 }
 144 +++ 86.34 seconds ellapsed (2.95)
 145 ==================== ('CREATING SLICES on plc1',)
 146 01:== created slice 3 - max nodes=1
 147 01:== Attached person user1-1@plc1.org to slice oneone_slice1
 148 01: Checking slices: got 2 local (e=2) & 1 foreign (e=1)
 149 02: Checking slices: got 1 local (e=1) & 1 foreign (e=1)
 150 === refresh after slice created on plc1
 151 +++ 88.57 seconds ellapsed (2.22)
 152 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
 153 +++ ellapsed: { process-keys 0.00152897834778 process-nodes 0.00817203521729 process-persons 0.0101108551025 process-sa 0.0455498695374 process-sat 0.00291109085083 process-sites 0.0045051574707 process-slices 0.0193800926208 time_all 1.36998486519 time_gather 0.0266888141632 time_process 0.092169046402 time_transmit 1.25112700462 }
 154 +++ 90.86 seconds ellapsed (2.30)
 155 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 1 peername Thierry plc1 }
 156 +++ ellapsed: { process-keys 0.00121784210205 process-nodes 0.006432056427 process-persons 0.00792193412781 process-sa 0.0354299545288 process-sat 0.00229907035828 process-sites 0.00358819961548 process-slices 0.0593400001526 time_all 1.42629885674 time_gather 0.0346400737762 time_process 0.116239070892 time_transmit 1.27541971207 }
 157 +++ 93.89 seconds ellapsed (3.03)
 158 01: Checking slices: got 2 local (e=2) & 1 foreign (e=1)
 159 02: Checking slices: got 1 local (e=1) & 2 foreign (e=2)
 160 01: local slice oneone_slice1 (e=0)  on nodes  []
 161 02: foreign slice oneone_slice1 (e=0)  on nodes  []
 162 ==================== ('ADDING LOCAL NODES IN SLICES',)
 163 01:== added in slice oneone_slice1 local  ['n1-001.plc1.org']
 164 01: local slice oneone_slice1 (e=1)  on nodes  [4]
 165 [LOC:1] :  n1-001.plc1.org
 166 02: foreign slice oneone_slice1 (e=0)  on nodes  []
 167 === refresh After local nodes were added on plc1
 168 +++ 99.57 seconds ellapsed (5.68)
 169 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
 170 +++ ellapsed: { process-keys 0.00151109695435 process-nodes 0.0081479549408 process-persons 0.00996899604797 process-sa 0.052747964859 process-sat 0.00294899940491 process-sites 0.00451302528381 process-slices 0.0271790027618 time_all 1.39193797112 time_gather 0.0279450416565 time_process 0.107028007507 time_transmit 1.25696492195 }
 171 +++ 101.90 seconds ellapsed (2.33)
 172 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
 173 +++ ellapsed: { process-keys 0.00130009651184 process-nodes 0.00653386116028 process-persons 0.00807523727417 process-sa 0.0371618270874 process-sat 0.00233578681946 process-sites 0.0103189945221 process-slices 0.020336151123 time_all 1.39414095879 time_gather 0.0356500148773 time_process 0.0860719680786 time_transmit 1.27241897583 }
 174 +++ 104.88 seconds ellapsed (2.98)
 175 01: local slice oneone_slice1 (e=1)  on nodes  [4]
 176 [LOC:1] :  n1-001.plc1.org
 177 02: foreign slice oneone_slice1 (e=1)  on nodes  [2]
 178 [FOR:1] :  n1-001.plc1.org
 179 ==================== ('ADDING FOREIGN NODES IN SLICES',)
 180 01:== added in slice oneone_slice1 foreign  ['n2-001.plc2.org']
 181 01: local slice oneone_slice1 (e=2)  on nodes  [4, 3]
 182 [LOC:1] :  n1-001.plc1.org
 183 [FOR:1] :  n2-001.plc2.org
 184 02: foreign slice oneone_slice1 (e=1)  on nodes  [2]
 185 [FOR:1] :  n1-001.plc1.org
 186 === refresh After foreign nodes were added in plc1
 187 +++ 108.67 seconds ellapsed (3.79)
 188 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
 189 +++ ellapsed: { process-keys 0.00152397155762 process-nodes 0.0081639289856 process-persons 0.0100080966949 process-sa 0.0452840328217 process-sat 0.00294494628906 process-sites 0.00448203086853 process-slices 0.019238948822 time_all 1.38103199005 time_gather 0.0268399715424 time_process 0.0916569232941 time_transmit 1.26253509521 }
 190 +++ 110.97 seconds ellapsed (2.30)
 191 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
 192 +++ ellapsed: { process-keys 0.00126123428345 process-nodes 0.00659394264221 process-persons 0.00803995132446 process-sa 0.0343852043152 process-sat 0.00231695175171 process-sites 0.00365781784058 process-slices 0.0234179496765 time_all 1.38852381706 time_gather 0.0352280139923 time_process 0.0796828269958 time_transmit 1.27361297607 }
 193 +++ 114.10 seconds ellapsed (3.12)
 194 01: local slice oneone_slice1 (e=2)  on nodes  [4, 3]
 195 [LOC:1] :  n1-001.plc1.org
 196 [FOR:1] :  n2-001.plc2.org
 197 02: foreign slice oneone_slice1 (e=2)  on nodes  [2, 4]
 198 [LOC:1] :  n2-001.plc2.org
 199 [FOR:1] :  n1-001.plc1.org
 200 01: 2 slivers (exp. 2) in GetSlivers for node n1-001.plc1.org
 201 >>slivername =  thone_netflow
 202 {    'attributes': [    {'name': 'system', 'value': '1'},
 203                         {'name': 'vref', 'value': 'planetflow'},
 204                         {    'name': 'proper_op',
 205                              'value': 'open file=/etc/passwd, flags=r'},
 206                         {'name': 'proper_op', 'value': 'create_socket'},
 207                         {'name': 'proper_op', 'value': 'bind_socket'}],
 208      'expires': 1167124802,
 209      'instantiation': 'plc-instantiated',
 210      'keys': [],
 211      'name': 'thone_netflow',
 212      'slice_id': 1}
 213 >>slivername =  oneone_slice1
 214 {    'attributes': [],
 215      'expires': 1167124892,
 216      'instantiation': 'plc-instantiated',
 217      'keys': [{'key_type': 'ssh', 'key': 'ssh-rsa 11key4plc11 user1-key1'}],
 218      'name': 'oneone_slice1',
 219      'slice_id': 3}
 220 02: 2 slivers (exp. 2) in GetSlivers for node n2-001.plc2.org
 221 >>slivername =  thtwo_netflow
 222 {    'attributes': [    {'name': 'system', 'value': '1'},
 223                         {'name': 'vref', 'value': 'planetflow'},
 224                         {    'name': 'proper_op',
 225                              'value': 'open file=/etc/passwd, flags=r'},
 226                         {'name': 'proper_op', 'value': 'create_socket'},
 227                         {'name': 'proper_op', 'value': 'bind_socket'}],
 228      'expires': 1167124803,
 229      'instantiation': 'plc-instantiated',
 230      'keys': [],
 231      'name': 'thtwo_netflow',
 232      'slice_id': 1}
 233 >>slivername =  oneone_slice1
 234 {    'attributes': [],
 235      'expires': 1167124892,
 236      'instantiation': 'plc-instantiated',
 237      'keys': [{'key_type': 'ssh', 'key': 'ssh-rsa 11key4plc11 user1-key1'}],
 238      'name': 'oneone_slice1',
 239      'slice_id': 3}
 240 ==================== ('DELETING FOREIGN NODES FROM SLICES',)
 241 01:== deleted in slice oneone_slice1 foreign  ['n2-001.plc2.org']
 242 01: local slice oneone_slice1 (e=1)  on nodes  [4]
 243 [LOC:1] :  n1-001.plc1.org
 244 02: foreign slice oneone_slice1 (e=2)  on nodes  [2, 4]
 245 [LOC:1] :  n2-001.plc2.org
 246 [FOR:1] :  n1-001.plc1.org
 247 01: 2 slivers (exp. 2) in GetSlivers for node n1-001.plc1.org
 248 >>slivername =  thone_netflow
 249 {    'attributes': [    {'name': 'system', 'value': '1'},
 250                         {'name': 'vref', 'value': 'planetflow'},
 251                         {    'name': 'proper_op',
 252                              'value': 'open file=/etc/passwd, flags=r'},
 253                         {'name': 'proper_op', 'value': 'create_socket'},
 254                         {'name': 'proper_op', 'value': 'bind_socket'}],
 255      'expires': 1167124802,
 256      'instantiation': 'plc-instantiated',
 257      'keys': [],
 258      'name': 'thone_netflow',
 259      'slice_id': 1}
 260 >>slivername =  oneone_slice1
 261 {    'attributes': [],
 262      'expires': 1167124892,
 263      'instantiation': 'plc-instantiated',
 264      'keys': [{'key_type': 'ssh', 'key': 'ssh-rsa 11key4plc11 user1-key1'}],
 265      'name': 'oneone_slice1',
 266      'slice_id': 3}
 267 === refresh After foreign nodes were removed on plc1
 268 +++ 119.61 seconds ellapsed (5.51)
 269 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
 270 +++ ellapsed: { process-keys 0.00157403945923 process-nodes 0.00830793380737 process-persons 0.0101699829102 process-sa 0.0491280555725 process-sat 0.00297117233276 process-sites 0.0045690536499 process-slices 0.0274848937988 time_all 1.39951491356 time_gather 0.0271489620209 time_process 0.104216098785 time_transmit 1.26814985275 }
 271 +++ 121.96 seconds ellapsed (2.35)
 272 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc1 }
 273 +++ ellapsed: { process-keys 0.00129103660583 process-nodes 0.00658583641052 process-persons 0.00811719894409 process-sa 0.0365178585052 process-sat 0.00234580039978 process-sites 0.00364899635315 process-slices 0.0177311897278 time_all 1.36673092842 time_gather 0.0343110561371 time_process 0.0762469768524 time_transmit 1.25617289543 }
 274 +++ 124.91 seconds ellapsed (2.95)
 275 01: local slice oneone_slice1 (e=1)  on nodes  [4]
 276 [LOC:1] :  n1-001.plc1.org
 277 02: foreign slice oneone_slice1 (e=1)  on nodes  [2]
 278 [FOR:1] :  n1-001.plc1.org
 279 ==================== ('DELETING LOCAL NODES FROM SLICES',)
 280 01:== deleted in slice oneone_slice1 local  ['n1-001.plc1.org']
 281 01: local slice oneone_slice1 (e=0)  on nodes  []
 282 02: foreign slice oneone_slice1 (e=1)  on nodes  [2]
 283 [FOR:1] :  n1-001.plc1.org
 284 === refresh After local nodes were removed on plc1
 285 +++ 128.68 seconds ellapsed (3.77)
 286 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
 287 +++ ellapsed: { process-keys 0.00156998634338 process-nodes 0.00831007957458 process-persons 0.010241985321 process-sa 0.0475158691406 process-sat 0.00295495986938 process-sites 0.00458598136902 process-slices 0.0194849967957 time_all 1.38277220726 time_gather 0.0275099277496 time_process 0.0946750640869 time_transmit 1.26058721542 }
 288 +++ 130.99 seconds ellapsed (2.31)
 289 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
 290 +++ ellapsed: { process-keys 0.00128293037415 process-nodes 0.00624203681946 process-persons 0.00768899917603 process-sa 0.0340049266815 process-sat 0.00234603881836 process-sites 0.00349497795105 process-slices 0.0255310535431 time_all 1.37187600136 time_gather 0.0345640182495 time_process 0.0806000232697 time_transmit 1.25671195984 }
 291 +++ 133.94 seconds ellapsed (2.95)
 292 01: local slice oneone_slice1 (e=0)  on nodes  []
 293 02: foreign slice oneone_slice1 (e=0)  on nodes  []
 294 ==================== ('CHECKING SLICES CLEAN UP',)
 295 01:== Cleaning all slices
 296 01:==== Cleaned slice 3
 297 01: Checking slices: got 1 local (e=1) & 1 foreign (e=1)
 298 02: Checking slices: got 1 local (e=1) & 2 foreign (e=2)
 299 === refresh After slices clenaup
 300 +++ 137.84 seconds ellapsed (3.89)
 301 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices 0 peername Thierry plc2 }
 302 +++ ellapsed: { process-keys 0.00156617164612 process-nodes 0.00817894935608 process-persons 0.0102140903473 process-sa 0.0504338741302 process-sat 0.00292778015137 process-sites 0.004558801651 process-slices 0.0272362232208 time_all 1.390625 time_gather 0.0272800922394 time_process 0.105129003525 time_transmit 1.25821590424 }
 303 +++ 140.18 seconds ellapsed (2.34)
 304 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 0 new_slice_attributes 2 new_slices -1 peername Thierry plc1 }
 305 +++ ellapsed: { process-keys 0.00125813484192 process-nodes 0.00647687911987 process-persons 0.00806307792664 process-sa 0.0369892120361 process-sat 0.00230312347412 process-sites 0.00358200073242 process-slices 0.0377957820892 time_all 1.51435804367 time_gather 0.0339138507843 time_process 0.0964782238007 time_transmit 1.38396596909 }
 306 +++ 143.29 seconds ellapsed (3.11)
 307 01: Checking slices: got 1 local (e=1) & 1 foreign (e=1)
 308 02: Checking slices: got 1 local (e=1) & 1 foreign (e=1)
 309 +++ 145.12 seconds ellapsed (1.83)
 310 01:== created SliceAttributeType = 21
 311 02:== created SliceAttributeType = 21
 312 === refresh after SliceAttributeType creation
 313 +++ 146.93 seconds ellapsed (1.81)
 314 01:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 1 new_slice_attributes 5 new_slices 0 peername Thierry plc2 }
 315 +++ ellapsed: { process-keys 0.00155091285706 process-nodes 0.00823616981506 process-persons 0.0102210044861 process-sa 0.0424509048462 process-sat 0.0136008262634 process-sites 0.00462889671326 process-slices 0.0196220874786 time_all 1.38090491295 time_gather 0.0281710624695 time_process 0.100321769714 time_transmit 1.25241208076 }
 316 +++ 149.24 seconds ellapsed (2.31)
 317 02:== Refreshing peer Result: { new_keys 0 new_nodes 0 new_persons 0 new_sites 0 new_slice_attribute_types 1 new_slice_attributes 5 new_slices 0 peername Thierry plc1 }
 318 +++ ellapsed: { process-keys 0.00128197669983 process-nodes 0.00620007514954 process-persons 0.00767993927002 process-sa 0.0344758033752 process-sat 0.011048078537 process-sites 0.00348806381226 process-slices 0.0212440490723 time_all 1.39222192764 time_gather 0.0343980789185 time_process 0.0854279994965 time_transmit 1.27239584923 }
 319 +++ 152.21 seconds ellapsed (2.97)
 320 +++ 152.21 seconds ellapsed (0.00)
 321 01:============================== DUMPING
 322 01: SITES
 323 1 None thone Thierry plc1 Central []
 324 2 None oneone Thierry plc1 [1, 4]
 325 3 1 thtwo Thierry plc2 Central []
 326 4 1 twoone Thierry plc2 [2, 3]
 327 01: KEYS
 328 1 None ssh-rsa 11key4plc11 user1-key1
 329 2 1 ssh-rsa 22key4plc22 user1-key1
 330 01: PERSONS
 331 1 None maint@localhost.localdomain keys: [] sites: []
 332 2 None root@plc1.org keys: [] sites: [1]
 333 3 None peer1@planet-lab.org keys: [] sites: []
 334 4 None peer2@planet-lab.org keys: [] sites: []
 335 5 1 root@plc2.org keys: [] sites: [3]
 336 6 None user1-1@plc1.org keys: [1] sites: []
 337 7 1 user2-1@plc2.org keys: [2] sites: []
 338 01: NODES
 339 3 1 n2-001.plc2.org sls= [] site= 4
 340 4 None n1-001.plc1.org sls= [] site= 2
 341 01: SLICES
 342 1 None thone_netflow nodes= [] persons= []
 343 --- sas= [1, 2, 3, 4, 5] thone_netflow crp= 1
 344 --- 'expires': 1167124802
 345 2 1 thtwo_netflow nodes= [] persons= []
 346 --- sas= [27, 28, 29] thtwo_netflow crp= 1
 347 --- 'expires': 1167124803
 348 01: Slice Attribute Types
 349 1 None type 20 Type of slice (e.g. vserver)
 350 2 None system 10 Is a default system slice (1) or not (0 or unset)
 351 3 None enabled 10 Slice enabled (1 or unset) or suspended (0)
 352 4 None vref 30 Reference image
 353 5 None initscript 10 Slice initialization script
 354 6 None cpu_min 10 Minimum CPU share (ms/s)
 355 7 None cpu_share 10 Number of CPU shares
 356 8 None net_min 10 Minimum bandwidth (bps)
 357 9 None net_max 10 Maximum bandwidth (bps)
 358 10 None net_avg 10 Average bandwidth (bps)
 359 11 None net_share 10 Number of bandwidth shares
 360 12 None net2_min 10 Minimum bandwidth over routes exempt from node bandwidth limits (bps)
 361 13 None net2_max 10 Maximum bandwidth over routes exempt from node bandwidth limits (bps)
 362 14 None net2_avg 10 Average bandwidth over routes exempt from node bandwidth limits (bps)
 363 15 None net2_share 10 Number of bandwidth shares over routes exempt from node bandwidth limits
 364 16 None disk_max 10 Disk quota (1k disk blocks)
 365 17 None proper_op 10 Proper operation (e.g. bind_socket)
 366 18 None plc_slice_type 20 Type of slice rspec to be created
 367 19 None plc_agent_version 10 Version of PLC agent (slice creation service) software to be deployed
 368 20 None plc_ticket_pubkey 10 Public key used to verify PLC-signed tickets
 369 21 None sat_1 10 custom sat on plc1
 370 22 1 sat_2 10 custom sat on plc2
 371 01: Slice Attributes
 372 1 None system AT_id: 2
 373 --- v= 1 sl= 1 n= None
 374 2 None vref AT_id: 4
 375 --- v= planetflow sl= 1 n= None
 376 3 None proper_op AT_id: 17
 377 --- v= open file=/etc/passwd, flags=r sl= 1 n= None
 378 4 None proper_op AT_id: 17
 379 --- v= create_socket sl= 1 n= None
 380 5 None proper_op AT_id: 17
 381 --- v= bind_socket sl= 1 n= None
 382 27 1 system AT_id: 2
 383 --- v= 1 sl= 2 n= None
 384 28 1 vref AT_id: 4
 385 --- v= planetflow sl= 2 n= None
 386 29 1 proper_op AT_id: 17
 387 --- v= open file=/etc/passwd, flags=r sl= 2 n= None
 388 +++ 153.16 seconds ellapsed (0.95)
 389 01: Gathering all slivers
 390 +++ 153.45 seconds ellapsed (0.29)
 391 01: SLIVERS for first 1 nodes
 392 01:============================== END DUMP
 393 02:============================== DUMPING
 394 02: SITES
 395 1 None thtwo Thierry plc2 Central []
 396 2 None twoone Thierry plc2 [1, 3, 4]
 397 3 1 thone Thierry plc1 Central []
 398 4 1 oneone Thierry plc1 [2]
 399 02: KEYS
 400 1 None ssh-rsa 22key4plc22 user1-key1
 401 2 1 ssh-rsa 11key4plc11 user1-key1
 402 02: PERSONS
 403 1 None maint@localhost.localdomain keys: [] sites: []
 404 2 None root@plc2.org keys: [] sites: [1]
 405 3 None peer2@planet-lab.org keys: [] sites: []
 406 4 None peer1@planet-lab.org keys: [] sites: []
 407 5 1 root@plc1.org keys: [] sites: [3]
 408 6 None user2-1@plc2.org keys: [1] sites: []
 409 7 1 user1-1@plc1.org keys: [2] sites: []
 410 02: NODES
 411 2 1 n1-001.plc1.org sls= [] site= 4
 412 4 None n2-001.plc2.org sls= [] site= 2
 413 02: SLICES
 414 1 None thtwo_netflow nodes= [] persons= []
 415 --- sas= [1, 2, 3, 4, 5] thtwo_netflow crp= 1
 416 --- 'expires': 1167124803
 417 2 1 thone_netflow nodes= [] persons= []
 418 --- sas= [27, 28, 29] thone_netflow crp= 1
 419 --- 'expires': 1167124802
 420 02: Slice Attribute Types
 421 1 None type 20 Type of slice (e.g. vserver)
 422 2 None system 10 Is a default system slice (1) or not (0 or unset)
 423 3 None enabled 10 Slice enabled (1 or unset) or suspended (0)
 424 4 None vref 30 Reference image
 425 5 None initscript 10 Slice initialization script
 426 6 None cpu_min 10 Minimum CPU share (ms/s)
 427 7 None cpu_share 10 Number of CPU shares
 428 8 None net_min 10 Minimum bandwidth (bps)
 429 9 None net_max 10 Maximum bandwidth (bps)
 430 10 None net_avg 10 Average bandwidth (bps)
 431 11 None net_share 10 Number of bandwidth shares
 432 12 None net2_min 10 Minimum bandwidth over routes exempt from node bandwidth limits (bps)
 433 13 None net2_max 10 Maximum bandwidth over routes exempt from node bandwidth limits (bps)
 434 14 None net2_avg 10 Average bandwidth over routes exempt from node bandwidth limits (bps)
 435 15 None net2_share 10 Number of bandwidth shares over routes exempt from node bandwidth limits
 436 16 None disk_max 10 Disk quota (1k disk blocks)
 437 17 None proper_op 10 Proper operation (e.g. bind_socket)
 438 18 None plc_slice_type 20 Type of slice rspec to be created
 439 19 None plc_agent_version 10 Version of PLC agent (slice creation service) software to be deployed
 440 20 None plc_ticket_pubkey 10 Public key used to verify PLC-signed tickets
 441 21 None sat_2 10 custom sat on plc2
 442 22 1 sat_1 10 custom sat on plc1
 443 02: Slice Attributes
 444 1 None system AT_id: 2
 445 --- v= 1 sl= 1 n= None
 446 2 None vref AT_id: 4
 447 --- v= planetflow sl= 1 n= None
 448 3 None proper_op AT_id: 17
 449 --- v= open file=/etc/passwd, flags=r sl= 1 n= None
 450 4 None proper_op AT_id: 17
 451 --- v= create_socket sl= 1 n= None
 452 5 None proper_op AT_id: 17
 453 --- v= bind_socket sl= 1 n= None
 454 27 1 system AT_id: 2
 455 --- v= 1 sl= 2 n= None
 456 28 1 vref AT_id: 4
 457 --- v= planetflow sl= 2 n= None
 458 29 1 proper_op AT_id: 17
 459 --- v= open file=/etc/passwd, flags=r sl= 2 n= None
 460 +++ 159.70 seconds ellapsed (6.25)
 461 02: Gathering all slivers
 462 +++ 161.10 seconds ellapsed (1.40)
 463 02: SLIVERS for first 1 nodes
 464 02:============================== END DUMP
 465 +++ 161.91 seconds ellapsed (0.80)
 466 ==================== ('END',)
 467 +++ 161.91 seconds ellapsed (0.00)