Revalidating some GRAS tests for 64bit architectures

Update ChangeLog

git-svn-id: svn+ssh://scm.gforge.inria.fr/svn/simgrid/simgrid/trunk@6707 48e7efb5-ca39-0410-a469-dd3cf9ba447f

diff --git a/ChangeLog b/ChangeLog
index 177029e..1227b93 100644 (file)
--- a/ChangeLog
+++ b/ChangeLog
@@ -2,6 +2,15 @@ SimGrid (3.3.4) unstable; urgency=low
 
  The "Desktop Grid needs love too" release.
 
+ MSG:
+ * Port of MSG's mailbox on top of SIMIX network
+   The put/get mechanism was greatly simplified thanks to the new SIMIX module.
+
+ SIMIX:
+ * New SIMIX network module
+   It provides a rendez-vous point (aka mailbox) based send/recv API to perform
+   synchronous communication. 
+
  Models improvements:
  * Major speedup in the maxmin system solving by using lazy evaluation
    Instead of solving completely the maxmin system at each iteration, 

diff --git a/examples/amok/bandwidth/bandwidth_sg_64.tesh b/examples/amok/bandwidth/bandwidth_sg_64.tesh
index 3e74b02..15b1bab 100644 (file)
--- a/examples/amok/bandwidth/bandwidth_sg_64.tesh
+++ b/examples/amok/bandwidth/bandwidth_sg_64.tesh
@@ -1,13 +1,13 @@
 $ $SG_TEST_EXENV bandwidth/bandwidth_simulator${EXEEXT} ${srcdir:=.}/../msg/small_platform.xml ${srcdir:=.}/bandwidth/bandwidth.xml
 > [Ginette:maestro:(3) 0.000000] [Bandwidth/INFO] Maestro starting
-> [Tremblay:sensor:(1) 0.000015] [Bandwidth/INFO] Sensor starting (on port 3000)
-> [Jupiter:sensor:(2) 0.000015] [Bandwidth/INFO] Sensor starting (on port 3000)
-> [Ginette:maestro:(3) 0.000015] [Bandwidth/INFO] Wait for peers for 5 sec
-> [Ginette:maestro:(3) 5.000015] [Bandwidth/INFO] Contact Tremblay:3000
-> [Ginette:maestro:(3) 5.000030] [Bandwidth/INFO] Test the BW between me and one of the sensors
-> [Ginette:maestro:(3) 6.137822] [Bandwidth/INFO] Experience between me and Tremblay:3000 (initially 1 msgs of 524288 bytes, maybe modified to fill the pipe at least 1.0s) took 1.062123 sec, achieving 7657.304762 kb/s
-> [Ginette:maestro:(3) 6.137822] [Bandwidth/INFO] Test the BW between Tremblay:3000 and Jupiter:3000
-> [Ginette:maestro:(3) 7.289823] [Bandwidth/INFO] Experience between Tremblay:3000 and Jupiter:3000 took took 1.062501 sec, achieving 6663.458290 kb/s
-> [Ginette:maestro:(3) 7.293859] [gras/INFO] Exiting GRAS
-> [Jupiter:sensor:(2) 7.293859] [gras/INFO] Exiting GRAS
-> [Tremblay:sensor:(1) 8.289823] [gras/INFO] Exiting GRAS
+> [Tremblay:sensor:(1) 0.000156] [Bandwidth/INFO] Sensor starting (on port 3000)
+> [Ginette:maestro:(3) 0.000156] [Bandwidth/INFO] Wait for peers for 5 sec
+> [Jupiter:sensor:(2) 0.000156] [Bandwidth/INFO] Sensor starting (on port 3000)
+> [Ginette:maestro:(3) 5.000156] [Bandwidth/INFO] Contact Tremblay:3000
+> [Ginette:maestro:(3) 5.000312] [Bandwidth/INFO] Test the BW between me and one of the sensors
+> [Ginette:maestro:(3) 7.091307] [Bandwidth/INFO] Experience between me and Tremblay:3000 (initially 1 msgs of 524288 bytes, maybe modified to fill the pipe at least 1.0s) took 1.090703 sec, achieving 7489.460506 kb/s
+> [Ginette:maestro:(3) 7.091307] [Bandwidth/INFO] Test the BW between Tremblay:3000 and Jupiter:3000
+> [Ginette:maestro:(3) 9.249135] [Bandwidth/INFO] Experience between Tremblay:3000 and Jupiter:3000 took took 1.089859 sec, achieving 6296.831079 kb/s
+> [Ginette:maestro:(3) 9.291110] [gras/INFO] Exiting GRAS
+> [Jupiter:sensor:(2) 9.291110] [gras/INFO] Exiting GRAS
+> [Tremblay:sensor:(1) 10.249135] [gras/INFO] Exiting GRAS

