__step=000059
__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_sigouin_int_avl_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=data000059.txt
__path_data=data/data000059.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993175.700134808
binary=./brown_sigouin_int_avl_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_sigouin_int_avl_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=48
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.013s
prefilling_size=163404 prefilling_elapsed_millis=1.001s
prefilling_size=180568 prefilling_elapsed_millis=1.101s
prefilling_size=197619 prefilling_elapsed_millis=1.201s
prefilling_size=214463 prefilling_elapsed_millis=1.301s
prefilling_size=231661 prefilling_elapsed_millis=1.402s
prefilling_size=248600 prefilling_elapsed_millis=1.502s
prefilling_size=265551 prefilling_elapsed_millis=1.602s
prefilling_size=282402 prefilling_elapsed_millis=1.702s
prefilling_size=299353 prefilling_elapsed_millis=1.802s
prefilling_size=316021 prefilling_elapsed_millis=1.903s
prefilling_size=332773 prefilling_elapsed_millis=2.003s
prefilling_size=349641 prefilling_elapsed_millis=2.103s
prefilling_size=366338 prefilling_elapsed_millis=2.203s
prefilling_size=383067 prefilling_elapsed_millis=2.303s
prefilling_size=399821 prefilling_elapsed_millis=2.404s
prefilling_size=416665 prefilling_elapsed_millis=2.504s
prefilling_size=433305 prefilling_elapsed_millis=2.604s
prefilling_size=449769 prefilling_elapsed_millis=2.704s
prefilling_size=466360 prefilling_elapsed_millis=2.804s
prefilling_size=482916 prefilling_elapsed_millis=2.905s
prefilling_size=499382 prefilling_elapsed_millis=3.005s
prefilling_size=516011 prefilling_elapsed_millis=3.105s
prefilling_size=532565 prefilling_elapsed_millis=3.205s
prefilling_size=548897 prefilling_elapsed_millis=3.305s
prefilling_size=565358 prefilling_elapsed_millis=3.406s
prefilling_size=581776 prefilling_elapsed_millis=3.506s
prefilling_size=597999 prefilling_elapsed_millis=3.606s
prefilling_size=614449 prefilling_elapsed_millis=3.706s
prefilling_size=630842 prefilling_elapsed_millis=3.806s
prefilling_size=647011 prefilling_elapsed_millis=3.906s
prefilling_size=663124 prefilling_elapsed_millis=4.007s
prefilling_size=679246 prefilling_elapsed_millis=4.107s
prefilling_size=695220 prefilling_elapsed_millis=4.207s
prefilling_size=711515 prefilling_elapsed_millis=4.307s
prefilling_size=727715 prefilling_elapsed_millis=4.407s
prefilling_size=743742 prefilling_elapsed_millis=4.508s
prefilling_size=759730 prefilling_elapsed_millis=4.608s
prefilling_size=775678 prefilling_elapsed_millis=4.708s
prefilling_size=791754 prefilling_elapsed_millis=4.808s
prefilling_size=807765 prefilling_elapsed_millis=4.908s
prefilling_size=823837 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9176065 keys with 96 threads...
tid=0 thread_prefill_amount=10415 percent_done_estimate=10.9 elapsed_s=6 est_remaining_s=102 / 108
tid=0 thread_prefill_amount=12498 percent_done_estimate=13.1 elapsed_s=7 est_remaining_s=99 / 106
tid=0 thread_prefill_amount=14581 percent_done_estimate=15.3 elapsed_s=8 est_remaining_s=96 / 104
tid=0 thread_prefill_amount=16664 percent_done_estimate=17.4 elapsed_s=9 est_remaining_s=94 / 103
tid=0 thread_prefill_amount=18747 percent_done_estimate=19.6 elapsed_s=10 est_remaining_s=91 / 101
tid=0 thread_prefill_amount=20830 percent_done_estimate=21.8 elapsed_s=11 est_remaining_s=89 / 100
tid=0 thread_prefill_amount=22913 percent_done_estimate=24.0 elapsed_s=12 est_remaining_s=86 / 98
tid=0 thread_prefill_amount=24996 percent_done_estimate=26.2 elapsed_s=13 est_remaining_s=83 / 96
tid=0 thread_prefill_amount=27079 percent_done_estimate=28.3 elapsed_s=14 est_remaining_s=81 / 95
tid=0 thread_prefill_amount=29162 percent_done_estimate=30.5 elapsed_s=15 est_remaining_s=78 / 94
tid=0 thread_prefill_amount=31245 percent_done_estimate=32.7 elapsed_s=16 est_remaining_s=76 / 92
tid=0 thread_prefill_amount=33328 percent_done_estimate=34.9 elapsed_s=17 est_remaining_s=73 / 91
tid=0 thread_prefill_amount=35411 percent_done_estimate=37.0 elapsed_s=19 est_remaining_s=71 / 90
tid=0 thread_prefill_amount=37494 percent_done_estimate=39.2 elapsed_s=20 est_remaining_s=68 / 88
tid=0 thread_prefill_amount=39577 percent_done_estimate=41.4 elapsed_s=21 est_remaining_s=66 / 87
tid=0 thread_prefill_amount=41660 percent_done_estimate=43.6 elapsed_s=22 est_remaining_s=64 / 85
tid=0 thread_prefill_amount=43743 percent_done_estimate=45.8 elapsed_s=23 est_remaining_s=61 / 84
tid=0 thread_prefill_amount=45826 percent_done_estimate=47.9 elapsed_s=24 est_remaining_s=59 / 83
tid=0 thread_prefill_amount=47909 percent_done_estimate=50.1 elapsed_s=25 est_remaining_s=56 / 81
tid=0 thread_prefill_amount=49992 percent_done_estimate=52.3 elapsed_s=26 est_remaining_s=54 / 80
tid=0 thread_prefill_amount=52075 percent_done_estimate=54.5 elapsed_s=27 est_remaining_s=51 / 79
tid=0 thread_prefill_amount=54158 percent_done_estimate=56.7 elapsed_s=28 est_remaining_s=49 / 77
tid=0 thread_prefill_amount=56241 percent_done_estimate=58.8 elapsed_s=29 est_remaining_s=46 / 76
tid=0 thread_prefill_amount=58324 percent_done_estimate=61.0 elapsed_s=31 est_remaining_s=44 / 74
tid=0 thread_prefill_amount=60407 percent_done_estimate=63.2 elapsed_s=32 est_remaining_s=41 / 73
tid=0 thread_prefill_amount=62490 percent_done_estimate=65.4 elapsed_s=33 est_remaining_s=39 / 72
tid=0 thread_prefill_amount=64573 percent_done_estimate=67.6 elapsed_s=34 est_remaining_s=36 / 70
tid=0 thread_prefill_amount=66656 percent_done_estimate=69.7 elapsed_s=35 est_remaining_s=34 / 69
tid=0 thread_prefill_amount=68739 percent_done_estimate=71.9 elapsed_s=36 est_remaining_s=32 / 68
tid=0 thread_prefill_amount=70822 percent_done_estimate=74.1 elapsed_s=37 est_remaining_s=29 / 66
tid=0 thread_prefill_amount=72905 percent_done_estimate=76.3 elapsed_s=38 est_remaining_s=27 / 65
tid=0 thread_prefill_amount=74988 percent_done_estimate=78.5 elapsed_s=39 est_remaining_s=24 / 64
tid=0 thread_prefill_amount=77071 percent_done_estimate=80.6 elapsed_s=40 est_remaining_s=22 / 62
tid=0 thread_prefill_amount=79154 percent_done_estimate=82.8 elapsed_s=41 est_remaining_s=19 / 61
tid=0 thread_prefill_amount=81237 percent_done_estimate=85.0 elapsed_s=43 est_remaining_s=17 / 59
tid=0 thread_prefill_amount=83320 percent_done_estimate=87.2 elapsed_s=44 est_remaining_s=14 / 58
tid=0 thread_prefill_amount=85403 percent_done_estimate=89.3 elapsed_s=45 est_remaining_s=12 / 57
tid=0 thread_prefill_amount=87486 percent_done_estimate=91.5 elapsed_s=46 est_remaining_s=10 / 55
tid=0 thread_prefill_amount=89569 percent_done_estimate=93.7 elapsed_s=47 est_remaining_s=7 / 54
tid=0 thread_prefill_amount=91652 percent_done_estimate=95.9 elapsed_s=48 est_remaining_s=5 / 53
tid=0 thread_prefill_amount=93735 percent_done_estimate=98.1 elapsed_s=49 est_remaining_s=2 / 51
tid=0 thread_prefill_amount=95818 percent_done_estimate=100.2 elapsed_s=50 est_remaining_s=-0 / 50
tid=0 thread_prefill_amount=97901 percent_done_estimate=102.4 elapsed_s=51 est_remaining_s=-3 / 49
tid=0 thread_prefill_amount=99984 percent_done_estimate=104.6 elapsed_s=52 est_remaining_s=-5 / 47
tid=0 thread_prefill_amount=102067 percent_done_estimate=106.8 elapsed_s=54 est_remaining_s=-8 / 46
tid=0 thread_prefill_amount=104150 percent_done_estimate=109.0 elapsed_s=54 est_remaining_s=-10 / 44
timing_elapsed 51.4965s
prefilling_size=10000000 prefilling_elapsed_millis=51.619s
finished prefilling to size 10000000 for expected size 10000000 keysum=99988162578359, performing 14746233 updates; total_prefilling_elapsed_ms=51619 ms)
pref_size=10000000
pref_millis=51619
prefill_elapsed_ms=51620
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6993232.660170588
REALTIME_END_PERF_FORMAT=6993242.660296599

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

