__step=000033
__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=data000033.txt
__path_data=data/data000033.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219872.104298144
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.028s
prefilling_size=369632 prefilling_elapsed_millis=1s
prefilling_size=404571 prefilling_elapsed_millis=1.101s
prefilling_size=439270 prefilling_elapsed_millis=1.201s
prefilling_size=473890 prefilling_elapsed_millis=1.301s
prefilling_size=508322 prefilling_elapsed_millis=1.401s
prefilling_size=542406 prefilling_elapsed_millis=1.501s
prefilling_size=576259 prefilling_elapsed_millis=1.601s
prefilling_size=610080 prefilling_elapsed_millis=1.701s
prefilling_size=643911 prefilling_elapsed_millis=1.801s
prefilling_size=677515 prefilling_elapsed_millis=1.902s
prefilling_size=711143 prefilling_elapsed_millis=2.002s
prefilling_size=744528 prefilling_elapsed_millis=2.102s
prefilling_size=778013 prefilling_elapsed_millis=2.202s
prefilling_size=811372 prefilling_elapsed_millis=2.302s
prefilling_size=844504 prefilling_elapsed_millis=2.402s
prefilling_size=877740 prefilling_elapsed_millis=2.502s
prefilling_size=910557 prefilling_elapsed_millis=2.602s
prefilling_size=943228 prefilling_elapsed_millis=2.703s
prefilling_size=975781 prefilling_elapsed_millis=2.803s
prefilling_size=1008323 prefilling_elapsed_millis=2.903s
prefilling_size=1041050 prefilling_elapsed_millis=3.003s
prefilling_size=1073216 prefilling_elapsed_millis=3.103s
prefilling_size=1105345 prefilling_elapsed_millis=3.204s
prefilling_size=1137520 prefilling_elapsed_millis=3.304s
prefilling_size=1169573 prefilling_elapsed_millis=3.404s
prefilling_size=1201350 prefilling_elapsed_millis=3.504s
prefilling_size=1232937 prefilling_elapsed_millis=3.604s
prefilling_size=1264433 prefilling_elapsed_millis=3.704s
prefilling_size=1296175 prefilling_elapsed_millis=3.804s
prefilling_size=1327612 prefilling_elapsed_millis=3.904s
prefilling_size=1358833 prefilling_elapsed_millis=4.005s
prefilling_size=1389988 prefilling_elapsed_millis=4.105s
prefilling_size=1420871 prefilling_elapsed_millis=4.205s
prefilling_size=1451864 prefilling_elapsed_millis=4.305s
prefilling_size=1482786 prefilling_elapsed_millis=4.405s
prefilling_size=1513349 prefilling_elapsed_millis=4.505s
prefilling_size=1543853 prefilling_elapsed_millis=4.605s
prefilling_size=1574337 prefilling_elapsed_millis=4.705s
prefilling_size=1604907 prefilling_elapsed_millis=4.806s
prefilling_size=1635286 prefilling_elapsed_millis=4.906s
prefilling_size=1665272 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 8334680 keys with 64 threads...
tid=0 thread_prefill_amount=18750 percent_done_estimate=14.4 elapsed_s=6 est_remaining_s=78 / 83
tid=0 thread_prefill_amount=21875 percent_done_estimate=16.8 elapsed_s=7 est_remaining_s=75 / 82
tid=0 thread_prefill_amount=25000 percent_done_estimate=19.2 elapsed_s=8 est_remaining_s=72 / 80
tid=0 thread_prefill_amount=28125 percent_done_estimate=21.6 elapsed_s=9 est_remaining_s=70 / 78
tid=0 thread_prefill_amount=31250 percent_done_estimate=24.0 elapsed_s=9 est_remaining_s=68 / 77
tid=0 thread_prefill_amount=34375 percent_done_estimate=26.4 elapsed_s=10 est_remaining_s=65 / 76
tid=0 thread_prefill_amount=37500 percent_done_estimate=28.8 elapsed_s=11 est_remaining_s=63 / 74
tid=0 thread_prefill_amount=40625 percent_done_estimate=31.2 elapsed_s=12 est_remaining_s=61 / 73
tid=0 thread_prefill_amount=43750 percent_done_estimate=33.6 elapsed_s=13 est_remaining_s=58 / 72
tid=0 thread_prefill_amount=46875 percent_done_estimate=36.0 elapsed_s=14 est_remaining_s=56 / 70
tid=0 thread_prefill_amount=50000 percent_done_estimate=38.4 elapsed_s=15 est_remaining_s=54 / 69
tid=0 thread_prefill_amount=53125 percent_done_estimate=40.8 elapsed_s=16 est_remaining_s=51 / 67
tid=0 thread_prefill_amount=56250 percent_done_estimate=43.2 elapsed_s=17 est_remaining_s=49 / 66
tid=0 thread_prefill_amount=59375 percent_done_estimate=45.6 elapsed_s=17 est_remaining_s=46 / 64
tid=0 thread_prefill_amount=62500 percent_done_estimate=48.0 elapsed_s=18 est_remaining_s=44 / 62
tid=0 thread_prefill_amount=65625 percent_done_estimate=50.4 elapsed_s=19 est_remaining_s=41 / 60
tid=0 thread_prefill_amount=68750 percent_done_estimate=52.8 elapsed_s=19 est_remaining_s=39 / 58
tid=0 thread_prefill_amount=71875 percent_done_estimate=55.2 elapsed_s=20 est_remaining_s=36 / 56
tid=0 thread_prefill_amount=75000 percent_done_estimate=57.6 elapsed_s=20 est_remaining_s=34 / 54
tid=0 thread_prefill_amount=78125 percent_done_estimate=60.0 elapsed_s=21 est_remaining_s=31 / 52
tid=0 thread_prefill_amount=81250 percent_done_estimate=62.4 elapsed_s=21 est_remaining_s=29 / 50
tid=0 thread_prefill_amount=84375 percent_done_estimate=64.8 elapsed_s=22 est_remaining_s=26 / 48
tid=0 thread_prefill_amount=87500 percent_done_estimate=67.2 elapsed_s=22 est_remaining_s=24 / 46
tid=0 thread_prefill_amount=90625 percent_done_estimate=69.6 elapsed_s=22 est_remaining_s=22 / 44
tid=0 thread_prefill_amount=93750 percent_done_estimate=72.0 elapsed_s=23 est_remaining_s=20 / 42
tid=0 thread_prefill_amount=96875 percent_done_estimate=74.4 elapsed_s=23 est_remaining_s=18 / 40
tid=0 thread_prefill_amount=100000 percent_done_estimate=76.8 elapsed_s=23 est_remaining_s=16 / 39
tid=0 thread_prefill_amount=103125 percent_done_estimate=79.2 elapsed_s=23 est_remaining_s=14 / 37
tid=0 thread_prefill_amount=106250 percent_done_estimate=81.6 elapsed_s=23 est_remaining_s=12 / 35
tid=0 thread_prefill_amount=109375 percent_done_estimate=84.0 elapsed_s=24 est_remaining_s=10 / 34
tid=0 thread_prefill_amount=112500 percent_done_estimate=86.4 elapsed_s=24 est_remaining_s=8 / 32
tid=0 thread_prefill_amount=115625 percent_done_estimate=88.8 elapsed_s=24 est_remaining_s=7 / 31
tid=0 thread_prefill_amount=118750 percent_done_estimate=91.2 elapsed_s=24 est_remaining_s=5 / 30
tid=0 thread_prefill_amount=121875 percent_done_estimate=93.6 elapsed_s=25 est_remaining_s=4 / 28
tid=0 thread_prefill_amount=125000 percent_done_estimate=96.0 elapsed_s=25 est_remaining_s=2 / 27
tid=0 thread_prefill_amount=128125 percent_done_estimate=98.4 elapsed_s=25 est_remaining_s=1 / 26
tid=0 thread_prefill_amount=131250 percent_done_estimate=100.8 elapsed_s=25 est_remaining_s=-0 / 24
tid=0 thread_prefill_amount=134375 percent_done_estimate=103.2 elapsed_s=25 est_remaining_s=-2 / 23
tid=0 thread_prefill_amount=137500 percent_done_estimate=105.6 elapsed_s=25 est_remaining_s=-3 / 22
tid=0 thread_prefill_amount=140625 percent_done_estimate=108.0 elapsed_s=25 est_remaining_s=-4 / 21
tid=0 thread_prefill_amount=143750 percent_done_estimate=110.4 elapsed_s=25 est_remaining_s=-5 / 20
timing_elapsed 19.3587s
prefilling_size=10000000 prefilling_elapsed_millis=20.327s
finished prefilling to size 10000000 for expected size 10000000 keysum=100026523804065, performing 15762220 updates; total_prefilling_elapsed_ms=20327 ms)
pref_size=10000000
pref_millis=20327
prefill_elapsed_ms=20327
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=219897.602873143
REALTIME_END_PERF_FORMAT=219907.602941791
###############################################################################
################################## 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 447 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.034s
tree_stats_numInternalsAtDepth=1 1 8 62 447 3280 24916 193236 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1524507
tree_stats_numNodesAtDepth=1 1 8 62 447 3280 24916 193236 1524507
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9994779
tree_stats_avgDegreeAtDepth=1 8 7.75 7.20968 7.33781 7.59634 7.7555 7.88935 6.55607
tree_stats_height=9
tree_stats_numInternals=221951
tree_stats_numLeaves=1524507
tree_stats_numNodes=1746458
tree_stats_numKeys=9994779
tree_stats_avgDegreeInternal=7.86866
tree_stats_avgDegreeLeaves=6.55607
tree_stats_avgDegree=6.72289
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=365247
first_thread_announced_epoch_by_thread=13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352 13352
sum_commit_tx_reader_total=191243116
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 2884216 3017527 3021693 2552664 2706529 2659805 2639755 3034875 2650462 2688860 2732093 2688415 2667136 2760965 2685658 2845571 2907456 2827424 2883812 2805431 2871387 2938035 2964118 3312930 2741226 3085781 3381692 3406757 3373573 3276159 3033927 3002129 3058691 3312265 2713100 2743322 3038427 2889616 2895734 3429683 3393114 2891102 3038031 3052663 3346099 2844449 3373362 2841504 3073881 3419611 3304566 3416857 3016174 3056212 2927139 2962542 3269078 3325868 3332759 3051418 2718509 2681436 2751976 3025867
sum_commit_tx_writer_total=10070443
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 151694 159025 158520 134777 142586 140545 138495 159416 138815 141448 143754 141656 140809 145894 141413 149439 152911 148235 151502 147372 151520 155272 155620 174781 144065 162608 177676 179294 177398 172772 160422 157362 161649 173680 143275 144674 160041 152051 152833 180265 179010 152353 159993 160587 176334 150245 177582 149177 162445 179215 173924 180004 158795 160524 154668 156404 171881 175417 175773 160462 143840 141192 145469 159585
sum_abort_tx_total=459
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 2 9 5 8 4 8 6 7 9 9 5 8 11 8 8 7 7 11 4 7 8 6 9 7 7 6 5 9 9 5 9 6 7 9 3 7 8 9 8 10 4 8 10 12 6 8 7 6 6 8 2 7 5 11 12 4 4 5 9 11 7 6 6 5
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=38.3:964 48.6:14545 58.9:33325 69.2:14355 79.5:694 89.8:77 100.1:28 110.4:7 120.7:4
[ 28.00, 38.30]: 964
( 38.30, 48.60]: 14545
( 48.60, 58.90]: 33325
( 58.90, 69.20]: 14355
( 69.20, 79.50]: 694
( 79.50, 89.80]: 77
( 89.80, 100.10]: 28
( 100.10, 110.40]: 7
( 110.40, 120.70]: 4
linear_histogram_of_none_tx_wrset_size_full_data=29.4:59861 84.2:3585 139:466 193.8:77 248.6:10
[ 2.00, 29.40]: 59861
( 56.80, 84.20]: 3585
( 111.60, 139.00]: 466
( 166.40, 193.80]: 77
( 221.20, 248.60]: 10
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=159241 160368 162007 159742 159391 159861 160446 158575 157008 159715 160304 150969 158265 161669 162475 158552 179498 179117 180309 179081 178152 177946 178999 177620 172430 171976 176036 175633 173261 174469 175556 175155 134874 142265 143706 140468 138775 141206 143319 139284 141410 143611 145363 141879 140831 142835 145613 141605 149421 149365 152442 149615 148317 151521 147430 152581 144645 154436 156464 151911 151085 155402 155258 152401
sum_num_inserts_total=10063164
sum_num_deletes_by_thread=158802 160805 161850 160051 159645 159626 160842 158572 157680 159937 161077 151517 159229 161573 162932 158695 179690 180315 180712 178387 177545 176942 179768 177436 172878 172071 176320 175093 174564 174067 176153 174324 134962 142547 144231 139600 138816 141593 143339 138784 141252 143019 145249 141564 140522 143326 145745 141837 149664 148680 153383 150381 148660 152267 147706 152381 144508 154555 156555 152816 151221 155203 156049 152799
sum_num_deletes_total=10072312
sum_num_searches_by_thread=2867409 2890707 2912469 2874556 2875255 2878981 2891962 2857822 2844803 2878372 2895355 2733424 2859058 2897098 2922982 2862966 3239638 3237429 3248927 3214656 3203671 3196056 3227284 3195915 3103623 3096912 3170077 3150559 3130665 3137409 3156823 3138232 2417605 2564303 2597354 2520282 2500659 2547509 2589189 2511209 2539966 2575719 2606833 2546628 2526592 2570214 2615501 2543629 2695925 2692636 2754542 2694698 2678682 2731526 2657667 2738493 2598843 2772816 2805927 2736940 2720601 2782702 2808431 2743367
sum_num_searches_total=181178083
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3185452 3211880 3236326 3194349 3194291 3198468 3213250 3174969 3159491 3198024 3216736 3035910 3176552 3220340 3248389 3180213 3598826 3596861 3609948 3572124 3559368 3550944 3586051 3550971 3448931 3440959 3522433 3501285 3478490 3485945 3508532 3487711 2687441 2849115 2885291 2800350 2778250 2830308 2875847 2789277 2822628 2862349 2897445 2830071 2807945 2856375 2906859 2827071 2995010 2990681 3060367 2994694 2975659 3035314 2952803 3043455 2887996 3081807 3118946 3041667 3022907 3093307 3119738 3048567
average_num_operations_total=3145524
stdev_num_operations_total=33238
sum_num_operations_total=201313559
min_num_operations_total=2687441
max_num_operations_total=3609948
first_time_thread_terminate_by_thread=10000105 10000067 10000068 10000067 10000070 10000070 10000070 10000070 10000069 10000069 10000069 10000069 10000068 10000068 10000068 10000068 10000071 10000071 10000071 10000071 10000068 10000067 10000067 10000068 10000071 10000071 10000071 10000071 10000072 10000072 10000072 10000072 10000070 10000070 10000070 10000070 10000071 10000071 10000071 10000071 10000068 10000068 10000068 10000069 10000068 10000068 10000068 10000068 10000069 10000069 10000069 10000069 10000068 10000068 10000068 10000068 10000071 10000071 10000071 10000071 10000069 10000069 10000069 10000069
min_time_thread_terminate_total=10000067
max_time_thread_terminate_total=10000105
first_time_thread_start_by_thread=6 2 2 2 3 3 3 3 3 3 3 3 4 4 4 4 3 3 3 3 4 4 4 4 5 5 5 5 4 4 4 4 4 4 4 4 5 5 5 5 5 5 5 5 5 5 5 5 3 3 4 3 6 6 6 6 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=99944130370578
threads_final_size=9994779
final_keysum=99944130370578
final_size=9994779
validate_result=success
Validation OK.
Structural validation OK.
total_find=181178083
total_rq=0
total_inserts=10063164
total_deletes=10072312
total_updates=20135476
total_queries=181178083
total_ops=201313559
find_throughput=18117808
rq_throughput=0
update_throughput=2013547
query_throughput=18117808
total_throughput=20131355
total find : 181178083
total rq : 0
total inserts : 10063164
total deletes : 10072312
total updates : 20135476
total queries : 181178083
total ops : 201313559
find throughput : 18117808
rq throughput : 0
update throughput : 2013547
query throughput : 18117808
total throughput : 20131355
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=146559188111904
total_execution_walltime=35.789s
REALTIME_MAIN_END_PERF_FORMAT=219907.894267554
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=36.00, faults_major=1, faults_minor=451793, mem_maxresident_kb=1804660, user_cputime=2131.48, sys_cputime=22.95, percent_cpu=5983%
timeout=false
algorithm=brown_abtree_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1