__step=000073
__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 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=data000073.txt
__path_data=data/data000073.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993838.596132520
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=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


HyTM1 system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=2481800 prefilling_elapsed_millis=1.001s
prefilling_size=2686153 prefilling_elapsed_millis=1.101s
prefilling_size=2885840 prefilling_elapsed_millis=1.201s
prefilling_size=3079714 prefilling_elapsed_millis=1.301s
prefilling_size=3269464 prefilling_elapsed_millis=1.402s
prefilling_size=3455390 prefilling_elapsed_millis=1.502s
prefilling_size=3636894 prefilling_elapsed_millis=1.602s
prefilling_size=3861986 prefilling_elapsed_millis=1.702s
prefilling_size=4034006 prefilling_elapsed_millis=1.802s
prefilling_size=4201040 prefilling_elapsed_millis=1.903s
prefilling_size=4364883 prefilling_elapsed_millis=2.003s
prefilling_size=4524697 prefilling_elapsed_millis=2.103s
prefilling_size=4680963 prefilling_elapsed_millis=2.203s
prefilling_size=4833447 prefilling_elapsed_millis=2.304s
prefilling_size=4982402 prefilling_elapsed_millis=2.404s
prefilling_size=5127597 prefilling_elapsed_millis=2.504s
prefilling_size=5268923 prefilling_elapsed_millis=2.604s
prefilling_size=5407346 prefilling_elapsed_millis=2.704s
prefilling_size=5542199 prefilling_elapsed_millis=2.805s
prefilling_size=5674330 prefilling_elapsed_millis=2.905s
prefilling_size=5802115 prefilling_elapsed_millis=3.005s
prefilling_size=5927108 prefilling_elapsed_millis=3.105s
prefilling_size=6047425 prefilling_elapsed_millis=3.205s
prefilling_size=6165268 prefilling_elapsed_millis=3.306s
prefilling_size=6279972 prefilling_elapsed_millis=3.406s
prefilling_size=6392543 prefilling_elapsed_millis=3.506s
prefilling_size=6508648 prefilling_elapsed_millis=3.606s
prefilling_size=6654376 prefilling_elapsed_millis=3.706s
prefilling_size=6767224 prefilling_elapsed_millis=3.807s
prefilling_size=6867357 prefilling_elapsed_millis=3.907s
prefilling_size=6962738 prefilling_elapsed_millis=4.007s
prefilling_size=7056916 prefilling_elapsed_millis=4.107s
prefilling_size=7147933 prefilling_elapsed_millis=4.207s
prefilling_size=7236936 prefilling_elapsed_millis=4.308s
prefilling_size=7324103 prefilling_elapsed_millis=4.408s
prefilling_size=7408685 prefilling_elapsed_millis=4.508s
prefilling_size=7491054 prefilling_elapsed_millis=4.608s
prefilling_size=7570468 prefilling_elapsed_millis=4.708s
prefilling_size=7647388 prefilling_elapsed_millis=4.809s
prefilling_size=7722248 prefilling_elapsed_millis=4.909s
prefilling_size=7794672 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 2205270 keys with 48 threads...
tid=0 thread_prefill_amount=154142 percent_done_estimate=335.5 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=158308 percent_done_estimate=344.6 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=162474 percent_done_estimate=353.6 elapsed_s=5 est_remaining_s=-8 / -3
tid=0 thread_prefill_amount=166640 percent_done_estimate=362.7 elapsed_s=5 est_remaining_s=-9 / -3
tid=0 thread_prefill_amount=170806 percent_done_estimate=371.8 elapsed_s=5 est_remaining_s=-9 / -3
tid=0 thread_prefill_amount=174972 percent_done_estimate=380.8 elapsed_s=5 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=179138 percent_done_estimate=389.9 elapsed_s=6 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=183304 percent_done_estimate=399.0 elapsed_s=6 est_remaining_s=-9 / -4
tid=0 thread_prefill_amount=187470 percent_done_estimate=408.0 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=191636 percent_done_estimate=417.1 elapsed_s=6 est_remaining_s=-10 / -4
tid=0 thread_prefill_amount=195802 percent_done_estimate=426.2 elapsed_s=6 est_remaining_s=-10 / -4
timing_elapsed 0.805016s
prefilling_size=10000000 prefilling_elapsed_millis=0.807s
finished prefilling to size 10000000 for expected size 10000000 keysum=100000265234515, performing 34390175 updates; total_prefilling_elapsed_ms=807 ms)
pref_size=10000000
pref_millis=807
prefill_elapsed_ms=807
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6993844.684161955
REALTIME_END_PERF_FORMAT=6993854.684241374

