__step=000316
__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.rhnorec_post -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=data000316.txt
__path_data=data/data000316.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998576.656133412
binary=./brown_abtree_tm_auto.debra.rhnorec_post
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


RH noREC w/postfix htm system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.012s
prefilling_size=117827 prefilling_elapsed_millis=1.001s
prefilling_size=129475 prefilling_elapsed_millis=1.101s
prefilling_size=140690 prefilling_elapsed_millis=1.201s
prefilling_size=151793 prefilling_elapsed_millis=1.302s
prefilling_size=162889 prefilling_elapsed_millis=1.402s
prefilling_size=173896 prefilling_elapsed_millis=1.502s
prefilling_size=184794 prefilling_elapsed_millis=1.602s
prefilling_size=195445 prefilling_elapsed_millis=1.702s
prefilling_size=206414 prefilling_elapsed_millis=1.803s
prefilling_size=217207 prefilling_elapsed_millis=1.903s
prefilling_size=227488 prefilling_elapsed_millis=2.003s
prefilling_size=237421 prefilling_elapsed_millis=2.103s
prefilling_size=247508 prefilling_elapsed_millis=2.203s
prefilling_size=257870 prefilling_elapsed_millis=2.304s
prefilling_size=267960 prefilling_elapsed_millis=2.404s
prefilling_size=277958 prefilling_elapsed_millis=2.504s
prefilling_size=287802 prefilling_elapsed_millis=2.604s
prefilling_size=298068 prefilling_elapsed_millis=2.704s
prefilling_size=308048 prefilling_elapsed_millis=2.805s
prefilling_size=317424 prefilling_elapsed_millis=2.905s
prefilling_size=326704 prefilling_elapsed_millis=3.005s
prefilling_size=336202 prefilling_elapsed_millis=3.105s
prefilling_size=345423 prefilling_elapsed_millis=3.205s
prefilling_size=354832 prefilling_elapsed_millis=3.306s
prefilling_size=363765 prefilling_elapsed_millis=3.406s
prefilling_size=372664 prefilling_elapsed_millis=3.506s
prefilling_size=381888 prefilling_elapsed_millis=3.606s
prefilling_size=390534 prefilling_elapsed_millis=3.706s
prefilling_size=399402 prefilling_elapsed_millis=3.807s
prefilling_size=408026 prefilling_elapsed_millis=3.907s
prefilling_size=416552 prefilling_elapsed_millis=4.007s
prefilling_size=425303 prefilling_elapsed_millis=4.107s
prefilling_size=433566 prefilling_elapsed_millis=4.207s
prefilling_size=441694 prefilling_elapsed_millis=4.308s
prefilling_size=449740 prefilling_elapsed_millis=4.408s
prefilling_size=457444 prefilling_elapsed_millis=4.508s
prefilling_size=465662 prefilling_elapsed_millis=4.608s
prefilling_size=473421 prefilling_elapsed_millis=4.708s
prefilling_size=481070 prefilling_elapsed_millis=4.809s
prefilling_size=488448 prefilling_elapsed_millis=4.909s
prefilling_size=496168 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 503774 keys with 96 threads...
tid=0 thread_prefill_amount=6249 percent_done_estimate=119.1 elapsed_s=6 est_remaining_s=-2 / 4
tid=0 thread_prefill_amount=8332 percent_done_estimate=158.8 elapsed_s=8 est_remaining_s=-6 / 1
timing_elapsed 3.92687s
prefilling_size=1000000 prefilling_elapsed_millis=3.936s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000158289361, performing 2206533 updates; total_prefilling_elapsed_ms=3936 ms)
pref_size=1000000
pref_millis=3936
prefill_elapsed_ms=3936
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998585.916158967
REALTIME_END_PERF_FORMAT=6998595.916297696

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

tree_stats_computeWalltime=0.037s

tree_stats_numInternalsAtDepth=1 1 6 46 344 2611 20378 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 161001
tree_stats_numNodesAtDepth=1 1 6 46 344 2611 20378 161001
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 998195
tree_stats_avgDegreeAtDepth=1 6 7.66667 7.47826 7.59012 7.80467 7.90073 6.19993

tree_stats_height=8
tree_stats_numInternals=23387
tree_stats_numLeaves=161001
tree_stats_numNodes=184388
tree_stats_numKeys=998195

