Logo AND Algorithmique Numérique Distribuée

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