tree_stats_computeWalltime=0.03s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262045 519753 965612 1430275 1379117 728718 173926 12462 55 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 87 3935 64125 410710 1151164 1497640 901417 223336 15660 65
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523688 1029737 1840985 2530281 2226358 1075343 235798 15715 65
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523688 1029737 1840985 2530281 2226358 1075343 235798 15715 65
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99813 1.97383 1.85009 1.59751 1.33484 1.15569 1.05754 1.0138 1.00064 1

tree_stats_height=28
tree_stats_numInternals=5734105
tree_stats_numLeaves=4268140
tree_stats_numNodes=10002245
tree_stats_numKeys=10002245

tree_stats_avgDegreeInternal=1.74434
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42672
tree_stats_avgKeyDepth=21.7055


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=47710
first_thread_announced_epoch_by_thread=1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454 1454
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=30082541
sum_fasthtm_abort_total=64841051
sum_htmpostfix_commit_total=1542836
sum_htmpostfix_abort_total=7853414
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=55258862
sum_stmprefix_commit_total=3
sum_slow_commit_total=39971
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=16553 16230 16572 15149 16882 16864 16644 16482 16402 15081 16643 16603 16192 16782 16869 14778 16644 16696 16230 16224 16080 14773 16332 16359 16900 16303 16558 14860 16897 16781 16546 16504 16528 14732 17015 16528 16424 16421 16955 15278 16428 16726 16293 16170 16424 14881 16642 16504 17234 17228 17132 15267 17299 16886 16747 16721 16957 15358 17141 17165 17035 16801 16907 15602 17168 17341 16156 16353 16787 15456 17059 16897 17289 17036 16926 15362 17277 16914 17010 16902 16944 15592 17061 17435 16812 17012 16800 15495 17231 17308 16201 16489 16754 15336 17129 17258
sum_num_inserts_total=1583632
sum_num_deletes_by_thread=16346 16256 16628 14986 16711 16804 16376 16624 16441 14719 16691 16535 16247 16885 16668 14786 16609 16488 16426 16156 16432 14752 16454 16543 16527 16423 16475 15126 16827 16712 16091 16318 16429 14787 16834 16726 16249 16384 16759 15121 16616 16535 16309 16336 16170 15025 16277 16386 17292 17159 17127 15568 17332 16968 17084 16563 17001 15390 16994 17347 16906 16807 17029 15596 17003 17127 16119 16375 16840 15204 17326 17436 17115 16980 17310 15458 17234 16967 16904 16921 17146 15506 17231 17123 16803 17125 16909 15328 17229 17437 16303 16380 16799 15475 17196 17227
sum_num_deletes_total=1582704
sum_num_searches_by_thread=298029 295438 300083 272124 303764 301541 297601 297190 296855 265755 298233 300107 291306 301390 302985 265202 296118 296766 297298 291471 293373 266395 292126 295399 299470 294813 297116 272886 305412 302013 294083 293938 297394 262885 303132 296155 293606 292768 304794 271891 295332 300090 294903 290445 293023 271689 300554 296121 309022 309647 307934 279792 310568 306734 306511 297295 308234 276179 306761 308763 306953 301781 306478 279966 307425 312220 290051 294925 303719 274343 307640 307642 307700 303715 310836 277931 311195 303790 305986 302394 308466 279455 307874 313872 301828 306799 302959 277347 308700 315071 291962 297664 299819 278587 310412 313008
sum_num_searches_total=28499015
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=330928 327924 333283 302259 337357 335209 330621 330296 329698 295555 331567 333245 323745 335057 336522 294766 329371 329950 329954 323851 325885 295920 324912 328301 332897 327539 330149 302872 339136 335506 326720 326760 330351 292404 336981 329409 326279 325573 338508 302290 328376 333351 327505 322951 325617 301595 333473 329011 343548 344034 342193 310627 345199 340588 340342 330579 342192 306927 340896 343275 340894 335389 340414 311164 341596 346688 322326 327653 337346 305003 342025 341975 342104 337731 345072 308751 345706 337671 339900 336217 342556 310553 342166 348430 335443 340936 336668 308170 343160 349816 324466 330533 333372 309398 344737 347493
average_num_operations_total=329847
stdev_num_operations_total=1402
sum_num_operations_total=31665351
min_num_operations_total=292404
max_num_operations_total=349816
first_time_thread_terminate_by_thread=10000328 10000359 10000372 10000369 10000345 10000126 10000126 10000123 10000132 10000314 10000203 10000343 10000274 10000268 10000309 10000165 10000137 10000126 10000295 10000366 10000371 10000357 10000340 10000336 10000241 10000364 10000353 10000289 10000362 10000370 10000179 10000362 10000211 10000351 10000327 10000126 10000308 10000143 10000123 10000368 10000292 10000347 10000324 10000126 10000355 10000285 10000159 10000331 10000286 10000245 10000308 10000252 10000145 10000215 10000260 10000334 10000130 10000178 10000260 10000127 10000225 10000316 10000349 10000320 10000345 10000214 10000178 10000125 10000127 10000128 10000367 10000192 10000296 10000140 10000250 10000203 10000293 10000255 10000144 10000294 10000310 10000305 10000127 10000154 10000341 10000167 10000307 10000276 10000194 10000127 10000321 10000350 10000242 10000264 10000189 10000140
min_time_thread_terminate_total=10000123
max_time_thread_terminate_total=10000372
first_time_thread_start_by_thread=10 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 6 6 6 5 6 6 5 5 6 6 5 6 6 6 6 5 5 6 6 6 6 6 6 5 6 6 6 5 6 6 5 5 6 6 5 6 6 6 6 5 5 6 6 6 6 6
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100016824424714
threads_final_size=10002245
final_keysum=100016824424714
final_size=10002245
validate_result=success
Validation OK.
Structural validation OK.

total_find=28499015
total_rq=0
total_inserts=1583632
total_deletes=1582704
total_updates=3166336
total_queries=28499015
total_ops=31665351
find_throughput=2849901
rq_throughput=0
update_throughput=316633
query_throughput=2849901
total_throughput=3166535


total find                    : 28499015
total rq                      : 0
total inserts                 : 1583632
total deletes                 : 1582704
total updates                 : 3166336
total queries                 : 28499015
total ops                     : 31665351
find throughput               : 2849901
rq throughput                 : 0
update throughput             : 316633
query throughput              : 2849901
total throughput              : 3166535

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=45.4307
PAPI_L3_TCM=14.6412
PAPI_TOT_CYC=76116.6
PAPI_TOT_INS=4553.78
garbage=142423310680421
total_execution_walltime=67.095s
REALTIME_MAIN_END_PERF_FORMAT=6993242.806449530
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=67.31, faults_major=0, faults_minor=375781, mem_maxresident_kb=1501572, user_cputime=6216.35, sys_cputime=95.60, percent_cpu=9376%
timeout=false
algorithm=brown_sigouin_int_avl_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