__step=000033
__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_int_bst_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=data000033.txt
__path_data=data/data000033.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992286.664023126
binary=./brown_int_bst_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_int_bst_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=32
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.005s
prefilling_size=3447252 prefilling_elapsed_millis=1.001s
prefilling_size=3735665 prefilling_elapsed_millis=1.101s
prefilling_size=4016776 prefilling_elapsed_millis=1.201s
prefilling_size=4289471 prefilling_elapsed_millis=1.301s
prefilling_size=4557472 prefilling_elapsed_millis=1.402s
prefilling_size=4806077 prefilling_elapsed_millis=1.502s
prefilling_size=5046423 prefilling_elapsed_millis=1.602s
prefilling_size=5295449 prefilling_elapsed_millis=1.702s
prefilling_size=5521935 prefilling_elapsed_millis=1.802s
prefilling_size=5737843 prefilling_elapsed_millis=1.903s
prefilling_size=5949663 prefilling_elapsed_millis=2.003s
prefilling_size=6155963 prefilling_elapsed_millis=2.103s
prefilling_size=6356012 prefilling_elapsed_millis=2.203s
prefilling_size=6550918 prefilling_elapsed_millis=2.303s
prefilling_size=6739985 prefilling_elapsed_millis=2.404s
prefilling_size=6923812 prefilling_elapsed_millis=2.504s
prefilling_size=7101431 prefilling_elapsed_millis=2.604s
prefilling_size=7274684 prefilling_elapsed_millis=2.704s
prefilling_size=7440156 prefilling_elapsed_millis=2.804s
prefilling_size=7599686 prefilling_elapsed_millis=2.905s
prefilling_size=7752598 prefilling_elapsed_millis=3.005s
prefilling_size=7900501 prefilling_elapsed_millis=3.105s
prefilling_size=8041069 prefilling_elapsed_millis=3.205s
prefilling_size=8175984 prefilling_elapsed_millis=3.305s
prefilling_size=8303497 prefilling_elapsed_millis=3.406s
prefilling_size=8425264 prefilling_elapsed_millis=3.506s
prefilling_size=8539962 prefilling_elapsed_millis=3.606s
prefilling_size=8648444 prefilling_elapsed_millis=3.706s
prefilling_size=8750550 prefilling_elapsed_millis=3.806s
prefilling_size=8846503 prefilling_elapsed_millis=3.907s
prefilling_size=8937463 prefilling_elapsed_millis=4.007s
prefilling_size=9023234 prefilling_elapsed_millis=4.107s
prefilling_size=9102869 prefilling_elapsed_millis=4.207s
prefilling_size=9176969 prefilling_elapsed_millis=4.307s
prefilling_size=9243628 prefilling_elapsed_millis=4.408s
prefilling_size=9306863 prefilling_elapsed_millis=4.508s
prefilling_size=9366463 prefilling_elapsed_millis=4.608s
prefilling_size=9420633 prefilling_elapsed_millis=4.708s
prefilling_size=9471853 prefilling_elapsed_millis=4.808s
prefilling_size=9518917 prefilling_elapsed_millis=4.909s
prefilling_size=9562088 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 437902 keys with 48 threads...
tid=0 thread_prefill_amount=187470 percent_done_estimate=2054.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=191636 percent_done_estimate=2100.6 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.144017s
prefilling_size=10000000 prefilling_elapsed_millis=0.144s
finished prefilling to size 10000000 for expected size 10000000 keysum=100001213883483, performing 64279717 updates; total_prefilling_elapsed_ms=144 ms)
pref_size=10000000
pref_millis=144
prefill_elapsed_ms=144
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=3005

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992292.68158313
REALTIME_END_PERF_FORMAT=6992302.68237261

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

tree_stats_computeWalltime=0.128s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 504 987 1893 3513 6299 10710 17319 27142 40910 59668 84373 115860 155533 203944 259804 320889 382292 437938 482803 511746 518966 505866 473649 425466 367138 304155 242032 184752 135785 96344 66283 43556 28315 17669 10567 6123 3435 1905 1019 544 267 112 56 22 10 5 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 13 50 158 393 1034 2238 4134 7183 11920 18590 28301 40724 57108 78929 105656 137908 172983 208494 239380 265981 279523 281968 273431 252683 224127 190700 155428 121257 90967 65338 46148 30311 20031 12809 7745 4513 2543 1404 769 437 224 84 45 17 7 6
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 506 1000 1943 3671 6692 11744 19557 31276 48093 71588 102963 144161 196257 261052 338733 426545 520200 610921 691297 751126 784947 785389 755617 698897 619821 528282 432732 340180 257042 187311 131621 89704 58626 37700 23376 13868 7948 4448 2423 1313 704 336 140 67 27 12 6
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 254 506 1000 1943 3671 6692 11744 19557 31276 48093 71588 102963 144161 196257 261052 338733 426545 520200 610921 691297 751126 784947 785389 755617 698897 619821 528282 432732 340180 257042 187311 131621 89704 58626 37700 23376 13868 7948 4448 2423 1313 704 336 140 67 27 12 6
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.98438 1.99213 1.98024 1.956 1.91508 1.86598 1.81366 1.75332 1.71366 1.66987 1.63789 1.60478 1.58067 1.55769 1.53766 1.51633 1.49225 1.46727 1.4395 1.41472 1.38814 1.36372 1.33942 1.318 1.2981 1.27809 1.25998 1.24339 1.22681 1.21251 1.20046 1.18833 1.17794 1.168 1.16008 1.15138 1.14121 1.1316 1.12745 1.11646 1.12134 1.12186 1.09801 1.08333 1.07857 1.07463 1.07407 1.08333 1

