Revalidating some GRAS tests.

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

diff --git a/examples/gras/all2all/test_sg_32 b/examples/gras/all2all/test_sg_32
index a03145c..e8f8036 100755 (executable)
--- a/examples/gras/all2all/test_sg_32
+++ b/examples/gras/all2all/test_sg_32
@@ -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_32 b/examples/gras/mmrpc/test_sg_32
index 0d64953..4522d96 100755 (executable)
--- a/examples/gras/mmrpc/test_sg_32
+++ b/examples/gras/mmrpc/test_sg_32
@@ -1,10 +1,9 @@
 $ $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.053798] [MatMult/INFO] >>>>>>>> Request sent to Tremblay:4000 <<<<<<<<
-> [Tremblay:server:(1) 0.081677] [gras/INFO] Exiting GRAS
-> [Fafard:client:(2) 0.081677] [MatMult/INFO] >>>>>>>> Got answer from Tremblay:4000 (values are right) <<<<<<<<
-> [Fafard:client:(2) 0.081677] [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.072513] [MatMult/INFO] >>>>>>>> Request sent to Tremblay:4000 <<<<<<<<
+> [Tremblay:server:(1) 0.118967] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 0.118967] [MatMult/INFO] >>>>>>>> Got answer from Tremblay:4000 (values are right) <<<<<<<<
+> [Fafard:client:(2) 0.118967] [gras/INFO] Exiting GRAS

diff --git a/examples/gras/mutual_exclusion/simple_token/test_sg_32 b/examples/gras/mutual_exclusion/simple_token/test_sg_32
index 973516e..326cd73 100755 (executable)
--- a/examples/gras/mutual_exclusion/simple_token/test_sg_32
+++ b/examples/gras/mutual_exclusion/simple_token/test_sg_32
@@ -1,11 +1,11 @@
 $ $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_32 b/examples/gras/ping/test_sg_32
