__step=000714
__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" ./guerraoui_ext_bst_ticket.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=data000714.txt
__path_data=data/data000714.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=215436.820950178
binary=./guerraoui_ext_bst_ticket.debra
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=guerraoui_ext_bst_ticket
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=40
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.024s
prefilling_size=7316572 prefilling_elapsed_millis=1s
prefilling_size=7589500 prefilling_elapsed_millis=1.101s
prefilling_size=7832192 prefilling_elapsed_millis=1.201s
prefilling_size=8045990 prefilling_elapsed_millis=1.301s
prefilling_size=8235955 prefilling_elapsed_millis=1.401s
prefilling_size=8406390 prefilling_elapsed_millis=1.501s
prefilling_size=8558967 prefilling_elapsed_millis=1.601s
prefilling_size=8696588 prefilling_elapsed_millis=1.701s
prefilling_size=8817771 prefilling_elapsed_millis=1.801s
prefilling_size=8929600 prefilling_elapsed_millis=1.902s
prefilling_size=9029213 prefilling_elapsed_millis=2.002s
prefilling_size=9119388 prefilling_elapsed_millis=2.102s
prefilling_size=9201405 prefilling_elapsed_millis=2.202s
prefilling_size=9275972 prefilling_elapsed_millis=2.302s
prefilling_size=9342352 prefilling_elapsed_millis=2.402s
prefilling_size=9402607 prefilling_elapsed_millis=2.502s
prefilling_size=9455707 prefilling_elapsed_millis=2.602s
prefilling_size=9504454 prefilling_elapsed_millis=2.702s
prefilling_size=9549560 prefilling_elapsed_millis=2.803s
prefilling_size=9588644 prefilling_elapsed_millis=2.903s
prefilling_size=9626873 prefilling_elapsed_millis=3.003s
prefilling_size=9658702 prefilling_elapsed_millis=3.103s
prefilling_size=9689030 prefilling_elapsed_millis=3.204s
prefilling_size=9717223 prefilling_elapsed_millis=3.304s
prefilling_size=9740820 prefilling_elapsed_millis=3.404s
prefilling_size=9762579 prefilling_elapsed_millis=3.504s
prefilling_size=9785266 prefilling_elapsed_millis=3.604s
prefilling_size=9805206 prefilling_elapsed_millis=3.704s
finished prefilling to size 9805251 for expected size 10000000 keysum=98039355750511, performing 79549761 updates; total_prefilling_elapsed_ms=3805 ms)
pref_size=9805251
pref_millis=3805
prefill_elapsed_ms=3824
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=2067

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=215440.682040698
REALTIME_END_PERF_FORMAT=215450.682127083

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

tree_stats_computeWalltime=0.23s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 252 498 985 1926 3627 6564 11543 19262 30879 47231 70020 100479 139466 187869 246119 311283 382911 457304 532258 602369 661488 704730 726296 722681 694142 642522 574748 497297 414439 334264 259288 194026 141172 99098 67219 44532 28335 17404 10463 6067 3303 1889 997 556 264 126 46 17 4 4 6 4 3 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 4 6 11 44 225 690 1585 3824 7645 14527 24442 39561 61492 91063 129619 180955 239655 308518 382350 462147 543250 618246 683164 729911 751220 745762 710296 652199 580155 494614 409240 324550 246880 183246 130977 89906 60729 39266 24345 14859 8831 4717 2781 1438 848 402 206 75 30 4 2 8 5 4 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 504 996 1970 3852 7254 13128 23086 38524 61758 94462 140040 200958 278932 375738 492238 622566 765822 914608 1064516 1204738 1322976 1409460 1452592 1445362 1388284 1285044 1149496 994594 828878 668528 518576 388052 282344 198196 134438 89064 56670 34808 20926 12134 6606 3778 1994 1112 528 252 92 34 8 8 12 8 6 4
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 4 6 11 44 224 690 1585 3824 7645 14527 24442 39561 61492 91063 129619 180955 239655 308518 382350 462147 543250 618246 683164 729911 751220 745762 710296 652199 580155 494614 409240 324550 246880 183246 130977 89906 60729 39266 24345 14859 8831 4717 2781 1438 848 402 206 75 30 4 2 8 5 4 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.98438 1.9881 1.98896 1.97766 1.94133 1.90488 1.87927 1.83436 1.80155 1.76478 1.74125 1.7175 1.69401 1.67353 1.65503 1.63238 1.61505 1.59714 1.58195 1.56586 1.54907 1.53269 1.5153 1.49751 1.48025 1.46282 1.44726 1.43262 1.41669 1.40327 1.38785 1.37415 1.3638 1.35098 1.33915 1.33125 1.31814 1.30711 1.30059 1.28993 1.27221 1.28595 1.2639 1.27884 1.23741 1.23864 1.18254 1.18478 1.11765 1.5 1.75 1.33333 1.375 1.33333 1

tree_stats_height=63
tree_stats_numInternals=10000532
tree_stats_numLeaves=10000533
tree_stats_numNodes=20001065
tree_stats_numKeys=10000532

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

