__step=000025
__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.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=data000025.txt
__path_data=data/data000025.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992159.180131180
binary=./brown_int_bst_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_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


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.009s
prefilling_size=5199020 prefilling_elapsed_millis=1.001s
prefilling_size=5515796 prefilling_elapsed_millis=1.101s
prefilling_size=5810228 prefilling_elapsed_millis=1.201s
prefilling_size=6083905 prefilling_elapsed_millis=1.301s
prefilling_size=6340123 prefilling_elapsed_millis=1.402s
prefilling_size=6578229 prefilling_elapsed_millis=1.502s
prefilling_size=6800230 prefilling_elapsed_millis=1.602s
prefilling_size=7007725 prefilling_elapsed_millis=1.702s
prefilling_size=7202098 prefilling_elapsed_millis=1.802s
prefilling_size=7382264 prefilling_elapsed_millis=1.903s
prefilling_size=7551137 prefilling_elapsed_millis=2.003s
prefilling_size=7708847 prefilling_elapsed_millis=2.103s
prefilling_size=7855715 prefilling_elapsed_millis=2.203s
prefilling_size=7988017 prefilling_elapsed_millis=2.303s
prefilling_size=8113234 prefilling_elapsed_millis=2.404s
prefilling_size=8230593 prefilling_elapsed_millis=2.504s
prefilling_size=8341818 prefilling_elapsed_millis=2.604s
prefilling_size=8445592 prefilling_elapsed_millis=2.704s
prefilling_size=8542660 prefilling_elapsed_millis=2.804s
prefilling_size=8632616 prefilling_elapsed_millis=2.905s
prefilling_size=8718133 prefilling_elapsed_millis=3.005s
prefilling_size=8797921 prefilling_elapsed_millis=3.105s
prefilling_size=8873217 prefilling_elapsed_millis=3.205s
prefilling_size=8942845 prefilling_elapsed_millis=3.305s
prefilling_size=9007991 prefilling_elapsed_millis=3.406s
prefilling_size=9069536 prefilling_elapsed_millis=3.506s
prefilling_size=9128115 prefilling_elapsed_millis=3.606s
prefilling_size=9181464 prefilling_elapsed_millis=3.706s
prefilling_size=9232151 prefilling_elapsed_millis=3.806s
prefilling_size=9279292 prefilling_elapsed_millis=3.906s
prefilling_size=9324095 prefilling_elapsed_millis=4.007s
prefilling_size=9366299 prefilling_elapsed_millis=4.107s
prefilling_size=9406200 prefilling_elapsed_millis=4.207s
prefilling_size=9443165 prefilling_elapsed_millis=4.307s
prefilling_size=9478431 prefilling_elapsed_millis=4.408s
prefilling_size=9511102 prefilling_elapsed_millis=4.508s
prefilling_size=9540700 prefilling_elapsed_millis=4.608s
prefilling_size=9569323 prefilling_elapsed_millis=4.708s
prefilling_size=9596566 prefilling_elapsed_millis=4.808s
prefilling_size=9621351 prefilling_elapsed_millis=4.909s
prefilling_size=9644303 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 355683 keys with 48 threads...
tid=0 thread_prefill_amount=187470 percent_done_estimate=2529.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=191636 percent_done_estimate=2586.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0677195s
prefilling_size=10000000 prefilling_elapsed_millis=0.067s
finished prefilling to size 10000000 for expected size 10000000 keysum=99995081652084, performing 68221281 updates; total_prefilling_elapsed_ms=67 ms)
pref_size=10000000
pref_millis=67
prefill_elapsed_ms=68
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=3104

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992164.548159526
REALTIME_END_PERF_FORMAT=6992174.548238872

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

tree_stats_computeWalltime=0.121s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 126 250 485 933 1767 3286 5950 10664 18388 30778 49295 75558 110883 155632 209580 269667 332646 393210 445784 485964 508392 511573 494118 460609 413437 360257 302280 245339 192899 146646 108082 76785 52549 34623 21653 13099 7542 4301 2370 1184 567 246 110 42 18 6 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 2 1 8 23 54 119 369 646 1592 3260 6671 12383 21609 35568 54461 79613 109892 144412 180556 214136 244365 265720 277875 276700 265742 243064 218352 187011 154617 124354 95828 72166 52289 36446 24646 15535 9601 5566 3178 1842 927 460 194 94 26 16 6
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 251 493 956 1821 3405 6319 11310 19980 34038 55966 87941 132492 191200 264041 349280 442538 537622 626340 700100 752757 777293 771993 737309 679179 603321 520632 432350 347516 271000 203910 148951 104838 71069 46299 28634 17143 9867 5548 3026 1494 706 304 136 44 22 6
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 251 493 956 1821 3405 6319 11310 19980 34038 55966 87941 132492 191200 264041 349280 442538 537622 626340 700100 752757 777293 771993 737309 679179 603321 520632 432350 347516 271000 203910 148951 104838 71069 46299 28634 17143 9867 5548 3026 1494 706 304 136 44 22 6
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.97656 1.96813 1.95538 1.92887 1.89951 1.89075 1.84824 1.8237 1.78328 1.74 1.69053 1.64741 1.6062 1.56699 1.52908 1.49494 1.46318 1.43363 1.40604 1.38108 1.35722 1.33503 1.31502 1.29644 1.27958 1.26582 1.24983 1.23633 1.22474 1.21131 1.20043 1.18834 1.17665 1.16429 1.15078 1.14123 1.13562 1.12638 1.11824 1.10245 1.09304 1.08215 1.08553 1.01471 1.09091 1 1

