Logo AND Algorithmique Numérique Distribuée

Public GIT Repository
[trace] updating tracing tesh'es to check correct container destruction order
authorschnorr <schnorr@48e7efb5-ca39-0410-a469-dd3cf9ba447f>
Mon, 3 Jan 2011 14:49:19 +0000 (14:49 +0000)
committerschnorr <schnorr@48e7efb5-ca39-0410-a469-dd3cf9ba447f>
Mon, 3 Jan 2011 14:49:19 +0000 (14:49 +0000)
git-svn-id: svn+ssh://scm.gforge.inria.fr/svn/simgrid/simgrid/trunk@9349 48e7efb5-ca39-0410-a469-dd3cf9ba447f

examples/msg/tracing/ms.tesh
examples/msg/tracing/procmig.tesh
examples/msg/tracing/tasks.tesh

index 71f96ca..3e8f5e6 100644 (file)
@@ -4017,167 +4017,167 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  4.441598] (0:@) free_paje_event: event_type=9, timestamp=4.421262
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
 > [  4.441598] (0:@) destroy container Fafard
+> [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
+> [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.2-0
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.2-0
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.4-3
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.4-3
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.3-2-5
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.3-2-5
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.1-8-6
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.1-8-6
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.6-7
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.6-7
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container Tremblay
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container Tremblay
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 10
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 10
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 11
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 11
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 12
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 12
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container loopback
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container loopback
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container R.1-0
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container R.1-0
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 0
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 0
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 1
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 1
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 2
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 2
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 3
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 3
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 4
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 4
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 5
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 5
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 6
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 6
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 7
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 7
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 8
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 8
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 9
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 9
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container Bourassa
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container Bourassa
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container Jupiter
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container Jupiter
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container Ginette
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container Ginette
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container AS0
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container AS0
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
+> [  4.441598] (0:@) destroy container 0
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
 > [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
-> [  4.441598] (0:@) destroy container 0
 > [  4.441598] (0:@) new_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) print_pajeDestroyContainer: event_type=6, timestamp=4.441598
 > [  4.441598] (0:@) free_paje_event: event_type=6, timestamp=4.441598
-> [  4.441598] (0:@) TRACE_paje_dump_buffer: dump until 4.441598. starts
-> [  4.441598] (0:@) TRACE_paje_dump_buffer: ends
 > [  4.441598] (0:@) Filename tracing/ms.trace is closed
 
 $ $SG_TEST_EXENV cat tracing/ms.trace
index 301aa7d..981f5a7 100644 (file)
@@ -7,12 +7,15 @@ $ $SG_TEST_EXENV tracing/procmig$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:t
 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'tracing/filename' to 'tracing/procmig.trace'
 > [0.000000] [xbt_cfg/INFO] Configuration change: Set 'tracing/msg/process' to '1'
 > [Fafard:emigrant:(1) 0.000000] [msg_test/INFO] Setting process category
-> [Fafard:emigrant:(1) 2.000000] [msg_test/INFO] Migrating to Tremblay
-> [Tremblay:emigrant:(1) 4.000000] [msg_test/INFO] Migrating to Jupiter
-> [Jupiter:emigrant:(1) 6.000000] [msg_test/INFO] Migrating to Fafard
-> [Fafard:emigrant:(1) 8.000000] [msg_test/INFO] Migrating to Ginette
-> [Ginette:emigrant:(1) 10.000000] [msg_test/INFO] Migrating to Bourassa
-> [12.000000] [msg_test/INFO] Simulation time 12
+> [Fafard:emigrant:(1) 2.020551] [msg_test/INFO] Migrating to Tremblay
+> [Tremblay:emigrant:(1) 4.020707] [msg_test/INFO] Migrating to Jupiter
+> [Jupiter:emigrant:(1) 6.035906] [msg_test/INFO] Migrating to Fafard
+> [Fafard:emigrant:(1) 8.056457] [msg_test/INFO] Migrating to Ginette
+> [Ginette:emigrant:(1) 10.069689] [msg_test/INFO] Migrating to Bourassa
+> [Bourassa:emigrant:(1) 12.090025] [msg_test/INFO] Migrating to Fafard
+> [Fafard:emigrant:(1) 14.110575] [msg_test/INFO] Migrating to Tremblay
+> [Tremblay:emigrant:(1) 16.110731] [msg_test/INFO] Migrating to Ginette
+> [18.123963] [msg_test/INFO] Simulation time 18.124
 
 $ cat tracing/procmig.trace
 > %EventDef PajeDefineContainerType 0 
@@ -121,314 +124,403 @@ $ cat tracing/procmig.trace
 > 0 4 2 MSG_PROCESS
 > 2 5 4 MSG_PROCESS_STATE
 > 4 6 0 4 4 MSG_PROCESS_LINK
+> 4 7 0 4 4 MSG_PROCESS_TASK_LINK
 > 5 0 3 2 1 Jupiter
 > 5 0 4 2 1 Fafard
 > 5 0 5 2 1 Ginette
 > 5 0 6 2 1 Bourassa
-> 0 7 1 ROUTER
-> 5 0 7 7 1 R.4-3
-> 5 0 8 7 1 R.3-2-5
-> 5 0 9 7 1 R.2-0
-> 5 0 10 7 1 R.1-0
-> 5 0 11 7 1 R.1-8-6
-> 5 0 12 7 1 R.6-7
-> 0 8 1 LINK
-> 5 0 13 8 1 6
-> 1 9 8 bandwidth "1 1 1"
-> 1 10 8 latency "1 1 1"
-> 5 0 14 8 1 11
-> 5 0 15 8 1 3
-> 5 0 16 8 1 7
-> 5 0 17 8 1 9
-> 5 0 18 8 1 12
-> 5 0 19 8 1 2
-> 5 0 20 8 1 8
-> 5 0 21 8 1 1
-> 5 0 22 8 1 4
-> 5 0 23 8 1 0
-> 5 0 24 8 1 10
-> 5 0 25 8 1 5
-> 5 0 26 8 1 loopback
-> 4 11 1 2 8 HOST-LINK
-> 4 12 1 8 2 LINK-HOST
-> 4 13 1 8 7 LINK-ROUTER
-> 4 14 1 8 8 LINK-LINK
-> 4 15 1 7 8 ROUTER-LINK
+> 0 8 1 ROUTER
+> 5 0 7 8 1 R.4-3
+> 5 0 8 8 1 R.3-2-5
+> 5 0 9 8 1 R.2-0
+> 5 0 10 8 1 R.1-0
+> 5 0 11 8 1 R.1-8-6
+> 5 0 12 8 1 R.6-7
+> 0 9 1 LINK
+> 5 0 13 9 1 6
+> 1 10 9 bandwidth "1 1 1"
+> 1 11 9 latency "1 1 1"
+> 5 0 14 9 1 11
+> 5 0 15 9 1 3
+> 5 0 16 9 1 7
+> 5 0 17 9 1 9
+> 5 0 18 9 1 12
+> 5 0 19 9 1 2
+> 5 0 20 9 1 8
+> 5 0 21 9 1 1
+> 5 0 22 9 1 4
+> 5 0 23 9 1 0
+> 5 0 24 9 1 10
+> 5 0 25 9 1 5
+> 5 0 26 9 1 loopback
+> 4 12 1 2 9 HOST-LINK
+> 4 13 1 9 2 LINK-HOST
+> 4 14 1 9 8 LINK-ROUTER
+> 4 15 1 9 9 LINK-LINK
+> 4 16 1 8 9 ROUTER-LINK
 > 7 0 3 2 98095000.000000
 > 7 0 3 3 76296000.000000
 > 7 0 3 4 76296000.000000
 > 7 0 3 5 48492000.000000
 > 7 0 3 6 48492000.000000
-> 7 0 9 13 41279125.000000
-> 7 0 10 13 0.000060
-> 7 0 9 14 252750.000000
-> 7 0 10 14 0.005705
-> 7 0 9 15 34285622.000000
-> 7 0 10 15 0.000514
-> 7 0 9 16 11618875.000000
-> 7 0 10 16 0.000190
-> 7 0 9 17 7209750.000000
-> 7 0 10 17 0.001462
-> 7 0 9 18 1792625.000000
-> 7 0 10 18 0.007878
-> 7 0 9 19 22222222.000000
-> 7 0 10 19 0.000137
-> 7 0 9 20 8158000.000000
-> 7 0 10 20 0.000271
-> 7 0 9 21 34285625.000000
-> 7 0 10 21 0.000514
-> 7 0 9 22 10099625.000000
-> 7 0 10 22 0.000480
-> 7 0 9 23 41279125.000000
-> 7 0 10 23 0.000060
-> 7 0 9 24 4679750.000000
-> 7 0 10 24 0.000849
-> 7 0 9 25 27946250.000000
-> 7 0 10 25 0.000278
-> 7 0 9 26 498000000.000000
-> 7 0 10 26 0.000015
-> 13 0 11 1 G 4 0
-> 14 0 11 1 G 26 0
-> 13 0 12 1 G 26 1
-> 14 0 12 1 G 4 1
-> 13 0 11 1 G 4 2
-> 14 0 11 1 G 20 2
-> 13 0 13 1 G 20 3
-> 14 0 13 1 G 11 3
-> 13 0 11 1 G 4 4
-> 14 0 11 1 G 20 4
-> 13 0 14 1 G 20 5
-> 14 0 14 1 G 21 5
-> 13 0 14 1 G 21 6
-> 14 0 14 1 G 23 6
-> 13 0 14 1 G 23 7
-> 14 0 14 1 G 19 7
-> 13 0 14 1 G 19 8
-> 14 0 14 1 G 15 8
-> 13 0 14 1 G 15 9
-> 14 0 14 1 G 22 9
-> 13 0 12 1 G 22 10
-> 14 0 12 1 G 2 10
-> 13 0 11 1 G 4 11
-> 14 0 11 1 G 20 11
-> 13 0 14 1 G 20 12
-> 14 0 14 1 G 13 12
-> 13 0 14 1 G 13 13
-> 14 0 14 1 G 16 13
-> 13 0 12 1 G 16 14
-> 14 0 12 1 G 6 14
-> 13 0 11 1 G 4 15
-> 14 0 11 1 G 20 15
-> 13 0 14 1 G 20 16
-> 14 0 14 1 G 21 16
-> 13 0 14 1 G 21 17
-> 14 0 14 1 G 23 17
-> 13 0 14 1 G 23 18
-> 14 0 14 1 G 19 18
-> 13 0 14 1 G 19 19
-> 14 0 14 1 G 15 19
-> 13 0 14 1 G 15 20
-> 14 0 14 1 G 22 20
-> 13 0 14 1 G 22 21
-> 14 0 14 1 G 17 21
-> 13 0 12 1 G 17 22
-> 14 0 12 1 G 3 22
-> 13 0 11 1 G 4 23
-> 14 0 11 1 G 20 23
-> 13 0 14 1 G 20 24
-> 14 0 14 1 G 21 24
-> 13 0 14 1 G 21 25
-> 14 0 14 1 G 23 25
-> 13 0 14 1 G 23 26
-> 14 0 14 1 G 19 26
-> 13 0 14 1 G 19 27
-> 14 0 14 1 G 25 27
-> 13 0 12 1 G 25 28
-> 14 0 12 1 G 5 28
-> 13 0 15 1 G 9 29
-> 14 0 15 1 G 19 29
-> 13 0 13 1 G 19 30
-> 14 0 13 1 G 8 30
-> 13 0 15 1 G 9 31
-> 14 0 15 1 G 23 31
-> 13 0 13 1 G 23 32
-> 14 0 13 1 G 10 32
-> 13 0 15 1 G 7 33
-> 14 0 15 1 G 15 33
-> 13 0 13 1 G 15 34
-> 14 0 13 1 G 8 34
-> 13 0 15 1 G 7 35
-> 14 0 15 1 G 22 35
-> 13 0 12 1 G 22 36
-> 14 0 12 1 G 2 36
-> 13 0 15 1 G 8 37
-> 14 0 15 1 G 25 37
-> 13 0 12 1 G 25 38
-> 14 0 12 1 G 5 38
-> 13 0 15 1 G 11 39
-> 14 0 15 1 G 13 39
-> 13 0 13 1 G 13 40
-> 14 0 13 1 G 12 40
-> 13 0 15 1 G 11 41
-> 14 0 15 1 G 21 41
-> 13 0 13 1 G 21 42
-> 14 0 13 1 G 10 42
-> 13 0 15 1 G 12 43
-> 14 0 15 1 G 16 43
-> 13 0 12 1 G 16 44
-> 14 0 12 1 G 6 44
-> 13 0 11 1 G 2 45
-> 14 0 11 1 G 26 45
-> 13 0 12 1 G 26 46
-> 14 0 12 1 G 2 46
-> 13 0 11 1 G 2 47
-> 14 0 11 1 G 22 47
-> 13 0 14 1 G 22 48
-> 14 0 14 1 G 15 48
-> 13 0 14 1 G 15 49
-> 14 0 14 1 G 19 49
-> 13 0 14 1 G 19 50
-> 14 0 14 1 G 23 50
-> 13 0 14 1 G 23 51
-> 14 0 14 1 G 21 51
-> 13 0 14 1 G 21 52
-> 14 0 14 1 G 13 52
-> 13 0 14 1 G 13 53
-> 14 0 14 1 G 16 53
-> 13 0 12 1 G 16 54
-> 14 0 12 1 G 6 54
-> 13 0 11 1 G 2 55
-> 14 0 11 1 G 17 55
-> 13 0 12 1 G 17 56
-> 14 0 12 1 G 3 56
-> 13 0 11 1 G 2 57
-> 14 0 11 1 G 22 57
-> 13 0 14 1 G 22 58
-> 14 0 14 1 G 15 58
-> 13 0 14 1 G 15 59
-> 14 0 14 1 G 25 59
-> 13 0 12 1 G 25 60
-> 14 0 12 1 G 5 60
-> 13 0 11 1 G 6 61
-> 14 0 11 1 G 26 61
-> 13 0 12 1 G 26 62
-> 14 0 12 1 G 6 62
-> 13 0 11 1 G 6 63
-> 14 0 11 1 G 16 63
-> 13 0 14 1 G 16 64
-> 14 0 14 1 G 13 64
-> 13 0 14 1 G 13 65
-> 14 0 14 1 G 21 65
-> 13 0 14 1 G 21 66
-> 14 0 14 1 G 23 66
-> 13 0 14 1 G 23 67
-> 14 0 14 1 G 19 67
-> 13 0 14 1 G 19 68
-> 14 0 14 1 G 15 68
-> 13 0 14 1 G 15 69
-> 14 0 14 1 G 22 69
-> 13 0 14 1 G 22 70
-> 14 0 14 1 G 17 70
-> 13 0 12 1 G 17 71
-> 14 0 12 1 G 3 71
-> 13 0 11 1 G 6 72
-> 14 0 11 1 G 16 72
-> 13 0 14 1 G 16 73
-> 14 0 14 1 G 13 73
-> 13 0 14 1 G 13 74
-> 14 0 14 1 G 21 74
-> 13 0 14 1 G 21 75
-> 14 0 14 1 G 23 75
-> 13 0 14 1 G 23 76
-> 14 0 14 1 G 19 76
-> 13 0 14 1 G 19 77
-> 14 0 14 1 G 25 77
-> 13 0 12 1 G 25 78
-> 14 0 12 1 G 5 78
-> 13 0 11 1 G 3 79
-> 14 0 11 1 G 26 79
-> 13 0 12 1 G 26 80
-> 14 0 12 1 G 3 80
-> 13 0 11 1 G 3 81
-> 14 0 11 1 G 17 81
-> 13 0 14 1 G 17 82
-> 14 0 14 1 G 22 82
-> 13 0 14 1 G 22 83
-> 14 0 14 1 G 15 83
-> 13 0 14 1 G 15 84
-> 14 0 14 1 G 25 84
-> 13 0 12 1 G 25 85
-> 14 0 12 1 G 5 85
-> 13 0 11 1 G 5 86
-> 14 0 11 1 G 26 86
-> 13 0 12 1 G 26 87
-> 14 0 12 1 G 5 87
+> 7 0 10 13 41279125.000000
+> 7 0 11 13 0.000060
+> 7 0 10 14 252750.000000
+> 7 0 11 14 0.005705
+> 7 0 10 15 34285622.000000
+> 7 0 11 15 0.000514
+> 7 0 10 16 11618875.000000
+> 7 0 11 16 0.000190
+> 7 0 10 17 7209750.000000
+> 7 0 11 17 0.001462
+> 7 0 10 18 1792625.000000
+> 7 0 11 18 0.007878
+> 7 0 10 19 22222222.000000
+> 7 0 11 19 0.000137
+> 7 0 10 20 8158000.000000
+> 7 0 11 20 0.000271
+> 7 0 10 21 34285625.000000
+> 7 0 11 21 0.000514
+> 7 0 10 22 10099625.000000
+> 7 0 11 22 0.000480
+> 7 0 10 23 41279125.000000
+> 7 0 11 23 0.000060
+> 7 0 10 24 4679750.000000
+> 7 0 11 24 0.000849
+> 7 0 10 25 27946250.000000
+> 7 0 11 25 0.000278
+> 7 0 10 26 498000000.000000
+> 7 0 11 26 0.000015
+> 13 0 12 1 G 4 0
+> 14 0 12 1 G 26 0
+> 13 0 13 1 G 26 1
+> 14 0 13 1 G 4 1
+> 13 0 12 1 G 4 2
+> 14 0 12 1 G 20 2
+> 13 0 14 1 G 20 3
+> 14 0 14 1 G 11 3
+> 13 0 12 1 G 4 4
+> 14 0 12 1 G 20 4
+> 13 0 15 1 G 20 5
+> 14 0 15 1 G 21 5
+> 13 0 15 1 G 21 6
+> 14 0 15 1 G 23 6
+> 13 0 15 1 G 23 7
+> 14 0 15 1 G 19 7
+> 13 0 15 1 G 19 8
+> 14 0 15 1 G 15 8
+> 13 0 15 1 G 15 9
+> 14 0 15 1 G 22 9
+> 13 0 13 1 G 22 10
+> 14 0 13 1 G 2 10
+> 13 0 12 1 G 4 11
+> 14 0 12 1 G 20 11
+> 13 0 15 1 G 20 12
+> 14 0 15 1 G 13 12
+> 13 0 15 1 G 13 13
+> 14 0 15 1 G 16 13
+> 13 0 13 1 G 16 14
+> 14 0 13 1 G 6 14
+> 13 0 12 1 G 4 15
+> 14 0 12 1 G 20 15
+> 13 0 15 1 G 20 16
+> 14 0 15 1 G 21 16
+> 13 0 15 1 G 21 17
+> 14 0 15 1 G 23 17
+> 13 0 15 1 G 23 18
+> 14 0 15 1 G 19 18
+> 13 0 15 1 G 19 19
+> 14 0 15 1 G 15 19
+> 13 0 15 1 G 15 20
+> 14 0 15 1 G 22 20
+> 13 0 15 1 G 22 21
+> 14 0 15 1 G 17 21
+> 13 0 13 1 G 17 22
+> 14 0 13 1 G 3 22
+> 13 0 12 1 G 4 23
+> 14 0 12 1 G 20 23
+> 13 0 15 1 G 20 24
+> 14 0 15 1 G 21 24
+> 13 0 15 1 G 21 25
+> 14 0 15 1 G 23 25
+> 13 0 15 1 G 23 26
+> 14 0 15 1 G 19 26
+> 13 0 15 1 G 19 27
+> 14 0 15 1 G 25 27
+> 13 0 13 1 G 25 28
+> 14 0 13 1 G 5 28
+> 13 0 16 1 G 9 29
+> 14 0 16 1 G 19 29
+> 13 0 14 1 G 19 30
+> 14 0 14 1 G 8 30
+> 13 0 16 1 G 9 31
+> 14 0 16 1 G 23 31
+> 13 0 14 1 G 23 32
+> 14 0 14 1 G 10 32
+> 13 0 16 1 G 7 33
+> 14 0 16 1 G 15 33
+> 13 0 14 1 G 15 34
+> 14 0 14 1 G 8 34
+> 13 0 16 1 G 7 35
+> 14 0 16 1 G 22 35
+> 13 0 13 1 G 22 36
+> 14 0 13 1 G 2 36
+> 13 0 16 1 G 8 37
+> 14 0 16 1 G 25 37
+> 13 0 13 1 G 25 38
+> 14 0 13 1 G 5 38
+> 13 0 16 1 G 11 39
+> 14 0 16 1 G 13 39
+> 13 0 14 1 G 13 40
+> 14 0 14 1 G 12 40
+> 13 0 16 1 G 11 41
+> 14 0 16 1 G 21 41
+> 13 0 14 1 G 21 42
+> 14 0 14 1 G 10 42
+> 13 0 16 1 G 12 43
+> 14 0 16 1 G 16 43
+> 13 0 13 1 G 16 44
+> 14 0 13 1 G 6 44
+> 13 0 12 1 G 2 45
+> 14 0 12 1 G 26 45
+> 13 0 13 1 G 26 46
+> 14 0 13 1 G 2 46
+> 13 0 12 1 G 2 47
+> 14 0 12 1 G 22 47
+> 13 0 15 1 G 22 48
+> 14 0 15 1 G 15 48
+> 13 0 15 1 G 15 49
+> 14 0 15 1 G 19 49
+> 13 0 15 1 G 19 50
+> 14 0 15 1 G 23 50
+> 13 0 15 1 G 23 51
+> 14 0 15 1 G 21 51
+> 13 0 15 1 G 21 52
+> 14 0 15 1 G 13 52
+> 13 0 15 1 G 13 53
+> 14 0 15 1 G 16 53
+> 13 0 13 1 G 16 54
+> 14 0 13 1 G 6 54
+> 13 0 12 1 G 2 55
+> 14 0 12 1 G 17 55
+> 13 0 13 1 G 17 56
+> 14 0 13 1 G 3 56
+> 13 0 12 1 G 2 57
+> 14 0 12 1 G 22 57
+> 13 0 15 1 G 22 58
+> 14 0 15 1 G 15 58
+> 13 0 15 1 G 15 59
+> 14 0 15 1 G 25 59
+> 13 0 13 1 G 25 60
+> 14 0 13 1 G 5 60
+> 13 0 12 1 G 6 61
+> 14 0 12 1 G 26 61
+> 13 0 13 1 G 26 62
+> 14 0 13 1 G 6 62
+> 13 0 12 1 G 6 63
+> 14 0 12 1 G 16 63
+> 13 0 15 1 G 16 64
+> 14 0 15 1 G 13 64
+> 13 0 15 1 G 13 65
+> 14 0 15 1 G 21 65
+> 13 0 15 1 G 21 66
+> 14 0 15 1 G 23 66
+> 13 0 15 1 G 23 67
+> 14 0 15 1 G 19 67
+> 13 0 15 1 G 19 68
+> 14 0 15 1 G 15 68
+> 13 0 15 1 G 15 69
+> 14 0 15 1 G 22 69
+> 13 0 15 1 G 22 70
+> 14 0 15 1 G 17 70
+> 13 0 13 1 G 17 71
+> 14 0 13 1 G 3 71
+> 13 0 12 1 G 6 72
+> 14 0 12 1 G 16 72
+> 13 0 15 1 G 16 73
+> 14 0 15 1 G 13 73
+> 13 0 15 1 G 13 74
+> 14 0 15 1 G 21 74
+> 13 0 15 1 G 21 75
+> 14 0 15 1 G 23 75
+> 13 0 15 1 G 23 76
+> 14 0 15 1 G 19 76
+> 13 0 15 1 G 19 77
+> 14 0 15 1 G 25 77
+> 13 0 13 1 G 25 78
+> 14 0 13 1 G 5 78
+> 13 0 12 1 G 3 79
+> 14 0 12 1 G 26 79
+> 13 0 13 1 G 26 80
+> 14 0 13 1 G 3 80
+> 13 0 12 1 G 3 81
+> 14 0 12 1 G 17 81
+> 13 0 15 1 G 17 82
+> 14 0 15 1 G 22 82
+> 13 0 15 1 G 22 83
+> 14 0 15 1 G 15 83
+> 13 0 15 1 G 15 84
+> 14 0 15 1 G 25 84
+> 13 0 13 1 G 25 85
+> 14 0 13 1 G 5 85
+> 13 0 12 1 G 5 86
+> 14 0 12 1 G 26 86
+> 13 0 13 1 G 26 87
+> 14 0 13 1 G 5 87
 > 5 0 27 4 4 emigrant
-> 1 16 4 emigrant "1 0 0"
-> 7 0 16 27 1.000000
+> 1 17 4 emigrant "1 0 0"
+> 5 0 28 4 2 master
+> 1 18 4 master "1 0 0"
+> 7 0 17 27 1.000000
 > 10 0 5 27 executing
 > 11 0 5 27 sleep
-> 6 2.000000 4 27
+> 7 0 18 28 1.000000
+> 10 0 5 28 executing
+> 11 0 5 28 send
+> 13 0 7 0 SR 28 p0
 > 12 2.000000 5 27
-> 13 2.000000 6 0 M 27 0
-> 5 2.000000 28 4 2 emigrant
-> 7 2.000000 16 28 1.000000
-> 14 2.000000 6 0 M 28 0
-> 11 2.000000 5 28 sleep
-> 6 4.000000 4 28
-> 12 4.000000 5 28
-> 13 4.000000 6 0 M 28 1
-> 5 4.000000 29 4 3 emigrant
-> 7 4.000000 16 29 1.000000
-> 14 4.000000 6 0 M 29 1
-> 11 4.000000 5 29 sleep
-> 6 6.000000 4 29
-> 12 6.000000 5 29
-> 13 6.000000 6 0 M 29 2
-> 5 6.000000 30 4 4 emigrant
-> 7 6.000000 16 30 1.000000
-> 14 6.000000 6 0 M 30 2
-> 11 6.000000 5 30 sleep
-> 6 8.000000 4 30
-> 12 8.000000 5 30
-> 13 8.000000 6 0 M 30 3
-> 5 8.000000 31 4 5 emigrant
-> 7 8.000000 16 31 1.000000
-> 14 8.000000 6 0 M 31 3
-> 11 8.000000 5 31 sleep
-> 6 10.000000 4 31
-> 12 10.000000 5 31
-> 13 10.000000 6 0 M 31 4
-> 5 10.000000 32 4 6 emigrant
-> 6 12.000000 4 32
-> 7 10.000000 16 32 1.000000
-> 14 10.000000 6 0 M 32 4
-> 11 10.000000 5 32 sleep
-> 12 12.000000 5 32
-> 6 12.000000 2 4
-> 6 12.000000 7 9
-> 6 12.000000 7 7
-> 6 12.000000 7 8
-> 6 12.000000 7 11
-> 6 12.000000 7 12
-> 6 12.000000 2 2
-> 6 12.000000 8 24
-> 6 12.000000 8 14
-> 6 12.000000 8 18
-> 6 12.000000 8 26
-> 6 12.000000 7 10
-> 6 12.000000 8 23
-> 6 12.000000 8 21
-> 6 12.000000 8 19
-> 6 12.000000 8 15
-> 6 12.000000 8 22
-> 6 12.000000 8 25
-> 6 12.000000 8 13
-> 6 12.000000 8 16
-> 6 12.000000 8 20
-> 6 12.000000 8 17
-> 6 12.000000 2 6
-> 6 12.000000 2 3
-> 6 12.000000 2 5
-> 6 12.000000 1 1
-> 6 12.000000 0 0
+> 11 2.000000 5 27 receive
+> 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
+> 7 2.020551 17 29 1.000000
+> 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 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
+> 7 4.020707 17 30 1.000000
+> 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 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
+> 7 6.035906 17 31 1.000000
+> 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 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
+> 7 8.056457 17 32 1.000000
+> 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 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
+> 7 10.069689 17 33 1.000000
+> 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 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
+> 7 12.090025 17 34 1.000000
+> 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 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
+> 7 14.110575 17 35 1.000000
+> 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 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
+> 7 16.110731 17 36 1.000000
+> 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 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
+> 6 18.123963 8 8
+> 6 18.123963 8 11
+> 6 18.123963 8 12
+> 6 18.123963 2 2
+> 6 18.123963 9 24
+> 6 18.123963 9 14
+> 6 18.123963 9 18
+> 6 18.123963 9 26
+> 6 18.123963 8 10
+> 6 18.123963 9 23
+> 6 18.123963 9 21
+> 6 18.123963 9 19
+> 6 18.123963 9 15
+> 6 18.123963 9 22
+> 6 18.123963 9 25
+> 6 18.123963 9 13
+> 6 18.123963 9 16
+> 6 18.123963 9 20
+> 6 18.123963 9 17
+> 6 18.123963 2 6
+> 6 18.123963 2 3
+> 6 18.123963 2 5
+> 6 18.123963 1 1
+> 6 18.123963 0 0
index 3ee5ef1..cc4078e 100644 (file)
@@ -400,14 +400,13 @@ $ cat tracing/tasks.trace
 > 14 0 12 1 G 5 87
 > 5 0 27 4 2 task-0
 > 1 16 4 compute "1 1 1"
-> 6 0 4 27
 > 7 0 16 27 1.000000
 > 11 0 5 27 created
 > 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
-> 6 0.002339 4 28
 > 7 0.002339 16 28 1.000000
 > 11 0.002339 5 28 created
 > 7 0.002339 16 29 1.000000
@@ -416,9 +415,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 0.168300 4 30
 > 7 0.168300 16 30 1.000000
 > 11 0.168300 5 30 created
 > 7 0.168300 16 31 1.000000
@@ -427,9 +426,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 0.386454 4 32
 > 7 0.386454 16 32 1.000000
 > 11 0.386454 5 32 created
 > 7 0.386454 16 33 1.000000
@@ -438,11 +437,11 @@ $ cat tracing/tasks.trace
 > 11 0.386454 5 33 MSG_task_execute
 > 12 0.386454 5 32
 > 13 0.386454 6 0 SR 32 3
-> 6 0.512049 4 29
+> 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
-> 6 0.526913 4 34
 > 7 0.526913 16 34 1.000000
 > 11 0.526913 5 34 created
 > 7 0.526913 16 35 1.000000
@@ -451,9 +450,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 0.742786 4 36
 > 7 0.742786 16 36 1.000000
 > 11 0.742786 5 36 created
 > 7 0.742786 16 37 1.000000
@@ -462,9 +461,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 0.745124 4 38
 > 7 0.745124 16 38 1.000000
 > 11 0.745124 5 38 created
 > 7 0.745124 16 39 1.000000
@@ -473,11 +472,11 @@ $ cat tracing/tasks.trace
 > 11 0.745124 5 39 MSG_task_execute
 > 12 0.745124 5 38
 > 13 0.745124 6 0 SR 38 6
-> 6 0.823643 4 31
+> 6 0.745124 4 38
 > 12 0.823643 5 31
+> 6 0.823643 4 31
 > 5 0.989605 40 4 2 task-7
 > 5 0.989605 41 4 3 task-6
-> 6 0.989605 4 40
 > 7 0.989605 16 40 1.000000
 > 11 0.989605 5 40 created
 > 7 0.989605 16 41 1.000000
@@ -486,13 +485,13 @@ $ cat tracing/tasks.trace
 > 11 0.989605 5 41 MSG_task_execute
 > 12 0.989605 5 40
 > 13 0.989605 6 0 SR 40 7
-> 6 1.041796 4 33
+> 6 0.989605 4 40
 > 12 1.041796 5 33
-> 6 1.254834 4 39
+> 6 1.041796 4 33
 > 12 1.254834 5 39
+> 6 1.254834 4 39
 > 5 1.259949 42 4 2 task-8
 > 5 1.259949 43 4 4 task-7
-> 6 1.259949 4 42
 > 7 1.259949 16 42 1.000000
 > 11 1.259949 5 42 created
 > 7 1.259949 16 43 1.000000
@@ -501,9 +500,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 1.262288 4 44
 > 7 1.262288 16 44 1.000000
 > 11 1.262288 5 44 created
 > 7 1.262288 16 45 1.000000
@@ -512,13 +511,13 @@ $ cat tracing/tasks.trace
 > 11 1.262288 5 45 MSG_task_execute
 > 12 1.262288 5 44
 > 13 1.262288 6 0 SR 44 9
-> 6 1.558011 4 35
+> 6 1.262288 4 44
 > 12 1.558011 5 35
-> 6 1.644947 4 41
+> 6 1.558011 4 35
 > 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
-> 6 1.698471 4 46
 > 7 1.698471 16 46 1.000000
 > 11 1.698471 5 46 created
 > 7 1.698471 16 47 1.000000
@@ -527,13 +526,13 @@ $ cat tracing/tasks.trace
 > 11 1.698471 5 47 MSG_task_execute
 > 12 1.698471 5 46
 > 13 1.698471 6 0 SR 46 10
-> 6 1.771998 4 45
+> 6 1.698471 4 46
 > 12 1.771998 5 45
-> 6 1.773883 4 37
+> 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
-> 6 1.864433 4 48
 > 7 1.864433 16 48 1.000000
 > 11 1.864433 5 48 created
 > 7 1.864433 16 49 1.000000
@@ -542,9 +541,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 1.866771 4 50
 > 7 1.866771 16 50 1.000000
 > 11 1.866771 5 50 created
 > 7 1.866771 16 51 1.000000
@@ -553,11 +552,11 @@ $ cat tracing/tasks.trace
 > 11 1.866771 5 51 MSG_task_execute
 > 12 1.866771 5 50
 > 13 1.866771 6 0 SR 50 12
-> 6 1.915291 4 43
+> 6 1.866771 4 50
 > 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
-> 6 2.082643 4 52
 > 7 2.082643 16 52 1.000000
 > 11 2.082643 5 52 created
 > 7 2.082643 16 53 1.000000
@@ -566,9 +565,9 @@ $ cat tracing/tasks.trace
 > 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
-> 6 2.300797 4 54
 > 7 2.300797 16 54 1.000000
 > 11 2.300797 5 54 created
 > 7 2.300797 16 55 1.000000
@@ -577,11 +576,11 @@ $ cat tracing/tasks.trace
 > 11 2.300797 5 55 MSG_task_execute
 > 12 2.300797 5 54
 > 13 2.300797 6 0 SR 54 14
-> 6 2.376481 4 51
+> 6 2.300797 4 54
 > 12 2.376481 5 51
+> 6 2.376481 4 51
 > 5 2.378820 56 4 2 task-15
 > 5 2.378820 57 4 2 task-14
-> 6 2.378820 4 56
 > 7 2.378820 16 56 1.000000
 > 11 2.378820 5 56 created
 > 7 2.378820 16 57 1.000000
@@ -590,11 +589,11 @@ $ cat tracing/tasks.trace
 > 11 2.378820 5 57 MSG_task_execute
 > 12 2.378820 5 56
 > 13 2.378820 6 0 SR 56 15
-> 6 2.519775 4 49
+> 6 2.378820 4 56
 > 12 2.519775 5 49
+> 6 2.519775 4 49
 > 5 2.685737 58 4 2 task-16
 > 5 2.685737 59 4 3 task-15
-> 6 2.685737 4 58
 > 7 2.685737 16 58 1.000000
 > 11 2.685737 5 58 created
 > 7 2.685737 16 59 1.000000
@@ -603,11 +602,11 @@ $ cat tracing/tasks.trace
 > 11 2.685737 5 59 MSG_task_execute
 > 12 2.685737 5 58
 > 13 2.685737 6 0 SR 58 16
-> 6 2.729569 4 47
+> 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
-> 6 2.870028 4 60
 > 7 2.870028 16 60 1.000000
 > 11 2.870028 5 60 created
 > 7 2.870028 16 61 1.000000
@@ -616,11 +615,11 @@ $ cat tracing/tasks.trace
 > 11 2.870028 5 61 MSG_task_execute
 > 12 2.870028 5 60
 > 13 2.870028 6 0 SR 60 17
-> 6 2.888530 4 57
+> 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
-> 6 2.890868 4 62
 > 7 2.890868 16 62 1.000000
 > 11 2.890868 5 62 created
 > 7 2.890868 16 63 1.000000
@@ -629,13 +628,13 @@ $ cat tracing/tasks.trace
 > 11 2.890868 5 63 MSG_task_execute
 > 12 2.890868 5 62
 > 13 2.890868 6 0 SR 62 18
-> 6 2.956139 4 55
+> 6 2.890868 4 62
 > 12 2.956139 5 55
-> 6 3.113741 4 53
+> 6 2.956139 4 55
 > 12 3.113741 5 53
+> 6 3.113741 4 53
 > 5 3.174292 64 4 2 task-19
 > 5 3.174292 65 4 4 task-18
-> 6 3.174292 4 64
 > 7 3.174292 16 64 1.000000
 > 11 3.174292 5 64 created
 > 7 3.174292 16 65 1.000000
@@ -644,12 +643,12 @@ $ cat tracing/tasks.trace
 > 11 3.174292 5 65 MSG_task_execute
 > 12 3.174292 5 64
 > 13 3.174292 6 0 SR 64 19
-> 6 3.341079 4 59
+> 6 3.174292 4 64
 > 12 3.341079 5 59
+> 6 3.341079 4 59
 > 5 3.390164 66 4 2 task-0
 > 1 17 4 finalize "1 1 1"
 > 5 3.390164 67 4 6 task-19
-> 6 3.390164 4 66
 > 7 3.390164 17 66 1.000000
 > 11 3.390164 5 66 created
 > 7 3.390164 16 67 1.000000
@@ -658,63 +657,64 @@ $ cat tracing/tasks.trace
 > 11 3.390164 5 67 MSG_task_execute
 > 12 3.390164 5 66
 > 13 3.390164 6 0 SR 66 20
-> 6 3.400578 4 63
+> 6 3.390164 4 66
 > 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
-> 6 3.405364 4 69
 > 7 3.405364 17 68 1.000000
 > 11 3.405364 5 68 created
 > 7 3.405364 17 69 1.000000
 > 11 3.405364 5 69 created
 > 14 3.405364 6 0 SR 69 20
-> 6 3.405364 4 68
+> 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
-> 6 3.405520 4 71
 > 7 3.405520 17 70 1.000000
 > 11 3.405520 5 70 created
 > 7 3.405520 17 71 1.000000
 > 11 3.405520 5 71 created
 > 14 3.405520 6 0 SR 71 21
-> 6 3.405520 4 70
+> 6 3.405520 4 71
 > 12 3.405520 5 70
 > 13 3.405520 6 0 SR 70 22
-> 6 3.829634 4 65
+> 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
-> 6 3.850185 4 73
 > 7 3.850185 17 72 1.000000
 > 11 3.850185 5 72 created
 > 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 72
+> 6 3.850185 4 73
 > 12 3.850185 5 72
 > 13 3.850185 6 0 SR 72 23
-> 6 3.901126 4 61
+> 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
-> 6 3.914358 4 75
 > 7 3.914358 17 74 1.000000
 > 11 3.914358 5 74 created
 > 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 74
+> 6 3.914358 4 75
 > 12 3.914358 5 74
 > 13 3.914358 6 0 SR 74 24
-> 6 4.421262 4 67
+> 6 3.914358 4 74
 > 12 4.421262 5 67
+> 6 4.421262 4 67
 > 5 4.441598 76 4 6 task-4
-> 6 4.441598 4 76
 > 7 4.441598 17 76 1.000000
 > 11 4.441598 5 76 created
 > 14 4.441598 6 0 SR 76 24
+> 6 4.441598 4 76
 > 6 4.441598 2 4
 > 6 4.441598 7 9
 > 6 4.441598 7 7