__step=000034
__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" ./ellen_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=data000034.txt
__path_data=data/data000034.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6979137.317884981
binary=./ellen_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=ellen_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=40 descriptor=64
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.004s
prefilling_size=6172114 prefilling_elapsed_millis=1.001s
prefilling_size=6466443 prefilling_elapsed_millis=1.101s
prefilling_size=6734724 prefilling_elapsed_millis=1.201s
prefilling_size=6980948 prefilling_elapsed_millis=1.301s
prefilling_size=7206534 prefilling_elapsed_millis=1.402s
prefilling_size=7413247 prefilling_elapsed_millis=1.502s
prefilling_size=7604306 prefilling_elapsed_millis=1.602s
prefilling_size=7778196 prefilling_elapsed_millis=1.702s
prefilling_size=7940447 prefilling_elapsed_millis=1.802s
prefilling_size=8089172 prefilling_elapsed_millis=1.903s
prefilling_size=8227984 prefilling_elapsed_millis=2.003s
prefilling_size=8355422 prefilling_elapsed_millis=2.103s
prefilling_size=8472396 prefilling_elapsed_millis=2.203s
prefilling_size=8580075 prefilling_elapsed_millis=2.303s
prefilling_size=8680647 prefilling_elapsed_millis=2.404s
prefilling_size=8773541 prefilling_elapsed_millis=2.504s
prefilling_size=8860011 prefilling_elapsed_millis=2.604s
prefilling_size=8941859 prefilling_elapsed_millis=2.704s
prefilling_size=9016815 prefilling_elapsed_millis=2.804s
prefilling_size=9085660 prefilling_elapsed_millis=2.905s
prefilling_size=9148663 prefilling_elapsed_millis=3.005s
prefilling_size=9209579 prefilling_elapsed_millis=3.105s
prefilling_size=9266169 prefilling_elapsed_millis=3.205s
prefilling_size=9318262 prefilling_elapsed_millis=3.306s
prefilling_size=9365184 prefilling_elapsed_millis=3.406s
prefilling_size=9407986 prefilling_elapsed_millis=3.506s
prefilling_size=9449491 prefilling_elapsed_millis=3.606s
prefilling_size=9488840 prefilling_elapsed_millis=3.706s
prefilling_size=9524409 prefilling_elapsed_millis=3.807s
prefilling_size=9558670 prefilling_elapsed_millis=3.907s
prefilling_size=9589345 prefilling_elapsed_millis=4.007s
prefilling_size=9617587 prefilling_elapsed_millis=4.107s
prefilling_size=9644356 prefilling_elapsed_millis=4.207s
prefilling_size=9669158 prefilling_elapsed_millis=4.308s
prefilling_size=9690727 prefilling_elapsed_millis=4.408s
prefilling_size=9712405 prefilling_elapsed_millis=4.508s
prefilling_size=9731270 prefilling_elapsed_millis=4.608s
prefilling_size=9749664 prefilling_elapsed_millis=4.708s
prefilling_size=9767458 prefilling_elapsed_millis=4.809s
prefilling_size=9783171 prefilling_elapsed_millis=4.909s
prefilling_size=9798482 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 201475 keys with 48 threads...
tid=0 thread_prefill_amount=220798 percent_done_estimate=5260.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0341201s
prefilling_size=10000000 prefilling_elapsed_millis=0.034s
finished prefilling to size 10000000 for expected size 10000000 keysum=100014883471820, performing 79548045 updates; total_prefilling_elapsed_ms=34 ms)
pref_size=10000000
pref_millis=34
prefill_elapsed_ms=34
6node_tIxPvE_object_size=40
6node_tIxPvE_allocated_count=0
6node_tIxPvE_allocated_size=0MB
6node_tIxPvE_get_from_pool=0
6node_tIxPvE_deallocated=0
6node_tIxPvE_limbo_count=27466
6node_tIxPvE_limbo_details=6512 10231 10723 

global_epoch_counter=3690

6info_tIxPvE_object_size=64
6info_tIxPvE_allocated_count=0
6info_tIxPvE_allocated_size=0MB
6info_tIxPvE_get_from_pool=0
6info_tIxPvE_deallocated=0
6info_tIxPvE_limbo_count=27466
6info_tIxPvE_limbo_details=6512 10231 10723 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979142.618959045
REALTIME_END_PERF_FORMAT=6979152.619040005

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

