From 81bcfbd76b4524f3e963d9479321e276636ef405 Mon Sep 17 00:00:00 2001 From: schnorr Date: Wed, 2 Feb 2011 13:23:20 +0000 Subject: [PATCH] [trace] fixing tracing tesh'es due to changes in event order git-svn-id: svn+ssh://scm.gforge.inria.fr/svn/simgrid/simgrid/trunk@9564 48e7efb5-ca39-0410-a469-dd3cf9ba447f --- examples/msg/tracing/ms.tesh | 192 +++++++++--------- examples/msg/tracing/procmig.tesh | 52 ++--- examples/msg/tracing/tasks.tesh | 325 +++++++++++++++--------------- 3 files changed, 285 insertions(+), 284 deletions(-) diff --git a/examples/msg/tracing/ms.tesh b/examples/msg/tracing/ms.tesh index ec6fee3996..a169800339 100644 --- a/examples/msg/tracing/ms.tesh +++ b/examples/msg/tracing/ms.tesh @@ -1355,12 +1355,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.002339] (0:@) insert_into_buffer: inserted at end, pos = 5 > [ 0.002339] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.002339] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.002339] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.002339 -> [ 0.002339] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.002339, buffersize=6) -> [ 0.002339] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 6 > [ 0.002339] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=0.002339 -> [ 0.002339] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=0.002339, buffersize=7) -> [ 0.002339] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 7 +> [ 0.002339] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=0.002339, buffersize=6) +> [ 0.002339] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 6 +> [ 0.002339] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.002339 +> [ 0.002339] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.002339, buffersize=7) +> [ 0.002339] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 7 > [ 0.017538] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.017538] (0:@) TRACE_paje_dump_buffer: ends > [ 0.168300] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.017538 @@ -1383,12 +1383,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.168300] (0:@) insert_into_buffer: inserted at end, pos = 13 > [ 0.168300] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.168300] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.168300] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.168300 -> [ 0.168300] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.168300, buffersize=14) -> [ 0.168300] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 14 > [ 0.168300] (3:slave@Jupiter) new_pajeAddVariable: event_type=8, timestamp=0.168300 -> [ 0.168300] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=0.168300, buffersize=15) -> [ 0.168300] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 15 +> [ 0.168300] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=0.168300, buffersize=14) +> [ 0.168300] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 14 +> [ 0.168300] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.168300 +> [ 0.168300] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.168300, buffersize=15) +> [ 0.168300] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 15 > [ 0.168300] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.002339 > [ 0.168300] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.002339, buffersize=16) > [ 0.168300] (0:@) insert_into_buffer: inserted at 8 @@ -1519,12 +1519,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.386454] (0:@) insert_into_buffer: inserted at end, pos = 57 > [ 0.386454] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.386454] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.386454] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.386454 -> [ 0.386454] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.386454, buffersize=58) -> [ 0.386454] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 58 > [ 0.386454] (4:slave@Fafard) new_pajeAddVariable: event_type=8, timestamp=0.386454 -> [ 0.386454] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=0.386454, buffersize=59) -> [ 0.386454] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 59 +> [ 0.386454] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=0.386454, buffersize=58) +> [ 0.386454] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 58 +> [ 0.386454] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.386454 +> [ 0.386454] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.386454, buffersize=59) +> [ 0.386454] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 59 > [ 0.386454] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.168300 > [ 0.386454] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.168300, buffersize=60) > [ 0.386454] (0:@) insert_into_buffer: inserted at 22 @@ -1633,12 +1633,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.526913] (0:@) insert_into_buffer: inserted at end, pos = 93 > [ 0.526913] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.526913] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.526913] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.526913 -> [ 0.526913] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.526913, buffersize=94) -> [ 0.526913] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 94 > [ 0.526913] (5:slave@Ginette) new_pajeAddVariable: event_type=8, timestamp=0.526913 -> [ 0.526913] (5:slave@Ginette) insert_into_buffer: insert event_type=8, timestamp=0.526913, buffersize=95) -> [ 0.526913] (5:slave@Ginette) insert_into_buffer: inserted at end, pos = 95 +> [ 0.526913] (5:slave@Ginette) insert_into_buffer: insert event_type=8, timestamp=0.526913, buffersize=94) +> [ 0.526913] (5:slave@Ginette) insert_into_buffer: inserted at end, pos = 94 +> [ 0.526913] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.526913 +> [ 0.526913] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.526913, buffersize=95) +> [ 0.526913] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 95 > [ 0.547249] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.547249] (0:@) TRACE_paje_dump_buffer: ends > [ 0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249 @@ -1739,12 +1739,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.742786] (0:@) insert_into_buffer: inserted at end, pos = 127 > [ 0.742786] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts > [ 0.742786] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.742786] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.742786 -> [ 0.742786] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.742786, buffersize=128) -> [ 0.742786] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 128 > [ 0.742786] (6:slave@Bourassa) new_pajeAddVariable: event_type=8, timestamp=0.742786 -> [ 0.742786] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=0.742786, buffersize=129) -> [ 0.742786] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 129 +> [ 0.742786] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=0.742786, buffersize=128) +> [ 0.742786] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 128 +> [ 0.742786] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.742786 +> [ 0.742786] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.742786, buffersize=129) +> [ 0.742786] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 129 > [ 0.742942] (0:@) TRACE_paje_dump_buffer: dump until 0.168300. starts > [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.000156 > [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.000156 @@ -1758,12 +1758,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.742942] (0:@) free_paje_event: event_type=9, timestamp=0.002339 > [ 0.742942] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.002339 > [ 0.742942] (0:@) free_paje_event: event_type=9, timestamp=0.002339 -> [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.002339 -> [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.002339 > [ 0.742942] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.002339 > [ 0.742942] (0:@) free_paje_event: event_type=8, timestamp=0.002339 > [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.002339 > [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.002339 +> [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.002339 +> [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.002339 > [ 0.742942] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.002339 > [ 0.742942] (0:@) free_paje_event: event_type=8, timestamp=0.002339 > [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.002339 @@ -1782,10 +1782,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.742942] (0:@) free_paje_event: event_type=9, timestamp=0.168300 > [ 0.742942] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.168300 > [ 0.742942] (0:@) free_paje_event: event_type=9, timestamp=0.168300 -> [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.168300 -> [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.168300 > [ 0.742942] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.168300 > [ 0.742942] (0:@) free_paje_event: event_type=8, timestamp=0.168300 +> [ 0.742942] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.168300 +> [ 0.742942] (0:@) free_paje_event: event_type=7, timestamp=0.168300 > [ 0.742942] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.168300 > [ 0.742942] (0:@) free_paje_event: event_type=9, timestamp=0.168300 > [ 0.742942] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.168300 @@ -1809,12 +1809,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.745124] (0:@) insert_into_buffer: inserted at end, pos = 109 > [ 0.745124] (0:@) TRACE_paje_dump_buffer: dump until 0.168300. starts > [ 0.745124] (0:@) TRACE_paje_dump_buffer: ends -> [ 0.745124] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.745124 -> [ 0.745124] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.745124, buffersize=110) -> [ 0.745124] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 110 > [ 0.745124] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=0.745124 -> [ 0.745124] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=0.745124, buffersize=111) -> [ 0.745124] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 111 +> [ 0.745124] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=0.745124, buffersize=110) +> [ 0.745124] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 110 +> [ 0.745124] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.745124 +> [ 0.745124] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.745124, buffersize=111) +> [ 0.745124] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 111 > [ 0.823643] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.168300 > [ 0.823643] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.168300, buffersize=112) > [ 0.823643] (0:@) insert_into_buffer: inserted at 0 @@ -1914,10 +1914,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 0.823643] (0:@) free_paje_event: event_type=9, timestamp=0.386454 > [ 0.823643] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.386454 > [ 0.823643] (0:@) free_paje_event: event_type=9, timestamp=0.386454 -> [ 0.823643] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.386454 -> [ 0.823643] (0:@) free_paje_event: event_type=7, timestamp=0.386454 > [ 0.823643] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.386454 > [ 0.823643] (0:@) free_paje_event: event_type=8, timestamp=0.386454 +> [ 0.823643] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.386454 +> [ 0.823643] (0:@) free_paje_event: event_type=7, timestamp=0.386454 > [ 0.823643] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.386454 > [ 0.823643] (0:@) free_paje_event: event_type=9, timestamp=0.386454 > [ 0.823643] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.386454 @@ -2038,10 +2038,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.041796] (0:@) free_paje_event: event_type=9, timestamp=0.526913 > [ 1.041796] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.526913 > [ 1.041796] (0:@) free_paje_event: event_type=9, timestamp=0.526913 -> [ 1.041796] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.526913 -> [ 1.041796] (0:@) free_paje_event: event_type=7, timestamp=0.526913 > [ 1.041796] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.526913 > [ 1.041796] (0:@) free_paje_event: event_type=8, timestamp=0.526913 +> [ 1.041796] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.526913 +> [ 1.041796] (0:@) free_paje_event: event_type=7, timestamp=0.526913 > [ 1.041796] (0:@) TRACE_paje_dump_buffer: ends > [ 1.062347] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts > [ 1.062347] (0:@) TRACE_paje_dump_buffer: ends @@ -2155,12 +2155,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.262288] (0:@) insert_into_buffer: inserted at end, pos = 85 > [ 1.262288] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts > [ 1.262288] (0:@) TRACE_paje_dump_buffer: ends -> [ 1.262288] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.262288 -> [ 1.262288] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.262288, buffersize=86) -> [ 1.262288] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 86 > [ 1.262288] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=1.262288 -> [ 1.262288] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=1.262288, buffersize=87) -> [ 1.262288] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 87 +> [ 1.262288] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=1.262288, buffersize=86) +> [ 1.262288] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 86 +> [ 1.262288] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.262288 +> [ 1.262288] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.262288, buffersize=87) +> [ 1.262288] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 87 > [ 1.558011] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.526913 > [ 1.558011] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.526913, buffersize=88) > [ 1.558011] (0:@) insert_into_buffer: inserted at 0 @@ -2252,10 +2252,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.558011] (0:@) free_paje_event: event_type=9, timestamp=0.742786 > [ 1.558011] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.742786 > [ 1.558011] (0:@) free_paje_event: event_type=9, timestamp=0.742786 -> [ 1.558011] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.742786 -> [ 1.558011] (0:@) free_paje_event: event_type=7, timestamp=0.742786 > [ 1.558011] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.742786 > [ 1.558011] (0:@) free_paje_event: event_type=8, timestamp=0.742786 +> [ 1.558011] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.742786 +> [ 1.558011] (0:@) free_paje_event: event_type=7, timestamp=0.742786 > [ 1.558011] (0:@) TRACE_paje_dump_buffer: ends > [ 1.571243] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts > [ 1.571243] (0:@) TRACE_paje_dump_buffer: ends @@ -2404,10 +2404,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.773883] (0:@) free_paje_event: event_type=9, timestamp=0.745124 > [ 1.773883] (0:@) print_pajeSubVariable: event_type=9, timestamp=0.745124 > [ 1.773883] (0:@) free_paje_event: event_type=9, timestamp=0.745124 -> [ 1.773883] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.745124 -> [ 1.773883] (0:@) free_paje_event: event_type=7, timestamp=0.745124 > [ 1.773883] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.745124 > [ 1.773883] (0:@) free_paje_event: event_type=8, timestamp=0.745124 +> [ 1.773883] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.745124 +> [ 1.773883] (0:@) free_paje_event: event_type=7, timestamp=0.745124 > [ 1.773883] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.745124 > [ 1.773883] (0:@) free_paje_event: event_type=8, timestamp=0.745124 > [ 1.773883] (0:@) print_pajeAddVariable: event_type=8, timestamp=0.745124 @@ -2511,12 +2511,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.864433] (0:@) insert_into_buffer: inserted at end, pos = 45 > [ 1.864433] (0:@) TRACE_paje_dump_buffer: dump until 1.259949. starts > [ 1.864433] (0:@) TRACE_paje_dump_buffer: ends -> [ 1.864433] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.864433 -> [ 1.864433] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.864433, buffersize=46) -> [ 1.864433] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 46 > [ 1.864433] (3:slave@Jupiter) new_pajeAddVariable: event_type=8, timestamp=1.864433 -> [ 1.864433] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=1.864433, buffersize=47) -> [ 1.864433] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 47 +> [ 1.864433] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=1.864433, buffersize=46) +> [ 1.864433] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 46 +> [ 1.864433] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.864433 +> [ 1.864433] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.864433, buffersize=47) +> [ 1.864433] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 47 > [ 1.864589] (0:@) TRACE_paje_dump_buffer: dump until 1.259949. starts > [ 1.864589] (0:@) TRACE_paje_dump_buffer: ends > [ 1.866771] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.864589 @@ -2533,12 +2533,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.866771] (0:@) insert_into_buffer: inserted at end, pos = 51 > [ 1.866771] (0:@) TRACE_paje_dump_buffer: dump until 1.259949. starts > [ 1.866771] (0:@) TRACE_paje_dump_buffer: ends -> [ 1.866771] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.866771 -> [ 1.866771] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.866771, buffersize=52) -> [ 1.866771] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 52 > [ 1.866771] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=1.866771 -> [ 1.866771] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=1.866771, buffersize=53) -> [ 1.866771] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 53 +> [ 1.866771] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=1.866771, buffersize=52) +> [ 1.866771] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 52 +> [ 1.866771] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.866771 +> [ 1.866771] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.866771, buffersize=53) +> [ 1.866771] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 53 > [ 1.887107] (0:@) TRACE_paje_dump_buffer: dump until 1.259949. starts > [ 1.887107] (0:@) TRACE_paje_dump_buffer: ends > [ 1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.259949 @@ -2650,10 +2650,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 1.915291] (0:@) free_paje_event: event_type=9, timestamp=1.262288 > [ 1.915291] (0:@) print_pajeSubVariable: event_type=9, timestamp=1.262288 > [ 1.915291] (0:@) free_paje_event: event_type=9, timestamp=1.262288 -> [ 1.915291] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.262288 -> [ 1.915291] (0:@) free_paje_event: event_type=7, timestamp=1.262288 > [ 1.915291] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.262288 > [ 1.915291] (0:@) free_paje_event: event_type=8, timestamp=1.262288 +> [ 1.915291] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.262288 +> [ 1.915291] (0:@) free_paje_event: event_type=7, timestamp=1.262288 > [ 1.915291] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.262288 > [ 1.915291] (0:@) free_paje_event: event_type=8, timestamp=1.262288 > [ 1.915291] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.262288 @@ -2789,12 +2789,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 2.082643] (0:@) insert_into_buffer: inserted at end, pos = 81 > [ 2.082643] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts > [ 2.082643] (0:@) TRACE_paje_dump_buffer: ends -> [ 2.082643] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.082643 -> [ 2.082643] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.082643, buffersize=82) -> [ 2.082643] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 82 > [ 2.082643] (6:slave@Bourassa) new_pajeAddVariable: event_type=8, timestamp=2.082643 -> [ 2.082643] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=2.082643, buffersize=83) -> [ 2.082643] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 83 +> [ 2.082643] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=2.082643, buffersize=82) +> [ 2.082643] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 82 +> [ 2.082643] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.082643 +> [ 2.082643] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.082643, buffersize=83) +> [ 2.082643] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 83 > [ 2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.866771 > [ 2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.866771, buffersize=84) > [ 2.082643] (0:@) insert_into_buffer: inserted at 24 @@ -2883,12 +2883,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 2.300797] (0:@) insert_into_buffer: inserted at end, pos = 111 > [ 2.300797] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts > [ 2.300797] (0:@) TRACE_paje_dump_buffer: ends -> [ 2.300797] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.300797 -> [ 2.300797] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.300797, buffersize=112) -> [ 2.300797] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 112 > [ 2.300797] (4:slave@Fafard) new_pajeAddVariable: event_type=8, timestamp=2.300797 -> [ 2.300797] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=2.300797, buffersize=113) -> [ 2.300797] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 113 +> [ 2.300797] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=2.300797, buffersize=112) +> [ 2.300797] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 112 +> [ 2.300797] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.300797 +> [ 2.300797] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.300797, buffersize=113) +> [ 2.300797] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 113 > [ 2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.082643 > [ 2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.082643, buffersize=114) > [ 2.300797] (0:@) insert_into_buffer: inserted at 88 @@ -3022,10 +3022,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=1.864433 > [ 2.729569] (0:@) print_pajeSubVariable: event_type=9, timestamp=1.864433 > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=1.864433 -> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.864433 -> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=1.864433 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.864433 > [ 2.729569] (0:@) free_paje_event: event_type=8, timestamp=1.864433 +> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.864433 +> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=1.864433 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.864433 > [ 2.729569] (0:@) free_paje_event: event_type=8, timestamp=1.864433 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.864433 @@ -3038,10 +3038,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=1.866771 > [ 2.729569] (0:@) print_pajeSubVariable: event_type=9, timestamp=1.866771 > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=1.866771 -> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.866771 -> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=1.866771 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.866771 > [ 2.729569] (0:@) free_paje_event: event_type=8, timestamp=1.866771 +> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=1.866771 +> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=1.866771 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.866771 > [ 2.729569] (0:@) free_paje_event: event_type=8, timestamp=1.866771 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.866771 @@ -3162,10 +3162,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=2.082643 > [ 2.729569] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.082643 > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=2.082643 -> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=2.082643 -> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=2.082643 > [ 2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=2.082643 > [ 2.729569] (0:@) free_paje_event: event_type=8, timestamp=2.082643 +> [ 2.729569] (0:@) print_pajeSetVariable: event_type=7, timestamp=2.082643 +> [ 2.729569] (0:@) free_paje_event: event_type=7, timestamp=2.082643 > [ 2.729569] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.082643 > [ 2.729569] (0:@) free_paje_event: event_type=9, timestamp=2.082643 > [ 2.729569] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.082643 @@ -3362,10 +3362,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 3.113741] (0:@) free_paje_event: event_type=9, timestamp=2.300797 > [ 3.113741] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.300797 > [ 3.113741] (0:@) free_paje_event: event_type=9, timestamp=2.300797 -> [ 3.113741] (0:@) print_pajeSetVariable: event_type=7, timestamp=2.300797 -> [ 3.113741] (0:@) free_paje_event: event_type=7, timestamp=2.300797 > [ 3.113741] (0:@) print_pajeAddVariable: event_type=8, timestamp=2.300797 > [ 3.113741] (0:@) free_paje_event: event_type=8, timestamp=2.300797 +> [ 3.113741] (0:@) print_pajeSetVariable: event_type=7, timestamp=2.300797 +> [ 3.113741] (0:@) free_paje_event: event_type=7, timestamp=2.300797 > [ 3.113741] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.300797 > [ 3.113741] (0:@) free_paje_event: event_type=9, timestamp=2.300797 > [ 3.113741] (0:@) print_pajeSubVariable: event_type=9, timestamp=2.300797 @@ -3689,12 +3689,12 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 3.390164] (0:@) insert_into_buffer: inserted at end, pos = 97 > [ 3.390164] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts > [ 3.390164] (0:@) TRACE_paje_dump_buffer: ends -> [ 3.390164] (1:master@Tremblay) new_pajeNewEvent: event_type=15, timestamp=3.390164 -> [ 3.390164] (1:master@Tremblay) insert_into_buffer: insert event_type=15, timestamp=3.390164, buffersize=98) -> [ 3.390164] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 98 > [ 3.390164] (6:slave@Bourassa) new_pajeAddVariable: event_type=8, timestamp=3.390164 -> [ 3.390164] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=3.390164, buffersize=99) -> [ 3.390164] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 99 +> [ 3.390164] (6:slave@Bourassa) insert_into_buffer: insert event_type=8, timestamp=3.390164, buffersize=98) +> [ 3.390164] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 98 +> [ 3.390164] (1:master@Tremblay) new_pajeNewEvent: event_type=15, timestamp=3.390164 +> [ 3.390164] (1:master@Tremblay) insert_into_buffer: insert event_type=15, timestamp=3.390164, buffersize=99) +> [ 3.390164] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 99 > [ 3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.174292 > [ 3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.174292, buffersize=100) > [ 3.390164] (0:@) insert_into_buffer: inserted at 40 @@ -3962,10 +3962,10 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/fi > [ 3.901126] (0:@) free_paje_event: event_type=9, timestamp=3.390164 > [ 3.901126] (0:@) print_pajeSubVariable: event_type=9, timestamp=3.390164 > [ 3.901126] (0:@) free_paje_event: event_type=9, timestamp=3.390164 -> [ 3.901126] (0:@) print_pajeNewEvent: event_type=15, timestamp=3.390164 -> [ 3.901126] (0:@) free_paje_event: event_type=15, timestamp=3.390164 > [ 3.901126] (0:@) print_pajeAddVariable: event_type=8, timestamp=3.390164 > [ 3.901126] (0:@) free_paje_event: event_type=8, timestamp=3.390164 +> [ 3.901126] (0:@) print_pajeNewEvent: event_type=15, timestamp=3.390164 +> [ 3.901126] (0:@) free_paje_event: event_type=15, timestamp=3.390164 > [ 3.901126] (0:@) print_pajeSubVariable: event_type=9, timestamp=3.390164 > [ 3.901126] (0:@) free_paje_event: event_type=9, timestamp=3.390164 > [ 3.901126] (0:@) print_pajeSubVariable: event_type=9, timestamp=3.390164 @@ -4555,8 +4555,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 8 0.000156 20 26 458160000.000000 > 9 0.002339 9 26 458160000.000000 > 9 0.002339 20 26 458160000.000000 -> 7 0.002339 17 2 1.000000 > 8 0.002339 18 2 50000000.000000 +> 7 0.002339 17 2 1.000000 > 7 0.002339 4 2 0.000000 > 8 0.002339 4 2 98095000.000000 > 7 0.002339 21 2 0.000000 @@ -4567,8 +4567,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 8 0.017538 20 17 6632970.000000 > 9 0.168300 9 17 6632970.000000 > 9 0.168300 20 17 6632970.000000 -> 7 0.168300 17 2 2.000000 > 8 0.168300 18 3 50000000.000000 +> 7 0.168300 17 2 2.000000 > 9 0.168300 4 2 98095000.000000 > 9 0.168300 21 2 98095000.000000 > 8 0.168300 4 2 98095000.000000 @@ -4613,8 +4613,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 0.386454 20 21 5060664.718311 > 9 0.386454 9 20 5060664.718311 > 9 0.386454 20 20 5060664.718311 -> 7 0.386454 17 2 3.000000 > 8 0.386454 18 4 50000000.000000 +> 7 0.386454 17 2 3.000000 > 9 0.386454 4 2 98095000.000000 > 9 0.386454 21 2 98095000.000000 > 8 0.386454 4 2 98095000.000000 @@ -4651,8 +4651,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 0.526913 20 15 7859911.230163 > 9 0.526913 9 25 7859911.230163 > 9 0.526913 20 25 7859911.230163 -> 7 0.526913 17 2 4.000000 > 8 0.526913 18 5 50000000.000000 +> 7 0.526913 17 2 4.000000 > 7 0.526913 4 5 0.000000 > 8 0.526913 4 5 48492000.000000 > 7 0.526913 21 5 0.000000 @@ -4689,8 +4689,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 0.742786 20 13 5114134.701194 > 9 0.742786 9 16 5114134.701194 > 9 0.742786 20 16 5114134.701194 -> 7 0.742786 17 2 5.000000 > 8 0.742786 18 6 50000000.000000 +> 7 0.742786 17 2 5.000000 > 7 0.742786 4 6 0.000000 > 8 0.742786 4 6 48492000.000000 > 7 0.742786 21 6 0.000000 @@ -4699,8 +4699,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 8 0.742942 20 26 458160000.000000 > 9 0.745124 9 26 458160000.000000 > 9 0.745124 20 26 458160000.000000 -> 7 0.745124 17 2 6.000000 > 8 0.745124 18 2 50000000.000000 +> 7 0.745124 17 2 6.000000 > 8 0.745124 4 2 98095000.000000 > 8 0.745124 21 2 98095000.000000 > 9 0.823643 4 3 76296000.000000 @@ -4751,8 +4751,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 8 1.260105 20 26 458160000.000000 > 9 1.262288 9 26 458160000.000000 > 9 1.262288 20 26 458160000.000000 -> 7 1.262288 17 2 9.000000 > 8 1.262288 18 2 50000000.000000 +> 7 1.262288 17 2 9.000000 > 8 1.262288 4 2 98095000.000000 > 8 1.262288 21 2 98095000.000000 > 9 1.558011 4 5 48492000.000000 @@ -4795,16 +4795,16 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 8 1.773883 20 17 6632970.000000 > 9 1.864433 9 17 6632970.000000 > 9 1.864433 20 17 6632970.000000 -> 7 1.864433 17 2 11.000000 > 8 1.864433 18 3 50000000.000000 +> 7 1.864433 17 2 11.000000 > 8 1.864433 4 3 76296000.000000 > 8 1.864433 21 3 76296000.000000 > 8 1.864589 9 26 458160000.000000 > 8 1.864589 20 26 458160000.000000 > 9 1.866771 9 26 458160000.000000 > 9 1.866771 20 26 458160000.000000 -> 7 1.866771 17 2 12.000000 > 8 1.866771 18 2 50000000.000000 +> 7 1.866771 17 2 12.000000 > 8 1.866771 4 2 98095000.000000 > 8 1.866771 21 2 98095000.000000 > 8 1.887107 9 22 5114134.701194 @@ -4865,8 +4865,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 2.082643 20 13 5114134.701194 > 9 2.082643 9 16 5114134.701194 > 9 2.082643 20 16 5114134.701194 -> 7 2.082643 17 2 13.000000 > 8 2.082643 18 6 50000000.000000 +> 7 2.082643 17 2 13.000000 > 9 2.082643 4 2 98095000.000000 > 9 2.082643 21 2 98095000.000000 > 8 2.082643 4 2 98095000.000000 @@ -4897,8 +4897,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 2.300797 20 21 5060664.718311 > 9 2.300797 9 20 5060664.718311 > 9 2.300797 20 20 5060664.718311 -> 7 2.300797 17 2 14.000000 > 8 2.300797 18 4 50000000.000000 +> 7 2.300797 17 2 14.000000 > 9 2.300797 4 2 98095000.000000 > 9 2.300797 21 2 98095000.000000 > 8 2.300797 4 2 98095000.000000 @@ -5043,8 +5043,8 @@ $ $SG_TEST_EXENV cat tracing/ms.trace > 9 3.390164 20 13 5114134.701194 > 9 3.390164 9 16 5114134.701194 > 9 3.390164 20 16 5114134.701194 -> 15 3.390164 19 0 finish_send_tasks > 8 3.390164 18 6 50000000.000000 +> 15 3.390164 19 0 finish_send_tasks > 9 3.390164 4 2 98095000.000000 > 9 3.390164 21 2 98095000.000000 > 8 3.390164 4 2 98095000.000000 diff --git a/examples/msg/tracing/procmig.tesh b/examples/msg/tracing/procmig.tesh index 0b28d51f1d..c80eb48bc8 100644 --- a/examples/msg/tracing/procmig.tesh +++ b/examples/msg/tracing/procmig.tesh @@ -375,115 +375,115 @@ $ cat tracing/procmig.trace > 13 0 7 0 SR 28 p0 > 12 2.000000 5 27 > 11 2.000000 5 27 receive +> 12 2.020551 5 28 +> 11 2.020551 5 28 send +> 13 2.020551 7 0 SR 28 p1 > 12 2.020551 5 27 > 14 2.020551 7 0 SR 27 p0 -> 12 2.020551 5 28 > 13 2.020551 6 0 M 27 0 > 6 2.020551 4 27 > 5 2.020551 29 4 2 emigrant-1 > 10 2.020551 5 29 executing > 14 2.020551 6 0 M 29 0 -> 11 2.020551 5 28 send -> 13 2.020551 7 0 SR 28 p1 > 11 2.020551 5 29 sleep > 12 4.020551 5 29 > 11 4.020551 5 29 receive +> 12 4.020707 5 28 +> 11 4.020707 5 28 send +> 13 4.020707 7 0 SR 28 p2 > 12 4.020707 5 29 > 14 4.020707 7 0 SR 29 p1 -> 12 4.020707 5 28 > 13 4.020707 6 0 M 29 1 > 6 4.020707 4 29 > 5 4.020707 30 4 3 emigrant-1 > 10 4.020707 5 30 executing > 14 4.020707 6 0 M 30 1 -> 11 4.020707 5 28 send -> 13 4.020707 7 0 SR 28 p2 > 11 4.020707 5 30 sleep > 12 6.020707 5 30 > 11 6.020707 5 30 receive +> 12 6.035906 5 28 +> 11 6.035906 5 28 send +> 13 6.035906 7 0 SR 28 p3 > 12 6.035906 5 30 > 14 6.035906 7 0 SR 30 p2 -> 12 6.035906 5 28 > 13 6.035906 6 0 M 30 2 > 6 6.035906 4 30 > 5 6.035906 31 4 4 emigrant-1 > 10 6.035906 5 31 executing > 14 6.035906 6 0 M 31 2 -> 11 6.035906 5 28 send -> 13 6.035906 7 0 SR 28 p3 > 11 6.035906 5 31 sleep > 12 8.035906 5 31 > 11 8.035906 5 31 receive +> 12 8.056457 5 28 +> 11 8.056457 5 28 send +> 13 8.056457 7 0 SR 28 p4 > 12 8.056457 5 31 > 14 8.056457 7 0 SR 31 p3 -> 12 8.056457 5 28 > 13 8.056457 6 0 M 31 3 > 6 8.056457 4 31 > 5 8.056457 32 4 5 emigrant-1 > 10 8.056457 5 32 executing > 14 8.056457 6 0 M 32 3 -> 11 8.056457 5 28 send -> 13 8.056457 7 0 SR 28 p4 > 11 8.056457 5 32 sleep > 12 10.056457 5 32 > 11 10.056457 5 32 receive +> 12 10.069689 5 28 +> 11 10.069689 5 28 send +> 13 10.069689 7 0 SR 28 p5 > 12 10.069689 5 32 > 14 10.069689 7 0 SR 32 p4 -> 12 10.069689 5 28 > 13 10.069689 6 0 M 32 4 > 6 10.069689 4 32 > 5 10.069689 33 4 6 emigrant-1 > 10 10.069689 5 33 executing > 14 10.069689 6 0 M 33 4 -> 11 10.069689 5 28 send -> 13 10.069689 7 0 SR 28 p5 > 11 10.069689 5 33 sleep > 12 12.069689 5 33 > 11 12.069689 5 33 receive +> 12 12.090025 5 28 +> 11 12.090025 5 28 send +> 13 12.090025 7 0 SR 28 p6 > 12 12.090025 5 33 > 14 12.090025 7 0 SR 33 p5 -> 12 12.090025 5 28 > 13 12.090025 6 0 M 33 5 > 6 12.090025 4 33 > 5 12.090025 34 4 4 emigrant-1 > 10 12.090025 5 34 executing > 14 12.090025 6 0 M 34 5 -> 11 12.090025 5 28 send -> 13 12.090025 7 0 SR 28 p6 > 11 12.090025 5 34 sleep > 12 14.090025 5 34 > 11 14.090025 5 34 receive +> 12 14.110575 5 28 +> 11 14.110575 5 28 send +> 13 14.110575 7 0 SR 28 p7 > 12 14.110575 5 34 > 14 14.110575 7 0 SR 34 p6 -> 12 14.110575 5 28 > 13 14.110575 6 0 M 34 6 > 6 14.110575 4 34 > 5 14.110575 35 4 2 emigrant-1 > 10 14.110575 5 35 executing > 14 14.110575 6 0 M 35 6 -> 11 14.110575 5 28 send -> 13 14.110575 7 0 SR 28 p7 > 11 14.110575 5 35 sleep > 12 16.110575 5 35 > 11 16.110575 5 35 receive +> 12 16.110731 5 28 +> 11 16.110731 5 28 send +> 13 16.110731 7 0 SR 28 p8 > 12 16.110731 5 35 > 14 16.110731 7 0 SR 35 p7 -> 12 16.110731 5 28 > 13 16.110731 6 0 M 35 7 > 6 16.110731 4 35 > 5 16.110731 36 4 5 emigrant-1 > 10 16.110731 5 36 executing > 14 16.110731 6 0 M 36 7 -> 11 16.110731 5 28 send -> 13 16.110731 7 0 SR 28 p8 > 11 16.110731 5 36 sleep > 12 18.110731 5 36 > 11 18.110731 5 36 receive +> 12 18.123963 5 28 +> 6 18.123963 4 28 > 12 18.123963 5 36 > 14 18.123963 7 0 SR 36 p8 -> 12 18.123963 5 28 > 6 18.123963 4 36 -> 6 18.123963 4 28 > 6 18.123963 2 4 > 6 18.123963 8 9 > 6 18.123963 8 7 diff --git a/examples/msg/tracing/tasks.tesh b/examples/msg/tracing/tasks.tesh index f7722923a7..6aeab433bf 100644 --- a/examples/msg/tracing/tasks.tesh +++ b/examples/msg/tracing/tasks.tesh @@ -49,6 +49,7 @@ $ $SG_TEST_EXENV ${bindir:=.}/tracing/tasks$EXEEXT --cfg=tracing:1 --cfg=tracing > [Bourassa:slave:(6) 4.421262] [msg_test/INFO] End of execution > [4.441598] [msg_test/INFO] Simulation time 4.4416 + $ cat tracing/tasks.trace > %EventDef PajeDefineContainerType 0 > % Alias string @@ -405,311 +406,311 @@ $ cat tracing/tasks.trace > 12 0 5 27 > 13 0 6 0 SR 27 0 > 6 0 4 27 -> 5 0.002339 28 4 2 task-1 -> 5 0.002339 29 4 2 task-0 +> 5 0.002339 28 4 2 task-0 +> 5 0.002339 29 4 2 task-1 > 7 0.002339 16 28 1.000000 > 11 0.002339 5 28 created +> 14 0.002339 6 0 SR 28 0 +> 11 0.002339 5 28 MSG_task_execute > 7 0.002339 16 29 1.000000 > 11 0.002339 5 29 created -> 14 0.002339 6 0 SR 29 0 -> 11 0.002339 5 29 MSG_task_execute -> 12 0.002339 5 28 -> 13 0.002339 6 0 SR 28 1 -> 6 0.002339 4 28 -> 5 0.168300 30 4 2 task-2 -> 5 0.168300 31 4 3 task-1 +> 12 0.002339 5 29 +> 13 0.002339 6 0 SR 29 1 +> 6 0.002339 4 29 +> 5 0.168300 30 4 3 task-1 +> 5 0.168300 31 4 2 task-2 > 7 0.168300 16 30 1.000000 > 11 0.168300 5 30 created +> 14 0.168300 6 0 SR 30 1 +> 11 0.168300 5 30 MSG_task_execute > 7 0.168300 16 31 1.000000 > 11 0.168300 5 31 created -> 14 0.168300 6 0 SR 31 1 -> 11 0.168300 5 31 MSG_task_execute -> 12 0.168300 5 30 -> 13 0.168300 6 0 SR 30 2 -> 6 0.168300 4 30 -> 5 0.386454 32 4 2 task-3 -> 5 0.386454 33 4 4 task-2 +> 12 0.168300 5 31 +> 13 0.168300 6 0 SR 31 2 +> 6 0.168300 4 31 +> 5 0.386454 32 4 4 task-2 +> 5 0.386454 33 4 2 task-3 > 7 0.386454 16 32 1.000000 > 11 0.386454 5 32 created +> 14 0.386454 6 0 SR 32 2 +> 11 0.386454 5 32 MSG_task_execute > 7 0.386454 16 33 1.000000 > 11 0.386454 5 33 created -> 14 0.386454 6 0 SR 33 2 -> 11 0.386454 5 33 MSG_task_execute -> 12 0.386454 5 32 -> 13 0.386454 6 0 SR 32 3 -> 6 0.386454 4 32 -> 12 0.512049 5 29 -> 6 0.512049 4 29 -> 5 0.526913 34 4 2 task-4 -> 5 0.526913 35 4 5 task-3 +> 12 0.386454 5 33 +> 13 0.386454 6 0 SR 33 3 +> 6 0.386454 4 33 +> 12 0.512049 5 28 +> 6 0.512049 4 28 +> 5 0.526913 34 4 5 task-3 +> 5 0.526913 35 4 2 task-4 > 7 0.526913 16 34 1.000000 > 11 0.526913 5 34 created +> 14 0.526913 6 0 SR 34 3 +> 11 0.526913 5 34 MSG_task_execute > 7 0.526913 16 35 1.000000 > 11 0.526913 5 35 created -> 14 0.526913 6 0 SR 35 3 -> 11 0.526913 5 35 MSG_task_execute -> 12 0.526913 5 34 -> 13 0.526913 6 0 SR 34 4 -> 6 0.526913 4 34 -> 5 0.742786 36 4 2 task-5 -> 5 0.742786 37 4 6 task-4 +> 12 0.526913 5 35 +> 13 0.526913 6 0 SR 35 4 +> 6 0.526913 4 35 +> 5 0.742786 36 4 6 task-4 +> 5 0.742786 37 4 2 task-5 > 7 0.742786 16 36 1.000000 > 11 0.742786 5 36 created +> 14 0.742786 6 0 SR 36 4 +> 11 0.742786 5 36 MSG_task_execute > 7 0.742786 16 37 1.000000 > 11 0.742786 5 37 created -> 14 0.742786 6 0 SR 37 4 -> 11 0.742786 5 37 MSG_task_execute -> 12 0.742786 5 36 -> 13 0.742786 6 0 SR 36 5 -> 6 0.742786 4 36 -> 5 0.745124 38 4 2 task-6 -> 5 0.745124 39 4 2 task-5 +> 12 0.742786 5 37 +> 13 0.742786 6 0 SR 37 5 +> 6 0.742786 4 37 +> 5 0.745124 38 4 2 task-5 +> 5 0.745124 39 4 2 task-6 > 7 0.745124 16 38 1.000000 > 11 0.745124 5 38 created +> 14 0.745124 6 0 SR 38 5 +> 11 0.745124 5 38 MSG_task_execute > 7 0.745124 16 39 1.000000 > 11 0.745124 5 39 created -> 14 0.745124 6 0 SR 39 5 -> 11 0.745124 5 39 MSG_task_execute -> 12 0.745124 5 38 -> 13 0.745124 6 0 SR 38 6 -> 6 0.745124 4 38 -> 12 0.823643 5 31 -> 6 0.823643 4 31 +> 12 0.745124 5 39 +> 13 0.745124 6 0 SR 39 6 +> 6 0.745124 4 39 +> 12 0.823643 5 30 +> 6 0.823643 4 30 > 5 0.989605 40 4 2 task-7 -> 5 0.989605 41 4 3 task-6 > 7 0.989605 16 40 1.000000 > 11 0.989605 5 40 created +> 12 0.989605 5 40 +> 13 0.989605 6 0 SR 40 7 +> 6 0.989605 4 40 +> 5 0.989605 41 4 3 task-6 > 7 0.989605 16 41 1.000000 > 11 0.989605 5 41 created > 14 0.989605 6 0 SR 41 6 > 11 0.989605 5 41 MSG_task_execute -> 12 0.989605 5 40 -> 13 0.989605 6 0 SR 40 7 -> 6 0.989605 4 40 -> 12 1.041796 5 33 -> 6 1.041796 4 33 -> 12 1.254834 5 39 -> 6 1.254834 4 39 +> 12 1.041796 5 32 +> 6 1.041796 4 32 +> 12 1.254834 5 38 +> 6 1.254834 4 38 > 5 1.259949 42 4 2 task-8 -> 5 1.259949 43 4 4 task-7 > 7 1.259949 16 42 1.000000 > 11 1.259949 5 42 created +> 12 1.259949 5 42 +> 13 1.259949 6 0 SR 42 8 +> 6 1.259949 4 42 +> 5 1.259949 43 4 4 task-7 +> 5 1.262288 44 4 2 task-8 +> 5 1.262288 45 4 2 task-9 > 7 1.259949 16 43 1.000000 > 11 1.259949 5 43 created > 14 1.259949 6 0 SR 43 7 > 11 1.259949 5 43 MSG_task_execute -> 12 1.259949 5 42 -> 13 1.259949 6 0 SR 42 8 -> 6 1.259949 4 42 -> 5 1.262288 44 4 2 task-9 -> 5 1.262288 45 4 2 task-8 > 7 1.262288 16 44 1.000000 > 11 1.262288 5 44 created +> 14 1.262288 6 0 SR 44 8 +> 11 1.262288 5 44 MSG_task_execute > 7 1.262288 16 45 1.000000 > 11 1.262288 5 45 created -> 14 1.262288 6 0 SR 45 8 -> 11 1.262288 5 45 MSG_task_execute -> 12 1.262288 5 44 -> 13 1.262288 6 0 SR 44 9 -> 6 1.262288 4 44 -> 12 1.558011 5 35 -> 6 1.558011 4 35 +> 12 1.262288 5 45 +> 13 1.262288 6 0 SR 45 9 +> 6 1.262288 4 45 +> 12 1.558011 5 34 +> 6 1.558011 4 34 > 12 1.644947 5 41 > 6 1.644947 4 41 > 5 1.698471 46 4 2 task-10 -> 5 1.698471 47 4 5 task-9 > 7 1.698471 16 46 1.000000 > 11 1.698471 5 46 created +> 12 1.698471 5 46 +> 13 1.698471 6 0 SR 46 10 +> 6 1.698471 4 46 +> 5 1.698471 47 4 5 task-9 > 7 1.698471 16 47 1.000000 > 11 1.698471 5 47 created > 14 1.698471 6 0 SR 47 9 > 11 1.698471 5 47 MSG_task_execute -> 12 1.698471 5 46 -> 13 1.698471 6 0 SR 46 10 -> 6 1.698471 4 46 -> 12 1.771998 5 45 -> 6 1.771998 4 45 -> 12 1.773883 5 37 -> 6 1.773883 4 37 -> 5 1.864433 48 4 2 task-11 -> 5 1.864433 49 4 3 task-10 +> 12 1.771998 5 44 +> 6 1.771998 4 44 +> 12 1.773883 5 36 +> 6 1.773883 4 36 +> 5 1.864433 48 4 3 task-10 +> 5 1.864433 49 4 2 task-11 > 7 1.864433 16 48 1.000000 > 11 1.864433 5 48 created +> 14 1.864433 6 0 SR 48 10 +> 11 1.864433 5 48 MSG_task_execute > 7 1.864433 16 49 1.000000 > 11 1.864433 5 49 created -> 14 1.864433 6 0 SR 49 10 -> 11 1.864433 5 49 MSG_task_execute -> 12 1.864433 5 48 -> 13 1.864433 6 0 SR 48 11 -> 6 1.864433 4 48 -> 5 1.866771 50 4 2 task-12 -> 5 1.866771 51 4 2 task-11 +> 12 1.864433 5 49 +> 13 1.864433 6 0 SR 49 11 +> 6 1.864433 4 49 +> 5 1.866771 50 4 2 task-11 +> 5 1.866771 51 4 2 task-12 > 7 1.866771 16 50 1.000000 > 11 1.866771 5 50 created +> 14 1.866771 6 0 SR 50 11 +> 11 1.866771 5 50 MSG_task_execute > 7 1.866771 16 51 1.000000 > 11 1.866771 5 51 created -> 14 1.866771 6 0 SR 51 11 -> 11 1.866771 5 51 MSG_task_execute -> 12 1.866771 5 50 -> 13 1.866771 6 0 SR 50 12 -> 6 1.866771 4 50 +> 12 1.866771 5 51 +> 13 1.866771 6 0 SR 51 12 +> 6 1.866771 4 51 > 12 1.915291 5 43 > 6 1.915291 4 43 -> 5 2.082643 52 4 2 task-13 -> 5 2.082643 53 4 6 task-12 +> 5 2.082643 52 4 6 task-12 +> 5 2.082643 53 4 2 task-13 > 7 2.082643 16 52 1.000000 > 11 2.082643 5 52 created +> 14 2.082643 6 0 SR 52 12 +> 11 2.082643 5 52 MSG_task_execute > 7 2.082643 16 53 1.000000 > 11 2.082643 5 53 created -> 14 2.082643 6 0 SR 53 12 -> 11 2.082643 5 53 MSG_task_execute -> 12 2.082643 5 52 -> 13 2.082643 6 0 SR 52 13 -> 6 2.082643 4 52 -> 5 2.300797 54 4 2 task-14 -> 5 2.300797 55 4 4 task-13 +> 12 2.082643 5 53 +> 13 2.082643 6 0 SR 53 13 +> 6 2.082643 4 53 +> 5 2.300797 54 4 4 task-13 +> 5 2.300797 55 4 2 task-14 > 7 2.300797 16 54 1.000000 > 11 2.300797 5 54 created +> 14 2.300797 6 0 SR 54 13 +> 11 2.300797 5 54 MSG_task_execute > 7 2.300797 16 55 1.000000 > 11 2.300797 5 55 created -> 14 2.300797 6 0 SR 55 13 -> 11 2.300797 5 55 MSG_task_execute -> 12 2.300797 5 54 -> 13 2.300797 6 0 SR 54 14 -> 6 2.300797 4 54 -> 12 2.376481 5 51 -> 6 2.376481 4 51 +> 12 2.300797 5 55 +> 13 2.300797 6 0 SR 55 14 +> 6 2.300797 4 55 +> 12 2.376481 5 50 +> 6 2.376481 4 50 > 5 2.378820 56 4 2 task-15 -> 5 2.378820 57 4 2 task-14 > 7 2.378820 16 56 1.000000 > 11 2.378820 5 56 created +> 12 2.378820 5 56 +> 13 2.378820 6 0 SR 56 15 +> 6 2.378820 4 56 +> 5 2.378820 57 4 2 task-14 > 7 2.378820 16 57 1.000000 > 11 2.378820 5 57 created > 14 2.378820 6 0 SR 57 14 > 11 2.378820 5 57 MSG_task_execute -> 12 2.378820 5 56 -> 13 2.378820 6 0 SR 56 15 -> 6 2.378820 4 56 -> 12 2.519775 5 49 -> 6 2.519775 4 49 +> 12 2.519775 5 48 +> 6 2.519775 4 48 > 5 2.685737 58 4 2 task-16 -> 5 2.685737 59 4 3 task-15 > 7 2.685737 16 58 1.000000 > 11 2.685737 5 58 created +> 12 2.685737 5 58 +> 13 2.685737 6 0 SR 58 16 +> 6 2.685737 4 58 +> 5 2.685737 59 4 3 task-15 > 7 2.685737 16 59 1.000000 > 11 2.685737 5 59 created > 14 2.685737 6 0 SR 59 15 > 11 2.685737 5 59 MSG_task_execute -> 12 2.685737 5 58 -> 13 2.685737 6 0 SR 58 16 -> 6 2.685737 4 58 > 12 2.729569 5 47 > 6 2.729569 4 47 > 5 2.870028 60 4 2 task-17 -> 5 2.870028 61 4 5 task-16 > 7 2.870028 16 60 1.000000 > 11 2.870028 5 60 created +> 12 2.870028 5 60 +> 13 2.870028 6 0 SR 60 17 +> 6 2.870028 4 60 +> 5 2.870028 61 4 5 task-16 > 7 2.870028 16 61 1.000000 > 11 2.870028 5 61 created > 14 2.870028 6 0 SR 61 16 > 11 2.870028 5 61 MSG_task_execute -> 12 2.870028 5 60 -> 13 2.870028 6 0 SR 60 17 -> 6 2.870028 4 60 > 12 2.888530 5 57 > 6 2.888530 4 57 > 5 2.890868 62 4 2 task-18 -> 5 2.890868 63 4 2 task-17 > 7 2.890868 16 62 1.000000 > 11 2.890868 5 62 created +> 12 2.890868 5 62 +> 13 2.890868 6 0 SR 62 18 +> 6 2.890868 4 62 +> 5 2.890868 63 4 2 task-17 > 7 2.890868 16 63 1.000000 > 11 2.890868 5 63 created > 14 2.890868 6 0 SR 63 17 > 11 2.890868 5 63 MSG_task_execute -> 12 2.890868 5 62 -> 13 2.890868 6 0 SR 62 18 -> 6 2.890868 4 62 -> 12 2.956139 5 55 -> 6 2.956139 4 55 -> 12 3.113741 5 53 -> 6 3.113741 4 53 +> 12 2.956139 5 54 +> 6 2.956139 4 54 +> 12 3.113741 5 52 +> 6 3.113741 4 52 > 5 3.174292 64 4 2 task-19 -> 5 3.174292 65 4 4 task-18 > 7 3.174292 16 64 1.000000 > 11 3.174292 5 64 created +> 12 3.174292 5 64 +> 13 3.174292 6 0 SR 64 19 +> 6 3.174292 4 64 +> 5 3.174292 65 4 4 task-18 > 7 3.174292 16 65 1.000000 > 11 3.174292 5 65 created > 14 3.174292 6 0 SR 65 18 > 11 3.174292 5 65 MSG_task_execute -> 12 3.174292 5 64 -> 13 3.174292 6 0 SR 64 19 -> 6 3.174292 4 64 > 12 3.341079 5 59 > 6 3.341079 4 59 -> 5 3.390164 66 4 2 task-0 +> 5 3.390164 66 4 6 task-19 +> 5 3.390164 67 4 2 task-0 > 1 17 4 finalize "1 1 1" -> 5 3.390164 67 4 6 task-19 -> 7 3.390164 17 66 1.000000 +> 7 3.390164 16 66 1.000000 > 11 3.390164 5 66 created -> 7 3.390164 16 67 1.000000 +> 14 3.390164 6 0 SR 66 19 +> 11 3.390164 5 66 MSG_task_execute +> 7 3.390164 17 67 1.000000 > 11 3.390164 5 67 created -> 14 3.390164 6 0 SR 67 19 -> 11 3.390164 5 67 MSG_task_execute -> 12 3.390164 5 66 -> 13 3.390164 6 0 SR 66 20 -> 6 3.390164 4 66 +> 12 3.390164 5 67 +> 13 3.390164 6 0 SR 67 20 +> 6 3.390164 4 67 > 12 3.400578 5 63 > 6 3.400578 4 63 -> 5 3.405364 68 4 2 task-1 -> 5 3.405364 69 4 3 task-0 +> 5 3.405364 68 4 3 task-0 > 7 3.405364 17 68 1.000000 > 11 3.405364 5 68 created +> 14 3.405364 6 0 SR 68 20 +> 6 3.405364 4 68 +> 5 3.405364 69 4 2 task-1 > 7 3.405364 17 69 1.000000 > 11 3.405364 5 69 created -> 14 3.405364 6 0 SR 69 20 +> 12 3.405364 5 69 +> 13 3.405364 6 0 SR 69 21 > 6 3.405364 4 69 -> 12 3.405364 5 68 -> 13 3.405364 6 0 SR 68 21 -> 6 3.405364 4 68 -> 5 3.405520 70 4 2 task-2 -> 5 3.405520 71 4 2 task-1 +> 5 3.405520 70 4 2 task-1 > 7 3.405520 17 70 1.000000 > 11 3.405520 5 70 created +> 14 3.405520 6 0 SR 70 21 +> 6 3.405520 4 70 +> 5 3.405520 71 4 2 task-2 > 7 3.405520 17 71 1.000000 > 11 3.405520 5 71 created -> 14 3.405520 6 0 SR 71 21 +> 12 3.405520 5 71 +> 13 3.405520 6 0 SR 71 22 > 6 3.405520 4 71 -> 12 3.405520 5 70 -> 13 3.405520 6 0 SR 70 22 -> 6 3.405520 4 70 > 12 3.829634 5 65 > 6 3.829634 4 65 > 5 3.850185 72 4 2 task-3 -> 5 3.850185 73 4 4 task-2 > 7 3.850185 17 72 1.000000 > 11 3.850185 5 72 created +> 12 3.850185 5 72 +> 13 3.850185 6 0 SR 72 23 +> 6 3.850185 4 72 +> 5 3.850185 73 4 4 task-2 > 7 3.850185 17 73 1.000000 > 11 3.850185 5 73 created > 14 3.850185 6 0 SR 73 22 > 6 3.850185 4 73 -> 12 3.850185 5 72 -> 13 3.850185 6 0 SR 72 23 -> 6 3.850185 4 72 > 12 3.901126 5 61 > 6 3.901126 4 61 > 5 3.914358 74 4 2 task-4 -> 5 3.914358 75 4 5 task-3 > 7 3.914358 17 74 1.000000 > 11 3.914358 5 74 created +> 12 3.914358 5 74 +> 13 3.914358 6 0 SR 74 24 +> 6 3.914358 4 74 +> 5 3.914358 75 4 5 task-3 > 7 3.914358 17 75 1.000000 > 11 3.914358 5 75 created > 14 3.914358 6 0 SR 75 23 > 6 3.914358 4 75 -> 12 3.914358 5 74 -> 13 3.914358 6 0 SR 74 24 -> 6 3.914358 4 74 -> 12 4.421262 5 67 -> 6 4.421262 4 67 +> 12 4.421262 5 66 +> 6 4.421262 4 66 > 5 4.441598 76 4 6 task-4 > 7 4.441598 17 76 1.000000 > 11 4.441598 5 76 created -- 2.20.1