__step=000078
__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.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=data000078.txt
__path_data=data/data000078.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6993931.497492358
binary=./brown_sigouin_int_avl_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_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=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=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,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.022s
prefilling_size=807271 prefilling_elapsed_millis=1.001s
prefilling_size=910850 prefilling_elapsed_millis=1.101s
prefilling_size=1021915 prefilling_elapsed_millis=1.201s
prefilling_size=1136047 prefilling_elapsed_millis=1.301s
prefilling_size=1230870 prefilling_elapsed_millis=1.402s
prefilling_size=1316267 prefilling_elapsed_millis=1.502s
prefilling_size=1398285 prefilling_elapsed_millis=1.602s
prefilling_size=1479952 prefilling_elapsed_millis=1.702s
prefilling_size=1562585 prefilling_elapsed_millis=1.802s
prefilling_size=1643107 prefilling_elapsed_millis=1.903s
prefilling_size=1722504 prefilling_elapsed_millis=2.003s
prefilling_size=1800976 prefilling_elapsed_millis=2.103s
prefilling_size=1877735 prefilling_elapsed_millis=2.203s
prefilling_size=1955089 prefilling_elapsed_millis=2.303s
prefilling_size=2032204 prefilling_elapsed_millis=2.404s
prefilling_size=2108813 prefilling_elapsed_millis=2.504s
prefilling_size=2185340 prefilling_elapsed_millis=2.604s
prefilling_size=2261302 prefilling_elapsed_millis=2.704s
prefilling_size=2335951 prefilling_elapsed_millis=2.804s
prefilling_size=2420815 prefilling_elapsed_millis=2.905s
prefilling_size=2530532 prefilling_elapsed_millis=3.005s
prefilling_size=2607230 prefilling_elapsed_millis=3.105s
prefilling_size=2679936 prefilling_elapsed_millis=3.205s
prefilling_size=2751973 prefilling_elapsed_millis=3.305s
prefilling_size=2823538 prefilling_elapsed_millis=3.406s
prefilling_size=2895695 prefilling_elapsed_millis=3.506s
prefilling_size=2967909 prefilling_elapsed_millis=3.606s
prefilling_size=3040544 prefilling_elapsed_millis=3.706s
prefilling_size=3110931 prefilling_elapsed_millis=3.806s
prefilling_size=3185633 prefilling_elapsed_millis=3.907s
prefilling_size=3264016 prefilling_elapsed_millis=4.007s
prefilling_size=3352698 prefilling_elapsed_millis=4.107s
prefilling_size=3442255 prefilling_elapsed_millis=4.207s
prefilling_size=3538082 prefilling_elapsed_millis=4.307s
prefilling_size=3628456 prefilling_elapsed_millis=4.408s
prefilling_size=3720904 prefilling_elapsed_millis=4.508s
prefilling_size=3801644 prefilling_elapsed_millis=4.608s
prefilling_size=3872582 prefilling_elapsed_millis=4.708s
prefilling_size=3939339 prefilling_elapsed_millis=4.808s
prefilling_size=4003931 prefilling_elapsed_millis=4.909s
prefilling_size=4067564 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 5932358 keys with 144 threads...
tid=0 thread_prefill_amount=29148 percent_done_estimate=70.8 elapsed_s=5 est_remaining_s=5 / 10
tid=0 thread_prefill_amount=30536 percent_done_estimate=74.1 elapsed_s=5 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=31924 percent_done_estimate=77.5 elapsed_s=6 est_remaining_s=4 / 9
tid=0 thread_prefill_amount=33312 percent_done_estimate=80.9 elapsed_s=6 est_remaining_s=3 / 9
tid=0 thread_prefill_amount=34700 percent_done_estimate=84.2 elapsed_s=6 est_remaining_s=3 / 9
tid=0 thread_prefill_amount=36088 percent_done_estimate=87.6 elapsed_s=6 est_remaining_s=2 / 8
tid=0 thread_prefill_amount=37476 percent_done_estimate=91.0 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=38864 percent_done_estimate=94.3 elapsed_s=7 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=40252 percent_done_estimate=97.7 elapsed_s=7 est_remaining_s=0 / 7
tid=0 thread_prefill_amount=41640 percent_done_estimate=101.1 elapsed_s=7 est_remaining_s=-0 / 7
tid=0 thread_prefill_amount=43028 percent_done_estimate=104.4 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=44416 percent_done_estimate=107.8 elapsed_s=8 est_remaining_s=-1 / 6
tid=0 thread_prefill_amount=45804 percent_done_estimate=111.2 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=47192 percent_done_estimate=114.6 elapsed_s=8 est_remaining_s=-2 / 6
tid=0 thread_prefill_amount=48580 percent_done_estimate=117.9 elapsed_s=8 est_remaining_s=-3 / 5
tid=0 thread_prefill_amount=49968 percent_done_estimate=121.3 elapsed_s=8 est_remaining_s=-3 / 5
tid=0 thread_prefill_amount=51356 percent_done_estimate=124.7 elapsed_s=9 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=52744 percent_done_estimate=128.0 elapsed_s=9 est_remaining_s=-4 / 4
tid=0 thread_prefill_amount=54132 percent_done_estimate=131.4 elapsed_s=9 est_remaining_s=-5 / 4
tid=0 thread_prefill_amount=55520 percent_done_estimate=134.8 elapsed_s=9 est_remaining_s=-5 / 4
tid=0 thread_prefill_amount=56908 percent_done_estimate=138.1 elapsed_s=9 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=58296 percent_done_estimate=141.5 elapsed_s=10 est_remaining_s=-6 / 3
tid=0 thread_prefill_amount=59684 percent_done_estimate=144.9 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=61072 percent_done_estimate=148.2 elapsed_s=10 est_remaining_s=-7 / 3
tid=0 thread_prefill_amount=62460 percent_done_estimate=151.6 elapsed_s=10 est_remaining_s=-8 / 2
tid=0 thread_prefill_amount=63848 percent_done_estimate=155.0 elapsed_s=11 est_remaining_s=-8 / 2
tid=0 thread_prefill_amount=65236 percent_done_estimate=158.4 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=66624 percent_done_estimate=161.7 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=68012 percent_done_estimate=165.1 elapsed_s=11 est_remaining_s=-10 / 1
tid=0 thread_prefill_amount=69400 percent_done_estimate=168.5 elapsed_s=11 est_remaining_s=-10 / 1
timing_elapsed 6.17928s
prefilling_size=10000000 prefilling_elapsed_millis=6.194s
finished prefilling to size 10000000 for expected size 10000000 keysum=99988844763325, performing 19760411 updates; total_prefilling_elapsed_ms=6194 ms)
pref_size=10000000
pref_millis=6194
prefill_elapsed_ms=6194
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6993943.53791626
REALTIME_END_PERF_FORMAT=6993953.53872841

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

