__step=000082
__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000082.txt
__path_data=data/data000082.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994055.525466622
binary=./brown_abtree_tm_auto.debra.norec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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,96,97,98,99,100,101,102,103,104,105,106,107,108,109,110,111,112,113,114,115,116,117,118,119
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.007s
prefilling_size=946838 prefilling_elapsed_millis=1.001s
prefilling_size=1037227 prefilling_elapsed_millis=1.101s
prefilling_size=1127191 prefilling_elapsed_millis=1.201s
prefilling_size=1215896 prefilling_elapsed_millis=1.301s
prefilling_size=1304059 prefilling_elapsed_millis=1.402s
prefilling_size=1391585 prefilling_elapsed_millis=1.502s
prefilling_size=1478316 prefilling_elapsed_millis=1.602s
prefilling_size=1564146 prefilling_elapsed_millis=1.702s
prefilling_size=1649398 prefilling_elapsed_millis=1.802s
prefilling_size=1733805 prefilling_elapsed_millis=1.903s
prefilling_size=1817353 prefilling_elapsed_millis=2.003s
prefilling_size=1899962 prefilling_elapsed_millis=2.103s
prefilling_size=1982081 prefilling_elapsed_millis=2.203s
prefilling_size=2063126 prefilling_elapsed_millis=2.303s
prefilling_size=2143662 prefilling_elapsed_millis=2.404s
prefilling_size=2223192 prefilling_elapsed_millis=2.504s
prefilling_size=2302028 prefilling_elapsed_millis=2.604s
prefilling_size=2380591 prefilling_elapsed_millis=2.704s
prefilling_size=2458015 prefilling_elapsed_millis=2.804s
prefilling_size=2534259 prefilling_elapsed_millis=2.905s
prefilling_size=2610430 prefilling_elapsed_millis=3.005s
prefilling_size=2685188 prefilling_elapsed_millis=3.105s
prefilling_size=2759689 prefilling_elapsed_millis=3.205s
prefilling_size=2833718 prefilling_elapsed_millis=3.305s
prefilling_size=2906628 prefilling_elapsed_millis=3.406s
prefilling_size=2979588 prefilling_elapsed_millis=3.506s
prefilling_size=3050940 prefilling_elapsed_millis=3.606s
prefilling_size=3121742 prefilling_elapsed_millis=3.706s
prefilling_size=3191697 prefilling_elapsed_millis=3.806s
prefilling_size=3261735 prefilling_elapsed_millis=3.907s
prefilling_size=3330814 prefilling_elapsed_millis=4.007s
prefilling_size=3399287 prefilling_elapsed_millis=4.107s
prefilling_size=3466922 prefilling_elapsed_millis=4.207s
prefilling_size=3533782 prefilling_elapsed_millis=4.307s
prefilling_size=3600580 prefilling_elapsed_millis=4.407s
prefilling_size=3666488 prefilling_elapsed_millis=4.508s
prefilling_size=3731564 prefilling_elapsed_millis=4.608s
prefilling_size=3795751 prefilling_elapsed_millis=4.708s
prefilling_size=3860031 prefilling_elapsed_millis=4.808s
prefilling_size=3923350 prefilling_elapsed_millis=4.908s
prefilling_size=3986537 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6013418 keys with 48 threads...
tid=0 thread_prefill_amount=83320 percent_done_estimate=66.5 elapsed_s=5 est_remaining_s=6 / 11
tid=0 thread_prefill_amount=87486 percent_done_estimate=69.8 elapsed_s=5 est_remaining_s=5 / 11
tid=0 thread_prefill_amount=91652 percent_done_estimate=73.2 elapsed_s=6 est_remaining_s=5 / 10
tid=0 thread_prefill_amount=95818 percent_done_estimate=76.5 elapsed_s=6 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=99984 percent_done_estimate=79.8 elapsed_s=6 est_remaining_s=3 / 9
tid=0 thread_prefill_amount=104150 percent_done_estimate=83.1 elapsed_s=6 est_remaining_s=3 / 9
tid=0 thread_prefill_amount=108316 percent_done_estimate=86.5 elapsed_s=6 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=112482 percent_done_estimate=89.8 elapsed_s=7 est_remaining_s=2 / 8
tid=0 thread_prefill_amount=116648 percent_done_estimate=93.1 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=120814 percent_done_estimate=96.4 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=124980 percent_done_estimate=99.8 elapsed_s=7 est_remaining_s=0 / 7
tid=0 thread_prefill_amount=129146 percent_done_estimate=103.1 elapsed_s=7 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=133312 percent_done_estimate=106.4 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=137478 percent_done_estimate=109.7 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=141644 percent_done_estimate=113.1 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=145810 percent_done_estimate=116.4 elapsed_s=8 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=149976 percent_done_estimate=119.7 elapsed_s=8 est_remaining_s=-3 / 5
tid=0 thread_prefill_amount=154142 percent_done_estimate=123.0 elapsed_s=9 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=158308 percent_done_estimate=126.4 elapsed_s=9 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=162474 percent_done_estimate=129.7 elapsed_s=9 est_remaining_s=-5 / 4
tid=0 thread_prefill_amount=166640 percent_done_estimate=133.0 elapsed_s=9 est_remaining_s=-5 / 4
tid=0 thread_prefill_amount=170806 percent_done_estimate=136.3 elapsed_s=9 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=174972 percent_done_estimate=139.7 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=179138 percent_done_estimate=143.0 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=183304 percent_done_estimate=146.3 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=187470 percent_done_estimate=149.6 elapsed_s=10 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=191636 percent_done_estimate=153.0 elapsed_s=10 est_remaining_s=-8 / 2
tid=0 thread_prefill_amount=195802 percent_done_estimate=156.3 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=199968 percent_done_estimate=159.6 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=204134 percent_done_estimate=162.9 elapsed_s=11 est_remaining_s=-10 / 1
timing_elapsed 6.03677s
prefilling_size=10000000 prefilling_elapsed_millis=6.051s
finished prefilling to size 10000000 for expected size 10000000 keysum=99998478897232, performing 19588114 updates; total_prefilling_elapsed_ms=6051 ms)
pref_size=10000000
pref_millis=6051
prefill_elapsed_ms=6051
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6994066.874191408
REALTIME_END_PERF_FORMAT=6994076.874275282
###############################################################################
################################## 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 (48 threads)...
partitioned into 422 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.013s
tree_stats_numInternalsAtDepth=1 1 8 60 422 3150 23849 185749 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1467167
tree_stats_numNodesAtDepth=1 1 8 60 422 3150 23849 185749 1467167
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9998636
tree_stats_avgDegreeAtDepth=1 8 7.5 7.03333 7.46445 7.57111 7.78854 7.89865 6.81493
tree_stats_height=9
tree_stats_numInternals=213240
tree_stats_numLeaves=1467167
tree_stats_numNodes=1680407
tree_stats_numKeys=9998636
tree_stats_avgDegreeInternal=7.88036
tree_stats_avgDegreeLeaves=6.81493
tree_stats_avgDegree=6.95013
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=282959
first_thread_announced_epoch_by_thread=13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712 13712
sum_commit_tx_reader_total=124368436
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 2585677 2600918 2572219 2597722 2579885 2584155 2610585 2599715 2571859 2587491 2571337 2573876 2591983 2592297 2584180 2617416 2575500 2572253 2596566 2592408 2600309 2608464 2579013 2592185 2566544 2626540 2584523 2588556 2600244 2596443 2583779 2577019 2591336 2578175 2592336 2606802 2601698 2607959 2576064 2595746 2603639 2607841 2608015 2586055 2563401 2595997 2583389 2608322
sum_commit_tx_writer_total=6543600
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 136287 136809 135640 136676 136488 135982 137439 137090 134495 135796 135102 135671 136438 136917 135892 137675 136277 134846 136369 136444 136395 137350 135447 135691 134925 137979 135629 136589 136508 136674 135251 136344 136541 135391 136348 137283 136999 137026 135023 136898 136977 137253 137054 135775 134918 137164 136598 137237
sum_abort_tx_total=154
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 4 2 3 3 3 8 4 2 3 6 3 2 4 3 3 5 3 4 2 1 1 3 2 2 3 6 8 7 1 4 3 6 1 2 2 0 1 4 2 7 4 3 3 2 4 2 2 1
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 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=36.6:276 44.2:5173 51.8:15417 59.4:20096 67:6200 74.6:730 82.2:71 89.8:20 97.4:15 105:1
[ 29.00, 36.60]: 276
( 36.60, 44.20]: 5173
( 44.20, 51.80]: 15417
( 51.80, 59.40]: 20096
( 59.40, 67.00]: 6200
( 67.00, 74.60]: 730
( 74.60, 82.20]: 71
( 82.20, 89.80]: 20
( 89.80, 97.40]: 15
( 97.40, 105.00]: 1
linear_histogram_of_none_tx_wrset_size_full_data=29.4:45054 84.2:2580 139:316 193.8:47 248.6:2
[ 2.00, 29.40]: 45054
( 56.80, 84.20]: 2580
( 111.60, 139.00]: 316
( 166.40, 193.80]: 47
( 221.20, 248.60]: 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=135100 136386 136838 137213 136894 135489 136354 136093 137176 135823 136678 135994 135877 135999 137007 136752 136034 136610 134617 136368 136474 136941 136922 135594 135605 135220 136191 137098 137182 136851 135397 135631 137789 136844 137578 135404 136623 135366 137074 137270 136754 135592 134817 135198 135598 136241 135944 135740
sum_num_inserts_total=6540240
sum_num_deletes_by_thread=135038 135832 137037 137125 137168 135672 136014 136102 137933 136466 137574 135082 135800 137128 137263 137532 134783 135889 134794 135855 137240 136652 137407 135526 135410 134501 135698 137139 137515 136291 135551 136176 137705 136431 137071 135703 135942 136394 137292 137069 136545 136731 135015 135315 135897 136316 136150 135858
sum_num_deletes_total=6542627
sum_num_searches_by_thread=2428181 2447769 2459286 2471221 2470023 2447869 2450919 2444178 2479982 2456132 2462553 2438471 2440100 2452018 2466346 2470810 2440270 2456185 2436943 2462175 2459221 2463111 2463398 2449032 2443445 2431748 2455987 2471577 2464000 2459975 2435491 2448330 2489025 2455939 2473375 2442459 2456287 2441603 2470619 2470730 2459345 2455554 2437267 2437346 2450335 2464195 2449870 2448474
sum_num_searches_total=117829169
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2698319 2719987 2733161 2745559 2744085 2719030 2723287 2716373 2755091 2728421 2736805 2709547 2711777 2725145 2740616 2745094 2711087 2728684 2706354 2734398 2732935 2736704 2737727 2720152 2714460 2701469 2727876 2745814 2738697 2733117 2706439 2720137 2764519 2729214 2748024 2713566 2728852 2713363 2744985 2745069 2732644 2727877 2707099 2707859 2721830 2736752 2721964 2720072
average_num_operations_total=2727334
stdev_num_operations_total=2166
sum_num_operations_total=130912036
min_num_operations_total=2698319
max_num_operations_total=2764519
first_time_thread_terminate_by_thread=10000139 10000084 10000084 10000084 10000084 10000085 10000084 10000084 10000083 10000083 10000085 10000084 10000085 10000085 10000084 10000084 10000085 10000084 10000085 10000084 10000083 10000083 10000085 10000083 10000083 10000084 10000083 10000084 10000085 10000085 10000084 10000083 10000083 10000083 10000085 10000084 10000085 10000085 10000083 10000084 10000083 10000084 10000085 10000084 10000084 10000084 10000085 10000084
min_time_thread_terminate_total=10000083
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=11 5 5 6 6 6 5 6 5 6 6 5 6 6 6 5 5 6 6 6 5 6 5 5 5 5 5 6 6 6 5 6 5 6 6 5 6 6 6 5 5 6 6 6 5 6 5 5
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=
threads_final_keysum=99981157990792
threads_final_size=9998636
final_keysum=99981157990792
final_size=9998636
validate_result=success
Validation OK.
Structural validation OK.
total_find=117829169
total_rq=0
total_inserts=6540240
total_deletes=6542627
total_updates=13082867
total_queries=117829169
total_ops=130912036
find_throughput=11782916
rq_throughput=0
update_throughput=1308286
query_throughput=11782916
total_throughput=13091203
total find : 117829169
total rq : 0
total inserts : 6540240
total deletes : 6542627
total updates : 13082867
total queries : 117829169
total ops : 130912036
find throughput : 11782916
rq throughput : 0
update throughput : 1308286
query throughput : 11782916
total throughput : 13091203
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=20.244
PAPI_L3_TCM=5.98392
PAPI_TOT_CYC=8611.61
PAPI_TOT_INS=6257.12
garbage=589103070600444
total_execution_walltime=21.668s
REALTIME_MAIN_END_PERF_FORMAT=6994077.194611729
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=21.92, faults_major=0, faults_minor=435614, mem_maxresident_kb=1742508, user_cputime=989.81, sys_cputime=32.01, percent_cpu=4660%
timeout=false
algorithm=brown_abtree_tm_auto.debra.norec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2