[trace] faster method to insert into buffer

details:
- insert directly at the end if last event has
the same timestamp as the event to be inserted

git-svn-id: svn+ssh://scm.gforge.inria.fr/svn/simgrid/simgrid/trunk@9344 48e7efb5-ca39-0410-a469-dd3cf9ba447f

diff --git a/examples/msg/tracing/ms.tesh b/examples/msg/tracing/ms.tesh
index 2e4d7da..71f96ca 100644 (file)
--- a/examples/msg/tracing/ms.tesh
+++ b/examples/msg/tracing/ms.tesh
@@ -46,28 +46,28 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=1)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 1
 > [  0.000000] (0:@) new container Fafard, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=2)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 2
 > [  0.000000] (0:@) new container Ginette, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=3)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 3
 > [  0.000000] (0:@) new container Bourassa, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=4)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 4
 > [  0.000000] (0:@) new container R.4-3, child of AS0
 > [  0.000000] (0:@) new type ROUTER, child of L1
 > [  0.000000] (0:@) new_pajeDefineContainerType: event_type=0
@@ -116,10 +116,10 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=1, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=5)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 5
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=6)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 6
 > [  0.000000] (0:@) new type bandwidth_used, child of LINK
 > [  0.000000] (0:@) VariableType bandwidth_used(9), child of LINK(6)
 > [  0.000000] (0:@) new_pajeDefineVariableType: event_type=1
@@ -131,130 +131,130 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=7)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 7
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=8)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 8
 > [  0.000000] (0:@) new container 3, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=9)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 9
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=10)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 10
 > [  0.000000] (0:@) new container 7, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=11)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 11
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=12)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 12
 > [  0.000000] (0:@) new container 9, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=13)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 13
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=14)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 14
 > [  0.000000] (0:@) new container 12, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=15)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 15
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=16)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 16
 > [  0.000000] (0:@) new container 2, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=17)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 17
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=18)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 18
 > [  0.000000] (0:@) new container 8, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=19)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 19
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=20)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 20
 > [  0.000000] (0:@) new container 1, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=21)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 21
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=22)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 22
 > [  0.000000] (0:@) new container 4, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=23)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 23
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=24)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 24
 > [  0.000000] (0:@) new container 0, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=25)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 25
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=26)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 26
 > [  0.000000] (0:@) new container 10, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=27)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 27
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=28)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 28
 > [  0.000000] (0:@) new container 5, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=29)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 29
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=30)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 30
 > [  0.000000] (0:@) new container loopback, child of AS0
 > [  0.000000] (0:@) new_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) print_pajeCreateContainer: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=5, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=31)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 31
 > [  0.000000] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=32)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 32
 > [  0.000000] (0:@) new type HOST-LINK, child of L1
 > [  0.000000] (0:@) LinkType HOST-LINK(10), child of L1(1)  HOST(2)->LINK(6)
 > [  0.000000] (0:@) new_pajeDefineLinkType: event_type=4
@@ -262,10 +262,10 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=4, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=33)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 33
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=34)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 34
 > [  0.000000] (0:@) new type LINK-HOST, child of L1
 > [  0.000000] (0:@) LinkType LINK-HOST(11), child of L1(1)  LINK(6)->HOST(2)
 > [  0.000000] (0:@) new_pajeDefineLinkType: event_type=4
@@ -273,16 +273,16 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=4, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=35)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 35
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=36)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 36
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=37)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 37
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=38)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 38
 > [  0.000000] (0:@) new type LINK-ROUTER, child of L1
 > [  0.000000] (0:@) LinkType LINK-ROUTER(12), child of L1(1)  LINK(6)->ROUTER(5)
 > [  0.000000] (0:@) new_pajeDefineLinkType: event_type=4
@@ -290,16 +290,16 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=4, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=39)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 39
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=40)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 40
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=41)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 41
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=42)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 42
 > [  0.000000] (0:@) new type LINK-LINK, child of L1
 > [  0.000000] (0:@) LinkType LINK-LINK(13), child of L1(1)  LINK(6)->LINK(6)
 > [  0.000000] (0:@) new_pajeDefineLinkType: event_type=4
@@ -307,148 +307,148 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=4, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=43)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 43
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=44)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 44
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=45)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 45
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=46)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 46
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=47)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 47
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=48)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 48
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=49)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 49
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=50)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 50
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=51)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 51
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=52)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 52
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=53)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 53
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=54)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 54
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=55)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 55
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=56)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 56
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=57)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=58)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 58
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=59)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=60)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 60
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=61)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=62)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 62
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=63)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=64)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 64
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=65)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=66)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 66
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=67)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=68)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 68
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=69)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=70)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 70
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=71)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=72)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 72
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=73)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=74)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 74
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=75)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=76)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 76
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=77)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=78)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 78
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=79)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=80)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 80
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=81)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=82)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 82
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=83)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=84)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 84
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=85)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=86)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 86
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=87)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 87
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=88)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 88
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=89)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 89
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=90)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 90
 > [  0.000000] (0:@) new type ROUTER-LINK, child of L1
 > [  0.000000] (0:@) LinkType ROUTER-LINK(14), child of L1(1)  ROUTER(5)->LINK(6)
 > [  0.000000] (0:@) new_pajeDefineLinkType: event_type=4
@@ -456,358 +456,358 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (0:@) free_paje_event: event_type=4, timestamp=0.000000
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=91)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 91
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=92)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 92
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=93)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 93
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=94)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 94
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=95)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 95
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=96)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 96
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=97)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 97
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=98)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 98
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=99)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 99
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=100)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 100
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=101)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 101
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=102)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 102
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=103)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 103
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=104)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 104
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=105)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 105
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=106)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 106
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=107)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 107
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=108)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 108
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=109)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 109
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=110)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 110
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=111)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 111
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=112)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 112
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=113)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 113
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=114)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 114
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=115)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 115
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=116)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 116
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=117)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 117
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=118)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 118
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=119)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 119
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=120)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 120
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=121)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 121
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=122)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 122
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=123)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 123
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=124)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 124
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=125)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 125
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=126)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 126
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=127)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 127
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=128)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 128
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=129)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 129
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=130)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 130
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=131)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 131
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=132)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 132
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=133)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 133
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=134)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 134
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=135)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 135
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=136)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 136
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=137)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 137
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=138)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 138
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=139)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 139
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=140)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 140
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=141)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 141
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=142)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 142
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=143)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 143
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=144)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 144
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=145)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 145
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=146)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 146
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=147)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 147
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=148)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 148
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=149)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 149
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=150)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 150
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=151)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 151
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=152)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 152
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=153)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 153
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=154)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 154
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=155)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 155
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=156)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 156
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=157)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 157
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=158)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 158
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=159)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 159
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=160)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 160
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=161)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 161
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=162)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 162
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=163)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 163
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=164)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 164
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=165)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 165
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=166)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 166
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=167)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 167
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=168)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 168
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=169)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 169
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=170)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 170
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=171)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 171
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=172)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 172
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=173)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 173
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=174)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 174
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=175)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 175
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=176)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 176
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=177)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 177
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=178)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 178
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=179)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 179
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=180)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 180
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=181)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 181
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=182)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 182
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=183)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 183
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=184)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 184
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=185)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 185
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=186)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 186
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=187)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 187
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=188)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 188
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=189)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 189
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=190)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 190
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=191)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 191
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=192)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 192
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=193)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 193
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=194)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 194
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=195)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 195
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=196)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 196
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=197)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 197
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=198)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 198
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=199)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 199
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=200)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 200
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=201)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 201
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=202)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 202
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=203)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 203
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=204)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 204
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=205)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 205
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=206)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 206
 > [  0.000000] (0:@) new_pajeStartLink: event_type=13, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=13, timestamp=0.000000, buffersize=207)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 207
 > [  0.000000] (0:@) new_pajeEndLink: event_type=14, timestamp=0.000000
 > [  0.000000] (0:@) insert_into_buffer: insert event_type=14, timestamp=0.000000, buffersize=208)
