__step=000010
__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_int_bst_tm_auto.debra.tl2 -nwork 64 -nprefill 64 -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=data000010.txt
__path_data=data/data000010.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219294.563855453
binary=./brown_int_bst_tm_auto.debra.tl2
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=brown_int_bst_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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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=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


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.024s
prefilling_size=5345982 prefilling_elapsed_millis=1s
prefilling_size=5676629 prefilling_elapsed_millis=1.101s
prefilling_size=5986655 prefilling_elapsed_millis=1.201s
prefilling_size=6273383 prefilling_elapsed_millis=1.301s
prefilling_size=6540572 prefilling_elapsed_millis=1.401s
prefilling_size=6785142 prefilling_elapsed_millis=1.502s
prefilling_size=7013173 prefilling_elapsed_millis=1.602s
prefilling_size=7226417 prefilling_elapsed_millis=1.702s
prefilling_size=7424594 prefilling_elapsed_millis=1.802s
prefilling_size=7607728 prefilling_elapsed_millis=1.903s
prefilling_size=7779039 prefilling_elapsed_millis=2.003s
prefilling_size=7935801 prefilling_elapsed_millis=2.103s
prefilling_size=8083385 prefilling_elapsed_millis=2.203s
prefilling_size=8220430 prefilling_elapsed_millis=2.304s
prefilling_size=8348153 prefilling_elapsed_millis=2.404s
prefilling_size=8466829 prefilling_elapsed_millis=2.504s
prefilling_size=8576338 prefilling_elapsed_millis=2.604s
prefilling_size=8677299 prefilling_elapsed_millis=2.705s
prefilling_size=8770279 prefilling_elapsed_millis=2.805s
prefilling_size=8858640 prefilling_elapsed_millis=2.905s
prefilling_size=8941485 prefilling_elapsed_millis=3.006s
prefilling_size=9016906 prefilling_elapsed_millis=3.106s
prefilling_size=9086484 prefilling_elapsed_millis=3.206s
prefilling_size=9150481 prefilling_elapsed_millis=3.307s
prefilling_size=9211573 prefilling_elapsed_millis=3.407s
prefilling_size=9267478 prefilling_elapsed_millis=3.507s
prefilling_size=9321087 prefilling_elapsed_millis=3.607s
prefilling_size=9368767 prefilling_elapsed_millis=3.707s
prefilling_size=9412782 prefilling_elapsed_millis=3.808s
prefilling_size=9454308 prefilling_elapsed_millis=3.908s
prefilling_size=9493143 prefilling_elapsed_millis=4.008s
prefilling_size=9530521 prefilling_elapsed_millis=4.108s
prefilling_size=9563471 prefilling_elapsed_millis=4.209s
prefilling_size=9595875 prefilling_elapsed_millis=4.309s
prefilling_size=9623965 prefilling_elapsed_millis=4.409s
prefilling_size=9649832 prefilling_elapsed_millis=4.509s
prefilling_size=9675338 prefilling_elapsed_millis=4.61s
prefilling_size=9697580 prefilling_elapsed_millis=4.71s
prefilling_size=9717690 prefilling_elapsed_millis=4.81s
prefilling_size=9737700 prefilling_elapsed_millis=4.91s
prefilling_size=9757356 prefilling_elapsed_millis=5.011s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 242579 keys with 64 threads...
tid=0 thread_prefill_amount=153125 percent_done_estimate=4039.9 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.0423951s
prefilling_size=10000000 prefilling_elapsed_millis=0.044s
finished prefilling to size 10000000 for expected size 10000000 keysum=100001590836136, performing 74947103 updates; total_prefilling_elapsed_ms=44 ms)
pref_size=10000000
pref_millis=44
prefill_elapsed_ms=44
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=1925

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=219299.782882029
REALTIME_END_PERF_FORMAT=219309.782952545

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

