__step=000130
__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 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000130.txt
__path_data=data/data000130.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=221436.880579470
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=2000000
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.021s
prefilling_size=314884 prefilling_elapsed_millis=1s
prefilling_size=339935 prefilling_elapsed_millis=1.1s
prefilling_size=363939 prefilling_elapsed_millis=1.201s
prefilling_size=386995 prefilling_elapsed_millis=1.301s
prefilling_size=409336 prefilling_elapsed_millis=1.401s
prefilling_size=430740 prefilling_elapsed_millis=1.501s
prefilling_size=451225 prefilling_elapsed_millis=1.601s
prefilling_size=471271 prefilling_elapsed_millis=1.701s
prefilling_size=490715 prefilling_elapsed_millis=1.801s
prefilling_size=509503 prefilling_elapsed_millis=1.901s
prefilling_size=527453 prefilling_elapsed_millis=2.002s
prefilling_size=544448 prefilling_elapsed_millis=2.102s
prefilling_size=561019 prefilling_elapsed_millis=2.202s
prefilling_size=577085 prefilling_elapsed_millis=2.302s
prefilling_size=592182 prefilling_elapsed_millis=2.402s
prefilling_size=607103 prefilling_elapsed_millis=2.502s
prefilling_size=621542 prefilling_elapsed_millis=2.602s
prefilling_size=635334 prefilling_elapsed_millis=2.702s
prefilling_size=648706 prefilling_elapsed_millis=2.803s
prefilling_size=661711 prefilling_elapsed_millis=2.903s
prefilling_size=674276 prefilling_elapsed_millis=3.003s
prefilling_size=686016 prefilling_elapsed_millis=3.103s
prefilling_size=697568 prefilling_elapsed_millis=3.204s
prefilling_size=708782 prefilling_elapsed_millis=3.304s
prefilling_size=719639 prefilling_elapsed_millis=3.404s
prefilling_size=729736 prefilling_elapsed_millis=3.504s
prefilling_size=739488 prefilling_elapsed_millis=3.604s
prefilling_size=749108 prefilling_elapsed_millis=3.704s
prefilling_size=758446 prefilling_elapsed_millis=3.804s
prefilling_size=767398 prefilling_elapsed_millis=3.904s
prefilling_size=775958 prefilling_elapsed_millis=4.005s
prefilling_size=784316 prefilling_elapsed_millis=4.105s
prefilling_size=792399 prefilling_elapsed_millis=4.205s
prefilling_size=800242 prefilling_elapsed_millis=4.305s
prefilling_size=807562 prefilling_elapsed_millis=4.405s
prefilling_size=814841 prefilling_elapsed_millis=4.505s
prefilling_size=821761 prefilling_elapsed_millis=4.605s
prefilling_size=828449 prefilling_elapsed_millis=4.705s
prefilling_size=834929 prefilling_elapsed_millis=4.806s
prefilling_size=841231 prefilling_elapsed_millis=4.906s
prefilling_size=846843 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 153135 keys with 64 threads...
tid=0 thread_prefill_amount=9375 percent_done_estimate=391.8 elapsed_s=5 est_remaining_s=-9 / -4
timing_elapsed 0.365717s
prefilling_size=1000000 prefilling_elapsed_millis=0.384s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000306743587, performing 4032777 updates; total_prefilling_elapsed_ms=384 ms)
pref_size=1000000
pref_millis=384
prefill_elapsed_ms=384
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=221442.430874025
REALTIME_END_PERF_FORMAT=221452.430943013
###############################################################################
################################## 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 406 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.036s
tree_stats_numInternalsAtDepth=1 1 8 55 406 3128 24515 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 189802
tree_stats_numNodesAtDepth=1 1 8 55 406 3128 24515 189802
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1000107
tree_stats_avgDegreeAtDepth=1 8 6.875 7.38182 7.70443 7.83728 7.74228 5.26921
tree_stats_height=8
tree_stats_numInternals=28114
tree_stats_numLeaves=189802
tree_stats_numNodes=217916
tree_stats_numKeys=1000107
tree_stats_avgDegreeInternal=7.75116
tree_stats_avgDegreeLeaves=5.26921
tree_stats_avgDegree=5.58942
tree_stats_avgKeyDepth=7
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=369983
first_thread_announced_epoch_by_thread=11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984 11984
sum_commit_tx_reader_total=180365775
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 2876847 2888760 2942662 2844387 2855454 2887054 2848792 3370156 2871834 2947076 2949295 2896984 3450749 3338251 3378860 3495641 3288010 3311872 3258098 3309965 3275747 3289721 3236970 2282988 2328675 2318750 2300841 2367776 2437110 2339224 2305629 2332668 2441015 2328291 2294528 2334310 2416982 2324711 2610300 2662963 2732484 2687691 2641128 2870643 2700427 2673276 2634945 2681031 2619281 2702309 2687940 2972177 2852619 3338570 3322762 2777911 3386386 2407419 3427392 2694007 2768023 2761431 2911183 2874794
sum_commit_tx_writer_total=9492819
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 151433 151457 154949 149267 150037 151795 149505 178024 151673 155248 155138 152844 180996 175540 177721 183686 173450 174138 171473 173541 171992 172990 170438 120109 123018 121942 121781 124815 128624 123557 121182 122287 128266 122254 121238 122514 127067 122962 138504 140550 144403 141641 139500 150657 142695 140903 138862 140908 136924 141872 141942 156819 149644 174383 174612 146106 177792 126581 180211 141236 145979 146062 154103 150979
sum_abort_tx_total=1072
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 15 17 21 16 15 17 14 20 16 18 12 21 22 29 16 13 16 20 12 16 9 22 14 18 14 18 27 18 14 19 22 15 15 18 13 16 13 17 11 18 21 20 13 17 15 16 16 8 15 17 12 14 12 17 14 14 19 15 17 23 16 18 25 21
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=32.4:910 40.8:10891 49.2:32466 57.6:17429 66:2141 74.4:116 82.8:30 91.2:15 99.6:1
[ 24.00, 32.40]: 910
( 32.40, 40.80]: 10891
( 40.80, 49.20]: 32466
( 49.20, 57.60]: 17429
( 57.60, 66.00]: 2141
( 66.00, 74.40]: 116
( 74.40, 82.80]: 30
( 82.80, 91.20]: 15
( 91.20, 99.60]: 1
linear_histogram_of_none_tx_wrset_size_full_data=24.6:61279 69.8:4 92.4:2395 137.6:286 182.8:31 228:2
[ 2.00, 24.60]: 61279
( 47.20, 69.80]: 4
( 69.80, 92.40]: 2395
( 115.00, 137.60]: 286
( 160.20, 182.80]: 31
( 205.40, 228.00]: 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=150966 153968 157299 150545 150116 151645 155060 151159 149378 152506 155188 151779 149795 152193 154932 149970 178133 178006 181300 174661 176069 177779 184198 179793 173398 173691 171356 173426 172135 173274 174709 170686 119334 122549 126311 121434 121358 124530 128143 122913 120908 122721 128945 122420 120918 122889 126791 121642 138054 140193 144149 141256 139096 142379 145821 140706 138647 141778 145249 141205 137144 141577 145833 141563
sum_num_inserts_total=9487569
sum_num_deletes_by_thread=151328 153419 156173 151244 150208 151759 155443 150864 150164 153074 155493 151504 149494 151353 154651 150211 177722 177964 181014 175393 174864 178280 183387 180900 172796 174643 171748 174246 171923 173211 175168 170508 120456 122526 126503 122583 121079 124805 128184 123521 121475 122701 128407 122247 121056 122549 127014 122849 137967 139845 144107 141999 139337 142888 145600 140211 138852 141565 145931 141046 137182 142278 146135 141879
sum_num_deletes_total=9494926
sum_num_searches_by_thread=2723479 2757899 2815524 2719511 2701939 2735445 2791821 2721484 2698755 2744248 2793752 2724997 2694365 2736671 2788028 2705310 3192325 3208208 3269431 3162899 3162858 3200522 3311742 3246910 3115266 3137676 3086467 3135834 3103681 3116226 3147497 3066214 2163307 2206618 2281186 2196675 2180185 2243256 2309407 2216347 2184428 2209533 2311929 2205878 2173792 2211386 2290244 2203182 2472783 2523475 2588631 2546077 2502195 2557855 2622581 2533262 2496308 2551900 2616313 2539688 2481879 2560326 2632049 2546440
sum_num_searches_total=170876099
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3025773 3065286 3128996 3021300 3002263 3038849 3102324 3023507 2998297 3049828 3104433 3028280 2993654 3040217 3097611 3005491 3548180 3564178 3631745 3512953 3513791 3556581 3679327 3607603 3461460 3486010 3429571 3483506 3447739 3462711 3497374 3407408 2403097 2451693 2534000 2440692 2422622 2492591 2565734 2462781 2426811 2454955 2569281 2450545 2415766 2456824 2544049 2447673 2748804 2803513 2876887 2829332 2780628 2843122 2914002 2814179 2773807 2835243 2907493 2821939 2756205 2844181 2924017 2829882
average_num_operations_total=2966540
stdev_num_operations_total=48624
sum_num_operations_total=189858594
min_num_operations_total=2403097
max_num_operations_total=3679327
first_time_thread_terminate_by_thread=10000108 10000069 10000068 10000069 10000076 10000076 10000076 10000076 10000070 10000070 10000070 10000070 10000068 10000068 10000070 10000068 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000074 10000072 10000072 10000072 10000072 10000071 10000071 10000071 10000071 10000072 10000072 10000072 10000072 10000075 10000075 10000075 10000075 10000076 10000076 10000076 10000076 10000070 10000069 10000070 10000070 10000073 10000073 10000073 10000073 10000070 10000071 10000071 10000070 10000074 10000074 10000074 10000074 10000075 10000075 10000075 10000075
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000108
first_time_thread_start_by_thread=6 2 2 2 6 6 6 6 3 3 3 3 4 4 4 4 4 4 4 4 6 6 6 6 5 5 5 5 6 6 6 6 5 5 5 5 4 4 4 4 4 4 4 4 6 6 6 6 7 7 7 7 5 5 5 5 7 7 7 7 7 7 7 7
min_time_thread_start_total=2
max_time_thread_start_total=7
sum_duration_all_ops_total=
threads_final_keysum=999912049968
threads_final_size=1000107
final_keysum=999912049968
final_size=1000107
validate_result=success
Validation OK.
Structural validation OK.
total_find=170876099
total_rq=0
total_inserts=9487569
total_deletes=9494926
total_updates=18982495
total_queries=170876099
total_ops=189858594
find_throughput=17087609
rq_throughput=0
update_throughput=1898249
query_throughput=17087609
total_throughput=18985859
total find : 170876099
total rq : 0
total inserts : 9487569
total deletes : 9494926
total updates : 18982495
total queries : 170876099
total ops : 189858594
find throughput : 17087609
rq throughput : 0
update throughput : 1898249
query throughput : 17087609
total throughput : 18985859
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=46797342386211
total_execution_walltime=15.845s
REALTIME_MAIN_END_PERF_FORMAT=221452.726215483
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.96, faults_major=0, faults_minor=136161, mem_maxresident_kb=542552, user_cputime=977.89, sys_cputime=19.15, percent_cpu=6243%
timeout=false
algorithm=brown_abtree_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2