-> [  0.000000] (0:@) insert_into_buffer: inserted at end
+> [  0.000000] (0:@) insert_into_buffer: inserted at end, pos = 208
 > [  0.000000] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts
 > [  0.000000] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (0:@) free_paje_event: event_type=7, timestamp=0.000000
@@ -1298,25 +1298,25 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.000000] (1:master@Tremblay) insert_into_buffer: inserted at beginning
 > [  0.000000] (1:master@Tremblay) new_pajeNewEvent: event_type=15, timestamp=0.000000
 > [  0.000000] (1:master@Tremblay) insert_into_buffer: insert event_type=15, timestamp=0.000000, buffersize=1)
-> [  0.000000] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  0.000000] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 1
 > [  0.000000] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=2)
-> [  0.000000] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  0.000000] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 2
 > [  0.000000] (2:slave@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (2:slave@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=3)
-> [  0.000000] (2:slave@Tremblay) insert_into_buffer: inserted at end
+> [  0.000000] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 3
 > [  0.000000] (3:slave@Jupiter) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (3:slave@Jupiter) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=4)
-> [  0.000000] (3:slave@Jupiter) insert_into_buffer: inserted at end
+> [  0.000000] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 4
 > [  0.000000] (4:slave@Fafard) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (4:slave@Fafard) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=5)
-> [  0.000000] (4:slave@Fafard) insert_into_buffer: inserted at end
+> [  0.000000] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 5
 > [  0.000000] (5:slave@Ginette) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (5:slave@Ginette) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=6)
-> [  0.000000] (5:slave@Ginette) insert_into_buffer: inserted at end
+> [  0.000000] (5:slave@Ginette) insert_into_buffer: inserted at end, pos = 6
 > [  0.000000] (6:slave@Bourassa) new_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000000] (6:slave@Bourassa) insert_into_buffer: insert event_type=7, timestamp=0.000000, buffersize=7)
-> [  0.000000] (6:slave@Bourassa) insert_into_buffer: inserted at end
+> [  0.000000] (6:slave@Bourassa) insert_into_buffer: inserted at end, pos = 7
 > [  0.000156] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts
 > [  0.000156] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.000000
 > [  0.000156] (0:@) free_paje_event: event_type=7, timestamp=0.000000
@@ -1340,7 +1340,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.002339] (0:@) insert_into_buffer: inserted at beginning
 > [  0.002339] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.000156
 > [  0.002339] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.000156, buffersize=1)
-> [  0.002339] (0:@) insert_into_buffer: inserted at end
+> [  0.002339] (0:@) insert_into_buffer: inserted at end, pos = 1
 > [  0.002339] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.002339
 > [  0.002339] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.002339, buffersize=2)
 > [  0.002339] (0:@) insert_into_buffer: inserted at end
@@ -1352,15 +1352,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.002339] (0:@) insert_into_buffer: inserted at 3
 > [  0.002339] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.002339
 > [  0.002339] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.002339, buffersize=5)
-> [  0.002339] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.002339] (2:slave@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
@@ -1368,7 +1368,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.168300] (0:@) insert_into_buffer: inserted at end
 > [  0.168300] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.017538
 > [  0.168300] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.017538, buffersize=9)
-> [  0.168300] (0:@) insert_into_buffer: inserted at end
+> [  0.168300] (0:@) insert_into_buffer: inserted at end, pos = 9
 > [  0.168300] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.168300
 > [  0.168300] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.168300, buffersize=10)
 > [  0.168300] (0:@) insert_into_buffer: inserted at end
@@ -1380,15 +1380,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.168300] (0:@) insert_into_buffer: inserted at 11
 > [  0.168300] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.168300
 > [  0.168300] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.168300, buffersize=13)
-> [  0.168300] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.168300] (3:slave@Jupiter) 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
@@ -1397,7 +1397,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.168300] (0:@) insert_into_buffer: inserted at 9
 > [  0.168300] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.168300
 > [  0.168300] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.168300, buffersize=18)
-> [  0.168300] (0:@) insert_into_buffer: inserted at end
+> [  0.168300] (0:@) insert_into_buffer: inserted at end, pos = 18
 > [  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=19)
 > [  0.168300] (0:@) insert_into_buffer: inserted at 10
@@ -1406,7 +1406,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.168300] (0:@) insert_into_buffer: inserted at 11
 > [  0.168300] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.168300
 > [  0.168300] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.168300, buffersize=21)
-> [  0.168300] (0:@) insert_into_buffer: inserted at end
+> [  0.168300] (0:@) insert_into_buffer: inserted at end, pos = 21
 > [  0.188851] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts
 > [  0.188851] (0:@) TRACE_paje_dump_buffer: ends
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
@@ -1414,7 +1414,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at end
 > [  0.386454] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.188851, buffersize=23)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 23
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=24)
 > [  0.386454] (0:@) insert_into_buffer: inserted at end
@@ -1426,7 +1426,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 25
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=27)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 27
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=28)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 26
@@ -1435,7 +1435,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 27
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=30)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 30
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=31)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 28
@@ -1444,7 +1444,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 29
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=33)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 33
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=34)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 30
@@ -1453,7 +1453,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 31
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=36)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 36
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=37)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 32
@@ -1462,7 +1462,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 33
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=39)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 39
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=40)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 34
@@ -1471,7 +1471,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 35
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=42)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 42
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=43)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 36
@@ -1480,7 +1480,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 37
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=45)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 45
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=46)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 38
@@ -1489,7 +1489,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 39
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=48)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 48
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=49)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 40
@@ -1498,7 +1498,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 41
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=51)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 51
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=52)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 42
@@ -1507,7 +1507,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 43
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=54)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 54
 > [  0.386454] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.188851
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.188851, buffersize=55)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 44
@@ -1516,32 +1516,32 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.386454] (0:@) insert_into_buffer: inserted at 45
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=57)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.386454] (4:slave@Fafard) 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.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=61)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  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=62)
 > [  0.386454] (0:@) insert_into_buffer: inserted at 23
 > [  0.386454] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.386454
 > [  0.386454] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.386454, buffersize=63)
