__step=000060
__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=data000060.txt
__path_data=data/data000060.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993243.88286416
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.009s
prefilling_size=170391 prefilling_elapsed_millis=1.001s
prefilling_size=188290 prefilling_elapsed_millis=1.101s
prefilling_size=206271 prefilling_elapsed_millis=1.201s
prefilling_size=224167 prefilling_elapsed_millis=1.301s
prefilling_size=242086 prefilling_elapsed_millis=1.402s
prefilling_size=259993 prefilling_elapsed_millis=1.502s
prefilling_size=277894 prefilling_elapsed_millis=1.602s
prefilling_size=295543 prefilling_elapsed_millis=1.702s
prefilling_size=313176 prefilling_elapsed_millis=1.802s
prefilling_size=331094 prefilling_elapsed_millis=1.903s
prefilling_size=348975 prefilling_elapsed_millis=2.003s
prefilling_size=366614 prefilling_elapsed_millis=2.103s
prefilling_size=384252 prefilling_elapsed_millis=2.203s
prefilling_size=401860 prefilling_elapsed_millis=2.303s
prefilling_size=419353 prefilling_elapsed_millis=2.404s
prefilling_size=436523 prefilling_elapsed_millis=2.504s
prefilling_size=454012 prefilling_elapsed_millis=2.604s
prefilling_size=471384 prefilling_elapsed_millis=2.704s
prefilling_size=488731 prefilling_elapsed_millis=2.804s
prefilling_size=506079 prefilling_elapsed_millis=2.905s
prefilling_size=523453 prefilling_elapsed_millis=3.005s
prefilling_size=540703 prefilling_elapsed_millis=3.105s
prefilling_size=557855 prefilling_elapsed_millis=3.205s
prefilling_size=574863 prefilling_elapsed_millis=3.306s
prefilling_size=592033 prefilling_elapsed_millis=3.406s
prefilling_size=609185 prefilling_elapsed_millis=3.506s
prefilling_size=626661 prefilling_elapsed_millis=3.606s
prefilling_size=643911 prefilling_elapsed_millis=3.706s
prefilling_size=660831 prefilling_elapsed_millis=3.807s
prefilling_size=677938 prefilling_elapsed_millis=3.907s
prefilling_size=695293 prefilling_elapsed_millis=4.007s
prefilling_size=712365 prefilling_elapsed_millis=4.107s
prefilling_size=729092 prefilling_elapsed_millis=4.207s
prefilling_size=746169 prefilling_elapsed_millis=4.308s
prefilling_size=763310 prefilling_elapsed_millis=4.408s
prefilling_size=780196 prefilling_elapsed_millis=4.508s
prefilling_size=796942 prefilling_elapsed_millis=4.608s
prefilling_size=813885 prefilling_elapsed_millis=4.708s
prefilling_size=830878 prefilling_elapsed_millis=4.809s
prefilling_size=847582 prefilling_elapsed_millis=4.909s
prefilling_size=864227 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 9135670 keys with 96 threads...
tid=0 thread_prefill_amount=10415 percent_done_estimate=10.9 elapsed_s=6 est_remaining_s=110 / 117
tid=0 thread_prefill_amount=12498 percent_done_estimate=13.1 elapsed_s=7 est_remaining_s=107 / 114
tid=0 thread_prefill_amount=14581 percent_done_estimate=15.3 elapsed_s=8 est_remaining_s=103 / 112
tid=0 thread_prefill_amount=16664 percent_done_estimate=17.5 elapsed_s=9 est_remaining_s=100 / 109
tid=0 thread_prefill_amount=18747 percent_done_estimate=19.7 elapsed_s=11 est_remaining_s=97 / 107
tid=0 thread_prefill_amount=20830 percent_done_estimate=21.9 elapsed_s=12 est_remaining_s=94 / 105
tid=0 thread_prefill_amount=22913 percent_done_estimate=24.1 elapsed_s=13 est_remaining_s=91 / 103
tid=0 thread_prefill_amount=24996 percent_done_estimate=26.3 elapsed_s=14 est_remaining_s=88 / 101
tid=0 thread_prefill_amount=27079 percent_done_estimate=28.5 elapsed_s=15 est_remaining_s=85 / 100
tid=0 thread_prefill_amount=29162 percent_done_estimate=30.6 elapsed_s=16 est_remaining_s=82 / 98
tid=0 thread_prefill_amount=31245 percent_done_estimate=32.8 elapsed_s=17 est_remaining_s=79 / 96
tid=0 thread_prefill_amount=33328 percent_done_estimate=35.0 elapsed_s=18 est_remaining_s=76 / 95
tid=0 thread_prefill_amount=35411 percent_done_estimate=37.2 elapsed_s=19 est_remaining_s=74 / 93
tid=0 thread_prefill_amount=37494 percent_done_estimate=39.4 elapsed_s=21 est_remaining_s=71 / 92
tid=0 thread_prefill_amount=39577 percent_done_estimate=41.6 elapsed_s=22 est_remaining_s=69 / 90
tid=0 thread_prefill_amount=41660 percent_done_estimate=43.8 elapsed_s=23 est_remaining_s=66 / 89
tid=0 thread_prefill_amount=43743 percent_done_estimate=46.0 elapsed_s=24 est_remaining_s=63 / 87
tid=0 thread_prefill_amount=45826 percent_done_estimate=48.2 elapsed_s=25 est_remaining_s=61 / 86
tid=0 thread_prefill_amount=47909 percent_done_estimate=50.3 elapsed_s=26 est_remaining_s=58 / 84
tid=0 thread_prefill_amount=49992 percent_done_estimate=52.5 elapsed_s=27 est_remaining_s=55 / 83
tid=0 thread_prefill_amount=52075 percent_done_estimate=54.7 elapsed_s=28 est_remaining_s=53 / 81
tid=0 thread_prefill_amount=54158 percent_done_estimate=56.9 elapsed_s=29 est_remaining_s=50 / 80
tid=0 thread_prefill_amount=56241 percent_done_estimate=59.1 elapsed_s=31 est_remaining_s=48 / 78
tid=0 thread_prefill_amount=58324 percent_done_estimate=61.3 elapsed_s=32 est_remaining_s=45 / 77
tid=0 thread_prefill_amount=60407 percent_done_estimate=63.5 elapsed_s=33 est_remaining_s=42 / 75
tid=0 thread_prefill_amount=62490 percent_done_estimate=65.7 elapsed_s=34 est_remaining_s=40 / 74
tid=0 thread_prefill_amount=64573 percent_done_estimate=67.9 elapsed_s=35 est_remaining_s=37 / 72
tid=0 thread_prefill_amount=66656 percent_done_estimate=70.0 elapsed_s=36 est_remaining_s=35 / 71
tid=0 thread_prefill_amount=68739 percent_done_estimate=72.2 elapsed_s=37 est_remaining_s=32 / 69
tid=0 thread_prefill_amount=70822 percent_done_estimate=74.4 elapsed_s=38 est_remaining_s=30 / 68
tid=0 thread_prefill_amount=72905 percent_done_estimate=76.6 elapsed_s=39 est_remaining_s=27 / 66
tid=0 thread_prefill_amount=74988 percent_done_estimate=78.8 elapsed_s=40 est_remaining_s=25 / 65
tid=0 thread_prefill_amount=77071 percent_done_estimate=81.0 elapsed_s=42 est_remaining_s=22 / 64
tid=0 thread_prefill_amount=79154 percent_done_estimate=83.2 elapsed_s=43 est_remaining_s=19 / 62
tid=0 thread_prefill_amount=81237 percent_done_estimate=85.4 elapsed_s=44 est_remaining_s=17 / 61
tid=0 thread_prefill_amount=83320 percent_done_estimate=87.6 elapsed_s=45 est_remaining_s=14 / 59
tid=0 thread_prefill_amount=85403 percent_done_estimate=89.7 elapsed_s=46 est_remaining_s=12 / 58
tid=0 thread_prefill_amount=87486 percent_done_estimate=91.9 elapsed_s=47 est_remaining_s=9 / 56
tid=0 thread_prefill_amount=89569 percent_done_estimate=94.1 elapsed_s=48 est_remaining_s=7 / 55
tid=0 thread_prefill_amount=91652 percent_done_estimate=96.3 elapsed_s=49 est_remaining_s=4 / 54
tid=0 thread_prefill_amount=93735 percent_done_estimate=98.5 elapsed_s=50 est_remaining_s=2 / 52
tid=0 thread_prefill_amount=95818 percent_done_estimate=100.7 elapsed_s=52 est_remaining_s=-1 / 51
tid=0 thread_prefill_amount=97901 percent_done_estimate=102.9 elapsed_s=53 est_remaining_s=-3 / 49
tid=0 thread_prefill_amount=99984 percent_done_estimate=105.1 elapsed_s=54 est_remaining_s=-6 / 48
tid=0 thread_prefill_amount=102067 percent_done_estimate=107.3 elapsed_s=54 est_remaining_s=-8 / 46
timing_elapsed 49.0884s
prefilling_size=10000000 prefilling_elapsed_millis=49.206s
finished prefilling to size 10000000 for expected size 10000000 keysum=99995382065597, performing 14792491 updates; total_prefilling_elapsed_ms=49206 ms)
pref_size=10000000
pref_millis=49206
prefill_elapsed_ms=49206
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6993297.595893253
REALTIME_END_PERF_FORMAT=6993307.595998233
###############################################################################
################################## 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.029s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131070 261971 517988 959523 1438249 1408024 724969 152870 8425 28 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2 161 5316 65360 394075 1144453 1543869 906665 196359 10474 31
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523304 1024883 1832324 2552477 2268838 1059535 204784 10502 31
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523304 1024883 1832324 2552477 2268838 1059535 204784 10502 31
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99695 1.96864 1.85161 1.6081 1.33725 1.14746 1.049 1.01014 1.00029 1
tree_stats_height=28
tree_stats_numInternals=5734188
tree_stats_numLeaves=4266765
tree_stats_numNodes=10000953
tree_stats_numKeys=10000953
tree_stats_avgDegreeInternal=1.74409
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42664
tree_stats_avgKeyDepth=21.6971
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=51934
first_thread_announced_epoch_by_thread=1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498 1498
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=32011101
sum_fasthtm_abort_total=69423562
sum_htmpostfix_commit_total=1634786
sum_htmpostfix_abort_total=9457570
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=50636778
sum_stmprefix_commit_total=6
sum_slow_commit_total=49525
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=18599 19020 18698 18167 18236 17320 18877 19166 18491 18501 18414 17621 18804 19009 18722 18079 18189 17809 18813 18884 18273 18400 18662 17398 18596 19045 18613 18226 18468 17688 19234 19058 18124 18689 18122 17479 18604 19149 18708 18447 18398 17926 18931 18767 18306 18470 18465 17585 16692 17004 16645 16380 16647 16570 17144 17081 16965 16923 16678 16558 16858 16612 16542 16533 16599 16124 16579 16632 17165 16661 16547 15962 16790 16671 16601 16750 16257 16389 16997 17149 16823 16772 16630 16593 17015 17167 16778 16327 16367 16243 16910 16853 16674 17107 16641 16268
sum_num_inserts_total=1686123
sum_num_deletes_by_thread=18484 18826 18411 18411 18339 17606 19087 19076 18498 18469 18301 17612 18561 19314 18588 18736 18424 17561 18738 18734 18155 18348 18484 17713 18494 18964 18378 18353 18469 17463 18975 19167 18288 18507 18779 17469 18708 19133 18949 18154 18460 17697 18824 18928 18439 18379 18509 17454 16831 16988 16836 16242 16368 16520 16987 17018 16720 17025 16703 16934 16907 16914 16339 16266 16374 16253 16664 16699 17100 16870 16381 15835 17062 16626 16656 16572 16590 16518 16822 17041 16879 16856 16621 16400 17021 17030 16868 16478 16342 16336 16607 16955 17168 16670 16692 16036
sum_num_deletes_total=1686036
sum_num_searches_by_thread=334615 338671 331610 327765 327996 315094 342079 344677 333125 335460 331462 313354 334896 343787 335996 331545 331804 315773 340522 335657 328408 330170 332439 316375 329581 343641 331278 330739 328785 311663 342429 343225 328971 336098 329462 312121 338828 343150 340440 329781 332282 319139 343420 338603 328914 332494 330528 312621 303313 305170 301118 295118 296365 299665 311907 309926 300746 305458 297436 301041 303291 301437 298556 293291 292037 289695 299509 299298 306925 304358 297371 285648 306165 299910 301340 298792 295137 297752 307486 303714 302486 302705 297435 296527 306407 309081 303844 292604 295407 291723 302987 306368 304929 302927 298710 288671
sum_num_searches_total=30323259
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=371698 376517 368719 364343 364571 350020 380043 382919 370114 372430 368177 348587 372261 382110 373306 368360 368417 351143 378073 373275 364836 366918 369585 351486 366671 381650 368269 367318 365722 346814 380638 381450 365383 373294 366363 347069 376140 381432 378097 366382 369140 354762 381175 376298 365659 369343 367502 347660 336836 339162 334599 327740 329380 332755 346038 344025 334431 339406 330817 334533 337056 334963 331437 326090 325010 322072 332752 332629 341190 337889 330299 317445 340017 333207 334597 332114 327984 330659 341305 337904 336188 336333 330686 329520 340443 343278 337490 325409 328116 324302 336504 340176 338771 336704 332043 320975
average_num_operations_total=350993
stdev_num_operations_total=1986
sum_num_operations_total=33695418
min_num_operations_total=317445
max_num_operations_total=382919
first_time_thread_terminate_by_thread=10000298 10000306 10000289 10000262 10000201 10000291 10000158 10000357 10000221 10000275 10000114 10000320 10000295 10000117 10000270 10000247 10000258 10000315 10000345 10000333 10000221 10000285 10000269 10000306 10000304 10000114 10000122 10000340 10000116 10000283 10000114 10000333 10000313 10000301 10000164 10000285 10000113 10000271 10000359 10000163 10000285 10000335 10000337 10000267 10000114 10000207 10000120 10000115 10000171 10000175 10000365 10000379 10000328 10000116 10000287 10000236 10000313 10000373 10000383 10000302 10000344 10000365 10000374 10000115 10000115 10000369 10000386 10000381 10000281 10000378 10000362 10000360 10000118 10000325 10000129 10000236 10000115 10000235 10000208 10000328 10000372 10000385 10000302 10000375 10000389 10000388 10000368 10000373 10000382 10000115 10000387 10000377 10000380 10000121 10000121 10000384
min_time_thread_terminate_total=10000113
max_time_thread_terminate_total=10000389
first_time_thread_start_by_thread=10 5 5 4 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 4 4 5 5 4 5 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 4 5 5 5 5 5 6 5 5 5 6 6 6 5 5 6 5 6 5 5 5 5 5 5 6 5 5 5 5 5 6 5 5 5 6 6 6 5 5 6 5 6 5 5 5 5 5 5 6
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=
threads_final_keysum=100017643329566
threads_final_size=10000953
final_keysum=100017643329566
final_size=10000953
validate_result=success
Validation OK.
Structural validation OK.
total_find=30323259
total_rq=0
total_inserts=1686123
total_deletes=1686036
total_updates=3372159
total_queries=30323259
total_ops=33695418
find_throughput=3032325
rq_throughput=0
update_throughput=337215
query_throughput=3032325
total_throughput=3369541
total find : 30323259
total rq : 0
total inserts : 1686123
total deletes : 1686036
total updates : 3372159
total queries : 30323259
total ops : 33695418
find throughput : 3032325
rq throughput : 0
update throughput : 337215
query throughput : 3032325
total throughput : 3369541
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=43.9672
PAPI_L3_TCM=14.2552
PAPI_TOT_CYC=71869.4
PAPI_TOT_INS=4511.06
garbage=151579587618703
total_execution_walltime=64.638s
REALTIME_MAIN_END_PERF_FORMAT=6993307.739759086
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=64.85, faults_major=0, faults_minor=320039, mem_maxresident_kb=1278448, user_cputime=6033.35, sys_cputime=87.29, percent_cpu=9436%
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=2