__step=000050
__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 0.5 0.5 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000050.txt
__path_data=data/data000050.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979358.590488616
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=0.5
DEL_FRAC=0.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=0.5 0.5
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=7166456 prefilling_elapsed_millis=1.001s
prefilling_size=7435307 prefilling_elapsed_millis=1.101s
prefilling_size=7674515 prefilling_elapsed_millis=1.201s
prefilling_size=7888735 prefilling_elapsed_millis=1.301s
prefilling_size=8081196 prefilling_elapsed_millis=1.402s
prefilling_size=8254562 prefilling_elapsed_millis=1.502s
prefilling_size=8410759 prefilling_elapsed_millis=1.602s
prefilling_size=8552682 prefilling_elapsed_millis=1.702s
prefilling_size=8680307 prefilling_elapsed_millis=1.802s
prefilling_size=8796675 prefilling_elapsed_millis=1.903s
prefilling_size=8900990 prefilling_elapsed_millis=2.003s
prefilling_size=8995277 prefilling_elapsed_millis=2.103s
prefilling_size=9080482 prefilling_elapsed_millis=2.203s
prefilling_size=9157391 prefilling_elapsed_millis=2.303s
prefilling_size=9229021 prefilling_elapsed_millis=2.404s
prefilling_size=9294466 prefilling_elapsed_millis=2.504s
prefilling_size=9352905 prefilling_elapsed_millis=2.604s
prefilling_size=9406887 prefilling_elapsed_millis=2.704s
prefilling_size=9457931 prefilling_elapsed_millis=2.804s
prefilling_size=9503457 prefilling_elapsed_millis=2.905s
prefilling_size=9545744 prefilling_elapsed_millis=3.005s
prefilling_size=9581171 prefilling_elapsed_millis=3.105s
prefilling_size=9614521 prefilling_elapsed_millis=3.205s
prefilling_size=9646437 prefilling_elapsed_millis=3.305s
prefilling_size=9676836 prefilling_elapsed_millis=3.406s
prefilling_size=9702206 prefilling_elapsed_millis=3.506s
prefilling_size=9728740 prefilling_elapsed_millis=3.606s
prefilling_size=9748865 prefilling_elapsed_millis=3.706s
prefilling_size=9769384 prefilling_elapsed_millis=3.806s
prefilling_size=9788318 prefilling_elapsed_millis=3.907s
prefilling_size=9805369 prefilling_elapsed_millis=4.007s
finished prefilling to size 9805392 for expected size 10000000 keysum=98066997336721, performing 83002606 updates; total_prefilling_elapsed_ms=4108 ms)
pref_size=9805392
pref_millis=4108
prefill_elapsed_ms=4125
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=3814

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979362.894282525
REALTIME_END_PERF_FORMAT=6979372.894415048

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

tree_stats_computeWalltime=0.106s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 254 508 1003 1973 3804 7185 13173 23389 40004 65715 103171 153639 217985 295660 383586 474958 564988 645494 709608 751633 764944 749605 708143 643940 565846 478396 391484 308942 235118 172766 122605 83516 55162 35159 21607 12846 7349 4126 2285 1199 594 280 134 67 24 3 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 0 0 13 33 142 423 1197 2957 6774 14293 28259 52703 89293 140310 207734 292214 384928 484482 581380 667583 738322 780283 791067 772346 722034 653296 565308 474026 382766 297470 222927 161694 111870 75165 48711 30368 18343 10572 5967 3371 1804 908 426 201 110 45 5 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 508 1016 2006 3946 7608 14370 26346 46778 80008 131430 206342 307278 435970 591320 767172 949916 1129976 1290988 1419216 1503266 1529888 1499210 1416286 1287880 1131692 956792 782968 617884 470236 345532 245210 167032 110324 70318 43214 25692 14698 8252 4570 2398 1188 560 268 134 48 6 2 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1 0 0 13 33 142 423 1197 2957 6774 14293 28259 52703 89293 140310 207734 292214 384928 484482 581380 667583 738322 780283 791067 772346 722034 653296 565308 474026 382766 297470 222927 161694 111870 75165 48711 30368 18343 10572 5967 3371 1804 908 426 201 110 45 5 1 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 2 2 1.9872 1.98355 1.96401 1.9444 1.9167 1.88776 1.85519 1.82136 1.78499 1.74458 1.70941 1.67817 1.64869 1.6191 1.59478 1.57125 1.54966 1.52961 1.50885 1.48997 1.47234 1.45467 1.43936 1.42273 1.40916 1.39458 1.38052 1.3674 1.35483 1.34059 1.33025 1.31869 1.30728 1.29726 1.28604 1.28072 1.2769 1.26236 1.24771 1.23569 1.23929 1.25 1.1791 1.0625 1.16667 1.5 1

