__step=000291
__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=data000291.txt
__path_data=data/data000291.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998178.708132718
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.014s
prefilling_size=833118 prefilling_elapsed_millis=1.001s
prefilling_size=865218 prefilling_elapsed_millis=1.101s
prefilling_size=891646 prefilling_elapsed_millis=1.201s
prefilling_size=914298 prefilling_elapsed_millis=1.301s
prefilling_size=934895 prefilling_elapsed_millis=1.402s
prefilling_size=949019 prefilling_elapsed_millis=1.502s
prefilling_size=959752 prefilling_elapsed_millis=1.602s
prefilling_size=967795 prefilling_elapsed_millis=1.702s
prefilling_size=974947 prefilling_elapsed_millis=1.802s
prefilling_size=979757 prefilling_elapsed_millis=1.902s
prefilling_size=984415 prefilling_elapsed_millis=2.003s
finished prefilling to size 984431 for expected size 1000000 keysum=984485781071, performing 8636907 updates; total_prefilling_elapsed_ms=2104 ms)
pref_size=984431
pref_millis=2104
prefill_elapsed_ms=2124
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998181.12280916
REALTIME_END_PERF_FORMAT=6998191.12431560

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

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

10s
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.032s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16383 32744 64868 118650 159546 119898 40462 5027 122 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 22 562 9690 63481 152408 142342 50891 6395 152
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65430 128340 223027 272306 182804 55918 6517 152
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65430 128340 223027 272306 182804 55918 6517 152
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99756 1.97007 1.81329 1.50559 1.23101 1.08455 1.02665 1.0046 1

tree_stats_height=24
tree_stats_numInternals=574083
tree_stats_numLeaves=425944
tree_stats_numNodes=1000027
tree_stats_numKeys=1000027