tree_stats_height=54
tree_stats_numInternals=6559700
tree_stats_numLeaves=3441995
tree_stats_numNodes=10001695
tree_stats_numKeys=10001695

tree_stats_avgDegreeInternal=1.52472
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34414
tree_stats_avgKeyDepth=28.5845

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=10535


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:39 2:1047 4:38063 8:275157 16:42273 32:1
    [2^00, 2^01]: 39
    (2^01, 2^02]: 1047
    (2^02, 2^03]: 38063
    (2^03, 2^04]: 275157
    (2^04, 2^05]: 42273
    (2^05, 2^06]: 1
sum_limbo_reclamation_event_size_total=4103036
count_limbo_reclamation_event_size_by_thread=7429 7428 7429 7429 7429 7429 7429 7429 7428 7429 7429 7429 7429 7429 7429 7429 7429 7429 7429 7428 7429 7428 7428 7429 7428 7428 7429 7429 7429 7428 7429 7429 7429 7429 7428 7428 7429 7429 7428 7429 7429 7429 7428 7429 7429 7429 7429 7429
count_limbo_reclamation_event_size_total=356580
sum_limbo_reclamation_event_count_total=356732
first_thread_announced_epoch_by_thread=21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070 21070
sum_num_inserts_by_thread=167956 169693 171182 171122 171835 169566 169489 169429 173026 172596 171958 170508 170453 170809 171139 172732 173002 170331 168727 170144 171379 171968 171243 169989 168362 169825 171096 171615 171685 169187 169576 170952 172520 173236 171791 171348 168861 170329 171654 172085 172154 171338 169135 170353 171778 173052 171937 171042
sum_num_inserts_total=8205187
sum_num_deletes_by_thread=168313 169767 171646 171366 171996 168785 169381 170449 172924 172982 171256 170835 169341 170416 171491 172278 171906 171072 169046 170631 171148 172209 171304 169453 169025 170324 171507 172135 171784 169477 169270 171227 172751 172718 171826 171248 169650 171064 171264 173499 172465 171093 168266 170156 171577 173002 171565 170124
sum_num_deletes_total=8207012
sum_num_searches_by_thread=3030219 3055856 3080049 3087240 3092532 3044954 3052221 3071512 3108271 3113884 3095476 3075459 3057447 3074578 3087204 3097488 3098649 3075765 3039412 3071009 3088714 3105715 3086721 3062912 3036156 3061903 3087815 3089800 3091440 3052724 3050237 3073235 3108092 3112716 3096404 3077267 3054663 3076355 3093304 3107650 3102492 3079611 3041881 3065832 3091517 3110249 3091127 3063886
sum_num_searches_total=147769643
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3366488 3395316 3422877 3429728 3436363 3383305 3391091 3411390 3454221 3459462 3438690 3416802 3397241 3415803 3429834 3442498 3443557 3417168 3377185 3411784 3431241 3449892 3429268 3402354 3373543 3402052 3430418 3433550 3434909 3391388 3389083 3415414 3453363 3458670 3440021 3419863 3393174 3417748 3436222 3453234 3447111 3422042 3379282 3406341 3434872 3456303 3434629 3405052
average_num_operations_total=3420455
stdev_num_operations_total=3601
sum_num_operations_total=164181842
min_num_operations_total=3366488
max_num_operations_total=3459462
first_time_thread_terminate_by_thread=10000135 10000079 10000079 10000080 10000080 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000080 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000080 10000079 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000135
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=100023131214149
threads_final_size=10001695
final_keysum=100023131214149
final_size=10001695
validate_result=success
Validation OK.
Structural validation OK.

total_find=147769643
total_rq=0
total_inserts=8205187
total_deletes=8207012
total_updates=16412199
total_queries=147769643
total_ops=164181842
find_throughput=14776964
rq_throughput=0
update_throughput=1641219
query_throughput=14776964
total_throughput=16418184


total find                    : 147769643
total rq                      : 0
total inserts                 : 8205187
total deletes                 : 8207012
total updates                 : 16412199
total queries                 : 147769643
total ops                     : 164181842
find throughput               : 14776964
rq throughput                 : 0
update throughput             : 1641219
query throughput              : 14776964
total throughput              : 16418184

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=41.0294
PAPI_L3_TCM=6.11584
PAPI_TOT_CYC=6634.9
PAPI_TOT_INS=2743.97
garbage=738231282029229
total_execution_walltime=15.645s
REALTIME_MAIN_END_PERF_FORMAT=6992174.826426646
TL2 system shutdown:
  GCLOCK=0x3764A7E Starts=231915652 Aborts=196902
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.79, faults_major=0, faults_minor=239341, mem_maxresident_kb=635280, user_cputime=692.36, sys_cputime=47.60, percent_cpu=4684%
timeout=false
algorithm=brown_int_bst_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=1