__step=000292
__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.hytm1 -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000292.txt
__path_data=data/data000292.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998191.333628894
binary=./brown_sigouin_int_avl_tm_auto.debra.hytm1
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=2000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.016s
prefilling_size=839207 prefilling_elapsed_millis=1.001s
prefilling_size=872302 prefilling_elapsed_millis=1.101s
prefilling_size=897549 prefilling_elapsed_millis=1.201s
prefilling_size=920310 prefilling_elapsed_millis=1.301s
prefilling_size=940857 prefilling_elapsed_millis=1.402s
prefilling_size=953371 prefilling_elapsed_millis=1.502s
prefilling_size=963697 prefilling_elapsed_millis=1.602s
prefilling_size=971364 prefilling_elapsed_millis=1.702s
prefilling_size=978565 prefilling_elapsed_millis=1.802s
prefilling_size=982554 prefilling_elapsed_millis=1.903s
finished prefilling to size 982549 for expected size 1000000 keysum=981993178693, performing 8194314 updates; total_prefilling_elapsed_ms=2004 ms)
pref_size=982549
pref_millis=2004
prefill_elapsed_ms=2022
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998193.568734972
REALTIME_END_PERF_FORMAT=6998203.568874590

###############################################################################
################################## 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 (192 threads)...
partitioned into 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.039s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32760 64825 117844 159426 120605 40613 4871 143 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 8 628 10200 62220 151709 143241 51345 6111 177
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65453 128044 221646 272314 183854 56216 6254 177
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65453 128044 221646 272314 183854 56216 6254 177
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99771 1.96587 1.81067 1.50932 1.23226 1.08487 1.0246 1.00544 1

tree_stats_height=24
tree_stats_numInternals=573854
tree_stats_numLeaves=425639
tree_stats_numNodes=999493
tree_stats_numKeys=999493

