__step=000721
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 50.0 50.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000721.txt
__path_data=data/data000721.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215529.671907014
binary=./natarajan_ext_bst_lf.debra
parsed custom binding: 0-63,128-191,64-127,192-255
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=50
DEL_FRAC=50
RQ=0
RQSIZE=0
MAXKEY=20000000
PREFILL_THREADS=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
RQ_THREADS=0
distribution=0
INS_DEL_FRAC=50.0 50.0
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.018s
prefilling_size=7687426 prefilling_elapsed_millis=1s
prefilling_size=7942094 prefilling_elapsed_millis=1.1s
prefilling_size=8165069 prefilling_elapsed_millis=1.201s
prefilling_size=8360256 prefilling_elapsed_millis=1.301s
prefilling_size=8533954 prefilling_elapsed_millis=1.401s
prefilling_size=8688228 prefilling_elapsed_millis=1.501s
prefilling_size=8824263 prefilling_elapsed_millis=1.601s
prefilling_size=8942774 prefilling_elapsed_millis=1.701s
prefilling_size=9050451 prefilling_elapsed_millis=1.801s
prefilling_size=9146784 prefilling_elapsed_millis=1.901s
prefilling_size=9233778 prefilling_elapsed_millis=2.002s
prefilling_size=9311205 prefilling_elapsed_millis=2.102s
prefilling_size=9380226 prefilling_elapsed_millis=2.202s
prefilling_size=9440591 prefilling_elapsed_millis=2.302s
prefilling_size=9496224 prefilling_elapsed_millis=2.402s
prefilling_size=9546963 prefilling_elapsed_millis=2.502s
prefilling_size=9591329 prefilling_elapsed_millis=2.602s
prefilling_size=9631951 prefilling_elapsed_millis=2.702s
prefilling_size=9669961 prefilling_elapsed_millis=2.802s
prefilling_size=9700802 prefilling_elapsed_millis=2.903s
prefilling_size=9730839 prefilling_elapsed_millis=3.003s
prefilling_size=9759629 prefilling_elapsed_millis=3.103s
prefilling_size=9784151 prefilling_elapsed_millis=3.203s
prefilling_size=9803578 prefilling_elapsed_millis=3.304s
finished prefilling to size 9803595 for expected size 10000000 keysum=98031477176611, performing 80426854 updates; total_prefilling_elapsed_ms=3404 ms)
pref_size=9803595
pref_millis=3404
prefill_elapsed_ms=3423
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=2086

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215533.126982735
REALTIME_END_PERF_FORMAT=215543.127062596

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

tree_stats_computeWalltime=0.224s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 511 1009 1985 3798 7142 12975 22844 38832 63431 99635 150195 216837 298935 394043 495868 595425 682327 748055 786113 790019 763943 711234 639483 555222 466542 380837 300644 229730 169928 122284 85952 58858 39400 25193 16020 10087 6054 3505 1938 1056 552 306 169 97 43 11 1 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 1 13 33 172 454 1309 3106 6856 14233 27227 49075 83553 134739 203827 292218 396311 508523 616599 709997 782207 816095 816652 782985 723744 643902 552247 461030 371558 289532 217572 158616 113046 78316 53607 34366 21953 14120 8603 5072 2820 1560 798 443 241 151 75 21 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1022 2018 3970 7596 14284 25950 45688 77664 126862 199270 300390 433674 597870 788086 991736 1190850 1364654 1496110 1572226 1580038 1527886 1422468 1278966 1110444 933084 761674 601288 459460 339856 244568 171904 117716 78800 50386 32040 20174 12108 7010 3876 2112 1104 612 338 194 86 22 2 2
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 0 1 13 33 172 454 1309 3106 6856 14233 27227 49075 83553 134739 203827 292218 396311 508523 616599 709997 782207 816095 816652 782985 723744 643902 552247 461030 371558 289532 217572 158616 113046 78316 53607 34366 21953 14120 8603 5072 2820 1560 798 443 241 151 75 21 1 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.99805 1.98728 1.98365 1.95668 1.94023 1.90836 1.88031 1.84994 1.81674 1.78538 1.75373 1.72185 1.68931 1.65908 1.62921 1.60039 1.57297 1.54816 1.52544 1.50248 1.4835 1.4655 1.44956 1.43412 1.42014 1.40815 1.39471 1.38206 1.36984 1.35981 1.35144 1.34239 1.3347 1.31971 1.31795 1.31483 1.30009 1.28948 1.27646 1.27245 1.26136 1.27717 1.27614 1.28698 1.22165 1.12791 1.04545 1.5 1

tree_stats_height=58
tree_stats_numInternals=9999580
tree_stats_numLeaves=9999581
tree_stats_numNodes=19999161
tree_stats_numKeys=9999581

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

tree_stats_bytesAtDepth=32 64 128 256 512 1024 2048 4096 8192 16384 32704 64576 127040 243072 457088 830400 1462016 2485248 4059584 6376640 9612480 13877568 19131840 25218752 31735552 38107200 43668928 47875520 50311232 50561216 48892352 45518976 40926912 35534208 29858688 24373568 19241216 14702720 10875392 7826176 5500928 3766912 2521600 1612352 1025280 645568 387456 224320 124032 67584 35328 19584 10816 6208 2752 704 64 64
tree_stats_sizeInBytes=639973152

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:17 4:1 8:4 16:4 32:21 64:79 128:14029 256:341546 512:72 1024:180
    (2^01, 2^02]: 17
    (2^02, 2^03]: 1
    (2^03, 2^04]: 4
    (2^04, 2^05]: 4
    (2^05, 2^06]: 21
    (2^06, 2^07]: 79
    (2^07, 2^08]: 14029
    (2^08, 2^09]: 341546
    (2^09, 2^10]: 72
    (2^10, 2^11]: 180
