__step=000721
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./natarajan_ext_bst_lf.debra -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000721.txt
__path_data=data/data000721.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6987596.308633686
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=natarajan_ext_bst_lf
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=50
DEL_FRAC=50
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=50.0 50.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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.005s
prefilling_size=7238355 prefilling_elapsed_millis=1.001s
prefilling_size=7511150 prefilling_elapsed_millis=1.101s
prefilling_size=7753381 prefilling_elapsed_millis=1.201s
prefilling_size=7971068 prefilling_elapsed_millis=1.301s
prefilling_size=8165469 prefilling_elapsed_millis=1.402s
prefilling_size=8339885 prefilling_elapsed_millis=1.502s
prefilling_size=8495795 prefilling_elapsed_millis=1.602s
prefilling_size=8637190 prefilling_elapsed_millis=1.702s
prefilling_size=8763742 prefilling_elapsed_millis=1.802s
prefilling_size=8878267 prefilling_elapsed_millis=1.903s
prefilling_size=8981069 prefilling_elapsed_millis=2.003s
prefilling_size=9075529 prefilling_elapsed_millis=2.103s
prefilling_size=9159955 prefilling_elapsed_millis=2.203s
prefilling_size=9238028 prefilling_elapsed_millis=2.303s
prefilling_size=9307469 prefilling_elapsed_millis=2.404s
prefilling_size=9370959 prefilling_elapsed_millis=2.504s
prefilling_size=9425509 prefilling_elapsed_millis=2.604s
prefilling_size=9478429 prefilling_elapsed_millis=2.704s
prefilling_size=9525839 prefilling_elapsed_millis=2.804s
prefilling_size=9567654 prefilling_elapsed_millis=2.905s
prefilling_size=9606014 prefilling_elapsed_millis=3.005s
prefilling_size=9641404 prefilling_elapsed_millis=3.105s
prefilling_size=9673345 prefilling_elapsed_millis=3.205s
prefilling_size=9701158 prefilling_elapsed_millis=3.305s
prefilling_size=9728934 prefilling_elapsed_millis=3.405s
prefilling_size=9753070 prefilling_elapsed_millis=3.506s
prefilling_size=9773145 prefilling_elapsed_millis=3.606s
prefilling_size=9793345 prefilling_elapsed_millis=3.706s
prefilling_size=9810048 prefilling_elapsed_millis=3.806s
finished prefilling to size 9810100 for expected size 10000000 keysum=98095367554765, performing 79569545 updates; total_prefilling_elapsed_ms=3907 ms)
pref_size=9810100
pref_millis=3907
prefill_elapsed_ms=3927
6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=3656

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6987600.406330208
REALTIME_END_PERF_FORMAT=6987610.406477023

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

tree_stats_computeWalltime=0.219s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 253 506 1004 1971 3818 7244 13182 23197 39130 63262 98080 145350 206374 280251 363879 452979 539829 617734 679050 719089 734898 725519 692996 638753 571749 498560 423574 350850 283562 223611 172287 129569 95241 67898 47064 31945 21188 13799 8971 5742 3697 2368 1520 980 588 326 142 50 17 3 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 3 0 8 37 124 392 1306 3167 7264 14998 28444 50810 84326 132497 196623 274779 366129 461924 556418 639011 703280 744277 758042 747239 705757 644938 573546 496298 418138 343513 274935 215005 163897 122584 88732 62183 42702 28577 18627 12200 7787 5026 3216 2060 1372 850 510 234 83 31 5 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 506 1012 2008 3942 7636 14488 26364 46394 78260 126524 196160 290700 412748 560502 727758 905958 1079658 1235468 1358100 1438178 1469796 1451038 1385992 1277506 1143498 997120 847148 701700 567124 447222 344574 259138 190482 135796 94128 63890 42376 27598 17942 11484 7394 4736 3040 1960 1176 652 284 100 34 6 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 3 0 8 37 124 392 1306 3167 7264 14998 28444 50810 84326 132497 196623 274779 366129 461924 556418 639011 703280 744277 758042 747239 705757 644938 573546 496298 418138 343513 274935 215005 163897 122584 88732 62183 42702 28577 18627 12200 7787 5026 3216 2060 1372 850 510 234 83 31 5 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98828 2 1.99209 1.98157 1.96854 1.94866 1.90986 1.87987 1.84343 1.80836 1.77519 1.74098 1.70992 1.67899 1.6492 1.62243 1.59587 1.57216 1.54963 1.52948 1.51099 1.49362 1.47759 1.46086 1.44755 1.436 1.4248 1.41415 1.40411 1.39429 1.38524 1.37603 1.36753 1.35645 1.34658 1.33938 1.33163 1.32563 1.32506 1.32003 1.32193 1.32026 1.32095 1.32237 1.3 1.27721 1.21779 1.17606 1.17 1.08824 1.16667 1

tree_stats_height=60
tree_stats_numInternals=10003905
tree_stats_numLeaves=10003906
tree_stats_numNodes=20007811
tree_stats_numKeys=10003906