index 7c26467..6411790 100755 (executable)
--- a/examples/gras/ping/test_sg_32
+++ b/examples/gras/ping/test_sg_32
@@ -3,17 +3,17 @@ p Runs the 'ping' test within the simulator (simulation times valid for 32bits a
 $ $SG_TEST_EXENV ./ping_simulator${EXEEXT:=} ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/ping.xml $ARGS
 > [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/spawn/test_sg_32 b/examples/gras/spawn/test_sg_32
index 99ef818..96758b4 100755 (executable)
--- a/examples/gras/spawn/test_sg_32
+++ b/examples/gras/spawn/test_sg_32
@@ -2,30 +2,30 @@ p Runs the 'spawn' test within the simulator (simulation times valid for 32bits
 
 $ $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_32 b/examples/gras/synchro/test_sg_32
index 3559d08..236befc 100755 (executable)
--- a/examples/gras/synchro/test_sg_32
+++ b/examples/gras/synchro/test_sg_32
@@ -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 1:(1) 0.150000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.150000] [philo/INFO] Thread 3 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 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 2:(1) 0.150000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.150000] [philo/INFO] Thread 1 gets hungry (lunch #6)
+> [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 2:(1) 0.170000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.170000] [philo/INFO] Thread 2 thinks
 > [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 3:(1) 0.180000] [philo/INFO] Thread 3 gets hungry (lunch #2)
 > [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 1:(1) 0.240000] [philo/INFO] Thread 1 eats
-> [Tremblay:thread 3:(1) 0.240000] [philo/INFO] Thread 3 eats
-> [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 2:(1) 0.190000] [philo/INFO] Thread 2 gets hungry (lunch #3)
+> [Tremblay:thread 2:(1) 0.190000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.190000] [philo/INFO] Thread 1 gets hungry (lunch #7)
+> [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.210000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.210000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.210000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.210000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 1:(1) 0.220000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.220000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.230000] [philo/INFO] Thread 2 gets hungry (lunch #4)
+> [Tremblay:thread 1:(1) 0.230000] [philo/INFO] Thread 1 gets hungry (lunch #8)
+> [Tremblay:thread 1:(1) 0.230000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.240000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.240000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 1:(1) 0.240000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.240000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.240000] [philo/INFO] Thread 4 gets hungry (lunch #3)
+> [Tremblay:thread 4:(1) 0.240000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 1:(1) 0.250000] [philo/INFO] Thread 1 gets hungry (lunch #9)
+> [Tremblay:thread 2:(1) 0.260000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.260000] [philo/INFO] Thread 2 thinks
 > [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 3:(1) 0.270000] [philo/INFO] Thread 3 gets hungry (lunch #3)
 > [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 4:(1) 0.280000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.280000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 1:(1) 0.280000] [philo/INFO] Thread 1 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 2:(1) 0.280000] [philo/INFO] Thread 2 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.280000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 2:(1) 0.300000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.300000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.300000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.320000] [philo/INFO] Thread 4 gets hungry (lunch #4)
+> [Tremblay:thread 2:(1) 0.320000] [philo/INFO] Thread 2 gets hungry (lunch #6)
+> [Tremblay:thread 3:(1) 0.330000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.330000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.330000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 2:(1) 0.350000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.350000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.360000] [philo/INFO] Thread 3 gets hungry (lunch #4)
+> [Tremblay:thread 4:(1) 0.370000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.370000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.370000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 2:(1) 0.370000] [philo/INFO] Thread 2 gets hungry (lunch #7)
+> [Tremblay:thread 3:(1) 0.400000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.400000] [philo/INFO] Thread 3 thinks
 > [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.410000] [philo/INFO] Thread 4 gets hungry (lunch #5)
+> [Tremblay:thread 4:(1) 0.410000] [philo/INFO] Thread 4 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 3:(1) 0.490000] [philo/INFO] Thread 3 eats
-> [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 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 3:(1) 0.430000] [philo/INFO] Thread 3 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.440000] [philo/INFO] Thread 2 gets hungry (lunch #8)
+> [Tremblay:thread 2:(1) 0.440000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.450000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.450000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.460000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.460000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.460000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 2:(1) 0.480000] [philo/INFO] Thread 2 gets hungry (lunch #9)
+> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 gets hungry (lunch #6)
+> [Tremblay:thread 3:(1) 0.490000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.490000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 2:(1) 0.490000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 2:(1) 0.510000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.510000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.520000] [philo/INFO] Thread 3 gets hungry (lunch #6)
+> [Tremblay:thread 4:(1) 0.530000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.530000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.530000] [philo/INFO] Thread 2 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 3:(1) 0.530000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.560000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.560000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.570000] [philo/INFO] Thread 4 gets hungry (lunch #7)
+> [Tremblay:thread 4:(1) 0.570000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.590000] [philo/INFO] Thread 3 gets hungry (lunch #7)
+> [Tremblay:thread 4:(1) 0.610000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.610000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.610000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.640000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.640000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.650000] [philo/INFO] Thread 4 gets hungry (lunch #8)
+> [Tremblay:thread 4:(1) 0.650000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.670000] [philo/INFO] Thread 3 gets hungry (lunch #8)
+> [Tremblay:thread 4:(1) 0.690000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.690000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.690000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.720000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.720000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.730000] [philo/INFO] Thread 4 gets hungry (lunch #9)
+> [Tremblay:thread 4:(1) 0.730000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.750000] [philo/INFO] Thread 3 gets hungry (lunch #9)
+> [Tremblay:thread 4:(1) 0.770000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.770000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 3:(1) 0.770000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 3:(1) 0.800000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.800000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.810000] [philo/INFO] Thread 4 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 3:(1) 0.830000] [philo/INFO] Thread 3 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:philosopher:(1) 0.830000] [philo/INFO] Cancel all childs
+> [Tremblay:philosopher:(1) 0.830000] [gras/INFO] Exiting GRAS