diff --git a/examples/amok/saturate/saturate_sg_64.tesh b/examples/amok/saturate/saturate_sg_64.tesh
index cfc4393..d1faea6 100644 (file)
--- a/examples/amok/saturate/saturate_sg_64.tesh
+++ b/examples/amok/saturate/saturate_sg_64.tesh
@@ -1,17 +1,17 @@
 $ $SG_TEST_EXENV saturate/saturate_simulator${EXEEXT} ${srcdir:=.}/../msg/small_platform.xml ${srcdir:=.}/saturate/saturate.xml
 > [Tremblay:sensor:(1) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
-> [Jupiter:sensor:(2) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
-> [Fafard:sensor:(3) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
 > [Ginette:sensor:(4) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
-> [Ginette:sensor:(4) 5.001254] [gras/INFO] Exiting GRAS
-> [Bourassa:maestro:(5) 5.001790] [saturate/INFO] Start saturation between Tremblay:3000 and Jupiter:3000
-> [Fafard:sensor:(3) 5.001790] [gras/INFO] Exiting GRAS
-> [Bourassa:maestro:(5) 5.005719] [saturate/INFO] Have a rest
-> [Bourassa:maestro:(5) 6.005719] [saturate/INFO] Stop the saturation
-> [Tremblay:sensor:(1) 6.158109] [amok_bw_sat/INFO] Saturation(Tremblay:3000->Jupiter:3000) started
-> [Tremblay:sensor:(1) 12.156805] [amok_bw_sat/INFO] Saturation from Tremblay:3000 to Jupiter:3000 stopped by Bourassa:4000
-> [Bourassa:maestro:(5) 12.158764] [saturate/INFO] Saturation took 6.00sec, achieving 1137477.521033b/s
-> [Tremblay:sensor:(1) 12.160734] [gras/INFO] Exiting GRAS
-> [Bourassa:maestro:(5) 12.164166] [gras/INFO] Exiting GRAS
-> [Jupiter:sensor:(2) 72.156805] [amok_bw_sat/INFO] Saturation comming from Tremblay:3000 stopped on Jupiter:3000
-> [Jupiter:sensor:(2) 72.156805] [gras/INFO] Exiting GRAS
+> [Fafard:sensor:(3) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
+> [Jupiter:sensor:(2) 0.000000] [saturate/INFO] Sensor starting (on port 3000)
+> [Jupiter:sensor:(2) 5.035692] [gras/INFO] Exiting GRAS
+> [Bourassa:maestro:(5) 5.041260] [saturate/INFO] Start saturation between Tremblay:3000 and Ginette:3000
+> [Fafard:sensor:(3) 5.041260] [gras/INFO] Exiting GRAS
+> [Bourassa:maestro:(5) 5.082092] [saturate/INFO] Have a rest
+> [Bourassa:maestro:(5) 6.082092] [saturate/INFO] Stop the saturation
+> [Tremblay:sensor:(1) 7.200021] [amok_bw_sat/INFO] Saturation(Tremblay:3000->Ginette:3000) started
+> [Tremblay:sensor:(1) 13.188834] [amok_bw_sat/INFO] Saturation from Tremblay:3000 to Ginette:3000 stopped by Bourassa:4000
+> [Bourassa:maestro:(5) 13.209174] [saturate/INFO] Saturation took 5.99sec, achieving 1280588.867148b/s
+> [Tremblay:sensor:(1) 13.229666] [gras/INFO] Exiting GRAS
+> [Bourassa:maestro:(5) 13.242710] [gras/INFO] Exiting GRAS
+> [Ginette:sensor:(4) 73.188834] [amok_bw_sat/INFO] Saturation comming from Tremblay:3000 stopped on Ginette:3000
+> [Ginette:sensor:(4) 73.188834] [gras/INFO] Exiting GRAS

diff --git a/examples/gras/all2all/test_sg_64 b/examples/gras/all2all/test_sg_64
index a03145c..e8f8036 100755 (executable)
--- a/examples/gras/all2all/test_sg_64
+++ b/examples/gras/all2all/test_sg_64
@@ -1,61 +1,61 @@
 $ $SG_TEST_EXENV ./all2all_simulator$EXEEXT ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/all2all.xml
 > [Tremblay:sender:(1) 0.000000] [all2all/INFO] Launch current node
-> [Jupiter:sender:(3) 0.000000] [all2all/INFO] Launch current node
-> [Fafard:sender:(5) 0.000000] [all2all/INFO] Launch current node
-> [Ginette:sender:(7) 0.000000] [all2all/INFO] Launch current node
 > [Bourassa:sender:(9) 0.000000] [all2all/INFO] Launch current node
-> [Tremblay:receiver:(2) 0.000015] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Jupiter:receiver:(4) 0.000015] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Fafard:receiver:(6) 0.000015] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Ginette:receiver:(8) 0.000015] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Bourassa:receiver:(10) 0.000015] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Jupiter:sender:(3) 0.001551] [all2all/INFO]   Sent Data from Jupiter to Tremblay
-> [Tremblay:receiver:(2) 0.001551] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
-> [Fafard:sender:(5) 0.003629] [all2all/INFO]   Sent Data from Fafard to Tremblay
-> [Tremblay:receiver:(2) 0.003629] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
-> [Ginette:sender:(7) 0.004966] [all2all/INFO]   Sent Data from Ginette to Tremblay
-> [Tremblay:receiver:(2) 0.004966] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
-> [Jupiter:sender:(3) 0.005180] [all2all/INFO]   Sent Data from Jupiter to Fafard
-> [Fafard:receiver:(6) 0.005180] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
-> [Bourassa:sender:(9) 0.007022] [all2all/INFO]   Sent Data from Bourassa to Tremblay
-> [Tremblay:receiver:(2) 0.007022] [all2all/INFO] Got Data from Bourassa:0 (still 0 to go)
-> [Tremblay:receiver:(2) 0.007022] [gras/INFO] Exiting GRAS
-> [Fafard:sender:(5) 0.007258] [all2all/INFO]   Sent Data from Fafard to Jupiter
-> [Jupiter:receiver:(4) 0.007258] [all2all/INFO] Got Data from Fafard:0 (still 3 to go)
-> [Jupiter:sender:(3) 0.008069] [all2all/INFO]   Sent Data from Jupiter to Ginette
-> [Ginette:receiver:(8) 0.008069] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
-> [Fafard:sender:(5) 0.009394] [all2all/INFO]   Sent Data from Fafard to Ginette
-> [Ginette:receiver:(8) 0.009394] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
-> [Ginette:sender:(7) 0.010132] [all2all/INFO]   Sent Data from Ginette to Jupiter
-> [Jupiter:receiver:(4) 0.010132] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
-> [Ginette:sender:(7) 0.011471] [all2all/INFO]   Sent Data from Ginette to Fafard
-> [Fafard:receiver:(6) 0.011471] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
-> [Jupiter:sender:(3) 0.011677] [all2all/INFO]   Sent Data from Jupiter to Bourassa
-> [Jupiter:sender:(3) 0.011677] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.011677] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
-> [Fafard:sender:(5) 0.012260] [all2all/INFO]   Sent Data from Fafard to Bourassa
-> [Fafard:sender:(5) 0.012260] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.012260] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
-> [Ginette:sender:(7) 0.013563] [all2all/INFO]   Sent Data from Ginette to Bourassa
-> [Ginette:sender:(7) 0.013563] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.013563] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
-> [Bourassa:sender:(9) 0.013724] [all2all/INFO]   Sent Data from Bourassa to Jupiter
-> [Jupiter:receiver:(4) 0.013724] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
-> [Bourassa:sender:(9) 0.014322] [all2all/INFO]   Sent Data from Bourassa to Fafard
-> [Fafard:receiver:(6) 0.014322] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
-> [Tremblay:sender:(1) 0.015260] [all2all/INFO]   Sent Data from Tremblay to Jupiter
-> [Jupiter:receiver:(4) 0.015260] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
-> [Jupiter:receiver:(4) 0.015260] [gras/INFO] Exiting GRAS
-> [Bourassa:sender:(9) 0.015640] [all2all/INFO]   Sent Data from Bourassa to Ginette
-> [Bourassa:sender:(9) 0.015640] [gras/INFO] Exiting GRAS
-> [Ginette:receiver:(8) 0.015640] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
-> [Tremblay:sender:(1) 0.017353] [all2all/INFO]   Sent Data from Tremblay to Fafard
-> [Fafard:receiver:(6) 0.017353] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
-> [Fafard:receiver:(6) 0.017353] [gras/INFO] Exiting GRAS
-> [Tremblay:sender:(1) 0.018705] [all2all/INFO]   Sent Data from Tremblay to Ginette
-> [Ginette:receiver:(8) 0.018705] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
-> [Ginette:receiver:(8) 0.018705] [gras/INFO] Exiting GRAS
-> [Tremblay:sender:(1) 0.020776] [all2all/INFO]   Sent Data from Tremblay to Bourassa
-> [Tremblay:sender:(1) 0.020776] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.020776] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
-> [Bourassa:receiver:(10) 0.020776] [gras/INFO] Exiting GRAS
+> [Ginette:sender:(7) 0.000000] [all2all/INFO] Launch current node
+> [Fafard:sender:(5) 0.000000] [all2all/INFO] Launch current node
+> [Jupiter:sender:(3) 0.000000] [all2all/INFO] Launch current node
+> [Bourassa:receiver:(10) 0.000156] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
+> [Ginette:receiver:(8) 0.000156] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
+> [Fafard:receiver:(6) 0.000156] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
+> [Jupiter:receiver:(4) 0.000156] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
+> [Tremblay:receiver:(2) 0.000156] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
+> [Tremblay:sender:(1) 0.025433] [all2all/INFO]   Sent Data from Tremblay to Jupiter
+> [Jupiter:receiver:(4) 0.025433] [all2all/INFO] Got Data from Tremblay:0 (still 3 to go)
+> [Bourassa:sender:(9) 0.030592] [all2all/INFO]   Sent Data from Bourassa to Tremblay
+> [Tremblay:receiver:(2) 0.030592] [all2all/INFO] Got Data from Bourassa:0 (still 3 to go)
+> [Ginette:sender:(7) 0.043889] [all2all/INFO]   Sent Data from Ginette to Tremblay
+> [Tremblay:receiver:(2) 0.043889] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
+> [Tremblay:sender:(1) 0.046241] [all2all/INFO]   Sent Data from Tremblay to Fafard
+> [Fafard:receiver:(6) 0.046241] [all2all/INFO] Got Data from Tremblay:0 (still 3 to go)
+> [Tremblay:sender:(1) 0.059694] [all2all/INFO]   Sent Data from Tremblay to Ginette
+> [Ginette:receiver:(8) 0.059694] [all2all/INFO] Got Data from Tremblay:0 (still 3 to go)
+> [Fafard:sender:(5) 0.064541] [all2all/INFO]   Sent Data from Fafard to Tremblay
+> [Tremblay:receiver:(2) 0.064541] [all2all/INFO] Got Data from Fafard:0 (still 1 to go)
+> [Bourassa:sender:(9) 0.066459] [all2all/INFO]   Sent Data from Bourassa to Jupiter
+> [Jupiter:receiver:(4) 0.066459] [all2all/INFO] Got Data from Bourassa:0 (still 2 to go)
+> [Bourassa:sender:(9) 0.072096] [all2all/INFO]   Sent Data from Bourassa to Fafard
+> [Fafard:receiver:(6) 0.072096] [all2all/INFO] Got Data from Bourassa:0 (still 2 to go)
+> [Jupiter:sender:(3) 0.079818] [all2all/INFO]   Sent Data from Jupiter to Tremblay
+> [Tremblay:receiver:(2) 0.079818] [all2all/INFO] Got Data from Jupiter:0 (still 0 to go)
+> [Tremblay:receiver:(2) 0.079818] [gras/INFO] Exiting GRAS
+> [Tremblay:sender:(1) 0.080286] [all2all/INFO]   Sent Data from Tremblay to Bourassa
+> [Tremblay:sender:(1) 0.080286] [gras/INFO] Exiting GRAS
+> [Bourassa:receiver:(10) 0.080286] [all2all/INFO] Got Data from Tremblay:0 (still 3 to go)
+> [Bourassa:sender:(9) 0.085203] [all2all/INFO]   Sent Data from Bourassa to Ginette
+> [Bourassa:sender:(9) 0.085203] [gras/INFO] Exiting GRAS
+> [Ginette:receiver:(8) 0.085203] [all2all/INFO] Got Data from Bourassa:0 (still 2 to go)
+> [Ginette:sender:(7) 0.095031] [all2all/INFO]   Sent Data from Ginette to Jupiter
+> [Jupiter:receiver:(4) 0.095031] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
+> [Jupiter:sender:(3) 0.115901] [all2all/INFO]   Sent Data from Jupiter to Fafard
+> [Fafard:receiver:(6) 0.115901] [all2all/INFO] Got Data from Jupiter:0 (still 1 to go)
+> [Ginette:sender:(7) 0.129072] [all2all/INFO]   Sent Data from Ginette to Fafard
+> [Fafard:receiver:(6) 0.129072] [all2all/INFO] Got Data from Ginette:0 (still 0 to go)
+> [Fafard:receiver:(6) 0.129072] [gras/INFO] Exiting GRAS
+> [Fafard:sender:(5) 0.130957] [all2all/INFO]   Sent Data from Fafard to Jupiter
+> [Jupiter:receiver:(4) 0.130957] [all2all/INFO] Got Data from Fafard:0 (still 0 to go)
+> [Jupiter:receiver:(4) 0.130957] [gras/INFO] Exiting GRAS
+> [Ginette:sender:(7) 0.142180] [all2all/INFO]   Sent Data from Ginette to Bourassa
+> [Ginette:sender:(7) 0.142180] [gras/INFO] Exiting GRAS
+> [Bourassa:receiver:(10) 0.142180] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
+> [Jupiter:sender:(3) 0.144629] [all2all/INFO]   Sent Data from Jupiter to Ginette
+> [Ginette:receiver:(8) 0.144629] [all2all/INFO] Got Data from Jupiter:0 (still 1 to go)
+> [Fafard:sender:(5) 0.157800] [all2all/INFO]   Sent Data from Fafard to Ginette
+> [Ginette:receiver:(8) 0.157800] [all2all/INFO] Got Data from Fafard:0 (still 0 to go)
+> [Ginette:receiver:(8) 0.157800] [gras/INFO] Exiting GRAS
+> [Jupiter:sender:(3) 0.180496] [all2all/INFO]   Sent Data from Jupiter to Bourassa
+> [Jupiter:sender:(3) 0.180496] [gras/INFO] Exiting GRAS
+> [Bourassa:receiver:(10) 0.180496] [all2all/INFO] Got Data from Jupiter:0 (still 1 to go)
+> [Fafard:sender:(5) 0.185976] [all2all/INFO]   Sent Data from Fafard to Bourassa
+> [Fafard:sender:(5) 0.185976] [gras/INFO] Exiting GRAS
+> [Bourassa:receiver:(10) 0.185976] [all2all/INFO] Got Data from Fafard:0 (still 0 to go)
+> [Bourassa:receiver:(10) 0.185976] [gras/INFO] Exiting GRAS

