3 p Testing a simple master/slave example application
5 $ $SG_TEST_EXENV masterslave/masterslave_forwarder$EXEEXT ${srcdir:=.}/small_platform.xml ${srcdir:=.}/masterslave/deployment_masterslave.xml --cfg=cpu_model:Cas01_IM
6 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'cpu_model' to 'Cas01_IM'
7 > [0.000000] [surf_config/INFO] Switching workstation model to compound since you changed the network and/or cpu model(s)
8 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Got 5 slaves and 20 tasks to process
9 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Sending "Task_0" to "Jupiter"
10 > [Tremblay:master:(1) 0.165962] [msg_test/INFO] Sent
11 > [Tremblay:master:(1) 0.165962] [msg_test/INFO] Sending "Task_1" to "Fafard"
12 > [Jupiter:slave:(3) 0.165962] [msg_test/INFO] Received "Task_0"
13 > [Jupiter:slave:(3) 0.165962] [msg_test/INFO] Processing "Task_0"
14 > [Fafard:slave:(4) 0.384115] [msg_test/INFO] Received "Task_1"
15 > [Fafard:slave:(4) 0.384115] [msg_test/INFO] Processing "Task_1"
16 > [Tremblay:master:(1) 0.384115] [msg_test/INFO] Sent
17 > [Tremblay:master:(1) 0.384115] [msg_test/INFO] Sending "Task_2" to "Ginette"
18 > [Ginette:slave:(5) 0.524575] [msg_test/INFO] Received "Task_2"
19 > [Ginette:slave:(5) 0.524575] [msg_test/INFO] Processing "Task_2"
20 > [Tremblay:master:(1) 0.524575] [msg_test/INFO] Sent
21 > [Tremblay:master:(1) 0.524575] [msg_test/INFO] Sending "Task_3" to "Bourassa"
22 > [Bourassa:slave:(6) 0.740447] [msg_test/INFO] Received "Task_3"
23 > [Bourassa:slave:(6) 0.740447] [msg_test/INFO] Processing "Task_3"
24 > [Tremblay:master:(1) 0.740447] [msg_test/INFO] Sent
25 > [Tremblay:master:(1) 0.740447] [msg_test/INFO] Sending "Task_4" to "Tremblay"
26 > [Tremblay:master:(1) 0.740447] [msg_test/INFO] Hey ! It's me ! :)
27 > [Tremblay:slave:(2) 0.742786] [msg_test/INFO] Received "Task_4"
28 > [Tremblay:slave:(2) 0.742786] [msg_test/INFO] Processing "Task_4"
29 > [Tremblay:master:(1) 0.742786] [msg_test/INFO] Sent
30 > [Tremblay:master:(1) 0.742786] [msg_test/INFO] Sending "Task_5" to "Jupiter"
31 > [Jupiter:slave:(3) 0.821304] [msg_test/INFO] "Task_0" done
32 > [Tremblay:master:(1) 0.987266] [msg_test/INFO] Sent
33 > [Tremblay:master:(1) 0.987266] [msg_test/INFO] Sending "Task_6" to "Fafard"
34 > [Jupiter:slave:(3) 0.987266] [msg_test/INFO] Received "Task_5"
35 > [Jupiter:slave:(3) 0.987266] [msg_test/INFO] Processing "Task_5"
36 > [Fafard:slave:(4) 1.039457] [msg_test/INFO] "Task_1" done
37 > [Tremblay:slave:(2) 1.252495] [msg_test/INFO] "Task_4" done
38 > [Tremblay:master:(1) 1.257610] [msg_test/INFO] Sent
39 > [Tremblay:master:(1) 1.257610] [msg_test/INFO] Sending "Task_7" to "Ginette"
40 > [Fafard:slave:(4) 1.257610] [msg_test/INFO] Received "Task_6"
41 > [Fafard:slave:(4) 1.257610] [msg_test/INFO] Processing "Task_6"
42 > [Ginette:slave:(5) 1.555672] [msg_test/INFO] "Task_2" done
43 > [Jupiter:slave:(3) 1.642608] [msg_test/INFO] "Task_5" done
44 > [Tremblay:master:(1) 1.696132] [msg_test/INFO] Sent
45 > [Tremblay:master:(1) 1.696132] [msg_test/INFO] Sending "Task_8" to "Bourassa"
46 > [Ginette:slave:(5) 1.696132] [msg_test/INFO] Received "Task_7"
47 > [Ginette:slave:(5) 1.696132] [msg_test/INFO] Processing "Task_7"
48 > [Bourassa:slave:(6) 1.771545] [msg_test/INFO] "Task_3" done
49 > [Fafard:slave:(4) 1.912953] [msg_test/INFO] "Task_6" done
50 > [Tremblay:master:(1) 1.987417] [msg_test/INFO] Sent
51 > [Tremblay:master:(1) 1.987417] [msg_test/INFO] Sending "Task_9" to "Tremblay"
52 > [Tremblay:master:(1) 1.987417] [msg_test/INFO] Hey ! It's me ! :)
53 > [Bourassa:slave:(6) 1.987417] [msg_test/INFO] Received "Task_8"
54 > [Bourassa:slave:(6) 1.987417] [msg_test/INFO] Processing "Task_8"
55 > [Tremblay:slave:(2) 1.989756] [msg_test/INFO] Received "Task_9"
56 > [Tremblay:slave:(2) 1.989756] [msg_test/INFO] Processing "Task_9"
57 > [Tremblay:master:(1) 1.989756] [msg_test/INFO] Sent
58 > [Tremblay:master:(1) 1.989756] [msg_test/INFO] Sending "Task_10" to "Jupiter"
59 > [Jupiter:slave:(3) 2.155718] [msg_test/INFO] Received "Task_10"
60 > [Jupiter:slave:(3) 2.155718] [msg_test/INFO] Processing "Task_10"
61 > [Tremblay:master:(1) 2.155718] [msg_test/INFO] Sent
62 > [Tremblay:master:(1) 2.155718] [msg_test/INFO] Sending "Task_11" to "Fafard"
63 > [Fafard:slave:(4) 2.373871] [msg_test/INFO] Received "Task_11"
64 > [Fafard:slave:(4) 2.373871] [msg_test/INFO] Processing "Task_11"
65 > [Tremblay:master:(1) 2.373871] [msg_test/INFO] Sent
66 > [Tremblay:master:(1) 2.373871] [msg_test/INFO] Sending "Task_12" to "Ginette"
67 > [Tremblay:slave:(2) 2.499466] [msg_test/INFO] "Task_9" done
68 > [Ginette:slave:(5) 2.727230] [msg_test/INFO] "Task_7" done
69 > [Jupiter:slave:(3) 2.811060] [msg_test/INFO] "Task_10" done
70 > [Tremblay:master:(1) 2.867690] [msg_test/INFO] Sent
71 > [Tremblay:master:(1) 2.867690] [msg_test/INFO] Sending "Task_13" to "Bourassa"
72 > [Ginette:slave:(5) 2.867690] [msg_test/INFO] Received "Task_12"
73 > [Ginette:slave:(5) 2.867690] [msg_test/INFO] Processing "Task_12"
74 > [Bourassa:slave:(6) 3.018515] [msg_test/INFO] "Task_8" done
75 > [Fafard:slave:(4) 3.029213] [msg_test/INFO] "Task_11" done
76 > [Tremblay:master:(1) 3.234387] [msg_test/INFO] Sent
77 > [Tremblay:master:(1) 3.234387] [msg_test/INFO] Sending "Task_14" to "Tremblay"
78 > [Tremblay:master:(1) 3.234387] [msg_test/INFO] Hey ! It's me ! :)
79 > [Bourassa:slave:(6) 3.234387] [msg_test/INFO] Received "Task_13"
80 > [Bourassa:slave:(6) 3.234387] [msg_test/INFO] Processing "Task_13"
81 > [Tremblay:slave:(2) 3.236726] [msg_test/INFO] Received "Task_14"
82 > [Tremblay:slave:(2) 3.236726] [msg_test/INFO] Processing "Task_14"
83 > [Tremblay:master:(1) 3.236726] [msg_test/INFO] Sent
84 > [Tremblay:master:(1) 3.236726] [msg_test/INFO] Sending "Task_15" to "Jupiter"
85 > [Jupiter:slave:(3) 3.402688] [msg_test/INFO] Received "Task_15"
86 > [Jupiter:slave:(3) 3.402688] [msg_test/INFO] Processing "Task_15"
87 > [Tremblay:master:(1) 3.402688] [msg_test/INFO] Sent
88 > [Tremblay:master:(1) 3.402688] [msg_test/INFO] Sending "Task_16" to "Fafard"
89 > [Fafard:slave:(4) 3.620841] [msg_test/INFO] Received "Task_16"
90 > [Fafard:slave:(4) 3.620841] [msg_test/INFO] Processing "Task_16"
91 > [Tremblay:master:(1) 3.620841] [msg_test/INFO] Sent
92 > [Tremblay:master:(1) 3.620841] [msg_test/INFO] Sending "Task_17" to "Ginette"
93 > [Tremblay:slave:(2) 3.746436] [msg_test/INFO] "Task_14" done
94 > [Ginette:slave:(5) 3.898788] [msg_test/INFO] "Task_12" done
95 > [Tremblay:master:(1) 4.039247] [msg_test/INFO] Sent
96 > [Tremblay:master:(1) 4.039247] [msg_test/INFO] Sending "Task_18" to "Bourassa"
97 > [Ginette:slave:(5) 4.039247] [msg_test/INFO] Received "Task_17"
98 > [Ginette:slave:(5) 4.039247] [msg_test/INFO] Processing "Task_17"
99 > [Jupiter:slave:(3) 4.058030] [msg_test/INFO] "Task_15" done
100 > [Bourassa:slave:(6) 4.265485] [msg_test/INFO] "Task_13" done
101 > [Fafard:slave:(4) 4.276183] [msg_test/INFO] "Task_16" done
102 > [Tremblay:master:(1) 4.481357] [msg_test/INFO] Sent
103 > [Tremblay:master:(1) 4.481357] [msg_test/INFO] Sending "Task_19" to "Tremblay"
104 > [Tremblay:master:(1) 4.481357] [msg_test/INFO] Hey ! It's me ! :)
105 > [Bourassa:slave:(6) 4.481357] [msg_test/INFO] Received "Task_18"
106 > [Bourassa:slave:(6) 4.481357] [msg_test/INFO] Processing "Task_18"
107 > [Tremblay:slave:(2) 4.483696] [msg_test/INFO] Received "Task_19"
108 > [Tremblay:slave:(2) 4.483696] [msg_test/INFO] Processing "Task_19"
109 > [Tremblay:master:(1) 4.483696] [msg_test/INFO] Sent
110 > [Tremblay:master:(1) 4.483696] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
111 > [Jupiter:slave:(3) 4.498896] [msg_test/INFO] Received "finalize"
112 > [Jupiter:slave:(3) 4.498896] [msg_test/INFO] I'm done. See you!
113 > [Fafard:slave:(4) 4.519447] [msg_test/INFO] Received "finalize"
114 > [Fafard:slave:(4) 4.519447] [msg_test/INFO] I'm done. See you!
115 > [Tremblay:slave:(2) 4.993406] [msg_test/INFO] "Task_19" done
116 > [Ginette:slave:(5) 5.070345] [msg_test/INFO] "Task_17" done
117 > [Ginette:slave:(5) 5.083577] [msg_test/INFO] Received "finalize"
118 > [Ginette:slave:(5) 5.083577] [msg_test/INFO] I'm done. See you!
119 > [Bourassa:slave:(6) 5.512455] [msg_test/INFO] "Task_18" done
120 > [Bourassa:slave:(6) 5.532791] [msg_test/INFO] Received "finalize"
121 > [Bourassa:slave:(6) 5.532791] [msg_test/INFO] I'm done. See you!
122 > [Tremblay:slave:(2) 5.532947] [msg_test/INFO] Received "finalize"
123 > [Tremblay:slave:(2) 5.532947] [msg_test/INFO] I'm done. See you!
124 > [Tremblay:master:(1) 5.532947] [msg_test/INFO] Goodbye now!
125 > [5.532947] [msg_test/INFO] Simulation time 5.53295
127 p Testing a master/slave example application with a forwarder module
129 $ $SG_TEST_EXENV masterslave/masterslave_forwarder$EXEEXT ${srcdir:=.}/msg_platform.xml ${srcdir:=.}/masterslave/deployment_masterslave_forwarder.xml --cfg=cpu_model:Cas01_IM
130 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'cpu_model' to 'Cas01_IM'
131 > [0.000000] [surf_config/INFO] Switching workstation model to compound since you changed the network and/or cpu model(s)
132 > [Jacquelin:master:(1) 0.000000] [msg_test/INFO] Got 5 slaves and 20 tasks to process
133 > [Jacquelin:master:(1) 0.000000] [msg_test/INFO] Sending "Task_0" to "iRMX"
134 > [Jacquelin:master:(1) 4.772530] [msg_test/INFO] Sent
135 > [Jacquelin:master:(1) 4.772530] [msg_test/INFO] Sending "Task_1" to "Casavant"
136 > [iRMX:slave:(4) 4.772530] [msg_test/INFO] Received "Task_0"
137 > [iRMX:slave:(4) 4.772530] [msg_test/INFO] Processing "Task_0"
138 > [iRMX:slave:(4) 4.845345] [msg_test/INFO] "Task_0" done
139 > [Casavant:forwarder:(3) 6.900351] [msg_test/INFO] Received "Task_1"
140 > [Casavant:forwarder:(3) 6.900351] [msg_test/INFO] Sending "Task_1" to "Robert"
141 > [Jacquelin:master:(1) 6.900351] [msg_test/INFO] Sent
142 > [Jacquelin:master:(1) 6.900351] [msg_test/INFO] Sending "Task_2" to "Bousquet"
143 > [Bousquet:slave:(5) 8.491392] [msg_test/INFO] Received "Task_2"
144 > [Bousquet:slave:(5) 8.491392] [msg_test/INFO] Processing "Task_2"
145 > [Jacquelin:master:(1) 8.491392] [msg_test/INFO] Sent
146 > [Jacquelin:master:(1) 8.491392] [msg_test/INFO] Sending "Task_3" to "Soucy"
147 > [Bousquet:slave:(5) 8.607896] [msg_test/INFO] "Task_2" done
148 > [Robert:slave:(10) 9.260549] [msg_test/INFO] Received "Task_1"
149 > [Robert:slave:(10) 9.260549] [msg_test/INFO] Processing "Task_1"
150 > [Robert:slave:(10) 9.296956] [msg_test/INFO] "Task_1" done
151 > [Soucy:slave:(6) 10.755127] [msg_test/INFO] Received "Task_3"
152 > [Soucy:slave:(6) 10.755127] [msg_test/INFO] Processing "Task_3"
153 > [Jacquelin:master:(1) 10.755127] [msg_test/INFO] Sent
154 > [Jacquelin:master:(1) 10.755127] [msg_test/INFO] Sending "Task_4" to "Jackson"
155 > [Soucy:slave:(6) 10.791535] [msg_test/INFO] "Task_3" done
156 > [Jackson:forwarder:(2) 12.090242] [msg_test/INFO] Received "Task_4"
157 > [Jackson:forwarder:(2) 12.090242] [msg_test/INFO] Sending "Task_4" to "Kuenning"
158 > [Jacquelin:master:(1) 12.090242] [msg_test/INFO] Sent
159 > [Jacquelin:master:(1) 12.090242] [msg_test/INFO] Sending "Task_5" to "iRMX"
160 > [Kuenning:slave:(7) 12.173866] [msg_test/INFO] Received "Task_4"
161 > [Kuenning:slave:(7) 12.173866] [msg_test/INFO] Processing "Task_4"
162 > [Kuenning:slave:(7) 12.232118] [msg_test/INFO] "Task_4" done
163 > [iRMX:slave:(4) 16.862772] [msg_test/INFO] Received "Task_5"
164 > [iRMX:slave:(4) 16.862772] [msg_test/INFO] Processing "Task_5"
165 > [Jacquelin:master:(1) 16.862772] [msg_test/INFO] Sent
166 > [Jacquelin:master:(1) 16.862772] [msg_test/INFO] Sending "Task_6" to "Casavant"
167 > [iRMX:slave:(4) 16.935587] [msg_test/INFO] "Task_5" done
168 > [Casavant:forwarder:(3) 18.990593] [msg_test/INFO] Received "Task_6"
169 > [Casavant:forwarder:(3) 18.990593] [msg_test/INFO] Sending "Task_6" to "Sirois"
170 > [Jacquelin:master:(1) 18.990593] [msg_test/INFO] Sent
171 > [Jacquelin:master:(1) 18.990593] [msg_test/INFO] Sending "Task_7" to "Bousquet"
172 > [Sirois:slave:(11) 19.793293] [msg_test/INFO] Received "Task_6"
173 > [Sirois:slave:(11) 19.793293] [msg_test/INFO] Processing "Task_6"
174 > [Sirois:slave:(11) 19.836983] [msg_test/INFO] "Task_6" done
175 > [Bousquet:slave:(5) 20.581634] [msg_test/INFO] Received "Task_7"
176 > [Bousquet:slave:(5) 20.581634] [msg_test/INFO] Processing "Task_7"
177 > [Jacquelin:master:(1) 20.581634] [msg_test/INFO] Sent
178 > [Jacquelin:master:(1) 20.581634] [msg_test/INFO] Sending "Task_8" to "Soucy"
179 > [Bousquet:slave:(5) 20.698138] [msg_test/INFO] "Task_7" done
180 > [Soucy:slave:(6) 22.845369] [msg_test/INFO] Received "Task_8"
181 > [Soucy:slave:(6) 22.845369] [msg_test/INFO] Processing "Task_8"
182 > [Jacquelin:master:(1) 22.845369] [msg_test/INFO] Sent
183 > [Jacquelin:master:(1) 22.845369] [msg_test/INFO] Sending "Task_9" to "Jackson"
184 > [Soucy:slave:(6) 22.881777] [msg_test/INFO] "Task_8" done
185 > [Jackson:forwarder:(2) 24.180485] [msg_test/INFO] Received "Task_9"
186 > [Jackson:forwarder:(2) 24.180485] [msg_test/INFO] Sending "Task_9" to "Browne"
187 > [Jacquelin:master:(1) 24.180485] [msg_test/INFO] Sent
188 > [Jacquelin:master:(1) 24.180485] [msg_test/INFO] Sending "Task_10" to "iRMX"
189 > [Browne:slave:(8) 27.009931] [msg_test/INFO] Received "Task_9"
190 > [Browne:slave:(8) 27.009931] [msg_test/INFO] Processing "Task_9"
191 > [Browne:slave:(8) 27.046339] [msg_test/INFO] "Task_9" done
192 > [iRMX:slave:(4) 28.953014] [msg_test/INFO] Received "Task_10"
193 > [iRMX:slave:(4) 28.953014] [msg_test/INFO] Processing "Task_10"
194 > [Jacquelin:master:(1) 28.953014] [msg_test/INFO] Sent
195 > [Jacquelin:master:(1) 28.953014] [msg_test/INFO] Sending "Task_11" to "Casavant"
196 > [iRMX:slave:(4) 29.025830] [msg_test/INFO] "Task_10" done
197 > [Casavant:forwarder:(3) 31.080835] [msg_test/INFO] Received "Task_11"
198 > [Casavant:forwarder:(3) 31.080835] [msg_test/INFO] Sending "Task_11" to "Monique"
199 > [Jacquelin:master:(1) 31.080835] [msg_test/INFO] Sent
200 > [Jacquelin:master:(1) 31.080835] [msg_test/INFO] Sending "Task_12" to "Bousquet"
201 > [Monique:slave:(12) 32.472434] [msg_test/INFO] Received "Task_11"
202 > [Monique:slave:(12) 32.472434] [msg_test/INFO] Processing "Task_11"
203 > [Monique:slave:(12) 32.516124] [msg_test/INFO] "Task_11" done
204 > [Bousquet:slave:(5) 32.671876] [msg_test/INFO] Received "Task_12"
205 > [Bousquet:slave:(5) 32.671876] [msg_test/INFO] Processing "Task_12"
206 > [Jacquelin:master:(1) 32.671876] [msg_test/INFO] Sent
207 > [Jacquelin:master:(1) 32.671876] [msg_test/INFO] Sending "Task_13" to "Soucy"
208 > [Bousquet:slave:(5) 32.788380] [msg_test/INFO] "Task_12" done
209 > [Soucy:slave:(6) 34.935611] [msg_test/INFO] Received "Task_13"
210 > [Soucy:slave:(6) 34.935611] [msg_test/INFO] Processing "Task_13"
211 > [Jacquelin:master:(1) 34.935611] [msg_test/INFO] Sent
212 > [Jacquelin:master:(1) 34.935611] [msg_test/INFO] Sending "Task_14" to "Jackson"
213 > [Soucy:slave:(6) 34.972019] [msg_test/INFO] "Task_13" done
214 > [Jackson:forwarder:(2) 36.270727] [msg_test/INFO] Received "Task_14"
215 > [Jackson:forwarder:(2) 36.270727] [msg_test/INFO] Sending "Task_14" to "Stephen"
216 > [Jacquelin:master:(1) 36.270727] [msg_test/INFO] Sent
217 > [Jacquelin:master:(1) 36.270727] [msg_test/INFO] Sending "Task_15" to "iRMX"
218 > [Stephen:slave:(9) 40.508273] [msg_test/INFO] Received "Task_14"
219 > [Stephen:slave:(9) 40.508273] [msg_test/INFO] Processing "Task_14"
220 > [Stephen:slave:(9) 40.581088] [msg_test/INFO] "Task_14" done
221 > [iRMX:slave:(4) 41.043257] [msg_test/INFO] Received "Task_15"
222 > [iRMX:slave:(4) 41.043257] [msg_test/INFO] Processing "Task_15"
223 > [Jacquelin:master:(1) 41.043257] [msg_test/INFO] Sent
224 > [Jacquelin:master:(1) 41.043257] [msg_test/INFO] Sending "Task_16" to "Casavant"
225 > [iRMX:slave:(4) 41.116072] [msg_test/INFO] "Task_15" done
226 > [Casavant:forwarder:(3) 43.171078] [msg_test/INFO] Received "Task_16"
227 > [Casavant:forwarder:(3) 43.171078] [msg_test/INFO] Sending "Task_16" to "Robert"
228 > [Jacquelin:master:(1) 43.171078] [msg_test/INFO] Sent
229 > [Jacquelin:master:(1) 43.171078] [msg_test/INFO] Sending "Task_17" to "Bousquet"
230 > [Bousquet:slave:(5) 44.762118] [msg_test/INFO] Received "Task_17"
231 > [Bousquet:slave:(5) 44.762118] [msg_test/INFO] Processing "Task_17"
232 > [Jacquelin:master:(1) 44.762118] [msg_test/INFO] Sent
233 > [Jacquelin:master:(1) 44.762118] [msg_test/INFO] Sending "Task_18" to "Soucy"
234 > [Bousquet:slave:(5) 44.878622] [msg_test/INFO] "Task_17" done
235 > [Robert:slave:(10) 45.531275] [msg_test/INFO] Received "Task_16"
236 > [Robert:slave:(10) 45.531275] [msg_test/INFO] Processing "Task_16"
237 > [Robert:slave:(10) 45.567683] [msg_test/INFO] "Task_16" done
238 > [Soucy:slave:(6) 47.025854] [msg_test/INFO] Received "Task_18"
239 > [Soucy:slave:(6) 47.025854] [msg_test/INFO] Processing "Task_18"
240 > [Jacquelin:master:(1) 47.025854] [msg_test/INFO] Sent
241 > [Jacquelin:master:(1) 47.025854] [msg_test/INFO] Sending "Task_19" to "Jackson"
242 > [Soucy:slave:(6) 47.062262] [msg_test/INFO] "Task_18" done
243 > [Jackson:forwarder:(2) 48.360969] [msg_test/INFO] Received "Task_19"
244 > [Jackson:forwarder:(2) 48.360969] [msg_test/INFO] Sending "Task_19" to "Kuenning"
245 > [Jacquelin:master:(1) 48.360969] [msg_test/INFO] Sent
246 > [Jacquelin:master:(1) 48.360969] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
247 > [Kuenning:slave:(7) 48.444592] [msg_test/INFO] Received "Task_19"
248 > [Kuenning:slave:(7) 48.444592] [msg_test/INFO] Processing "Task_19"
249 > [Kuenning:slave:(7) 48.502845] [msg_test/INFO] "Task_19" done
250 > [iRMX:slave:(4) 50.794024] [msg_test/INFO] Received "finalize"
251 > [iRMX:slave:(4) 50.794024] [msg_test/INFO] I'm done. See you!
252 > [Casavant:forwarder:(3) 51.878795] [msg_test/INFO] Received "finalize"
253 > [Casavant:forwarder:(3) 51.878795] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
254 > [Bousquet:slave:(5) 52.689914] [msg_test/INFO] Received "finalize"
255 > [Bousquet:slave:(5) 52.689914] [msg_test/INFO] I'm done. See you!
256 > [Robert:slave:(10) 53.082033] [msg_test/INFO] Received "finalize"
257 > [Robert:slave:(10) 53.082033] [msg_test/INFO] I'm done. See you!
258 > [Sirois:slave:(11) 53.491253] [msg_test/INFO] Received "finalize"
259 > [Sirois:slave:(11) 53.491253] [msg_test/INFO] I'm done. See you!
260 > [Soucy:slave:(6) 53.843975] [msg_test/INFO] Received "finalize"
261 > [Soucy:slave:(6) 53.843975] [msg_test/INFO] I'm done. See you!
262 > [Monique:slave:(12) 54.200695] [msg_test/INFO] Received "finalize"
263 > [Monique:slave:(12) 54.200695] [msg_test/INFO] I'm done. See you!
264 > [Casavant:forwarder:(3) 54.200695] [msg_test/INFO] I'm done. See you!
265 > [Jackson:forwarder:(2) 54.524622] [msg_test/INFO] Received "finalize"
266 > [Jackson:forwarder:(2) 54.524622] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
267 > [Jacquelin:master:(1) 54.524622] [msg_test/INFO] Goodbye now!
268 > [Kuenning:slave:(7) 54.529294] [msg_test/INFO] Received "finalize"
269 > [Kuenning:slave:(7) 54.529294] [msg_test/INFO] I'm done. See you!
270 > [Browne:slave:(8) 55.971757] [msg_test/INFO] Received "finalize"
271 > [Browne:slave:(8) 55.971757] [msg_test/INFO] I'm done. See you!
272 > [Stephen:slave:(9) 58.132075] [msg_test/INFO] Received "finalize"
273 > [Stephen:slave:(9) 58.132075] [msg_test/INFO] I'm done. See you!
274 > [Jackson:forwarder:(2) 58.132075] [msg_test/INFO] I'm done. See you!
275 > [58.132075] [msg_test/INFO] Simulation time 58.1321
277 p Testing a simple master/slave example application handling failures
279 $ masterslave/masterslave_failure$EXEEXT --log=xbt_cfg.thres:critical --log=no_loc ${srcdir:=.}/small_platform_with_failures.xml ${srcdir:=.}/masterslave/deployment_masterslave.xml --cfg=path:${srcdir} --cfg=cpu_model:Cas01_IM
280 > [0.000000] [surf_config/INFO] Switching workstation model to compound since you changed the network and/or cpu model(s)
281 > [0.000000] [simix_process/WARNING] Cannot launch process 'slave' on failed host 'Fafard'
282 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Got 5 slave(s) :
283 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Jupiter
284 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Fafard
285 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Ginette
286 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Bourassa
287 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Tremblay
288 > [Tremblay:master:(1) 0.000000] [msg_test/INFO] Got 20 task to process :
289 > [Tremblay:master:(1) 0.165962] [msg_test/INFO] Send completed
290 > [Jupiter:slave:(3) 0.165962] [msg_test/INFO] Received "Task"
291 > [Jupiter:slave:(3) 0.165962] [msg_test/INFO] Communication time : "0.165962"
292 > [Jupiter:slave:(3) 0.165962] [msg_test/INFO] Processing "Task"
293 > [Jupiter:slave:(3) 0.821304] [msg_test/INFO] "Task" done
294 > [Jupiter:slave:(3) 1.100000] [msg_test/INFO] Gloups. The cpu on which I'm running just turned off!. See you!
295 > [Tremblay:master:(1) 10.165962] [msg_test/INFO] Mmh. Something went wrong with 'Fafard'. Nevermind. Let's keep going!
296 > [Ginette:slave:(4) 10.306421] [msg_test/INFO] Received "Task"
297 > [Ginette:slave:(4) 10.306421] [msg_test/INFO] Communication time : "0.140460"
298 > [Ginette:slave:(4) 10.306421] [msg_test/INFO] Processing "Task"
299 > [Tremblay:master:(1) 10.306421] [msg_test/INFO] Send completed
300 > [Bourassa:slave:(5) 10.522294] [msg_test/INFO] Received "Task"
301 > [Bourassa:slave:(5) 10.522294] [msg_test/INFO] Communication time : "0.215872"
302 > [Bourassa:slave:(5) 10.522294] [msg_test/INFO] Processing "Task"
303 > [Tremblay:master:(1) 10.522294] [msg_test/INFO] Send completed
304 > [Tremblay:slave:(2) 10.524632] [msg_test/INFO] Received "Task"
305 > [Tremblay:slave:(2) 10.524632] [msg_test/INFO] Communication time : "0.002339"
306 > [Tremblay:slave:(2) 10.524632] [msg_test/INFO] Processing "Task"
307 > [Tremblay:master:(1) 10.524632] [msg_test/INFO] Send completed
308 > [Tremblay:slave:(2) 11.034342] [msg_test/INFO] "Task" done
309 > [Ginette:slave:(4) 11.337519] [msg_test/INFO] "Task" done
310 > [Bourassa:slave:(5) 11.553392] [msg_test/INFO] "Task" done
311 > [Tremblay:master:(1) 20.524632] [msg_test/INFO] Mmh. Something went wrong with 'Jupiter'. Nevermind. Let's keep going!
312 > [Tremblay:master:(1) 30.524632] [msg_test/INFO] Mmh. Something went wrong with 'Fafard'. Nevermind. Let's keep going!
313 > [Ginette:slave:(4) 30.524632] [msg_test/INFO] Mmh. Something went wrong. Nevermind. Let's keep going!
314 > [Tremblay:master:(1) 30.524632] [msg_test/INFO] Mmh. Something went wrong with 'Ginette'. Nevermind. Let's keep going!
315 > [Bourassa:slave:(5) 30.740505] [msg_test/INFO] Received "Task"
316 > [Bourassa:slave:(5) 30.740505] [msg_test/INFO] Communication time : "0.215872"
317 > [Bourassa:slave:(5) 30.740505] [msg_test/INFO] Processing "Task"
318 > [Tremblay:master:(1) 30.740505] [msg_test/INFO] Send completed
319 > [Tremblay:slave:(2) 30.742843] [msg_test/INFO] Received "Task"
320 > [Tremblay:slave:(2) 30.742843] [msg_test/INFO] Communication time : "0.002339"
321 > [Tremblay:slave:(2) 30.742843] [msg_test/INFO] Processing "Task"
322 > [Tremblay:master:(1) 30.742843] [msg_test/INFO] Send completed
323 > [Tremblay:slave:(2) 31.252553] [msg_test/INFO] "Task" done
324 > [Bourassa:slave:(5) 31.771603] [msg_test/INFO] "Task" done
325 > [Tremblay:master:(1) 40.742843] [msg_test/INFO] Mmh. Something went wrong with 'Jupiter'. Nevermind. Let's keep going!
326 > [Tremblay:master:(1) 50.742843] [msg_test/INFO] Mmh. Something went wrong with 'Fafard'. Nevermind. Let's keep going!
327 > [Ginette:slave:(4) 50.883303] [msg_test/INFO] Received "Task"
328 > [Ginette:slave:(4) 50.883303] [msg_test/INFO] Communication time : "0.140460"
329 > [Ginette:slave:(4) 50.883303] [msg_test/INFO] Processing "Task"
330 > [Tremblay:master:(1) 50.883303] [msg_test/INFO] Send completed
331 > [Bourassa:slave:(5) 51.099175] [msg_test/INFO] Received "Task"
332 > [Bourassa:slave:(5) 51.099175] [msg_test/INFO] Communication time : "0.215872"
333 > [Bourassa:slave:(5) 51.099175] [msg_test/INFO] Processing "Task"
334 > [Tremblay:master:(1) 51.099175] [msg_test/INFO] Send completed
335 > [Tremblay:slave:(2) 51.101514] [msg_test/INFO] Received "Task"
336 > [Tremblay:slave:(2) 51.101514] [msg_test/INFO] Communication time : "0.002339"
337 > [Tremblay:slave:(2) 51.101514] [msg_test/INFO] Processing "Task"
338 > [Tremblay:master:(1) 51.101514] [msg_test/INFO] Send completed
339 > [Tremblay:slave:(2) 51.611224] [msg_test/INFO] "Task" done
340 > [Ginette:slave:(4) 51.914401] [msg_test/INFO] "Task" done
341 > [Bourassa:slave:(5) 52.130273] [msg_test/INFO] "Task" done
342 > [Tremblay:master:(1) 61.101514] [msg_test/INFO] Mmh. Something went wrong with 'Jupiter'. Nevermind. Let's keep going!
343 > [Tremblay:master:(1) 71.101514] [msg_test/INFO] Mmh. Something went wrong with 'Fafard'. Nevermind. Let's keep going!
344 > [Ginette:slave:(4) 71.241973] [msg_test/INFO] Received "Task"
345 > [Ginette:slave:(4) 71.241973] [msg_test/INFO] Communication time : "0.140460"
346 > [Ginette:slave:(4) 71.241973] [msg_test/INFO] Processing "Task"
347 > [Tremblay:master:(1) 71.241973] [msg_test/INFO] Send completed
348 > [Bourassa:slave:(5) 71.457846] [msg_test/INFO] Received "Task"
349 > [Bourassa:slave:(5) 71.457846] [msg_test/INFO] Communication time : "0.215872"
350 > [Bourassa:slave:(5) 71.457846] [msg_test/INFO] Processing "Task"
351 > [Tremblay:master:(1) 71.457846] [msg_test/INFO] Send completed
352 > [Tremblay:slave:(2) 71.460184] [msg_test/INFO] Received "Task"
353 > [Tremblay:slave:(2) 71.460184] [msg_test/INFO] Communication time : "0.002339"
354 > [Tremblay:slave:(2) 71.460184] [msg_test/INFO] Processing "Task"
355 > [Tremblay:master:(1) 71.460184] [msg_test/INFO] Send completed
356 > [Tremblay:master:(1) 71.460184] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
357 > [Tremblay:slave:(2) 71.969894] [msg_test/INFO] "Task" done
358 > [Ginette:slave:(4) 72.273071] [msg_test/INFO] "Task" done
359 > [Tremblay:master:(1) 72.460184] [msg_test/INFO] Mmh. Can't reach 'Jupiter'! Nevermind. Let's keep going!
360 > [Bourassa:slave:(5) 72.488944] [msg_test/INFO] "Task" done
361 > [Tremblay:master:(1) 73.460184] [msg_test/INFO] Mmh. Can't reach 'Fafard'! Nevermind. Let's keep going!
362 > [Ginette:slave:(4) 73.473416] [msg_test/INFO] Received "finalize"
363 > [Ginette:slave:(4) 73.473416] [msg_test/INFO] I'm done. See you!
364 > [Bourassa:slave:(5) 73.493752] [msg_test/INFO] Received "finalize"
365 > [Bourassa:slave:(5) 73.493752] [msg_test/INFO] I'm done. See you!
366 > [Tremblay:slave:(2) 73.493908] [msg_test/INFO] Received "finalize"
367 > [Tremblay:slave:(2) 73.493908] [msg_test/INFO] I'm done. See you!
368 > [Tremblay:master:(1) 73.493908] [msg_test/INFO] Goodbye now!
369 > [73.493908] [msg_test/INFO] Simulation time 73.4939
371 p Testing the bypassing of the flexml parser
373 $ $SG_TEST_EXENV masterslave/masterslave_bypass --log=no_loc --cfg=cpu_model:Cas01_IM
374 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'cpu_model' to 'Cas01_IM'
375 > [0.000000] [surf_config/INFO] Switching workstation model to compound since you changed the network and/or cpu model(s)
376 > [0.000000] [surf_parse/WARNING] Bypassing the XML parser since surf_parse_open received a NULL pointer. If it is not what you want, go fix your code.
377 > [host A:master:(1) 0.000000] [msg_test/INFO] Got 1 slave(s) :
378 > [host A:master:(1) 0.000000] [msg_test/INFO] host B
379 > [host A:master:(1) 0.000000] [msg_test/INFO] Got 20 task to process :
380 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_0"
381 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_1"
382 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_2"
383 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_3"
384 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_4"
385 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_5"
386 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_6"
387 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_7"
388 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_8"
389 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_9"
390 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_10"
391 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_11"
392 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_12"
393 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_13"
394 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_14"
395 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_15"
396 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_16"
397 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_17"
398 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_18"
399 > [host A:master:(1) 0.000000] [msg_test/INFO] "Task_19"
400 > [host A:master:(1) 0.000000] [msg_test/INFO] Sending "Task_0" to "host B"
401 > [host B:slave:(2) 0.000000] [msg_test/INFO] I'm a slave
402 > [host A:master:(1) 4.080000] [msg_test/INFO] Send completed
403 > [host A:master:(1) 4.080000] [msg_test/INFO] Sending "Task_1" to "host B"
404 > [host B:slave:(2) 4.080000] [msg_test/INFO] Received "Task_0"
405 > [host B:slave:(2) 4.080000] [msg_test/INFO] Processing "Task_0"
406 > [host B:slave:(2) 4.130000] [msg_test/INFO] "Task_0" done
407 > [host A:master:(1) 8.210000] [msg_test/INFO] Send completed
408 > [host A:master:(1) 8.210000] [msg_test/INFO] Sending "Task_2" to "host B"
409 > [host B:slave:(2) 8.210000] [msg_test/INFO] Received "Task_1"
410 > [host B:slave:(2) 8.210000] [msg_test/INFO] Processing "Task_1"
411 > [host B:slave:(2) 8.260000] [msg_test/INFO] "Task_1" done
412 > [host A:master:(1) 12.340000] [msg_test/INFO] Send completed
413 > [host A:master:(1) 12.340000] [msg_test/INFO] Sending "Task_3" to "host B"
414 > [host B:slave:(2) 12.340000] [msg_test/INFO] Received "Task_2"
415 > [host B:slave:(2) 12.340000] [msg_test/INFO] Processing "Task_2"
416 > [host B:slave:(2) 12.390000] [msg_test/INFO] "Task_2" done
417 > [host A:master:(1) 16.470000] [msg_test/INFO] Send completed
418 > [host A:master:(1) 16.470000] [msg_test/INFO] Sending "Task_4" to "host B"
419 > [host B:slave:(2) 16.470000] [msg_test/INFO] Received "Task_3"
420 > [host B:slave:(2) 16.470000] [msg_test/INFO] Processing "Task_3"
421 > [host B:slave:(2) 16.520000] [msg_test/INFO] "Task_3" done
422 > [host A:master:(1) 20.600000] [msg_test/INFO] Send completed
423 > [host A:master:(1) 20.600000] [msg_test/INFO] Sending "Task_5" to "host B"
424 > [host B:slave:(2) 20.600000] [msg_test/INFO] Received "Task_4"
425 > [host B:slave:(2) 20.600000] [msg_test/INFO] Processing "Task_4"
426 > [host B:slave:(2) 20.650000] [msg_test/INFO] "Task_4" done
427 > [host A:master:(1) 24.730000] [msg_test/INFO] Send completed
428 > [host A:master:(1) 24.730000] [msg_test/INFO] Sending "Task_6" to "host B"
429 > [host B:slave:(2) 24.730000] [msg_test/INFO] Received "Task_5"
430 > [host B:slave:(2) 24.730000] [msg_test/INFO] Processing "Task_5"
431 > [host B:slave:(2) 24.780000] [msg_test/INFO] "Task_5" done
432 > [host A:master:(1) 28.860000] [msg_test/INFO] Send completed
433 > [host A:master:(1) 28.860000] [msg_test/INFO] Sending "Task_7" to "host B"
434 > [host B:slave:(2) 28.860000] [msg_test/INFO] Received "Task_6"
435 > [host B:slave:(2) 28.860000] [msg_test/INFO] Processing "Task_6"
436 > [host B:slave:(2) 28.910000] [msg_test/INFO] "Task_6" done
437 > [host A:master:(1) 32.990000] [msg_test/INFO] Send completed
438 > [host A:master:(1) 32.990000] [msg_test/INFO] Sending "Task_8" to "host B"
439 > [host B:slave:(2) 32.990000] [msg_test/INFO] Received "Task_7"
440 > [host B:slave:(2) 32.990000] [msg_test/INFO] Processing "Task_7"
441 > [host B:slave:(2) 33.040000] [msg_test/INFO] "Task_7" done
442 > [host A:master:(1) 37.120000] [msg_test/INFO] Send completed
443 > [host A:master:(1) 37.120000] [msg_test/INFO] Sending "Task_9" to "host B"
444 > [host B:slave:(2) 37.120000] [msg_test/INFO] Received "Task_8"
445 > [host B:slave:(2) 37.120000] [msg_test/INFO] Processing "Task_8"
446 > [host B:slave:(2) 37.170000] [msg_test/INFO] "Task_8" done
447 > [host A:master:(1) 41.250000] [msg_test/INFO] Send completed
448 > [host A:master:(1) 41.250000] [msg_test/INFO] Sending "Task_10" to "host B"
449 > [host B:slave:(2) 41.250000] [msg_test/INFO] Received "Task_9"
450 > [host B:slave:(2) 41.250000] [msg_test/INFO] Processing "Task_9"
451 > [host B:slave:(2) 41.300000] [msg_test/INFO] "Task_9" done
452 > [host A:master:(1) 45.380000] [msg_test/INFO] Send completed
453 > [host A:master:(1) 45.380000] [msg_test/INFO] Sending "Task_11" to "host B"
454 > [host B:slave:(2) 45.380000] [msg_test/INFO] Received "Task_10"
455 > [host B:slave:(2) 45.380000] [msg_test/INFO] Processing "Task_10"
456 > [host B:slave:(2) 45.430000] [msg_test/INFO] "Task_10" done
457 > [host A:master:(1) 49.510000] [msg_test/INFO] Send completed
458 > [host A:master:(1) 49.510000] [msg_test/INFO] Sending "Task_12" to "host B"
459 > [host B:slave:(2) 49.510000] [msg_test/INFO] Received "Task_11"
460 > [host B:slave:(2) 49.510000] [msg_test/INFO] Processing "Task_11"
461 > [host B:slave:(2) 49.560000] [msg_test/INFO] "Task_11" done
462 > [host A:master:(1) 53.640000] [msg_test/INFO] Send completed
463 > [host A:master:(1) 53.640000] [msg_test/INFO] Sending "Task_13" to "host B"
464 > [host B:slave:(2) 53.640000] [msg_test/INFO] Received "Task_12"
465 > [host B:slave:(2) 53.640000] [msg_test/INFO] Processing "Task_12"
466 > [host B:slave:(2) 53.690000] [msg_test/INFO] "Task_12" done
467 > [host A:master:(1) 57.770000] [msg_test/INFO] Send completed
468 > [host A:master:(1) 57.770000] [msg_test/INFO] Sending "Task_14" to "host B"
469 > [host B:slave:(2) 57.770000] [msg_test/INFO] Received "Task_13"
470 > [host B:slave:(2) 57.770000] [msg_test/INFO] Processing "Task_13"
471 > [host B:slave:(2) 57.820000] [msg_test/INFO] "Task_13" done
472 > [host A:master:(1) 61.900000] [msg_test/INFO] Send completed
473 > [host A:master:(1) 61.900000] [msg_test/INFO] Sending "Task_15" to "host B"
474 > [host B:slave:(2) 61.900000] [msg_test/INFO] Received "Task_14"
475 > [host B:slave:(2) 61.900000] [msg_test/INFO] Processing "Task_14"
476 > [host B:slave:(2) 61.950000] [msg_test/INFO] "Task_14" done
477 > [host A:master:(1) 66.030000] [msg_test/INFO] Send completed
478 > [host A:master:(1) 66.030000] [msg_test/INFO] Sending "Task_16" to "host B"
479 > [host B:slave:(2) 66.030000] [msg_test/INFO] Received "Task_15"
480 > [host B:slave:(2) 66.030000] [msg_test/INFO] Processing "Task_15"
481 > [host B:slave:(2) 66.080000] [msg_test/INFO] "Task_15" done
482 > [host A:master:(1) 70.160000] [msg_test/INFO] Send completed
483 > [host A:master:(1) 70.160000] [msg_test/INFO] Sending "Task_17" to "host B"
484 > [host B:slave:(2) 70.160000] [msg_test/INFO] Received "Task_16"
485 > [host B:slave:(2) 70.160000] [msg_test/INFO] Processing "Task_16"
486 > [host B:slave:(2) 70.210000] [msg_test/INFO] "Task_16" done
487 > [host A:master:(1) 74.290000] [msg_test/INFO] Send completed
488 > [host A:master:(1) 74.290000] [msg_test/INFO] Sending "Task_18" to "host B"
489 > [host B:slave:(2) 74.290000] [msg_test/INFO] Received "Task_17"
490 > [host B:slave:(2) 74.290000] [msg_test/INFO] Processing "Task_17"
491 > [host B:slave:(2) 74.340000] [msg_test/INFO] "Task_17" done
492 > [host A:master:(1) 78.420000] [msg_test/INFO] Send completed
493 > [host A:master:(1) 78.420000] [msg_test/INFO] Sending "Task_19" to "host B"
494 > [host B:slave:(2) 78.420000] [msg_test/INFO] Received "Task_18"
495 > [host B:slave:(2) 78.420000] [msg_test/INFO] Processing "Task_18"
496 > [host B:slave:(2) 78.470000] [msg_test/INFO] "Task_18" done
497 > [host A:master:(1) 82.550000] [msg_test/INFO] Send completed
498 > [host A:master:(1) 82.550000] [msg_test/INFO] All tasks have been dispatched. Let's tell everybody the computation is over.
499 > [host B:slave:(2) 82.550000] [msg_test/INFO] Received "Task_19"
500 > [host B:slave:(2) 82.550000] [msg_test/INFO] Processing "Task_19"
501 > [host B:slave:(2) 82.600000] [msg_test/INFO] "Task_19" done
502 > [host A:master:(1) 84.680000] [msg_test/INFO] Goodbye now!
503 > [host B:slave:(2) 84.680000] [msg_test/INFO] Received "finalize"
504 > [host B:slave:(2) 84.680000] [msg_test/INFO] I'm done. See you!
505 > [84.680000] [msg_test/INFO] Simulation time 84.68
