> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> [ 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
> 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
> 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
> 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
> 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
> 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
> 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
> 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
> 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
> 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
> 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
> 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
> [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
> 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