tree_stats_computeWalltime=0.021s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131066 261939 518284 961285 1426902 1373104 729012 188754 18935 382 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 172 5004 64763 406907 1149967 1481581 886929 239142 23827 473
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262111 523288 1026048 1833809 2523071 2210593 1075683 258077 24209 473
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262111 523288 1026048 1833809 2523071 2210593 1075683 258077 24209 473
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.9998 1.99709 1.97033 1.85037 1.59776 1.33193 1.15682 1.06445 1.02044 1.00376 1

tree_stats_height=28
tree_stats_numInternals=5740734
tree_stats_numLeaves=4258770
tree_stats_numNodes=9999504
tree_stats_numKeys=9999504

tree_stats_avgDegreeInternal=1.74185
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.4259
tree_stats_avgKeyDepth=21.7158


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=170495
first_thread_announced_epoch_by_thread=2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574 2574
sum_num_inserts_by_thread=82238 81898 83201 83504 82971 82349 81974 81890 83251 81298 81835 77808 82467 81829 82814 83088 82430 81696 82036 81835 82477 84035 82223 82712 82451 82208 83542 81032 82554 81974 82003 80570 83023 81922 81623 81082 83192 82296 83323 83204 82460 82017 82383 80952 82972 78634 81988 81889 81994 82573 82899 83226 82728 82033 80546 82607 84029 83485 81560 82288 82156 82692 83590 82936 83289 81547 81653 82119 83409 83019 81983 81667 82046 81939 82722 81908 82855 81437 81524 81688 81064 82846 82815 81993 82478 82314 82919 82136 82818 82084 81624 82353 83478 82268 82751 82156 80972 80843 79845 82023 81641 81411 81230 81976 82260 81645 81007 81123 81384 81196 82383 83072 80375 80161 79535 81446 81992 82125 80424 79738 80872 80339 81136 80776 81579 81026 81344 82045 83160 82262 81057 79953 81760 79734 82404 81484 80875 80841 79318 80264 82513 81777 80804 80571
sum_num_inserts_total=11795098
sum_num_deletes_by_thread=82126 81299 83338 83733 81993 81798 81869 81439 83266 81931 82069 78221 82198 82214 82582 82992 82789 81979 82378 81814 82120 83017 82260 82652 82704 82670 83277 81394 82690 82521 81787 80096 82652 82630 81658 81734 83304 82505 83402 83642 82561 82166 82173 81164 82944 79032 81862 81984 82172 82517 82739 83233 83126 82334 80985 82511 83839 83165 81829 82655 82027 83290 82798 83129 83175 82226 82075 82708 82864 82706 82294 82100 82655 82145 82510 82037 82896 81310 81824 81961 80604 83376 82353 82318 82529 83268 82943 81917 82787 81538 82159 82454 82893 81956 82638 82587 81237 81300 79744 81593 81943 81176 81475 81890 82642 81765 81638 81409 80900 81795 82226 82415 81160 80656 78651 80839 81954 81960 80396 79825 80610 79822 80544 80701 81065 80656 81389 82242 82515 82477 80954 80275 80791 80316 82239 81335 81058 80757 78980 79955 81434 81558 80653 80173
sum_num_deletes_total=11795872
sum_num_searches_by_thread=1477228 1469256 1496254 1500529 1484595 1471948 1474550 1472147 1496703 1473141 1474959 1404355 1486821 1472920 1485724 1498076 1487391 1477974 1478771 1471932 1487252 1505425 1473768 1482298 1482890 1481304 1494407 1463164 1492626 1478118 1477959 1446520 1489437 1481285 1477025 1470382 1492134 1477520 1497994 1501452 1485452 1479182 1472025 1458742 1496934 1420056 1470503 1476090 1472561 1482757 1489776 1500949 1486638 1483526 1457240 1485851 1509173 1493116 1469939 1479776 1474474 1493504 1501018 1494808 1500441 1472256 1478208 1481897 1501403 1491092 1479027 1476085 1484193 1478280 1490004 1479018 1485758 1466096 1469210 1480791 1460826 1495228 1491109 1485239 1482662 1486913 1495295 1475039 1488875 1474204 1474003 1479031 1505577 1474478 1487200 1484401 1456801 1458291 1443961 1469156 1472462 1465585 1463621 1474641 1485242 1465189 1463767 1459387 1459694 1470934 1479211 1480178 1452541 1447641 1424488 1462839 1469240 1474861 1453085 1429123 1455252 1446160 1454091 1452331 1461885 1452354 1462345 1474890 1491312 1485002 1453679 1448790 1470134 1443037 1479137 1466031 1460493 1448612 1421918 1445851 1472955 1467864 1457713 1440651
sum_num_searches_total=212290588
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1641592 1632453 1662793 1667766 1649559 1636095 1638393 1635476 1663220 1636370 1638863 1560384 1651486 1636963 1651120 1664156 1652610 1641649 1643185 1635581 1651849 1672477 1638251 1647662 1648045 1646182 1661226 1625590 1657870 1642613 1641749 1607186 1655112 1645837 1640306 1633198 1658630 1642321 1664719 1668298 1650473 1643365 1636581 1620858 1662850 1577722 1634353 1639963 1636727 1647847 1655414 1667408 1652492 1647893 1618771 1650969 1677041 1659766 1633328 1644719 1638657 1659486 1667406 1660873 1666905 1636029 1641936 1646724 1667676 1656817 1643304 1639852 1648894 1642364 1655236 1642963 1651509 1628843 1632558 1644440 1622494 1661450 1656277 1649550 1647669 1652495 1661157 1639092 1654480 1637826 1637786 1643838 1671948 1638702 1652589 1649144 1619010 1620434 1603550 1632772 1636046 1628172 1626326 1638507 1650144 1628599 1626412 1621919 1621978 1633925 1643820 1645665 1614076 1608458 1582674 1625124 1633186 1638946 1613905 1588686 1616734 1606321 1615771 1613808 1624529 1614036 1625078 1639177 1656987 1649741 1615690 1609018 1632685 1603087 1643780 1628850 1622426 1610210 1580216 1606070 1636902 1631199 1619170 1601395
average_num_operations_total=1638066
stdev_num_operations_total=1685
sum_num_operations_total=235881558
min_num_operations_total=1560384
max_num_operations_total=1677041
first_time_thread_terminate_by_thread=10000163 10000114 10000117 10000119 10000118 10000118 10000119 10000118 10000120 10000118 10000117 10000116 10000115 10000119 10000119 10000116 10000116 10000118 10000114 10000118 10000114 10000116 10000116 10000115 10000115 10000114 10000117 10000119 10000118 10000118 10000119 10000118 10000120 10000118 10000117 10000116 10000115 10000119 10000119 10000115 10000118 10000118 10000114 10000118 10000114 10000116 10000114 10000115 10000114 10000115 10000118 10000120 10000114 10000120 10000114 10000119 10000119 10000114 10000115 10000114 10000115 10000117 10000120 10000118 10000114 10000117 10000119 10000118 10000114 10000116 10000118 10000118 10000116 10000115 10000118 10000119 10000119 10000118 10000114 10000119 10000113 10000114 10000114 10000115 10000115 10000117 10000120 10000118 10000114 10000117 10000119 10000118 10000119 10000116 10000118 10000114 10000118 10000119 10000118 10000117 10000118 10000116 10000117 10000116 10000119 10000117 10000117 10000116 10000119 10000120 10000116 10000117 10000117 10000116 10000116 10000115 10000117 10000116 10000116 10000116 10000118 10000119 10000119 10000116 10000117 10000116 10000116 10000116 10000119 10000117 10000117 10000116 10000119 10000116 10000116 10000116 10000117 10000116 10000116 10000119 10000119 10000116 10000116 10000117
min_time_thread_terminate_total=10000113
max_time_thread_terminate_total=10000163
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 4 4 5 4 4 4 4 5 4 5 4 4 4 4 5 4 3 4 4 4 4 4 4 4 4 4 5 4 4 4 4 5 4 5 4 4 4 4 5 4 5 4 4 5 4 5 5 4 4 4 5 5 5 4 5 5 5 4 5 5 4 4 5 4 5 4 4 5 4 5 5 4 4 4 5 5 5 4 5 5 5 4 5 5 4 4 5 4 5 5 5 4 5 5 5 4 5 5 4 4 4 4 4 4 5 4 4 4 4 4 5 5 5 5 5 4 5 5 5 4 5 5 4 4 4 4 4 4 5 4 4 4 4 4 5 5
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=99990572226088
threads_final_size=9999504
final_keysum=99990572226088
final_size=9999504
validate_result=success
Validation OK.
Structural validation OK.