tree_stats_avgDegreeInternal=1.74196
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42593
tree_stats_avgKeyDepth=18.3112


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=359712
first_thread_announced_epoch_by_thread=7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696 7696
sum_num_inserts_by_thread=168269 171425 170475 171444 168300 167568 170008 171008 172369 170941 170340 166827 169479 170770 168122 169291 171462 165857 167911 168384 170975 172428 169058 166678 159560 168600 168593 169539 170611 164284 166912 169423 171970 172701 170203 169032 167808 170413 169142 171587 169862 168507 167455 169749 167736 170252 169371 167538 169378 169293 164972 170616 169787 169148 167818 170960 172966 173409 169494 170466 172771 172454 170558 169441 171158 171962 169167 171083 172815 168231 170189 170754 168419 169085 169787 174172 169828 170749 169190 166077 172677 173271 170019 170953 169421 171076 171257 170090 172115 165540 171963 170898 174202 171878 172909 171630
sum_num_inserts_total=16306333
sum_num_deletes_by_thread=168248 171277 170579 170788 169185 168402 170053 170747 173454 171334 170308 167702 169504 170806 168417 169473 171433 165579 168394 168427 169931 172786 168773 165810 159555 169128 168490 169797 171518 165306 167589 170067 172009 173503 169406 169482 168027 170340 169253 171814 169934 169018 168096 169167 167568 170475 169400 167663 169825 169898 163699 170373 169534 169581 167566 170943 173765 173636 169124 170631 172442 172365 171031 168655 170804 171880 169109 171342 172646 168915 170542 170676 169582 169414 170046 173223 170294 170256 168836 166161 172706 173703 169417 170290 168563 171459 172135 169949 172817 164994 170652 170990 173626 171494 173614 171389
sum_num_deletes_total=16312607
sum_num_searches_by_thread=3025375 3074859 3073699 3083746 3044853 3011602 3054958 3073988 3111487 3077597 3065383 3011442 3051432 3071336 3025109 3056649 3083637 2988305 3026331 3021893 3071786 3103774 3045801 2997898 2870024 3043000 3032570 3054583 3069570 2969933 3009559 3053962 3096453 3101605 3050061 3047390 3020831 3073453 3052614 3103140 3059779 3034931 3014231 3049794 3018533 3077205 3046678 3024168 3059644 3052382 2967020 3065246 3052279 3053613 3019909 3072023 3107643 3123877 3048790 3066847 3102849 3097101 3081549 3046300 3081925 3089831 3046556 3074772 3102545 3041265 3068397 3074947 3053676 3051266 3062648 3123832 3062214 3071254 3043923 2987777 3107414 3122889 3057104 3073217 3045088 3088572 3090919 3058535 3103661 2971573 3078176 3078392 3124254 3095997 3108619 3089537
sum_num_searches_total=293574854
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3361892 3417561 3414753 3425978 3382338 3347572 3395019 3415743 3457310 3419872 3406031 3345971 3390415 3412912 3361648 3395413 3426532 3319741 3362636 3358704 3412692 3448988 3383632 3330386 3189139 3380728 3369653 3393919 3411699 3299523 3344060 3393452 3440432 3447809 3389670 3385904 3356666 3414206 3391009 3446541 3399575 3372456 3349782 3388710 3353837 3417932 3385449 3359369 3398847 3391573 3295691 3406235 3391600 3392342 3355293 3413926 3454374 3470922 3387408 3407944 3448062 3441920 3423138 3384396 3423887 3433673 3384832 3417197 3448006 3378411 3409128 3416377 3391677 3389765 3402481 3471227 3402336 3412259 3381949 3320015 3452797 3469863 3396540 3414460 3383072 3431107 3434311 3398574 3448593 3302107 3420791 3420280 3472082 3439369 3455142 3432556
average_num_operations_total=3397852
stdev_num_operations_total=4529
sum_num_operations_total=326193794
min_num_operations_total=3189139
max_num_operations_total=3472082
first_time_thread_terminate_by_thread=10000153 10000151 10000151 10000152 10000151 10000152 10000151 10000150 10000151 10000151 10000152 10000151 10000152 10000151 10000153 10000151 10000152 10000151 10000161 10000151 10000152 10000153 10000151 10000152 10000153 10000151 10000152 10000152 10000151 10000152 10000153 10000152 10000152 10000153 10000152 10000151 10000152 10000151 10000153 10000151 10000152 10000151 10000150 10000151 10000152 10000153 10000151 10000151 10000150 10000151 10000152 10000153 10000150 10000151 10000152 10000152 10000150 10000151 10000150 10000150 10000151 10000151 10000153 10000151 10000153 10000153 10000150 10000153 10000151 10000152 10000151 10000152 10000151 10000151 10000152 10000151 10000150 10000151 10000152 10000151 10000150 10000153 10000150 10000151 10000151 10000150 10000153 10000151 10000153 10000153 10000150 10000153 10000151 10000152 10000151 10000152
min_time_thread_terminate_total=10000150
max_time_thread_terminate_total=10000161
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 4 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 4 3 3 4 3 3 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 3 3 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=999521327641
threads_final_size=1000027
final_keysum=999521327641
final_size=1000027
validate_result=success
Validation OK.
Structural validation OK.

total_find=293574854
total_rq=0
total_inserts=16306333
total_deletes=16312607
total_updates=32618940
total_queries=293574854
total_ops=326193794
find_throughput=29357485
rq_throughput=0
update_throughput=3261894
query_throughput=29357485
total_throughput=32619379


total find                    : 293574854
total rq                      : 0
total inserts                 : 16306333
total deletes                 : 16312607
total updates                 : 32618940
total queries                 : 293574854
total ops                     : 326193794
find throughput               : 29357485
rq throughput                 : 0
update throughput             : 3261894
query throughput              : 29357485
total throughput              : 32619379

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=14.0124
PAPI_L3_TCM=2.40049
PAPI_TOT_CYC=8061.04
PAPI_TOT_INS=8144.37
garbage=145213535677297
total_execution_walltime=12.398s
REALTIME_MAIN_END_PERF_FORMAT=6998191.107308839
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 334645548
[slow htm]
[fallback]
total fallback commit         : 185154
seconds global lock is held   : 7.64199
[time_cmd_output] time_elapsed_sec=12.57, faults_major=0, faults_minor=332675, mem_maxresident_kb=845780, user_cputime=1177.69, sys_cputime=10.46, percent_cpu=9445%
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=1