tree_stats_computeWalltime=0.122s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 252 494 957 1815 3399 6316 11344 19560 32148 50870 76782 110938 152989 202310 256853 313865 369468 418114 455920 479980 489020 480248 455384 416407 370095 318058 264393 213335 167271 126807 93187 66682 45488 30397 19632 12144 7374 4433 2549 1413 774 439 228 102 32 13 5 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 2 5 14 46 126 264 707 1659 3789 7298 13677 22917 37070 55800 79064 107002 137155 169771 201439 228345 248649 262375 265637 259348 242585 221106 194610 164474 134628 108488 83493 62003 45378 31327 21195 14037 8792 5295 3256 1955 1052 571 334 174 93 26 9 2 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 254 499 971 1861 3525 6580 12051 21219 35937 58168 90459 133855 190059 258110 335917 420867 506623 587885 657359 708325 737669 742623 721021 675755 612680 539164 459003 377809 301899 235295 176680 128685 90866 61724 40827 26181 16166 9728 5805 3368 1826 1010 562 276 125 39 14 5 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 254 499 971 1861 3525 6580 12051 21219 35937 58168 90459 133855 190059 258110 335917 420867 506623 587885 657359 708325 737669 742623 721021 675755 612680 539164 459003 377809 301899 235295 176680 128685 90866 61724 40827 26181 16166 9728 5805 3368 1826 1010 562 276 125 39 14 5 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.97244 1.95591 1.931 1.91886 1.90241 1.87158 1.81943 1.77181 1.72404 1.6806 1.63093 1.59109 1.5531 1.51764 1.48826 1.458 1.43112 1.40696 1.38397 1.3638 1.34379 1.32422 1.30564 1.29045 1.27595 1.26141 1.24709 1.23441 1.22532 1.21196 1.20092 1.18793 1.17868 1.16898 1.16041 1.15362 1.14561 1.14104 1.14109 1.12262 1.12924 1.12178 1.08541 1.08333 1.056 1.02564 1 1 1

tree_stats_height=57
tree_stats_numInternals=6550541
tree_stats_numLeaves=3447046
tree_stats_numNodes=9997587
tree_stats_numKeys=9997587

tree_stats_avgDegreeInternal=1.52622
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34479
tree_stats_avgKeyDepth=28.8896

4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0 

global_epoch_counter=8468


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:25 2:69 4:5301 8:216644 16:196395 32:47 64:0 128:126
    [2^00, 2^01]: 25
    (2^01, 2^02]: 69
    (2^02, 2^03]: 5301
    (2^03, 2^04]: 216644
    (2^04, 2^05]: 196395
    (2^05, 2^06]: 47
    (2^06, 2^07]: 0
    (2^07, 2^08]: 126
