__step=000018
__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.norec -nwork 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000018.txt
__path_data=data/data000018.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219423.934054763
binary=./brown_sigouin_int_avl_tm_auto.debra.norec
parsed custom binding: 0-63,128-191,64-127,192-255
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=64
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=64
WORK_THREADS=64
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.017s
prefilling_size=585815 prefilling_elapsed_millis=1s
prefilling_size=644728 prefilling_elapsed_millis=1.101s
prefilling_size=703145 prefilling_elapsed_millis=1.201s
prefilling_size=761256 prefilling_elapsed_millis=1.301s
prefilling_size=818303 prefilling_elapsed_millis=1.401s
prefilling_size=875571 prefilling_elapsed_millis=1.501s
prefilling_size=932609 prefilling_elapsed_millis=1.601s
prefilling_size=988763 prefilling_elapsed_millis=1.701s
prefilling_size=1045047 prefilling_elapsed_millis=1.801s
prefilling_size=1101009 prefilling_elapsed_millis=1.902s
prefilling_size=1157449 prefilling_elapsed_millis=2.002s
prefilling_size=1213424 prefilling_elapsed_millis=2.102s
prefilling_size=1269244 prefilling_elapsed_millis=2.202s
prefilling_size=1324692 prefilling_elapsed_millis=2.302s
prefilling_size=1379666 prefilling_elapsed_millis=2.402s
prefilling_size=1434444 prefilling_elapsed_millis=2.502s
prefilling_size=1488219 prefilling_elapsed_millis=2.602s
prefilling_size=1542401 prefilling_elapsed_millis=2.703s
prefilling_size=1596781 prefilling_elapsed_millis=2.803s
prefilling_size=1650989 prefilling_elapsed_millis=2.903s
prefilling_size=1704442 prefilling_elapsed_millis=3.003s
prefilling_size=1757864 prefilling_elapsed_millis=3.104s
prefilling_size=1811032 prefilling_elapsed_millis=3.204s
prefilling_size=1863605 prefilling_elapsed_millis=3.304s
prefilling_size=1916414 prefilling_elapsed_millis=3.404s
prefilling_size=1968926 prefilling_elapsed_millis=3.504s
prefilling_size=2021295 prefilling_elapsed_millis=3.604s
prefilling_size=2073533 prefilling_elapsed_millis=3.704s
prefilling_size=2126293 prefilling_elapsed_millis=3.805s
prefilling_size=2178030 prefilling_elapsed_millis=3.905s
prefilling_size=2230192 prefilling_elapsed_millis=4.005s
prefilling_size=2281506 prefilling_elapsed_millis=4.105s
prefilling_size=2333017 prefilling_elapsed_millis=4.205s
prefilling_size=2384434 prefilling_elapsed_millis=4.305s
prefilling_size=2434962 prefilling_elapsed_millis=4.405s
prefilling_size=2486106 prefilling_elapsed_millis=4.505s
prefilling_size=2536778 prefilling_elapsed_millis=4.606s
prefilling_size=2586733 prefilling_elapsed_millis=4.706s
prefilling_size=2637122 prefilling_elapsed_millis=4.806s
prefilling_size=2687075 prefilling_elapsed_millis=4.906s
prefilling_size=2736870 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7263085 keys with 64 threads...
tid=0 thread_prefill_amount=53125 percent_done_estimate=46.8 elapsed_s=5 est_remaining_s=13 / 19
tid=0 thread_prefill_amount=56250 percent_done_estimate=49.6 elapsed_s=5 est_remaining_s=13 / 18
tid=0 thread_prefill_amount=59375 percent_done_estimate=52.3 elapsed_s=6 est_remaining_s=12 / 17
tid=0 thread_prefill_amount=62500 percent_done_estimate=55.1 elapsed_s=6 est_remaining_s=11 / 17
tid=0 thread_prefill_amount=65625 percent_done_estimate=57.8 elapsed_s=6 est_remaining_s=10 / 16
tid=0 thread_prefill_amount=68750 percent_done_estimate=60.6 elapsed_s=6 est_remaining_s=9 / 16
tid=0 thread_prefill_amount=71875 percent_done_estimate=63.3 elapsed_s=7 est_remaining_s=9 / 15
tid=0 thread_prefill_amount=75000 percent_done_estimate=66.1 elapsed_s=7 est_remaining_s=8 / 15
tid=0 thread_prefill_amount=78125 percent_done_estimate=68.8 elapsed_s=7 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=81250 percent_done_estimate=71.6 elapsed_s=7 est_remaining_s=7 / 14
tid=0 thread_prefill_amount=84375 percent_done_estimate=74.3 elapsed_s=8 est_remaining_s=6 / 14
tid=0 thread_prefill_amount=87500 percent_done_estimate=77.1 elapsed_s=8 est_remaining_s=5 / 13
tid=0 thread_prefill_amount=90625 percent_done_estimate=79.9 elapsed_s=8 est_remaining_s=5 / 13
tid=0 thread_prefill_amount=93750 percent_done_estimate=82.6 elapsed_s=8 est_remaining_s=4 / 12
tid=0 thread_prefill_amount=96875 percent_done_estimate=85.4 elapsed_s=9 est_remaining_s=3 / 12
tid=0 thread_prefill_amount=100000 percent_done_estimate=88.1 elapsed_s=9 est_remaining_s=3 / 12
tid=0 thread_prefill_amount=103125 percent_done_estimate=90.9 elapsed_s=9 est_remaining_s=2 / 11
tid=0 thread_prefill_amount=106250 percent_done_estimate=93.6 elapsed_s=9 est_remaining_s=1 / 11
tid=0 thread_prefill_amount=109375 percent_done_estimate=96.4 elapsed_s=10 est_remaining_s=1 / 10
tid=0 thread_prefill_amount=112500 percent_done_estimate=99.1 elapsed_s=10 est_remaining_s=0 / 10
tid=0 thread_prefill_amount=115625 percent_done_estimate=101.9 elapsed_s=10 est_remaining_s=-0 / 10
tid=0 thread_prefill_amount=118750 percent_done_estimate=104.6 elapsed_s=10 est_remaining_s=-1 / 9
tid=0 thread_prefill_amount=121875 percent_done_estimate=107.4 elapsed_s=10 est_remaining_s=-2 / 9
tid=0 thread_prefill_amount=125000 percent_done_estimate=110.1 elapsed_s=11 est_remaining_s=-2 / 8
tid=0 thread_prefill_amount=128125 percent_done_estimate=112.9 elapsed_s=11 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=131250 percent_done_estimate=115.7 elapsed_s=11 est_remaining_s=-3 / 8
tid=0 thread_prefill_amount=134375 percent_done_estimate=118.4 elapsed_s=11 est_remaining_s=-4 / 7
tid=0 thread_prefill_amount=137500 percent_done_estimate=121.2 elapsed_s=12 est_remaining_s=-5 / 7
tid=0 thread_prefill_amount=140625 percent_done_estimate=123.9 elapsed_s=12 est_remaining_s=-5 / 7
tid=0 thread_prefill_amount=143750 percent_done_estimate=126.7 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=146875 percent_done_estimate=129.4 elapsed_s=12 est_remaining_s=-6 / 6
tid=0 thread_prefill_amount=150000 percent_done_estimate=132.2 elapsed_s=12 est_remaining_s=-7 / 6
tid=0 thread_prefill_amount=153125 percent_done_estimate=134.9 elapsed_s=13 est_remaining_s=-7 / 5
tid=0 thread_prefill_amount=156250 percent_done_estimate=137.7 elapsed_s=13 est_remaining_s=-8 / 5
tid=0 thread_prefill_amount=159375 percent_done_estimate=140.4 elapsed_s=13 est_remaining_s=-8 / 5
tid=0 thread_prefill_amount=162500 percent_done_estimate=143.2 elapsed_s=13 est_remaining_s=-9 / 4
timing_elapsed 9.1743s
prefilling_size=10000000 prefilling_elapsed_millis=9.633s
finished prefilling to size 10000000 for expected size 10000000 keysum=99987733555145, performing 17321244 updates; total_prefilling_elapsed_ms=9633 ms)
pref_size=10000000
pref_millis=9633
prefill_elapsed_ms=9633
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=219438.734879072
REALTIME_END_PERF_FORMAT=219448.734949156
###############################################################################
################################## 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 (64 threads)...
partitioned into 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.028s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131063 261804 515830 944476 1394280 1380523 769644 197609 17206 260 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 8 302 6896 74171 403248 1092901 1470079 939527 250926 21606 316
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262106 522726 1018647 1797528 2473424 2239723 1137136 268132 21866 316
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262106 522726 1018647 1797528 2473424 2239723 1137136 268132 21866 316
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99998 1.99979 1.99548 1.96191 1.83744 1.60035 1.34737 1.16408 1.06202 1.01738 1.00256 1
tree_stats_height=28
tree_stats_numInternals=5743766
tree_stats_numLeaves=4259980
tree_stats_numNodes=10003746
tree_stats_numKeys=10003746
tree_stats_avgDegreeInternal=1.74167
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42584
tree_stats_avgKeyDepth=21.7384
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=233086
first_thread_announced_epoch_by_thread=8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574 8574
sum_commit_tx_reader_total=125662482
sum_commit_tx_reader_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1869332 1864945 1874230 1912693 1862917 1853920 2152383 1918794 1933752 1856756 1917377 1900296 1908312 1919236 1911295 1901875 1912675 1924866 1872028 1912690 1909137 1924844 1912569 1909908 1911405 1915647 1902757 1930027 1922369 1918723 1921859 1908640 1923869 1900217 1912561 1915754 1914603 1903218 1896562 1869767 2160608 2153585 2152942 1859065 2163090 1920602 1865580 2153841 2157464 2154094 1878422 2150745 2162164 2167952 2165973 1865079 2155305 2164023 1873548 1868347 1881454 1870893 2152939 2159959
sum_commit_tx_writer_total=6614472
sum_commit_tx_writer_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 98500 98558 97818 100726 98207 97712 113772 100377 101428 97848 100065 100084 100432 101310 100903 100281 100742 101687 98277 101003 100786 101130 101076 100916 100444 100792 100223 101588 101314 100463 101375 100887 101794 100378 100168 101475 100986 100161 99869 98407 113903 113041 113221 97682 113393 100665 98650 113569 113632 113233 99027 112975 113786 113836 114147 98336 113478 113448 98497 98535 98836 98410 112932 113278
sum_abort_tx_total=362
sum_abort_tx_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 5 8 2 3 0 4 5 9 7 6 5 7 4 9 11 5 4 6 7 9 7 9 5 6 7 4 6 5 9 3 5 3 6 2 3 4 5 6 4 6 5 4 7 7 5 7 10 1 7 6 6 10 8 8 8 5 4 3 4 6 6 6 4 4
max_ht_capacity_by_thread=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048 2048
linear_histogram_of_none_tx_rdset_size_full_data=28.1:31 38.2:842 48.3:30737 58.4:30464 68.5:1624 78.6:235 88.7:48 98.8:11 108.9:7
[ 18.00, 28.10]: 31
( 28.10, 38.20]: 842
( 38.20, 48.30]: 30737
( 48.30, 58.40]: 30464
( 58.40, 68.50]: 1624
( 68.50, 78.60]: 235
( 78.60, 88.70]: 48
( 88.70, 98.80]: 11
( 98.80, 108.90]: 7
linear_histogram_of_none_tx_wrset_size_full_data=3.8:19632 6.6:4530 9.4:14358 12.2:7394 15:10868 17.8:5125 20.6:693 23.4:1263 26.2:123 29:12
[ 1.00, 3.80]: 19632
( 3.80, 6.60]: 4530
( 6.60, 9.40]: 14358
( 9.40, 12.20]: 7394
( 12.20, 15.00]: 10868
( 15.00, 17.80]: 5125
( 17.80, 20.60]: 693
( 20.60, 23.40]: 1263
( 23.40, 26.20]: 123
( 26.20, 29.00]: 12
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=112913 114199 113687 112999 113399 113524 113458 113272 113405 113948 112882 113180 113626 113957 113289 113416 101291 100682 100850 100594 99570 100699 101916 100660 100163 100657 100813 100860 100954 100620 100592 99930 100574 100632 100870 100047 101017 101116 101066 100617 100923 101634 100858 101973 101386 101538 100896 100386 98038 97759 98366 98203 97732 98551 98555 98422 98624 97727 97905 98158 98393 98738 98641 98375
sum_num_inserts_total=6613725
sum_num_deletes_by_thread=113488 113823 114147 113325 113851 113524 113597 112817 113911 113734 113334 113118 113987 113111 113402 112795 100508 101248 100681 99705 99809 99678 101031 100689 100054 101082 99669 100451 100451 101071 101310 101082 100285 100732 100524 100418 102474 101832 101255 100378 100521 101883 101186 101377 101137 100905 100740 100245 97622 97717 98448 98447 98412 98938 98432 98496 98163 97677 98680 98562 98098 98457 99203 98582
sum_num_deletes_total=6614309
sum_num_searches_by_thread=2046836 2052098 2053954 2039547 2049233 2050423 2039100 2042694 2048634 2043414 2041194 2037422 2046898 2039095 2040636 2040415 1811846 1808894 1810318 1802681 1797052 1800003 1823606 1812070 1812512 1813850 1802897 1808216 1808518 1817495 1811791 1807732 1816583 1812053 1810804 1801691 1822172 1832232 1823653 1818176 1819823 1828098 1821190 1820333 1814706 1818103 1814803 1799964 1758944 1756156 1773491 1766853 1775904 1779960 1770845 1767312 1764337 1761343 1766830 1771454 1770391 1774850 1782446 1772346
sum_num_searches_total=119048920
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2273237 2280120 2281788 2265871 2276483 2277471 2266155 2268783 2275950 2271096 2267410 2263720 2274511 2266163 2267327 2266626 2013645 2010824 2011849 2002980 1996431 2000380 2026553 2013419 2012729 2015589 2003379 2009527 2009923 2019186 2013693 2008744 2017442 2013417 2012198 2002156 2025663 2035180 2025974 2019171 2021267 2031615 2023234 2023683 2017229 2020546 2016439 2000595 1954604 1951632 1970305 1963503 1972048 1977449 1967832 1964230 1961124 1956747 1963415 1968174 1966882 1972045 1980290 1969303
average_num_operations_total=2066827
stdev_num_operations_total=15243
sum_num_operations_total=132276954
min_num_operations_total=1951632
max_num_operations_total=2281788
first_time_thread_terminate_by_thread=10000110 10000069 10000069 10000069 10000070 10000070 10000070 10000070 10000072 10000072 10000072 10000072 10000073 10000069 10000069 10000069 10000071 10000068 10000071 10000068 10000070 10000070 10000070 10000071 10000072 10000073 10000073 10000073 10000069 10000069 10000068 10000068 10000071 10000073 10000073 10000071 10000070 10000070 10000069 10000074 10000072 10000071 10000071 10000071 10000071 10000074 10000074 10000071 10000070 10000070 10000072 10000070 10000074 10000070 10000070 10000074 10000072 10000072 10000072 10000072 10000073 10000073 10000073 10000072
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000110
first_time_thread_start_by_thread=7 3 3 3 5 5 5 5 4 4 4 4 5 5 5 5 4 4 4 4 4 4 4 4 4 4 4 4 6 6 6 6 5 5 5 5 7 7 7 7 6 6 6 6 7 7 7 7 6 6 6 6 5 5 5 4 6 6 6 6 7 7 7 7
min_time_thread_start_total=3
max_time_thread_start_total=7
sum_duration_all_ops_total=
threads_final_keysum=100031883500558
threads_final_size=10003746
final_keysum=100031883500558
final_size=10003746
validate_result=success
Validation OK.
Structural validation OK.
total_find=119048920
total_rq=0
total_inserts=6613725
total_deletes=6614309
total_updates=13228034
total_queries=119048920
total_ops=132276954
find_throughput=11904892
rq_throughput=0
update_throughput=1322803
query_throughput=11904892
total_throughput=13227695
total find : 119048920
total rq : 0
total inserts : 6613725
total deletes : 6614309
total updates : 13228034
total queries : 119048920
total ops : 132276954
find throughput : 11904892
rq throughput : 0
update throughput : 1322803
query throughput : 11904892
total throughput : 13227695
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=45887179047247
total_execution_walltime=25.075s
REALTIME_MAIN_END_PERF_FORMAT=219449.10033720
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=25.22, faults_major=0, faults_minor=236402, mem_maxresident_kb=943552, user_cputime=1535.30, sys_cputime=16.46, percent_cpu=6151%
timeout=false
algorithm=brown_sigouin_int_avl_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2