__step=000051
__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 96 -nprefill 96 -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=data000051.txt
__path_data=data/data000051.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979373.363081249
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.025s
prefilling_size=8688752 prefilling_elapsed_millis=1.001s
prefilling_size=8867009 prefilling_elapsed_millis=1.101s
prefilling_size=9019769 prefilling_elapsed_millis=1.201s
prefilling_size=9149140 prefilling_elapsed_millis=1.301s
prefilling_size=9259364 prefilling_elapsed_millis=1.402s
prefilling_size=9355125 prefilling_elapsed_millis=1.502s
prefilling_size=9435207 prefilling_elapsed_millis=1.602s
prefilling_size=9504683 prefilling_elapsed_millis=1.702s
prefilling_size=9567188 prefilling_elapsed_millis=1.802s
prefilling_size=9617360 prefilling_elapsed_millis=1.903s
prefilling_size=9663674 prefilling_elapsed_millis=2.003s
prefilling_size=9702951 prefilling_elapsed_millis=2.103s
prefilling_size=9739110 prefilling_elapsed_millis=2.203s
prefilling_size=9768291 prefilling_elapsed_millis=2.303s
prefilling_size=9796231 prefilling_elapsed_millis=2.404s
prefilling_size=9821367 prefilling_elapsed_millis=2.504s
finished prefilling to size 9821429 for expected size 10000000 keysum=98204117530257, performing 89154140 updates; total_prefilling_elapsed_ms=2606 ms)
pref_size=9821429
pref_millis=2606
prefill_elapsed_ms=2625
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=1150

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979376.176034098
REALTIME_END_PERF_FORMAT=6979386.176169521

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

tree_stats_computeWalltime=0.191s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1018 1990 3778 6971 12356 20955 34316 54529 83069 122090 172104 234927 308420 388939 472958 554279 624641 679500 712444 722215 708677 672508 617057 548166 471588 393834 318535 251473 192815 143437 104147 73541 51173 34915 22929 14686 9033 5441 3190 1836 999 568 314 161 55 22 10 1 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 6 46 202 585 1586 3757 7594 14103 25989 44048 72076 109281 161434 227901 304920 391637 483917 569782 646556 702673 735753 744846 727959 685948 624744 549342 469133 385597 310131 242193 182727 134753 95909 67431 46901 31172 20339 12625 7692 4544 2673 1430 822 467 267 88 34 19 0 3 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2036 3980 7556 13942 24712 41910 68632 109058 166138 244180 344208 469854 616840 777878 945916 1108558 1249282 1359000 1424888 1444430 1417354 1345016 1234114 1096332 943176 787668 637070 502946 385630 286874 208294 147082 102346 69830 45858 29372 18066 10882 6380 3672 1998 1136 628 322 110 44 20 2 4 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 6 46 202 585 1586 3757 7594 14103 25989 44048 72076 109281 161434 227901 304920 391637 483917 569782 646556 702673 735753 744846 727959 685948 624744 549342 469133 385597 310131 242193 182727 134753 95909 67431 46901 31172 20339 12625 7692 4544 2673 1430 822 467 267 88 34 19 0 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 1.99414 1.97741 1.94925 1.92258 1.88624 1.84797 1.8188 1.79451 1.7617 1.73487 1.70482 1.68251 1.65642 1.63053 1.60801 1.58597 1.56347 1.54391 1.52424 1.50686 1.49063 1.47448 1.45877 1.44418 1.43015 1.41756 1.4044 1.39473 1.38337 1.37195 1.36304 1.35306 1.34792 1.34115 1.32835 1.32025 1.30754 1.30117 1.29314 1.28777 1.27206 1.28428 1.27641 1.25637 1.17081 1.2 1.22727 1.05 2 1.25 1