tree_stats_avgDegreeInternal=2
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.5
tree_stats_avgKeyDepth=30.7114

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16192 32384 64256 126144 244352 463616 843648 1484608 2504320 4048768 6277120 9302400 13207936 17936064 23288256 28990656 34549056 39534976 43459200 46021696 47033472 46433216 44351744 40880192 36591936 31907840 27108736 22454400 18147968 14311104 11026368 8292416 6095424 4345472 3012096 2044480 1356032 883136 574144 367488 236608 151552 97280 62720 37632 20864 9088 3200 1088 192 64
tree_stats_sizeInBytes=640249952

6node_tIxPvE_object_size=32
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=0
6node_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=12040


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:0 16:0 32:0 64:5 128:377535 256:24796
    (2^06, 2^07]: 5
    (2^07, 2^08]: 377535
    (2^08, 2^09]: 24796
sum_limbo_reclamation_event_size_total=90568696
count_limbo_reclamation_event_size_by_thread=8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382 8382
count_limbo_reclamation_event_size_total=402336
sum_limbo_reclamation_event_count_total=402432
first_thread_announced_epoch_by_thread=24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080 24080
sum_num_inserts_by_thread=1878293 1890012 1901196 1901939 1893126 1875828 1883587 1889789 1907512 1907075 1889179 1879342 1886211 1896584 1905550 1904731 1894480 1885672 1876751 1885471 1897799 1903793 1881961 1873378 1881923 1888207 1901178 1900639 1893021 1876408 1884476 1890775 1908460 1909697 1889214 1879819 1886885 1896401 1906968 1906459 1895262 1884289 1876427 1885100 1896554 1902483 1886579 1871610
sum_num_inserts_total=90788093
sum_num_deletes_by_thread=1878724 1889455 1898481 1900650 1895260 1877372 1882491 1891352 1909450 1907723 1889791 1882160 1887508 1895147 1905010 1906342 1897147 1886133 1875649 1886268 1895985 1902005 1885577 1872592 1878896 1887118 1899319 1900278 1893998 1877195 1884122 1890327 1907310 1910107 1889608 1879947 1890228 1894762 1905032 1905829 1896281 1884407 1876578 1885874 1896416 1901757 1882732 1871905
sum_num_deletes_total=90788298
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3757017 3779467 3799677 3802589 3788386 3753200 3766078 3781141 3816962 3814798 3778970 3761502 3773719 3791731 3810560 3811073 3791627 3771805 3752400 3771739 3793784 3805798 3767538 3745970 3760819 3775325 3800497 3800917 3787019 3753603 3768598 3781102 3815770 3819804 3778822 3759766 3777113 3791163 3812000 3812288 3791543 3768696 3753005 3770974 3792970 3804240 3769311 3743515
average_num_operations_total=3782841
stdev_num_operations_total=3089
sum_num_operations_total=181576391
min_num_operations_total=3743515
max_num_operations_total=3819804
first_time_thread_terminate_by_thread=10000146 10000146 10000146 10000146 10000146 10000147 10000146 10000146 10000145 10000146 10000148 10000146 10000147 10000146 10000148 10000146 10000146 10000146 10000146 10000147 10000147 10000146 10000147 10000146 10000146 10000147 10000146 10000146 10000147 10000146 10000146 10000146 10000147 10000146 10000146 10000146 10000146 10000147 10000146 10000146 10000146 10000146 10000146 10000147 10000147 10000146 10000146 10000147
min_time_thread_terminate_total=10000145
max_time_thread_terminate_total=10000148
first_time_thread_start_by_thread=3 3 3 2 3 3 3 3 2 3 3 3 3 2 2 2 2 3 2 3 2 3 3 3 3 3 3 2 3 3 3 3 2 3 3 3 3 2 2 2 2 3 2 3 2 3 3 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=100036347957154
threads_final_size=10003906
final_keysum=100036347957154
final_size=10003906
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=90788093
total_deletes=90788298
total_updates=181576391
total_queries=0
total_ops=181576391
find_throughput=0
rq_throughput=0
update_throughput=18157639
query_throughput=0
total_throughput=18157639


total find                    : 0
total rq                      : 0
total inserts                 : 90788093
total deletes                 : 90788298
total updates                 : 181576391
total queries                 : 0
total ops                     : 181576391
find throughput               : 0
rq throughput                 : 0
update throughput             : 18157639
query throughput              : 0
total throughput              : 18157639

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=30.6371
PAPI_L3_TCM=11.0288
PAPI_TOT_CYC=7369.46
PAPI_TOT_INS=1114.22
garbage=0
total_execution_walltime=14.418s
REALTIME_MAIN_END_PERF_FORMAT=6987610.728059432
[time_cmd_output] time_elapsed_sec=14.59, faults_major=0, faults_minor=236852, mem_maxresident_kb=949396, user_cputime=696.67, sys_cputime=3.26, percent_cpu=4796%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1