-> [  0.386454] (0:@) insert_into_buffer: inserted at end
+> [  0.386454] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  0.399685] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts
 > [  0.399685] (0:@) TRACE_paje_dump_buffer: ends
 > [  0.512049] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.386454
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.386454, buffersize=64)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 64
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=65)
 > [  0.512049] (0:@) insert_into_buffer: inserted at end
@@ -1550,31 +1550,31 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.512049] (0:@) insert_into_buffer: inserted at 65
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=67)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  0.512049] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.399685, buffersize=68)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 66
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=69)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  0.512049] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.399685, buffersize=70)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 67
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=71)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  0.512049] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.399685, buffersize=72)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 68
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=73)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  0.512049] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.399685, buffersize=74)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 69
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=75)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  0.512049] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.399685, buffersize=76)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 70
@@ -1583,7 +1583,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.512049] (0:@) insert_into_buffer: inserted at 71
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=78)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 78
 > [  0.512049] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.399685
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.399685, buffersize=79)
 > [  0.512049] (0:@) insert_into_buffer: inserted at 72
@@ -1592,12 +1592,12 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.512049] (0:@) insert_into_buffer: inserted at 73
 > [  0.512049] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.512049
 > [  0.512049] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.512049, buffersize=81)
-> [  0.512049] (0:@) insert_into_buffer: inserted at end
+> [  0.512049] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  0.512049] (0:@) TRACE_paje_dump_buffer: dump until 0.000000. starts
 > [  0.512049] (0:@) TRACE_paje_dump_buffer: ends
 > [  0.526913] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.512049
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.512049, buffersize=82)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  0.526913] (0:@) insert_into_buffer: inserted at end, pos = 82
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=83)
 > [  0.526913] (0:@) insert_into_buffer: inserted at end
@@ -1606,39 +1606,39 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.526913] (0:@) insert_into_buffer: inserted at 83
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=85)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  0.526913] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  0.526913] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.512049
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.512049, buffersize=86)
 > [  0.526913] (0:@) insert_into_buffer: inserted at 84
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=87)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  0.526913] (0:@) insert_into_buffer: inserted at end, pos = 87
 > [  0.526913] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.512049
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.512049, buffersize=88)
 > [  0.526913] (0:@) insert_into_buffer: inserted at 85
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=89)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  0.526913] (0:@) insert_into_buffer: inserted at end, pos = 89
 > [  0.526913] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.512049
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.512049, buffersize=90)
 > [  0.526913] (0:@) insert_into_buffer: inserted at 86
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=91)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  0.526913] (0:@) insert_into_buffer: inserted at end, pos = 91
 > [  0.526913] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.512049
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.512049, buffersize=92)
 > [  0.526913] (0:@) insert_into_buffer: inserted at 87
 > [  0.526913] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.526913
 > [  0.526913] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.526913, buffersize=93)
-> [  0.526913] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.526913] (5:slave@Ginette) 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
@@ -1652,55 +1652,55 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.742786] (0:@) insert_into_buffer: inserted at 97
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=99)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 99
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=100)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 98
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=101)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 101
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=102)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 99
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=103)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 103
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=104)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 100
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=105)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 105
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=106)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 101
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=107)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 107
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=108)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 102
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=109)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 109
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=110)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 103
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=111)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 111
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=112)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 104
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=113)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 113
 > [  0.742786] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.547249, buffersize=114)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 105
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=115)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 115
 > [  0.742786] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.547249, buffersize=116)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 106
@@ -1709,7 +1709,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.742786] (0:@) insert_into_buffer: inserted at 107
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=118)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 118
 > [  0.742786] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.547249, buffersize=119)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 108
@@ -1718,7 +1718,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.742786] (0:@) insert_into_buffer: inserted at 109
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=121)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 121
 > [  0.742786] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.547249, buffersize=122)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 110
@@ -1727,7 +1727,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.742786] (0:@) insert_into_buffer: inserted at 111
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=124)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  0.742786] (0:@) insert_into_buffer: inserted at end, pos = 124
 > [  0.742786] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.547249
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.547249, buffersize=125)
 > [  0.742786] (0:@) insert_into_buffer: inserted at 112
@@ -1736,15 +1736,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.742786] (0:@) insert_into_buffer: inserted at 113
 > [  0.742786] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.742786
 > [  0.742786] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.742786, buffersize=127)
-> [  0.742786] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.742786] (6:slave@Bourassa) 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
@@ -1806,15 +1806,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.745124] (0:@) insert_into_buffer: inserted at 107
 > [  0.745124] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.745124
 > [  0.745124] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.745124, buffersize=109)
-> [  0.745124] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  0.745124] (2:slave@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
@@ -1832,7 +1832,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.823643] (0:@) insert_into_buffer: inserted at 3
 > [  0.823643] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.823643
 > [  0.823643] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.823643, buffersize=117)
-> [  0.823643] (0:@) insert_into_buffer: inserted at end
+> [  0.823643] (0:@) insert_into_buffer: inserted at end, pos = 117
 > [  0.823643] (0:@) TRACE_paje_dump_buffer: dump until 0.386454. starts
 > [  0.823643] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.168300
 > [  0.823643] (0:@) free_paje_event: event_type=7, timestamp=0.168300
@@ -1939,22 +1939,22 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  0.989605] (0:@) TRACE_paje_dump_buffer: ends
 > [  0.989605] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=0.989605
 > [  0.989605] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=0.989605, buffersize=74)
-> [  0.989605] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  0.989605] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 74
 > [  0.989605] (3:slave@Jupiter) new_pajeAddVariable: event_type=8, timestamp=0.989605
 > [  0.989605] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=0.989605, buffersize=75)
-> [  0.989605] (3:slave@Jupiter) insert_into_buffer: inserted at end
+> [  0.989605] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 75
 > [  0.989605] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.745124
 > [  0.989605] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.745124, buffersize=76)
 > [  0.989605] (0:@) insert_into_buffer: inserted at 70
 > [  0.989605] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.989605
 > [  0.989605] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.989605, buffersize=77)