tree_stats_height=61
tree_stats_numInternals=9853636
tree_stats_numLeaves=9853637
tree_stats_numNodes=19707273
tree_stats_numKeys=9853637

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

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32768 65152 127360 241792 446144 790784 1341120 2196224 3489856 5316416 7813760 11014656 15035328 19738880 24892096 30269312 35473856 39977024 43488000 45596416 46221760 45355328 43040512 39491648 35082624 30181632 25205376 20386240 16094272 12340160 9179968 6665408 4706624 3275072 2234560 1467456 939904 578112 348224 204160 117504 63936 36352 20096 10304 3520 1408 640 64 128 64
tree_stats_sizeInBytes=630632736

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:118704 4:242208 8:101371 16:1328
    (2^01, 2^02]: 118704
    (2^02, 2^03]: 242208
    (2^03, 2^04]: 101371
    (2^04, 2^05]: 1328
sum_limbo_reclamation_event_size_total=2366052
count_limbo_reclamation_event_size_by_thread=4828 4835 4875 4798 4869 4827 4784 4858 4837 4813 4851 4860 4836 4794 4864 4868 4802 4769 4820 4811 4807 4841 4868 4840 4847 4848 4840 4826 4843 4810 4805 4879 4844 4849 4836 4844 4845 4856 4835 4833 4856 4843 4842 4826 4840 4846 4826 4820 4850 4807 4842 4847 4822 4777 4830 4779 4885 4825 4871 4813 4812 4778 4812 4833 4829 4832 4820 4835 4837 4844 4841 4841 4840 4814 4798 4805 4793 4821 4833 4833 4820 4860 4811 4831 4802 4832 4825 4800 4862 4810 4784 4804 4861 4785 4813 4818
count_limbo_reclamation_event_size_total=463611
sum_limbo_reclamation_event_count_total=515616
first_thread_announced_epoch_by_thread=13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042 13042
sum_num_inserts_by_thread=24917 24860 25077 25444 25076 24927 25123 24920 25086 25587 24670 24807 24966 24914 24975 25290 24911 24803 24382 24961 25068 25342 24716 24556 24705 24830 25107 25293 24945 24717 24617 24855 25254 25258 24837 24859 24901 25117 25436 25373 25235 24993 24694 24755 25222 25223 24769 24378 24809 24825 25035 25041 24962 24902 24962 25288 25123 25340 24982 24979 24881 24861 25186 25242 25033 24480 24584 25084 25220 25246 24987 25003 24784 25186 25384 25045 24976 25084 24694 25160 25089 25155 24973 24691 24915 24562 25095 25307 24870 24889 25102 24799 25206 25177 24819 24659
sum_num_inserts_total=2398427
sum_num_deletes_by_thread=25042 25061 25238 25146 24996 24849 25044 25008 25392 25264 24941 24788 24736 25095 25502 25345 25156 24866 24795 24768 24931 25033 24788 24737 24725 24772 25111 25322 25250 24735 25033 24670 25077 25116 24775 24837 24954 25041 25202 25188 25242 24758 25016 24708 24709 25025 24695 24428 24890 24849 25297 25078 24697 24887 24800 24836 25277 25370 24994 24931 24756 24682 25519 25356 25234 24938 24992 24996 25052 25238 25078 24864 24816 24861 25106 25253 25043 24776 24847 25095 25002 25196 25110 25101 24982 24875 25236 25044 25268 24539 24677 24457 25247 25010 24923 24491
sum_num_deletes_total=2398476
sum_num_searches_by_thread=4892867 4938206 4972496 4985248 4948773 4902412 4935536 4940101 4998777 5008843 4943129 4911782 4932137 4968642 4991636 5002151 4971568 4931522 4888874 4934521 4972868 4988934 4913790 4870104 4896408 4920663 4968736 4982938 4959465 4886185 4934831 4937208 4994269 4991377 4935015 4903894 4923308 4957648 5001648 5000427 4971984 4916152 4888210 4917044 4952393 4977852 4914524 4870960 4913808 4956524 4983899 4973764 4927930 4918823 4913170 4954131 5005021 5011495 4957522 4917678 4927959 4958977 4997467 4986585 4965854 4892059 4898823 4910348 4978779 4991602 4938353 4900804 4914748 4955911 4999570 4995516 4934114 4909635 4917163 4935371 5017806 5019252 4968146 4913699 4921474 4956553 4981402 5002067 4951011 4900358 4893128 4904719 4976632 4994248 4960726 4904714
sum_num_searches_total=474957394
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4942826 4988127 5022811 5035838 4998845 4952188 4985703 4990029 5049255 5059694 4992740 4961377 4981839 5018651 5042113 5052786 5021635 4981191 4938051 4984250 5022867 5039309 4963294 4919397 4945838 4970265 5018954 5033553 5009660 4935637 4984481 4986733 5044600 5041751 4984627 4953590 4973163 5007806 5052286 5050988 5022461 4965903 4937920 4966507 5002324 5028100 4963988 4919766 4963507 5006198 5034231 5023883 4977589 4968612 4962932 5004255 5055421 5062205 5007498 4967588 4977596 5008520 5048172 5037183 5016121 4941477 4948399 4960428 5029051 5042086 4988418 4950671 4964348 5005958 5050060 5045814 4984133 4959495 4966704 4985626 5067897 5069603 5018229 4963491 4971371 5005990 5031733 5052418 5001149 4949786 4942907 4953975 5027085 5044435 5010468 4953864
average_num_operations_total=4997440
stdev_num_operations_total=3956
sum_num_operations_total=479754297
min_num_operations_total=4919397
max_num_operations_total=5069603
first_time_thread_terminate_by_thread=10000139 10000138 10000137 10000137 10000135 10000135 10000138 10000136 10000137 10000136 10000138 10000136 10000136 10000136 10000136 10000137 10000135 10000137 10000139 10000135 10000136 10000136 10000139 10000136 10000139 10000138 10000135 10000136 10000135 10000139 10000135 10000135 10000137 10000135 10000136 10000136 10000136 10000136 10000135 10000137 10000135 10000136 10000139 10000138 10000136 10000136 10000139 10000136 10000135 10000138 10000135 10000138 10000135 10000137 10000136 10000138 10000138 10000135 10000135 10000135 10000137 10000137 10000136 10000136 10000135 10000137 10000135 10000138 10000136 10000137 10000135 10000138 10000135 10000138 10000136 10000138 10000135 10000137 10000136 10000138 10000135 10000135 10000135 10000135 10000137 10000137 10000136 10000136 10000135 10000137 10000135 10000138 10000136 10000135 10000135 10000138
min_time_thread_terminate_total=10000135
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=3 4 3 4 4 3 3 3 4 3 4 4 3 3 4 4 4 3 3 3 3 4 4 3 3 4 3 4 4 3 3 3 4 3 4 4 3 3 4 4 4 3 3 3 3 4 4 3 3 4 3 4 3 3 4 3 3 3 3 3 3 3 3 3 3 3 4 4 3 4 3 4 3 4 3 4 3 3 4 3 3 3 3 3 3 3 3 3 3 3 4 4 3 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=4
sum_duration_all_ops_total=

threads_final_keysum=98514228019684
threads_final_size=9853637
final_keysum=98514228019684
final_size=9853637
validate_result=success
Validation OK.
Structural validation OK.

total_find=474957394
total_rq=0
total_inserts=2398427
total_deletes=2398476
total_updates=4796903
total_queries=474957394
total_ops=479754297
find_throughput=47495739
rq_throughput=0
update_throughput=479690
query_throughput=47495739
total_throughput=47975429


total find                    : 474957394
total rq                      : 0
total inserts                 : 2398427
total deletes                 : 2398476
total updates                 : 4796903
total queries                 : 474957394
total ops                     : 479754297
find throughput               : 47495739
rq throughput                 : 0
update throughput             : 479690
query throughput              : 47495739
total throughput              : 47975429

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=25.8966
PAPI_L3_TCM=10.147
PAPI_TOT_CYC=5572.86
PAPI_TOT_INS=368.162
garbage=2318089266598636
total_execution_walltime=13.081s
REALTIME_MAIN_END_PERF_FORMAT=6979386.444964212
[time_cmd_output] time_elapsed_sec=13.26, faults_major=0, faults_minor=236122, mem_maxresident_kb=942504, user_cputime=1238.34, sys_cputime=3.57, percent_cpu=9365%
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