__step=000369
__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=data000369.txt
__path_data=data/data000369.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983245.585025784
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.006s
prefilling_size=6199164 prefilling_elapsed_millis=1.001s
prefilling_size=6495163 prefilling_elapsed_millis=1.101s
prefilling_size=6765472 prefilling_elapsed_millis=1.201s
prefilling_size=7011171 prefilling_elapsed_millis=1.301s
prefilling_size=7236932 prefilling_elapsed_millis=1.402s
prefilling_size=7445264 prefilling_elapsed_millis=1.502s
prefilling_size=7636369 prefilling_elapsed_millis=1.602s
prefilling_size=7812564 prefilling_elapsed_millis=1.702s
prefilling_size=7973864 prefilling_elapsed_millis=1.802s
prefilling_size=8122526 prefilling_elapsed_millis=1.903s
prefilling_size=8260935 prefilling_elapsed_millis=2.003s
prefilling_size=8386518 prefilling_elapsed_millis=2.103s
prefilling_size=8503628 prefilling_elapsed_millis=2.203s
prefilling_size=8611130 prefilling_elapsed_millis=2.303s
prefilling_size=8712929 prefilling_elapsed_millis=2.404s
prefilling_size=8806139 prefilling_elapsed_millis=2.504s
prefilling_size=8891573 prefilling_elapsed_millis=2.604s
prefilling_size=8970726 prefilling_elapsed_millis=2.704s
prefilling_size=9044017 prefilling_elapsed_millis=2.805s
prefilling_size=9113863 prefilling_elapsed_millis=2.905s
prefilling_size=9176662 prefilling_elapsed_millis=3.005s
prefilling_size=9237382 prefilling_elapsed_millis=3.105s
prefilling_size=9291857 prefilling_elapsed_millis=3.205s
prefilling_size=9342176 prefilling_elapsed_millis=3.306s
prefilling_size=9388002 prefilling_elapsed_millis=3.406s
prefilling_size=9432598 prefilling_elapsed_millis=3.506s
prefilling_size=9474196 prefilling_elapsed_millis=3.606s
prefilling_size=9512487 prefilling_elapsed_millis=3.706s
prefilling_size=9546351 prefilling_elapsed_millis=3.807s
prefilling_size=9578040 prefilling_elapsed_millis=3.907s
prefilling_size=9607790 prefilling_elapsed_millis=4.007s
prefilling_size=9635184 prefilling_elapsed_millis=4.107s
prefilling_size=9660624 prefilling_elapsed_millis=4.207s
prefilling_size=9684733 prefilling_elapsed_millis=4.308s
prefilling_size=9706700 prefilling_elapsed_millis=4.408s
prefilling_size=9727620 prefilling_elapsed_millis=4.508s
prefilling_size=9745831 prefilling_elapsed_millis=4.608s
prefilling_size=9763570 prefilling_elapsed_millis=4.708s
prefilling_size=9780668 prefilling_elapsed_millis=4.809s
prefilling_size=9797345 prefilling_elapsed_millis=4.909s
prefilling_size=9809683 prefilling_elapsed_millis=5.009s
finished prefilling to size 9809640 for expected size 10000000 keysum=98080341991840, performing 79562084 updates; total_prefilling_elapsed_ms=5110 ms)
pref_size=9809640
pref_millis=5110
prefill_elapsed_ms=5133
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=3687

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

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983250.878186403
REALTIME_END_PERF_FORMAT=6983260.878321492

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

tree_stats_computeWalltime=0.178s

tree_stats_numInternalsAtDepth=1 1 1 2 4 8 16 32 63 124 243 467 894 1723 3220 5952 10678 18497 31073 50178 77970 116395 167206 230046 303392 384166 467554 546862 615474 669852 705858 719725 712163 680780 629900 562761 488316 410163 335134 266801 206761 154839 113579 81076 56507 38157 24754 15477 9299 5458 3120 1736 941 468 268 133 64 30 10 3 0
tree_stats_numLeavesAtDepth=0 1 1 0 0 0 0 0 1 2 5 19 40 65 226 488 1226 2859 5921 11968 22386 39545 65584 104366 156700 222618 300778 388246 478250 561096 633846 691991 727287 743546 731660 697039 637206 566469 485192 403467 326841 258683 196099 146082 105645 74857 51560 34031 21655 13140 7796 4504 2531 1414 668 403 202 98 50 17 6
tree_stats_numNodesAtDepth=1 2 2 2 4 8 16 32 64 126 248 486 934 1788 3446 6440 11904 21356 36994 62146 100356 155940 232790 334412 460092 606784 768332 935108 1093724 1230948 1339704 1411716 1439450 1424326 1361560 1259800 1125522 976632 820326 670268 533602 413522 309678 227158 162152 113014 76314 49508 30954 18598 10916 6240 3472 1882 936 536 266 128 60 20 6
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 1 2 5 19 40 65 226 488 1226 2859 5921 11968 22386 39545 65584 104366 156700 222618 300778 388246 478250 561096 633846 691991 727287 743546 731660 697039 637206 566469 485192 403467 326841 258683 196099 146082 105645 74857 51560 34031 21655 13140 7796 4504 2531 1414 668 403 202 98 50 17 6
tree_stats_avgDegreeAtDepth=2 1 1 2 2 2 2 2 1.98438 1.98413 1.97984 1.96091 1.95717 1.96365 1.93442 1.92422 1.89701 1.86613 1.83995 1.80742 1.77693 1.74641 1.71827 1.68791 1.65942 1.63312 1.60853 1.58481 1.56273 1.54418 1.52688 1.50982 1.49475 1.47797 1.46263 1.44671 1.43386 1.41998 1.40854 1.39805 1.38748 1.37444 1.36676 1.35691 1.34848 1.33763 1.32437 1.31262 1.30041 1.29347 1.28582 1.27821 1.27103 1.24867 1.28632 1.24813 1.2406 1.23438 1.16667 1.15 1