diff --git a/examples/gras/mmrpc/test_sg_64 b/examples/gras/mmrpc/test_sg_64
index 3af8327..6c15d37 100755 (executable)
--- a/examples/gras/mmrpc/test_sg_64
+++ b/examples/gras/mmrpc/test_sg_64
@@ -1,9 +1,10 @@
 $ $SG_TEST_EXENV ./mmrpc_simulator$EXEEXT ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/mmrpc.xml
 > [Tremblay:server:(1) 0.000000] [MatMult/INFO] Launch server (port=4000)
 > [Fafard:client:(2) 0.000000] [MatMult/INFO] Launch client (server on Tremblay:4000)
-> [Fafard:client:(2) 0.000015] [MatMult/INFO] Connected to Tremblay:4000.
-> [Fafard:client:(2) 0.000015] [MatMult/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
-> [Fafard:client:(2) 0.053801] [MatMult/INFO] >>>>>>>> Request sent to Tremblay:4000 <<<<<<<<
-> [Tremblay:server:(1) 0.081682] [gras/INFO] Exiting GRAS
-> [Fafard:client:(2) 0.081682] [MatMult/INFO] >>>>>>>> Got answer from Tremblay:4000 (values are right) <<<<<<<<
-> [Fafard:client:(2) 0.081682] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 0.000156] [MatMult/INFO] Connected to Tremblay:4000.
+> [Fafard:client:(2) 0.000156] [MatMult/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
+> [Fafard:client:(2) 0.072516] [MatMult/INFO] >>>>>>>> Request sent to Tremblay:4000 <<<<<<<<
+> [Tremblay:server:(1) 0.118972] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 0.118972] [MatMult/INFO] >>>>>>>> Got answer from Tremblay:4000 (values are right) <<<<<<<<
+> [Fafard:client:(2) 0.118972] [gras/INFO] Exiting GRAS
+

