> [Ginette:receiver:(8) 0.000000] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
> [Bourassa:sender:(9) 0.000000] [all2all/INFO] Launch current node
> [Bourassa:receiver:(10) 0.000000] [all2all/INFO] Listening on port 4000 (expecting 4 messages)
-> [Tremblay:receiver:(2) 0.001536] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
> [Jupiter:sender:(3) 0.001536] [all2all/INFO] Sent Data from Jupiter to Tremblay
-> [Tremblay:receiver:(2) 0.003614] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
+> [Tremblay:receiver:(2) 0.001536] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
> [Fafard:sender:(5) 0.003614] [all2all/INFO] Sent Data from Fafard to Tremblay
-> [Tremblay:receiver:(2) 0.004951] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
+> [Tremblay:receiver:(2) 0.003614] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
> [Ginette:sender:(7) 0.004951] [all2all/INFO] Sent Data from Ginette to Tremblay
-> [Fafard:receiver:(6) 0.005150] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
+> [Tremblay:receiver:(2) 0.004951] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
> [Jupiter:sender:(3) 0.005150] [all2all/INFO] Sent Data from Jupiter to Fafard
+> [Fafard:receiver:(6) 0.005150] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
+> [Bourassa:sender:(9) 0.007007] [all2all/INFO] Sent Data from Bourassa to Tremblay
> [Tremblay:receiver:(2) 0.007007] [all2all/INFO] Got Data from Bourassa:0 (still 0 to go)
> [Tremblay:receiver:(2) 0.007007] [gras/INFO] Exiting GRAS
-> [Bourassa:sender:(9) 0.007007] [all2all/INFO] Sent Data from Bourassa to Tremblay
-> [Jupiter:receiver:(4) 0.007228] [all2all/INFO] Got Data from Fafard:0 (still 3 to go)
> [Fafard:sender:(5) 0.007228] [all2all/INFO] Sent Data from Fafard to Jupiter
-> [Ginette:receiver:(8) 0.008024] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
+> [Jupiter:receiver:(4) 0.007228] [all2all/INFO] Got Data from Fafard:0 (still 3 to go)
> [Jupiter:sender:(3) 0.008024] [all2all/INFO] Sent Data from Jupiter to Ginette
-> [Ginette:receiver:(8) 0.009349] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
+> [Ginette:receiver:(8) 0.008024] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
> [Fafard:sender:(5) 0.009349] [all2all/INFO] Sent Data from Fafard to Ginette
-> [Jupiter:receiver:(4) 0.010102] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
+> [Ginette:receiver:(8) 0.009349] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
> [Ginette:sender:(7) 0.010102] [all2all/INFO] Sent Data from Ginette to Jupiter
-> [Fafard:receiver:(6) 0.011426] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
+> [Jupiter:receiver:(4) 0.010102] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
> [Ginette:sender:(7) 0.011426] [all2all/INFO] Sent Data from Ginette to Fafard
-> [Bourassa:receiver:(10) 0.011617] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
+> [Fafard:receiver:(6) 0.011426] [all2all/INFO] Got Data from Ginette:0 (still 2 to go)
> [Jupiter:sender:(3) 0.011617] [all2all/INFO] Sent Data from Jupiter to Bourassa
> [Jupiter:sender:(3) 0.011617] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.012200] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
+> [Bourassa:receiver:(10) 0.011617] [all2all/INFO] Got Data from Jupiter:0 (still 3 to go)
> [Fafard:sender:(5) 0.012200] [all2all/INFO] Sent Data from Fafard to Bourassa
> [Fafard:sender:(5) 0.012200] [gras/INFO] Exiting GRAS
-> [Bourassa:receiver:(10) 0.013503] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
+> [Bourassa:receiver:(10) 0.012200] [all2all/INFO] Got Data from Fafard:0 (still 2 to go)
> [Ginette:sender:(7) 0.013503] [all2all/INFO] Sent Data from Ginette to Bourassa
> [Ginette:sender:(7) 0.013503] [gras/INFO] Exiting GRAS
-> [Jupiter:receiver:(4) 0.013694] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
+> [Bourassa:receiver:(10) 0.013503] [all2all/INFO] Got Data from Ginette:0 (still 1 to go)
> [Bourassa:sender:(9) 0.013694] [all2all/INFO] Sent Data from Bourassa to Jupiter
-> [Fafard:receiver:(6) 0.014277] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
+> [Jupiter:receiver:(4) 0.013694] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
> [Bourassa:sender:(9) 0.014277] [all2all/INFO] Sent Data from Bourassa to Fafard
+> [Fafard:receiver:(6) 0.014277] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
+> [Tremblay:sender:(1) 0.015230] [all2all/INFO] Sent Data from Tremblay to Jupiter
> [Jupiter:receiver:(4) 0.015230] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
> [Jupiter:receiver:(4) 0.015230] [gras/INFO] Exiting GRAS
-> [Tremblay:sender:(1) 0.015230] [all2all/INFO] Sent Data from Tremblay to Jupiter
-> [Ginette:receiver:(8) 0.015580] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
> [Bourassa:sender:(9) 0.015580] [all2all/INFO] Sent Data from Bourassa to Ginette
> [Bourassa:sender:(9) 0.015580] [gras/INFO] Exiting GRAS
+> [Ginette:receiver:(8) 0.015580] [all2all/INFO] Got Data from Bourassa:0 (still 1 to go)
+> [Tremblay:sender:(1) 0.017308] [all2all/INFO] Sent Data from Tremblay to Fafard
> [Fafard:receiver:(6) 0.017308] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
> [Fafard:receiver:(6) 0.017308] [gras/INFO] Exiting GRAS
-> [Tremblay:sender:(1) 0.017308] [all2all/INFO] Sent Data from Tremblay to Fafard
+> [Tremblay:sender:(1) 0.018645] [all2all/INFO] Sent Data from Tremblay to Ginette
> [Ginette:receiver:(8) 0.018645] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
> [Ginette:receiver:(8) 0.018645] [gras/INFO] Exiting GRAS
-> [Tremblay:sender:(1) 0.018645] [all2all/INFO] Sent Data from Tremblay to Ginette
-> [Bourassa:receiver:(10) 0.020701] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
-> [Bourassa:receiver:(10) 0.020701] [gras/INFO] Exiting GRAS
> [Tremblay:sender:(1) 0.020701] [all2all/INFO] Sent Data from Tremblay to Bourassa
> [Tremblay:sender:(1) 0.020701] [gras/INFO] Exiting GRAS
+> [Bourassa:receiver:(10) 0.020701] [all2all/INFO] Got Data from Tremblay:0 (still 0 to go)
+> [Bourassa:receiver:(10) 0.020701] [gras/INFO] Exiting GRAS
> [Fafard:client:(2) 0.000000] [MatMult/INFO] Connected to Tremblay:4000.
> [Fafard:client:(2) 0.000000] [MatMult/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
> [Fafard:client:(2) 0.053786] [MatMult/INFO] >>>>>>>> Request sent to Tremblay:4000 <<<<<<<<
+> [Tremblay:server:(1) 0.081667] [gras/INFO] Exiting GRAS
> [Fafard:client:(2) 0.081667] [MatMult/INFO] >>>>>>>> Got answer from Tremblay:4000 (values are right) <<<<<<<<
> [Fafard:client:(2) 0.081667] [gras/INFO] Exiting GRAS
-> [Tremblay:server:(1) 0.081667] [gras/INFO] Exiting GRAS
> [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
-> [Tremblay:node:(1) 1.034039] [gras/INFO] Exiting GRAS
> [Bourassa:node:(5) 1.034039] [gras/INFO] Exiting GRAS
+> [Tremblay:node:(1) 1.034039] [gras/INFO] Exiting GRAS
> [Fafard:client:(2) 1.000000] [Ping/INFO] Connected to Tremblay:4000.
> [Fafard:client:(2) 1.000000] [Ping/INFO] Messages registered
> [Fafard:client:(2) 1.000000] [Ping/INFO] >>>>>>>> Connected to server which is on Tremblay:4000 <<<<<<<<
-> [Tremblay:server:(1) 1.001977] [Ping/INFO] >>>>>>>> Got message PING(1234) from Fafard:0 <<<<<<<<
> [Fafard:client:(2) 1.001977] [Ping/INFO] >>>>>>>> Message PING(1234) sent to Tremblay:4000 <<<<<<<<
-> [Fafard:client:(2) 1.003954] [Ping/INFO] >>>>>>>> Got PONG(4321) from Tremblay:4000 <<<<<<<<
-> [Fafard:client:(2) 1.003954] [Ping/INFO] Done.
-> [Fafard:client:(2) 1.003954] [gras/INFO] Exiting GRAS
+> [Tremblay:server:(1) 1.001977] [Ping/INFO] >>>>>>>> Got message PING(1234) from Fafard:0 <<<<<<<<
> [Tremblay:server:(1) 1.003954] [Ping/INFO] >>>>>>>> Answered with PONG(4321) <<<<<<<<
> [Tremblay:server:(1) 1.003954] [Ping/INFO] Done.
> [Tremblay:server:(1) 1.003954] [gras/INFO] Exiting GRAS
+> [Fafard:client:(2) 1.003954] [Ping/INFO] >>>>>>>> Got PONG(4321) from Tremblay:4000 <<<<<<<<
+> [Fafard:client:(2) 1.003954] [Ping/INFO] Done.
+> [Fafard:client:(2) 1.003954] [gras/INFO] Exiting GRAS
> [Jill:slave:(20) 0.000000] [pmm/INFO] Sensor starting (on port 3000)
> [Jacquelin:master:(1) 5.000000] [pmm/INFO] Got 19 pals
> [Jacquelin:master:(1) 5.000000] [pmm/INFO] Too much slaves. Killing Tanguay:3000
-> [Tanguay:slave:(11) 5.076159] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.076159] [pmm/INFO] Too much slaves. Killing Morin:3000
-> [Morin:slave:(12) 5.219451] [gras/INFO] Exiting GRAS
+> [Tanguay:slave:(11) 5.076159] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.219451] [pmm/INFO] Too much slaves. Killing Ethernet:3000
-> [Ethernet:slave:(13) 5.300942] [gras/INFO] Exiting GRAS
+> [Morin:slave:(12) 5.219451] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.300942] [pmm/INFO] Too much slaves. Killing Bellemarre:3000
-> [Bellemarre:slave:(14) 5.440115] [gras/INFO] Exiting GRAS
+> [Ethernet:slave:(13) 5.300942] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.440115] [pmm/INFO] Too much slaves. Killing Kuenning:3000
-> [Kuenning:slave:(15) 5.506011] [gras/INFO] Exiting GRAS
+> [Bellemarre:slave:(14) 5.440115] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.506011] [pmm/INFO] Too much slaves. Killing Gaston:3000
-> [Gaston:slave:(16) 5.579481] [gras/INFO] Exiting GRAS
+> [Kuenning:slave:(15) 5.506011] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.579481] [pmm/INFO] Too much slaves. Killing Bousquet:3000
-> [Bousquet:slave:(17) 5.657473] [gras/INFO] Exiting GRAS
+> [Gaston:slave:(16) 5.579481] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.657473] [pmm/INFO] Too much slaves. Killing Paul:3000
-> [Paul:slave:(18) 5.761626] [gras/INFO] Exiting GRAS
+> [Bousquet:slave:(17) 5.657473] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.761626] [pmm/INFO] Too much slaves. Killing St_Jean:3000
-> [St_Jean:slave:(19) 5.900924] [gras/INFO] Exiting GRAS
+> [Paul:slave:(18) 5.761626] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 5.900924] [pmm/INFO] Too much slaves. Killing Jill:3000
-> [Jill:slave:(20) 6.053308] [gras/INFO] Exiting GRAS
+> [St_Jean:slave:(19) 5.900924] [gras/INFO] Exiting GRAS
> [Jacquelin:master:(1) 6.053308] [pmm/INFO] XXXXXXXXXXXXXXXXXXXXXX begin Multiplication
+> [Jill:slave:(20) 6.053308] [gras/INFO] Exiting GRAS
> [Boivin:slave:(2) 7.139363] [pmm/INFO] Receive my pos (0,0) and assignment
> [Boivin:slave:(2) 7.139363] [pmm/INFO] LINE: step(0) = Myline(0). Broadcast my data (myport=3000).
> [Boivin:slave:(2) 7.139363] [pmm/INFO] LINE: Send to Geoff:3000
> [Jean_Yves:slave:(3) 7.215254] [pmm/INFO] Receive my pos (0,1) and assignment
> [Jean_Yves:slave:(3) 7.215254] [pmm/INFO] LINE: step(0) = Myline(0). Broadcast my data (myport=3000).
> [Jean_Yves:slave:(3) 7.215254] [pmm/INFO] LINE: Send to Disney:3000
+> [Boivin:slave:(2) 7.222076] [pmm/INFO] LINE: Send to McGee:3000
+> [Boivin:slave:(2) 7.230383] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
+> [Boivin:slave:(2) 7.230383] [pmm/INFO] ROW: Send to Jean_Yves:3000
> [TeX:slave:(4) 7.364023] [pmm/INFO] Receive my pos (0,2) and assignment
> [TeX:slave:(4) 7.364023] [pmm/INFO] LINE: step(0) = Myline(0). Broadcast my data (myport=3000).
> [TeX:slave:(4) 7.364023] [pmm/INFO] LINE: Send to iRMX:3000
> [Geoff:slave:(5) 7.364694] [pmm/INFO] Receive my pos (1,0) and assignment
-> [Geoff:slave:(5) 7.447407] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from Boivin:3000
-> [Geoff:slave:(5) 7.447407] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
-> [Geoff:slave:(5) 7.447407] [pmm/INFO] ROW: Send to Disney:3000
-> [Boivin:slave:(2) 7.447407] [pmm/INFO] LINE: Send to McGee:3000
+> [Geoff:slave:(5) 7.364694] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from Boivin:3000
+> [Geoff:slave:(5) 7.364694] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
+> [Geoff:slave:(5) 7.364694] [pmm/INFO] ROW: Send to Disney:3000
+> [Boivin:slave:(2) 7.385233] [pmm/INFO] ROW: Send to TeX:3000
+> [Jean_Yves:slave:(3) 7.431505] [pmm/INFO] LINE: Send to Gatien:3000
> [Disney:slave:(6) 7.514333] [pmm/INFO] Receive my pos (1,1) and assignment
-> [Disney:slave:(6) 7.730584] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from Jean_Yves:3000
-> [Jean_Yves:slave:(3) 7.730584] [pmm/INFO] LINE: Send to Gatien:3000
+> [Disney:slave:(6) 7.514333] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from Jean_Yves:3000
+> [Geoff:slave:(5) 7.575619] [pmm/INFO] ROW: Send to iRMX:3000
+> [Disney:slave:(6) 7.575619] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from Geoff
+> [Disney:slave:(6) 7.575619] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
+> [Disney:slave:(6) 7.575619] [pmm/INFO] LINE: Send to Jean_Yves:3000
+> [Jean_Yves:slave:(3) 7.604232] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from Boivin
+> [TeX:slave:(4) 7.617066] [pmm/INFO] LINE: Send to Laroche:3000
> [iRMX:slave:(7) 7.764025] [pmm/INFO] Receive my pos (1,2) and assignment
+> [iRMX:slave:(7) 7.764025] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from TeX:3000
+> [TeX:slave:(4) 7.771585] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from Boivin
+> [Disney:slave:(6) 7.791870] [pmm/INFO] LINE: Send to Gatien:3000
+> [Jean_Yves:slave:(3) 7.791870] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from Disney:3000
+> [Jean_Yves:slave:(3) 7.791870] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
+> [Jean_Yves:slave:(3) 7.791870] [pmm/INFO] ROW: Send to Boivin:3000
> [McGee:slave:(8) 7.836924] [pmm/INFO] Receive my pos (2,0) and assignment
-> [McGee:slave:(8) 7.845230] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from Boivin:3000
-> [McGee:slave:(8) 7.845230] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
-> [McGee:slave:(8) 7.845230] [pmm/INFO] ROW: Send to Gatien:3000
-> [Boivin:slave:(2) 7.845230] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
-> [Boivin:slave:(2) 7.845230] [pmm/INFO] ROW: Send to Jean_Yves:3000
-> [Disney:slave:(6) 7.874698] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from Geoff
-> [Disney:slave:(6) 7.874698] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
-> [Disney:slave:(6) 7.874698] [pmm/INFO] LINE: Send to Jean_Yves:3000
-> [Geoff:slave:(5) 7.874698] [pmm/INFO] ROW: Send to iRMX:3000
+> [McGee:slave:(8) 7.836924] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from Boivin:3000
+> [McGee:slave:(8) 7.836924] [pmm/INFO] ROW: step(0)=myrow(0). Broadcast my data
+> [McGee:slave:(8) 7.836924] [pmm/INFO] ROW: Send to Gatien:3000
+> [Geoff:slave:(5) 7.857890] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
+> [Geoff:slave:(5) 7.857890] [pmm/INFO] LINE: Send to Boivin:3000
+> [iRMX:slave:(7) 7.857890] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from Geoff
+> [iRMX:slave:(7) 7.857890] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
+> [iRMX:slave:(7) 7.857890] [pmm/INFO] LINE: Send to TeX:3000
+> [Disney:slave:(6) 7.898965] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
+> [Disney:slave:(6) 7.898965] [pmm/INFO] ROW: Send to Geoff:3000
+> [McGee:slave:(8) 7.930628] [pmm/INFO] ROW: Send to Laroche:3000
> [Gatien:slave:(9) 7.941533] [pmm/INFO] Receive my pos (2,1) and assignment
-> [iRMX:slave:(7) 8.017069] [pmm/INFO] LINE: step(0) <> Myline(1). Receive data from TeX:3000
-> [TeX:slave:(4) 8.017069] [pmm/INFO] LINE: Send to Laroche:3000
+> [Gatien:slave:(9) 7.941533] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from Jean_Yves:3000
+> [Gatien:slave:(9) 7.941533] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from McGee
+> [Gatien:slave:(9) 7.941533] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from Disney:3000
+> [Gatien:slave:(9) 7.941533] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
+> [Gatien:slave:(9) 7.941533] [pmm/INFO] ROW: Send to McGee:3000
+> [Jean_Yves:slave:(3) 7.946719] [pmm/INFO] ROW: Send to TeX:3000
+> [Gatien:slave:(9) 7.973196] [pmm/INFO] ROW: Send to Laroche:3000
+> [Geoff:slave:(5) 8.029433] [pmm/INFO] LINE: Send to McGee:3000
+> [Boivin:slave:(2) 8.029433] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from Geoff:3000
+> [Boivin:slave:(2) 8.029433] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Jean_Yves
+> [Disney:slave:(6) 8.043079] [pmm/INFO] ROW: Send to iRMX:3000
> [Laroche:slave:(10) 8.089210] [pmm/INFO] Receive my pos (2,2) and assignment
-> [Gatien:slave:(9) 8.114261] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from Jean_Yves:3000
-> [Gatien:slave:(9) 8.145924] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from McGee
-> [McGee:slave:(8) 8.145924] [pmm/INFO] ROW: Send to Laroche:3000
-> [Laroche:slave:(10) 8.243729] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from TeX:3000
-> [iRMX:slave:(7) 8.257892] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from Geoff
-> [iRMX:slave:(7) 8.257892] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
-> [iRMX:slave:(7) 8.257892] [pmm/INFO] LINE: Send to TeX:3000
-> [Geoff:slave:(5) 8.257892] [pmm/INFO] LINE: step(1) = Myline(1). Broadcast my data (myport=3000).
-> [Geoff:slave:(5) 8.257892] [pmm/INFO] LINE: Send to Boivin:3000
-> [Jean_Yves:slave:(3) 8.269111] [pmm/INFO] ROW: step(0)<>myrow(1). Receive data from Boivin
-> [Boivin:slave:(2) 8.269111] [pmm/INFO] ROW: Send to TeX:3000
-> [Laroche:slave:(10) 8.318408] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from McGee
-> [Jean_Yves:slave:(3) 8.485361] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from Disney:3000
-> [Jean_Yves:slave:(3) 8.485361] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
-> [Jean_Yves:slave:(3) 8.485361] [pmm/INFO] ROW: Send to Boivin:3000
-> [Disney:slave:(6) 8.485361] [pmm/INFO] LINE: Send to Gatien:3000
-> [iRMX:slave:(7) 8.510935] [pmm/INFO] LINE: Send to Laroche:3000
-> [Gatien:slave:(9) 8.592457] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from Disney:3000
-> [Gatien:slave:(9) 8.592457] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
-> [Gatien:slave:(9) 8.592457] [pmm/INFO] ROW: Send to McGee:3000
-> [Disney:slave:(6) 8.592457] [pmm/INFO] ROW: step(1)=myrow(1). Broadcast my data
-> [Disney:slave:(6) 8.592457] [pmm/INFO] ROW: Send to Geoff:3000
-> [TeX:slave:(4) 8.600346] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from Boivin
-> [TeX:slave:(4) 8.600346] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from iRMX:3000
-> [Laroche:slave:(10) 8.609782] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from iRMX:3000
-> [Gatien:slave:(9) 8.624120] [pmm/INFO] ROW: Send to Laroche:3000
-> [Boivin:slave:(2) 8.683059] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from Geoff:3000
-> [Geoff:slave:(5) 8.683059] [pmm/INFO] LINE: Send to McGee:3000
-> [Laroche:slave:(10) 8.729400] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Gatien
-> [Laroche:slave:(10) 8.729400] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
-> [Laroche:slave:(10) 8.729400] [pmm/INFO] LINE: Send to TeX:3000
-> [Gatien:slave:(9) 8.729400] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
-> [Gatien:slave:(9) 8.729400] [pmm/INFO] LINE: Send to Jean_Yves:3000
-> [McGee:slave:(8) 8.753049] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from Geoff:3000
-> [McGee:slave:(8) 8.753049] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Gatien
-> [McGee:slave:(8) 8.753049] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
-> [McGee:slave:(8) 8.753049] [pmm/INFO] LINE: Send to Boivin:3000
-> [Boivin:slave:(2) 8.837909] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Jean_Yves
-> [Jean_Yves:slave:(3) 8.837909] [pmm/INFO] ROW: Send to TeX:3000
-> [Boivin:slave:(2) 8.846216] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from McGee:3000
-> [McGee:slave:(8) 8.846216] [pmm/INFO] LINE: Send to Geoff:3000
-> [Laroche:slave:(10) 8.883919] [pmm/INFO] LINE: Send to iRMX:3000
-> [Geoff:slave:(5) 8.897163] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Disney
-> [Disney:slave:(6) 8.897163] [pmm/INFO] ROW: Send to iRMX:3000
-> [Geoff:slave:(5) 8.967152] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from McGee:3000
-> [Laroche:slave:(10) 8.982766] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
-> [Laroche:slave:(10) 8.982766] [pmm/INFO] ROW: Send to McGee:3000
-> [McGee:slave:(8) 9.057444] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from Laroche
-> [Laroche:slave:(10) 9.057444] [pmm/INFO] ROW: Send to Gatien:3000
-> [TeX:slave:(4) 9.099302] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Jean_Yves
-> [TeX:slave:(4) 9.099302] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from Laroche:3000
-> [TeX:slave:(4) 9.099302] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
-> [TeX:slave:(4) 9.099302] [pmm/INFO] ROW: Send to Boivin:3000
-> [McGee:slave:(8) 9.127912] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Boivin:slave:(2) 9.188713] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from TeX
-> [TeX:slave:(4) 9.188713] [pmm/INFO] ROW: Send to Jean_Yves:3000
-> [iRMX:slave:(7) 9.236677] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Disney
-> [iRMX:slave:(7) 9.236677] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from Laroche:3000
-> [iRMX:slave:(7) 9.236677] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
-> [iRMX:slave:(7) 9.236677] [pmm/INFO] ROW: Send to Geoff:3000
-> [Boivin:slave:(2) 9.271914] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Jean_Yves:slave:(3) 9.272030] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from Gatien:3000
-> [Gatien:slave:(9) 9.272030] [pmm/INFO] LINE: Send to Disney:3000
-> [Disney:slave:(6) 9.379125] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from Gatien:3000
-> [Geoff:slave:(5) 9.477500] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from iRMX
-> [iRMX:slave:(7) 9.477500] [pmm/INFO] ROW: Send to Disney:3000
-> [Geoff:slave:(5) 9.478035] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Gatien:slave:(9) 9.484406] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from Laroche
-> [Jean_Yves:slave:(3) 9.487412] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from TeX
-> [Gatien:slave:(9) 9.585499] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Laroche:slave:(10) 9.728332] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Disney:slave:(6) 9.731412] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from iRMX
-> [Jean_Yves:slave:(3) 9.801713] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [TeX:slave:(4) 9.945494] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Disney:slave:(6) 10.090144] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Jacquelin:master:(1) 10.331578] [pmm/INFO] The Result of Multiplication is :
+> [Laroche:slave:(10) 8.089210] [pmm/INFO] LINE: step(0) <> Myline(2). Receive data from TeX:3000
+> [Laroche:slave:(10) 8.089210] [pmm/INFO] ROW: step(0)<>myrow(2). Receive data from McGee
+> [Geoff:slave:(5) 8.099422] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Disney
+> [McGee:slave:(8) 8.099422] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from Geoff:3000
+> [McGee:slave:(8) 8.099422] [pmm/INFO] ROW: step(1)<>myrow(0). Receive data from Gatien
+> [McGee:slave:(8) 8.099422] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
+> [McGee:slave:(8) 8.099422] [pmm/INFO] LINE: Send to Boivin:3000
+> [McGee:slave:(8) 8.107729] [pmm/INFO] LINE: Send to Geoff:3000
+> [Boivin:slave:(2) 8.107729] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from McGee:3000
+> [Gatien:slave:(9) 8.110588] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
+> [Gatien:slave:(9) 8.110588] [pmm/INFO] LINE: Send to Jean_Yves:3000
+> [iRMX:slave:(7) 8.110933] [pmm/INFO] LINE: Send to Laroche:3000
+> [TeX:slave:(4) 8.110933] [pmm/INFO] LINE: step(1) <> Myline(0). Receive data from iRMX:3000
+> [Geoff:slave:(5) 8.177718] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from McGee:3000
+> [Laroche:slave:(10) 8.209779] [pmm/INFO] LINE: step(1) <> Myline(2). Receive data from iRMX:3000
+> [Laroche:slave:(10) 8.209779] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Gatien
+> [Laroche:slave:(10) 8.209779] [pmm/INFO] LINE: step(2) = Myline(2). Broadcast my data (myport=3000).
+> [Laroche:slave:(10) 8.209779] [pmm/INFO] LINE: Send to TeX:3000
+> [Gatien:slave:(9) 8.283315] [pmm/INFO] LINE: Send to Disney:3000
+> [iRMX:slave:(7) 8.296991] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Disney
+> [Jean_Yves:slave:(3) 8.326315] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from Gatien:3000
+> [TeX:slave:(4) 8.326315] [pmm/INFO] ROW: step(1)<>myrow(2). Receive data from Jean_Yves
+> [Disney:slave:(6) 8.390411] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from Gatien:3000
+> [Laroche:slave:(10) 8.480834] [pmm/INFO] LINE: Send to iRMX:3000
+> [TeX:slave:(4) 8.480834] [pmm/INFO] LINE: step(2) <> Myline(0). Receive data from Laroche:3000
+> [TeX:slave:(4) 8.480834] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
+> [TeX:slave:(4) 8.480834] [pmm/INFO] ROW: Send to Boivin:3000
+> [TeX:slave:(4) 8.570245] [pmm/INFO] ROW: Send to Jean_Yves:3000
+> [Boivin:slave:(2) 8.570245] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from TeX
+> [Laroche:slave:(10) 8.579681] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
+> [Laroche:slave:(10) 8.579681] [pmm/INFO] ROW: Send to McGee:3000
+> [iRMX:slave:(7) 8.579681] [pmm/INFO] LINE: step(2) <> Myline(1). Receive data from Laroche:3000
+> [iRMX:slave:(7) 8.579681] [pmm/INFO] ROW: step(2)=myrow(2). Broadcast my data
+> [iRMX:slave:(7) 8.579681] [pmm/INFO] ROW: Send to Geoff:3000
+> [Boivin:slave:(2) 8.653446] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Laroche:slave:(10) 8.654359] [pmm/INFO] ROW: Send to Gatien:3000
+> [McGee:slave:(8) 8.654359] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from Laroche
+> [McGee:slave:(8) 8.724827] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Gatien:slave:(9) 8.759640] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from Laroche
+> [Jean_Yves:slave:(3) 8.785628] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from TeX
+> [iRMX:slave:(7) 8.820504] [pmm/INFO] ROW: Send to Disney:3000
+> [Geoff:slave:(5) 8.820504] [pmm/INFO] ROW: step(2)<>myrow(0). Receive data from iRMX
+> [Laroche:slave:(10) 8.902473] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Gatien:slave:(9) 9.003566] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Disney:slave:(6) 9.074415] [pmm/INFO] ROW: step(2)<>myrow(1). Receive data from iRMX
+> [TeX:slave:(4) 9.147347] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Jean_Yves:slave:(3) 9.220728] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Geoff:slave:(5) 9.221263] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [iRMX:slave:(7) 9.462697] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Disney:slave:(6) 9.607346] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
+> [Jacquelin:master:(1) 9.607346] [pmm/INFO] The Result of Multiplication is :
> >>> Matrix C:res dump (18 x 18)
> 0.00 18.00 36.00 54.00 72.00 90.00 108.00 126.00 144.00 162.00 180.00 198.00 216.00 234.00 252.00 270.00 288.00 306.00
> 1.00 19.00 37.00 55.00 73.00 91.00 109.00 127.00 145.00 163.00 181.00 199.00 217.00 235.00 253.00 271.00 289.00 307.00
> 16.00 34.00 52.00 70.00 88.00 106.00 124.00 142.00 160.00 178.00 196.00 214.00 232.00 250.00 268.00 286.00 304.00 322.00
> 17.00 35.00 53.00 71.00 89.00 107.00 125.00 143.00 161.00 179.00 197.00 215.00 233.00 251.00 269.00 287.00 305.00 323.00
> <<< end_of_matrix C:res dump
-> [iRMX:slave:(7) 10.331578] [pmm/INFO] >>>>>>>> Result sent to Jacquelin:4242 <<<<<<<<
-> [Boivin:slave:(2) 10.412199] [gras/INFO] Exiting GRAS
-> [Jean_Yves:slave:(3) 10.483305] [gras/INFO] Exiting GRAS
-> [TeX:slave:(4) 10.622627] [gras/INFO] Exiting GRAS
-> [Geoff:slave:(5) 10.623038] [gras/INFO] Exiting GRAS
-> [Disney:slave:(6) 10.763202] [gras/INFO] Exiting GRAS
-> [iRMX:slave:(7) 10.997150] [gras/INFO] Exiting GRAS
-> [McGee:slave:(8) 11.065432] [gras/INFO] Exiting GRAS
-> [Gatien:slave:(9) 11.163390] [gras/INFO] Exiting GRAS
-> [Laroche:slave:(10) 11.301794] [gras/INFO] Exiting GRAS
-> [Jacquelin:master:(1) 11.301794] [gras/INFO] Exiting GRAS
+> [Boivin:slave:(2) 9.687967] [gras/INFO] Exiting GRAS
+> [Jean_Yves:slave:(3) 9.759073] [gras/INFO] Exiting GRAS
+> [TeX:slave:(4) 9.898396] [gras/INFO] Exiting GRAS
+> [Geoff:slave:(5) 9.898806] [gras/INFO] Exiting GRAS
+> [Disney:slave:(6) 10.038971] [gras/INFO] Exiting GRAS
+> [iRMX:slave:(7) 10.272918] [gras/INFO] Exiting GRAS
+> [McGee:slave:(8) 10.341200] [gras/INFO] Exiting GRAS
+> [Gatien:slave:(9) 10.439159] [gras/INFO] Exiting GRAS
+> [Jacquelin:master:(1) 10.577563] [gras/INFO] Exiting GRAS
+> [Laroche:slave:(10) 10.577563] [gras/INFO] Exiting GRAS
+
# optimization level: if inlining, gcc changes it).
# Trying to get it right would prevent me to ever submit that damn paper.
-$ $SG_TEST_EXENV ./rpc_simulator$EXEEXT ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/rpc.xml --log=root.fmt:"'[%h:%t:(%i)] [%c/%p] %m%n'"
+$ $SG_TEST_EXENV ./rpc_simulator$EXEEXT ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/rpc.xml --log=root.fmt:"'[%h:%P:(%i)] [%c/%p] %m%n'"
> [Tremblay:server:(1)] [Rpc/INFO] Launch server (port=4000)
> [Tremblay:server:(1)] [Rpc/INFO] Listening on port 4000
> [Ginette:forwarder:(2)] [Rpc/INFO] Launch forwarder (port=4000)
> [Fafard:client:(3)] [Rpc/INFO] Connected to Tremblay:4000.
> [Fafard:client:(3)] [Rpc/INFO] Connected to server which is on Tremblay:4000
> [Tremblay:server:(1)] [Rpc/INFO] Got message PING(1234) from Fafard:0
+> [Tremblay:server:(1)] [Rpc/INFO] Answered with PONG(4321)
> [Fafard:client:(3)] [Rpc/INFO] The answer to PING(1234) on Tremblay:4000 is PONG(4321)
> [Fafard:client:(3)] [Rpc/INFO] Call the exception raising RPC
-> [Tremblay:server:(1)] [Rpc/INFO] Answered with PONG(4321)
> [Fafard:client:(3)] [Rpc/INFO] Got the expected exception when calling the exception raising RPC
> [Fafard:client:(3)] [Rpc/INFO] Called the exception raising RPC
> [Fafard:client:(3)] [Rpc/INFO] Call the exception raising RPC (i=0)
> [Ginette:forwarder:(2)] [Rpc/INFO] Forward a request
> [Fafard:client:(3)] [Rpc/INFO] Got the expected exception when calling the exception raising RPC
> [Fafard:client:(3)] [Rpc/INFO] Ask Ginette:4000 to die
+> [Fafard:client:(3)] [Rpc/INFO] Ask Tremblay:4000 to die
> [Ginette:forwarder:(2)] [Rpc/INFO] Asked to die by Fafard:0
> [Ginette:forwarder:(2)] [Rpc/INFO] Done.
> [Ginette:forwarder:(2)] [gras/INFO] Exiting GRAS
-> [Fafard:client:(3)] [Rpc/INFO] Ask Tremblay:4000 to die
+> [Fafard:client:(3)] [Rpc/INFO] Done.
+> [Fafard:client:(3)] [gras/INFO] Exiting GRAS
> [Tremblay:server:(1)] [Rpc/INFO] Asked to die by Fafard:0
> [Tremblay:server:(1)] [Rpc/INFO] Done.
> [Tremblay:server:(1)] [gras/INFO] Exiting GRAS
-> [Fafard:client:(3)] [Rpc/INFO] Done.
-> [Fafard:client:(3)] [gras/INFO] Exiting GRAS
> [Tremblay:child:(5) 0.000000] [Spawn/INFO] I (Tremblay:4004) have found my dady on Tremblay:4000.
> [Tremblay:child:(6) 0.000000] [Spawn/INFO] I (Tremblay:4005) have found my dady on Tremblay:4000.
> [Tremblay:father:(1) 0.000015] [Spawn/INFO] Kid Tremblay:4001 pinged me with 1234
+> [Tremblay:father:(1) 0.000030] [Spawn/INFO] Answered to Tremblay:4001's request
+> [Tremblay:father:(1) 0.000030] [Spawn/INFO] Kid Tremblay:4002 pinged me with 1234
> [Tremblay:child:(2) 0.000030] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
> [Tremblay:child:(2) 0.000030] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000030] [Spawn/INFO] Answered to Tremblay:4001's request
-> [Tremblay:father:(1) 0.000045] [Spawn/INFO] Kid Tremblay:4002 pinged me with 1234
-> [Tremblay:child:(3) 0.000060] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(3) 0.000060] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000060] [Spawn/INFO] Answered to Tremblay:4002's request
-> [Tremblay:father:(1) 0.000075] [Spawn/INFO] Kid Tremblay:4003 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.000090] [Spawn/INFO] Answered to Tremblay:4003's request
-> [Tremblay:father:(1) 0.000105] [Spawn/INFO] Kid Tremblay:4004 pinged me with 1234
-> [Tremblay:child:(5) 0.000120] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(5) 0.000120] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000120] [Spawn/INFO] Answered to Tremblay:4004's request
-> [Tremblay:father:(1) 0.000135] [Spawn/INFO] Kid Tremblay:4005 pinged me with 1234
-> [Tremblay:child:(6) 0.000150] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
-> [Tremblay:child:(6) 0.000150] [gras/INFO] Exiting GRAS
-> [Tremblay:father:(1) 0.000150] [Spawn/INFO] Answered to Tremblay:4005's request
-> [Tremblay:father:(1) 0.000150] [Spawn/INFO] All kids gone. Leave now.
-> [Tremblay:father:(1) 0.000150] [gras/INFO] Exiting GRAS
\ No newline at end of file
+> [Tremblay:father:(1) 0.000045] [Spawn/INFO] Answered to Tremblay:4002's request
+> [Tremblay:father:(1) 0.000045] [Spawn/INFO] Kid Tremblay:4003 pinged me with 1234
+> [Tremblay:child:(3) 0.000045] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(3) 0.000045] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.000060] [Spawn/INFO] Answered to Tremblay:4003's request
+> [Tremblay:father:(1) 0.000060] [Spawn/INFO] Kid Tremblay:4004 pinged me with 1234
+> [Tremblay:child:(4) 0.000060] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(4) 0.000060] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.000075] [Spawn/INFO] Answered to Tremblay:4004's request
+> [Tremblay:father:(1) 0.000075] [Spawn/INFO] Kid Tremblay:4005 pinged me with 1234
+> [Tremblay:child:(5) 0.000075] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(5) 0.000075] [gras/INFO] Exiting GRAS
+> [Tremblay:father:(1) 0.000090] [Spawn/INFO] Answered to Tremblay:4005's request
+> [Tremblay:father:(1) 0.000090] [Spawn/INFO] All kids gone. Leave now.
+> [Tremblay:father:(1) 0.000090] [gras/INFO] Exiting GRAS
+> [Tremblay:child:(6) 0.000090] [Spawn/INFO] Pinged dad with 1234, he answered with 4321; leaving now.
+> [Tremblay:child:(6) 0.000090] [gras/INFO] Exiting GRAS
p Runs the 'philosopher' test within the simulator (simulation times valid for 64bits architectures)
$ $SG_TEST_EXENV ./synchro_simulator${EXEEXT:=} ${srcdir:=.}/../../msg/small_platform.xml ${srcdir:=.}/synchro.xml
-> [Tremblay:philosopher:(1) 0.000000] [philo/INFO] Wait 1 sec to check that gras_os_sleep do works
-> [Tremblay:philosopher:(1) 1.000000] [philo/INFO] Enough waiting, spawn the threads
-> [1.000000] [philo/INFO] Thread 0 gets hungry (lunch #0)
-> [1.000000] [philo/INFO] Thread 0 eats
-> [1.000000] [philo/INFO] Thread 1 gets hungry (lunch #0)
-> [1.000000] [philo/INFO] Thread 2 gets hungry (lunch #0)
-> [1.000000] [philo/INFO] Thread 2 eats
-> [1.000000] [philo/INFO] Thread 3 gets hungry (lunch #0)
-> [1.000000] [philo/INFO] Thread 4 gets hungry (lunch #0)
-> [1.000000] [philo/INFO] Thread 0 is full
-> [1.000000] [philo/INFO] Thread 0 thinks
-> [1.000000] [philo/INFO] Thread 4 eats
-> [1.000000] [philo/INFO] Thread 0 gets hungry (lunch #1)
-> [1.020000] [philo/INFO] Thread 2 is full
-> [1.020000] [philo/INFO] Thread 2 thinks
-> [1.020000] [philo/INFO] Thread 1 eats
-> [1.030000] [philo/INFO] Thread 1 is full
-> [1.030000] [philo/INFO] Thread 1 thinks
-> [1.040000] [philo/INFO] Thread 4 is full
-> [1.040000] [philo/INFO] Thread 4 thinks
-> [1.040000] [philo/INFO] Thread 2 gets hungry (lunch #1)
-> [1.040000] [philo/INFO] Thread 2 eats
-> [1.040000] [philo/INFO] Thread 1 gets hungry (lunch #1)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #2)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #3)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #4)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #5)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #6)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #7)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #8)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 gets hungry (lunch #9)
-> [1.040000] [philo/INFO] Thread 0 eats
-> [1.040000] [philo/INFO] Thread 0 is full
-> [1.040000] [philo/INFO] Thread 0 thinks
-> [1.040000] [philo/INFO] Thread 0 tries to enter the dead-end; hopefully, the master will cancel it
-> [1.060000] [philo/INFO] Thread 2 is full
-> [1.060000] [philo/INFO] Thread 2 thinks
-> [1.060000] [philo/INFO] Thread 1 eats
-> [1.060000] [philo/INFO] Thread 3 eats
-> [1.070000] [philo/INFO] Thread 1 is full
-> [1.070000] [philo/INFO] Thread 1 thinks
-> [1.080000] [philo/INFO] Thread 4 gets hungry (lunch #1)
-> [1.080000] [philo/INFO] Thread 2 gets hungry (lunch #2)
-> [1.080000] [philo/INFO] Thread 1 gets hungry (lunch #2)
-> [1.080000] [philo/INFO] Thread 1 eats
-> [1.090000] [philo/INFO] Thread 3 is full
-> [1.090000] [philo/INFO] Thread 3 thinks
-> [1.090000] [philo/INFO] Thread 1 is full
-> [1.090000] [philo/INFO] Thread 1 thinks
-> [1.090000] [philo/INFO] Thread 2 eats
-> [1.090000] [philo/INFO] Thread 4 eats
-> [1.100000] [philo/INFO] Thread 1 gets hungry (lunch #3)
-> [1.110000] [philo/INFO] Thread 2 is full
-> [1.110000] [philo/INFO] Thread 2 thinks
-> [1.110000] [philo/INFO] Thread 1 eats
-> [1.120000] [philo/INFO] Thread 3 gets hungry (lunch #1)
-> [1.120000] [philo/INFO] Thread 1 is full
-> [1.120000] [philo/INFO] Thread 1 thinks
-> [1.130000] [philo/INFO] Thread 4 is full
-> [1.130000] [philo/INFO] Thread 4 thinks
-> [1.130000] [philo/INFO] Thread 2 gets hungry (lunch #3)
-> [1.130000] [philo/INFO] Thread 2 eats
-> [1.130000] [philo/INFO] Thread 1 gets hungry (lunch #4)
-> [1.150000] [philo/INFO] Thread 2 is full
-> [1.150000] [philo/INFO] Thread 2 thinks
-> [1.150000] [philo/INFO] Thread 1 eats
-> [1.150000] [philo/INFO] Thread 3 eats
-> [1.160000] [philo/INFO] Thread 1 is full
-> [1.160000] [philo/INFO] Thread 1 thinks
-> [1.170000] [philo/INFO] Thread 4 gets hungry (lunch #2)
-> [1.170000] [philo/INFO] Thread 2 gets hungry (lunch #4)
-> [1.170000] [philo/INFO] Thread 1 gets hungry (lunch #5)
-> [1.170000] [philo/INFO] Thread 1 eats
-> [1.180000] [philo/INFO] Thread 3 is full
-> [1.180000] [philo/INFO] Thread 3 thinks
-> [1.180000] [philo/INFO] Thread 1 is full
-> [1.180000] [philo/INFO] Thread 1 thinks
-> [1.180000] [philo/INFO] Thread 2 eats
-> [1.180000] [philo/INFO] Thread 4 eats
-> [1.190000] [philo/INFO] Thread 1 gets hungry (lunch #6)
-> [1.200000] [philo/INFO] Thread 2 is full
-> [1.200000] [philo/INFO] Thread 2 thinks
-> [1.200000] [philo/INFO] Thread 1 eats
-> [1.210000] [philo/INFO] Thread 3 gets hungry (lunch #2)
-> [1.210000] [philo/INFO] Thread 1 is full
-> [1.210000] [philo/INFO] Thread 1 thinks
-> [1.220000] [philo/INFO] Thread 4 is full
-> [1.220000] [philo/INFO] Thread 4 thinks
-> [1.220000] [philo/INFO] Thread 2 gets hungry (lunch #5)
-> [1.220000] [philo/INFO] Thread 2 eats
-> [1.220000] [philo/INFO] Thread 1 gets hungry (lunch #7)
-> [1.240000] [philo/INFO] Thread 2 is full
-> [1.240000] [philo/INFO] Thread 2 thinks
-> [1.240000] [philo/INFO] Thread 1 eats
-> [1.240000] [philo/INFO] Thread 3 eats
-> [1.250000] [philo/INFO] Thread 1 is full
-> [1.250000] [philo/INFO] Thread 1 thinks
-> [1.260000] [philo/INFO] Thread 4 gets hungry (lunch #3)
-> [1.260000] [philo/INFO] Thread 2 gets hungry (lunch #6)
-> [1.260000] [philo/INFO] Thread 1 gets hungry (lunch #8)
-> [1.260000] [philo/INFO] Thread 1 eats
-> [1.270000] [philo/INFO] Thread 3 is full
-> [1.270000] [philo/INFO] Thread 3 thinks
-> [1.270000] [philo/INFO] Thread 1 is full
-> [1.270000] [philo/INFO] Thread 1 thinks
-> [1.270000] [philo/INFO] Thread 2 eats
-> [1.270000] [philo/INFO] Thread 4 eats
-> [1.280000] [philo/INFO] Thread 1 gets hungry (lunch #9)
-> [1.290000] [philo/INFO] Thread 2 is full
-> [1.290000] [philo/INFO] Thread 2 thinks
-> [1.290000] [philo/INFO] Thread 1 eats
-> [1.300000] [philo/INFO] Thread 3 gets hungry (lunch #3)
-> [1.300000] [philo/INFO] Thread 1 is full
-> [1.300000] [philo/INFO] Thread 1 thinks
-> [1.310000] [philo/INFO] Thread 4 is full
-> [1.310000] [philo/INFO] Thread 4 thinks
-> [1.310000] [philo/INFO] Thread 2 gets hungry (lunch #7)
-> [1.310000] [philo/INFO] Thread 2 eats
-> [1.310000] [philo/INFO] Thread 1 tries to enter the dead-end; hopefully, the master will cancel it
-> [1.330000] [philo/INFO] Thread 2 is full
-> [1.330000] [philo/INFO] Thread 2 thinks
-> [1.330000] [philo/INFO] Thread 3 eats
-> [1.350000] [philo/INFO] Thread 4 gets hungry (lunch #4)
-> [1.350000] [philo/INFO] Thread 2 gets hungry (lunch #8)
-> [1.360000] [philo/INFO] Thread 3 is full
-> [1.360000] [philo/INFO] Thread 3 thinks
-> [1.360000] [philo/INFO] Thread 2 eats
-> [1.360000] [philo/INFO] Thread 4 eats
-> [1.380000] [philo/INFO] Thread 2 is full
-> [1.380000] [philo/INFO] Thread 2 thinks
-> [1.390000] [philo/INFO] Thread 3 gets hungry (lunch #4)
-> [1.400000] [philo/INFO] Thread 4 is full
-> [1.400000] [philo/INFO] Thread 4 thinks
-> [1.400000] [philo/INFO] Thread 2 gets hungry (lunch #9)
-> [1.400000] [philo/INFO] Thread 2 eats
-> [1.420000] [philo/INFO] Thread 2 is full
-> [1.420000] [philo/INFO] Thread 2 thinks
-> [1.420000] [philo/INFO] Thread 3 eats
-> [1.440000] [philo/INFO] Thread 4 gets hungry (lunch #5)
-> [1.440000] [philo/INFO] Thread 2 tries to enter the dead-end; hopefully, the master will cancel it
-> [1.450000] [philo/INFO] Thread 3 is full
-> [1.450000] [philo/INFO] Thread 3 thinks
-> [1.450000] [philo/INFO] Thread 4 eats
-> [1.480000] [philo/INFO] Thread 3 gets hungry (lunch #5)
-> [1.490000] [philo/INFO] Thread 4 is full
-> [1.490000] [philo/INFO] Thread 4 thinks
-> [1.490000] [philo/INFO] Thread 3 eats
-> [1.520000] [philo/INFO] Thread 3 is full
-> [1.520000] [philo/INFO] Thread 3 thinks
-> [1.530000] [philo/INFO] Thread 4 gets hungry (lunch #6)
-> [1.530000] [philo/INFO] Thread 4 eats
-> [1.550000] [philo/INFO] Thread 3 gets hungry (lunch #6)
-> [1.570000] [philo/INFO] Thread 4 is full
-> [1.570000] [philo/INFO] Thread 4 thinks
-> [1.570000] [philo/INFO] Thread 3 eats
-> [1.600000] [philo/INFO] Thread 3 is full
-> [1.600000] [philo/INFO] Thread 3 thinks
-> [1.610000] [philo/INFO] Thread 4 gets hungry (lunch #7)
-> [1.610000] [philo/INFO] Thread 4 eats
-> [1.630000] [philo/INFO] Thread 3 gets hungry (lunch #7)
-> [1.650000] [philo/INFO] Thread 4 is full
-> [1.650000] [philo/INFO] Thread 4 thinks
-> [1.650000] [philo/INFO] Thread 3 eats
-> [1.680000] [philo/INFO] Thread 3 is full
-> [1.680000] [philo/INFO] Thread 3 thinks
-> [1.690000] [philo/INFO] Thread 4 gets hungry (lunch #8)
-> [1.690000] [philo/INFO] Thread 4 eats
-> [1.710000] [philo/INFO] Thread 3 gets hungry (lunch #8)
-> [1.730000] [philo/INFO] Thread 4 is full
-> [1.730000] [philo/INFO] Thread 4 thinks
-> [1.730000] [philo/INFO] Thread 3 eats
-> [1.760000] [philo/INFO] Thread 3 is full
-> [1.760000] [philo/INFO] Thread 3 thinks
-> [1.770000] [philo/INFO] Thread 4 gets hungry (lunch #9)
-> [1.770000] [philo/INFO] Thread 4 eats
-> [1.790000] [philo/INFO] Thread 3 gets hungry (lunch #9)
-> [1.810000] [philo/INFO] Thread 4 is full
-> [1.810000] [philo/INFO] Thread 4 thinks
-> [1.810000] [philo/INFO] Thread 3 eats
-> [1.840000] [philo/INFO] Thread 3 is full
-> [1.840000] [philo/INFO] Thread 3 thinks
-> [1.850000] [philo/INFO] Thread 4 tries to enter the dead-end; hopefully, the master will cancel it
-> [1.870000] [philo/INFO] Thread 3 tries to enter the dead-end; hopefully, the master will cancel it
-> [Tremblay:philosopher:(1) 1.870000] [philo/INFO] Cancel all childs
-> [Tremblay:philosopher:(1) 1.870000] [gras/INFO] Exiting GRAS
+> [Tremblay:philosopher:(1) 0.000000] [philo/INFO] Spawn the 5 threads (10 lunches scheduled)
+> [Tremblay:thread 0:(1) 0.000000] [philo/INFO] Thread 0 gets hungry (lunch #0)
+> [Tremblay:thread 0:(1) 0.000000] [philo/INFO] Thread 0 eats
+> [Tremblay:thread 1:(1) 0.000000] [philo/INFO] Thread 1 gets hungry (lunch #0)
+> [Tremblay:thread 2:(1) 0.000000] [philo/INFO] Thread 2 gets hungry (lunch #0)
+> [Tremblay:thread 2:(1) 0.000000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 3:(1) 0.000000] [philo/INFO] Thread 3 gets hungry (lunch #0)
+> [Tremblay:thread 4:(1) 0.000000] [philo/INFO] Thread 4 gets hungry (lunch #0)
+> [Tremblay:thread 0:(1) 0.000000] [philo/INFO] Thread 0 is full
+> [Tremblay:thread 0:(1) 0.000000] [philo/INFO] Thread 0 thinks
+> [Tremblay:thread 4:(1) 0.000000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 0:(1) 0.000000] [philo/INFO] Thread 0 gets hungry (lunch #1)
+> [Tremblay:thread 2:(1) 0.020000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.020000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.020000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 1:(1) 0.030000] [philo/INFO] Thread 1 is full
+> [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.060000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.060000] [philo/INFO] Thread 3 eats
+> [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 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 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 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 1:(1) 0.170000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.180000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.180000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 1:(1) 0.180000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.180000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.180000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.180000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 1:(1) 0.190000] [philo/INFO] Thread 1 gets hungry (lunch #6)
+> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.200000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.200000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.210000] [philo/INFO] Thread 3 gets hungry (lunch #2)
+> [Tremblay:thread 1:(1) 0.210000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.210000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 4:(1) 0.220000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.220000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.220000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.220000] [philo/INFO] Thread 1 gets hungry (lunch #7)
+> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.240000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 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 1:(1) 0.260000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.270000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.270000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 1:(1) 0.270000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.270000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 2:(1) 0.270000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.270000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 1:(1) 0.280000] [philo/INFO] Thread 1 gets hungry (lunch #9)
+> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.290000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 1:(1) 0.290000] [philo/INFO] Thread 1 eats
+> [Tremblay:thread 3:(1) 0.300000] [philo/INFO] Thread 3 gets hungry (lunch #3)
+> [Tremblay:thread 1:(1) 0.300000] [philo/INFO] Thread 1 is full
+> [Tremblay:thread 1:(1) 0.300000] [philo/INFO] Thread 1 thinks
+> [Tremblay:thread 4:(1) 0.310000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.310000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 gets hungry (lunch #7)
+> [Tremblay:thread 2:(1) 0.310000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 1:(1) 0.310000] [philo/INFO] Thread 1 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.330000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.330000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.350000] [philo/INFO] Thread 4 gets hungry (lunch #4)
+> [Tremblay:thread 2:(1) 0.350000] [philo/INFO] Thread 2 gets hungry (lunch #8)
+> [Tremblay:thread 3:(1) 0.360000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.360000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 2:(1) 0.360000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 4:(1) 0.360000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.380000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.390000] [philo/INFO] Thread 3 gets hungry (lunch #4)
+> [Tremblay:thread 4:(1) 0.400000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.400000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 gets hungry (lunch #9)
+> [Tremblay:thread 2:(1) 0.400000] [philo/INFO] Thread 2 eats
+> [Tremblay:thread 2:(1) 0.420000] [philo/INFO] Thread 2 is full
+> [Tremblay:thread 2:(1) 0.420000] [philo/INFO] Thread 2 thinks
+> [Tremblay:thread 3:(1) 0.420000] [philo/INFO] Thread 3 eats
+> [Tremblay:thread 4:(1) 0.440000] [philo/INFO] Thread 4 gets hungry (lunch #5)
+> [Tremblay:thread 2:(1) 0.440000] [philo/INFO] Thread 2 tries to enter the dead-end; hopefully, the master will cancel it
+> [Tremblay:thread 3:(1) 0.450000] [philo/INFO] Thread 3 is full
+> [Tremblay:thread 3:(1) 0.450000] [philo/INFO] Thread 3 thinks
+> [Tremblay:thread 4:(1) 0.450000] [philo/INFO] Thread 4 eats
+> [Tremblay:thread 3:(1) 0.480000] [philo/INFO] Thread 3 gets hungry (lunch #5)
+> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 is full
+> [Tremblay:thread 4:(1) 0.490000] [philo/INFO] Thread 4 thinks
+> [Tremblay:thread 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