tree_stats_computeWalltime=0.338s

tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 256 509 1006 1953 3722 6903 12330 21287 35099 55469 84012 122094 170643 230296 299519 375954 455103 532624 604192 663629 704928 725262 721108 695068 646725 582733 506851 425469 345443 271508 206965 152919 111127 78514 54034 36455 23661 14836 8943 5198 2929 1639 906 481 241 122 59 19 9 5 1 1 0
tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 0 3 12 59 184 541 1476 3373 7475 14729 26926 45930 73545 110990 161073 223084 296805 377582 461056 544755 622330 684594 729416 747148 743411 710717 658615 588233 505495 419378 336051 261011 194711 143740 102994 71613 49249 32486 20729 12688 7467 4219 2372 1331 721 360 185 99 29 13 9 1 2
tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 512 1018 2012 3906 7444 13806 24660 42574 70198 110938 168024 244188 341286 460592 599038 751908 910206 1065248 1208384 1327258 1409856 1450524 1442216 1390136 1293450 1165466 1013702 850938 690886 543016 413930 305838 222254 157028 108068 72910 47322 29672 17886 10396 5858 3278 1812 962 482 244 118 38 18 10 2 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 3 12 59 184 541 1476 3373 7475 14729 26926 45930 73545 110990 161073 223084 296805 377582 461056 544755 622330 684594 729416 747148 743411 710717 658615 588233 505495 419378 336051 261011 194711 143740 102994 71613 49249 32486 20729 12688 7467 4219 2372 1331 721 360 185 99 29 13 9 1 2
tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 2 1.99414 1.98821 1.97068 1.95289 1.92732 1.89309 1.86322 1.82442 1.79018 1.75729 1.72665 1.69882 1.67479 1.65029 1.6276 1.60526 1.58517 1.56718 1.54919 1.53112 1.51442 1.49714 1.48194 1.46522 1.45053 1.43489 1.41972 1.40596 1.39299 1.38114 1.36943 1.36335 1.35326 1.3441 1.33733 1.32452 1.31351 1.3014 1.29062 1.28174 1.27979 1.27639 1.26545 1.25052 1.25311 1.2418 1.16102 1.23684 1.27778 1.1 1.5 1

tree_stats_height=63
tree_stats_numInternals=10001016
tree_stats_numLeaves=10001017
tree_stats_numNodes=20002033
tree_stats_numKeys=10001015

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

tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20480 40720 80480 156240 297760 552240 986400 1702960 2807920 4437520 6720960 9767520 13651440 18423680 23961520 30076320 36408240 42609920 48335360 53090320 56394240 58020960 57688640 55605440 51738000 46618640 40548080 34037520 27635440 21720640 16557200 12233520 8890160 6281120 4322720 2916400 1892880 1186880 715440 415840 234320 131120 72480 38480 19280 9760 4720 1520 720 400 80 80
tree_stats_sizeInBytes=800081320

6node_tIxPvE_object_size=40
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=12457

6info_tIxPvE_object_size=64
6info_tIxPvE_allocated_count=0
6info_tIxPvE_allocated_size=0MB
6info_tIxPvE_get_from_pool=0
6info_tIxPvE_deallocated=0
6info_tIxPvE_limbo_count=0
6info_tIxPvE_limbo_details=0 0 0 

global_epoch_counter=0


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:83855 2:193317 4:139518 8:15223 16:65 32:12 64:34 128:230 256:2
    [2^00, 2^01]: 83855
    (2^01, 2^02]: 193317
    (2^02, 2^03]: 139518
    (2^03, 2^04]: 15223
    (2^04, 2^05]: 65
    (2^05, 2^06]: 12
    (2^06, 2^07]: 34
    (2^07, 2^08]: 230
    (2^08, 2^09]: 2
