__step=000027
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 180 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" ./brown_sigouin_int_avl_tm_auto.debra.tl2 -nwork 128 -nprefill 128 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000027.txt
__path_data=data/data000027.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219778.606871312
binary=./brown_sigouin_int_avl_tm_auto.debra.tl2
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=brown_sigouin_int_avl_tm_auto
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=128
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=128
WORK_THREADS=128
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=48
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,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167,168,169,170,171,172,173,174,175,176,177,178,179,180,181,182,183,184,185,186,187,188,189,190,191


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.059s
prefilling_size=5142114 prefilling_elapsed_millis=1s
prefilling_size=5495418 prefilling_elapsed_millis=1.101s
prefilling_size=5826480 prefilling_elapsed_millis=1.201s
prefilling_size=6130505 prefilling_elapsed_millis=1.301s
prefilling_size=6415178 prefilling_elapsed_millis=1.401s
prefilling_size=6677534 prefilling_elapsed_millis=1.501s
prefilling_size=6920853 prefilling_elapsed_millis=1.601s
prefilling_size=7146658 prefilling_elapsed_millis=1.701s
prefilling_size=7358221 prefilling_elapsed_millis=1.801s
prefilling_size=7555002 prefilling_elapsed_millis=1.902s
prefilling_size=7735482 prefilling_elapsed_millis=2.002s
prefilling_size=7903905 prefilling_elapsed_millis=2.102s
prefilling_size=8058826 prefilling_elapsed_millis=2.202s
prefilling_size=8201934 prefilling_elapsed_millis=2.302s
prefilling_size=8334239 prefilling_elapsed_millis=2.402s
prefilling_size=8458690 prefilling_elapsed_millis=2.502s
prefilling_size=8575216 prefilling_elapsed_millis=2.602s
prefilling_size=8682111 prefilling_elapsed_millis=2.703s
prefilling_size=8780799 prefilling_elapsed_millis=2.803s
prefilling_size=8872477 prefilling_elapsed_millis=2.903s
prefilling_size=8956333 prefilling_elapsed_millis=3.003s
prefilling_size=9033388 prefilling_elapsed_millis=3.104s
prefilling_size=9109119 prefilling_elapsed_millis=3.204s
prefilling_size=9177187 prefilling_elapsed_millis=3.304s
prefilling_size=9239249 prefilling_elapsed_millis=3.404s
prefilling_size=9296246 prefilling_elapsed_millis=3.504s
prefilling_size=9347856 prefilling_elapsed_millis=3.604s
prefilling_size=9397069 prefilling_elapsed_millis=3.704s
prefilling_size=9443209 prefilling_elapsed_millis=3.804s
prefilling_size=9485670 prefilling_elapsed_millis=3.905s
prefilling_size=9525053 prefilling_elapsed_millis=4.005s
prefilling_size=9559948 prefilling_elapsed_millis=4.105s
prefilling_size=9592696 prefilling_elapsed_millis=4.205s
prefilling_size=9623075 prefilling_elapsed_millis=4.305s
prefilling_size=9651876 prefilling_elapsed_millis=4.405s
prefilling_size=9677320 prefilling_elapsed_millis=4.505s
prefilling_size=9701044 prefilling_elapsed_millis=4.605s
prefilling_size=9721458 prefilling_elapsed_millis=4.706s
prefilling_size=9741454 prefilling_elapsed_millis=4.806s
prefilling_size=9761063 prefilling_elapsed_millis=4.906s
prefilling_size=9778443 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 221540 keys with 128 threads...
tid=0 thread_prefill_amount=76538 percent_done_estimate=4422.2 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0410477s
prefilling_size=10000000 prefilling_elapsed_millis=0.043s
finished prefilling to size 10000000 for expected size 10000000 keysum=99998375199308, performing 77039165 updates; total_prefilling_elapsed_ms=43 ms)
pref_size=10000000
pref_millis=43
prefill_elapsed_ms=43
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=219783.862893445
REALTIME_END_PERF_FORMAT=219793.862965527

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