-> [  0.989605] (0:@) insert_into_buffer: inserted at end
+> [  0.989605] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  0.989605] (0:@) new_pajeAddVariable: event_type=8, timestamp=0.745124
 > [  0.989605] (0:@) insert_into_buffer: insert event_type=8, timestamp=0.745124, buffersize=78)
 > [  0.989605] (0:@) insert_into_buffer: inserted at 71
 > [  0.989605] (0:@) new_pajeSubVariable: event_type=9, timestamp=0.989605
 > [  0.989605] (0:@) insert_into_buffer: insert event_type=9, timestamp=0.989605, buffersize=79)
-> [  0.989605] (0:@) insert_into_buffer: inserted at end
+> [  0.989605] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  1.041796] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.386454
 > [  1.041796] (0:@) insert_into_buffer: insert event_type=7, timestamp=0.386454, buffersize=80)
 > [  1.041796] (0:@) insert_into_buffer: inserted at 0
@@ -1972,7 +1972,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.041796] (0:@) insert_into_buffer: inserted at 3
 > [  1.041796] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.041796
 > [  1.041796] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.041796, buffersize=85)
-> [  1.041796] (0:@) insert_into_buffer: inserted at end
+> [  1.041796] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  1.041796] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts
 > [  1.041796] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.386454
 > [  1.041796] (0:@) free_paje_event: event_type=7, timestamp=0.386454
@@ -2056,48 +2056,48 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.254834] (0:@) insert_into_buffer: inserted at 51
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=55)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 55
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=56)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 54
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=57)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=58)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 55
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=59)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=60)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 56
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=61)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=62)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 57
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=63)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=64)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 58
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=65)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  1.254834] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.062347
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.062347, buffersize=66)
 > [  1.254834] (0:@) insert_into_buffer: inserted at 59
 > [  1.254834] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.254834
 > [  1.254834] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.254834, buffersize=67)
-> [  1.254834] (0:@) insert_into_buffer: inserted at end
+> [  1.254834] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  1.254834] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts
 > [  1.254834] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.259949] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.254834
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.254834, buffersize=68)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 68
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=69)
 > [  1.259949] (0:@) insert_into_buffer: inserted at end
@@ -2106,39 +2106,39 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.259949] (0:@) insert_into_buffer: inserted at 69
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=71)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  1.259949] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.254834
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.254834, buffersize=72)
 > [  1.259949] (0:@) insert_into_buffer: inserted at 70
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=73)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  1.259949] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.254834
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.254834, buffersize=74)
 > [  1.259949] (0:@) insert_into_buffer: inserted at 71
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=75)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  1.259949] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.254834
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.254834, buffersize=76)
 > [  1.259949] (0:@) insert_into_buffer: inserted at 72
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=77)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  1.259949] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.254834
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.254834, buffersize=78)
 > [  1.259949] (0:@) insert_into_buffer: inserted at 73
 > [  1.259949] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.259949
 > [  1.259949] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.259949, buffersize=79)
-> [  1.259949] (0:@) insert_into_buffer: inserted at end
+> [  1.259949] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  1.259949] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts
 > [  1.259949] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.259949] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.259949
 > [  1.259949] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.259949, buffersize=80)
-> [  1.259949] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  1.259949] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 80
 > [  1.259949] (4:slave@Fafard) new_pajeAddVariable: event_type=8, timestamp=1.259949
 > [  1.259949] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=1.259949, buffersize=81)
-> [  1.259949] (4:slave@Fafard) insert_into_buffer: inserted at end
+> [  1.259949] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 81
 > [  1.260105] (0:@) TRACE_paje_dump_buffer: dump until 0.526913. starts
 > [  1.260105] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.262288] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.260105
@@ -2152,15 +2152,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.262288] (0:@) insert_into_buffer: inserted at 83
 > [  1.262288] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.262288
 > [  1.262288] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.262288, buffersize=85)
-> [  1.262288] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  1.262288] (2:slave@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
@@ -2178,7 +2178,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.558011] (0:@) insert_into_buffer: inserted at 3
 > [  1.558011] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.558011
 > [  1.558011] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.558011, buffersize=93)
-> [  1.558011] (0:@) insert_into_buffer: inserted at end
+> [  1.558011] (0:@) insert_into_buffer: inserted at end, pos = 93
 > [  1.558011] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts
 > [  1.558011] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.526913
 > [  1.558011] (0:@) free_paje_event: event_type=7, timestamp=0.526913
@@ -2270,30 +2270,30 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.644947] (0:@) insert_into_buffer: inserted at 19
 > [  1.644947] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.644947
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.644947, buffersize=59)
-> [  1.644947] (0:@) insert_into_buffer: inserted at end
+> [  1.644947] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  1.644947] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.571243
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.571243, buffersize=60)
 > [  1.644947] (0:@) insert_into_buffer: inserted at 58
 > [  1.644947] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.644947
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.644947, buffersize=61)
-> [  1.644947] (0:@) insert_into_buffer: inserted at end
+> [  1.644947] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  1.644947] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.571243
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.571243, buffersize=62)
 > [  1.644947] (0:@) insert_into_buffer: inserted at 59
 > [  1.644947] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.644947
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.644947, buffersize=63)
-> [  1.644947] (0:@) insert_into_buffer: inserted at end
+> [  1.644947] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  1.644947] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.571243
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.571243, buffersize=64)
 > [  1.644947] (0:@) insert_into_buffer: inserted at 60
 > [  1.644947] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.644947
 > [  1.644947] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.644947, buffersize=65)
-> [  1.644947] (0:@) insert_into_buffer: inserted at end
+> [  1.644947] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  1.644947] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts
 > [  1.644947] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.698471] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.644947
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.644947, buffersize=66)
-> [  1.698471] (0:@) insert_into_buffer: inserted at end
+> [  1.698471] (0:@) insert_into_buffer: inserted at end, pos = 66
 > [  1.698471] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.698471
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.698471, buffersize=67)
 > [  1.698471] (0:@) insert_into_buffer: inserted at end
@@ -2302,38 +2302,38 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.698471] (0:@) insert_into_buffer: inserted at 67
 > [  1.698471] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.698471
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.698471, buffersize=69)
-> [  1.698471] (0:@) insert_into_buffer: inserted at end
+> [  1.698471] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  1.698471] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.644947
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.644947, buffersize=70)
 > [  1.698471] (0:@) insert_into_buffer: inserted at 68
 > [  1.698471] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.698471
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.698471, buffersize=71)
-> [  1.698471] (0:@) insert_into_buffer: inserted at end
+> [  1.698471] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  1.698471] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts
 > [  1.698471] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.698471] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=1.698471
 > [  1.698471] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=1.698471, buffersize=72)