###############################################################################
################################## 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.046s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 261971 517917 959156 1440284 1414598 721100 150335 8772 31 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 162 5417 65653 391905 1143404 1550918 894730 190999 10981 36
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523334 1024809 1832189 2558002 2272018 1045065 199771 11012 36
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523334 1024809 1832189 2558002 2272018 1045065 199771 11012 36
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99706 1.96858 1.8519 1.61005 1.33519 1.14259 1.0473 1.01121 1.00045 1

tree_stats_height=28
tree_stats_numInternals=5736307
tree_stats_numLeaves=4254205
tree_stats_numNodes=9990512
tree_stats_numKeys=9990512

tree_stats_avgDegreeInternal=1.74163
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42582
tree_stats_avgKeyDepth=21.693


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=417551
first_thread_announced_epoch_by_thread=20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642 20642
sum_num_inserts_by_thread=198160 198930 199921 199592 197819 198033 198969 198603 201242 201620 199683 198445 198350 200571 200219 201184 200168 197168 197809 197928 198682 200136 199064 197665 198435 197788 201078 201022 200103 197623 198416 199368 200689 201387 199038 199420 199375 200577 200458 200796 200405 191325 197860 197984 200696 198573 198830 197494
sum_num_inserts_total=9558701
sum_num_deletes_by_thread=198456 199568 200035 200081 197305 198838 199025 200069 200917 201263 200363 198542 198655 200719 199550 201573 200260 196942 197100 198277 198931 200749 199314 197904 198961 198663 200156 200466 198882 198198 198626 200123 200837 201860 199977 198483 199489 199788 200367 200630 200274 191200 197157 197149 200159 198796 198934 197467
sum_num_deletes_total=9561078
sum_num_searches_by_thread=3566537 3587543 3606169 3598585 3557585 3565627 3577716 3594459 3621057 3623947 3595019 3568274 3575163 3603293 3591979 3621704 3604315 3545791 3562070 3567237 3585201 3616314 3580479 3558647 3570592 3584117 3614366 3606214 3594969 3566280 3567596 3587791 3628248 3631590 3589828 3578798 3586367 3605501 3616496 3617628 3601180 3450960 3565089 3548899 3604072 3566838 3585915 3557979
sum_num_searches_total=172102024
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3963153 3986041 4006125 3998258 3952709 3962498 3975710 3993131 4023216 4026830 3995065 3965261 3972168 4004583 3991748 4024461 4004743 3939901 3956979 3963442 3982814 4017199 3978857 3954216 3967988 3980568 4015600 4007702 3993954 3962101 3964638 3987282 4029774 4034837 3988843 3976701 3985231 4005866 4017321 4019054 4001859 3833485 3960106 3944032 4004927 3964207 3983679 3952940
average_num_operations_total=3983787
stdev_num_operations_total=4836
sum_num_operations_total=191221803
min_num_operations_total=3833485
max_num_operations_total=4034837
first_time_thread_terminate_by_thread=10000140 10000080 10000080 10000080 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000080 10000080 10000079 10000079 10000081 10000079 10000079 10000079 10000080 10000079 10000079 10000080 10000080 10000079 10000079 10000079 10000079 10000079 10000080 10000080 10000079 10000079 10000079 10000080 10000081 10000080 10000079 10000079 10000081 10000079 10000079 10000079 10000081 10000079 10000079 10000080
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000140
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99893334383225
threads_final_size=9990512
final_keysum=99893334383225
final_size=9990512
validate_result=success
Validation OK.
Structural validation OK.

total_find=172102024
total_rq=0
total_inserts=9558701
total_deletes=9561078
total_updates=19119779
total_queries=172102024
total_ops=191221803
find_throughput=17210202
rq_throughput=0
update_throughput=1911977
query_throughput=17210202
total_throughput=19122180


total find                    : 172102024
total rq                      : 0
total inserts                 : 9558701
total deletes                 : 9561078
total updates                 : 19119779
total queries                 : 172102024
total ops                     : 191221803
find throughput               : 17210202
rq throughput                 : 0
update throughput             : 1911977
query throughput              : 17210202
total throughput              : 19122180

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=21.5959
PAPI_L3_TCM=7.14859
PAPI_TOT_CYC=6941.76
PAPI_TOT_INS=2774.33
garbage=861092271654384
total_execution_walltime=16.284s
REALTIME_MAIN_END_PERF_FORMAT=6993854.881386825
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 225333313
[slow htm]
[fallback]
total fallback commit         : 278666
seconds global lock is held   : 5.69682
[time_cmd_output] time_elapsed_sec=16.49, faults_major=0, faults_minor=500996, mem_maxresident_kb=1198424, user_cputime=768.82, sys_cputime=5.44, percent_cpu=4695%
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