__step=000099
__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=data000099.txt
__path_data=data/data000099.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6994779.668136077
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.015s
prefilling_size=112361 prefilling_elapsed_millis=1.001s
prefilling_size=123613 prefilling_elapsed_millis=1.101s
prefilling_size=134775 prefilling_elapsed_millis=1.201s
prefilling_size=146388 prefilling_elapsed_millis=1.301s
prefilling_size=158356 prefilling_elapsed_millis=1.402s
prefilling_size=170374 prefilling_elapsed_millis=1.502s
prefilling_size=182011 prefilling_elapsed_millis=1.602s
prefilling_size=193308 prefilling_elapsed_millis=1.702s
prefilling_size=204536 prefilling_elapsed_millis=1.802s
prefilling_size=216253 prefilling_elapsed_millis=1.903s
prefilling_size=227579 prefilling_elapsed_millis=2.003s
prefilling_size=239284 prefilling_elapsed_millis=2.103s
prefilling_size=251027 prefilling_elapsed_millis=2.203s
prefilling_size=262588 prefilling_elapsed_millis=2.303s
prefilling_size=274009 prefilling_elapsed_millis=2.404s
prefilling_size=284995 prefilling_elapsed_millis=2.504s
prefilling_size=296062 prefilling_elapsed_millis=2.604s
prefilling_size=307220 prefilling_elapsed_millis=2.704s
prefilling_size=318711 prefilling_elapsed_millis=2.804s
prefilling_size=329946 prefilling_elapsed_millis=2.905s
prefilling_size=341198 prefilling_elapsed_millis=3.005s
prefilling_size=352783 prefilling_elapsed_millis=3.105s
prefilling_size=363851 prefilling_elapsed_millis=3.205s
prefilling_size=375346 prefilling_elapsed_millis=3.305s
prefilling_size=386495 prefilling_elapsed_millis=3.406s
prefilling_size=397634 prefilling_elapsed_millis=3.506s
prefilling_size=408869 prefilling_elapsed_millis=3.606s
prefilling_size=420134 prefilling_elapsed_millis=3.706s
prefilling_size=431435 prefilling_elapsed_millis=3.806s
prefilling_size=442604 prefilling_elapsed_millis=3.906s
prefilling_size=453859 prefilling_elapsed_millis=4.007s
prefilling_size=464344 prefilling_elapsed_millis=4.107s
prefilling_size=475625 prefilling_elapsed_millis=4.207s
prefilling_size=486989 prefilling_elapsed_millis=4.307s
prefilling_size=497637 prefilling_elapsed_millis=4.407s
prefilling_size=508669 prefilling_elapsed_millis=4.508s
prefilling_size=519621 prefilling_elapsed_millis=4.608s
prefilling_size=530537 prefilling_elapsed_millis=4.708s
prefilling_size=541511 prefilling_elapsed_millis=4.808s
prefilling_size=552472 prefilling_elapsed_millis=4.908s
prefilling_size=563496 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9436403 keys with 96 threads...
tid=0 thread_prefill_amount=8332 percent_done_estimate=8.5 elapsed_s=6 est_remaining_s=147 / 153
tid=0 thread_prefill_amount=10415 percent_done_estimate=10.6 elapsed_s=8 est_remaining_s=143 / 150
tid=0 thread_prefill_amount=12498 percent_done_estimate=12.7 elapsed_s=9 est_remaining_s=138 / 147
tid=0 thread_prefill_amount=14581 percent_done_estimate=14.8 elapsed_s=10 est_remaining_s=134 / 145
tid=0 thread_prefill_amount=16664 percent_done_estimate=17.0 elapsed_s=12 est_remaining_s=131 / 143
tid=0 thread_prefill_amount=18747 percent_done_estimate=19.1 elapsed_s=13 est_remaining_s=128 / 141
tid=0 thread_prefill_amount=20830 percent_done_estimate=21.2 elapsed_s=15 est_remaining_s=125 / 140
tid=0 thread_prefill_amount=22913 percent_done_estimate=23.3 elapsed_s=16 est_remaining_s=121 / 138
tid=0 thread_prefill_amount=24996 percent_done_estimate=25.4 elapsed_s=18 est_remaining_s=118 / 136
tid=0 thread_prefill_amount=27079 percent_done_estimate=27.5 elapsed_s=19 est_remaining_s=115 / 134
tid=0 thread_prefill_amount=29162 percent_done_estimate=29.7 elapsed_s=21 est_remaining_s=111 / 132
tid=0 thread_prefill_amount=31245 percent_done_estimate=31.8 elapsed_s=22 est_remaining_s=108 / 130
tid=0 thread_prefill_amount=33328 percent_done_estimate=33.9 elapsed_s=24 est_remaining_s=104 / 128
tid=0 thread_prefill_amount=35411 percent_done_estimate=36.0 elapsed_s=25 est_remaining_s=101 / 126
tid=0 thread_prefill_amount=37494 percent_done_estimate=38.1 elapsed_s=27 est_remaining_s=98 / 125
tid=0 thread_prefill_amount=39577 percent_done_estimate=40.3 elapsed_s=28 est_remaining_s=94 / 123
tid=0 thread_prefill_amount=41660 percent_done_estimate=42.4 elapsed_s=30 est_remaining_s=91 / 121
tid=0 thread_prefill_amount=43743 percent_done_estimate=44.5 elapsed_s=31 est_remaining_s=88 / 119
tid=0 thread_prefill_amount=45826 percent_done_estimate=46.6 elapsed_s=33 est_remaining_s=84 / 117
tid=0 thread_prefill_amount=47909 percent_done_estimate=48.7 elapsed_s=34 est_remaining_s=81 / 115
tid=0 thread_prefill_amount=49992 percent_done_estimate=50.9 elapsed_s=36 est_remaining_s=78 / 114
tid=0 thread_prefill_amount=52075 percent_done_estimate=53.0 elapsed_s=37 est_remaining_s=74 / 112
tid=0 thread_prefill_amount=54158 percent_done_estimate=55.1 elapsed_s=39 est_remaining_s=71 / 110
tid=0 thread_prefill_amount=56241 percent_done_estimate=57.2 elapsed_s=40 est_remaining_s=68 / 108
tid=0 thread_prefill_amount=58324 percent_done_estimate=59.3 elapsed_s=42 est_remaining_s=64 / 106
tid=0 thread_prefill_amount=60407 percent_done_estimate=61.5 elapsed_s=43 est_remaining_s=61 / 104
tid=0 thread_prefill_amount=62490 percent_done_estimate=63.6 elapsed_s=45 est_remaining_s=57 / 102
tid=0 thread_prefill_amount=64573 percent_done_estimate=65.7 elapsed_s=46 est_remaining_s=54 / 100
tid=0 thread_prefill_amount=66656 percent_done_estimate=67.8 elapsed_s=48 est_remaining_s=51 / 98
tid=0 thread_prefill_amount=68739 percent_done_estimate=69.9 elapsed_s=49 est_remaining_s=47 / 96
tid=0 thread_prefill_amount=70822 percent_done_estimate=72.0 elapsed_s=51 est_remaining_s=44 / 95
tid=0 thread_prefill_amount=72905 percent_done_estimate=74.2 elapsed_s=52 est_remaining_s=41 / 93
tid=0 thread_prefill_amount=74988 percent_done_estimate=76.3 elapsed_s=53 est_remaining_s=37 / 91
tid=0 thread_prefill_amount=77071 percent_done_estimate=78.4 elapsed_s=55 est_remaining_s=34 / 89
tid=0 thread_prefill_amount=79154 percent_done_estimate=80.5 elapsed_s=57 est_remaining_s=31 / 87
tid=0 thread_prefill_amount=81237 percent_done_estimate=82.6 elapsed_s=58 est_remaining_s=27 / 85
tid=0 thread_prefill_amount=83320 percent_done_estimate=84.8 elapsed_s=60 est_remaining_s=24 / 84
tid=0 thread_prefill_amount=85403 percent_done_estimate=86.9 elapsed_s=61 est_remaining_s=21 / 82
tid=0 thread_prefill_amount=87486 percent_done_estimate=89.0 elapsed_s=63 est_remaining_s=17 / 80
tid=0 thread_prefill_amount=89569 percent_done_estimate=91.1 elapsed_s=64 est_remaining_s=14 / 78
tid=0 thread_prefill_amount=91652 percent_done_estimate=93.2 elapsed_s=66 est_remaining_s=11 / 76
tid=0 thread_prefill_amount=93735 percent_done_estimate=95.4 elapsed_s=67 est_remaining_s=7 / 74
tid=0 thread_prefill_amount=95818 percent_done_estimate=97.5 elapsed_s=68 est_remaining_s=4 / 72
tid=0 thread_prefill_amount=97901 percent_done_estimate=99.6 elapsed_s=70 est_remaining_s=1 / 70
tid=0 thread_prefill_amount=99984 percent_done_estimate=101.7 elapsed_s=71 est_remaining_s=-3 / 69
tid=0 thread_prefill_amount=102067 percent_done_estimate=103.8 elapsed_s=73 est_remaining_s=-6 / 67
tid=0 thread_prefill_amount=104150 percent_done_estimate=106.0 elapsed_s=74 est_remaining_s=-9 / 65
timing_elapsed 81.4101s
prefilling_size=10000000 prefilling_elapsed_millis=81.605s
finished prefilling to size 10000000 for expected size 10000000 keysum=99993575697256, performing 14447099 updates; total_prefilling_elapsed_ms=81605 ms)
pref_size=10000000
pref_millis=81605
prefill_elapsed_ms=81605
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6994866.612165066
REALTIME_END_PERF_FORMAT=6994876.612310721
###############################################################################
################################## 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 384 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.01s
tree_stats_numInternalsAtDepth=1 1 8 58 384 2876 21848 169849 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1342360
tree_stats_numNodesAtDepth=1 1 8 58 384 2876 21848 169849 1342360
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10000703
tree_stats_avgDegreeAtDepth=1 8 7.25 6.62069 7.48958 7.59666 7.77412 7.90326 7.45009
tree_stats_height=9
tree_stats_numInternals=195025
tree_stats_numLeaves=1342360
tree_stats_numNodes=1537385
tree_stats_numKeys=10000703
tree_stats_avgDegreeInternal=7.88301
tree_stats_avgDegreeLeaves=7.45009
tree_stats_avgDegree=7.50501
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=42335
first_thread_announced_epoch_by_thread=1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362 1362
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=26935886
sum_fasthtm_abort_total=58493606
sum_htmpostfix_commit_total=1390387
sum_htmpostfix_abort_total=5244234
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=49596830
sum_stmprefix_commit_total=9
sum_slow_commit_total=24201
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=16922 14451 14057 14159 14158 13920 17275 14177 14531 14351 13653 13990 16854 14296 14546 14272 14416 14507 17139 14163 14179 13947 13987 13722 17169 14280 14250 14141 13859 13971 17253 14234 14783 14497 14168 14063 17165 14035 14417 14354 14440 14347 17194 13868 13734 14233 13968 13865 17407 14499 14539 14415 14508 14068 17055 14545 14584 14265 14647 14803 16920 14637 14382 14287 14176 14436 17018 14132 14643 14566 14439 14448 17244 14788 14359 14211 14617 14348 17259 14537 14710 14577 14281 14367 16899 14317 14374 14192 14123 14296 16778 14101 14558 14768 14557 14277
sum_num_inserts_total=1417917
sum_num_deletes_by_thread=17093 14314 14024 14184 14228 13997 16803 13763 14409 14716 13789 13860 16816 14002 14714 14357 14384 14398 16984 14313 13881 14078 14000 13945 17269 14262 14144 14197 13872 13915 17472 14063 14638 14272 14038 14058 17163 14196 14254 14393 14422 14441 16866 13851 13783 13997 14016 13959 17314 14464 14378 14434 14573 14132 17333 14598 14447 14438 14272 14836 16958 14799 14253 14382 14241 14327 16917 14067 14654 14209 14493 14321 17126 14709 14492 14102 14594 14044 17151 14418 14751 14626 14122 14246 17059 14215 14652 14212 14046 14424 16591 14006 14707 14584 14520 14247
sum_num_deletes_total=1415077
sum_num_searches_by_thread=306018 258595 250450 255153 258594 252305 304681 251072 260179 262582 249007 252583 302467 256395 261502 256417 258495 259168 309199 256862 252461 253692 253651 249609 311520 257265 255572 253724 252773 248400 313225 257348 264844 257852 254206 252523 307692 255240 257785 259173 262089 260199 309647 251074 247425 254041 251719 251079 311078 261467 260412 259103 260624 254841 307841 259931 260399 256151 260658 266286 306116 264488 259167 258189 255633 259500 305657 254174 262451 257458 260044 259372 309750 265578 262926 257259 263209 253329 312711 259636 264652 263845 256829 256227 307238 257154 263300 258123 253224 260795 295817 252171 263013 262130 261859 258122
sum_num_searches_total=25517489
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=340033 287360 278531 283496 286980 280222 338759 279012 289119 291649 276449 280433 336137 284693 290762 285046 287295 288073 343322 285338 280521 281717 281638 277276 345958 285807 283966 282062 280504 276286 347950 285645 294265 286621 282412 280644 342020 283471 286456 287920 290951 288987 343707 278793 274942 282271 279703 278903 345799 290430 289329 287952 289705 283041 342229 289074 289430 284854 289577 295925 339994 293924 287802 286858 284050 288263 339592 282373 291748 286233 288976 288141 344120 295075 291777 285572 292420 281721 347121 288591 294113 293048 285232 284840 341196 285686 292326 286527 281393 289515 329186 280278 292278 291482 290936 286646
average_num_operations_total=295317
stdev_num_operations_total=2195
sum_num_operations_total=28350483
min_num_operations_total=274942
max_num_operations_total=347950
first_time_thread_terminate_by_thread=10001822 10000159 10000384 10001043 10000190 10000378 10001035 10000365 10000145 10000145 10000145 10001797 10001041 10000145 10000145 10000391 10001033 10000303 10000165 10000392 10000187 10000277 10000308 10000253 10001039 10000275 10000145 10000403 10000397 10000146 10000340 10000382 10000145 10000336 10000145 10000145 10000394 10000145 10000322 10000262 10000150 10000400 10000337 10000146 10000145 10000372 10000145 10000341 10000337 10000194 10000362 10000266 10001818 10001808 10000232 10000287 10000400 10000386 10000345 10000327 10000410 10000225 10002274 10001812 10001802 10000316 10000314 10000176 10000158 10000421 10000277 10000186 10000183 10000294 10000398 10000252 10000410 10000386 10000363 10000419 10000151 10000356 10000152 10000352 10000349 10000187 10000307 10001037 10000230 10000278 10001827 10001803 10000344 10000166 10000193 10000415
min_time_thread_terminate_total=10000145
max_time_thread_terminate_total=10002274
first_time_thread_start_by_thread=11 6 5 5 6 6 6 6 5 5 6 6 6 6 5 5 6 6 6 6 6 5 6 6 5 6 5 5 6 6 6 6 5 5 6 6 6 6 5 5 6 6 6 6 6 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 6
min_time_thread_start_total=5
max_time_thread_start_total=11
sum_duration_all_ops_total=
threads_final_keysum=100001424936410
threads_final_size=10000703
final_keysum=100001424936410
final_size=10000703
validate_result=success
Validation OK.
Structural validation OK.
total_find=25517489
total_rq=0
total_inserts=1417917
total_deletes=1415077
total_updates=2832994
total_queries=25517489
total_ops=28350483
find_throughput=2551748
rq_throughput=0
update_throughput=283299
query_throughput=2551748
total_throughput=2835048
total find : 25517489
total rq : 0
total inserts : 1417917
total deletes : 1415077
total updates : 2832994
total queries : 25517489
total ops : 28350483
find throughput : 2551748
rq throughput : 0
update throughput : 283299
query throughput : 2551748
total throughput : 2835048
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=39.8211
PAPI_L3_TCM=12.5479
PAPI_TOT_CYC=84761.8
PAPI_TOT_INS=4306.03
garbage=110566615923674
total_execution_walltime=97.072s
REALTIME_MAIN_END_PERF_FORMAT=6994876.740959006
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=97.52, faults_major=0, faults_minor=1091543, mem_maxresident_kb=4364480, user_cputime=8914.66, sys_cputime=149.91, percent_cpu=9294%
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=1