-> [  1.698471] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  1.698471] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 72
 > [  1.698471] (5:slave@Ginette) new_pajeAddVariable: event_type=8, timestamp=1.698471
 > [  1.698471] (5:slave@Ginette) insert_into_buffer: insert event_type=8, timestamp=1.698471, buffersize=73)
-> [  1.698471] (5:slave@Ginette) insert_into_buffer: inserted at end
+> [  1.698471] (5:slave@Ginette) insert_into_buffer: inserted at end, pos = 73
 > [  1.698471] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.262288
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.262288, buffersize=74)
 > [  1.698471] (0:@) insert_into_buffer: inserted at 56
 > [  1.698471] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.698471
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.698471, buffersize=75)
-> [  1.698471] (0:@) insert_into_buffer: inserted at end
+> [  1.698471] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  1.698471] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.262288
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.262288, buffersize=76)
 > [  1.698471] (0:@) insert_into_buffer: inserted at 57
 > [  1.698471] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.698471
 > [  1.698471] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.698471, buffersize=77)
-> [  1.698471] (0:@) insert_into_buffer: inserted at end
+> [  1.698471] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  1.713671] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts
 > [  1.713671] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.771998] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.698471
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.698471, buffersize=78)
-> [  1.771998] (0:@) insert_into_buffer: inserted at end
+> [  1.771998] (0:@) insert_into_buffer: inserted at end, pos = 78
 > [  1.771998] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.771998
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.771998, buffersize=79)
 > [  1.771998] (0:@) insert_into_buffer: inserted at end
@@ -2342,19 +2342,19 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.771998] (0:@) insert_into_buffer: inserted at 79
 > [  1.771998] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.771998
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.771998, buffersize=81)
-> [  1.771998] (0:@) insert_into_buffer: inserted at end
+> [  1.771998] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  1.771998] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.713671
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.713671, buffersize=82)
 > [  1.771998] (0:@) insert_into_buffer: inserted at 80
 > [  1.771998] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.771998
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.771998, buffersize=83)
-> [  1.771998] (0:@) insert_into_buffer: inserted at end
+> [  1.771998] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  1.771998] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.713671
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.713671, buffersize=84)
 > [  1.771998] (0:@) insert_into_buffer: inserted at 81
 > [  1.771998] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.771998
 > [  1.771998] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.771998, buffersize=85)
-> [  1.771998] (0:@) insert_into_buffer: inserted at end
+> [  1.771998] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  1.771998] (0:@) TRACE_paje_dump_buffer: dump until 0.742786. starts
 > [  1.771998] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.773883] (0:@) new_pajeSetVariable: event_type=7, timestamp=0.742786
@@ -2374,19 +2374,19 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.773883] (0:@) insert_into_buffer: inserted at 3
 > [  1.773883] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.773883
 > [  1.773883] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.773883, buffersize=91)
-> [  1.773883] (0:@) insert_into_buffer: inserted at end
+> [  1.773883] (0:@) insert_into_buffer: inserted at end, pos = 91
 > [  1.773883] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.771998
 > [  1.773883] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.771998, buffersize=92)
 > [  1.773883] (0:@) insert_into_buffer: inserted at 90
 > [  1.773883] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.773883
 > [  1.773883] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.773883, buffersize=93)
-> [  1.773883] (0:@) insert_into_buffer: inserted at end
+> [  1.773883] (0:@) insert_into_buffer: inserted at end, pos = 93
 > [  1.773883] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.771998
 > [  1.773883] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.771998, buffersize=94)
 > [  1.773883] (0:@) insert_into_buffer: inserted at 91
 > [  1.773883] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.773883
 > [  1.773883] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.773883, buffersize=95)
-> [  1.773883] (0:@) insert_into_buffer: inserted at end
+> [  1.773883] (0:@) insert_into_buffer: inserted at end, pos = 95
 > [  1.773883] (0:@) TRACE_paje_dump_buffer: dump until 1.259949. starts
 > [  1.773883] (0:@) print_pajeSetVariable: event_type=7, timestamp=0.742786
 > [  1.773883] (0:@) free_paje_event: event_type=7, timestamp=0.742786
@@ -2499,7 +2499,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.773883] (0:@) TRACE_paje_dump_buffer: ends
 > [  1.864433] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.773883
 > [  1.864433] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.773883, buffersize=42)
-> [  1.864433] (0:@) insert_into_buffer: inserted at end
+> [  1.864433] (0:@) insert_into_buffer: inserted at end, pos = 42
 > [  1.864433] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.864433
 > [  1.864433] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.864433, buffersize=43)
 > [  1.864433] (0:@) insert_into_buffer: inserted at end
@@ -2508,15 +2508,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.864433] (0:@) insert_into_buffer: inserted at 43
 > [  1.864433] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.864433
 > [  1.864433] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.864433, buffersize=45)
-> [  1.864433] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  1.864433] (3:slave@Jupiter) 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
@@ -2530,15 +2530,15 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.866771] (0:@) insert_into_buffer: inserted at 49
 > [  1.866771] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.866771
 > [  1.866771] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.866771, buffersize=51)
-> [  1.866771] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  1.866771] (2:slave@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
@@ -2552,91 +2552,91 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.915291] (0:@) insert_into_buffer: inserted at 1
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=57)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=58)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 56
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=59)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=60)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 57
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=61)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=62)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 58
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=63)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=64)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 59
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=65)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=66)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 60
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=67)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=68)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 61
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=69)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=70)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 62
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=71)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=72)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 63
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=73)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=74)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 64
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=75)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=76)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 65
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=77)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=78)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 66
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=79)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=80)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 67
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=81)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=82)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 68
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=83)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  1.915291] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.887107
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.887107, buffersize=84)
 > [  1.915291] (0:@) insert_into_buffer: inserted at 69
 > [  1.915291] (0:@) new_pajeSubVariable: event_type=9, timestamp=1.915291
 > [  1.915291] (0:@) insert_into_buffer: insert event_type=9, timestamp=1.915291, buffersize=85)
-> [  1.915291] (0:@) insert_into_buffer: inserted at end
+> [  1.915291] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  1.915291] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
 > [  1.915291] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.259949
 > [  1.915291] (0:@) free_paje_event: event_type=8, timestamp=1.259949
@@ -2705,7 +2705,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  1.915291] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=54)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 54
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=55)
 > [  2.082643] (0:@) insert_into_buffer: inserted at end
@@ -2714,99 +2714,99 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.082643] (0:@) insert_into_buffer: inserted at 55
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=57)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=58)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 56
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=59)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=60)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 57
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=61)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=62)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 58
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=63)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=64)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 59
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=65)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=66)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 60
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=67)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=68)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 61
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=69)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=70)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 62
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=71)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=72)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 63
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=73)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=74)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 64
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=75)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=76)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 65
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=77)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=78)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 66
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=79)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  2.082643] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.915291
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.915291, buffersize=80)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 67
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=81)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  2.082643] (6:slave@Bourassa) 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.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=85)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  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=86)
 > [  2.082643] (0:@) insert_into_buffer: inserted at 25
 > [  2.082643] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.082643
 > [  2.082643] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.082643, buffersize=87)
