__step=000370
__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 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=data000370.txt
__path_data=data/data000370.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983261.468139928
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=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=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.005s
prefilling_size=6115798 prefilling_elapsed_millis=1.001s
prefilling_size=6408236 prefilling_elapsed_millis=1.101s
prefilling_size=6675092 prefilling_elapsed_millis=1.201s
prefilling_size=6918955 prefilling_elapsed_millis=1.301s
prefilling_size=7142598 prefilling_elapsed_millis=1.401s
prefilling_size=7350365 prefilling_elapsed_millis=1.502s
prefilling_size=7540027 prefilling_elapsed_millis=1.602s
prefilling_size=7707897 prefilling_elapsed_millis=1.702s
prefilling_size=7869636 prefilling_elapsed_millis=1.802s
prefilling_size=8018797 prefilling_elapsed_millis=1.903s
prefilling_size=8156786 prefilling_elapsed_millis=2.003s
prefilling_size=8285208 prefilling_elapsed_millis=2.103s
prefilling_size=8403988 prefilling_elapsed_millis=2.203s
prefilling_size=8513780 prefilling_elapsed_millis=2.303s
prefilling_size=8615836 prefilling_elapsed_millis=2.404s
prefilling_size=8710870 prefilling_elapsed_millis=2.504s
prefilling_size=8798047 prefilling_elapsed_millis=2.604s
prefilling_size=8880554 prefilling_elapsed_millis=2.704s
prefilling_size=8955700 prefilling_elapsed_millis=2.804s
prefilling_size=9024629 prefilling_elapsed_millis=2.905s
prefilling_size=9089870 prefilling_elapsed_millis=3.005s
prefilling_size=9150649 prefilling_elapsed_millis=3.105s
prefilling_size=9207423 prefilling_elapsed_millis=3.205s
prefilling_size=9260198 prefilling_elapsed_millis=3.305s
prefilling_size=9309869 prefilling_elapsed_millis=3.406s
prefilling_size=9354573 prefilling_elapsed_millis=3.506s
prefilling_size=9395614 prefilling_elapsed_millis=3.606s
prefilling_size=9434416 prefilling_elapsed_millis=3.706s
prefilling_size=9472715 prefilling_elapsed_millis=3.806s
prefilling_size=9507527 prefilling_elapsed_millis=3.907s
prefilling_size=9540997 prefilling_elapsed_millis=4.007s
prefilling_size=9569943 prefilling_elapsed_millis=4.107s
prefilling_size=9598400 prefilling_elapsed_millis=4.207s
prefilling_size=9625210 prefilling_elapsed_millis=4.307s
prefilling_size=9649582 prefilling_elapsed_millis=4.407s
prefilling_size=9672020 prefilling_elapsed_millis=4.508s
prefilling_size=9692187 prefilling_elapsed_millis=4.608s
prefilling_size=9712154 prefilling_elapsed_millis=4.708s
prefilling_size=9730299 prefilling_elapsed_millis=4.808s
prefilling_size=9747945 prefilling_elapsed_millis=4.908s
prefilling_size=9763580 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 236428 keys with 48 threads...
tid=0 thread_prefill_amount=241628 percent_done_estimate=4905.6 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0395313s
prefilling_size=10000000 prefilling_elapsed_millis=0.039s
finished prefilling to size 10000000 for expected size 10000000 keysum=99999513226947, performing 79366127 updates; total_prefilling_elapsed_ms=39 ms)
pref_size=10000000
pref_millis=39
prefill_elapsed_ms=39
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=28013
6node_tIxPvE_limbo_details=8767 9763 9483 

global_epoch_counter=3669

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=28013
6info_tIxPvE_limbo_details=8767 9763 9483 

global_epoch_counter=0

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983266.784160157
REALTIME_END_PERF_FORMAT=6983276.784239881

###############################################################################
################################## 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.342s

tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 64 128 256 510 1009 1980 3797 7096 12919 22754 38603 62890 97764 145194 206517 281529 365991 455626 543386 623157 686909 728763 746049 737954 704070 649922 580774 503519 424072 347642 277113 215330 162276 118846 84867 58366 38427 24629 14943 8676 4875 2537 1262 552 235 69 25 9 6 1 0
tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 0 0 0 2 11 38 163 498 1273 3084 6905 14316 28016 50334 83871 131505 197067 276356 367866 463615 559405 645055 711477 754144 771838 758218 719070 658029 582966 500502 418171 338896 268384 205706 152825 111368 78305 52225 34315 21210 12477 7213 3812 1972 869 401 113 41 12 11 2
tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 128 256 512 1020 2018 3960 7594 14192 25838 45508 77206 125780 195528 290388 413034 563058 731982 911252 1086772 1246314 1373818 1457526 1492098 1475908 1408140 1299844 1161548 1007038 848144 695284 554226 430660 324552 237692 169734 116732 76854 49258 29886 17352 9750 5074 2524 1104 470 138 50 18 12 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 0 0 2 11 38 163 498 1273 3084 6905 14316 28016 50334 83871 131505 197067 276356 367866 463615 559405 645055 711477 754144 771838 758218 719070 658029 582966 500502 418171 338896 268384 205706 152825 111368 78305 52225 34315 21210 12477 7213 3812 1972 869 401 113 41 12 11 2
tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 2 2 2 1.99609 1.98922 1.98117 1.95884 1.93442 1.9103 1.88064 1.84827 1.81457 1.77726 1.74257 1.71118 1.68161 1.65001 1.62246 1.59631 1.5734 1.55115 1.53047 1.51186 1.49457 1.47704 1.46155 1.4468 1.43349 1.42111 1.40989 1.39856 1.38852 1.37681 1.36618 1.35705 1.34387 1.32919 1.32046 1.30336 1.2903 1.28095 1.26021 1.24872 1.2187 1.21286 1.14681 1.18116 1.18 1.33333 1.08333 1

