__step=000100
__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 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000100.txt
__path_data=data/data000100.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994877.243123336
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=20000000
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=118722 prefilling_elapsed_millis=1.001s
prefilling_size=130866 prefilling_elapsed_millis=1.101s
prefilling_size=142961 prefilling_elapsed_millis=1.201s
prefilling_size=155241 prefilling_elapsed_millis=1.301s
prefilling_size=167028 prefilling_elapsed_millis=1.402s
prefilling_size=179094 prefilling_elapsed_millis=1.502s
prefilling_size=190830 prefilling_elapsed_millis=1.602s
prefilling_size=202648 prefilling_elapsed_millis=1.702s
prefilling_size=214047 prefilling_elapsed_millis=1.802s
prefilling_size=225774 prefilling_elapsed_millis=1.903s
prefilling_size=237522 prefilling_elapsed_millis=2.003s
prefilling_size=249170 prefilling_elapsed_millis=2.103s
prefilling_size=261250 prefilling_elapsed_millis=2.203s
prefilling_size=272640 prefilling_elapsed_millis=2.303s
prefilling_size=284278 prefilling_elapsed_millis=2.404s
prefilling_size=296116 prefilling_elapsed_millis=2.504s
prefilling_size=307718 prefilling_elapsed_millis=2.604s
prefilling_size=319128 prefilling_elapsed_millis=2.704s
prefilling_size=330544 prefilling_elapsed_millis=2.804s
prefilling_size=342353 prefilling_elapsed_millis=2.905s
prefilling_size=354124 prefilling_elapsed_millis=3.005s
prefilling_size=365805 prefilling_elapsed_millis=3.105s
prefilling_size=377241 prefilling_elapsed_millis=3.205s
prefilling_size=388306 prefilling_elapsed_millis=3.305s
prefilling_size=399780 prefilling_elapsed_millis=3.406s
prefilling_size=411454 prefilling_elapsed_millis=3.506s
prefilling_size=423071 prefilling_elapsed_millis=3.606s
prefilling_size=434352 prefilling_elapsed_millis=3.706s
prefilling_size=446307 prefilling_elapsed_millis=3.806s
prefilling_size=457895 prefilling_elapsed_millis=3.907s
prefilling_size=469492 prefilling_elapsed_millis=4.007s
prefilling_size=481135 prefilling_elapsed_millis=4.107s
prefilling_size=492693 prefilling_elapsed_millis=4.207s
prefilling_size=503879 prefilling_elapsed_millis=4.307s
prefilling_size=515290 prefilling_elapsed_millis=4.408s
prefilling_size=526472 prefilling_elapsed_millis=4.508s
prefilling_size=538074 prefilling_elapsed_millis=4.608s
prefilling_size=549752 prefilling_elapsed_millis=4.708s
prefilling_size=560848 prefilling_elapsed_millis=4.808s
prefilling_size=572132 prefilling_elapsed_millis=4.909s
prefilling_size=583654 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9416249 keys with 96 threads...
tid=0 thread_prefill_amount=6249 percent_done_estimate=6.4 elapsed_s=5 est_remaining_s=176 / 182
tid=0 thread_prefill_amount=8332 percent_done_estimate=8.5 elapsed_s=7 est_remaining_s=173 / 180
tid=0 thread_prefill_amount=10415 percent_done_estimate=10.6 elapsed_s=9 est_remaining_s=170 / 179
tid=0 thread_prefill_amount=12498 percent_done_estimate=12.7 elapsed_s=11 est_remaining_s=164 / 175
tid=0 thread_prefill_amount=14581 percent_done_estimate=14.9 elapsed_s=12 est_remaining_s=161 / 173
tid=0 thread_prefill_amount=16664 percent_done_estimate=17.0 elapsed_s=14 est_remaining_s=156 / 170
tid=0 thread_prefill_amount=18747 percent_done_estimate=19.1 elapsed_s=16 est_remaining_s=152 / 168
tid=0 thread_prefill_amount=20830 percent_done_estimate=21.2 elapsed_s=18 est_remaining_s=148 / 165
tid=0 thread_prefill_amount=22913 percent_done_estimate=23.4 elapsed_s=19 est_remaining_s=144 / 163
tid=0 thread_prefill_amount=24996 percent_done_estimate=25.5 elapsed_s=21 est_remaining_s=139 / 161
tid=0 thread_prefill_amount=27079 percent_done_estimate=27.6 elapsed_s=23 est_remaining_s=135 / 158
tid=0 thread_prefill_amount=29162 percent_done_estimate=29.7 elapsed_s=25 est_remaining_s=131 / 155
tid=0 thread_prefill_amount=31245 percent_done_estimate=31.9 elapsed_s=26 est_remaining_s=127 / 153
tid=0 thread_prefill_amount=33328 percent_done_estimate=34.0 elapsed_s=28 est_remaining_s=123 / 152
tid=0 thread_prefill_amount=35411 percent_done_estimate=36.1 elapsed_s=30 est_remaining_s=119 / 149
tid=0 thread_prefill_amount=37494 percent_done_estimate=38.2 elapsed_s=32 est_remaining_s=115 / 146
tid=0 thread_prefill_amount=39577 percent_done_estimate=40.3 elapsed_s=33 est_remaining_s=110 / 144
tid=0 thread_prefill_amount=41660 percent_done_estimate=42.5 elapsed_s=35 est_remaining_s=107 / 141
tid=0 thread_prefill_amount=43743 percent_done_estimate=44.6 elapsed_s=37 est_remaining_s=103 / 139
tid=0 thread_prefill_amount=45826 percent_done_estimate=46.7 elapsed_s=38 est_remaining_s=99 / 137
tid=0 thread_prefill_amount=47909 percent_done_estimate=48.8 elapsed_s=40 est_remaining_s=95 / 135
tid=0 thread_prefill_amount=49992 percent_done_estimate=51.0 elapsed_s=42 est_remaining_s=91 / 133
tid=0 thread_prefill_amount=52075 percent_done_estimate=53.1 elapsed_s=44 est_remaining_s=87 / 131
tid=0 thread_prefill_amount=54158 percent_done_estimate=55.2 elapsed_s=46 est_remaining_s=83 / 129
tid=0 thread_prefill_amount=56241 percent_done_estimate=57.3 elapsed_s=47 est_remaining_s=79 / 127
tid=0 thread_prefill_amount=58324 percent_done_estimate=59.5 elapsed_s=49 est_remaining_s=75 / 124
tid=0 thread_prefill_amount=60407 percent_done_estimate=61.6 elapsed_s=51 est_remaining_s=71 / 122
tid=0 thread_prefill_amount=62490 percent_done_estimate=63.7 elapsed_s=53 est_remaining_s=67 / 120
tid=0 thread_prefill_amount=64573 percent_done_estimate=65.8 elapsed_s=54 est_remaining_s=64 / 118
tid=0 thread_prefill_amount=66656 percent_done_estimate=68.0 elapsed_s=56 est_remaining_s=60 / 116
tid=0 thread_prefill_amount=68739 percent_done_estimate=70.1 elapsed_s=58 est_remaining_s=56 / 114
tid=0 thread_prefill_amount=70822 percent_done_estimate=72.2 elapsed_s=60 est_remaining_s=52 / 111
tid=0 thread_prefill_amount=72905 percent_done_estimate=74.3 elapsed_s=62 est_remaining_s=48 / 109
tid=0 thread_prefill_amount=74988 percent_done_estimate=76.5 elapsed_s=63 est_remaining_s=44 / 107
tid=0 thread_prefill_amount=77071 percent_done_estimate=78.6 elapsed_s=64 est_remaining_s=39 / 104
tid=0 thread_prefill_amount=79154 percent_done_estimate=80.7 elapsed_s=65 est_remaining_s=35 / 100
tid=0 thread_prefill_amount=81237 percent_done_estimate=82.8 elapsed_s=66 est_remaining_s=31 / 97
tid=0 thread_prefill_amount=83320 percent_done_estimate=84.9 elapsed_s=67 est_remaining_s=27 / 94
tid=0 thread_prefill_amount=85403 percent_done_estimate=87.1 elapsed_s=68 est_remaining_s=23 / 91
tid=0 thread_prefill_amount=87486 percent_done_estimate=89.2 elapsed_s=69 est_remaining_s=19 / 88
tid=0 thread_prefill_amount=89569 percent_done_estimate=91.3 elapsed_s=70 est_remaining_s=15 / 85
tid=0 thread_prefill_amount=91652 percent_done_estimate=93.4 elapsed_s=71 est_remaining_s=11 / 82
tid=0 thread_prefill_amount=93735 percent_done_estimate=95.6 elapsed_s=71 est_remaining_s=7 / 79
tid=0 thread_prefill_amount=95818 percent_done_estimate=97.7 elapsed_s=72 est_remaining_s=4 / 76
tid=0 thread_prefill_amount=97901 percent_done_estimate=99.8 elapsed_s=73 est_remaining_s=0 / 73
tid=0 thread_prefill_amount=99984 percent_done_estimate=101.9 elapsed_s=74 est_remaining_s=-3 / 71
tid=0 thread_prefill_amount=102067 percent_done_estimate=104.1 elapsed_s=75 est_remaining_s=-7 / 68
timing_elapsed 75.007s
prefilling_size=10000000 prefilling_elapsed_millis=75.186s
finished prefilling to size 10000000 for expected size 10000000 keysum=99980998622166, performing 14477989 updates; total_prefilling_elapsed_ms=75186 ms)
pref_size=10000000
pref_millis=75186
prefill_elapsed_ms=75187
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6994957.748161307
REALTIME_END_PERF_FORMAT=6994967.748310350
###############################################################################
################################## 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 2880 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.047s
tree_stats_numInternalsAtDepth=1 1 8 54 380 2880 21961 170025 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1343809
tree_stats_numNodesAtDepth=1 1 8 54 380 2880 21961 170025 1343809
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 9998898
tree_stats_avgDegreeAtDepth=1 8 6.75 7.03704 7.57895 7.62535 7.74213 7.9036 7.44071
tree_stats_height=9
tree_stats_numInternals=195310
tree_stats_numLeaves=1343809
tree_stats_numNodes=1539119
tree_stats_numKeys=9998898
tree_stats_avgDegreeInternal=7.88039
tree_stats_avgDegreeLeaves=7.44071
tree_stats_avgDegree=7.49651
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=46172
first_thread_announced_epoch_by_thread=1552 1550 1552 1552 1552 1552 1552 1552 1550 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1550 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552 1552
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=27721932
sum_fasthtm_abort_total=60222568
sum_htmpostfix_commit_total=1430823
sum_htmpostfix_abort_total=5422879
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=49547338
sum_stmprefix_commit_total=1
sum_slow_commit_total=25435
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=15525 15571 15245 15631 15372 19508 15793 15658 15761 15724 15536 19712 15580 15795 15314 15735 15891 19873 15909 15479 15494 15474 15995 20055 15779 15549 15068 15359 15709 20002 15926 15258 15778 16015 15735 19345 15482 15685 15686 15391 15872 19837 16060 15403 15404 15457 16047 19882 13495 13661 13755 13944 13510 12762 13765 13807 14021 13632 13838 16798 13951 13784 13502 13592 13439 16872 13701 13616 13987 13909 13736 16804 13585 13515 13722 13625 13338 13088 13566 13680 13773 13778 13919 16691 13686 13502 13537 13449 13560 16949 13827 13728 13785 13831 13988 16549
sum_num_inserts_total=1457911
sum_num_deletes_by_thread=15585 15389 15180 15369 15453 19694 15920 15660 15933 15587 15684 19752 15950 15787 15700 15628 16072 19512 15869 15463 15310 15659 16154 19859 15898 15611 15126 15137 15685 19809 15755 15240 15865 15804 15452 19570 15451 15628 15304 15250 15825 20183 15943 15485 15555 15537 15798 19976 13632 13838 13742 13515 13671 13020 13598 13761 13846 13720 13977 16747 13893 13705 13418 13514 13550 16702 13625 13585 13911 14140 13888 16688 13632 13582 13578 13779 13300 12959 13429 13814 13697 13900 13768 16596 14052 13687 13609 13442 13503 16866 13456 13468 13839 13822 13795 16841
sum_num_deletes_total=1457156
sum_num_searches_by_thread=279729 278112 274348 281437 276467 349305 285929 282140 285901 284251 281065 356687 285427 283139 280129 283702 288891 354524 286666 277614 279191 279714 290917 359017 286049 277250 272803 277063 283873 361409 284831 274532 287157 284856 279435 348345 278159 282549 279102 277403 286411 360921 290546 280012 279155 281874 285992 358445 243979 249096 244560 247280 244382 230446 246291 245582 250766 248836 250919 304549 250974 245109 243534 242207 243548 300272 244739 244456 252553 251487 250207 303077 244885 241223 245165 246098 240784 235528 241951 246366 247893 247655 247869 302077 248703 246369 244554 241012 241774 304387 245908 246077 250069 248924 252115 304415
sum_num_searches_total=26263124
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=310839 309072 304773 312437 307292 388507 317642 313458 317595 315562 312285 396151 316957 314721 311143 315065 320854 393909 318444 308556 309995 310847 323066 398931 317726 308410 302997 307559 315267 401220 316512 305030 318800 316675 310622 387260 309092 313862 310092 308044 318108 400941 322549 310900 310114 312868 317837 398303 271106 276595 272057 274739 271563 256228 273654 273150 278633 276188 278734 338094 278818 272598 270454 269313 270537 333846 272065 271657 280451 279536 277831 336569 272102 268320 272465 273502 267422 261575 268946 273860 275363 275333 275556 335364 276441 273558 271700 267903 268837 338202 273191 273273 277693 276577 279898 337805
average_num_operations_total=303939
stdev_num_operations_total=3633
sum_num_operations_total=29178191
min_num_operations_total=256228
max_num_operations_total=401220
first_time_thread_terminate_by_thread=10000437 10000319 10000319 10000192 10000488 10000403 10000336 10000151 10000444 10000566 10000465 10000148 10000348 10000482 10000148 10000485 10000331 10000440 10000148 10000151 10000416 10000150 10000482 10000492 10000429 10000538 10000368 10000491 10000455 10000488 10000484 10000368 10000147 10000541 10000190 10000414 10000403 10000462 10000428 10000415 10000473 10000150 10000148 10000416 10000428 10000396 10000345 10000480 10000159 10000431 10000155 10000553 10000546 10000554 10000399 10000168 10000484 10000356 10000436 10000572 10000335 10000158 10000366 10000457 10000155 10000482 10000423 10000444 10000548 10000369 10000450 10000160 10000483 10000193 10000354 10000162 10000372 10000454 10000493 10000542 10000356 10000468 10000162 10000552 10000559 10000471 10000547 10000160 10000398 10000158 10000545 10000545 10000183 10000162 10000399 10000369
min_time_thread_terminate_total=10000147
max_time_thread_terminate_total=10000572
first_time_thread_start_by_thread=11 6 6 6 5 6 6 6 6 6 6 5 6 5 6 6 5 6 5 6 6 5 5 6 5 6 6 6 5 6 6 6 6 6 6 5 6 5 6 6 5 6 5 6 6 5 5 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6 6
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=
threads_final_keysum=99979232843497
threads_final_size=9998898
final_keysum=99979232843497
final_size=9998898
validate_result=success
Validation OK.
Structural validation OK.
total_find=26263124
total_rq=0
total_inserts=1457911
total_deletes=1457156
total_updates=2915067
total_queries=26263124
total_ops=29178191
find_throughput=2626312
rq_throughput=0
update_throughput=291506
query_throughput=2626312
total_throughput=2917819
total find : 26263124
total rq : 0
total inserts : 1457911
total deletes : 1457156
total updates : 2915067
total queries : 26263124
total ops : 29178191
find throughput : 2626312
rq throughput : 0
update throughput : 291506
query throughput : 2626312
total throughput : 2917819
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=39.8034
PAPI_L3_TCM=12.8789
PAPI_TOT_CYC=81861.8
PAPI_TOT_INS=4285.5
garbage=130074299833499
total_execution_walltime=90.658s
REALTIME_MAIN_END_PERF_FORMAT=6994967.911685556
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=91.09, faults_major=0, faults_minor=1092491, mem_maxresident_kb=4368208, user_cputime=8136.31, sys_cputime=148.46, percent_cpu=9094%
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