__step=000307
__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=data000307.txt
__path_data=data/data000307.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998421.555307754
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=273991 prefilling_elapsed_millis=1.001s
prefilling_size=296725 prefilling_elapsed_millis=1.101s
prefilling_size=318461 prefilling_elapsed_millis=1.201s
prefilling_size=339425 prefilling_elapsed_millis=1.301s
prefilling_size=360047 prefilling_elapsed_millis=1.402s
prefilling_size=379714 prefilling_elapsed_millis=1.502s
prefilling_size=399070 prefilling_elapsed_millis=1.602s
prefilling_size=417587 prefilling_elapsed_millis=1.702s
prefilling_size=435433 prefilling_elapsed_millis=1.802s
prefilling_size=452766 prefilling_elapsed_millis=1.903s
prefilling_size=469592 prefilling_elapsed_millis=2.003s
prefilling_size=485766 prefilling_elapsed_millis=2.103s
prefilling_size=501637 prefilling_elapsed_millis=2.203s
prefilling_size=516792 prefilling_elapsed_millis=2.303s
prefilling_size=531828 prefilling_elapsed_millis=2.404s
prefilling_size=546147 prefilling_elapsed_millis=2.504s
prefilling_size=560230 prefilling_elapsed_millis=2.604s
prefilling_size=573690 prefilling_elapsed_millis=2.704s
prefilling_size=586743 prefilling_elapsed_millis=2.804s
prefilling_size=599415 prefilling_elapsed_millis=2.905s
prefilling_size=611817 prefilling_elapsed_millis=3.005s
prefilling_size=623615 prefilling_elapsed_millis=3.105s
prefilling_size=635357 prefilling_elapsed_millis=3.205s
prefilling_size=646505 prefilling_elapsed_millis=3.305s
prefilling_size=657369 prefilling_elapsed_millis=3.406s
prefilling_size=667616 prefilling_elapsed_millis=3.506s
prefilling_size=677544 prefilling_elapsed_millis=3.606s
prefilling_size=687085 prefilling_elapsed_millis=3.706s
prefilling_size=696610 prefilling_elapsed_millis=3.806s
prefilling_size=706034 prefilling_elapsed_millis=3.906s
prefilling_size=714783 prefilling_elapsed_millis=4.007s
prefilling_size=723248 prefilling_elapsed_millis=4.107s
prefilling_size=731770 prefilling_elapsed_millis=4.207s
prefilling_size=739683 prefilling_elapsed_millis=4.307s
prefilling_size=747573 prefilling_elapsed_millis=4.407s
prefilling_size=754875 prefilling_elapsed_millis=4.508s
prefilling_size=762247 prefilling_elapsed_millis=4.608s
prefilling_size=769540 prefilling_elapsed_millis=4.708s
prefilling_size=776338 prefilling_elapsed_millis=4.808s
prefilling_size=783006 prefilling_elapsed_millis=4.909s
prefilling_size=789235 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 210730 keys with 96 threads...
tid=0 thread_prefill_amount=10415 percent_done_estimate=474.5 elapsed_s=6 est_remaining_s=-10 / -4
timing_elapsed 0.646934s
prefilling_size=1000000 prefilling_elapsed_millis=0.648s
finished prefilling to size 1000000 for expected size 1000000 keysum=999448337808, performing 3574024 updates; total_prefilling_elapsed_ms=648 ms)
pref_size=1000000
pref_millis=648
prefill_elapsed_ms=648
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998427.525555299
REALTIME_END_PERF_FORMAT=6998437.525680072

###############################################################################
################################## 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.039s

tree_stats_numInternalsAtDepth=1 1 7 46 367 2792 21713 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 171315
tree_stats_numNodesAtDepth=1 1 7 46 367 2792 21713 171315
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 998932
tree_stats_avgDegreeAtDepth=1 7 6.57143 7.97826 7.60763 7.77686 7.88997 5.83097

tree_stats_height=8
tree_stats_numInternals=24927
tree_stats_numLeaves=171315
tree_stats_numNodes=196242
tree_stats_numKeys=998932