total_find=212290588
total_rq=0
total_inserts=11795098
total_deletes=11795872
total_updates=23590970
total_queries=212290588
total_ops=235881558
find_throughput=21229058
rq_throughput=0
update_throughput=2359097
query_throughput=21229058
total_throughput=23588155


total find                    : 212290588
total rq                      : 0
total inserts                 : 11795098
total deletes                 : 11795872
total updates                 : 23590970
total queries                 : 212290588
total ops                     : 235881558
find throughput               : 21229058
rq throughput                 : 0
update throughput             : 2359097
query throughput              : 21229058
total throughput              : 23588155

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=22.3918
PAPI_L3_TCM=7.79138
PAPI_TOT_CYC=16775.8
PAPI_TOT_INS=17901.1
garbage=1060913728298703
total_execution_walltime=21.677s
REALTIME_MAIN_END_PERF_FORMAT=6993953.190476499
HyTM1 system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 255355842
[slow htm]
[fallback]
total fallback commit         : 286128
seconds global lock is held   : 17.2413
[time_cmd_output] time_elapsed_sec=21.88, faults_major=0, faults_minor=474026, mem_maxresident_kb=1168972, user_cputime=3056.28, sys_cputime=18.15, percent_cpu=14048%
timeout=false
algorithm=brown_sigouin_int_avl_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=2