sum_limbo_reclamation_event_size_total=6452825
count_limbo_reclamation_event_size_by_thread=6541 6540 6541 6541 6541 6541 6541 6541 6540 6541 6541 6541 6541 6541 6541 6541 6541 6541 6541 6540 6541 6540 6540 6540 6540 6540 6541 6540 6541 6541 6541 6541 6541 6541 6541 6541 6540 6541 6541 6541 6540 6541 6541 6540 6540 6541 6541 6541 6540 6541 6541 6541 6541 6541 6541 6541 6540 6541 6541 6541 6540 6541 6540 6541
count_limbo_reclamation_event_size_total=418607
sum_limbo_reclamation_event_count_total=418769
first_thread_announced_epoch_by_thread=16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936 16936
sum_num_inserts_by_thread=203854 203458 202713 201726 203483 200732 201577 201164 200676 200345 201604 201055 202472 201328 201555 201591 201794 201459 201034 200570 201360 201018 199681 199860 202151 201491 201676 201707 202759 201645 201593 201110 200590 200759 201531 201173 201109 200545 203737 202413 203599 202584 202782 202153 203242 201076 201556 200766 200339 199902 199074 200117 203439 202344 201608 201137 202582 201990 202229 201781 202333 201070 202010 200508
sum_num_inserts_total=12902319
sum_num_deletes_by_thread=204012 204277 201409 200543 203613 201878 200967 201369 201692 200630 201285 201589 202489 201932 202061 201172 202085 201797 201287 200806 200674 200385 199873 199521 201708 202124 201026 201025 202276 202270 201344 200652 201743 201370 201551 200897 202199 201415 203952 203313 203529 203622 203083 202691 202773 202506 201415 200871 200200 200510 199292 200814 202923 203388 201383 201521 201730 201986 201598 202198 202417 202257 201436 200782
sum_num_deletes_total=12911136
sum_num_searches_by_thread=3674018 3670230 3643657 3622255 3647133 3631380 3626158 3629737 3619791 3612035 3632979 3624386 3644457 3631299 3633483 3620892 3632586 3625257 3616514 3607806 3618523 3603828 3594065 3597650 3638796 3632348 3618948 3618849 3639416 3639229 3629360 3616252 3629343 3622813 3624884 3608109 3629242 3616955 3666617 3649518 3660352 3649444 3647163 3646556 3644671 3633948 3624538 3612701 3603922 3598795 3589603 3594374 3658918 3653825 3628904 3629290 3638105 3634188 3635918 3636682 3639305 3617267 3626996 3621874
sum_num_searches_total=232268137
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=4081884 4077965 4047779 4024524 4054229 4033990 4028702 4032270 4022159 4013010 4035868 4027030 4049418 4034559 4037099 4023655 4036465 4028513 4018835 4009182 4020557 4005231 3993619 3997031 4042655 4035963 4021650 4021581 4044451 4043144 4032297 4018014 4031676 4024942 4027966 4010179 4032550 4018915 4074306 4055244 4067480 4055650 4053028 4051400 4050686 4037530 4027509 4014338 4004461 3999207 3987969 3995305 4065280 4059557 4031895 4031948 4042417 4038164 4039745 4040661 4044055 4020594 4030442 4023164
average_num_operations_total=4032524
stdev_num_operations_total=2530
sum_num_operations_total=258081592
min_num_operations_total=3987969
max_num_operations_total=4081884
first_time_thread_terminate_by_thread=10000112 10000069 10000069 10000070 10000073 10000073 10000073 10000073 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000074 10000074 10000074 10000074 10000070 10000070 10000070 10000070 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000070 10000070 10000070 10000070 10000073 10000073 10000073 10000073 10000070 10000070 10000070 10000070 10000071 10000071 10000072 10000072 10000071 10000071 10000071 10000071
min_time_thread_terminate_total=10000069
max_time_thread_terminate_total=10000112
first_time_thread_start_by_thread=7 2 2 3 4 4 4 4 3 3 3 3 3 3 3 3 4 4 4 4 4 4 4 4 5 5 5 5 6 6 5 6 5 4 4 5 5 5 5 5 6 6 6 6 5 5 5 5 7 7 7 7 6 7 7 7 6 6 6 6 6 6 6 6
min_time_thread_start_total=2
max_time_thread_start_total=7
sum_duration_all_ops_total=

threads_final_keysum=99968905122280
threads_final_size=9997587
final_keysum=99968905122280
final_size=9997587
validate_result=success
Validation OK.
Structural validation OK.

total_find=232268137
total_rq=0
total_inserts=12902319
total_deletes=12911136
total_updates=25813455
total_queries=232268137
total_ops=258081592
find_throughput=23226813
rq_throughput=0
update_throughput=2581345
query_throughput=23226813
total_throughput=25808159


total find                    : 232268137
total rq                      : 0
total inserts                 : 12902319
total deletes                 : 12911136
total updates                 : 25813455
total queries                 : 232268137
total ops                     : 258081592
find throughput               : 23226813
rq throughput                 : 0
update throughput             : 2581345
query throughput              : 23226813
total throughput              : 25808159

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

garbage=345088769711429
total_execution_walltime=15.493s
REALTIME_MAIN_END_PERF_FORMAT=219310.57754811
TL2 system shutdown:
  GCLOCK=0x2C6F16E Starts=333428097 Aborts=870538
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.62, faults_major=0, faults_minor=240272, mem_maxresident_kb=635532, user_cputime=943.32, sys_cputime=35.13, percent_cpu=6264%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.tl2
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2