tree_stats_avgDegreeInternal=7.87267
tree_stats_avgDegreeLeaves=5.83097
tree_stats_avgDegree=6.09031
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=98352
first_thread_announced_epoch_by_thread=2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140 2140
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=29056 29953 30009 29853 29724 30029 29805 30307 30497 29948 30080 30018 29439 29022 30040 30122 30032 30016 29583 29912 29722 30241 29945 29304 29124 29227 29827 30297 30312 29633 29729 29816 30297 30372 29111 30425 29633 29716 30024 30249 30346 30133 29606 30173 30122 30288 30213 29381 33985 34535 34998 34848 35255 34511 35079 35124 35732 35613 35209 35883 34906 34881 35304 35124 35289 35652 34909 34409 35185 35128 34804 35422 34355 33647 34580 35044 35141 34839 34810 34447 35328 34999 35057 36133 34874 34433 35175 34624 35227 34699 34559 34711 35107 34626 34731 35892
sum_num_inserts_total=3113534
sum_num_deletes_by_thread=28677 29713 30244 29687 30096 29566 30053 30295 30500 30041 30551 30321 29299 29201 30402 30153 30272 29908 29610 29807 30060 29953 29961 29276 29296 29159 30033 29858 30438 29669 29753 29977 30386 30194 29129 30185 30391 29626 30139 30242 30387 30176 29367 29958 29927 30129 30157 29384 34204 34508 34995 34796 34745 34884 35078 35199 35224 35517 35231 35426 34765 34931 34864 34401 35305 35980 35041 34500 35367 35046 35159 35149 34649 33515 34240 34737 35178 34635 34810 34563 35595 34836 35315 36152 34634 34216 35052 34678 35345 34700 34953 34508 35204 34502 35351 35731
sum_num_deletes_total=3113020
sum_num_searches_by_thread=520275 539894 538570 539059 537625 538330 538844 547982 545435 538881 547409 539780 529701 525827 550002 540632 544669 539884 532506 535091 544968 541968 541833 528303 529410 526802 540693 538703 544420 535077 533064 535116 549511 544558 523659 547487 541535 533555 543826 541079 548036 546810 529717 539529 539678 542413 541710 528667 617986 620916 628655 627640 629641 626042 631688 632616 639454 640557 633426 643871 627183 627721 628034 622724 635631 644570 627410 617410 639876 631355 628316 632368 622447 604785 620033 626742 633921 623361 625293 621674 634169 633299 637051 647333 626508 619383 633590 622609 638807 626711 625204 621737 635443 624166 630586 642827
sum_num_searches_total=56065292
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=578008 599560 598823 598599 597445 597925 598702 608584 606432 598870 608040 600119 588439 584050 610444 600907 604973 599808 591699 594810 604750 602162 601739 586883 587830 585188 600553 598858 605170 594379 592546 594909 610194 605124 581899 608097 601559 592897 603989 601570 608769 607119 588690 599660 599727 602830 602080 587432 686175 689959 698648 697284 699641 695437 701845 702939 710410 711687 703866 715180 696854 697533 698202 692249 706225 716202 697360 686319 710428 701529 698279 702939 691451 671947 688853 696523 704240 692835 694913 690684 705092 703134 707423 719618 696016 688032 703817 691911 709379 696110 694716 690956 705754 693294 700668 714450
average_num_operations_total=648873
stdev_num_operations_total=5278
sum_num_operations_total=62291846
min_num_operations_total=578008
max_num_operations_total=719618
first_time_thread_terminate_by_thread=10000249 10000291 10000215 10000129 10000194 10000258 10000216 10000247 10000776 10000174 10000124 10000197 10000271 10000134 10000240 10000218 10000272 10000142 10000123 10000274 10000226 10000256 10000201 10000184 10000251 10000292 10000280 10000172 10000292 10000259 10000168 10000245 10000288 10000248 10000254 10000243 10000140 10000294 10000198 10000774 10000126 10000224 10000182 10000287 10000192 10000289 10000210 10000187 10000261 10000206 10000180 10000160 10000268 10000134 10000282 10000284 10000212 10000204 10000124 10000176 10000267 10000133 10000152 10000228 10000147 10000238 10000229 10000265 10000276 10000157 10000769 10000231 10000149 10000770 10000221 10000208 10000189 10000237 10000124 10000283 10000234 10000179 10000275 10000779 10000162 10000269 10000163 10000123 10000124 10000266 10000285 10000124 10000277 10000264 10000126 10000125
min_time_thread_terminate_total=10000123
max_time_thread_terminate_total=10000779
first_time_thread_start_by_thread=9 4 4 5 5 4 5 4 4 4 5 5 4 4 5 5 5 5 4 5 4 4 5 4 3 4 4 5 5 4 5 4 4 4 5 5 4 4 5 5 5 5 4 5 4 4 5 4 5 5 5 5 5 5 5 5 5 5 6 6 5 5 5 5 5 5 4 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 6 6 5 5 5 5 5 5 4 5 5 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=998615847203
threads_final_size=998932
final_keysum=998615847203
final_size=998932
validate_result=success
Validation OK.
Structural validation OK.

total_find=56065292
total_rq=0
total_inserts=3113534
total_deletes=3113020
total_updates=6226554
total_queries=56065292
total_ops=62291846
find_throughput=5606529
rq_throughput=0
update_throughput=622655
query_throughput=5606529
total_throughput=6229184


total find                    : 56065292
total rq                      : 0
total inserts                 : 3113534
total deletes                 : 3113020
total updates                 : 6226554
total queries                 : 56065292
total ops                     : 62291846
find throughput               : 5606529
rq throughput                 : 0
update throughput             : 622655
query throughput              : 5606529
total throughput              : 6229184

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=21.9148
PAPI_L3_TCM=3.81627
PAPI_TOT_CYC=42728
PAPI_TOT_INS=5202.1
garbage=28069749802726
total_execution_walltime=16.127s
REALTIME_MAIN_END_PERF_FORMAT=6998437.683244253
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 61910651
[slow htm]
[fallback]
total fallback commit         : 3955220
seconds global lock is held   : 0.445127
[time_cmd_output] time_elapsed_sec=16.27, faults_major=0, faults_minor=129511, mem_maxresident_kb=515588, user_cputime=1518.81, sys_cputime=5.27, percent_cpu=9366%
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=1