__step=000308
__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.hybridnorec -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000308.txt
__path_data=data/data000308.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998437.883830490
binary=./brown_abtree_tm_auto.debra.hybridnorec
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=2000000
PREFILL_THREADS=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.015s
prefilling_size=279087 prefilling_elapsed_millis=1.001s
prefilling_size=302323 prefilling_elapsed_millis=1.101s
prefilling_size=324814 prefilling_elapsed_millis=1.201s
prefilling_size=346325 prefilling_elapsed_millis=1.302s
prefilling_size=367158 prefilling_elapsed_millis=1.402s
prefilling_size=387346 prefilling_elapsed_millis=1.502s
prefilling_size=407071 prefilling_elapsed_millis=1.602s
prefilling_size=426058 prefilling_elapsed_millis=1.702s
prefilling_size=444289 prefilling_elapsed_millis=1.803s
prefilling_size=462019 prefilling_elapsed_millis=1.903s
prefilling_size=479058 prefilling_elapsed_millis=2.003s
prefilling_size=495674 prefilling_elapsed_millis=2.103s
prefilling_size=511770 prefilling_elapsed_millis=2.203s
prefilling_size=527188 prefilling_elapsed_millis=2.304s
prefilling_size=542322 prefilling_elapsed_millis=2.404s
prefilling_size=556830 prefilling_elapsed_millis=2.504s
prefilling_size=570917 prefilling_elapsed_millis=2.604s
prefilling_size=584495 prefilling_elapsed_millis=2.704s
prefilling_size=597647 prefilling_elapsed_millis=2.805s
prefilling_size=610603 prefilling_elapsed_millis=2.905s
prefilling_size=622742 prefilling_elapsed_millis=3.005s
prefilling_size=634607 prefilling_elapsed_millis=3.105s
prefilling_size=646105 prefilling_elapsed_millis=3.205s
prefilling_size=657195 prefilling_elapsed_millis=3.306s
prefilling_size=667886 prefilling_elapsed_millis=3.406s
prefilling_size=678292 prefilling_elapsed_millis=3.506s
prefilling_size=688281 prefilling_elapsed_millis=3.606s
prefilling_size=697938 prefilling_elapsed_millis=3.706s
prefilling_size=707537 prefilling_elapsed_millis=3.807s
prefilling_size=716762 prefilling_elapsed_millis=3.907s
prefilling_size=725667 prefilling_elapsed_millis=4.007s
prefilling_size=734113 prefilling_elapsed_millis=4.107s
prefilling_size=742661 prefilling_elapsed_millis=4.207s
prefilling_size=750470 prefilling_elapsed_millis=4.307s
prefilling_size=758488 prefilling_elapsed_millis=4.408s
prefilling_size=766234 prefilling_elapsed_millis=4.508s
prefilling_size=773254 prefilling_elapsed_millis=4.608s
prefilling_size=780397 prefilling_elapsed_millis=4.708s
prefilling_size=787433 prefilling_elapsed_millis=4.808s
prefilling_size=794314 prefilling_elapsed_millis=4.909s
prefilling_size=801152 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 198803 keys with 96 threads...
tid=0 thread_prefill_amount=8332 percent_done_estimate=402.3 elapsed_s=5 est_remaining_s=-9 / -4
timing_elapsed 0.619647s
prefilling_size=1000000 prefilling_elapsed_millis=0.621s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000109373190, performing 3639807 updates; total_prefilling_elapsed_ms=621 ms)
pref_size=1000000
pref_millis=621
prefill_elapsed_ms=621
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998443.820162638
REALTIME_END_PERF_FORMAT=6998453.820264927

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

tree_stats_computeWalltime=0.038s

tree_stats_numInternalsAtDepth=1 1 6 49 362 2792 21843 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 171938
tree_stats_numNodesAtDepth=1 1 6 49 362 2792 21843 171938
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 999666
tree_stats_avgDegreeAtDepth=1 6 8.16667 7.38776 7.71271 7.82342 7.87154 5.81411

tree_stats_height=8
tree_stats_numInternals=25054
tree_stats_numLeaves=171938
tree_stats_numNodes=196992
tree_stats_numKeys=999666

tree_stats_avgDegreeInternal=7.8627
tree_stats_avgDegreeLeaves=5.81411
tree_stats_avgDegree=6.07465
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=97902
first_thread_announced_epoch_by_thread=2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128 2128
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=

linear_histogram_of_none_tx_rdset_size_full_data=