tree_stats_avgDegreeInternal=1.74172
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42585
tree_stats_avgKeyDepth=18.3137


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=361056
first_thread_announced_epoch_by_thread=7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712 7712
sum_num_inserts_by_thread=169404 169602 172026 171365 171772 168267 167367 170517 172756 171589 169968 169620 170901 171288 171540 170899 172011 169721 170082 170850 169139 171754 169667 168641 169518 170149 170867 171099 170369 169196 169633 168906 171707 172397 171311 165137 168481 169194 170357 172063 171081 167418 167764 168006 169826 172046 168617 168365 171786 172258 175197 169224 169435 172053 168263 170622 172997 174621 171603 170060 171940 171358 171745 173136 171449 168536 169888 170747 172791 172534 163639 171463 170270 170962 173124 172353 171773 172661 170136 172463 174817 175026 171335 171384 169984 171852 172531 173105 170708 168587 170023 170616 173864 173941 171575 169267
sum_num_inserts_total=16393955
sum_num_deletes_by_thread=169355 169703 170949 171526 171025 167874 166904 170382 172817 172735 169176 169432 170275 171220 172010 172416 171783 170674 169727 170050 169376 171407 169078 169350 169919 170151 171100 171535 170620 169153 169880 168869 171036 171891 170995 165668 169345 169566 170315 172247 171299 167079 168201 168694 170280 172618 169484 169376 171001 173015 174563 169605 168826 171739 169250 170563 173211 173484 172007 170739 171393 172328 172233 173123 171933 167830 169617 171344 172518 173275 163562 170630 170021 171164 173086 172465 171580 171335 169633 172555 174970 174806 170475 171134 169648 172196 171539 173098 170485 168923 170673 172064 173646 173842 171809 169717
sum_num_deletes_total=16397218
sum_num_searches_by_thread=3051344 3055366 3087871 3087294 3082520 3027209 3003350 3074648 3106364 3101166 3065764 3054189 3074015 3084737 3093868 3088174 3086111 3073578 3052587 3064769 3047785 3087281 3046832 3044838 3052590 3054651 3077443 3079383 3074064 3044404 3053140 3031836 3088007 3102347 3070009 2981295 3042289 3052994 3064197 3085938 3084955 3011073 3025615 3032909 3056845 3095853 3039069 3038423 3086974 3102242 3130656 3062038 3039864 3097227 3031357 3073527 3118128 3125188 3095121 3070245 3082071 3089003 3095756 3118692 3091036 3025881 3054879 3076224 3112235 3109553 2948207 3076041 3068375 3085520 3120835 3107215 3089258 3088368 3062194 3098554 3140256 3144948 3080626 3094801 3066732 3098490 3090036 3115897 3066792 3033806 3069574 3075233 3121053 3130488 3095103 3054881
sum_num_searches_total=295092169
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3390103 3394671 3430846 3430185 3425317 3363350 3337621 3415547 3451937 3445490 3404908 3393241 3415191 3427245 3437418 3431489 3429905 3413973 3392396 3405669 3386300 3430442 3385577 3382829 3392027 3394951 3419410 3422017 3415053 3382753 3392653 3369611 3430750 3446635 3412315 3312100 3380115 3391754 3404869 3430248 3427335 3345570 3361580 3369609 3396951 3440517 3377170 3376164 3429761 3447515 3480416 3400867 3378125 3441019 3368870 3414712 3464336 3473293 3438731 3411044 3425404 3432689 3439734 3464951 3434418 3362247 3394384 3418315 3457544 3455362 3275408 3418134 3408666 3427646 3467045 3452033 3432611 3432364 3401963 3443572 3490043 3494780 3422436 3437319 3406364 3442538 3434106 3462100 3407985 3371316 3410270 3417913 3468563 3478271 3438487 3393865
average_num_operations_total=3415451
stdev_num_operations_total=3769
sum_num_operations_total=327883342
min_num_operations_total=3275408
max_num_operations_total=3494780
first_time_thread_terminate_by_thread=10000140 10000140 10000139 10000139 10000140 10000139 10000138 10000138 10000139 10000138 10000138 10000138 10000139 10000139 10000139 10000138 10000140 10000138 10000138 10000141 10000140 10000140 10000140 10000140 10000138 10000140 10000139 10000138 10000140 10000139 10000138 10000141 10000139 10000138 10000138 10000141 10000139 10000139 10000140 10000138 10000140 10000198 10000138 10000141 10000138 10000140 10000140 10000140 10000138 10000139 10000139 10000138 10000139 10000139 10000139 10000141 10000140 10000139 10000141 10000140 10000139 10000138 10000140 10000139 10000139 10000140 10000139 10000141 10000139 10000141 10000141 10000141 10000138 10000138 10000139 10000138 10000139 10000139 10000139 10000141 10000140 10000139 10000141 10000140 10000139 10000138 10000140 10000139 10000139 10000140 10000139 10000139 10000139 10000141 10000139 10000141
min_time_thread_terminate_total=10000138
max_time_thread_terminate_total=10000198
first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 4 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=999800235447
threads_final_size=999493
final_keysum=999800235447
final_size=999493
validate_result=success
Validation OK.
Structural validation OK.

total_find=295092169
total_rq=0
total_inserts=16393955
total_deletes=16397218
total_updates=32791173
total_queries=295092169
total_ops=327883342
find_throughput=29509216
rq_throughput=0
update_throughput=3279117
query_throughput=29509216
total_throughput=32788334


total find                    : 295092169
total rq                      : 0
total inserts                 : 16393955
total deletes                 : 16397218
total updates                 : 32791173
total queries                 : 295092169
total ops                     : 327883342
find throughput               : 29509216
rq throughput                 : 0
update throughput             : 3279117
query throughput              : 29509216
total throughput              : 32788334

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=13.979
PAPI_L3_TCM=2.39327
PAPI_TOT_CYC=8021.32
PAPI_TOT_INS=8119.22
garbage=144304431405051
total_execution_walltime=12.413s
REALTIME_MAIN_END_PERF_FORMAT=6998203.762115555
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 335895959
[slow htm]
[fallback]
total fallback commit         : 181698
seconds global lock is held   : 7.59418
[time_cmd_output] time_elapsed_sec=12.60, faults_major=0, faults_minor=331163, mem_maxresident_kb=842932, user_cputime=1169.78, sys_cputime=9.92, percent_cpu=9359%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.hytm1
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2