__step=000309
__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.hybridnorec -nwork 144 -nprefill 144 -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=data000309.txt
__path_data=data/data000309.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6998454.208395206
binary=./brown_abtree_tm_auto.debra.hybridnorec
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=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
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,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.049s
prefilling_size=222610 prefilling_elapsed_millis=1s
prefilling_size=241921 prefilling_elapsed_millis=1.101s
prefilling_size=260780 prefilling_elapsed_millis=1.201s
prefilling_size=279026 prefilling_elapsed_millis=1.301s
prefilling_size=296993 prefilling_elapsed_millis=1.401s
prefilling_size=314462 prefilling_elapsed_millis=1.501s
prefilling_size=331553 prefilling_elapsed_millis=1.602s
prefilling_size=347932 prefilling_elapsed_millis=1.702s
prefilling_size=364308 prefilling_elapsed_millis=1.802s
prefilling_size=380135 prefilling_elapsed_millis=1.902s
prefilling_size=395544 prefilling_elapsed_millis=2.002s
prefilling_size=410652 prefilling_elapsed_millis=2.103s
prefilling_size=425269 prefilling_elapsed_millis=2.203s
prefilling_size=439769 prefilling_elapsed_millis=2.303s
prefilling_size=453725 prefilling_elapsed_millis=2.403s
prefilling_size=467217 prefilling_elapsed_millis=2.503s
prefilling_size=480488 prefilling_elapsed_millis=2.604s
prefilling_size=493029 prefilling_elapsed_millis=2.704s
prefilling_size=505520 prefilling_elapsed_millis=2.804s
prefilling_size=517637 prefilling_elapsed_millis=2.904s
prefilling_size=529542 prefilling_elapsed_millis=3.004s
prefilling_size=541185 prefilling_elapsed_millis=3.105s
prefilling_size=552569 prefilling_elapsed_millis=3.205s
prefilling_size=563512 prefilling_elapsed_millis=3.305s
prefilling_size=573940 prefilling_elapsed_millis=3.405s
prefilling_size=584754 prefilling_elapsed_millis=3.506s
prefilling_size=595026 prefilling_elapsed_millis=3.606s
prefilling_size=604881 prefilling_elapsed_millis=3.706s
prefilling_size=614437 prefilling_elapsed_millis=3.806s
prefilling_size=623969 prefilling_elapsed_millis=3.906s
prefilling_size=633281 prefilling_elapsed_millis=4.007s
prefilling_size=642423 prefilling_elapsed_millis=4.107s
prefilling_size=651382 prefilling_elapsed_millis=4.207s
prefilling_size=660311 prefilling_elapsed_millis=4.307s
prefilling_size=668552 prefilling_elapsed_millis=4.407s
prefilling_size=676723 prefilling_elapsed_millis=4.508s
prefilling_size=684623 prefilling_elapsed_millis=4.608s
prefilling_size=692213 prefilling_elapsed_millis=4.708s
prefilling_size=699642 prefilling_elapsed_millis=4.808s
prefilling_size=707023 prefilling_elapsed_millis=4.909s
prefilling_size=714167 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 285750 keys with 144 threads...
tid=0 thread_prefill_amount=5552 percent_done_estimate=279.8 elapsed_s=5 est_remaining_s=-8 / -2
timing_elapsed 1.03936s
prefilling_size=1000000 prefilling_elapsed_millis=1.041s
finished prefilling to size 1000000 for expected size 1000000 keysum=999731403883, performing 3015163 updates; total_prefilling_elapsed_ms=1042 ms)
pref_size=1000000
pref_millis=1042
prefill_elapsed_ms=1042
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6998460.628160818
REALTIME_END_PERF_FORMAT=6998470.628265539

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

tree_stats_computeWalltime=0.034s

tree_stats_numInternalsAtDepth=1 1 7 46 349 2735 21172 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 167115
tree_stats_numNodesAtDepth=1 1 7 46 349 2735 21172 167115
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 1000019
tree_stats_avgDegreeAtDepth=1 7 6.57143 7.58696 7.83668 7.74113 7.89321 5.98402

