__step=000028
__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=data000028.txt
__path_data=data/data000028.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219794.293650151
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.037s
prefilling_size=5632046 prefilling_elapsed_millis=1s
prefilling_size=5984290 prefilling_elapsed_millis=1.101s
prefilling_size=6302079 prefilling_elapsed_millis=1.201s
prefilling_size=6599231 prefilling_elapsed_millis=1.301s
prefilling_size=6872397 prefilling_elapsed_millis=1.401s
prefilling_size=7122565 prefilling_elapsed_millis=1.501s
prefilling_size=7351451 prefilling_elapsed_millis=1.601s
prefilling_size=7561593 prefilling_elapsed_millis=1.701s
prefilling_size=7758341 prefilling_elapsed_millis=1.801s
prefilling_size=7937442 prefilling_elapsed_millis=1.901s
prefilling_size=8100759 prefilling_elapsed_millis=2.002s
prefilling_size=8252212 prefilling_elapsed_millis=2.102s
prefilling_size=8395600 prefilling_elapsed_millis=2.202s
prefilling_size=8525879 prefilling_elapsed_millis=2.302s
prefilling_size=8643572 prefilling_elapsed_millis=2.402s
prefilling_size=8751187 prefilling_elapsed_millis=2.502s
prefilling_size=8851764 prefilling_elapsed_millis=2.602s
prefilling_size=8943491 prefilling_elapsed_millis=2.702s
prefilling_size=9029027 prefilling_elapsed_millis=2.803s
prefilling_size=9106924 prefilling_elapsed_millis=2.903s
prefilling_size=9178131 prefilling_elapsed_millis=3.003s
prefilling_size=9242981 prefilling_elapsed_millis=3.103s
prefilling_size=9303767 prefilling_elapsed_millis=3.204s
prefilling_size=9358999 prefilling_elapsed_millis=3.304s
prefilling_size=9409688 prefilling_elapsed_millis=3.404s
prefilling_size=9456698 prefilling_elapsed_millis=3.504s
prefilling_size=9499745 prefilling_elapsed_millis=3.604s
prefilling_size=9539540 prefilling_elapsed_millis=3.704s
prefilling_size=9576134 prefilling_elapsed_millis=3.804s
prefilling_size=9610661 prefilling_elapsed_millis=3.904s
prefilling_size=9640214 prefilling_elapsed_millis=4.005s
prefilling_size=9668563 prefilling_elapsed_millis=4.105s
prefilling_size=9694219 prefilling_elapsed_millis=4.205s
prefilling_size=9718687 prefilling_elapsed_millis=4.305s
prefilling_size=9742417 prefilling_elapsed_millis=4.405s
prefilling_size=9762490 prefilling_elapsed_millis=4.505s
prefilling_size=9780187 prefilling_elapsed_millis=4.605s
prefilling_size=9796234 prefilling_elapsed_millis=4.705s
prefilling_size=9812754 prefilling_elapsed_millis=4.806s
finished prefilling to size 9812760 for expected size 10000000 keysum=98143975218155, performing 83036512 updates; total_prefilling_elapsed_ms=4907 ms)
pref_size=9812760
pref_millis=4907
prefill_elapsed_ms=4920
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=219799.322899856
REALTIME_END_PERF_FORMAT=219809.322981487

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

tree_stats_computeWalltime=0.061s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131066 261818 514943 932482 1350542 1330743 788862 246138 32015 1054 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 6 286 7700 82794 417386 1060036 1382743 941068 310189 40588 1279
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262104 522643 1015276 1767928 2390779 2171605 1187206 342204 41642 1279
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262104 522643 1015276 1767928 2390779 2171605 1187206 342204 41642 1279
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99974 1.99512 1.95731 1.82288 1.58839 1.35171 1.18343 1.08092 1.02813 1.0054 1

tree_stats_height=28
tree_stats_numInternals=5720734
tree_stats_numLeaves=4244075
tree_stats_numNodes=9964809
tree_stats_numKeys=9964809

