Tutorial: Topology Log file Grid_2007_08_27_20_45_37.log¶
Click here to return to the tutorial.
Shown below is a snapshot of the log file generated for the tut_topo_1.rb file. The entire log file could not be acommodated here because of a restiction in the page size.
1 2007-08-27 20:45:37 INFO nodeHandler::init: NodeHandler Version 4.2.0 (1272) 2 2007-08-27 20:45:37 INFO nodeHandler::init: Experiment ID: grid_2007_08_27_20_45_37 3 2007-08-27 20:45:37 DEBUG nodeHandler::Experiment: command line args: . 4 2007-08-27 20:45:37 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/allOffSoft? 5 2007-08-27 20:45:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/allOffSoft? 6 2007-08-27 20:45:37 INFO nodeHandler::ExecApp: Starting application 'commServer': /opt/nodehandler4-4.2.0/sbin/commServer --logfile /tmp/commServer-grid_2007_08_27_20_45_37.log -d 4 --iface eth1 7 2007-08-27 20:45:37 DEBUG nodeHandler::Communicator: commServer(STARTED): '' 8 2007-08-27 20:45:39 DEBUG nodeHandler::ExecApp: writing 'R' to app 'commServer' 9 2007-08-27 20:45:44 DEBUG nodeHandler::web: Mounting /resource to /opt/nodehandler4-4.2.0/repository/public_html 10 2007-08-27 20:45:44 INFO nodeHandler::Experiment: load system:exp:stdlib 11 2007-08-27 20:45:44 INFO nodeHandler::prop.resetDelay: resetDelay = 210:Fixnum 12 2007-08-27 20:45:44 INFO nodeHandler::prop.resetTries: resetTries = 1:Fixnum 13 2007-08-27 20:45:44 INFO nodeHandler::Experiment: load tut_topo_1 14 2007-08-27 20:45:44 INFO nodeHandler::prop.minNodeNumber: minNodeNumber = 12:Fixnum 15 2007-08-27 20:45:44 INFO nodeHandler::prop.receiverNumber: receiverNumber = 8:Fixnum 16 2007-08-27 20:45:44 INFO nodeHandler::Topology: Loading topology 'topo_grid_active'. 17 2007-08-27 20:45:44 DEBUG nodeHandler::service call: http://cmc:5012/cmc/allStatus?domain= 18 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.17 n_7_17' to app 'commServer' 19 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_17: Created node 7@17 20 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.8 n_7_8' to app 'commServer' 21 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_8: Created node 7@8 22 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.19 n_20_19' to app 'commServer' 23 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_19: Created node 20@19 24 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.17 n_1_17' to app 'commServer' 25 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_17: Created node 1@17 26 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.19 n_10_19' to app 'commServer' 27 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_19: Created node 10@19 28 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.4.6 n_4_6' to app 'commServer' 29 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_4_6: Created node 4@6 30 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.15.18 n_15_18' to app 'commServer' 31 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_15_18: Created node 15@18 32 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.18 n_20_18' to app 'commServer' 33 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_18: Created node 20@18 34 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.20 n_11_20' to app 'commServer' 35 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_20: Created node 11@20 36 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.18 n_19_18' to app 'commServer' 37 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_18: Created node 19@18 38 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.5 n_16_5' to app 'commServer' 39 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_5: Created node 16@5 40 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.7 n_1_7' to app 'commServer' 41 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_7: Created node 1@7 42 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.1 n_11_1' to app 'commServer' 43 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_1: Created node 11@1 44 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.2 n_5_2' to app 'commServer' 45 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_2: Created node 5@2 46 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.11 n_19_11' to app 'commServer' 47 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_11: Created node 19@11 48 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.1 n_16_1' to app 'commServer' 49 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_1: Created node 16@1 50 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.15 n_1_15' to app 'commServer' 51 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_15: Created node 1@15 52 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '9@11' 53 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.14.16 n_14_16' to app 'commServer' 54 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_14_16: Created node 14@16 55 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.14 n_9_14' to app 'commServer' 56 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_14: Created node 9@14 57 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.20 n_20_20' to app 'commServer' 58 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_20: Created node 20@20 59 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.6.6 n_6_6' to app 'commServer' 60 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_6_6: Created node 6@6 61 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.15 n_5_15' to app 'commServer' 62 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_15: Created node 5@15 63 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.15.9 n_15_9' to app 'commServer' 64 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_15_9: Created node 15@9 65 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.17.5 n_17_5' to app 'commServer' 66 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_17_5: Created node 17@5 67 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.5 n_20_5' to app 'commServer' 68 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_5: Created node 20@5 69 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '11@4' 70 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.4.12 n_4_12' to app 'commServer' 71 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_4_12: Created node 4@12 72 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.3 n_19_3' to app 'commServer' 73 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_3: Created node 19@3 74 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.13.12 n_13_12' to app 'commServer' 75 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_13_12: Created node 13@12 76 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.17 n_11_17' to app 'commServer' 77 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_17: Created node 11@17 78 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.1 n_9_1' to app 'commServer' 79 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_1: Created node 9@1 80 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.4 n_10_4' to app 'commServer' 81 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_4: Created node 10@4 82 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.13.7 n_13_7' to app 'commServer' 83 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_13_7: Created node 13@7 84 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.8 n_19_8' to app 'commServer' 85 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_8: Created node 19@8 86 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.19 n_1_19' to app 'commServer' 87 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_19: Created node 1@19 88 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.7 n_5_7' to app 'commServer' 89 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_7: Created node 5@7 90 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.2 n_9_2' to app 'commServer' 91 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_2: Created node 9@2 92 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '11@3' 93 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.12.11 n_12_11' to app 'commServer' 94 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_12_11: Created node 12@11 95 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.18 n_18_18' to app 'commServer' 96 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_18: Created node 18@18 97 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.6 n_18_6' to app 'commServer' 98 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_6: Created node 18@6 99 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.12.20 n_12_20' to app 'commServer' 100 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_12_20: Created node 12@20 101 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.10 n_18_10' to app 'commServer' 102 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_10: Created node 18@10 103 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.20 n_5_20' to app 'commServer' 104 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_20: Created node 5@20 105 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.6.16 n_6_16' to app 'commServer' 106 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_6_16: Created node 6@16 107 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.17.16 n_17_16' to app 'commServer' 108 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_17_16: Created node 17@16 109 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.17.4 n_17_4' to app 'commServer' 110 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_17_4: Created node 17@4 111 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.2 n_1_2' to app 'commServer' 112 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_2: Created node 1@2 113 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.13 n_7_13' to app 'commServer' 114 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_13: Created node 7@13 115 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.10 n_1_10' to app 'commServer' 116 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_10: Created node 1@10 117 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.8.3 n_8_3' to app 'commServer' 118 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_8_3: Created node 8@3 119 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.13.16 n_13_16' to app 'commServer' 120 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_13_16: Created node 13@16 121 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.16 n_18_16' to app 'commServer' 122 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_16: Created node 18@16 123 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.4.19 n_4_19' to app 'commServer' 124 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_4_19: Created node 4@19 125 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '6@17' 126 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.19 n_7_19' to app 'commServer' 127 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_19: Created node 7@19 128 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.13.4 n_13_4' to app 'commServer' 129 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_13_4: Created node 13@4 130 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.20 n_7_20' to app 'commServer' 131 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_20: Created node 7@20 132 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.15.5 n_15_5' to app 'commServer' 133 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_15_5: Created node 15@5 134 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.12 n_16_12' to app 'commServer' 135 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_12: Created node 16@12 136 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.15 n_16_15' to app 'commServer' 137 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_15: Created node 16@15 138 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.2 n_16_2' to app 'commServer' 139 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_2: Created node 16@2 140 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.12 n_9_12' to app 'commServer' 141 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_12: Created node 9@12 142 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.1 n_19_1' to app 'commServer' 143 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_1: Created node 19@1 144 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.14.3 n_14_3' to app 'commServer' 145 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_14_3: Created node 14@3 146 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.6.18 n_6_18' to app 'commServer' 147 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_6_18: Created node 6@18 148 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.4.11 n_4_11' to app 'commServer' 149 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_4_11: Created node 4@11 150 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.17 n_10_17' to app 'commServer' 151 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_17: Created node 10@17 152 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.2 n_18_2' to app 'commServer' 153 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_2: Created node 18@2 154 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.9 n_7_9' to app 'commServer' 155 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_9: Created node 7@9 156 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.14.6 n_14_6' to app 'commServer' 157 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_14_6: Created node 14@6 158 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.12.4 n_12_4' to app 'commServer' 159 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_12_4: Created node 12@4 160 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.15.16 n_15_16' to app 'commServer' 161 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_15_16: Created node 15@16 162 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.9 n_10_9' to app 'commServer' 163 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_9: Created node 10@9 164 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '6@1' 165 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.7 n_11_7' to app 'commServer' 166 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_7: Created node 11@7 167 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.15 n_11_15' to app 'commServer' 168 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_15: Created node 11@15 169 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.10 n_7_10' to app 'commServer' 170 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_10: Created node 7@10 171 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.9 n_16_9' to app 'commServer' 172 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_9: Created node 16@9 173 2007-08-27 20:45:46 WARN nodeHandler::topo_grid_active: Ignoring missing node '14@2' 174 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.8 n_1_8' to app 'commServer' 175 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_8: Created node 1@8 176 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.16 n_5_16' to app 'commServer' 177 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_16: Created node 5@16 178 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.1 n_5_1' to app 'commServer' 179 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_1: Created node 5@1 180 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.3 n_20_3' to app 'commServer' 181 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_3: Created node 20@3 182 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.6 n_19_6' to app 'commServer' 183 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_6: Created node 19@6 184 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.11 n_20_11' to app 'commServer' 185 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_11: Created node 20@11 186 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.6 n_10_6' to app 'commServer' 187 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_6: Created node 10@6 188 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.5.9 n_5_9' to app 'commServer' 189 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_5_9: Created node 5@9 190 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.7 n_19_7' to app 'commServer' 191 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_7: Created node 19@7 192 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.17.7 n_17_7' to app 'commServer' 193 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_17_7: Created node 17@7 194 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.17 n_18_17' to app 'commServer' 195 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_17: Created node 18@17 196 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.8.17 n_8_17' to app 'commServer' 197 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_8_17: Created node 8@17 198 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.4.9 n_4_9' to app 'commServer' 199 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_4_9: Created node 4@9 200 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.12.1 n_12_1' to app 'commServer' 201 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_12_1: Created node 12@1 202 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.8.1 n_8_1' to app 'commServer' 203 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_8_1: Created node 8@1 204 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.5 n_19_5' to app 'commServer' 205 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_5: Created node 19@5 206 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.20 n_10_20' to app 'commServer' 207 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_20: Created node 10@20 208 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.14.5 n_14_5' to app 'commServer' 209 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_14_5: Created node 14@5 210 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.8 n_9_8' to app 'commServer' 211 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_8: Created node 9@8 212 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.16.3 n_16_3' to app 'commServer' 213 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_16_3: Created node 16@3 214 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.9 n_1_9' to app 'commServer' 215 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_9: Created node 1@9 216 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.2 n_19_2' to app 'commServer' 217 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_2: Created node 19@2 218 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.18.8 n_18_8' to app 'commServer' 219 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_18_8: Created node 18@8 220 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.7.5 n_7_5' to app 'commServer' 221 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_7_5: Created node 7@5 222 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.18 n_10_18' to app 'commServer' 223 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_18: Created node 10@18 224 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.15.2 n_15_2' to app 'commServer' 225 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_15_2: Created node 15@2 226 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.20.4 n_20_4' to app 'commServer' 227 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_20_4: Created node 20@4 228 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.9.17 n_9_17' to app 'commServer' 229 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_9_17: Created node 9@17 230 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.14.19 n_14_19' to app 'commServer' 231 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_14_19: Created node 14@19 232 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.19.20 n_19_20' to app 'commServer' 233 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_19_20: Created node 19@20 234 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.5 n_11_5' to app 'commServer' 235 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_5: Created node 11@5 236 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.13.11 n_13_11' to app 'commServer' 237 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_13_11: Created node 13@11 238 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.6 n_1_6' to app 'commServer' 239 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_6: Created node 1@6 240 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.10.5 n_10_5' to app 'commServer' 241 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_10_5: Created node 10@5 242 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.11.16 n_11_16' to app 'commServer' 243 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_11_16: Created node 11@16 244 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.1.13 n_1_13' to app 'commServer' 245 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_1_13: Created node 1@13 246 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'a 10.10.17.13 n_17_13' to app 'commServer' 247 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_17_13: Created node 17@13 248 2007-08-27 20:45:46 DEBUG nodeHandler::node::n_6_18: Added to group 'sender' 249 2007-08-27 20:45:46 DEBUG nodeHandler::ExecApp: writing 'A n_6_18 sender' to app 'commServer' 250 2007-08-27 20:45:46 DEBUG nodeHandler::nodeSetPath: Use prototype test:proto:sender. 251 2007-08-27 20:45:46 DEBUG nodeHandler::Prototype: : Loading prototype "test:proto:sender". 252 2007-08-27 20:45:47 DEBUG nodeHandler::AppDefinition: : Loading app definition "test:app:otg". 253 2007-08-27 20:45:52 DEBUG nodeHandler::node::n_6_18: Add application otg:test:app:otg to n_6_18 254 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 255 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 256 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 257 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'mode=' => 'net/w0' : (?-mix:net\/[ew][01]) 258 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 259 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/mode#Master 260 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: n_6_18 is not up yet. 261 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: Deferred message: CONFIGURE sender net/w0/mode Master 262 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 263 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 264 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 265 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'type=' => 'net/w0' : (?-mix:net\/[ew][01]) 266 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 267 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/type#g 268 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: n_6_18 is not up yet. 269 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: Deferred message: CONFIGURE sender net/w0/type g 270 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 271 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 272 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 273 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'essid=' => 'net/w0' : (?-mix:net\/[ew][01]) 274 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 275 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/essid#npc123 276 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: n_6_18 is not up yet. 277 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: Deferred message: CONFIGURE sender net/w0/essid npc123 278 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 279 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 280 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 281 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'ip=' => 'net/w0' : (?-mix:net\/[ew][01]) 282 2007-08-27 20:45:52 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 283 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/ip#192.168.0.1 284 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: n_6_18 is not up yet. 285 2007-08-27 20:45:52 DEBUG nodeHandler::set::sender: Deferred message: CONFIGURE sender net/w0/ip 192.168.0.1 286 2007-08-27 20:45:54 DEBUG nodeHandler::every: *: fires 287 2007-08-27 20:45:54 DEBUG nodeHandler::set::_ALL_: Running each for sender 288 2007-08-27 20:45:54 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/1/1 - (still down: n_6_18) 289 2007-08-27 20:46:04 DEBUG nodeHandler::every: *: fires 290 2007-08-27 20:46:04 DEBUG nodeHandler::set::_ALL_: Running each for sender 291 2007-08-27 20:46:04 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/1/1 - (still down: n_6_18) 292 2007-08-27 20:46:14 DEBUG nodeHandler::every: *: fires 293 2007-08-27 20:46:14 DEBUG nodeHandler::set::_ALL_: Running each for sender 294 2007-08-27 20:46:14 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/1/1 - (still down: n_6_18) 295 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_4_6: Added to group 'receivers' 296 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_4_6 receivers' to app 'commServer' 297 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_7_8: Added to group 'receivers' 298 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_7_8 receivers' to app 'commServer' 299 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_18_10: Added to group 'receivers' 300 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_18_10 receivers' to app 'commServer' 301 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_10_20: Added to group 'receivers' 302 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_10_20 receivers' to app 'commServer' 303 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_10_5: Added to group 'receivers' 304 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_10_5 receivers' to app 'commServer' 305 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_9_17: Added to group 'receivers' 306 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_9_17 receivers' to app 'commServer' 307 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_20_11: Added to group 'receivers' 308 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_20_11 receivers' to app 'commServer' 309 2007-08-27 20:46:22 DEBUG nodeHandler::node::n_15_2: Added to group 'receivers' 310 2007-08-27 20:46:22 DEBUG nodeHandler::ExecApp: writing 'A n_15_2 receivers' to app 'commServer' 311 2007-08-27 20:46:22 DEBUG nodeHandler::nodeSetPath: Use prototype test:proto:receiver. 312 2007-08-27 20:46:22 DEBUG nodeHandler::Prototype: : Loading prototype "test:proto:receiver". 313 2007-08-27 20:46:22 DEBUG nodeHandler::AppDefinition: : Loading app definition "test:app:otr". 314 2007-08-27 20:46:27 DEBUG nodeHandler::every: *: fires 315 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_4_6: Add application otr:test:app:otr to n_4_6 316 2007-08-27 20:46:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 317 2007-08-27 20:46:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 318 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_7_8: Add application otr:test:app:otr to n_7_8 319 2007-08-27 20:46:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 320 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_18_10: Add application otr:test:app:otr to n_18_10 321 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_10_20: Add application otr:test:app:otr to n_10_20 322 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_10_5: Add application otr:test:app:otr to n_10_5 323 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_9_17: Add application otr:test:app:otr to n_9_17 324 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_20_11: Add application otr:test:app:otr to n_20_11 325 2007-08-27 20:46:27 DEBUG nodeHandler::node::n_15_2: Add application otr:test:app:otr to n_15_2 326 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 327 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 328 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 329 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'mode=' => 'net/w0' : (?-mix:net\/[ew][01]) 330 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 331 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/mode#Managed 332 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 333 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: Deferred message: CONFIGURE receivers net/w0/mode Managed 334 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 335 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 336 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 337 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'type=' => 'net/w0' : (?-mix:net\/[ew][01]) 338 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 339 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/type#g 340 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 341 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: Deferred message: CONFIGURE receivers net/w0/type g 342 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 343 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 344 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 345 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'essid=' => 'net/w0' : (?-mix:net\/[ew][01]) 346 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 347 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/essid#npc123 348 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 349 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: Deferred message: CONFIGURE receivers net/w0/essid npc123 350 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Testing 'net' => '' : (?-mix:) 351 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath: Checking against '(?-mix:)' 352 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net: Testing 'w0' => 'net' : 353 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Testing 'ip=' => 'net/w0' : (?-mix:net\/[ew][01]) 354 2007-08-27 20:46:27 DEBUG nodeHandler::nodeSetPath::net/w0: Checking against '(?-mix:net\/[ew][01])' 355 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/ip#%192.168.%x.%y 356 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 357 2007-08-27 20:46:27 DEBUG nodeHandler::set::receivers: Deferred message: CONFIGURE receivers net/w0/ip %192.168.%x.%y 358 2007-08-27 20:46:37 DEBUG nodeHandler::every: *: fires 359 2007-08-27 20:46:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 360 2007-08-27 20:46:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 361 2007-08-27 20:46:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 362 2007-08-27 20:46:47 DEBUG nodeHandler::every: *: fires 363 2007-08-27 20:46:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 364 2007-08-27 20:46:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 365 2007-08-27 20:46:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 366 2007-08-27 20:46:57 DEBUG nodeHandler::every: *: fires 367 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 368 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 369 2007-08-27 20:46:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 370 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 371 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: Running 'eachGroup' in RootGroupNodeSet 372 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: Call sender 373 2007-08-27 20:46:57 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/nodeSetOn?nodes=[[6,18]] 374 2007-08-27 20:46:57 DEBUG nodeHandler::service call: http://cmc:5012/cmc/nodeSetOn?nodes=[[6,18]] 375 2007-08-27 20:46:57 DEBUG nodeHandler::set::_ALL_: Call receivers 376 2007-08-27 20:46:57 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/nodeSetOn?nodes=[[4,6],[7,8],[9,17],[10,5],[10,20],[15,2],[18,10],[20,11]] 377 2007-08-27 20:46:57 DEBUG nodeHandler::service call: http://cmc:5012/cmc/nodeSetOn?nodes=[[4,6],[7,8],[9,17],[10,5],[10,20],[15,2],[18,10],[20,11]] 378 2007-08-27 20:47:02 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 379 2007-08-27 20:47:07 DEBUG nodeHandler::every: *: fires 380 2007-08-27 20:47:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 381 2007-08-27 20:47:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 382 2007-08-27 20:47:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 383 2007-08-27 20:47:07 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 384 2007-08-27 20:47:12 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 385 2007-08-27 20:47:17 DEBUG nodeHandler::every: *: fires 386 2007-08-27 20:47:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 387 2007-08-27 20:47:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 388 2007-08-27 20:47:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 389 2007-08-27 20:47:17 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 390 2007-08-27 20:47:22 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 391 2007-08-27 20:47:27 DEBUG nodeHandler::every: *: fires 392 2007-08-27 20:47:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 393 2007-08-27 20:47:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 394 2007-08-27 20:47:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 395 2007-08-27 20:47:27 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 396 2007-08-27 20:47:32 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 397 2007-08-27 20:47:37 DEBUG nodeHandler::every: *: fires 398 2007-08-27 20:47:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 399 2007-08-27 20:47:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 400 2007-08-27 20:47:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 401 2007-08-27 20:47:37 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 402 2007-08-27 20:47:42 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 403 2007-08-27 20:47:47 DEBUG nodeHandler::every: *: fires 404 2007-08-27 20:47:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 405 2007-08-27 20:47:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 406 2007-08-27 20:47:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 407 2007-08-27 20:47:47 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 408 2007-08-27 20:47:52 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 409 2007-08-27 20:47:57 DEBUG nodeHandler::every: *: fires 410 2007-08-27 20:47:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 411 2007-08-27 20:47:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 412 2007-08-27 20:47:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 413 2007-08-27 20:47:57 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 414 2007-08-27 20:48:02 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 415 2007-08-27 20:48:07 DEBUG nodeHandler::every: *: fires 416 2007-08-27 20:48:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 417 2007-08-27 20:48:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 418 2007-08-27 20:48:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 419 2007-08-27 20:48:07 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 420 2007-08-27 20:48:12 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 421 2007-08-27 20:48:17 DEBUG nodeHandler::every: *: fires 422 2007-08-27 20:48:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 423 2007-08-27 20:48:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 424 2007-08-27 20:48:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 425 2007-08-27 20:48:17 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 426 2007-08-27 20:48:22 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 427 2007-08-27 20:48:27 DEBUG nodeHandler::every: *: fires 428 2007-08-27 20:48:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 429 2007-08-27 20:48:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 430 2007-08-27 20:48:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 0/9/9 - (still down: n_6_18,n_4_6,n_7_8) 431 2007-08-27 20:48:27 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 432 2007-08-27 20:48:32 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 433 2007-08-27 20:48:36 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_15_2 ENROLLED' 434 2007-08-27 20:48:36 DEBUG nodeHandler::Communicator: Process message 'n_15_2 ENROLLED' 435 2007-08-27 20:48:36 DEBUG nodeHandler::agentCmd::enrolled: n_15_2: n_15_2 senderId: n_15_2: 436 2007-08-27 20:48:36 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 437 2007-08-27 20:48:37 DEBUG nodeHandler::every: *: fires 438 2007-08-27 20:48:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 439 2007-08-27 20:48:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 440 2007-08-27 20:48:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 1/8/9 - (still down: n_6_18,n_4_6,n_7_8) 441 2007-08-27 20:48:37 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 442 2007-08-27 20:48:38 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_20_11 ENROLLED' 443 2007-08-27 20:48:38 DEBUG nodeHandler::Communicator: Process message 'n_20_11 ENROLLED' 444 2007-08-27 20:48:38 DEBUG nodeHandler::agentCmd::enrolled: n_20_11: n_20_11 senderId: n_20_11: 445 2007-08-27 20:48:38 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 446 2007-08-27 20:48:40 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_18_10 ENROLLED' 447 2007-08-27 20:48:40 DEBUG nodeHandler::Communicator: Process message 'n_18_10 ENROLLED' 448 2007-08-27 20:48:40 DEBUG nodeHandler::agentCmd::enrolled: n_18_10: n_18_10 senderId: n_18_10: 449 2007-08-27 20:48:40 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 450 2007-08-27 20:48:41 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_7_8 ENROLLED' 451 2007-08-27 20:48:41 DEBUG nodeHandler::Communicator: Process message 'n_7_8 ENROLLED' 452 2007-08-27 20:48:41 DEBUG nodeHandler::agentCmd::enrolled: n_7_8: n_7_8 senderId: n_7_8: 453 2007-08-27 20:48:41 DEBUG nodeHandler::set::receivers: n_4_6 is not up yet. 454 2007-08-27 20:48:42 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_4_6 ENROLLED' 455 2007-08-27 20:48:42 DEBUG nodeHandler::Communicator: Process message 'n_4_6 ENROLLED' 456 2007-08-27 20:48:42 DEBUG nodeHandler::agentCmd::enrolled: n_4_6: n_4_6 senderId: n_4_6: 457 2007-08-27 20:48:42 DEBUG nodeHandler::set::receivers: n_10_20 is not up yet. 458 2007-08-27 20:48:42 DEBUG nodeHandler::set::_ALL_: n_6_18 is not up yet. 459 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_6_18 ENROLLED' 460 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: Process message 'n_6_18 ENROLLED' 461 2007-08-27 20:48:43 DEBUG nodeHandler::agentCmd::enrolled: n_6_18: n_6_18 senderId: n_6_18: 462 2007-08-27 20:48:43 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/mode#Master 463 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: Send message: S sender CONFIGURE net/w0/mode Master 464 2007-08-27 20:48:43 DEBUG nodeHandler::ExecApp: writing 'S sender CONFIGURE net/w0/mode Master' to app 'commServer' 465 2007-08-27 20:48:43 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/type#g 466 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: Send message: S sender CONFIGURE net/w0/type g 467 2007-08-27 20:48:43 DEBUG nodeHandler::ExecApp: writing 'S sender CONFIGURE net/w0/type g' to app 'commServer' 468 2007-08-27 20:48:43 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/essid#npc123 469 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: Send message: S sender CONFIGURE net/w0/essid npc123 470 2007-08-27 20:48:43 DEBUG nodeHandler::ExecApp: writing 'S sender CONFIGURE net/w0/essid npc123' to app 'commServer' 471 2007-08-27 20:48:43 DEBUG nodeHandler::set::sender: #send: args(2)'net/w0/ip#192.168.0.1 472 2007-08-27 20:48:43 DEBUG nodeHandler::Communicator: Send message: S sender CONFIGURE net/w0/ip 192.168.0.1 473 2007-08-27 20:48:43 DEBUG nodeHandler::ExecApp: writing 'S sender CONFIGURE net/w0/ip 192.168.0.1' to app 'commServer' 474 2007-08-27 20:48:47 DEBUG nodeHandler::every: *: fires 475 2007-08-27 20:48:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 476 2007-08-27 20:48:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 477 2007-08-27 20:48:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 478 2007-08-27 20:48:47 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 479 2007-08-27 20:48:48 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_6_18 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 480 2007-08-27 20:48:48 DEBUG nodeHandler::Communicator: Process message 'n_6_18 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 481 2007-08-27 20:48:48 DEBUG nodeHandler::agentCmd::DEV_EVENT: CELL_ID' from 'net/w0' executing on n_6_18: '00:60:B3:AC:2D:22' 482 2007-08-27 20:48:48 INFO nodeHandler::node::n_6_18: Device 'net/w0' reported 00:60:B3:AC:2D:22 483 2007-08-27 20:48:52 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 484 2007-08-27 20:48:57 DEBUG nodeHandler::every: *: fires 485 2007-08-27 20:48:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 486 2007-08-27 20:48:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 487 2007-08-27 20:48:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 488 2007-08-27 20:48:57 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 489 2007-08-27 20:49:02 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 490 2007-08-27 20:49:07 DEBUG nodeHandler::every: *: fires 491 2007-08-27 20:49:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 492 2007-08-27 20:49:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 493 2007-08-27 20:49:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 494 2007-08-27 20:49:07 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 495 2007-08-27 20:49:12 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 496 2007-08-27 20:49:17 DEBUG nodeHandler::every: *: fires 497 2007-08-27 20:49:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 498 2007-08-27 20:49:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 499 2007-08-27 20:49:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 500 2007-08-27 20:49:17 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 501 2007-08-27 20:49:22 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 502 2007-08-27 20:49:27 DEBUG nodeHandler::every: *: fires 503 2007-08-27 20:49:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 504 2007-08-27 20:49:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 505 2007-08-27 20:49:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 506 2007-08-27 20:49:27 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 507 2007-08-27 20:49:32 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 508 2007-08-27 20:49:37 DEBUG nodeHandler::every: *: fires 509 2007-08-27 20:49:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 510 2007-08-27 20:49:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 511 2007-08-27 20:49:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 512 2007-08-27 20:49:37 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 513 2007-08-27 20:49:42 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 514 2007-08-27 20:49:47 DEBUG nodeHandler::every: *: fires 515 2007-08-27 20:49:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 516 2007-08-27 20:49:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 517 2007-08-27 20:49:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 518 2007-08-27 20:49:47 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 519 2007-08-27 20:49:52 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 520 2007-08-27 20:49:57 DEBUG nodeHandler::every: *: fires 521 2007-08-27 20:49:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 522 2007-08-27 20:49:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 523 2007-08-27 20:49:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 524 2007-08-27 20:49:57 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 525 2007-08-27 20:50:02 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 526 2007-08-27 20:50:07 DEBUG nodeHandler::every: *: fires 527 2007-08-27 20:50:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 528 2007-08-27 20:50:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 529 2007-08-27 20:50:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 530 2007-08-27 20:50:07 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 531 2007-08-27 20:50:12 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 532 2007-08-27 20:50:17 DEBUG nodeHandler::every: *: fires 533 2007-08-27 20:50:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 534 2007-08-27 20:50:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 535 2007-08-27 20:50:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 536 2007-08-27 20:50:17 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 537 2007-08-27 20:50:22 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 538 2007-08-27 20:50:27 DEBUG nodeHandler::every: *: fires 539 2007-08-27 20:50:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 540 2007-08-27 20:50:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 541 2007-08-27 20:50:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 542 2007-08-27 20:50:27 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 543 2007-08-27 20:50:32 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 544 2007-08-27 20:50:37 DEBUG nodeHandler::every: *: fires 545 2007-08-27 20:50:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 546 2007-08-27 20:50:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 547 2007-08-27 20:50:37 INFO nodeHandler::stdlib: Resetting node n_10_20 548 2007-08-27 20:50:37 DEBUG nodeHandler::node::n_10_20: Resetting node 549 2007-08-27 20:50:37 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/on?x=10&y=20 550 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/on?x=10&y=20 551 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/reset?x=10&y=20 552 2007-08-27 20:50:37 INFO nodeHandler::stdlib: Resetting node n_10_5 553 2007-08-27 20:50:37 DEBUG nodeHandler::node::n_10_5: Resetting node 554 2007-08-27 20:50:37 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/on?x=10&y=5 555 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/on?x=10&y=5 556 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/reset?x=10&y=5 557 2007-08-27 20:50:37 INFO nodeHandler::stdlib: Resetting node n_9_17 558 2007-08-27 20:50:37 DEBUG nodeHandler::node::n_9_17: Resetting node 559 2007-08-27 20:50:37 DEBUG nodeHandler::CMC: up http://cmc:5012/cmc/on?x=9&y=17 560 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/on?x=9&y=17 561 2007-08-27 20:50:37 DEBUG nodeHandler::service call: http://cmc:5012/cmc/reset?x=9&y=17 562 2007-08-27 20:50:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 563 2007-08-27 20:50:37 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 564 2007-08-27 20:50:42 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 565 2007-08-27 20:50:47 DEBUG nodeHandler::every: *: fires 566 2007-08-27 20:50:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 567 2007-08-27 20:50:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 568 2007-08-27 20:50:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 569 2007-08-27 20:50:47 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 570 2007-08-27 20:50:52 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 571 2007-08-27 20:50:57 DEBUG nodeHandler::every: *: fires 572 2007-08-27 20:50:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 573 2007-08-27 20:50:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 574 2007-08-27 20:50:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 575 2007-08-27 20:50:57 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 576 2007-08-27 20:51:02 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 577 2007-08-27 20:51:07 DEBUG nodeHandler::every: *: fires 578 2007-08-27 20:51:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 579 2007-08-27 20:51:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 580 2007-08-27 20:51:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 581 2007-08-27 20:51:07 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 582 2007-08-27 20:51:12 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 583 2007-08-27 20:51:17 DEBUG nodeHandler::every: *: fires 584 2007-08-27 20:51:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 585 2007-08-27 20:51:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 586 2007-08-27 20:51:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 587 2007-08-27 20:51:17 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 588 2007-08-27 20:51:22 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 589 2007-08-27 20:51:27 DEBUG nodeHandler::every: *: fires 590 2007-08-27 20:51:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 591 2007-08-27 20:51:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 592 2007-08-27 20:51:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 593 2007-08-27 20:51:27 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 594 2007-08-27 20:51:32 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 595 2007-08-27 20:51:37 DEBUG nodeHandler::every: *: fires 596 2007-08-27 20:51:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 597 2007-08-27 20:51:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 598 2007-08-27 20:51:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 599 2007-08-27 20:51:37 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 600 2007-08-27 20:51:42 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 601 2007-08-27 20:51:47 DEBUG nodeHandler::every: *: fires 602 2007-08-27 20:51:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 603 2007-08-27 20:51:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 604 2007-08-27 20:51:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 605 2007-08-27 20:51:47 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 606 2007-08-27 20:51:52 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 607 2007-08-27 20:51:57 DEBUG nodeHandler::every: *: fires 608 2007-08-27 20:51:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 609 2007-08-27 20:51:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 610 2007-08-27 20:51:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 611 2007-08-27 20:51:57 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 612 2007-08-27 20:52:02 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 613 2007-08-27 20:52:07 DEBUG nodeHandler::every: *: fires 614 2007-08-27 20:52:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 615 2007-08-27 20:52:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 616 2007-08-27 20:52:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 617 2007-08-27 20:52:07 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 618 2007-08-27 20:52:12 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 619 2007-08-27 20:52:17 DEBUG nodeHandler::every: *: fires 620 2007-08-27 20:52:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 621 2007-08-27 20:52:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 622 2007-08-27 20:52:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 6/3/9 - (still down: n_10_20,n_10_5,n_9_17) 623 2007-08-27 20:52:17 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 624 2007-08-27 20:52:19 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_10_5 ENROLLED' 625 2007-08-27 20:52:19 DEBUG nodeHandler::Communicator: Process message 'n_10_5 ENROLLED' 626 2007-08-27 20:52:19 DEBUG nodeHandler::agentCmd::enrolled: n_10_5: n_10_5 senderId: n_10_5: 627 2007-08-27 20:52:19 DEBUG nodeHandler::set::receivers: n_10_20 is not up yet. 628 2007-08-27 20:52:22 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 629 2007-08-27 20:52:27 DEBUG nodeHandler::every: *: fires 630 2007-08-27 20:52:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 631 2007-08-27 20:52:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 632 2007-08-27 20:52:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 7/2/9 - (still down: n_10_20,n_9_17) 633 2007-08-27 20:52:27 DEBUG nodeHandler::set::_ALL_: n_10_20 is not up yet. 634 2007-08-27 20:52:28 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_10_20 ENROLLED' 635 2007-08-27 20:52:28 DEBUG nodeHandler::Communicator: Process message 'n_10_20 ENROLLED' 636 2007-08-27 20:52:28 DEBUG nodeHandler::agentCmd::enrolled: n_10_20: n_10_20 senderId: n_10_20: 637 2007-08-27 20:52:28 DEBUG nodeHandler::set::receivers: n_9_17 is not up yet. 638 2007-08-27 20:52:32 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 639 2007-08-27 20:52:37 DEBUG nodeHandler::every: *: fires 640 2007-08-27 20:52:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 641 2007-08-27 20:52:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 642 2007-08-27 20:52:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 643 2007-08-27 20:52:37 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 644 2007-08-27 20:52:42 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 645 2007-08-27 20:52:47 DEBUG nodeHandler::every: *: fires 646 2007-08-27 20:52:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 647 2007-08-27 20:52:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 648 2007-08-27 20:52:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 649 2007-08-27 20:52:47 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 650 2007-08-27 20:52:52 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 651 2007-08-27 20:52:57 DEBUG nodeHandler::every: *: fires 652 2007-08-27 20:52:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 653 2007-08-27 20:52:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 654 2007-08-27 20:52:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 655 2007-08-27 20:52:57 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 656 2007-08-27 20:53:02 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 657 2007-08-27 20:53:07 DEBUG nodeHandler::every: *: fires 658 2007-08-27 20:53:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 659 2007-08-27 20:53:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 660 2007-08-27 20:53:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 661 2007-08-27 20:53:07 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 662 2007-08-27 20:53:12 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 663 2007-08-27 20:53:17 DEBUG nodeHandler::every: *: fires 664 2007-08-27 20:53:17 DEBUG nodeHandler::set::_ALL_: Running each for sender 665 2007-08-27 20:53:17 DEBUG nodeHandler::set::_ALL_: Running each for receivers 666 2007-08-27 20:53:17 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 667 2007-08-27 20:53:17 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 668 2007-08-27 20:53:22 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 669 2007-08-27 20:53:27 DEBUG nodeHandler::every: *: fires 670 2007-08-27 20:53:27 DEBUG nodeHandler::set::_ALL_: Running each for sender 671 2007-08-27 20:53:27 DEBUG nodeHandler::set::_ALL_: Running each for receivers 672 2007-08-27 20:53:27 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 673 2007-08-27 20:53:27 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 674 2007-08-27 20:53:32 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 675 2007-08-27 20:53:37 DEBUG nodeHandler::every: *: fires 676 2007-08-27 20:53:37 DEBUG nodeHandler::set::_ALL_: Running each for sender 677 2007-08-27 20:53:37 DEBUG nodeHandler::set::_ALL_: Running each for receivers 678 2007-08-27 20:53:37 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 679 2007-08-27 20:53:38 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 680 2007-08-27 20:53:43 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 681 2007-08-27 20:53:47 DEBUG nodeHandler::every: *: fires 682 2007-08-27 20:53:47 DEBUG nodeHandler::set::_ALL_: Running each for sender 683 2007-08-27 20:53:47 DEBUG nodeHandler::set::_ALL_: Running each for receivers 684 2007-08-27 20:53:47 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 685 2007-08-27 20:53:48 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 686 2007-08-27 20:53:53 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 687 2007-08-27 20:53:57 DEBUG nodeHandler::every: *: fires 688 2007-08-27 20:53:57 DEBUG nodeHandler::set::_ALL_: Running each for sender 689 2007-08-27 20:53:57 DEBUG nodeHandler::set::_ALL_: Running each for receivers 690 2007-08-27 20:53:57 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 691 2007-08-27 20:53:58 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 692 2007-08-27 20:54:03 DEBUG nodeHandler::set::_ALL_: n_9_17 is not up yet. 693 2007-08-27 20:54:07 DEBUG nodeHandler::every: *: fires 694 2007-08-27 20:54:07 DEBUG nodeHandler::set::_ALL_: Running each for sender 695 2007-08-27 20:54:07 DEBUG nodeHandler::set::_ALL_: Running each for receivers 696 2007-08-27 20:54:07 WARN nodeHandler::stdlib: Giving up on node n_9_17 697 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 'X n_9_17' to app 'commServer' 698 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 's n_9_17 RESET' to app 'commServer' 699 2007-08-27 20:54:07 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/mode#Managed 700 2007-08-27 20:54:07 DEBUG nodeHandler::Communicator: Send message: S receivers CONFIGURE net/w0/mode Managed 701 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 'S receivers CONFIGURE net/w0/mode Managed' to app 'commServer' 702 2007-08-27 20:54:07 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/type#g 703 2007-08-27 20:54:07 DEBUG nodeHandler::Communicator: Send message: S receivers CONFIGURE net/w0/type g 704 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 'S receivers CONFIGURE net/w0/type g' to app 'commServer' 705 2007-08-27 20:54:07 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/essid#npc123 706 2007-08-27 20:54:07 DEBUG nodeHandler::Communicator: Send message: S receivers CONFIGURE net/w0/essid npc123 707 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 'S receivers CONFIGURE net/w0/essid npc123' to app 'commServer' 708 2007-08-27 20:54:07 DEBUG nodeHandler::set::receivers: #send: args(2)'net/w0/ip#%192.168.%x.%y 709 2007-08-27 20:54:07 DEBUG nodeHandler::Communicator: Send message: S receivers CONFIGURE net/w0/ip %192.168.%x.%y 710 2007-08-27 20:54:07 DEBUG nodeHandler::ExecApp: writing 'S receivers CONFIGURE net/w0/ip %192.168.%x.%y' to app 'commServer' 711 2007-08-27 20:54:07 INFO nodeHandler::stdlib: Waiting for nodes (Up/Down/Total): 8/1/9 - (still down: n_9_17) 712 2007-08-27 20:54:08 INFO nodeHandler::whenAll: *: 'apps/app/status[@value='INSTALLED.OK']' fires 713 2007-08-27 20:54:11 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_15_2 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 714 2007-08-27 20:54:11 DEBUG nodeHandler::Communicator: Process message 'n_15_2 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 715 2007-08-27 20:54:11 DEBUG nodeHandler::agentCmd::DEV_EVENT: CELL_ID' from 'net/w0' executing on n_15_2: '00:60:B3:AC:2D:22' 716 2007-08-27 20:54:11 INFO nodeHandler::node::n_15_2: Device 'net/w0' reported 00:60:B3:AC:2D:22 717 2007-08-27 20:54:13 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_10_20 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 718 2007-08-27 20:54:13 DEBUG nodeHandler::Communicator: Process message 'n_10_20 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 719 2007-08-27 20:54:13 DEBUG nodeHandler::agentCmd::DEV_EVENT: CELL_ID' from 'net/w0' executing on n_10_20: '00:60:B3:AC:2D:22' 720 2007-08-27 20:54:13 INFO nodeHandler::node::n_10_20: Device 'net/w0' reported 00:60:B3:AC:2D:22 721 2007-08-27 20:54:13 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_20_11 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 722 2007-08-27 20:54:13 DEBUG nodeHandler::Communicator: Process message 'n_20_11 DEV_EVENT CELL_ID net/w0 00:60:B3:AC:2D:22' 723 2007-08-27 20:54:13 DEBUG nodeHandler::agentCmd::DEV_EVENT: CELL_ID' from 'net/w0' executing on n_20_11: '00:60:B3:AC:2D:22' 724 2007-08-27 20:54:13 INFO nodeHandler::node::n_20_11: Device 'net/w0' reported 00:60:B3:AC:2D:22 725 2007-08-27 20:54:14 DEBUG nodeHandler::Communicator: commServer(STDOUT): 'n_10_5 DEV_EVENT CE