diff --git a/examples/gras/mutual_exclusion/simple_token/test_sg_64 b/examples/gras/mutual_exclusion/simple_token/test_sg_64
index 9a01787..e33cd31 100755 (executable)
--- a/examples/gras/mutual_exclusion/simple_token/test_sg_64
+++ b/examples/gras/mutual_exclusion/simple_token/test_sg_64
@@ -1,12 +1,12 @@
 $ $SG_TEST_EXENV ./simple_token_simulator$EXEEXT ${srcdir:=.}/../../../msg/small_platform.xml ${srcdir:=.}/simple_token.xml 
 > [Tremblay:node:(1) 0.000000] [SimpleToken/INFO] Create the token (with value 0) and send it to Fafard:4000
-> [Tremblay:node:(1) 0.011346] [SimpleToken/INFO] Begin a new loop. Still to do: 2
-> [Tremblay:node:(1) 0.022693] [SimpleToken/INFO] Begin a new loop. Still to do: 1
-> [Tremblay:node:(1) 0.034039] [SimpleToken/INFO] Begin a new loop. Still to do: 0
-> [Tremblay:node:(1) 0.034039] [SimpleToken/INFO] Shut down the token-ring. There was 14 hops.
-> [Fafard:node:(2) 1.028109] [gras/INFO] Exiting GRAS
-> [Jupiter:node:(3) 1.030844] [gras/INFO] Exiting GRAS
-> [Ginette:node:(4) 1.032083] [gras/INFO] Exiting GRAS
-> [Bourassa:node:(5) 1.034039] [gras/INFO] Exiting GRAS
-> [Tremblay:node:(1) 1.034039] [gras/INFO] Exiting GRAS
+> [Tremblay:node:(1) 0.117961] [SimpleToken/INFO] Begin a new loop. Still to do: 2
+> [Tremblay:node:(1) 0.235922] [SimpleToken/INFO] Begin a new loop. Still to do: 1
+> [Tremblay:node:(1) 0.353882] [SimpleToken/INFO] Begin a new loop. Still to do: 0
+> [Tremblay:node:(1) 0.353882] [SimpleToken/INFO] Shut down the token-ring. There was 14 hops.
+> [Fafard:node:(2) 1.292225] [gras/INFO] Exiting GRAS
+> [Jupiter:node:(3) 1.320657] [gras/INFO] Exiting GRAS
+> [Ginette:node:(4) 1.333546] [gras/INFO] Exiting GRAS
+> [Bourassa:node:(5) 1.353882] [gras/INFO] Exiting GRAS
+> [Tremblay:node:(1) 1.353882] [gras/INFO] Exiting GRAS
 

