From 8973ef91846ab9820d5f04fb6a1c16048758f72c Mon Sep 17 00:00:00 2001 From: donassbr Date: Fri, 25 Sep 2009 14:11:57 +0000 Subject: [PATCH] Revalidating some GRAS tests. git-svn-id: svn+ssh://scm.gforge.inria.fr/svn/simgrid/simgrid/trunk@6681 48e7efb5-ca39-0410-a469-dd3cf9ba447f --- examples/gras/all2all/test_sg_32 | 116 +++--- examples/gras/mmrpc/test_sg_32 | 13 +- .../mutual_exclusion/simple_token/test_sg_32 | 18 +- examples/gras/ping/test_sg_32 | 28 +- examples/gras/spawn/test_sg_32 | 54 +-- examples/gras/synchro/test_sg_32 | 344 +++++++++--------- 6 files changed, 286 insertions(+), 287 deletions(-) diff --git a/examples/gras/all2all/test_sg_32 b/examples/gras/all2all/test_sg_32 index a03145cc2b..e8f80363b3 100755 --- 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 0d64953022..4522d96565 100755 --- 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 973516e5cb..326cd73d15 100755 --- 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 7c264675be..6411790d55 100755 --- 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 99ef818d87..96758b4b67 100755 --- 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 3559d08cb9..236befc1a6 100755 --- 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 -- 2.20.1