tree_stats_height=56
tree_stats_numInternals=6558677
tree_stats_numLeaves=3443694
tree_stats_numNodes=10002371
tree_stats_numKeys=10002371

tree_stats_avgDegreeInternal=1.52506
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34429
tree_stats_avgKeyDepth=29.416

4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=13488


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:58 2:1671 4:55208 8:369454 16:53424
    [2^00, 2^01]: 58
    (2^01, 2^02]: 1671
    (2^02, 2^03]: 55208
    (2^03, 2^04]: 369454
    (2^04, 2^05]: 53424
sum_limbo_reclamation_event_size_total=5456735
count_limbo_reclamation_event_size_by_thread=9997 9996 9996 9996 9996 9996 9996 9997 9996 9996 9997 9996 9997 9996 9996 9996 9998 9995 9996 9997 9996 9996 9996 9996 9997 9997 9996 9996 9996 9997 9996 9996 9996 9996 9995 9996 9996 9996 9995 9995 9996 9996 9997 9996 9996 9996 9996 9996
count_limbo_reclamation_event_size_total=479815
sum_limbo_reclamation_event_count_total=503223
first_thread_announced_epoch_by_thread=26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976 26976
sum_num_inserts_by_thread=236242 240094 242326 237905 241825 239809 239503 240660 238919 238528 226607 240160 236964 224400 233770 239401 239323 240934 240266 240451 239025 243415 238345 236258 223467 239840 238717 242315 241729 239513 237919 241119 242444 236332 236513 239701 235795 240464 239432 235961 241414 238334 232677 240356 242589 243325 240302 238630
sum_num_inserts_total=11444018
sum_num_deletes_by_thread=235804 239473 243011 237852 241165 239244 240498 240423 239287 237779 225824 239723 237676 225179 233664 239758 240153 240460 240207 239491 239791 242967 238994 236394 224340 240409 238146 240416 241985 238339 238152 240580 242159 234825 234449 239973 236467 240589 239590 234774 242789 237695 232425 241476 242525 243288 240212 238394
sum_num_deletes_total=11438814
sum_num_searches_by_thread=4257268 4321792 4380245 4284505 4355734 4315155 4316977 4334549 4311981 4293406 4072749 4311610 4277603 4045795 4209478 4313812 4323198 4329907 4322672 4318337 4313905 4366613 4291859 4256821 4025635 4308927 4285218 4364959 4350785 4300741 4279241 4336674 4362283 4256150 4236986 4311819 4252085 4322622 4303935 4237290 4357582 4293385 4177921 4343697 4357407 4379824 4329611 4291027
sum_num_searches_total=205991775
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4729314 4801359 4865582 4760262 4838724 4794208 4796978 4815632 4790187 4769713 4525180 4791493 4752243 4495374 4676912 4792971 4802674 4811301 4803145 4798279 4792721 4852995 4769198 4729473 4473442 4789176 4762081 4847690 4834499 4778593 4755312 4818373 4846886 4727307 4707948 4791493 4724347 4803675 4782957 4708025 4841785 4769414 4643023 4825529 4842521 4866437 4810125 4768051
average_num_operations_total=4768220
stdev_num_operations_total=12411
sum_num_operations_total=228874607
min_num_operations_total=4473442
max_num_operations_total=4866437
first_time_thread_terminate_by_thread=10000132 10000079 10000079 10000078 10000079 10000079 10000080 10000079 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000081 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000081 10000079 10000080 10000079 10000079 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000132
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=100033603791546
threads_final_size=10002371
final_keysum=100033603791546
final_size=10002371
validate_result=success
Validation OK.
Structural validation OK.

total_find=205991775
total_rq=0
total_inserts=11444018
total_deletes=11438814
total_updates=22882832
total_queries=205991775
total_ops=228874607
find_throughput=20599177
rq_throughput=0
update_throughput=2288283
query_throughput=20599177
total_throughput=22887460


total find                    : 205991775
total rq                      : 0
total inserts                 : 11444018
total deletes                 : 11438814
total updates                 : 22882832
total queries                 : 205991775
total ops                     : 228874607
find throughput               : 20599177
rq throughput                 : 0
update throughput             : 2288283
query throughput              : 20599177
total throughput              : 22887460

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=18.9507
PAPI_L3_TCM=5.8711
PAPI_TOT_CYC=5848.42
PAPI_TOT_INS=1561
garbage=1029978999657586
total_execution_walltime=15.706s
REALTIME_MAIN_END_PERF_FORMAT=6992302.371085297
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 292984869
[slow htm]
[fallback]
total fallback commit         : 169456
seconds global lock is held   : 2.2691
[time_cmd_output] time_elapsed_sec=15.84, faults_major=0, faults_minor=234239, mem_maxresident_kb=633960, user_cputime=740.02, sys_cputime=6.34, percent_cpu=4711%
timeout=false
algorithm=brown_int_bst_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