tree_stats_bytesAtDepth=40 80 160 320 640 1280 2560 5120 10240 20160 39840 78800 154080 290160 525120 923440 1540960 2470320 3778480 5601600 8038320 11157280 15029520 19689520 24902640 30632880 36584320 42580640 48189520 52919040 56378400 58103680 57814480 55531360 51401760 45979840 39783760 33155120 26741120 20743040 15522080 11293760 7927840 5377520 3562560 2266800 1392320 837040 485360 264240 151120 79760 44480 21120 10080 3680 1360 320 320 480 320 240 160
tree_stats_sizeInBytes=800042600

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:1 16:3 32:8 64:107 128:13499 256:302700 512:157 1024:68
    (2^03, 2^04]: 1
    (2^04, 2^05]: 3
    (2^05, 2^06]: 8
    (2^06, 2^07]: 107
    (2^07, 2^08]: 13499
    (2^08, 2^09]: 302700
    (2^09, 2^10]: 157
    (2^10, 2^11]: 68
sum_limbo_reclamation_event_size_total=93624314
count_limbo_reclamation_event_size_by_thread=4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4945 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946 4946
count_limbo_reclamation_event_size_total=316543
sum_limbo_reclamation_event_count_total=316672
first_thread_announced_epoch_by_thread=14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030 14030
sum_num_inserts_by_thread=1477804 1477396 1475227 1466984 1469249 1471365 1462310 1462031 1465202 1466377 1458686 1471822 1472818 1474246 1469524 1467675 1469803 1471214 1462289 1459407 1461628 1464199 1455001 1456688 1456944 1468001 1467199 1465501 1468209 1468547 1464883 1463110 1469951 1468703 1462237 1464375 1467079 1467796 1462822 1474110 1474942 1476930 1470589 1472877 1475413 1477766 1465690 1464439 1456700 1456909 1452436 1454889 1455912 1466868 1463824 1461131 1467390 1468661 1464032 1466248 1469670 1471108 1468202 1464078
sum_num_inserts_total=93855116
sum_num_deletes_by_thread=1479369 1478102 1474101 1468829 1470897 1469382 1466557 1464704 1465927 1465215 1461915 1469550 1472102 1473189 1470085 1469884 1469718 1472025 1461952 1458430 1459094 1460859 1455629 1456453 1457479 1468256 1466029 1465529 1467950 1467755 1464363 1464627 1472274 1469288 1460635 1463465 1465378 1468991 1464022 1475363 1476196 1478212 1470145 1473981 1475785 1476206 1464926 1464171 1457275 1457822 1451552 1449626 1455240 1470643 1463157 1463579 1465996 1467577 1463307 1465012 1469909 1471621 1466877 1467596
sum_num_deletes_total=93861783
sum_num_searches_by_thread=
sum_num_searches_total=
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2957173 2955498 2949328 2935813 2940146 2940747 2928867 2926735 2931129 2931592 2920601 2941372 2944920 2947435 2939609 2937559 2939521 2943239 2924241 2917837 2920722 2925058 2910630 2913141 2914423 2936257 2933228 2931030 2936159 2936302 2929246 2927737 2942225 2937991 2922872 2927840 2932457 2936787 2926844 2949473 2951138 2955142 2940734 2946858 2951198 2953972 2930616 2928610 2913975 2914731 2903988 2904515 2911152 2937511 2926981 2924710 2933386 2936238 2927339 2931260 2939579 2942729 2935079 2931674
average_num_operations_total=2933076
stdev_num_operations_total=1565
sum_num_operations_total=187716899
min_num_operations_total=2903988
max_num_operations_total=2957173
first_time_thread_terminate_by_thread=10000089 10000089 10000089 10000089 10000086 10000087 10000086 10000086 10000088 10000089 10000089 10000089 10000088 10000088 10000088 10000088 10000087 10000087 10000087 10000087 10000089 10000089 10000089 10000089 10000087 10000087 10000087 10000087 10000086 10000086 10000086 10000086 10000088 10000088 10000092 10000088 10000085 10000086 10000085 10000086 10000087 10000087 10000087 10000087 10000086 10000086 10000087 10000086 10000086 10000086 10000086 10000086 10000088 10000088 10000088 10000088 10000086 10000086 10000086 10000085 10000087 10000087 10000087 10000087
min_time_thread_terminate_total=10000085
max_time_thread_terminate_total=10000092
first_time_thread_start_by_thread=7 7 7 7 7 7 7 7 8 8 8 8 7 7 7 7 7 7 7 7 6 6 6 6 7 6 6 6 8 8 8 8 9 9 9 9 5 5 5 5 9 9 9 9 6 6 6 6 7 8 8 8 5 5 5 5 6 6 6 6 8 8 8 8
min_time_thread_start_total=5
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100008239158624
threads_final_size=10000532
final_keysum=100008239158624
final_size=10000532
validate_result=success
Validation OK.
Structural validation OK.

total_find=0
total_rq=0
total_inserts=93855116
total_deletes=93861783
total_updates=187716899
total_queries=0
total_ops=187716899
find_throughput=0
rq_throughput=0
update_throughput=18771689
query_throughput=0
total_throughput=18771689


total find                    : 0
total rq                      : 0
total inserts                 : 93855116
total deletes                 : 93861783
total updates                 : 187716899
total queries                 : 0
total ops                     : 187716899
find throughput               : 0
rq throughput                 : 0
update throughput             : 18771689
query throughput              : 0
total throughput              : 18771689

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

garbage=0
total_execution_walltime=14.144s
REALTIME_MAIN_END_PERF_FORMAT=215450.966249950
[time_cmd_output] time_elapsed_sec=14.31, faults_major=0, faults_minor=316655, mem_maxresident_kb=1268012, user_cputime=913.39, sys_cputime=7.04, percent_cpu=6428%
timeout=false
algorithm=guerraoui_ext_bst_ticket.debra
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2