tree_stats_height=8
tree_stats_numInternals=24311
tree_stats_numLeaves=167115
tree_stats_numNodes=191426
tree_stats_numKeys=1000019

tree_stats_avgDegreeInternal=7.87405
tree_stats_avgDegreeLeaves=5.98402
tree_stats_avgDegree=6.22405
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=55392
first_thread_announced_epoch_by_thread=806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806 806
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=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
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=
sum_stmprefix_commit_total=
sum_slow_commit_total=
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=16978 16961 17471 17333 18291 17793 17792 17566 17507 17679 17944 18342 17338 16879 17542 17876 17790 18091 17168 17153 17625 18085 18010 18048 17292 17159 17428 17615 17879 17964 17159 17360 17558 17830 17868 18090 17185 17231 17533 17475 17844 17816 17412 17306 17339 17458 18000 17992 20380 20026 20594 20737 20790 20314 20369 20382 20915 20270 19916 21292 20595 20256 20889 21228 20786 20906 20193 19891 20696 21084 20802 20999 19956 20088 20521 20519 20581 20335 20452 20370 20980 20583 20405 21649 20340 20542 20419 20766 21123 21013 20147 20205 20338 20393 20696 20826 12590 12462 12893 12806 12640 12353 12718 12684 12915 12770 12573 12844 12647 12497 12950 12906 12650 12861 12369 12322 12662 12952 12796 12914 12636 12442 12686 12802 12417 12549 12908 12726 13005 12778 12359 12923 12724 12547 12871 12984 12625 12957 12300 12366 12916 12719 12618 13184
sum_num_inserts_total=2442428
sum_num_deletes_by_thread=17116 17027 17452 17457 18083 18080 17520 17344 17544 17591 17898 18427 17172 16934 17645 17619 17943 18076 16897 17251 17605 18000 17913 18002 17082 16978 17327 17615 18112 18063 17195 17357 17679 17999 17801 18229 17299 17178 17595 17709 18016 18210 17146 17137 17695 17686 17698 17853 20123 19774 20836 20768 20456 20493 20543 20844 20756 20108 20224 20974 20235 20360 20905 21043 20823 21040 20035 19556 20713 20986 20592 20955 20006 20060 20382 20303 20546 20265 20541 20443 21282 20480 20223 21567 20323 20399 20354 20661 21035 20524 20009 20033 20530 20511 20887 20795 12641 12198 12894 12691 12620 12376 12519 12466 12825 12804 12516 13218 12728 12467 13123 12862 12890 12803 12780 12412 12636 13013 12733 13125 12680 12403 12701 12841 12468 12536 12648 12517 12863 12837 12461 13331 12614 12574 12976 12715 12505 12790 12462 12402 12691 12838 12897 12972
sum_num_deletes_total=2440618
sum_num_searches_by_thread=308085 306483 315359 316113 328896 320929 320800 312589 311040 316947 321649 332666 311021 304548 316852 321177 322334 325824 308144 309906 317893 324394 323406 325438 309942 308490 311709 318095 324685 320862 311520 311592 321658 321972 320788 324681 306461 308408 316257 316704 322614 325318 310213 309419 315754 316666 319553 326414 363488 361242 371572 375049 367359 366055 368938 367314 378961 367859 362443 379856 366530 363844 375197 377825 375039 377549 360776 354670 372312 381967 372301 378202 359170 358530 367795 367292 371573 365482 368526 368527 380934 366590 365529 389754 364731 369031 366758 375696 378244 372494 361121 362630 370903 368577 371882 376637 226452 223077 232330 230534 229602 222799 227739 226533 232391 232761 226904 234760 229030 224133 234326 232088 231493 231432 226673 220455 227040 234720 230827 235793 224244 223516 228795 233411 224315 225634 229595 227179 233129 231814 222067 237468 227555 226897 230847 229398 227091 229787 222759 225349 228208 226882 230637 236065
sum_num_searches_total=43963556
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=342179 340471 350282 350903 365270 356802 356112 347499 346091 352217 357491 369435 345531 338361 352039 356672 358067 361991 342209 344310 353123 360479 359329 361488 344316 342627 346464 353325 360676 356889 345874 346309 356895 357801 356457 361000 340945 342817 351385 351888 358474 361344 344771 343862 350788 351810 355251 362259 403991 401042 413002 416554 408605 406862 409850 408540 420632 408237 402583 422122 407360 404460 416991 420096 416648 419495 401004 394117 413721 424037 413695 420156 399132 398678 408698 408114 412700 406082 409519 409340 423196 407653 406157 432970 405394 409972 407531 417123 420402 414031 401277 402868 411771 409481 413465 418258 251683 247737 258117 256031 254862 247528 252976 251683 258131 258335 251993 260822 254405 249097 260399 257856 257033 257096 251822 245189 252338 260685 256356 261832 249560 248361 254182 259054 249200 250719 255151 252422 258997 257429 246887 263722 252893 252018 256694 255097 252221 255534 247521 250117 253815 252439 256152 262221
average_num_operations_total=339212
stdev_num_operations_total=5453
sum_num_operations_total=48846602
min_num_operations_total=245189
max_num_operations_total=432970
first_time_thread_terminate_by_thread=10000364 10000103 10000158 10000204 10000114 10000277 10000344 10000357 10000109 10000318 10000136 10000107 10000140 10000409 10000424 10000125 10000350 10000107 10000359 10000425 10000305 10000120 10000356 10000307 10000104 10000190 10000328 10000202 10000427 10000422 10000104 10000236 10000333 10000363 10000137 10000296 10000272 10000353 10000372 10000156 10000310 10000144 10000299 10000275 10000351 10000118 10000322 10000134 10000145 10000368 10000246 10000194 10000389 10000403 10000407 10000416 10000361 10000526 10000393 10000377 10000213 10000381 10000420 10000181 10000210 10000177 10000415 10000174 10000378 10000124 10000239 10000107 10000150 10000370 10000262 10000376 10000244 10000169 10000103 10000416 10000295 10000103 10000153 10000413 10000414 10000383 10000103 10000242 10000391 10000348 10000396 10000104 10000380 10000367 10000150 10000186 10000292 10000199 10000313 10000342 10000109 10000314 10000404 10000400 10000221 10000260 10000394 10000402 10000281 10000332 10000418 10000287 10000385 10000232 10000386 10000395 10000326 10000250 10000162 10000214 10000269 10000251 10000387 10000428 10000227 10000406 10000418 10000103 10000218 10000285 10000401 10000165 10000411 10000257 10000235 10000301 10000340 10000289 10000329 10000421 10000338 10000142 10000264 10000410
min_time_thread_terminate_total=10000103
max_time_thread_terminate_total=10000526
first_time_thread_start_by_thread=9 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 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=1000151792166
threads_final_size=1000019
final_keysum=1000151792166
final_size=1000019
validate_result=success
Validation OK.
Structural validation OK.

total_find=43963556
total_rq=0
total_inserts=2442428
total_deletes=2440618
total_updates=4883046
total_queries=43963556
total_ops=48846602
find_throughput=4396355
rq_throughput=0
update_throughput=488304
query_throughput=4396355
total_throughput=4884660


total find                    : 43963556
total rq                      : 0
total inserts                 : 2442428
total deletes                 : 2440618
total updates                 : 4883046
total queries                 : 43963556
total ops                     : 48846602
find throughput               : 4396355
rq throughput                 : 0
update throughput             : 488304
query throughput              : 4396355
total throughput              : 4884660

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=26.0028
PAPI_L3_TCM=4.8672
PAPI_TOT_CYC=81872.7
PAPI_TOT_INS=7367.55
garbage=21903688388745
total_execution_walltime=16.579s
REALTIME_MAIN_END_PERF_FORMAT=6998470.788441665
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 48365385
[slow htm]
[fallback]
total fallback commit         : 3496381
seconds global lock is held   : 0.400192
[time_cmd_output] time_elapsed_sec=16.75, faults_major=0, faults_minor=137883, mem_maxresident_kb=546420, user_cputime=2336.53, sys_cputime=6.74, percent_cpu=13985%
timeout=false
algorithm=brown_abtree_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1