diff --git a/examples/gras/ping/test_sg_64 b/examples/gras/ping/test_sg_64
index 0564f8e..5d741f1 100755 (executable)
--- a/examples/gras/ping/test_sg_64
+++ b/examples/gras/ping/test_sg_64
@@ -3,17 +3,18 @@ p Runs the 'ping' test within the simulator (simulation times valid for 64bits a
 $ $SG_TEST_EXENV ./ping_simulator${EXEEXT:=} ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/ping.xml
 > [Tremblay:server:(1) 0.000000] [Ping/INFO] Launch server (port=4000)
 > [Fafard:client:(2) 0.000000] [Ping/INFO] Launch client (server on Tremblay:4000)
-> [Tremblay:server:(1) 0.000015] [Ping/INFO] Messages registered
-> [Tremblay:server:(1) 0.000015] [Ping/INFO] Messages registered
-> [Tremblay:server:(1) 0.000015] [Ping/INFO] >>>>>>>> Listening on port 4000 <<<<<<<<
-> [Fafard:client:(2) 0.000015] [Ping/INFO] Connected to Tremblay:4000.
-> [Fafard:client:(2) 0.000015] [Ping/INFO] Messages registered
-> [Fafard:client:(2) 0.000015] [Ping/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
-> [Fafard:client:(2) 0.001992] [Ping/INFO] >>>>>>>> Message PING(1234) sent to Tremblay:4000 <<<<<<<<
-> [Tremblay:server:(1) 0.001992] [Ping/INFO] >>>>>>>> Got message PING(1234) from Fafard:0 <<<<<<<<
-> [Tremblay:server:(1) 0.003969] [Ping/INFO] >>>>>>>> Answered with PONG(4321) <<<<<<<<
-> [Tremblay:server:(1) 0.003969] [Ping/INFO] Done.
-> [Tremblay:server:(1) 0.003969] [gras/INFO] Exiting GRAS
-> [Fafard:client:(2) 0.003969] [Ping/INFO] >>>>>>>> Got PONG(4321) from Tremblay:4000 <<<<<<<<
-> [Fafard:client:(2) 0.003969] [Ping/INFO] Done.
-> [Fafard:client:(2) 0.003969] [gras/INFO] Exiting GRAS
+> [Tremblay:server:(1) 0.000156] [Ping/INFO] Messages registered
+> [Tremblay:server:(1) 0.000156] [Ping/INFO] Messages registered
+> [Tremblay:server:(1) 0.000156] [Ping/INFO] >>>>>>>> Listening on port 4000 <<<<<<<<
+> [Fafard:client:(2) 0.000156] [Ping/INFO] Connected to Tremblay:4000.
+> [Fafard:client:(2) 0.000156] [Ping/INFO] Messages registered
+> [Fafard:client:(2) 0.000156] [Ping/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
+> [Fafard:client:(2) 0.020707] [Ping/INFO] >>>>>>>> Message PING(1234) sent to Tremblay:4000 <<<<<<<<
+> [Tremblay:server:(1) 0.020707] [Ping/INFO] >>>>>>>> Got message PING(1234) from Fafard:0 <<<<<<<<
+> [Tremblay:server:(1) 0.041259] [Ping/INFO] >>>>>>>> Answered with PONG(4321) <<<<<<<<
+> [Tremblay:server:(1) 0.041259] [Ping/INFO] Done.
+> [Tremblay:server:(1) 0.041259] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 0.041259] [Ping/INFO] >>>>>>>> Got PONG(4321) from Tremblay:4000 <<<<<<<<
+> [Fafard:client:(2) 0.041259] [Ping/INFO] Done.
+> [Fafard:client:(2) 0.041259] [gras/INFO] Exiting GRAS
+

diff --git a/examples/gras/rpc/test_sg_64 b/examples/gras/rpc/test_sg_64
index 0ed5f17..03e8af0 100755 (executable)
--- a/examples/gras/rpc/test_sg_64
+++ b/examples/gras/rpc/test_sg_64
@@ -6,8 +6,8 @@
 
 $ $SG_TEST_EXENV ./rpc_simulator$EXEEXT ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/rpc.xml --log=root.fmt:"'[%h:%P:(%i)] [%c/%p] %m%n'"
 > [Tremblay:server:(1)] [Rpc/INFO] Launch server (port=4000)
-> [Ginette:forwarder:(2)] [Rpc/INFO] Launch forwarder (port=4000)
 > [Fafard:client:(3)] [Rpc/INFO] Launch client (server on Tremblay:4000)
+> [Ginette:forwarder:(2)] [Rpc/INFO] Launch forwarder (port=4000)
 > [Tremblay:server:(1)] [Rpc/INFO] Listening on port 4000
 > [Fafard:client:(3)] [Rpc/INFO] Connected to Tremblay:4000.
 > [Fafard:client:(3)] [Rpc/INFO] Connected to server which is on Tremblay:4000

diff --git a/examples/gras/spawn/test_sg_64 b/examples/gras/spawn/test_sg_64
index 8f915ed..a679fba 100755 (executable)
--- a/examples/gras/spawn/test_sg_64
+++ b/examples/gras/spawn/test_sg_64
@@ -2,30 +2,31 @@ p Runs the 'spawn' test within the simulator (simulation times valid for 64bits
 
 $ $SG_TEST_EXENV ./spawn_simulator${EXEEXT:=} ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/spawn.xml
 > [Tremblay:father:(1) 0.000000] [Spawn/INFO] Spawn the kids
-> [Tremblay:child:(2) 0.000030] [Spawn/INFO] I (Tremblay:4001) have found my dady on Tremblay:4000.
-> [Tremblay:child:(3) 0.000030] [Spawn/INFO] I (Tremblay:4002) have found my dady on Tremblay:4000.
-> [Tremblay:child:(4) 0.000030] [Spawn/INFO] I (Tremblay:4003) have found my dady on Tremblay:4000.
-> [Tremblay:child:(5) 0.000030] [Spawn/INFO] I (Tremblay:4004) have found my dady on Tremblay:4000.
-> [Tremblay:child:(6) 0.000030] [Spawn/INFO] I (Tremblay:4005) have found my dady on Tremblay:4000.
-> [Tremblay:father:(1) 0.000045] [Spawn/INFO] Kid Tremblay:4001 pinged me with 1234
-> [Tremblay:father:(1) 0.000060] [Spawn/INFO] Answered to Tremblay:4001's request
-> [Tremblay:father:(1) 0.000060] [Spawn/INFO] Kid Tremblay:4002 pinged me with 1234
-> [Tremblay:child:(2) 0.000060] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(2) 0.000060] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000075] [Spawn/INFO] Answered to Tremblay:4002's request
-> [Tremblay:father:(1) 0.000075] [Spawn/INFO] Kid Tremblay:4003 pinged me with 1234
-> [Tremblay:child:(3) 0.000075] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(3) 0.000075] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000090] [Spawn/INFO] Answered to Tremblay:4003's request
-> [Tremblay:father:(1) 0.000090] [Spawn/INFO] Kid Tremblay:4004 pinged me with 1234
-> [Tremblay:child:(4) 0.000090] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(4) 0.000090] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000105] [Spawn/INFO] Answered to Tremblay:4004's request
-> [Tremblay:father:(1) 0.000105] [Spawn/INFO] Kid Tremblay:4005 pinged me with 1234
-> [Tremblay:child:(5) 0.000105] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(5) 0.000105] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000120] [Spawn/INFO] Answered to Tremblay:4005's request
-> [Tremblay:father:(1) 0.000120] [Spawn/INFO] All kids gone. Leave now.
-> [Tremblay:father:(1) 0.000120] [gras/INFO] Exiting GRAS
-> [Tremblay:child:(6) 0.000120] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(6) 0.000120] [gras/INFO] Exiting GRAS
+> [Tremblay:child:(2) 0.000312] [Spawn/INFO] I (Tremblay:4001) have found my dady on Tremblay:4000.
+> [Tremblay:child:(6) 0.000312] [Spawn/INFO] I (Tremblay:4002) have found my dady on Tremblay:4000.
+> [Tremblay:child:(3) 0.000312] [Spawn/INFO] I (Tremblay:4003) have found my dady on Tremblay:4000.
+> [Tremblay:child:(4) 0.000312] [Spawn/INFO] I (Tremblay:4004) have found my dady on Tremblay:4000.
+> [Tremblay:child:(5) 0.000312] [Spawn/INFO] I (Tremblay:4005) have found my dady on Tremblay:4000.
+> [Tremblay:father:(1) 0.000468] [Spawn/INFO] Kid Tremblay:4001 pinged me with 1234
+> [Tremblay:father:(1) 0.000624] [Spawn/INFO] Answered to Tremblay:4001's request
+> [Tremblay:father:(1) 0.000624] [Spawn/INFO] Kid Tremblay:4002 pinged me with 1234
+> [Tremblay:child:(2) 0.000624] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(2) 0.000624] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.000780] [Spawn/INFO] Answered to Tremblay:4002's request
+> [Tremblay:father:(1) 0.000780] [Spawn/INFO] Kid Tremblay:4003 pinged me with 1234
+> [Tremblay:child:(6) 0.000780] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(6) 0.000780] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.000936] [Spawn/INFO] Answered to Tremblay:4003's request
+> [Tremblay:father:(1) 0.000936] [Spawn/INFO] Kid Tremblay:4004 pinged me with 1234
+> [Tremblay:child:(3) 0.000936] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(3) 0.000936] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.001092] [Spawn/INFO] Answered to Tremblay:4004's request
+> [Tremblay:father:(1) 0.001092] [Spawn/INFO] Kid Tremblay:4005 pinged me with 1234
+> [Tremblay:child:(4) 0.001092] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(4) 0.001092] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.001248] [Spawn/INFO] Answered to Tremblay:4005's request
+> [Tremblay:father:(1) 0.001248] [Spawn/INFO] All kids gone. Leave now.
+> [Tremblay:father:(1) 0.001248] [gras/INFO] Exiting GRAS
+> [Tremblay:child:(5) 0.001248] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(5) 0.001248] [gras/INFO] Exiting GRAS
+