tree_stats_height=57
tree_stats_numInternals=9824126
tree_stats_numLeaves=9824127
tree_stats_numNodes=19648253
tree_stats_numKeys=9824127

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

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8128 16256 32512 64192 126272 243456 459840 843072 1496896 2560256 4205760 6602944 9832896 13951040 18922240 24549504 30397312 36159232 41311616 45414912 48104512 48956416 47974720 45321152 41212160 36214144 30617344 25054976 19772288 15047552 11057024 7846720 5345024 3530368 2250176 1382848 822144 470336 264064 146240 76736 38016 17920 8576 4288 1536 192 64 64
tree_stats_sizeInBytes=628744096

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:169638 4:132248 8:12930 16:14
    (2^01, 2^02]: 169638
    (2^02, 2^03]: 132248
    (2^03, 2^04]: 12930
    (2^04, 2^05]: 14
sum_limbo_reclamation_event_size_total=1050680
count_limbo_reclamation_event_size_by_thread=6565 6581 6543 6605 6588 6497 6497 6589 6617 6699 6464 6572 6598 6528 6544 6618 6514 6487 6481 6519 6647 6615 6562 6513 6487 6530 6682 6504 6464 6491 6516 6538 6692 6543 6574 6500 6571 6631 6657 6565 6612 6600 6538 6459 6493 6628 6632 6480
count_limbo_reclamation_event_size_total=314830
sum_limbo_reclamation_event_count_total=464400
first_thread_announced_epoch_by_thread=26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978 26978
sum_num_inserts_by_thread=22005 22350 22497 22172 22551 21975 22237 22299 22483 22475 22432 22151 21745 22242 22445 22400 22388 22154 22105 22102 22660 22484 22042 22354 21850 22044 22498 22416 22263 22041 22003 22457 22253 22591 22226 22017 22006 22346 22317 22526 22455 22334 22209 22047 22477 22376 22328 22170
sum_num_inserts_total=1068998
sum_num_deletes_by_thread=22213 22256 22483 22423 22234 21986 22099 22119 22591 22495 22285 22070 22168 22499 22426 22570 22510 22113 21888 22080 22583 22390 22264 21968 22226 22280 22315 22353 22299 22075 21990 22403 22455 22420 22229 22057 22072 22396 22389 22366 22383 22299 22114 21914 22389 22266 22050 22166
sum_num_deletes_total=1068619
sum_num_searches_by_thread=4363994 4407160 4440564 4423523 4410734 4381007 4399836 4401601 4446565 4452949 4423941 4385876 4386769 4424708 4454188 4438814 4439422 4397300 4382160 4389830 4416772 4436097 4394593 4381562 4382114 4408782 4426648 4439196 4428363 4364382 4386165 4421024 4451181 4456163 4410956 4402961 4392389 4411375 4443503 4442785 4423473 4394847 4380751 4405062 4432713 4436255 4393453 4382637
sum_num_searches_total=211797143
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4408212 4451766 4485544 4468118 4455519 4424968 4444172 4446019 4491639 4497919 4468658 4430097 4430682 4469449 4499059 4483784 4484320 4441567 4426153 4434012 4462015 4480971 4438899 4425884 4426190 4453106 4471461 4483965 4472925 4408498 4430158 4465884 4495889 4501174 4455411 4447035 4436467 4456117 4488209 4487677 4468311 4439480 4425074 4449023 4477579 4480897 4437831 4426973
average_num_operations_total=4456974
stdev_num_operations_total=3750
sum_num_operations_total=213934760
min_num_operations_total=4408212
max_num_operations_total=4501174
first_time_thread_terminate_by_thread=10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133 10000133
min_time_thread_terminate_total=10000133
max_time_thread_terminate_total=10000133
first_time_thread_start_by_thread=3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 4 3 4 3 3 4 3 3 3 3 3 4 3 3 3 3 3 3 3 3 3 3 4 3 4 3 4 3 3 4 3 3
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=98251258595482
threads_final_size=9824127
final_keysum=98251258595482
final_size=9824127
validate_result=success
Validation OK.
Structural validation OK.

total_find=211797143
total_rq=0
total_inserts=1068998
total_deletes=1068619
total_updates=2137617
total_queries=211797143
total_ops=213934760
find_throughput=21179714
rq_throughput=0
update_throughput=213761
query_throughput=21179714
total_throughput=21393476


total find                    : 211797143
total rq                      : 0
total inserts                 : 1068998
total deletes                 : 1068619
total updates                 : 2137617
total queries                 : 211797143
total ops                     : 213934760
find throughput               : 21179714
rq throughput                 : 0
update throughput             : 213761
query throughput              : 21179714
total throughput              : 21393476

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=24.9669
PAPI_L3_TCM=8.96627
PAPI_TOT_CYC=6256.36
PAPI_TOT_INS=361.003
garbage=1041397553411136
total_execution_walltime=14.496s
REALTIME_MAIN_END_PERF_FORMAT=6979373.108556872
[time_cmd_output] time_elapsed_sec=14.71, faults_major=0, faults_minor=232889, mem_maxresident_kb=933712, user_cputime=710.34, sys_cputime=3.04, percent_cpu=4847%
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=2