tree_stats_avgDegreeInternal=7.88421
tree_stats_avgDegreeLeaves=6.19993
tree_stats_avgDegree=6.41356
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=53560
first_thread_announced_epoch_by_thread=1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184 1184
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=33830722
sum_fasthtm_abort_total=72548615
sum_htmpostfix_commit_total=1763349
sum_htmpostfix_abort_total=5600849
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=51581415
sum_stmprefix_commit_total=75
sum_slow_commit_total=17198
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=17944 17609 17929 17507 14425 18933 17952 17799 17674 18275 14769 19192 17911 17715 18018 18270 14214 19344 17958 17646 17628 17834 14591 18939 17936 17617 18105 17772 14330 18446 18298 17876 18007 18572 14531 19488 17800 17846 17788 17767 14481 19402 17787 17981 17739 18049 14766 19337 20067 20113 20580 20418 15620 15607 20950 20403 20613 20158 15398 21948 20728 20675 20320 20275 15715 21133 20592 19954 20687 20420 16551 21672 20383 20508 20019 20266 15723 15666 20705 20566 20598 20263 16278 21930 20745 20514 19949 20207 15641 21222 19966 19900 20740 20293 16444 21653
sum_num_inserts_total=1782573
sum_num_deletes_by_thread=17954 17483 18016 17586 14998 18743 17811 17783 17924 18299 14586 19458 18013 17655 17850 17913 14368 19180 17913 17529 17429 17822 14826 19328 17874 17754 18007 17939 14249 18625 18221 17820 17677 18258 14533 19347 17945 18006 17764 17999 14340 19191 17940 17990 17872 18062 14632 19284 20043 20254 20476 20367 15799 15513 20854 20429 20733 20240 15338 21922 20754 20422 20318 20344 15590 21187 20645 19860 20345 20296 16664 21845 20325 20481 19847 20275 15730 15841 20943 20352 20599 20163 16215 22076 20869 20211 20288 20133 15642 20948 20358 19895 20574 20158 16354 21865
sum_num_deletes_total=1782176
sum_num_searches_by_thread=322880 315303 326011 315916 266485 341098 323721 317154 321532 329096 263786 345587 324334 317863 319246 325368 252887 345094 324845 318597 312708 320873 264559 342750 322142 317155 326643 319487 258108 332492 331819 321760 318079 328808 262072 344918 324281 321591 320169 319613 260120 346466 319012 326101 320820 325864 264636 346432 361318 364001 368707 366529 282539 279911 374251 366836 373299 363040 278259 394494 374612 367433 364656 367148 281055 378915 370290 357317 368753 365945 294766 390825 369877 371236 358739 364521 282161 282520 371183 370120 369398 363618 290718 396694 373534 366543 363589 362808 281651 380533 362525 353669 370270 361961 294664 392883
sum_num_searches_total=32046595
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=358778 350395 361956 351009 295908 378774 359484 352736 357130 365670 293141 384237 360258 353233 355114 361551 281469 383618 360716 353772 347765 356529 293976 381017 357952 352526 362755 355198 286687 369563 368338 357456 353763 365638 291136 383753 360026 357443 355721 355379 288941 385059 354739 362072 356431 361975 294034 385053 401428 404368 409763 407314 313958 311031 416055 407668 414645 403438 308995 438364 416094 408530 405294 407767 312360 421235 411527 397131 409785 406661 327981 434342 410585 412225 398605 405062 313614 314027 412831 411038 410595 404044 323211 440700 415148 407268 403826 403148 312934 422703 402849 393464 411584 402412 327462 436401
average_num_operations_total=370951
stdev_num_operations_total=4147
sum_num_operations_total=35611344
min_num_operations_total=281469
max_num_operations_total=440700
first_time_thread_terminate_by_thread=10000408 10000194 10000197 10000236 10000400 10000132 10000263 10000194 10000312 10000277 10000483 10000382 10000351 10000132 10000255 10000391 10000405 10000291 10000844 10000403 10000197 10000396 10000394 10000362 10000401 10000134 10000394 10000132 10000329 10000268 10000175 10000338 10000349 10000179 10000397 10000367 10000390 10000399 10000206 10000184 10000407 10000841 10000133 10000403 10000387 10000369 10000346 10000136 10000295 10000250 10000220 10000366 10000139 10000372 10000307 10000173 10000171 10000273 10000347 10000220 10000229 10000307 10000275 10000354 10000348 10000237 10000174 10000190 10000302 10000487 10000160 10000230 10000144 10000313 10000250 10000371 10000374 10000358 10000248 10000300 10000378 10000192 10000208 10000265 10000317 10000385 10000139 10000357 10000360 10000346 10000380 10000256 10000318 10000204 10000316 10000283
min_time_thread_terminate_total=10000132
max_time_thread_terminate_total=10000844
first_time_thread_start_by_thread=8 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 17 4 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 17 6 5 5 5 5 6 5 5 6 6 6 6 6 5 6 5 5 5 6 5 6 5 5 5 6 5 5 5 5 6 5 5 6 6 6 6 6 5 6 5 5 5 6 5 6 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=17
sum_duration_all_ops_total=

threads_final_keysum=997595711988
threads_final_size=998195
final_keysum=997595711988
final_size=998195
validate_result=success
Validation OK.
Structural validation OK.

total_find=32046595
total_rq=0
total_inserts=1782573
total_deletes=1782176
total_updates=3564749
total_queries=32046595
total_ops=35611344
find_throughput=3204659
rq_throughput=0
update_throughput=356474
query_throughput=3204659
total_throughput=3561134


total find                    : 32046595
total rq                      : 0
total inserts                 : 1782573
total deletes                 : 1782176
total updates                 : 3564749
total queries                 : 32046595
total ops                     : 35611344
find throughput               : 3204659
rq throughput                 : 0
update throughput             : 356474
query throughput              : 3204659
total throughput              : 3561134

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=31.5043
PAPI_L3_TCM=7.34563
PAPI_TOT_CYC=67185.7
PAPI_TOT_INS=3595.57
garbage=15546926295781
total_execution_walltime=19.41s
REALTIME_MAIN_END_PERF_FORMAT=6998596.66869839
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=19.59, faults_major=0, faults_minor=201621, mem_maxresident_kb=804912, user_cputime=1799.82, sys_cputime=30.09, percent_cpu=9340%
timeout=false
algorithm=brown_abtree_tm_auto.debra.rhnorec_post
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2