sum_limbo_reclamation_event_size_total=1433203
count_limbo_reclamation_event_size_by_thread=9060 9046 9072 9014 8948 8976 8950 8958 9044 9062 8986 9004 8990 8998 9020 8980 8948 9060 9028 9042 9008 9026 8894 8912 8960 8962 9034 9074 9048 8970 9006 9020 9040 9012 8948 9046 9042 9090 9056 8992 8932 9012 8986 9020 8970 9030 9004 8976
count_limbo_reclamation_event_size_total=432256
sum_limbo_reclamation_event_count_total=841718
first_thread_announced_epoch_by_thread=24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914 24914
sum_num_inserts_by_thread=20091 20247 20698 20417 20132 19913 19827 20178 20196 20391 20091 19754 20417 20281 20317 20436 20307 20163 19932 20063 20437 20065 20121 20003 20210 20042 20387 20195 20160 19948 19983 20387 20148 20385 20106 20394 20119 20362 20360 20326 20340 20461 19960 19791 20061 20155 20171 19928
sum_num_inserts_total=968856
sum_num_deletes_by_thread=19885 20075 20417 20122 19997 19965 19876 19987 20348 20151 20194 19864 20075 20284 20132 20288 20307 20335 20233 20221 20151 20332 19805 20018 19865 20187 20286 20261 20169 20125 19945 20400 20507 20681 20242 19790 20056 20119 20407 20133 20097 19912 19895 20050 20149 20324 20201 20021
sum_num_deletes_total=966884
sum_num_searches_by_thread=3968148 3990399 4021910 4010981 3999071 3964387 3974787 3992057 4034791 4040574 3988770 3974585 3990621 4009344 4029649 4026901 4009628 3984639 3960601 3985542 4007288 4021330 3979126 3950374 3972444 3988323 4021196 4018431 4001755 3958473 3979422 3998080 4035125 4035851 3989654 3969469 3991317 4011852 4032754 4035577 4010626 3982798 3957547 3982502 4000230 4023492 3980765 3950257
sum_num_searches_total=191843443
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4008124 4030721 4063025 4051520 4039200 4004265 4014490 4032222 4075335 4081116 4029055 4014203 4031113 4049909 4070098 4067625 4050242 4025137 4000766 4025826 4047876 4061727 4019052 3990395 4012519 4028552 4061869 4058887 4042084 3998546 4019350 4038867 4075780 4076917 4030002 4009653 4031492 4052333 4073521 4076036 4051063 4023171 3997402 4022343 4040440 4063971 4021137 3990206
average_num_operations_total=4037066
stdev_num_operations_total=3717
sum_num_operations_total=193779183
min_num_operations_total=3990206
max_num_operations_total=4081116
first_time_thread_terminate_by_thread=10000136 10000080 10000081 10000080 10000080 10000081 10000081 10000080 10000080 10000081 10000081 10000080 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000081 10000080 10000080 10000080 10000080 10000080 10000080 10000081 10000081 10000080 10000081 10000081 10000080 10000080 10000081 10000081 10000080 10000081 10000080 10000081 10000083 10000081 10000081 10000081 10000081 10000081 10000081 10000080 10000080
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000136
first_time_thread_start_by_thread=12 6 6 6 5 6 6 5 6 6 6 6 6 6 6 6 6 5 5 6 6 6 6 5 4 6 6 6 5 6 6 5 6 6 6 6 6 6 6 6 6 5 5 6 6 6 6 5
min_time_thread_start_total=4
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100025861224279
threads_final_size=10001015
final_keysum=100025861224279
final_size=10001015
validate_result=success
Validation OK.
Structural validation OK.

total_find=191843443
total_rq=0
total_inserts=968856
total_deletes=966884
total_updates=1935740
total_queries=191843443
total_ops=193779183
find_throughput=19184344
rq_throughput=0
update_throughput=193574
query_throughput=19184344
total_throughput=19377918


total find                    : 191843443
total rq                      : 0
total inserts                 : 968856
total deletes                 : 966884
total updates                 : 1935740
total queries                 : 191843443
total ops                     : 193779183
find throughput               : 19184344
rq throughput                 : 0
update throughput             : 193574
query throughput              : 19184344
total throughput              : 19377918

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=36.2079
PAPI_L3_TCM=12.5888
PAPI_TOT_CYC=6905.99
PAPI_TOT_INS=375.944
garbage=959541448197246
total_execution_walltime=15.809s
REALTIME_MAIN_END_PERF_FORMAT=6979153.127616540
[time_cmd_output] time_elapsed_sec=16.00, faults_major=0, faults_minor=310735, mem_maxresident_kb=1244012, user_cputime=738.14, sys_cputime=3.99, percent_cpu=4636%
timeout=false
algorithm=ellen_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