tree_stats_height=59
tree_stats_numInternals=9993953
tree_stats_numLeaves=9993954
tree_stats_numNodes=19987907
tree_stats_numKeys=9993952

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

tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5120 10240 20480 40800 80720 158400 303760 567680 1033520 1820320 3088240 5031200 7821120 11615520 16521360 22522320 29279280 36450080 43470880 49852560 54952720 58301040 59683920 59036320 56325600 51993760 46461920 40281520 33925760 27811360 22169040 17226400 12982080 9507680 6789360 4669280 3074160 1970320 1195440 694080 390000 202960 100960 44160 18800 5520 2000 720 480 80
tree_stats_sizeInBytes=799516280

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

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:0 2:3 4:16 8:11701 16:303105 32:164862 64:67 128:244 256:2
    (2^01, 2^02]: 3
    (2^02, 2^03]: 16
    (2^03, 2^04]: 11701
    (2^04, 2^05]: 303105
    (2^05, 2^06]: 164862
    (2^06, 2^07]: 67
    (2^07, 2^08]: 244
    (2^08, 2^09]: 2
sum_limbo_reclamation_event_size_total=13860761
count_limbo_reclamation_event_size_by_thread=10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000 10000
count_limbo_reclamation_event_size_total=480000
sum_limbo_reclamation_event_count_total=826838
first_thread_announced_epoch_by_thread=24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562 24562
sum_num_inserts_by_thread=195968 197744 200134 199210 197778 196037 196898 197491 200045 200722 197488 196611 197171 197832 199560 200183 198570 197259 196081 196808 199240 199517 196655 196699 197018 197914 199196 198903 198017 196642 196422 197478 200311 199571 197963 196845 196766 199415 200394 200047 197509 197523 196583 197350 199130 199070 197197 195492
sum_num_inserts_total=9504457
sum_num_deletes_by_thread=196231 198216 199733 200175 197846 196436 197052 197803 200590 200703 198250 197638 196795 198705 199439 199558 199215 197221 196110 197091 197750 199000 197119 196053 196863 198143 198482 199951 198500 196976 197470 198285 200583 200210 197925 196784 197604 198952 201038 199534 198839 197237 196736 196986 198982 199727 197415 196062
sum_num_deletes_total=9514013
sum_num_searches_by_thread=3535800 3556572 3592297 3589265 3567231 3532219 3557155 3560653 3602361 3606472 3557122 3548026 3555904 3571521 3592381 3590643 3572097 3556938 3527786 3548967 3578982 3585519 3543260 3526306 3549565 3556035 3578635 3589991 3579451 3542176 3559505 3562787 3605568 3600699 3568180 3539384 3555658 3581186 3599091 3589508 3568571 3544676 3535686 3554643 3577782 3583367 3544533 3521260
sum_num_searches_total=171143414
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3927999 3952532 3992164 3988650 3962855 3924692 3951105 3955947 4002996 4007897 3952860 3942275 3949870 3968058 3991380 3990384 3969882 3951418 3919977 3942866 3975972 3984036 3937034 3919058 3943446 3952092 3976313 3988845 3975968 3935794 3953397 3958550 4006462 4000480 3964068 3933013 3950028 3979553 4000523 3989089 3964919 3939436 3929005 3948979 3975894 3982164 3939145 3912814
average_num_operations_total=3961705
stdev_num_operations_total=3721
sum_num_operations_total=190161884
min_num_operations_total=3912814
max_num_operations_total=4007897
first_time_thread_terminate_by_thread=10000132 10000079 10000081 10000079 10000079 10000079 10000079 10000081 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000079 10000080 10000080 10000080 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000079 10000080 10000079 10000079 10000079 10000080
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000132
first_time_thread_start_by_thread=10 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 4 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 5 5 5 6 6 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99947229965186
threads_final_size=9993952
final_keysum=99947229965186
final_size=9993952
validate_result=success
Validation OK.
Structural validation OK.

total_find=171143414
total_rq=0
total_inserts=9504457
total_deletes=9514013
total_updates=19018470
total_queries=171143414
total_ops=190161884
find_throughput=17114341
rq_throughput=0
update_throughput=1901847
query_throughput=17114341
total_throughput=19016188


total find                    : 171143414
total rq                      : 0
total inserts                 : 9504457
total deletes                 : 9514013
total updates                 : 19018470
total queries                 : 171143414
total ops                     : 190161884
find throughput               : 17114341
rq throughput                 : 0
update throughput             : 1901847
query throughput              : 17114341
total throughput              : 19016188

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.9126
PAPI_L3_TCM=13.2603
PAPI_TOT_CYC=7038.73
PAPI_TOT_INS=405.123
garbage=856053825555001
total_execution_walltime=15.832s
REALTIME_MAIN_END_PERF_FORMAT=6983277.300931297
[time_cmd_output] time_elapsed_sec=16.04, faults_major=0, faults_minor=315211, mem_maxresident_kb=1261436, user_cputime=739.53, sys_cputime=3.51, percent_cpu=4630%
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