__step=000017
__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=data000017.txt
__path_data=data/data000017.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=219398.511281415
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.021s
prefilling_size=584279 prefilling_elapsed_millis=1s
prefilling_size=641924 prefilling_elapsed_millis=1.101s
prefilling_size=699266 prefilling_elapsed_millis=1.201s
prefilling_size=756485 prefilling_elapsed_millis=1.301s
prefilling_size=813319 prefilling_elapsed_millis=1.401s
prefilling_size=870078 prefilling_elapsed_millis=1.501s
prefilling_size=926750 prefilling_elapsed_millis=1.601s
prefilling_size=983109 prefilling_elapsed_millis=1.701s
prefilling_size=1039080 prefilling_elapsed_millis=1.801s
prefilling_size=1095313 prefilling_elapsed_millis=1.901s
prefilling_size=1150974 prefilling_elapsed_millis=2.002s
prefilling_size=1206356 prefilling_elapsed_millis=2.102s
prefilling_size=1260988 prefilling_elapsed_millis=2.202s
prefilling_size=1315986 prefilling_elapsed_millis=2.302s
prefilling_size=1370080 prefilling_elapsed_millis=2.402s
prefilling_size=1424150 prefilling_elapsed_millis=2.502s
prefilling_size=1478358 prefilling_elapsed_millis=2.602s
prefilling_size=1532427 prefilling_elapsed_millis=2.702s
prefilling_size=1585919 prefilling_elapsed_millis=2.803s
prefilling_size=1639699 prefilling_elapsed_millis=2.903s
prefilling_size=1693341 prefilling_elapsed_millis=3.003s
prefilling_size=1747061 prefilling_elapsed_millis=3.103s
prefilling_size=1800371 prefilling_elapsed_millis=3.204s
prefilling_size=1853213 prefilling_elapsed_millis=3.304s
prefilling_size=1905879 prefilling_elapsed_millis=3.404s
prefilling_size=1958231 prefilling_elapsed_millis=3.504s
prefilling_size=2010867 prefilling_elapsed_millis=3.604s
prefilling_size=2063261 prefilling_elapsed_millis=3.704s
prefilling_size=2115502 prefilling_elapsed_millis=3.804s
prefilling_size=2167521 prefilling_elapsed_millis=3.904s
prefilling_size=2219207 prefilling_elapsed_millis=4.005s
prefilling_size=2271045 prefilling_elapsed_millis=4.105s
prefilling_size=2322351 prefilling_elapsed_millis=4.205s
prefilling_size=2373347 prefilling_elapsed_millis=4.305s
prefilling_size=2424902 prefilling_elapsed_millis=4.405s
prefilling_size=2476110 prefilling_elapsed_millis=4.505s
prefilling_size=2526563 prefilling_elapsed_millis=4.605s
prefilling_size=2577434 prefilling_elapsed_millis=4.705s
prefilling_size=2627736 prefilling_elapsed_millis=4.806s
prefilling_size=2678111 prefilling_elapsed_millis=4.906s
prefilling_size=2727917 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 7272021 keys with 64 threads...
tid=0 thread_prefill_amount=40625 percent_done_estimate=35.8 elapsed_s=5 est_remaining_s=21 / 27
tid=0 thread_prefill_amount=43750 percent_done_estimate=38.5 elapsed_s=6 est_remaining_s=20 / 26
tid=0 thread_prefill_amount=46875 percent_done_estimate=41.3 elapsed_s=6 est_remaining_s=19 / 25
tid=0 thread_prefill_amount=50000 percent_done_estimate=44.0 elapsed_s=6 est_remaining_s=18 / 24
tid=0 thread_prefill_amount=53125 percent_done_estimate=46.8 elapsed_s=7 est_remaining_s=17 / 23
tid=0 thread_prefill_amount=56250 percent_done_estimate=49.5 elapsed_s=7 est_remaining_s=16 / 23
tid=0 thread_prefill_amount=59375 percent_done_estimate=52.3 elapsed_s=7 est_remaining_s=15 / 22
tid=0 thread_prefill_amount=62500 percent_done_estimate=55.0 elapsed_s=8 est_remaining_s=14 / 21
tid=0 thread_prefill_amount=65625 percent_done_estimate=57.8 elapsed_s=8 est_remaining_s=13 / 21
tid=0 thread_prefill_amount=68750 percent_done_estimate=60.5 elapsed_s=8 est_remaining_s=12 / 20
tid=0 thread_prefill_amount=71875 percent_done_estimate=63.3 elapsed_s=8 est_remaining_s=11 / 20
tid=0 thread_prefill_amount=75000 percent_done_estimate=66.0 elapsed_s=9 est_remaining_s=10 / 19
tid=0 thread_prefill_amount=78125 percent_done_estimate=68.8 elapsed_s=9 est_remaining_s=9 / 18
tid=0 thread_prefill_amount=81250 percent_done_estimate=71.5 elapsed_s=9 est_remaining_s=8 / 18
tid=0 thread_prefill_amount=84375 percent_done_estimate=74.3 elapsed_s=10 est_remaining_s=8 / 17
tid=0 thread_prefill_amount=87500 percent_done_estimate=77.0 elapsed_s=10 est_remaining_s=7 / 17
tid=0 thread_prefill_amount=90625 percent_done_estimate=79.8 elapsed_s=10 est_remaining_s=6 / 16
tid=0 thread_prefill_amount=93750 percent_done_estimate=82.5 elapsed_s=11 est_remaining_s=5 / 16
tid=0 thread_prefill_amount=96875 percent_done_estimate=85.3 elapsed_s=11 est_remaining_s=4 / 15
tid=0 thread_prefill_amount=100000 percent_done_estimate=88.0 elapsed_s=11 est_remaining_s=3 / 14
tid=0 thread_prefill_amount=103125 percent_done_estimate=90.8 elapsed_s=11 est_remaining_s=3 / 14
tid=0 thread_prefill_amount=106250 percent_done_estimate=93.5 elapsed_s=12 est_remaining_s=2 / 13
tid=0 thread_prefill_amount=109375 percent_done_estimate=96.3 elapsed_s=12 est_remaining_s=1 / 13
tid=0 thread_prefill_amount=112500 percent_done_estimate=99.0 elapsed_s=12 est_remaining_s=0 / 12
tid=0 thread_prefill_amount=115625 percent_done_estimate=101.8 elapsed_s=12 est_remaining_s=-0 / 12
tid=0 thread_prefill_amount=118750 percent_done_estimate=104.5 elapsed_s=13 est_remaining_s=-1 / 11
tid=0 thread_prefill_amount=121875 percent_done_estimate=107.3 elapsed_s=13 est_remaining_s=-2 / 11
tid=0 thread_prefill_amount=125000 percent_done_estimate=110.0 elapsed_s=13 est_remaining_s=-3 / 10
tid=0 thread_prefill_amount=128125 percent_done_estimate=112.8 elapsed_s=13 est_remaining_s=-3 / 10
tid=0 thread_prefill_amount=131250 percent_done_estimate=115.5 elapsed_s=14 est_remaining_s=-4 / 9
tid=0 thread_prefill_amount=134375 percent_done_estimate=118.3 elapsed_s=14 est_remaining_s=-5 / 9
tid=0 thread_prefill_amount=137500 percent_done_estimate=121.0 elapsed_s=14 est_remaining_s=-5 / 8
tid=0 thread_prefill_amount=140625 percent_done_estimate=123.8 elapsed_s=14 est_remaining_s=-6 / 8
tid=0 thread_prefill_amount=143750 percent_done_estimate=126.5 elapsed_s=14 est_remaining_s=-7 / 7
tid=0 thread_prefill_amount=146875 percent_done_estimate=129.3 elapsed_s=14 est_remaining_s=-7 / 7
tid=0 thread_prefill_amount=150000 percent_done_estimate=132.0 elapsed_s=14 est_remaining_s=-8 / 7
timing_elapsed 9.29024s
prefilling_size=10000000 prefilling_elapsed_millis=9.755s
finished prefilling to size 10000000 for expected size 10000000 keysum=100001038413042, performing 17302923 updates; total_prefilling_elapsed_ms=9755 ms)
pref_size=10000000
pref_millis=9755
prefill_elapsed_ms=9755
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=219413.442874650
REALTIME_END_PERF_FORMAT=219423.442943742
###############################################################################
################################## 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.026s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131071 262056 520763 979631 1481702 1412007 678816 134257 7630 39 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 76 3020 54097 394273 1208507 1570813 844004 170693 9528 49
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523783 1033728 1875975 2620514 2249629 978261 178323 9567 49
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65536 131072 262132 523783 1033728 1875975 2620514 2249629 978261 178323 9567 49
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99992 1.99845 1.97935 1.8671 1.60705 1.31964 1.13311 1.04505 1.01086 1.00105 1
tree_stats_height=28
tree_stats_numInternals=5739043
tree_stats_numLeaves=4255061
tree_stats_numNodes=9994104
tree_stats_numKeys=9994104
tree_stats_avgDegreeInternal=1.74142
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42576
tree_stats_avgKeyDepth=21.6642
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=233982
first_thread_announced_epoch_by_thread=8642 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644 8644
sum_commit_tx_reader_total=126161912
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 1932505 1916634 1906861 1937013 1918765 1910165 1930167 1924965 1875534 1919694 1879971 1880242 1931612 1874868 2163650 1867397 1871318 1864110 1871328 1882632 1917244 2146104 2145207 1929195 1922540 2144284 1917091 1912698 2171386 1863766 2148906 2174773 1880433 1861123 1861643 1868757 1918447 1922306 1913549 2171738 1936523 1931112 1875710 1934833 2163254 1916936 1920644 1913771 1939160 1936225 1936462 1883121 1938678 1934830 1923161 1936508 1938446 2174396 2153261 2172980 2161679 2151722 2166708 2171171
sum_commit_tx_writer_total=6641326
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 101620 100885 99913 102203 100794 100899 101773 101364 98579 101322 99199 99269 101472 98564 114337 98816 98508 98178 98164 99092 100960 113311 113168 101352 100940 113034 101453 100531 114048 98375 112825 113645 99538 98199 98092 98408 100934 101113 100442 114792 101926 101566 98893 101447 113515 100317 101132 100585 101644 101973 101578 99088 102460 102171 101530 101615 102073 114518 113162 114245 113860 112994 114597 114326
sum_abort_tx_total=321
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 6 7 8 3 6 5 7 8 4 2 8 4 5 6 4 5 3 3 7 4 5 4 6 5 6 7 3 9 4 6 6 2 5 4 2 4 4 3 7 3 2 4 4 4 9 6 3 6 7 4 6 8 3 5 6 1 4 3 5 6 8 6 6
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=25.1:6 34.2:201 43.3:3102 52.4:55730 61.5:3921 70.6:841 79.7:145 88.8:38 97.9:11 107:4
[ 16.00, 25.10]: 6
( 25.10, 34.20]: 201
( 34.20, 43.30]: 3102
( 43.30, 52.40]: 55730
( 52.40, 61.50]: 3921
( 61.50, 70.60]: 841
( 70.60, 79.70]: 145
( 79.70, 88.80]: 38
( 88.80, 97.90]: 11
( 97.90, 107.00]: 4
linear_histogram_of_none_tx_wrset_size_full_data=4.3:22194 7.6:6421 10.9:12754 14.2:15345 17.5:5187 20.8:688 24.1:1350 27.4:55 30.7:4 34:1
[ 1.00, 4.30]: 22194
( 4.30, 7.60]: 6421
( 7.60, 10.90]: 12754
( 10.90, 14.20]: 15345
( 14.20, 17.50]: 5187
( 17.50, 20.80]: 688
( 20.80, 24.10]: 1350
( 24.10, 27.40]: 55
( 27.40, 30.70]: 4
( 30.70, 34.00]: 1
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=101941 101964 102913 100973 101563 101872 102449 101521 101513 101394 100811 100596 100661 101237 101072 99827 99239 99040 99131 98554 98770 99143 98552 98233 98496 98527 98901 98221 97691 98334 98864 98680 113691 114443 114286 114110 114504 114481 113723 113338 112809 113437 112592 113352 113225 114345 114614 114002 101267 101150 101522 100898 100267 101078 100729 100259 100814 101033 101351 101396 102395 101924 101851 101760
sum_num_inserts_total=6641329
sum_num_deletes_by_thread=101755 101571 102101 101568 101957 102302 101921 101551 101913 101953 101817 101244 100723 100855 100066 100403 99038 99026 99264 97668 98718 99181 99123 98238 98094 98461 98407 98441 98381 98470 99460 98883 114485 114117 114544 114184 114201 114273 114284 113335 113287 112926 113218 112838 113554 113853 114429 113329 101039 100989 100642 100791 101408 101406 100457 100792 100769 101479 101085 102094 102029 102092 101958 101656
sum_num_deletes_total=6644096
sum_num_searches_by_thread=1834427 1836984 1836124 1828006 1829158 1835042 1832631 1828868 1830699 1829737 1822063 1815679 1809680 1817467 1813218 1806544 1781234 1783658 1780775 1763513 1777115 1781647 1775757 1773021 1765551 1772838 1768905 1762660 1766216 1770361 1783885 1776550 2059049 2056937 2060084 2049693 2056729 2052551 2047532 2039750 2038620 2033052 2031508 2032185 2034952 2060220 2057487 2049438 1821113 1824190 1821316 1817692 1812316 1816060 1816067 1812178 1816621 1818504 1819340 1832790 1834025 1836788 1834389 1834624
sum_num_searches_total=119517813
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2038123 2040519 2041138 2030547 2032678 2039216 2037001 2031940 2034125 2033084 2024691 2017519 2011064 2019559 2014356 2006774 1979511 1981724 1979170 1959735 1974603 1979971 1973432 1969492 1962141 1969826 1966213 1959322 1962288 1967165 1982209 1974113 2287225 2285497 2288914 2277987 2285434 2281305 2275539 2266423 2264716 2259415 2257318 2258375 2261731 2288418 2286530 2276769 2023419 2026329 2023480 2019381 2013991 2018544 2017253 2013229 2018204 2021016 2021776 2036280 2038449 2040804 2038198 2038040
average_num_operations_total=2075050
stdev_num_operations_total=15000
sum_num_operations_total=132803238
min_num_operations_total=1959322
max_num_operations_total=2288914
first_time_thread_terminate_by_thread=10000107 10000071 10000071 10000071 10000074 10000071 10000074 10000071 10000069 10000070 10000070 10000073 10000070 10000070 10000070 10000071 10000072 10000072 10000072 10000072 10000070 10000073 10000073 10000070 10000070 10000069 10000069 10000069 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000074 10000070 10000070 10000071 10000070 10000071 10000072 10000071 10000071 10000072 10000072 10000072 10000072 10000074 10000073 10000073 10000074 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000073 10000069 10000069 10000068 10000069
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000107
first_time_thread_start_by_thread=7 3 3 3 4 4 4 4 5 5 5 5 7 7 7 7 6 6 6 6 7 7 7 7 6 6 6 6 7 7 7 7 5 5 5 5 4 4 4 4 6 6 6 6 5 5 5 6 5 5 5 5 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=7
sum_duration_all_ops_total=
threads_final_keysum=99923621055112
threads_final_size=9994104
final_keysum=99923621055112
final_size=9994104
validate_result=success
Validation OK.
Structural validation OK.
total_find=119517813
total_rq=0
total_inserts=6641329
total_deletes=6644096
total_updates=13285425
total_queries=119517813
total_ops=132803238
find_throughput=11951781
rq_throughput=0
update_throughput=1328542
query_throughput=11951781
total_throughput=13280323
total find : 119517813
total rq : 0
total inserts : 6641329
total deletes : 6644096
total updates : 13285425
total queries : 119517813
total ops : 132803238
find throughput : 11951781
rq throughput : 0
update throughput : 1328542
query throughput : 11951781
total throughput : 13280323
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=167420143365433
total_execution_walltime=25.205s
REALTIME_MAIN_END_PERF_FORMAT=219423.717704909
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=25.35, faults_major=0, faults_minor=236499, mem_maxresident_kb=944092, user_cputime=1540.47, sys_cputime=15.93, percent_cpu=6138%
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=1