-> [  2.082643] (0:@) insert_into_buffer: inserted at end
+> [  2.082643] (0:@) insert_into_buffer: inserted at end, pos = 87
 > [  2.103194] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
 > [  2.103194] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
@@ -2820,90 +2820,90 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.300797] (0:@) insert_into_buffer: inserted at 89
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=91)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 91
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=92)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 90
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=93)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 93
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=94)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 91
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=95)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 95
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=96)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 92
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=97)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 97
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=98)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 93
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=99)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 99
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=100)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 94
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=101)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 101
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=102)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 95
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=103)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 103
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=104)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 96
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=105)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 105
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=106)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 97
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=107)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 107
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=108)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 98
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=109)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 109
 > [  2.300797] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.103194
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.103194, buffersize=110)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 99
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=111)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  2.300797] (4:slave@Fafard) 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.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=115)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 115
 > [  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=116)
 > [  2.300797] (0:@) insert_into_buffer: inserted at 89
 > [  2.300797] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.300797
 > [  2.300797] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.300797, buffersize=117)
-> [  2.300797] (0:@) insert_into_buffer: inserted at end
+> [  2.300797] (0:@) insert_into_buffer: inserted at end, pos = 117
 > [  2.376481] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.300797
 > [  2.376481] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.300797, buffersize=118)
-> [  2.376481] (0:@) insert_into_buffer: inserted at end
+> [  2.376481] (0:@) insert_into_buffer: inserted at end, pos = 118
 > [  2.376481] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.376481
 > [  2.376481] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.376481, buffersize=119)
 > [  2.376481] (0:@) insert_into_buffer: inserted at end
@@ -2912,7 +2912,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.376481] (0:@) insert_into_buffer: inserted at 119
 > [  2.376481] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.376481
 > [  2.376481] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.376481, buffersize=121)
-> [  2.376481] (0:@) insert_into_buffer: inserted at end
+> [  2.376481] (0:@) insert_into_buffer: inserted at end, pos = 121
 > [  2.376481] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
 > [  2.376481] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.376637] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
@@ -2927,10 +2927,10 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.378820] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.378820] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.378820
 > [  2.378820] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.378820, buffersize=124)
-> [  2.378820] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  2.378820] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 124
 > [  2.378820] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=2.378820
 > [  2.378820] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=2.378820, buffersize=125)
-> [  2.378820] (2:slave@Tremblay) insert_into_buffer: inserted at end
+> [  2.378820] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 125
 > [  2.519775] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.864433
 > [  2.519775] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.864433, buffersize=126)
 > [  2.519775] (0:@) insert_into_buffer: inserted at 18
@@ -2942,7 +2942,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.519775] (0:@) insert_into_buffer: inserted at 19
 > [  2.519775] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.519775
 > [  2.519775] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.519775, buffersize=129)
-> [  2.519775] (0:@) insert_into_buffer: inserted at end
+> [  2.519775] (0:@) insert_into_buffer: inserted at end, pos = 129
 > [  2.519775] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
 > [  2.519775] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.534975] (0:@) TRACE_paje_dump_buffer: dump until 1.698471. starts
@@ -2957,22 +2957,22 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.685737] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.685737] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.685737
 > [  2.685737] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.685737, buffersize=132)
-> [  2.685737] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  2.685737] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 132
 > [  2.685737] (3:slave@Jupiter) new_pajeAddVariable: event_type=8, timestamp=2.685737
 > [  2.685737] (3:slave@Jupiter) insert_into_buffer: insert event_type=8, timestamp=2.685737, buffersize=133)
-> [  2.685737] (3:slave@Jupiter) insert_into_buffer: inserted at end
+> [  2.685737] (3:slave@Jupiter) insert_into_buffer: inserted at end, pos = 133
 > [  2.685737] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.378820
 > [  2.685737] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.378820, buffersize=134)
 > [  2.685737] (0:@) insert_into_buffer: inserted at 128
 > [  2.685737] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.685737
 > [  2.685737] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.685737, buffersize=135)
-> [  2.685737] (0:@) insert_into_buffer: inserted at end
+> [  2.685737] (0:@) insert_into_buffer: inserted at end, pos = 135
 > [  2.685737] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.378820
 > [  2.685737] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.378820, buffersize=136)
 > [  2.685737] (0:@) insert_into_buffer: inserted at 129
 > [  2.685737] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.685737
 > [  2.685737] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.685737, buffersize=137)
-> [  2.685737] (0:@) insert_into_buffer: inserted at end
+> [  2.685737] (0:@) insert_into_buffer: inserted at end, pos = 137
 > [  2.729569] (0:@) new_pajeAddVariable: event_type=8, timestamp=1.698471
 > [  2.729569] (0:@) insert_into_buffer: insert event_type=8, timestamp=1.698471, buffersize=138)
 > [  2.729569] (0:@) insert_into_buffer: inserted at 0
@@ -2984,7 +2984,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.729569] (0:@) insert_into_buffer: inserted at 1
 > [  2.729569] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.729569
 > [  2.729569] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.729569, buffersize=141)
-> [  2.729569] (0:@) insert_into_buffer: inserted at end
+> [  2.729569] (0:@) insert_into_buffer: inserted at end, pos = 141
 > [  2.729569] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
 > [  2.729569] (0:@) print_pajeAddVariable: event_type=8, timestamp=1.698471
 > [  2.729569] (0:@) free_paje_event: event_type=8, timestamp=1.698471
@@ -3188,36 +3188,36 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.870028] (0:@) insert_into_buffer: inserted at 49
 > [  2.870028] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.870028
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.870028, buffersize=51)
-> [  2.870028] (0:@) insert_into_buffer: inserted at end
+> [  2.870028] (0:@) insert_into_buffer: inserted at end, pos = 51
 > [  2.870028] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.742800
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.742800, buffersize=52)
 > [  2.870028] (0:@) insert_into_buffer: inserted at 50
 > [  2.870028] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.870028
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.870028, buffersize=53)
-> [  2.870028] (0:@) insert_into_buffer: inserted at end
+> [  2.870028] (0:@) insert_into_buffer: inserted at end, pos = 53
 > [  2.870028] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
 > [  2.870028] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.870028] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.870028
 > [  2.870028] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.870028, buffersize=54)
-> [  2.870028] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  2.870028] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 54
 > [  2.870028] (5:slave@Ginette) new_pajeAddVariable: event_type=8, timestamp=2.870028
 > [  2.870028] (5:slave@Ginette) insert_into_buffer: insert event_type=8, timestamp=2.870028, buffersize=55)
