__step=000117 __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.hytm1 -nwork 144 -nprefill 144 -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=data000117.txt __path_data=data/data000117.txt __hostname=jax REALTIME_MAIN_START_PERF_FORMAT=6995697.841479331 binary=./brown_abtree_tm_auto.debra.hytm1 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=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 HyTM1 system ready Info: prefilling using hybrid approach; mixed then insert-only if needed. prefilling initialization took 0.024s prefilling_size=532010 prefilling_elapsed_millis=1.001s prefilling_size=616694 prefilling_elapsed_millis=1.101s prefilling_size=704797 prefilling_elapsed_millis=1.201s prefilling_size=782585 prefilling_elapsed_millis=1.301s prefilling_size=824076 prefilling_elapsed_millis=1.402s prefilling_size=859769 prefilling_elapsed_millis=1.502s prefilling_size=886587 prefilling_elapsed_millis=1.602s prefilling_size=908882 prefilling_elapsed_millis=1.702s prefilling_size=932880 prefilling_elapsed_millis=1.802s prefilling_size=972133 prefilling_elapsed_millis=1.903s prefilling_size=1040060 prefilling_elapsed_millis=2.003s prefilling_size=1111020 prefilling_elapsed_millis=2.103s prefilling_size=1182142 prefilling_elapsed_millis=2.203s prefilling_size=1251431 prefilling_elapsed_millis=2.304s prefilling_size=1321247 prefilling_elapsed_millis=2.404s prefilling_size=1391581 prefilling_elapsed_millis=2.504s prefilling_size=1460259 prefilling_elapsed_millis=2.604s prefilling_size=1516809 prefilling_elapsed_millis=2.704s prefilling_size=1558506 prefilling_elapsed_millis=2.805s prefilling_size=1593799 prefilling_elapsed_millis=2.905s prefilling_size=1627701 prefilling_elapsed_millis=3.005s prefilling_size=1659524 prefilling_elapsed_millis=3.105s prefilling_size=1691408 prefilling_elapsed_millis=3.205s prefilling_size=1723196 prefilling_elapsed_millis=3.306s prefilling_size=1750144 prefilling_elapsed_millis=3.406s prefilling_size=1769272 prefilling_elapsed_millis=3.506s prefilling_size=1790074 prefilling_elapsed_millis=3.606s prefilling_size=1811410 prefilling_elapsed_millis=3.706s prefilling_size=1831970 prefilling_elapsed_millis=3.807s prefilling_size=1854950 prefilling_elapsed_millis=3.907s prefilling_size=1885374 prefilling_elapsed_millis=4.007s prefilling_size=1935118 prefilling_elapsed_millis=4.107s prefilling_size=1991156 prefilling_elapsed_millis=4.207s prefilling_size=2055693 prefilling_elapsed_millis=4.308s prefilling_size=2118363 prefilling_elapsed_millis=4.408s prefilling_size=2179529 prefilling_elapsed_millis=4.508s prefilling_size=2243148 prefilling_elapsed_millis=4.608s prefilling_size=2304584 prefilling_elapsed_millis=4.708s prefilling_size=2367130 prefilling_elapsed_millis=4.809s prefilling_size=2427948 prefilling_elapsed_millis=4.909s prefilling_size=2488561 prefilling_elapsed_millis=5.009s Info: prefilling requires an additional insert phase to complete. Info: prefilling using INSERTION ONLY. timing_start inserting 7511334 keys with 144 threads... tid=0 thread_prefill_amount=18044 percent_done_estimate=34.6 elapsed_s=5 est_remaining_s=22 / 27 tid=0 thread_prefill_amount=19432 percent_done_estimate=37.3 elapsed_s=5 est_remaining_s=21 / 26 tid=0 thread_prefill_amount=20820 percent_done_estimate=39.9 elapsed_s=6 est_remaining_s=19 / 25 tid=0 thread_prefill_amount=22208 percent_done_estimate=42.6 elapsed_s=6 est_remaining_s=18 / 24 tid=0 thread_prefill_amount=23596 percent_done_estimate=45.2 elapsed_s=6 est_remaining_s=18 / 24 tid=0 thread_prefill_amount=24984 percent_done_estimate=47.9 elapsed_s=7 est_remaining_s=17 / 23 tid=0 thread_prefill_amount=26372 percent_done_estimate=50.6 elapsed_s=7 est_remaining_s=16 / 24 tid=0 thread_prefill_amount=27760 percent_done_estimate=53.2 elapsed_s=8 est_remaining_s=16 / 24 tid=0 thread_prefill_amount=29148 percent_done_estimate=55.9 elapsed_s=8 est_remaining_s=15 / 24 tid=0 thread_prefill_amount=30536 percent_done_estimate=58.5 elapsed_s=9 est_remaining_s=14 / 23 tid=0 thread_prefill_amount=31924 percent_done_estimate=61.2 elapsed_s=9 est_remaining_s=13 / 23 tid=0 thread_prefill_amount=33312 percent_done_estimate=63.9 elapsed_s=10 est_remaining_s=12 / 22 tid=0 thread_prefill_amount=34700 percent_done_estimate=66.5 elapsed_s=10 est_remaining_s=11 / 21 tid=0 thread_prefill_amount=36088 percent_done_estimate=69.2 elapsed_s=10 est_remaining_s=10 / 21 tid=0 thread_prefill_amount=37476 percent_done_estimate=71.8 elapsed_s=11 est_remaining_s=9 / 20 tid=0 thread_prefill_amount=38864 percent_done_estimate=74.5 elapsed_s=11 est_remaining_s=8 / 20 tid=0 thread_prefill_amount=40252 percent_done_estimate=77.2 elapsed_s=11 est_remaining_s=8 / 19 tid=0 thread_prefill_amount=41640 percent_done_estimate=79.8 elapsed_s=12 est_remaining_s=7 / 18 tid=0 thread_prefill_amount=43028 percent_done_estimate=82.5 elapsed_s=12 est_remaining_s=6 / 18 tid=0 thread_prefill_amount=44416 percent_done_estimate=85.2 elapsed_s=13 est_remaining_s=5 / 18 tid=0 thread_prefill_amount=45804 percent_done_estimate=87.8 elapsed_s=13 est_remaining_s=4 / 18 tid=0 thread_prefill_amount=47192 percent_done_estimate=90.5 elapsed_s=14 est_remaining_s=3 / 17 tid=0 thread_prefill_amount=48580 percent_done_estimate=93.1 elapsed_s=14 est_remaining_s=2 / 16 tid=0 thread_prefill_amount=49968 percent_done_estimate=95.8 elapsed_s=14 est_remaining_s=1 / 16 tid=0 thread_prefill_amount=51356 percent_done_estimate=98.5 elapsed_s=15 est_remaining_s=1 / 15 tid=0 thread_prefill_amount=52744 percent_done_estimate=101.1 elapsed_s=15 est_remaining_s=-0 / 15 tid=0 thread_prefill_amount=54132 percent_done_estimate=103.8 elapsed_s=16 est_remaining_s=-1 / 14 tid=0 thread_prefill_amount=55520 percent_done_estimate=106.4 elapsed_s=16 est_remaining_s=-2 / 14 tid=0 thread_prefill_amount=56908 percent_done_estimate=109.1 elapsed_s=16 est_remaining_s=-3 / 13 tid=0 thread_prefill_amount=58296 percent_done_estimate=111.8 elapsed_s=17 est_remaining_s=-4 / 13 tid=0 thread_prefill_amount=59684 percent_done_estimate=114.4 elapsed_s=17 est_remaining_s=-5 / 12 tid=0 thread_prefill_amount=61072 percent_done_estimate=117.1 elapsed_s=18 est_remaining_s=-6 / 12 tid=0 thread_prefill_amount=62460 percent_done_estimate=119.7 elapsed_s=18 est_remaining_s=-7 / 11 tid=0 thread_prefill_amount=63848 percent_done_estimate=122.4 elapsed_s=19 est_remaining_s=-8 / 11 tid=0 thread_prefill_amount=65236 percent_done_estimate=125.1 elapsed_s=20 est_remaining_s=-9 / 11 tid=0 thread_prefill_amount=66624 percent_done_estimate=127.7 elapsed_s=20 est_remaining_s=-10 / 10 tid=0 thread_prefill_amount=68012 percent_done_estimate=130.4 elapsed_s=21 est_remaining_s=-11 / 10 tid=0 thread_prefill_amount=69400 percent_done_estimate=133.0 elapsed_s=22 est_remaining_s=-12 / 10 timing_elapsed 17.1258s prefilling_size=10000000 prefilling_elapsed_millis=17.166s finished prefilling to size 10000000 for expected size 10000000 keysum=99983371703926, performing 16923882 updates; total_prefilling_elapsed_ms=17167 ms) pref_size=10000000 pref_millis=17167 prefill_elapsed_ms=17167 main thread: starting timer... ############################################################################### ################################ BEGIN RUNNING ################################ ############################################################################### REALTIME_START_PERF_FORMAT=6995720.404160411 REALTIME_END_PERF_FORMAT=6995730.404245353 ############################################################################### ################################## 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 3421 subtrees; running parallel for... computing stats for the top of the tree (above the partitions)... tree_stats_computeWalltime=0.046s tree_stats_numInternalsAtDepth=1 1 8 66 460 3421 25689 200331 0 tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1582008 tree_stats_numNodesAtDepth=1 1 8 66 460 3421 25689 200331 1582008 tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001439 tree_stats_avgDegreeAtDepth=1 8 8.25 6.9697 7.43696 7.50921 7.79832 7.89697 6.32199 tree_stats_height=9 tree_stats_numInternals=229977 tree_stats_numLeaves=1582008 tree_stats_numNodes=1811985 tree_stats_numKeys=10001439 tree_stats_avgDegreeInternal=7.87898 tree_stats_avgDegreeLeaves=6.32199 tree_stats_avgDegree=6.5196 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=214702 first_thread_announced_epoch_by_thread=3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 3174 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=101832 102195 103229 103135 102194 101925 102733 102410 103602 102912 102312 102293 102115 102787 103306 102277 103021 102418 100989 102275 102469 103217 102076 101803 100990 101737 103116 102965 102820 101416 102379 103047 103225 102935 103284 102666 101937 103111 103615 103491 102495 101637 101519 102158 102268 102760 101936 101776 102664 102969 103936 103737 102222 102502 102615 103039 103500 104182 103489 103018 103015 103589 103548 104310 103785 102459 102657 102750 103863 104103 103489 102925 102763 103384 103735 104105 102888 102680 102580 103340 104126 104231 102127 103263 102747 103970 103873 104077 103044 102566 101519 103150 103992 103660 102657 102571 100222 100477 100869 100472 100447 100666 99519 100380 101349 101202 99880 100424 99913 101345 101728 101832 99971 99669 99622 99974 100658 100381 99777 100073 100262 100244 100720 100852 100531 100141 99328 100498 101569 101003 99928 99579 100141 101381 101465 101775 100469 99187 100097 101055 100773 100966 100509 99937 sum_num_inserts_total=14697482 sum_num_deletes_by_thread=101286 102460 103000 102606 103101 102138 102141 102531 103318 103201 102682 102064 101802 102601 103158 103692 103355 102486 101176 101631 102864 102755 102381 101772 101943 102219 103058 103095 102714 101669 102018 101684 102970 102741 102659 101930 102182 102436 103170 103158 103387 102383 100782 101551 102215 102289 102063 101822 102525 103836 104126 103603 102617 102719 102343 103077 103649 104404 102764 102259 102982 103415 103814 104194 103303 103318 102129 102594 104009 103777 103286 102735 102719 102811 104310 103799 102574 102587 102205 102658 103926 103915 102753 103098 103181 102818 103826 103783 103793 102126 102076 102495 103670 103307 103224 103136 100385 100170 101200 100633 101435 99702 99885 101079 100976 101636 100096 99695 99713 101744 101819 101198 100161 98469 99334 100530 99920 100584 99714 98701 99517 99941 100419 100702 100691 100006 100215 100547 101333 101513 100252 100215 100428 101291 101898 101407 100848 99535 99912 100237 101092 101427 101171 100076 sum_num_deletes_total=14692059 sum_num_searches_by_thread=1829511 1841145 1854178 1848413 1845055 1835858 1839262 1846891 1865079 1861733 1845522 1841318 1841887 1848297 1857584 1855084 1850815 1842139 1823478 1839542 1849042 1860024 1838845 1827712 1829560 1840572 1847509 1848508 1849809 1830084 1838594 1838681 1859501 1855167 1847003 1840846 1842817 1847266 1861370 1858976 1852674 1842970 1824521 1837668 1847355 1852713 1838531 1827039 1846133 1855617 1866548 1863903 1850805 1844946 1848962 1851131 1863512 1868320 1854697 1849535 1849713 1860067 1869441 1871668 1863572 1849885 1839814 1847643 1866821 1872124 1856728 1851464 1848336 1857712 1870717 1871954 1858162 1845127 1845937 1854498 1871599 1874591 1845050 1847183 1850506 1857769 1865494 1867502 1855421 1843061 1828850 1849184 1869819 1867282 1857091 1848993 1797641 1803734 1820958 1811813 1818590 1803806 1800990 1812093 1824675 1827083 1802689 1800671 1810470 1819891 1829054 1826263 1801304 1784548 1792996 1799344 1814714 1815895 1802786 1785904 1794189 1799992 1816394 1813116 1816430 1799304 1801450 1812137 1831644 1824845 1802531 1799386 1805301 1822246 1824392 1827087 1813238 1795674 1800392 1817668 1825584 1830040 1811397 1796191 sum_num_searches_total=264551575 sum_num_rq_by_thread= sum_num_rq_total= sum_num_operations_by_thread=2032629 2045800 2060407 2054154 2050350 2039921 2044136 2051832 2071999 2067846 2050516 2045675 2045804 2053685 2064048 2061053 2057191 2047043 2025643 2043448 2054375 2065996 2043302 2031287 2032493 2044528 2053683 2054568 2055343 2033169 2042991 2043412 2065696 2060843 2052946 2045442 2046936 2052813 2068155 2065625 2058556 2046990 2026822 2041377 2051838 2057762 2042530 2030637 2051322 2062422 2074610 2071243 2055644 2050167 2053920 2057247 2070661 2076906 2060950 2054812 2055710 2067071 2076803 2080172 2070660 2055662 2044600 2052987 2074693 2080004 2063503 2057124 2053818 2063907 2078762 2079858 2063624 2050394 2050722 2060496 2079651 2082737 2049930 2053544 2056434 2064557 2073193 2075362 2062258 2047753 2032445 2054829 2077481 2074249 2062972 2054700 1998248 2004381 2023027 2012918 2020472 2004174 2000394 2013552 2027000 2029921 2002665 2000790 2010096 2022980 2032601 2029293 2001436 1982686 1991952 1999848 2015292 2016860 2002277 1984678 1993968 2000177 2017533 2014670 2017652 1999451 2000993 2013182 2034546 2027361 2002711 1999180 2005870 2024918 2027755 2030269 2014555 1994396 2000401 2018960 2027449 2032433 2013077 1996204 average_num_operations_total=2041257 stdev_num_operations_total=2104 sum_num_operations_total=293941116 min_num_operations_total=1982686 max_num_operations_total=2082737 first_time_thread_terminate_by_thread=10000163 10000084 10000087 10000161 10000086 10000086 10000084 10000088 10000085 10000088 10000084 10000086 10000085 10000086 10000086 10000084 10000085 10000084 10000087 10000085 10000087 10000084 10000086 10000088 10000084 10000084 10000085 10000086 10000086 10000086 10000084 10000088 10000085 10000088 10000084 10000087 10000085 10000086 10000086 10000084 10000085 10000084 10000086 10000085 10000085 10000084 10000086 10000088 10000125 10000084 10000086 10000085 10000087 10000084 10000083 10000084 10000131 10000084 10000084 10000084 10000087 10000087 10000087 10000083 10000087 10000087 10000087 10000084 10000087 10000083 10000084 10000084 10000087 10000084 10000086 10000085 10000087 10000087 10000083 10000084 10000083 10000084 10000084 10000084 10000087 10000087 10000087 10000084 10000087 10000084 10000087 10000084 10000087 10000083 10000084 10000084 10000084 10000086 10000086 10000084 10000084 10000086 10000088 10000086 10000084 10000085 10000088 10000085 10000085 10000088 10000088 10000084 10000088 10000086 10000084 10000084 10000085 10000086 10000087 10000084 10000084 10000084 10000085 10000084 10000084 10000086 10000088 10000084 10000084 10000085 10000088 10000085 10000085 10000088 10000088 10000084 10000088 10000086 10000084 10000084 10000085 10000086 10000087 10000084 min_time_thread_terminate_total=10000083 max_time_thread_terminate_total=10000163 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 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 5 6 5 6 6 6 6 6 6 6 6 5 6 6 6 6 6 6 6 6 6 6 6 6 5 6 5 6 6 6 6 6 6 6 5 5 6 6 6 6 6 6 6 5 6 6 5 5 6 5 6 6 6 6 6 6 6 6 5 5 6 6 6 6 6 6 6 5 6 6 5 5 6 5 6 6 min_time_thread_start_total=4 max_time_thread_start_total=9 sum_duration_all_ops_total= threads_final_keysum=99999242767158 threads_final_size=10001439 final_keysum=99999242767158 final_size=10001439 validate_result=success Validation OK. Structural validation OK. total_find=264551575 total_rq=0 total_inserts=14697482 total_deletes=14692059 total_updates=29389541 total_queries=264551575 total_ops=293941116 find_throughput=26455157 rq_throughput=0 update_throughput=2938954 query_throughput=26455157 total_throughput=29394111 total find : 264551575 total rq : 0 total inserts : 14697482 total deletes : 14692059 total updates : 29389541 total queries : 264551575 total ops : 293941116 find throughput : 26455157 rq throughput : 0 update throughput : 2938954 query throughput : 26455157 total throughput : 29394111 elapsed milliseconds : 10000 napping milliseconds overtime : 100 PAPI_L2_TCM=17.5083 PAPI_L3_TCM=6.31448 PAPI_TOT_CYC=13552.3 PAPI_TOT_INS=10272.8 garbage=1313479343678634 total_execution_walltime=32.737s REALTIME_MAIN_END_PERF_FORMAT=6995730.580249140 HyTM1 system shutdown: HTM_ATTEMPT_THRESH=40 [fast htm] total fast htm commit : 308868552 [slow htm] [fallback] total fallback commit : 1996447 seconds global lock is held : 27.0629 [time_cmd_output] time_elapsed_sec=33.12, faults_major=0, faults_minor=1070914, mem_maxresident_kb=4034608, user_cputime=4610.73, sys_cputime=12.12, percent_cpu=13955% timeout=false algorithm=brown_abtree_tm_auto.debra.hytm1 thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191 millis=10000 __trials=1