tree_stats_height=61
tree_stats_numInternals=9926375
tree_stats_numLeaves=9926376
tree_stats_numNodes=19852751
tree_stats_numKeys=9926374

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

tree_stats_bytesAtDepth=40 80 80 80 160 320 640 1280 2560 5040 9920 19440 37360 71520 137840 257600 476160 854240 1479760 2485840 4014240 6237600 9311600 13376480 18403680 24271360 30733280 37404320 43748960 49237920 53588160 56468640 57578000 56973040 54462400 50392000 45020880 39065280 32813040 26810720 21344080 16540880 12387120 9086320 6486080 4520560 3052560 1980320 1238160 743920 436640 249600 138880 75280 37440 21440 10640 5120 2400 800 240
tree_stats_sizeInBytes=794110040

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

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:0 4:19 8:11857 16:305481 32:162416 64:35
    (2^02, 2^03]: 19
    (2^03, 2^04]: 11857
    (2^04, 2^05]: 305481
    (2^05, 2^06]: 162416
    (2^06, 2^07]: 35
sum_limbo_reclamation_event_size_total=13758557
count_limbo_reclamation_event_size_by_thread=9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996 9996
count_limbo_reclamation_event_size_total=479808
sum_limbo_reclamation_event_count_total=833184
first_thread_announced_epoch_by_thread=24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732 24732
sum_num_inserts_by_thread=198671 199316 201570 200345 199493 197785 199489 199059 201277 201498 198407 197686 199124 199877 201618 201649 200656 198715 197993 198802 199923 201059 198914 197567 198408 198934 200868 199843 199907 198460 198786 199563 200877 201845 200773 198568 199609 199682 201750 201000 199771 199226 197091 199088 200263 201256 199222 197643
sum_num_inserts_total=9582926
sum_num_deletes_by_thread=198954 200577 200221 199574 199906 197365 197949 199856 202388 201869 199602 198833 198598 200386 201955 201111 200222 198520 197483 198596 200548 201468 198325 196487 198115 199211 200849 200555 200851 198043 198584 199540 202011 202014 198870 198905 199979 200635 201713 201551 199696 198868 197418 199266 200209 200765 199429 197711
sum_num_deletes_total=9585581
sum_num_searches_by_thread=3566987 3587826 3613874 3614233 3598747 3560956 3574352 3591104 3624986 3630053 3587051 3572807 3584171 3605333 3624665 3621979 3600861 3581590 3558502 3581493 3604540 3621059 3575123 3549438 3567612 3587758 3614707 3612574 3599850 3566663 3583233 3596796 3631694 3633317 3594076 3574659 3586447 3607174 3628363 3627477 3604306 3581485 3556925 3581191 3600174 3617170 3576008 3555283
sum_num_searches_total=172516672
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3964612 3987719 4015665 4014152 3998146 3956106 3971790 3990019 4028651 4033420 3985060 3969326 3981893 4005596 4028238 4024739 4001739 3978825 3953978 3978891 4005011 4023586 3972362 3943492 3964135 3985903 4016424 4012972 4000608 3963166 3980603 3995899 4034582 4037176 3993719 3972132 3986035 4007491 4031826 4030028 4003773 3979579 3951434 3979545 4000646 4019191 3974659 3950637
average_num_operations_total=3993441
stdev_num_operations_total=3741
sum_num_operations_total=191685179
min_num_operations_total=3943492
max_num_operations_total=4037176
first_time_thread_terminate_by_thread=10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000136 10000137 10000135 10000135 10000136 10000136 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000135 10000136 10000135 10000135 10000136
min_time_thread_terminate_total=10000135
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=3 3 2 3 3 2 3 3 3 3 2 3 3 3 3 2 2 3 3 3 3 2 2 3 3 3 2 3 3 2 3 3 3 3 2 3 3 3 3 2 2 3 3 3 3 2 2 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=99257106939915
threads_final_size=9926374
final_keysum=99257106939915
final_size=9926374
validate_result=success
Validation OK.
Structural validation OK.

total_find=172516672
total_rq=0
total_inserts=9582926
total_deletes=9585581
total_updates=19168507
total_queries=172516672
total_ops=191685179
find_throughput=17251667
rq_throughput=0
update_throughput=1916850
query_throughput=17251667
total_throughput=19168517


total find                    : 172516672
total rq                      : 0
total inserts                 : 9582926
total deletes                 : 9585581
total updates                 : 19168507
total queries                 : 172516672
total ops                     : 191685179
find throughput               : 17251667
rq throughput                 : 0
update throughput             : 1916850
query throughput              : 17251667
total throughput              : 19168517

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=37.5434
PAPI_L3_TCM=13.1644
PAPI_TOT_CYC=6980.99
PAPI_TOT_INS=409.927
garbage=852004518814189
total_execution_walltime=15.589s
REALTIME_MAIN_END_PERF_FORMAT=6983261.174794285
[time_cmd_output] time_elapsed_sec=15.81, faults_major=0, faults_minor=310654, mem_maxresident_kb=1242932, user_cputime=756.55, sys_cputime=3.69, percent_cpu=4808%
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=1