-> [  2.870028] (5:slave@Ginette) insert_into_buffer: inserted at end
+> [  2.870028] (5:slave@Ginette) insert_into_buffer: inserted at end, pos = 55
 > [  2.870028] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.685737
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.685737, buffersize=56)
 > [  2.870028] (0:@) insert_into_buffer: inserted at 46
 > [  2.870028] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.870028
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.870028, buffersize=57)
-> [  2.870028] (0:@) insert_into_buffer: inserted at end
+> [  2.870028] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  2.870028] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.685737
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.685737, buffersize=58)
 > [  2.870028] (0:@) insert_into_buffer: inserted at 47
 > [  2.870028] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.870028
 > [  2.870028] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.870028, buffersize=59)
-> [  2.870028] (0:@) insert_into_buffer: inserted at end
+> [  2.870028] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  2.888530] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.870028
 > [  2.888530] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.870028, buffersize=60)
-> [  2.888530] (0:@) insert_into_buffer: inserted at end
+> [  2.888530] (0:@) insert_into_buffer: inserted at end, pos = 60
 > [  2.888530] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.888530
 > [  2.888530] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.888530, buffersize=61)
 > [  2.888530] (0:@) insert_into_buffer: inserted at end
@@ -3226,7 +3226,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.888530] (0:@) insert_into_buffer: inserted at 61
 > [  2.888530] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.888530
 > [  2.888530] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.888530, buffersize=63)
-> [  2.888530] (0:@) insert_into_buffer: inserted at end
+> [  2.888530] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  2.888530] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
 > [  2.888530] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.888686] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
@@ -3241,10 +3241,10 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.890868] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.890868] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=2.890868
 > [  2.890868] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=2.890868, buffersize=66)
-> [  2.890868] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  2.890868] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 66
 > [  2.890868] (2:slave@Tremblay) new_pajeAddVariable: event_type=8, timestamp=2.890868
 > [  2.890868] (2:slave@Tremblay) insert_into_buffer: insert event_type=8, timestamp=2.890868, buffersize=67)
-> [  2.890868] (2:slave@Tremblay) insert_into_buffer: inserted at end
+> [  2.890868] (2:slave@Tremblay) insert_into_buffer: inserted at end, pos = 67
 > [  2.956139] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.300797
 > [  2.956139] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.300797, buffersize=68)
 > [  2.956139] (0:@) insert_into_buffer: inserted at 30
@@ -3256,7 +3256,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  2.956139] (0:@) insert_into_buffer: inserted at 31
 > [  2.956139] (0:@) new_pajeSubVariable: event_type=9, timestamp=2.956139
 > [  2.956139] (0:@) insert_into_buffer: insert event_type=9, timestamp=2.956139, buffersize=71)
-> [  2.956139] (0:@) insert_into_buffer: inserted at end
+> [  2.956139] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  2.956139] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
 > [  2.956139] (0:@) TRACE_paje_dump_buffer: ends
 > [  2.976690] (0:@) TRACE_paje_dump_buffer: dump until 2.082643. starts
@@ -3272,43 +3272,43 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.113741] (0:@) insert_into_buffer: inserted at 1
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=75)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=76)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 74
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=77)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=78)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 75
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=79)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=80)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 76
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=81)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=82)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 77
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=83)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=84)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 78
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=85)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  3.113741] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.976690
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.976690, buffersize=86)
 > [  3.113741] (0:@) insert_into_buffer: inserted at 79
 > [  3.113741] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.113741
 > [  3.113741] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.113741, buffersize=87)
-> [  3.113741] (0:@) insert_into_buffer: inserted at end
+> [  3.113741] (0:@) insert_into_buffer: inserted at end, pos = 87
 > [  3.113741] (0:@) TRACE_paje_dump_buffer: dump until 2.685737. starts
 > [  3.113741] (0:@) print_pajeAddVariable: event_type=8, timestamp=2.082643
 > [  3.113741] (0:@) free_paje_event: event_type=8, timestamp=2.082643
@@ -3417,7 +3417,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.113741] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.113741
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.113741, buffersize=36)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 36
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=37)
 > [  3.174292] (0:@) insert_into_buffer: inserted at end
@@ -3426,51 +3426,51 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.174292] (0:@) insert_into_buffer: inserted at 37
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=39)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 39
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.113741
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.113741, buffersize=40)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 38
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=41)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 41
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.113741
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.113741, buffersize=42)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 39
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=43)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 43
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.113741
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.113741, buffersize=44)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 40
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=45)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 45
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.113741
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.113741, buffersize=46)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 41
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=47)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 47
 > [  3.174292] (0:@) TRACE_paje_dump_buffer: dump until 2.685737. starts
 > [  3.174292] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.174292] (1:master@Tremblay) new_pajeSetVariable: event_type=7, timestamp=3.174292
 > [  3.174292] (1:master@Tremblay) insert_into_buffer: insert event_type=7, timestamp=3.174292, buffersize=48)
-> [  3.174292] (1:master@Tremblay) insert_into_buffer: inserted at end
+> [  3.174292] (1:master@Tremblay) insert_into_buffer: inserted at end, pos = 48
 > [  3.174292] (4:slave@Fafard) new_pajeAddVariable: event_type=8, timestamp=3.174292
 > [  3.174292] (4:slave@Fafard) insert_into_buffer: insert event_type=8, timestamp=3.174292, buffersize=49)
-> [  3.174292] (4:slave@Fafard) insert_into_buffer: inserted at end
+> [  3.174292] (4:slave@Fafard) insert_into_buffer: inserted at end, pos = 49
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.890868
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.890868, buffersize=50)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 20
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=51)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 51
 > [  3.174292] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.890868
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=8, timestamp=2.890868, buffersize=52)
 > [  3.174292] (0:@) insert_into_buffer: inserted at 21
 > [  3.174292] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.174292
 > [  3.174292] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.174292, buffersize=53)
-> [  3.174292] (0:@) insert_into_buffer: inserted at end
+> [  3.174292] (0:@) insert_into_buffer: inserted at end, pos = 53
 > [  3.194628] (0:@) TRACE_paje_dump_buffer: dump until 2.685737. starts
 > [  3.194628] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=2.685737
@@ -3484,91 +3484,91 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.341079] (0:@) insert_into_buffer: inserted at 1
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=57)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 57
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=58)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 56
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=59)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 59
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=60)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 57
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=61)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 61
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=62)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 58
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=63)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 63
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=64)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 59
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=65)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 65
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=66)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 60
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=67)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 67
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=68)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 61
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=69)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 69
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=70)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 62
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=71)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 71
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=72)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 63
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=73)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=74)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 64
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=75)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=76)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 65
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=77)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=78)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 66
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=79)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=80)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 67
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=81)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=82)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 68
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=83)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  3.341079] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.194628
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.194628, buffersize=84)
 > [  3.341079] (0:@) insert_into_buffer: inserted at 69
 > [  3.341079] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.341079
 > [  3.341079] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.341079, buffersize=85)