diff --git a/examples/gras/synchro/test_sg_64 b/examples/gras/synchro/test_sg_64
index 3559d08..9310305 100755 (executable)
--- a/examples/gras/synchro/test_sg_64
+++ b/examples/gras/synchro/test_sg_64
@@ -20,192 +20,192 @@ $ $SG_TEST_EXENV ./synchro_simulator${EXEEXT:=} ${srcdir:=.}/../../msg/small_pla
 > [Tremblay:thread 1:(1) 0.030000] [philo/INFO] Thread 1 thinks
 > [Tremblay:thread 4:(1) 0.040000] [philo/INFO] Thread 4 is full
 > [Tremblay:thread 4:(1) 0.040000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 2:(1) 0.040000] [philo/INFO] Thread 2 gets hungry (lunch #1)
-> [Tremblay:thread 2:(1) 0.040000] [philo/INFO] Thread 2 eats
 > [Tremblay:thread 1:(1) 0.040000] [philo/INFO] Thread 1 gets hungry (lunch #1)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #2)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #3)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #4)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #5)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #6)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #7)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #8)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 gets hungry (lunch #9)
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 eats
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 is full
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 thinks
-> [Tremblay:thread 0:(1) 0.040000] [philo/INFO] Thread 0 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:thread 2:(1) 0.060000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.060000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.040000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 2:(1) 0.040000] [philo/INFO] Thread 2 gets hungry (lunch #1)
+> [Tremblay:thread 3:(1) 0.040000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 1:(1) 0.050000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.050000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #2)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #3)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #4)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #5)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #6)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #7)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #8)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 gets hungry (lunch #9)
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 0:(1) 0.050000] [philo/INFO] Thread 0 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 1:(1) 0.060000] [philo/INFO] Thread 1 gets hungry (lunch #2)
 > [Tremblay:thread 1:(1) 0.060000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.060000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.070000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.070000] [philo/INFO] Thread 3 thinks
 > [Tremblay:thread 1:(1) 0.070000] [philo/INFO] Thread 1 is full
 > [Tremblay:thread 1:(1) 0.070000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.070000] [philo/INFO] Thread 2 eats
 > [Tremblay:thread 4:(1) 0.080000] [philo/INFO] Thread 4 gets hungry (lunch #1)
-> [Tremblay:thread 2:(1) 0.080000] [philo/INFO] Thread 2 gets hungry (lunch #2)
-> [Tremblay:thread 1:(1) 0.080000] [philo/INFO] Thread 1 gets hungry (lunch #2)
-> [Tremblay:thread 1:(1) 0.080000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.090000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.090000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 1:(1) 0.090000] [philo/INFO] Thread 1 is full
-> [Tremblay:thread 1:(1) 0.090000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 2:(1) 0.090000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 4:(1) 0.090000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 1:(1) 0.100000] [philo/INFO] Thread 1 gets hungry (lunch #3)
-> [Tremblay:thread 2:(1) 0.110000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.110000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 4:(1) 0.080000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 1:(1) 0.080000] [philo/INFO] Thread 1 gets hungry (lunch #3)
+> [Tremblay:thread 2:(1) 0.090000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.090000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.090000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.100000] [philo/INFO] Thread 3 gets hungry (lunch #1)
+> [Tremblay:thread 1:(1) 0.100000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.100000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 1:(1) 0.110000] [philo/INFO] Thread 1 gets hungry (lunch #4)
 > [Tremblay:thread 1:(1) 0.110000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.120000] [philo/INFO] Thread 3 gets hungry (lunch #1)
+> [Tremblay:thread 2:(1) 0.110000] [philo/INFO] Thread 2 gets hungry (lunch #2)
+> [Tremblay:thread 4:(1) 0.120000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.120000] [philo/INFO] Thread 4 thinks
 > [Tremblay:thread 1:(1) 0.120000] [philo/INFO] Thread 1 is full
 > [Tremblay:thread 1:(1) 0.120000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 4:(1) 0.130000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.130000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 2:(1) 0.130000] [philo/INFO] Thread 2 gets hungry (lunch #3)
-> [Tremblay:thread 2:(1) 0.130000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 1:(1) 0.130000] [philo/INFO] Thread 1 gets hungry (lunch #4)
-> [Tremblay:thread 2:(1) 0.150000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.150000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.120000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 1:(1) 0.130000] [philo/INFO] Thread 1 gets hungry (lunch #5)
+> [Tremblay:thread 1:(1) 0.130000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 1:(1) 0.140000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.140000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 3:(1) 0.150000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.150000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 1:(1) 0.150000] [philo/INFO] Thread 1 gets hungry (lunch #6)
 > [Tremblay:thread 1:(1) 0.150000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.150000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.160000] [philo/INFO] Thread 4 gets hungry (lunch #2)
+> [Tremblay:thread 4:(1) 0.160000] [philo/INFO] Thread 4 eats
 > [Tremblay:thread 1:(1) 0.160000] [philo/INFO] Thread 1 is full
 > [Tremblay:thread 1:(1) 0.160000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 4:(1) 0.170000] [philo/INFO] Thread 4 gets hungry (lunch #2)
-> [Tremblay:thread 2:(1) 0.170000] [philo/INFO] Thread 2 gets hungry (lunch #4)
-> [Tremblay:thread 1:(1) 0.170000] [philo/INFO] Thread 1 gets hungry (lunch #5)
-> [Tremblay:thread 1:(1) 0.170000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.180000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.180000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 1:(1) 0.180000] [philo/INFO] Thread 1 is full
-> [Tremblay:thread 1:(1) 0.180000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 2:(1) 0.180000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 4:(1) 0.180000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 1:(1) 0.190000] [philo/INFO] Thread 1 gets hungry (lunch #6)
-> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 thinks
-> [Tremblay:thread 1:(1) 0.200000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.210000] [philo/INFO] Thread 3 gets hungry (lunch #2)
-> [Tremblay:thread 1:(1) 0.210000] [philo/INFO] Thread 1 is full
-> [Tremblay:thread 1:(1) 0.210000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 4:(1) 0.220000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.220000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 gets hungry (lunch #5)
-> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 1:(1) 0.220000] [philo/INFO] Thread 1 gets hungry (lunch #7)
-> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 2:(1) 0.160000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.170000] [philo/INFO] Thread 1 gets hungry (lunch #7)
+> [Tremblay:thread 3:(1) 0.180000] [philo/INFO] Thread 3 gets hungry (lunch #2)
+> [Tremblay:thread 2:(1) 0.180000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.180000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.180000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 1:(1) 0.190000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.190000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 gets hungry (lunch #3)
+> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.200000] [philo/INFO] Thread 1 gets hungry (lunch #8)
+> [Tremblay:thread 4:(1) 0.200000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.200000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.220000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.220000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 1:(1) 0.230000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.230000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 gets hungry (lunch #4)
+> [Tremblay:thread 1:(1) 0.240000] [philo/INFO] Thread 1 gets hungry (lunch #9)
 > [Tremblay:thread 1:(1) 0.240000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.240000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.240000] [philo/INFO] Thread 4 gets hungry (lunch #3)
+> [Tremblay:thread 3:(1) 0.250000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.250000] [philo/INFO] Thread 3 thinks
 > [Tremblay:thread 1:(1) 0.250000] [philo/INFO] Thread 1 is full
 > [Tremblay:thread 1:(1) 0.250000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 4:(1) 0.260000] [philo/INFO] Thread 4 gets hungry (lunch #3)
-> [Tremblay:thread 2:(1) 0.260000] [philo/INFO] Thread 2 gets hungry (lunch #6)
-> [Tremblay:thread 1:(1) 0.260000] [philo/INFO] Thread 1 gets hungry (lunch #8)
-> [Tremblay:thread 1:(1) 0.260000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.270000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.270000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 1:(1) 0.270000] [philo/INFO] Thread 1 is full
-> [Tremblay:thread 1:(1) 0.270000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 2:(1) 0.270000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 4:(1) 0.270000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 1:(1) 0.280000] [philo/INFO] Thread 1 gets hungry (lunch #9)
-> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 thinks
-> [Tremblay:thread 1:(1) 0.290000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.300000] [philo/INFO] Thread 3 gets hungry (lunch #3)
-> [Tremblay:thread 1:(1) 0.300000] [philo/INFO] Thread 1 is full
-> [Tremblay:thread 1:(1) 0.300000] [philo/INFO] Thread 1 thinks
-> [Tremblay:thread 4:(1) 0.310000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.310000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 gets hungry (lunch #7)
-> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 1:(1) 0.310000] [philo/INFO] Thread 1 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 thinks
-> [Tremblay:thread 3:(1) 0.330000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 4:(1) 0.350000] [philo/INFO] Thread 4 gets hungry (lunch #4)
-> [Tremblay:thread 2:(1) 0.350000] [philo/INFO] Thread 2 gets hungry (lunch #8)
-> [Tremblay:thread 3:(1) 0.360000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.360000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 2:(1) 0.360000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 4:(1) 0.360000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 thinks
-> [Tremblay:thread 3:(1) 0.390000] [philo/INFO] Thread 3 gets hungry (lunch #4)
-> [Tremblay:thread 4:(1) 0.400000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.400000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 gets hungry (lunch #9)
-> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 eats
-> [Tremblay:thread 2:(1) 0.420000] [philo/INFO] Thread 2 is full
-> [Tremblay:thread 2:(1) 0.420000] [philo/INFO] Thread 2 thinks
-> [Tremblay:thread 3:(1) 0.420000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 4:(1) 0.440000] [philo/INFO] Thread 4 gets hungry (lunch #5)
-> [Tremblay:thread 2:(1) 0.440000] [philo/INFO] Thread 2 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:thread 3:(1) 0.450000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.450000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.450000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 3:(1) 0.480000] [philo/INFO] Thread 3 gets hungry (lunch #5)
-> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.250000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.250000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 1:(1) 0.260000] [philo/INFO] Thread 1 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 2:(1) 0.270000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.270000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.280000] [philo/INFO] Thread 3 gets hungry (lunch #3)
+> [Tremblay:thread 4:(1) 0.290000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.290000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.310000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.330000] [philo/INFO] Thread 4 gets hungry (lunch #4)
+> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 gets hungry (lunch #6)
+> [Tremblay:thread 3:(1) 0.340000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.340000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 2:(1) 0.340000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.340000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 2:(1) 0.360000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.360000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.370000] [philo/INFO] Thread 3 gets hungry (lunch #4)
+> [Tremblay:thread 4:(1) 0.380000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.380000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 gets hungry (lunch #7)
+> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.400000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.420000] [philo/INFO] Thread 4 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.420000] [philo/INFO] Thread 2 gets hungry (lunch #8)
+> [Tremblay:thread 3:(1) 0.430000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.430000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 2:(1) 0.430000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.430000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 2:(1) 0.450000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.450000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.460000] [philo/INFO] Thread 3 gets hungry (lunch #5)
+> [Tremblay:thread 4:(1) 0.470000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.470000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.470000] [philo/INFO] Thread 2 gets hungry (lunch #9)
+> [Tremblay:thread 2:(1) 0.470000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 2:(1) 0.490000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.490000] [philo/INFO] Thread 2 thinks
 > [Tremblay:thread 3:(1) 0.490000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.510000] [philo/INFO] Thread 4 gets hungry (lunch #6)
+> [Tremblay:thread 2:(1) 0.510000] [philo/INFO] Thread 2 tries to enter the dead-end; hopefully, the master will cancel it
 > [Tremblay:thread 3:(1) 0.520000] [philo/INFO] Thread 3 is full
 > [Tremblay:thread 3:(1) 0.520000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.530000] [philo/INFO] Thread 4 gets hungry (lunch #6)
-> [Tremblay:thread 4:(1) 0.530000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 4:(1) 0.520000] [philo/INFO] Thread 4 eats
 > [Tremblay:thread 3:(1) 0.550000] [philo/INFO] Thread 3 gets hungry (lunch #6)
-> [Tremblay:thread 4:(1) 0.570000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.570000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 3:(1) 0.570000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 3:(1) 0.600000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.600000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.610000] [philo/INFO] Thread 4 gets hungry (lunch #7)
-> [Tremblay:thread 4:(1) 0.610000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 3:(1) 0.630000] [philo/INFO] Thread 3 gets hungry (lunch #7)
-> [Tremblay:thread 4:(1) 0.650000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.650000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 3:(1) 0.650000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 3:(1) 0.680000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.680000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.690000] [philo/INFO] Thread 4 gets hungry (lunch #8)
-> [Tremblay:thread 4:(1) 0.690000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 3:(1) 0.710000] [philo/INFO] Thread 3 gets hungry (lunch #8)
-> [Tremblay:thread 4:(1) 0.730000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.730000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 3:(1) 0.730000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 3:(1) 0.760000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.760000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.770000] [philo/INFO] Thread 4 gets hungry (lunch #9)
-> [Tremblay:thread 4:(1) 0.770000] [philo/INFO] Thread 4 eats
-> [Tremblay:thread 3:(1) 0.790000] [philo/INFO] Thread 3 gets hungry (lunch #9)
-> [Tremblay:thread 4:(1) 0.810000] [philo/INFO] Thread 4 is full
-> [Tremblay:thread 4:(1) 0.810000] [philo/INFO] Thread 4 thinks
-> [Tremblay:thread 3:(1) 0.810000] [philo/INFO] Thread 3 eats
-> [Tremblay:thread 3:(1) 0.840000] [philo/INFO] Thread 3 is full
-> [Tremblay:thread 3:(1) 0.840000] [philo/INFO] Thread 3 thinks
-> [Tremblay:thread 4:(1) 0.850000] [philo/INFO] Thread 4 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:thread 3:(1) 0.870000] [philo/INFO] Thread 3 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:philosopher:(1) 0.870000] [philo/INFO] Cancel all childs
-> [Tremblay:philosopher:(1) 0.870000] [gras/INFO] Exiting GRAS
+> [Tremblay:thread 4:(1) 0.560000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.560000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.560000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.590000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.590000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.600000] [philo/INFO] Thread 4 gets hungry (lunch #7)
+> [Tremblay:thread 4:(1) 0.600000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.620000] [philo/INFO] Thread 3 gets hungry (lunch #7)
+> [Tremblay:thread 4:(1) 0.640000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.640000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.640000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.670000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.670000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.680000] [philo/INFO] Thread 4 gets hungry (lunch #8)
+> [Tremblay:thread 4:(1) 0.680000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.700000] [philo/INFO] Thread 3 gets hungry (lunch #8)
+> [Tremblay:thread 4:(1) 0.720000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.720000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.720000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.750000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.750000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.760000] [philo/INFO] Thread 4 gets hungry (lunch #9)
+> [Tremblay:thread 4:(1) 0.760000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.780000] [philo/INFO] Thread 3 gets hungry (lunch #9)
+> [Tremblay:thread 4:(1) 0.800000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.800000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.800000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.830000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.830000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.840000] [philo/INFO] Thread 4 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 3:(1) 0.860000] [philo/INFO] Thread 3 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:philosopher:(1) 0.860000] [philo/INFO] Cancel all childs
+> [Tremblay:philosopher:(1) 0.860000] [gras/INFO] Exiting GRAS

diff --git a/teshsuite/gras/msg_handle/test_sg_64 b/teshsuite/gras/msg_handle/test_sg_64
index eaff41d..f6a6f35 100755 (executable)
--- a/teshsuite/gras/msg_handle/test_sg_64
+++ b/teshsuite/gras/msg_handle/test_sg_64
@@ -3,12 +3,13 @@ p Runs the 'msg_handle' test within the simulator (simulation times valid for 64
 $ $SG_TEST_EXENV gras/msg_handle/msg_handle_simulator$EXEEXT ${srcdir:=.}/../examples/msg/small_platform.xml ${srcdir:=.}/gras/msg_handle/msg_handle.xml
 > [Tremblay:server:(1) 0.000000] [test/INFO] Launch server (port=4000)
 > [Fafard:client:(2) 0.000000] [test/INFO] Launch client (port=4000)
-> [Tremblay:server:(1) 1.000030] [test/INFO] Initialization done.
-> [Tremblay:server:(1) 1.000030] [test/INFO] gras_msg_handle(0) works as expected (immediate timeout)
-> [Fafard:client:(2) 1.000030] [test/INFO] Initialization done.
-> [Tremblay:server:(1) 2.000030] [test/INFO] gras_msg_handle(1) works as expected (delayed timeout)
-> [Tremblay:server:(1) 5.000030] [test/INFO] Unlock pal
-> [Tremblay:server:(1) 5.002006] [gras/INFO] Exiting GRAS
-> [Fafard:client:(2) 5.002006] [test/INFO] Got the message
-> [Fafard:client:(2) 5.002006] [test/INFO] gras_msg_handle(-1) works as expected (locked)
-> [Fafard:client:(2) 5.002006] [gras/INFO] Exiting GRAS
+> [Tremblay:server:(1) 1.000312] [test/INFO] Initialization done.
+> [Tremblay:server:(1) 1.000312] [test/INFO] gras_msg_handle(0) works as expected (immediate timeout)
+> [Fafard:client:(2) 1.000312] [test/INFO] Initialization done.
+> [Tremblay:server:(1) 2.000312] [test/INFO] gras_msg_handle(1) works as expected (delayed timeout)
+> [Tremblay:server:(1) 5.000312] [test/INFO] Unlock pal
+> [Tremblay:server:(1) 5.020863] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 5.020863] [test/INFO] Got the message
+> [Fafard:client:(2) 5.020863] [test/INFO] gras_msg_handle(-1) works as expected (locked)
+> [Fafard:client:(2) 5.020863] [gras/INFO] Exiting GRAS
+