__step=000315
__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=data000315.txt
__path_data=data/data000315.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998556.572979840
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.011s
prefilling_size=109567 prefilling_elapsed_millis=1.001s
prefilling_size=120109 prefilling_elapsed_millis=1.101s
prefilling_size=130742 prefilling_elapsed_millis=1.201s
prefilling_size=141105 prefilling_elapsed_millis=1.301s
prefilling_size=151944 prefilling_elapsed_millis=1.402s
prefilling_size=162781 prefilling_elapsed_millis=1.502s
prefilling_size=173587 prefilling_elapsed_millis=1.602s
prefilling_size=184458 prefilling_elapsed_millis=1.702s
prefilling_size=194826 prefilling_elapsed_millis=1.802s
prefilling_size=204398 prefilling_elapsed_millis=1.903s
prefilling_size=214237 prefilling_elapsed_millis=2.003s
prefilling_size=224092 prefilling_elapsed_millis=2.103s
prefilling_size=234062 prefilling_elapsed_millis=2.203s
prefilling_size=243751 prefilling_elapsed_millis=2.303s
prefilling_size=253517 prefilling_elapsed_millis=2.404s
prefilling_size=263185 prefilling_elapsed_millis=2.504s
prefilling_size=272739 prefilling_elapsed_millis=2.604s
prefilling_size=282114 prefilling_elapsed_millis=2.704s
prefilling_size=291327 prefilling_elapsed_millis=2.804s
prefilling_size=300553 prefilling_elapsed_millis=2.904s
prefilling_size=309488 prefilling_elapsed_millis=3.005s
prefilling_size=318602 prefilling_elapsed_millis=3.105s
prefilling_size=327765 prefilling_elapsed_millis=3.205s
prefilling_size=336669 prefilling_elapsed_millis=3.305s
prefilling_size=345320 prefilling_elapsed_millis=3.405s
prefilling_size=354114 prefilling_elapsed_millis=3.506s
prefilling_size=362723 prefilling_elapsed_millis=3.606s
prefilling_size=371619 prefilling_elapsed_millis=3.706s
prefilling_size=380127 prefilling_elapsed_millis=3.806s
prefilling_size=388348 prefilling_elapsed_millis=3.906s
prefilling_size=396724 prefilling_elapsed_millis=4.007s
prefilling_size=404930 prefilling_elapsed_millis=4.107s
prefilling_size=413296 prefilling_elapsed_millis=4.207s
prefilling_size=421432 prefilling_elapsed_millis=4.307s
prefilling_size=429415 prefilling_elapsed_millis=4.407s
prefilling_size=437304 prefilling_elapsed_millis=4.508s
prefilling_size=445071 prefilling_elapsed_millis=4.608s
prefilling_size=452855 prefilling_elapsed_millis=4.708s
prefilling_size=460367 prefilling_elapsed_millis=4.808s
prefilling_size=468162 prefilling_elapsed_millis=4.908s
prefilling_size=475540 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 524391 keys with 96 threads...
tid=0 thread_prefill_amount=6249 percent_done_estimate=114.4 elapsed_s=6 est_remaining_s=-2 / 4
tid=0 thread_prefill_amount=8332 percent_done_estimate=152.5 elapsed_s=7 est_remaining_s=-6 / 2
tid=0 thread_prefill_amount=10415 percent_done_estimate=190.7 elapsed_s=9 est_remaining_s=-9 / -1
timing_elapsed 4.37373s
prefilling_size=1000000 prefilling_elapsed_millis=4.384s
finished prefilling to size 1000000 for expected size 1000000 keysum=999764789471, performing 2136870 updates; total_prefilling_elapsed_ms=4384 ms)
pref_size=1000000
pref_millis=4384
prefill_elapsed_ms=4384
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6998566.264157241
REALTIME_END_PERF_FORMAT=6998576.264329109
###############################################################################
################################## 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 2587 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.038s
tree_stats_numInternalsAtDepth=1 1 7 48 336 2587 20278 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 159996
tree_stats_numNodesAtDepth=1 1 7 48 336 2587 20278 159996
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 999355
tree_stats_avgDegreeAtDepth=1 7 6.85714 7 7.6994 7.83842 7.89013 6.24612
tree_stats_height=8
tree_stats_numInternals=23258
tree_stats_numLeaves=159996
tree_stats_numNodes=183254
tree_stats_numKeys=999355
tree_stats_avgDegreeInternal=7.87918
tree_stats_avgDegreeLeaves=6.24612
tree_stats_avgDegree=6.45339
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=50396
first_thread_announced_epoch_by_thread=1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1112 1114 1114 1114 1114 1112 1114 1114 1114 1114 1114 1114 1112 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114 1114
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=31495358
sum_fasthtm_abort_total=67581405
sum_htmpostfix_commit_total=1642159
sum_htmpostfix_abort_total=5123387
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=47041133
sum_stmprefix_commit_total=62
sum_slow_commit_total=15732
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=17994 14008 16965 17089 17299 16716 17752 13653 17182 17097 16913 17010 18112 14112 17323 17246 16728 16937 18115 14022 16674 16900 16810 16579 18021 14066 16964 17061 16775 16964 18080 13872 16999 17077 16595 16780 17944 14303 17346 16988 17110 16854 18028 13761 16552 17165 16931 16926 19246 14841 18706 18431 18034 17578 19365 15302 18794 18678 18447 18768 19750 15026 18000 17856 18105 18477 19096 15013 18476 18490 18304 18389 19282 14739 18703 18394 18053 17619 19504 15124 18677 18646 18354 18502 19259 15065 18133 17836 18046 17973 18993 14772 18422 18465 18481 18260
sum_num_inserts_total=1658872
sum_num_deletes_by_thread=17964 14070 16778 17042 17237 16650 17966 13634 16961 17165 16785 17053 18179 14311 17328 17207 16613 16836 18091 14001 16847 16758 16901 16832 17761 14143 16729 16879 16775 16660 17985 13607 16980 17001 16854 16896 18100 14318 17260 17146 17209 16682 18031 13910 16507 16922 16703 16850 19160 14852 18428 18437 18013 17537 19379 14935 18421 18684 18547 18291 19599 15286 18080 18050 18297 18530 19349 14957 18667 18296 18573 18410 19312 15011 18606 18664 18111 17643 19541 15084 18377 18744 18317 18540 19380 15216 18088 17844 18287 17892 19171 15141 18515 18552 18398 18509
sum_num_deletes_total=1658838
sum_num_searches_by_thread=322132 253974 305015 305894 313118 297668 324225 245088 308282 310301 301512 304188 325119 253952 310134 310541 302280 302957 325392 252850 301088 304820 301313 300543 320397 255648 307373 306392 302691 302574 325912 247211 306751 308354 298139 304632 324969 256799 310356 306895 306191 300629 324272 247288 297740 304540 303270 303163 346362 266270 331372 330785 326039 318835 348061 272179 334839 333861 335207 334242 352730 271123 324392 321755 328555 333499 345986 267763 334331 327426 329004 332759 348010 264954 331603 331581 325043 318122 349840 271341 334082 339865 333710 332564 347450 273945 327132 320232 325815 322930 343887 270955 330007 331222 328753 330611
sum_num_searches_total=29835601
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=358090 282052 338758 340025 347654 331034 359943 272375 342425 344563 335210 338251 361410 282375 344785 344994 335621 336730 361598 280873 334609 338478 335024 333954 356179 283857 341066 340332 336241 336198 361977 274690 340730 342432 331588 338308 361013 285420 344962 341029 340510 334165 360331 274959 330799 338627 336904 336939 384768 295963 368506 367653 362086 353950 386805 302416 372054 371223 372201 371301 392079 301435 360472 357661 364957 370506 384431 297733 371474 364212 365881 369558 386604 294704 368912 368639 361207 353384 388885 301549 371136 377255 370381 369606 386089 304226 363353 355912 362148 358795 382051 300868 366944 368239 365632 367380
average_num_operations_total=345346
stdev_num_operations_total=3042
sum_num_operations_total=33153311
min_num_operations_total=272375
max_num_operations_total=392079
first_time_thread_terminate_by_thread=10000417 10000369 10000314 10000326 10000363 10000287 10000172 10000358 10000253 10000413 10000437 10000314 10000363 10000495 10000423 10000225 10000174 10000218 10000229 10000171 10000267 10000366 10000439 10000208 10000207 10000171 10000207 10000363 10000320 10000235 10000390 10000266 10000346 10000418 10000425 10000192 10000345 10000394 10000223 10000193 10000388 10000374 10000301 10000171 10000227 10000276 10000409 10000226 10000265 10000303 10000305 10000389 10000414 10000489 10000171 10000300 10000279 10000431 10000416 10000412 10000658 10000490 10000224 10000249 10000648 10000247 10000420 10000432 10000171 10000391 10000326 10000355 10000171 10000446 10000429 10000180 10000427 10000394 10000272 10000364 10000417 10000443 10000355 10000382 10000279 10000435 10000323 10000445 10000171 10000442 10000337 10000388 10000378 10000330 10000355 10000441
min_time_thread_terminate_total=10000171
max_time_thread_terminate_total=10000658
first_time_thread_start_by_thread=8 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 4 5 5 4 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 4 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 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 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=999416447447
threads_final_size=999355
final_keysum=999416447447
final_size=999355
validate_result=success
Validation OK.
Structural validation OK.
total_find=29835601
total_rq=0
total_inserts=1658872
total_deletes=1658838
total_updates=3317710
total_queries=29835601
total_ops=33153311
find_throughput=2983560
rq_throughput=0
update_throughput=331771
query_throughput=2983560
total_throughput=3315331
total find : 29835601
total rq : 0
total inserts : 1658872
total deletes : 1658838
total updates : 3317710
total queries : 29835601
total ops : 33153311
find throughput : 2983560
rq throughput : 0
update throughput : 331771
query throughput : 2983560
total throughput : 3315331
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=30.9836
PAPI_L3_TCM=7.07309
PAPI_TOT_CYC=72428
PAPI_TOT_INS=3597.39
garbage=14911247343256
total_execution_walltime=19.841s
REALTIME_MAIN_END_PERF_FORMAT=6998576.416413016
RH noREC w/postfix htm system shutdown:
HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=20.02, faults_major=0, faults_minor=196901, mem_maxresident_kb=785952, user_cputime=1842.02, sys_cputime=29.81, percent_cpu=9349%
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