linear_histogram_of_none_tx_wrset_size_full_data=
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=30548 30369 31109 31669 31848 31466 30856 31194 31487 32221 31687 31572 30802 30107 30618 31854 31251 31710 30629 30753 30828 31678 31194 31642 30302 30523 30586 31972 31462 31801 31226 31281 31352 31870 32229 31404 30631 29869 30478 31686 31385 31682 30750 30450 30514 31560 31346 31349 33659 33899 33860 34388 34723 34736 34629 34350 34573 34821 34476 34825 34223 34383 33860 34441 34495 33497 34164 33764 34769 35232 34404 35175 33829 33723 33779 34471 35012 34925 34291 34580 34279 35184 34512 34819 33960 34270 33990 34615 34748 33091 33921 33502 34577 35425 34635 34982
sum_num_inserts_total=3147266
sum_num_deletes_by_thread=30446 30235 30973 31395 31463 31635 31157 30942 31469 32148 31756 31901 31017 30125 30600 31464 31117 31633 30588 30545 30908 31793 31273 31390 30621 30182 30858 31175 31391 31926 30782 30803 31320 32002 31687 31176 30700 29653 30487 31436 31064 31706 30731 30705 30687 31438 31469 31183 33429 33745 33985 34664 35372 34200 34274 34244 34120 35316 34799 35217 33700 34462 34000 34655 34365 33836 34076 33863 34546 35252 35032 34961 33825 33604 34239 34558 34564 34255 34281 34273 34094 35002 34736 34929 33846 34097 33753 34442 34797 33224 33538 33822 34241 35316 35404 34852
sum_num_deletes_total=3142960
sum_num_searches_by_thread=549395 541720 557868 567184 569831 573103 556210 564731 566616 581901 571848 570122 554724 542776 549920 567052 560231 567330 551645 550691 556640 574837 562416 569117 551643 546165 554819 570121 569254 576109 558448 558494 567378 575819 571801 563374 554631 535270 547578 569682 559763 571787 553849 556181 548777 569651 565805 568567 606042 608751 610372 618799 630875 619124 622038 618187 617268 629506 621811 625534 612043 622953 611477 621177 625995 604692 616288 601745 623841 637459 624132 628806 611624 605036 615761 618519 622488 626464 614592 622516 613355 629595 626541 624868 609945 617193 611569 625899 625633 591151 609550 602797 616526 637200 625393 633386
sum_num_searches_total=56639390
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=610389 602324 619950 630248 633142 636204 618223 626867 629572 646270 635291 633595 616543 603008 611138 630370 622599 630673 612862 611989 618376 638308 624883 632149 612566 606870 616263 633268 632107 639836 620456 620578 630050 639691 635717 625954 615962 594792 608543 632804 622212 635175 615330 617336 609978 632649 628620 631099 673130 676395 678217 687851 700970 688060 690941 686781 685961 699643 691086 695576 679966 691798 679337 690273 694855 672025 684528 669372 693156 707943 693568 698942 679278 672363 683779 687548 692064 695644 683164 691369 681728 699781 695789 694616 677751 685560 679312 694956 695178 657466 677009 670121 685344 707941 695432 703220
average_num_operations_total=655516
stdev_num_operations_total=3479
sum_num_operations_total=62929616
min_num_operations_total=594792
max_num_operations_total=707943
first_time_thread_terminate_by_thread=10000181 10000250 10000153 10000196 10000168 10000259 10000210 10000212 10000203 10000172 10000236 10000251 10000113 10000255 10000155 10000257 10000252 10000254 10000158 10000102 10000253 10000224 10000102 10000235 10000102 10000165 10000178 10000249 10000187 10000267 10000101 10000162 10000133 10000120 10000118 10000260 10000111 10000242 10000154 10000246 10000186 10000124 10000156 10000202 10000253 10000256 10000102 10000247 10000141 10000102 10000238 10000136 10000183 10000149 10000130 10000142 10000102 10000208 10000190 10000222 10000193 10000102 10000228 10000139 10000194 10000109 10000231 10000147 10000229 10000104 10000264 10000217 10000214 10000199 10000262 10000273 10000245 10000128 10000229 10000145 10000265 10000239 10000233 10000102 10000182 10000270 10000219 10000136 10000195 10000204 10000243 10000176 10000240 10000262 10000102 10000226
min_time_thread_terminate_total=10000101
max_time_thread_terminate_total=10000273
first_time_thread_start_by_thread=9 5 5 5 4 5 5 4 5 5 5 5 4 5 5 4 5 5 5 5 5 4 5 5 4 5 5 5 4 5 5 4 5 5 5 5 4 5 5 4 5 5 5 5 5 4 5 5 5 5 5 6 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=999148072033
threads_final_size=999666
final_keysum=999148072033
final_size=999666
validate_result=success
Validation OK.
Structural validation OK.

total_find=56639390
total_rq=0
total_inserts=3147266
total_deletes=3142960
total_updates=6290226
total_queries=56639390
total_ops=62929616
find_throughput=5663939
rq_throughput=0
update_throughput=629022
query_throughput=5663939
total_throughput=6292961


total find                    : 56639390
total rq                      : 0
total inserts                 : 3147266
total deletes                 : 3142960
total updates                 : 6290226
total queries                 : 56639390
total ops                     : 62929616
find throughput               : 5663939
rq throughput                 : 0
update throughput             : 629022
query throughput              : 5663939
total throughput              : 6292961

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=22.0362
PAPI_L3_TCM=3.83357
PAPI_TOT_CYC=42276.1
PAPI_TOT_INS=5262.45
garbage=27739983026275
total_execution_walltime=16.091s
REALTIME_MAIN_END_PERF_FORMAT=6998453.976021824
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 62539492
[slow htm]
[fallback]
total fallback commit         : 4029932
seconds global lock is held   : 0.438495
[time_cmd_output] time_elapsed_sec=16.23, faults_major=0, faults_minor=129925, mem_maxresident_kb=517260, user_cputime=1516.23, sys_cputime=5.69, percent_cpu=9374%
timeout=false
algorithm=brown_abtree_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2