tree_stats_computeWalltime=0.023s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 262021 519421 966840 1437339 1385575 723303 171326 13960 214 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 112 4133 62383 407714 1157084 1505142 887345 217362 17508 270
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523554 1029223 1845053 2542659 2228445 1058671 231322 17722 270
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262133 523554 1029223 1845053 2542659 2228445 1058671 231322 17722 270
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99993 1.99771 1.97373 1.85328 1.59907 1.33149 1.15049 1.05667 1.01626 1.00316 1

tree_stats_height=28
tree_stats_numInternals=5742140
tree_stats_numLeaves=4259055
tree_stats_numNodes=10001195
tree_stats_numKeys=10001195

tree_stats_avgDegreeInternal=1.74172
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42585
tree_stats_avgKeyDepth=21.7017


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0
    [2^00, 2^01]: 0
sum_limbo_reclamation_event_size_total=
count_limbo_reclamation_event_size_by_thread=
count_limbo_reclamation_event_size_total=
sum_limbo_reclamation_event_count_total=284302
first_thread_announced_epoch_by_thread=5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436 5436
sum_num_inserts_by_thread=136971 137087 137044 136618 137130 137640 138063 137558 137508 137460 137064 137203 137125 136704 136508 136948 137426 137410 137502 137479 137553 137369 137775 136860 137270 137408 136810 136757 137574 137616 136695 137244 138041 137561 138689 137769 137396 137285 137787 137792 137026 137164 137528 137689 138562 137927 137842 138147 137892 137987 136989 135987 137028 136819 136269 136652 136325 136676 136894 136928 136303 136878 136794 136984 136666 137290 136646 136784 136893 137442 136653 136429 137535 137928 136920 136917 137540 137715 136529 136444 137506 136829 136865 137238 137026 137366 137671 137862 137746 137812 137424 136604 137029 137086 137038 136780 137983 136675 138101 137758 138635 138480 138541 138067 137770 137823 138422 136664 137130 137621 137307 138062 137189 136964 137032 137285 137169 136816 137480 136538 136525 136252 136157 136628 137486 137825 137330 137153
sum_num_inserts_total=17570070
sum_num_deletes_by_thread=137994 137787 137438 135853 137093 137548 137061 137137 136751 138043 136513 136768 136966 136983 137423 136572 137925 137980 137423 136944 137694 137579 137304 137388 136747 137018 136216 137165 136216 137150 137299 136956 138502 137736 138565 138661 137728 138098 137502 137583 138024 137255 136365 136878 138158 138192 137893 137791 137331 137425 136568 136560 136889 136667 136397 136731 136882 136672 136839 137156 137522 136882 136993 137592 136318 136707 136587 136199 136823 137389 136796 136314 137213 138763 137530 137168 137454 137488 136353 136995 137685 137505 136432 137151 137196 137489 136921 137424 136466 137831 137625 137116 137338 136621 136035 137113 137930 137611 137930 137705 137445 137924 138030 137528 137049 137780 137858 137428 137493 138114 136624 136909 136696 136890 137020 136715 136942 137651 136981 136627 136570 136709 137129 137107 136881 137293 137463 137390
sum_num_deletes_total=17564438
sum_num_searches_by_thread=2477829 2471843 2470237 2458563 2473490 2470334 2466586 2463341 2469150 2477749 2463127 2464514 2471495 2466482 2467024 2468872 2475516 2480743 2475886 2472540 2477255 2484222 2471082 2465107 2471904 2470950 2464243 2460136 2469959 2473965 2467428 2463454 2486202 2481378 2494908 2485962 2476554 2482568 2479304 2478915 2473428 2475110 2471695 2476872 2485667 2487964 2481553 2479900 2473247 2468974 2464965 2463476 2465969 2462322 2461179 2460757 2461285 2465007 2471299 2468392 2472321 2472143 2467611 2468569 2462426 2461276 2457721 2461586 2461316 2462464 2461298 2467910 2474043 2482477 2463813 2463826 2471404 2467592 2457616 2459178 2473185 2469658 2464931 2463322 2472929 2470893 2477123 2472821 2471708 2474123 2470459 2463975 2469371 2469315 2456448 2458612 2473450 2468349 2482396 2473707 2476982 2484673 2478798 2485354 2479670 2479583 2477637 2468507 2480758 2477834 2469137 2469426 2460681 2463962 2468103 2470487 2473035 2473136 2468303 2465691 2462511 2462972 2462008 2466381 2474269 2470151 2474929 2472734
sum_num_searches_total=316260955
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2752794 2746717 2744719 2731034 2747713 2745522 2741710 2738036 2743409 2753252 2736704 2738485 2745586 2740169 2740955 2742392 2750867 2756133 2750811 2746963 2752502 2759170 2746161 2739355 2745921 2745376 2737269 2734058 2743749 2748731 2741422 2737654 2762745 2756675 2772162 2762392 2751678 2757951 2754593 2754290 2748478 2749529 2745588 2751439 2762387 2764083 2757288 2755838 2748470 2744386 2738522 2736023 2739886 2735808 2733845 2734140 2734492 2738355 2745032 2742476 2746146 2745903 2741398 2743145 2735410 2735273 2730954 2734569 2735032 2737295 2734747 2740653 2748791 2759168 2738263 2737911 2746398 2742795 2730498 2732617 2748376 2743992 2738228 2737711 2747151 2745748 2751715 2748107 2745920 2749766 2745508 2737695 2743738 2743022 2729521 2732505 2749363 2742635 2758427 2749170 2753062 2761077 2755369 2760949 2754489 2755186 2753917 2742599 2755381 2753569 2743068 2744397 2734566 2737816 2742155 2744487 2747146 2747603 2742764 2738856 2735606 2735933 2735294 2740116 2748636 2745269 2749722 2747277
average_num_operations_total=2745277
stdev_num_operations_total=742
sum_num_operations_total=351395463
min_num_operations_total=2729521
max_num_operations_total=2772162
first_time_thread_terminate_by_thread=10000118 10000071 10000073 10000072 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000075 10000073 10000073 10000073 10000073 10000073 10000074 10000072 10000072 10000071 10000071 10000071 10000071 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000071 10000071 10000071 10000071 10000073 10000074 10000073 10000073 10000070 10000070 10000071 10000070 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000072 10000071 10000072 10000071 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000074 10000074 10000074 10000074 10000073 10000073 10000073 10000077 10000073 10000073 10000072 10000072 10000071 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000073 10000072 10000072 10000074 10000074 10000074 10000074 10000073 10000071 10000071 10000071 10000073 10000074 10000073 10000075 10000070 10000074 10000071 10000070 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074
min_time_thread_terminate_total=10000070
max_time_thread_terminate_total=10000118
first_time_thread_start_by_thread=9 4 4 4 4 4 4 4 6 6 6 6 6 6 6 6 5 7 5 5 5 5 5 5 5 5 5 5 5 5 4 4 5 5 5 5 6 6 6 6 6 5 6 6 7 7 7 8 7 7 7 7 8 8 8 8 7 7 7 7 7 7 7 7 4 4 4 4 4 4 4 4 6 6 6 6 6 6 6 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 4 5 5 5 5 6 6 6 6 6 5 6 6 7 7 7 7 7 7 7 7 8 8 8 8 7 7 7 7 7 7 7 7
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=100008117279622
threads_final_size=10001195
final_keysum=100008117279622
final_size=10001195
validate_result=success
Validation OK.
Structural validation OK.

total_find=316260955
total_rq=0
total_inserts=17570070
total_deletes=17564438
total_updates=35134508
total_queries=316260955
total_ops=351395463
find_throughput=31626095
rq_throughput=0
update_throughput=3513450
query_throughput=31626095
total_throughput=35139546


total find                    : 316260955
total rq                      : 0
total inserts                 : 17570070
total deletes                 : 17564438
total updates                 : 35134508
total queries                 : 316260955
total ops                     : 351395463
find throughput               : 31626095
rq throughput                 : 0
update throughput             : 3513450
query throughput              : 31626095
total throughput              : 35139546

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=185216913763063
total_execution_walltime=15.395s
REALTIME_MAIN_END_PERF_FORMAT=219794.3255009
TL2 system shutdown:
  GCLOCK=0x294CA1C Starts=446177943 Aborts=18178584
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.62, faults_major=0, faults_minor=894936, mem_maxresident_kb=1963012, user_cputime=1855.88, sys_cputime=89.90, percent_cpu=12455%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.tl2
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1