__step=000066
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 numactl -i all /usr/bin/time -f "[time_cmd_output] time_elapsed_sec=%e, faults_major=%F, faults_minor=%R, mem_maxresident_kb=%M, user_cputime=%U, sys_cputime=%S, percent_cpu=%P" ./brown_sigouin_int_avl_tm_auto.debra.tl2 -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000066.txt
__path_data=data/data000066.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993726.724134103
binary=./brown_sigouin_int_avl_tm_auto.debra.tl2
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_sigouin_int_avl_tm_auto
FIND_FUNC=FIND_FUNC
INSERT_FUNC=insertIfAbsent
ERASE_FUNC=ERASE_FUNC
RQ_FUNC=RQ_FUNC
RECLAIM=reclaimer_debra
ALLOC=allocator_new
POOL=pool_none
MAX_THREADS_POW2=512
CPU_FREQ_GHZ=2.1
MILLIS_TO_RUN=10000
INS_FRAC=5
DEL_FRAC=5
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=5.0 5.0
PREFILL_TYPE=PREFILL_HYBRID
PREFILL_HYBRID_MIN_MS=1000
PREFILL_HYBRID_MAX_MS=5000
sizes: node=48
ACTUAL_THREAD_BINDINGS=0,1,2,3,4,5,6,7,8,9,10,11,12,13,14,15,16,17,18,19,20,21,22,23,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.007s
prefilling_size=4298855 prefilling_elapsed_millis=1.001s
prefilling_size=4587995 prefilling_elapsed_millis=1.101s
prefilling_size=4861457 prefilling_elapsed_millis=1.201s
prefilling_size=5120440 prefilling_elapsed_millis=1.301s
prefilling_size=5366493 prefilling_elapsed_millis=1.402s
prefilling_size=5596654 prefilling_elapsed_millis=1.502s
prefilling_size=5808856 prefilling_elapsed_millis=1.602s
prefilling_size=6009862 prefilling_elapsed_millis=1.702s
prefilling_size=6203597 prefilling_elapsed_millis=1.803s
prefilling_size=6385662 prefilling_elapsed_millis=1.903s
prefilling_size=6559559 prefilling_elapsed_millis=2.003s
prefilling_size=6724720 prefilling_elapsed_millis=2.103s
prefilling_size=6882819 prefilling_elapsed_millis=2.203s
prefilling_size=7032442 prefilling_elapsed_millis=2.304s
prefilling_size=7175992 prefilling_elapsed_millis=2.404s
prefilling_size=7311688 prefilling_elapsed_millis=2.504s
prefilling_size=7440644 prefilling_elapsed_millis=2.604s
prefilling_size=7563051 prefilling_elapsed_millis=2.704s
prefilling_size=7680796 prefilling_elapsed_millis=2.805s
prefilling_size=7792734 prefilling_elapsed_millis=2.905s
prefilling_size=7899330 prefilling_elapsed_millis=3.005s
prefilling_size=7999887 prefilling_elapsed_millis=3.105s
prefilling_size=8097013 prefilling_elapsed_millis=3.205s
prefilling_size=8188943 prefilling_elapsed_millis=3.306s
prefilling_size=8275748 prefilling_elapsed_millis=3.406s
prefilling_size=8359451 prefilling_elapsed_millis=3.506s
prefilling_size=8439332 prefilling_elapsed_millis=3.606s
prefilling_size=8513261 prefilling_elapsed_millis=3.706s
prefilling_size=8584482 prefilling_elapsed_millis=3.807s
prefilling_size=8653255 prefilling_elapsed_millis=3.907s
prefilling_size=8719345 prefilling_elapsed_millis=4.007s
prefilling_size=8780495 prefilling_elapsed_millis=4.107s
prefilling_size=8838640 prefilling_elapsed_millis=4.208s
prefilling_size=8893766 prefilling_elapsed_millis=4.308s
prefilling_size=8948279 prefilling_elapsed_millis=4.408s
prefilling_size=8999138 prefilling_elapsed_millis=4.508s
prefilling_size=9047164 prefilling_elapsed_millis=4.608s
prefilling_size=9093024 prefilling_elapsed_millis=4.709s
prefilling_size=9135701 prefilling_elapsed_millis=4.809s
prefilling_size=9178447 prefilling_elapsed_millis=4.909s
prefilling_size=9217836 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 782114 keys with 48 threads...
tid=0 thread_prefill_amount=204134 percent_done_estimate=1252.8 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=208300 percent_done_estimate=1278.4 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=212466 percent_done_estimate=1303.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=216632 percent_done_estimate=1329.5 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.172977s
prefilling_size=10000000 prefilling_elapsed_millis=0.173s
finished prefilling to size 10000000 for expected size 10000000 keysum=99999617474606, performing 53007335 updates; total_prefilling_elapsed_ms=173 ms)
pref_size=10000000
pref_millis=173
prefill_elapsed_ms=173
main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=6993732.212160423
REALTIME_END_PERF_FORMAT=6993742.212236712

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (48 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.053s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262033 519651 965406 1432868 1383633 726298 171460 13841 122 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 104 3979 63919 408737 1150750 1500853 892124 217729 17388 156
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262137 523630 1029325 1841605 2534383 2227151 1063584 231570 17510 156
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262137 523630 1029325 1841605 2534383 2227151 1063584 231570 17510 156
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99995 1.99794 1.97335 1.85124 1.59813 1.33283 1.15144 1.05652 1.01584 1.00194 1

tree_stats_height=28
tree_stats_numInternals=5737455
tree_stats_numLeaves=4255739
tree_stats_numNodes=9993194
tree_stats_numKeys=9993194

tree_stats_avgDegreeInternal=1.74175
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42586
tree_stats_avgKeyDepth=21.7025


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
    [2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=340559
first_thread_announced_epoch_by_thread=19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106 19106
sum_num_inserts_by_thread=160365 162371 162796 162926 162953 160859 161940 162230 164270 164509 162963 161481 162306 162433 163122 164320 163537 162438 160781 161644 162968 164474 162856 161626 160927 161324 163063 163667 162428 161567 161050 162021 163826 164536 163263 162696 161982 162350 162899 164186 163407 161826 161693 161731 162943 163751 162420 161520
sum_num_inserts_total=7803244
sum_num_deletes_by_thread=160718 161990 162970 163570 163075 161122 162145 162050 162442 164298 163500 162296 161259 163074 163523 165344 163821 162034 161198 161533 163584 164429 162989 161138 161343 162239 163362 163836 163330 160932 162226 162266 163901 164613 163006 162098 161218 162167 163537 164396 162945 161705 160791 162105 163932 164243 162014 161664
sum_num_deletes_total=7807971
sum_num_searches_by_thread=2887637 2909377 2939921 2942425 2935528 2900710 2906884 2917035 2946134 2963618 2936888 2913179 2905631 2923179 2944414 2948080 2942214 2918535 2893584 2916816 2937179 2953765 2925683 2908508 2899257 2913266 2936054 2946764 2934119 2906382 2906394 2922484 2948723 2962270 2936986 2923244 2907467 2924968 2945364 2954777 2941832 2916586 2890737 2914974 2931651 2952145 2928170 2906823
sum_num_searches_total=140468361
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3208720 3233738 3265687 3268921 3261556 3222691 3230969 3241315 3272846 3292425 3263351 3236956 3229196 3248686 3271059 3277744 3269572 3243007 3215563 3239993 3263731 3282668 3251528 3231272 3221527 3236829 3262479 3274267 3259877 3228881 3229670 3246771 3276450 3291419 3263255 3248038 3230667 3249485 3271800 3283359 3268184 3240117 3213221 3238810 3258526 3280139 3252604 3230007
average_num_operations_total=3251657
stdev_num_operations_total=3152
sum_num_operations_total=156079576
min_num_operations_total=3208720
max_num_operations_total=3292425
first_time_thread_terminate_by_thread=10000125 10000076 10000076 10000099 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000076 10000077 10000076 10000076 10000076 10000076 10000077 10000077 10000076 10000076 10000077 10000076 10000076 10000076 10000077 10000076 10000076 10000076 10000076 10000082 10000076 10000076 10000076 10000077 10000079 10000077 10000076 10000076 10000077 10000076 10000076 10000077 10000077 10000076 10000076
min_time_thread_terminate_total=10000076
max_time_thread_terminate_total=10000125
first_time_thread_start_by_thread=9 6 6 5 5 5 5 5 5 5 5 5 5 6 6 5 6 5 5 5 5 5 6 6 4 6 6 5 5 5 5 5 5 5 5 5 5 6 6 5 6 5 5 5 5 5 6 6
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99950350009460
threads_final_size=9993194
final_keysum=99950350009460
final_size=9993194
validate_result=success
Validation OK.
Structural validation OK.

total_find=140468361
total_rq=0
total_inserts=7803244
total_deletes=7807971
total_updates=15611215
total_queries=140468361
total_ops=156079576
find_throughput=14046836
rq_throughput=0
update_throughput=1561121
query_throughput=14046836
total_throughput=15607957


total find                    : 140468361
total rq                      : 0
total inserts                 : 7803244
total deletes                 : 7807971
total updates                 : 15611215
total queries                 : 140468361
total ops                     : 156079576
find throughput               : 14046836
rq throughput                 : 0
update throughput             : 1561121
query throughput              : 14046836
total throughput              : 15607957

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=45.387
PAPI_L3_TCM=7.65202
PAPI_TOT_CYC=7018.98
PAPI_TOT_INS=2409.93
garbage=673600202270349
total_execution_walltime=15.677s
REALTIME_MAIN_END_PERF_FORMAT=6993742.408201202
TL2 system shutdown:
  GCLOCK=0x3475176 Starts=208748833 Aborts=1136363
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.90, faults_major=0, faults_minor=608182, mem_maxresident_kb=1373960, user_cputime=696.80, sys_cputime=47.35, percent_cpu=4679%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2