-> [  3.341079] (0:@) insert_into_buffer: inserted at end
+> [  3.341079] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  3.341079] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts
 > [  3.341079] (0:@) print_pajeAddVariable: event_type=8, timestamp=2.685737
 > [  3.341079] (0:@) free_paje_event: event_type=8, timestamp=2.685737
@@ -3605,7 +3605,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.341079] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=70)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 70
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=71)
 > [  3.390164] (0:@) insert_into_buffer: inserted at end
@@ -3614,102 +3614,102 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.390164] (0:@) insert_into_buffer: inserted at 71
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=73)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 73
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=74)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 72
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=75)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 75
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=76)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 73
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=77)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 77
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=78)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 74
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=79)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 79
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=80)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 75
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=81)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 81
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=82)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 76
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=83)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 83
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=84)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 77
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=85)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 85
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=86)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 78
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=87)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 87
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=88)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 79
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=89)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 89
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=90)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 80
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=91)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 91
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=92)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 81
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=93)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 93
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=94)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 82
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=95)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 95
 > [  3.390164] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.341079
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.341079, buffersize=96)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 83
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=97)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  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
+> [  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
+> [  3.390164] (6:slave@Bourassa) 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.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=101)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 101
 > [  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=102)
 > [  3.390164] (0:@) insert_into_buffer: inserted at 41
 > [  3.390164] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.390164
 > [  3.390164] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.390164, buffersize=103)
-> [  3.390164] (0:@) insert_into_buffer: inserted at end
+> [  3.390164] (0:@) insert_into_buffer: inserted at end, pos = 103
 > [  3.400578] (0:@) new_pajeAddVariable: event_type=8, timestamp=3.390164
 > [  3.400578] (0:@) insert_into_buffer: insert event_type=8, timestamp=3.390164, buffersize=104)
-> [  3.400578] (0:@) insert_into_buffer: inserted at end
+> [  3.400578] (0:@) insert_into_buffer: inserted at end, pos = 104
 > [  3.400578] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.400578
 > [  3.400578] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.400578, buffersize=105)
 > [  3.400578] (0:@) insert_into_buffer: inserted at end
@@ -3718,7 +3718,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.400578] (0:@) insert_into_buffer: inserted at 105
 > [  3.400578] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.400578
 > [  3.400578] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.400578, buffersize=107)
-> [  3.400578] (0:@) insert_into_buffer: inserted at end
+> [  3.400578] (0:@) insert_into_buffer: inserted at end, pos = 107
 > [  3.400578] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts
 > [  3.400578] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.405364] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts
@@ -3736,7 +3736,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.829634] (0:@) insert_into_buffer: inserted at 43
 > [  3.829634] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.829634
 > [  3.829634] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.829634, buffersize=111)
-> [  3.829634] (0:@) insert_into_buffer: inserted at end
+> [  3.829634] (0:@) insert_into_buffer: inserted at end, pos = 111
 > [  3.829634] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts
 > [  3.829634] (0:@) TRACE_paje_dump_buffer: ends
 > [  3.850185] (0:@) TRACE_paje_dump_buffer: dump until 2.870028. starts
@@ -3752,7 +3752,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  3.901126] (0:@) insert_into_buffer: inserted at 1
 > [  3.901126] (0:@) new_pajeSubVariable: event_type=9, timestamp=3.901126
 > [  3.901126] (0:@) insert_into_buffer: insert event_type=9, timestamp=3.901126, buffersize=115)
-> [  3.901126] (0:@) insert_into_buffer: inserted at end
+> [  3.901126] (0:@) insert_into_buffer: inserted at end, pos = 115
 > [  3.901126] (0:@) TRACE_paje_dump_buffer: dump until 3.390164. starts
 > [  3.901126] (0:@) print_pajeAddVariable: event_type=8, timestamp=2.870028
 > [  3.901126] (0:@) free_paje_event: event_type=8, timestamp=2.870028
@@ -3988,7 +3988,7 @@ $ $SG_TEST_EXENV tracing/ms$EXEEXT --cfg=tracing:1 --cfg=tracing/filename:tracin
 > [  4.421262] (0:@) insert_into_buffer: inserted at 1
 > [  4.421262] (0:@) new_pajeSubVariable: event_type=9, timestamp=4.421262
 > [  4.421262] (0:@) insert_into_buffer: insert event_type=9, timestamp=4.421262, buffersize=9)
-> [  4.421262] (0:@) insert_into_buffer: inserted at end
+> [  4.421262] (0:@) insert_into_buffer: inserted at end, pos = 9
 > [  4.421262] (0:@) TRACE_paje_dump_buffer: dump until 3.405364. starts
 > [  4.421262] (0:@) print_pajeAddVariable: event_type=8, timestamp=3.390164
 > [  4.421262] (0:@) free_paje_event: event_type=8, timestamp=3.390164

diff --git a/src/instr/instr_paje_trace.c b/src/instr/instr_paje_trace.c
index 4841f79..945d3f7 100644 (file)
--- a/src/instr/instr_paje_trace.c
+++ b/src/instr/instr_paje_trace.c
@@ -321,12 +321,21 @@ static void insert_into_buffer (paje_event_t tbi)
   DEBUG4("%s: insert event_type=%d, timestamp=%f, buffersize=%ld)", __FUNCTION__, tbi->event_type, tbi->timestamp, xbt_dynar_length(buffer));
 
   unsigned int i;
-  if (xbt_dynar_length(buffer) == 0){
+  unsigned long len = xbt_dynar_length(buffer);
+  if (len == 0){
     xbt_dynar_push (buffer, &tbi);
     DEBUG1("%s: inserted at beginning", __FUNCTION__);
   }else{
+    //check if last event has the same timestamp that tbi event
+    paje_event_t e2 = *(paje_event_t*)xbt_dynar_get_ptr (buffer, len-1);
+    if (e2->timestamp == tbi->timestamp){
+      //insert at the end
+      DEBUG2("%s: inserted at end, pos = %ld", __FUNCTION__, len);
+      xbt_dynar_insert_at (buffer, len, &tbi);
+      return;
+    }
     int inserted = 0;
-    for (i = 0; i < xbt_dynar_length(buffer); i++){
+    for (i = 0; i < len; i++){
       paje_event_t e1 = *(paje_event_t*)xbt_dynar_get_ptr(buffer, i);
       if (e1->timestamp > tbi->timestamp){
         xbt_dynar_insert_at (buffer, i, &tbi);