tree_stats_avgDegreeInternal=1.74188
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42591
tree_stats_avgKeyDepth=21.775


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=305792
first_thread_announced_epoch_by_thread=5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848 5848
sum_num_inserts_by_thread=147952 147981 147767 146971 147964 148520 148383 147735 147609 147282 147942 147495 147140 147952 147298 147835 148609 148378 148656 149436 149358 147478 148112 147416 147837 147043 147585 146767 148580 147565 147368 147646 148489 148209 148723 147824 147365 148575 147962 148711 148075 148057 146990 148027 147522 147590 148487 148370 148123 147937 147851 147008 148198 147593 147072 147656 147945 147547 147826 148001 148138 147375 147571 147965 147377 148146 147511 147696 147755 147456 147263 147789 147868 147347 147775 147065 147030 147538 147641 147150 147797 147954 147284 146929 147736 148087 148315 147561 148238 147577 146799 147146 147883 147939 146664 147398 147666 147145 148205 147534 147320 149127 147456 148171 148475 148548 148220 147151 147508 147837 147846 147504 148108 147380 146354 147336 148859 147970 147215 147951 147617 148283 146704 147714 148234 148463 147466 148403
sum_num_inserts_total=18915926
sum_num_deletes_by_thread=147329 148359 147679 147372 147904 147858 147771 147923 146986 147525 147830 147612 147281 147312 148236 147773 148858 148621 148302 148101 149053 148286 148556 147523 147888 147809 148304 146489 148389 148044 147831 147789 149150 148638 148699 147828 148572 148413 147645 149091 148122 147976 147252 147631 147409 147960 147972 148504 147524 147644 147647 147261 148684 148549 147082 148127 148175 147956 148004 148150 148690 148011 147431 148283 147717 147970 146987 147100 147593 147662 147096 147756 147350 148016 147955 147303 147186 147133 147547 147475 147652 148276 147336 147295 147748 147889 148460 147745 147138 148379 147645 147568 148268 147827 146990 146988 148277 147784 148059 147474 147710 149111 148414 148277 149108 148064 148430 147152 147049 147429 148472 148282 148549 147728 146754 147779 148040 148147 148554 147605 148290 149281 147408 147693 147893 148218 147736 147090
sum_num_deletes_total=18929580
sum_num_searches_by_thread=2659967 2661781 2661031 2655638 2660963 2670992 2657465 2657335 2652299 2653386 2658079 2662664 2651289 2661049 2661906 2661545 2678746 2668990 2666594 2672544 2682796 2667652 2669445 2667046 2668581 2657952 2663269 2649142 2665867 2661421 2654139 2651786 2681598 2681176 2669151 2665480 2661107 2674467 2659563 2669636 2667855 2659106 2657202 2660528 2659481 2667911 2665029 2675950 2662867 2663005 2666209 2646342 2671095 2666844 2649381 2661425 2665245 2654604 2666668 2669086 2672742 2659864 2656018 2661827 2655036 2658156 2646138 2659177 2654457 2658277 2646756 2659064 2655361 2666892 2662171 2650160 2654204 2656397 2651173 2655363 2657080 2656853 2654326 2651296 2659761 2668598 2675416 2661683 2659675 2671174 2655504 2654303 2659799 2661704 2644905 2654126 2665351 2654437 2656219 2658204 2664793 2680313 2663092 2663019 2671697 2663021 2674008 2651513 2651325 2657283 2665486 2665340 2661975 2647577 2645402 2651366 2670601 2656047 2658361 2658253 2656855 2673401 2654281 2663024 2660820 2675534 2661237 2661487
sum_num_searches_total=340658128
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2955248 2958121 2956477 2949981 2956831 2967370 2953619 2952993 2946894 2948193 2953851 2957771 2945710 2956313 2957440 2957153 2976213 2965989 2963552 2970081 2981207 2963416 2966113 2961985 2964306 2952804 2959158 2942398 2962836 2957030 2949338 2947221 2979237 2978023 2966573 2961132 2957044 2971455 2955170 2967438 2964052 2955139 2951444 2956186 2954412 2963461 2961488 2972824 2958514 2958586 2961707 2940611 2967977 2962986 2943535 2957208 2961365 2950107 2962498 2965237 2969570 2955250 2951020 2958075 2950130 2954272 2940636 2953973 2949805 2953395 2941115 2954609 2950579 2962255 2957901 2944528 2948420 2951068 2946361 2949988 2952529 2953083 2948946 2945520 2955245 2964574 2972191 2956989 2955051 2967130 2949948 2949017 2955950 2957470 2938559 2948512 2961294 2949366 2952483 2953212 2959823 2978551 2958962 2959467 2969280 2959633 2970658 2945816 2945882 2952549 2961804 2961126 2958632 2942685 2938510 2946481 2967500 2952164 2954130 2953809 2952762 2970965 2948393 2958431 2956947 2972215 2956439 2956980
average_num_operations_total=2957059
stdev_num_operations_total=788
sum_num_operations_total=378503634
min_num_operations_total=2938510
max_num_operations_total=2981207
first_time_thread_terminate_by_thread=10000085 10000085 10000085 10000085 10000081 10000081 10000081 10000081 10000085 10000085 10000085 10000085 10000081 10000081 10000081 10000081 10000084 10000084 10000084 10000084 10000083 10000083 10000083 10000083 10000085 10000082 10000087 10000082 10000084 10000084 10000084 10000084 10000082 10000082 10000082 10000082 10000084 10000084 10000084 10000084 10000081 10000081 10000081 10000081 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000084 10000083 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000082 10000085 10000085 10000085 10000085 10000081 10000081 10000081 10000083 10000085 10000085 10000085 10000085 10000081 10000081 10000081 10000081 10000084 10000084 10000084 10000084 10000083 10000083 10000083 10000083 10000082 10000082 10000082 10000082 10000084 10000084 10000084 10000084 10000081 10000082 10000127 10000084 10000084 10000084 10000084 10000084 10000081 10000081 10000081 10000081 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000083 10000084 10000083 10000082 10000082 10000082 10000082 10000085 10000082 10000082 10000082
min_time_thread_terminate_total=10000081
max_time_thread_terminate_total=10000127
first_time_thread_start_by_thread=7 7 7 7 9 9 9 9 8 8 8 8 9 9 9 9 7 7 7 7 8 8 8 8 8 8 7 8 10 9 9 9 7 7 6 7 10 10 9 10 9 9 9 9 9 10 9 9 8 8 8 8 10 10 10 10 10 10 10 10 10 10 10 10 7 7 7 7 9 8 9 9 8 8 8 8 9 9 9 9 7 7 7 7 8 8 8 8 8 8 7 8 10 9 9 9 7 7 10 7 10 10 9 10 9 9 9 9 9 10 9 9 8 8 8 8 10 10 10 10 10 10 10 10 10 10 10 10
min_time_thread_start_total=6
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=99614639030023
threads_final_size=9964809
final_keysum=99614639030023
final_size=9964809
validate_result=success
Validation OK.
Structural validation OK.

total_find=340658128
total_rq=0
total_inserts=18915926
total_deletes=18929580
total_updates=37845506
total_queries=340658128
total_ops=378503634
find_throughput=34065812
rq_throughput=0
update_throughput=3784550
query_throughput=34065812
total_throughput=37850363


total find                    : 340658128
total rq                      : 0
total inserts                 : 18915926
total deletes                 : 18929580
total updates                 : 37845506
total queries                 : 340658128
total ops                     : 378503634
find throughput               : 34065812
rq throughput                 : 0
update throughput             : 3784550
query throughput              : 34065812
total throughput              : 37850363

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=224083957558251
total_execution_walltime=15.213s
REALTIME_MAIN_END_PERF_FORMAT=219809.507503516
TL2 system shutdown:
  GCLOCK=0x333EF48 Starts=486771889 Aborts=25231742
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.44, faults_major=0, faults_minor=946282, mem_maxresident_kb=2057528, user_cputime=1828.28, sys_cputime=98.02, percent_cpu=12474%
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=2