sum_limbo_reclamation_event_size_total=105885360
count_limbo_reclamation_event_size_by_thread=5562 5562 5562 5563 5562 5562 5563 5562 5562 5562 5562 5560 5562 5562 5562 5562 5561 5561 5562 5562 5561 5561 5561 5562 5561 5562 5561 5562 5562 5563 5562 5563 5562 5562 5562 5561 5562 5562 5563 5562 5561 5561 5562 5561 5561 5561 5561 5561 5562 5562 5562 5561 5562 5561 5563 5562 5561 5561 5561 5562 5562 5563 5562 5562
count_limbo_reclamation_event_size_total=355953
sum_limbo_reclamation_event_count_total=356144
first_thread_announced_epoch_by_thread=15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302 15302
sum_num_inserts_by_thread=1668794 1670183 1664925 1651568 1658368 1661859 1652236 1655881 1653359 1656312 1658570 1656347 1663392 1663115 1654715 1657866 1659270 1661847 1652677 1650582 1651367 1654191 1653097 1646641 1651136 1657329 1656919 1656059 1657927 1655818 1651958 1652386 1657913 1664119 1663901 1659644 1661133 1661070 1653550 1662924 1670812 1665942 1662035 1661989 1665071 1663191 1656953 1657256 1647052 1655479 1651809 1654051 1654707 1659077 1655549 1654356 1660286 1663782 1656215 1651073 1657300 1663595 1661679 1665187
sum_num_inserts_total=106115394
sum_num_deletes_by_thread=1669482 1668483 1666136 1652475 1655585 1658763 1654458 1655146 1652733 1655117 1659032 1657866 1662885 1662806 1658145 1660861 1658494 1658868 1654202 1652791 1653091 1654898 1650973 1648016 1651103 1656584 1656188 1657894 1656674 1655865 1652027 1654575 1658925 1662945 1658709 1658526 1660680 1658759 1656116 1665489 1668948 1668244 1662902 1661704 1666130 1661251 1657073 1657055 1650633 1657905 1651469 1650945 1653938 1657998 1651398 1656768 1660600 1660769 1655228 1652835 1659175 1663445 1661231 1663025
sum_num_deletes_total=106113034
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3338276 3338666 3331061 3304043 3313953 3320622 3306694 3311027 3306092 3311429 3317602 3314213 3326277 3325921 3312860 3318727 3317764 3320715 3306879 3303373 3304458 3309089 3304070 3294657 3302239 3313913 3313107 3313953 3314601 3311683 3303985 3306961 3316838 3327064 3322610 3318170 3321813 3319829 3309666 3328413 3339760 3334186 3324937 3323693 3331201 3324442 3314026 3314311 3297685 3313384 3303278 3304996 3308645 3317075 3306947 3311124 3320886 3324551 3311443 3303908 3316475 3327040 3322910 3328212
average_num_operations_total=3316069
stdev_num_operations_total=1278
sum_num_operations_total=212228428
min_num_operations_total=3294657
max_num_operations_total=3339760
first_time_thread_terminate_by_thread=10000101 10000108 10000082 10000085 10000082 10000080 10000104 10000080 10000082 10000082 10000082 10000097 10000081 10000081 10000087 10000081 10000096 10000090 10000095 10000097 10000081 10000081 10000096 10000081 10000081 10000085 10000080 10000080 10000081 10000081 10000100 10000097 10000079 10000081 10000129 10000079 10000082 10000081 10000081 10000081 10000090 10000079 10000088 10000081 10000098 10000086 10000085 10000088 10000082 10000082 10000092 10000085 10000081 10000081 10000081 10000082 10000098 10000084 10000082 10000082 10000082 10000080 10000080 10000082
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000129
first_time_thread_start_by_thread=5 5 5 5 4 4 4 4 6 6 6 6 6 6 6 6 7 7 7 7 7 7 7 7 7 7 7 7 6 6 6 6 5 5 5 5 6 6 6 6 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=7
sum_duration_all_ops_total=

threads_final_keysum=100010004920112
threads_final_size=9999581
final_keysum=100010004920112
final_size=9999581
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=106115394
total_deletes=106113034
total_updates=212228428
total_queries=0
total_ops=212228428
find_throughput=0
rq_throughput=0
update_throughput=21222842
query_throughput=0
total_throughput=21222842


total find                    : 0
total rq                      : 0
total inserts                 : 106115394
total deletes                 : 106113034
total updates                 : 212228428
total queries                 : 0
total ops                     : 212228428
find throughput               : 0
rq throughput                 : 0
update throughput             : 21222842
query throughput              : 0
total throughput              : 21222842

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=0
total_execution_walltime=13.838s
REALTIME_MAIN_END_PERF_FORMAT=215543.511004565
[time_cmd_output] time_elapsed_sec=13.98, faults_major=0, faults_minor=241572, mem_maxresident_kb=967332, user_cputime=893.29, sys_cputime=6.90, percent_cpu=6435%
timeout=false
algorithm=natarajan_ext_bst_lf.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1