__step=000034
__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_abtree_tm_auto.debra.norec -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=data000034.txt
__path_data=data/data000034.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219908.171673071
binary=./brown_abtree_tm_auto.debra.norec
parsed custom binding: 0-63,128-191,64-127,192-255
DS_TYPENAME=brown_abtree_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=200
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
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=367326 prefilling_elapsed_millis=1s
prefilling_size=402246 prefilling_elapsed_millis=1.101s
prefilling_size=436837 prefilling_elapsed_millis=1.201s
prefilling_size=471261 prefilling_elapsed_millis=1.301s
prefilling_size=505721 prefilling_elapsed_millis=1.401s
prefilling_size=539913 prefilling_elapsed_millis=1.501s
prefilling_size=573929 prefilling_elapsed_millis=1.601s
prefilling_size=607713 prefilling_elapsed_millis=1.701s
prefilling_size=641479 prefilling_elapsed_millis=1.801s
prefilling_size=674974 prefilling_elapsed_millis=1.901s
prefilling_size=708408 prefilling_elapsed_millis=2.002s
prefilling_size=741736 prefilling_elapsed_millis=2.102s
prefilling_size=774948 prefilling_elapsed_millis=2.202s
prefilling_size=807931 prefilling_elapsed_millis=2.302s
prefilling_size=840824 prefilling_elapsed_millis=2.402s
prefilling_size=873418 prefilling_elapsed_millis=2.502s
prefilling_size=906011 prefilling_elapsed_millis=2.602s
prefilling_size=938349 prefilling_elapsed_millis=2.702s
prefilling_size=970726 prefilling_elapsed_millis=2.802s
prefilling_size=1002902 prefilling_elapsed_millis=2.902s
prefilling_size=1035037 prefilling_elapsed_millis=3.003s
prefilling_size=1067091 prefilling_elapsed_millis=3.103s
prefilling_size=1098908 prefilling_elapsed_millis=3.204s
prefilling_size=1130590 prefilling_elapsed_millis=3.304s
prefilling_size=1162070 prefilling_elapsed_millis=3.404s
prefilling_size=1193558 prefilling_elapsed_millis=3.504s
prefilling_size=1224835 prefilling_elapsed_millis=3.604s
prefilling_size=1256327 prefilling_elapsed_millis=3.704s
prefilling_size=1287415 prefilling_elapsed_millis=3.804s
prefilling_size=1318433 prefilling_elapsed_millis=3.904s
prefilling_size=1349147 prefilling_elapsed_millis=4.004s
prefilling_size=1380043 prefilling_elapsed_millis=4.104s
prefilling_size=1410626 prefilling_elapsed_millis=4.205s
prefilling_size=1441013 prefilling_elapsed_millis=4.305s
prefilling_size=1471448 prefilling_elapsed_millis=4.405s
prefilling_size=1501955 prefilling_elapsed_millis=4.505s
prefilling_size=1532210 prefilling_elapsed_millis=4.605s
prefilling_size=1562080 prefilling_elapsed_millis=4.705s
prefilling_size=1591897 prefilling_elapsed_millis=4.805s
prefilling_size=1621794 prefilling_elapsed_millis=4.905s
prefilling_size=1651741 prefilling_elapsed_millis=5.005s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8348206 keys with 64 threads...
tid=0 thread_prefill_amount=40625 percent_done_estimate=31.1 elapsed_s=5 est_remaining_s=27 / 32
tid=0 thread_prefill_amount=43750 percent_done_estimate=33.5 elapsed_s=6 est_remaining_s=26 / 31
tid=0 thread_prefill_amount=46875 percent_done_estimate=35.9 elapsed_s=6 est_remaining_s=25 / 31
tid=0 thread_prefill_amount=50000 percent_done_estimate=38.3 elapsed_s=7 est_remaining_s=24 / 30
tid=0 thread_prefill_amount=53125 percent_done_estimate=40.7 elapsed_s=7 est_remaining_s=23 / 30
tid=0 thread_prefill_amount=56250 percent_done_estimate=43.1 elapsed_s=7 est_remaining_s=22 / 29
tid=0 thread_prefill_amount=59375 percent_done_estimate=45.5 elapsed_s=8 est_remaining_s=21 / 29
tid=0 thread_prefill_amount=62500 percent_done_estimate=47.9 elapsed_s=8 est_remaining_s=20 / 28
tid=0 thread_prefill_amount=65625 percent_done_estimate=50.3 elapsed_s=9 est_remaining_s=19 / 28
tid=0 thread_prefill_amount=68750 percent_done_estimate=52.7 elapsed_s=9 est_remaining_s=18 / 27
tid=0 thread_prefill_amount=71875 percent_done_estimate=55.1 elapsed_s=9 est_remaining_s=17 / 27
tid=0 thread_prefill_amount=75000 percent_done_estimate=57.5 elapsed_s=10 est_remaining_s=16 / 26
tid=0 thread_prefill_amount=78125 percent_done_estimate=59.9 elapsed_s=10 est_remaining_s=15 / 26
tid=0 thread_prefill_amount=81250 percent_done_estimate=62.3 elapsed_s=11 est_remaining_s=15 / 25
tid=0 thread_prefill_amount=84375 percent_done_estimate=64.7 elapsed_s=11 est_remaining_s=14 / 25
tid=0 thread_prefill_amount=87500 percent_done_estimate=67.1 elapsed_s=11 est_remaining_s=13 / 24
tid=0 thread_prefill_amount=90625 percent_done_estimate=69.5 elapsed_s=12 est_remaining_s=12 / 24
tid=0 thread_prefill_amount=93750 percent_done_estimate=71.9 elapsed_s=12 est_remaining_s=11 / 23
tid=0 thread_prefill_amount=96875 percent_done_estimate=74.3 elapsed_s=13 est_remaining_s=10 / 23
tid=0 thread_prefill_amount=100000 percent_done_estimate=76.7 elapsed_s=13 est_remaining_s=9 / 22
tid=0 thread_prefill_amount=103125 percent_done_estimate=79.1 elapsed_s=14 est_remaining_s=8 / 22
tid=0 thread_prefill_amount=106250 percent_done_estimate=81.5 elapsed_s=14 est_remaining_s=7 / 21
tid=0 thread_prefill_amount=109375 percent_done_estimate=83.9 elapsed_s=14 est_remaining_s=6 / 21
tid=0 thread_prefill_amount=112500 percent_done_estimate=86.2 elapsed_s=15 est_remaining_s=5 / 20
tid=0 thread_prefill_amount=115625 percent_done_estimate=88.6 elapsed_s=15 est_remaining_s=4 / 20
tid=0 thread_prefill_amount=118750 percent_done_estimate=91.0 elapsed_s=16 est_remaining_s=3 / 19
tid=0 thread_prefill_amount=121875 percent_done_estimate=93.4 elapsed_s=16 est_remaining_s=3 / 19
tid=0 thread_prefill_amount=125000 percent_done_estimate=95.8 elapsed_s=16 est_remaining_s=2 / 18
tid=0 thread_prefill_amount=128125 percent_done_estimate=98.2 elapsed_s=17 est_remaining_s=1 / 17
tid=0 thread_prefill_amount=131250 percent_done_estimate=100.6 elapsed_s=17 est_remaining_s=-0 / 17
tid=0 thread_prefill_amount=134375 percent_done_estimate=103.0 elapsed_s=17 est_remaining_s=-1 / 16
tid=0 thread_prefill_amount=137500 percent_done_estimate=105.4 elapsed_s=18 est_remaining_s=-2 / 16
tid=0 thread_prefill_amount=140625 percent_done_estimate=107.8 elapsed_s=18 est_remaining_s=-3 / 15
tid=0 thread_prefill_amount=143750 percent_done_estimate=110.2 elapsed_s=18 est_remaining_s=-4 / 14
tid=0 thread_prefill_amount=146875 percent_done_estimate=112.6 elapsed_s=18 est_remaining_s=-5 / 14
tid=0 thread_prefill_amount=150000 percent_done_estimate=115.0 elapsed_s=18 est_remaining_s=-5 / 13
tid=0 thread_prefill_amount=153125 percent_done_estimate=117.4 elapsed_s=19 est_remaining_s=-6 / 12
tid=0 thread_prefill_amount=156250 percent_done_estimate=119.8 elapsed_s=19 est_remaining_s=-7 / 12
tid=0 thread_prefill_amount=159375 percent_done_estimate=122.2 elapsed_s=19 est_remaining_s=-8 / 11
tid=0 thread_prefill_amount=162500 percent_done_estimate=124.6 elapsed_s=19 est_remaining_s=-9 / 11
tid=0 thread_prefill_amount=165625 percent_done_estimate=127.0 elapsed_s=19 est_remaining_s=-9 / 10
tid=0 thread_prefill_amount=168750 percent_done_estimate=129.4 elapsed_s=20 est_remaining_s=-10 / 10
timing_elapsed 19.4475s
prefilling_size=10000000 prefilling_elapsed_millis=20.42s
finished prefilling to size 10000000 for expected size 10000000 keysum=99979061260362, performing 15762148 updates; total_prefilling_elapsed_ms=20420 ms)
pref_size=10000000
pref_millis=20420
prefill_elapsed_ms=20420
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=219933.754874708
REALTIME_END_PERF_FORMAT=219943.754944356
###############################################################################
################################## 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 425 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.035s
tree_stats_numInternalsAtDepth=1 1 8 60 425 3315 24770 192919 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1524822
tree_stats_numNodesAtDepth=1 1 8 60 425 3315 24770 192919 1524822
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10010202
tree_stats_avgDegreeAtDepth=1 8 7.5 7.08333 7.8 7.4721 7.78841 7.90395 6.56483
tree_stats_height=9
tree_stats_numInternals=221499
tree_stats_numLeaves=1524822
tree_stats_numNodes=1746321
tree_stats_numKeys=10010202
tree_stats_avgDegreeInternal=7.8841
tree_stats_avgDegreeLeaves=6.56483
tree_stats_avgDegree=6.73217
tree_stats_avgKeyDepth=8
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=365119
first_thread_announced_epoch_by_thread=13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338 13338
sum_commit_tx_reader_total=190842959
sum_commit_tx_reader_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 3022899 3036052 2813725 3004905 2982060 3032777 2958729 3050254 3021907 2919587 2972145 3049558 3013352 3294986 3300966 3281507 3274188 3060077 2992662 3316069 3416881 3303124 3406909 2873538 3330485 3258370 3315011 2865004 2885444 2852697 2787057 2888901 2691745 2673304 2692181 2710495 2665649 2663627 2664454 2682618 3419344 2747360 2708364 2677965 3390552 2782811 2768792 2767239 2730686 2888040 2839697 2912711 2874930 2873098 2692086 2855715 2730781 2925160 2983772 3033035 3053319 3396897 3390901 3373805
sum_commit_tx_writer_total=10045620
sum_commit_tx_writer_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 159679 160186 147637 157332 157405 159366 155434 160648 159244 153649 156509 160454 158638 172468 173742 173489 172647 161262 158680 174225 180139 174041 179892 150770 175717 171820 173751 151846 152142 151252 146365 152260 141249 140026 141090 142644 140267 139847 139209 141669 179819 145042 142729 141083 178144 146278 145017 145861 143721 151272 148992 153817 151295 150799 141593 150569 143539 153889 157222 159417 160900 179173 179028 177731
sum_abort_tx_total=408
sum_abort_tx_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 10 10 8 3 6 8 4 6 5 5 9 4 10 7 7 7 7 7 5 8 3 8 7 2 6 6 5 7 7 3 7 6 7 2 4 10 9 7 4 5 13 6 4 6 4 8 3 4 8 8 11 7 5 11 8 7 7 4 9 8 2 3 7 4
max_ht_capacity_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048
linear_histogram_of_none_tx_rdset_size_full_data=39:975 49:15302 59:34494 69:12552 79:584 89:60 99:27 109:4 119:1
[ 29.00, 39.00]: 975
( 39.00, 49.00]: 15302
( 49.00, 59.00]: 34494
( 59.00, 69.00]: 12552
( 69.00, 79.00]: 584
( 79.00, 89.00]: 60
( 89.00, 99.00]: 27
( 99.00, 109.00]: 4
( 109.00, 119.00]: 1
linear_histogram_of_none_tx_wrset_size_full_data=39.6:59939 77.2:3548 152.4:444 190:59 227.6:7 265.2:2
[ 2.00, 39.60]: 59939
( 39.60, 77.20]: 3548
( 114.80, 152.40]: 444
( 152.40, 190.00]: 59
( 190.00, 227.60]: 7
( 227.60, 265.20]: 2
sum_fasthtm_commit_total=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
sum_htmprefix_commit_total=
sum_htmprefix_abort_total=
sum_htmprefix_abort_explicit_total=
sum_htmprefix_abort_conflict_total=
sum_htmprefix_abort_capacity_total=
sum_htmprefix_abort_nesting_total=
sum_htmprefix_abort_illegal_total=
sum_htmprefix_abort_zero_total=
sum_stmprefix_abort_total=
sum_stmprefix_commit_total=
sum_slow_commit_total=
sum_abtree_insert_repeat_total=
sum_abtree_erase_repeat_total=
sum_abtree_search_repeat_total=
sum_abtree_search_complete_total=
sum_abtree_fix_weight_viol_attempt_total=
sum_abtree_fix_degree_viol_attempt_total=
sum_abtree_rebalancing_kcas_attempt_total=
sum_abtree_update_kcas_attempt_total=
sum_abtree_internal_complex_search_while_iters_total=
sum_abtree_internal_basic_search_while_iters_total=
sum_kcas_desc_new_total=
sum_num_inserts_by_thread=178992 177964 179876 177342 177877 171745 179455 179681 173777 174274 175691 173601 172020 172411 173508 172833 157496 158484 160450 159800 159542 159107 160662 160287 157402 160008 160798 157148 154258 158594 160887 156626 148265 153425 155851 150939 151936 150999 153742 152051 150053 145787 151492 150904 147401 148733 151034 151714 140076 142393 145906 140851 141450 141383 143451 140477 139632 140992 143241 141365 140143 144592 146402 143170
sum_num_inserts_total=10042446
sum_num_deletes_by_thread=177833 180114 180345 177741 178106 171191 180209 179630 174687 173774 175686 173576 173075 172226 174294 172700 157083 157652 161206 159709 159417 159620 161014 160086 157443 159511 161481 157059 154311 158764 160377 156498 147812 154223 155469 151640 150533 151314 152868 151732 150632 145585 151308 150605 146914 148944 151158 152218 140141 142647 145016 140831 141716 142180 143720 140619 139744 140941 144047 141105 140005 144796 146096 142223
sum_num_deletes_total=10045200
sum_num_searches_by_thread=3213104 3217992 3238942 3196453 3212713 3087254 3237356 3227490 3141830 3140714 3154825 3129988 3122359 3102198 3126906 3109463 2824886 2835206 2899683 2872634 2863619 2862424 2889226 2875865 2847392 2872933 2891940 2826787 2770480 2854632 2888748 2815530 2665285 2765588 2802843 2721729 2714381 2721584 2759918 2733803 2705599 2621728 2736512 2702440 2639107 2691012 2724033 2737229 2523257 2566053 2622887 2551312 2541121 2550116 2587149 2532234 2524287 2537115 2587119 2550801 2525768 2603014 2636591 2567746
sum_num_searches_total=180800933
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3569929 3576070 3599163 3551536 3568696 3430190 3597020 3586801 3490294 3488762 3506202 3477165 3467454 3446835 3474708 3454996 3139465 3151342 3221339 3192143 3182578 3181151 3210902 3196238 3162237 3192452 3214219 3140994 3079049 3171990 3210012 3128654 2961362 3073236 3114163 3024308 3016850 3023897 3066528 3037586 3006284 2913100 3039312 3003949 2933422 2988689 3026225 3041161 2803474 2851093 2913809 2832994 2824287 2833679 2874320 2813330 2803663 2819048 2874407 2833271 2805916 2892402 2929089 2853139
average_num_operations_total=3138884
stdev_num_operations_total=31946
sum_num_operations_total=200888579
min_num_operations_total=2803474
max_num_operations_total=3599163
first_time_thread_terminate_by_thread=10000106 10000069 10000068 10000068 10000073 10000068 10000068 10000068 10000070 10000070 10000071 10000070 10000070 10000070 10000070 10000074 10000070 10000070 10000070 10000072 10000068 10000068 10000069 10000069 10000068 10000068 10000068 10000068 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000069 10000069 10000070 10000069 10000072 10000072 10000072 10000072 10000074 10000069 10000069 10000071 10000070 10000070 10000070 10000071 10000072 10000072 10000072 10000072 10000073 10000072 10000072 10000072
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000106
first_time_thread_start_by_thread=6 2 2 2 5 5 5 5 5 5 5 5 4 4 4 4 3 3 3 3 3 3 3 3 5 5 5 5 6 6 6 6 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 6 6 6 6 6 6
min_time_thread_start_total=2
max_time_thread_start_total=6
sum_duration_all_ops_total=
threads_final_keysum=100105078658058
threads_final_size=10010202
final_keysum=100105078658058
final_size=10010202
validate_result=success
Validation OK.
Structural validation OK.
total_find=180800933
total_rq=0
total_inserts=10042446
total_deletes=10045200
total_updates=20087646
total_queries=180800933
total_ops=200888579
find_throughput=18080093
rq_throughput=0
update_throughput=2008764
query_throughput=18080093
total_throughput=20088857
total find : 180800933
total rq : 0
total inserts : 10042446
total deletes : 10045200
total updates : 20087646
total queries : 180800933
total ops : 200888579
find throughput : 18080093
rq throughput : 0
update throughput : 2008764
query throughput : 18080093
total throughput : 20088857
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=146507263994072
total_execution_walltime=35.881s
REALTIME_MAIN_END_PERF_FORMAT=219944.53653722
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=36.08, faults_major=0, faults_minor=451663, mem_maxresident_kb=1804220, user_cputime=2139.52, sys_cputime=22.02, percent_cpu=5989%
timeout=false
algorithm=brown_abtree_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2