__step=000001
__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_int_bst_tm_auto.debra.norec -nwork 48 -nprefill 48 -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=data000001.txt
__path_data=data/data000001.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991103.672882487
binary=./brown_int_bst_tm_auto.debra.norec
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=brown_int_bst_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=48
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=48
WORK_THREADS=48
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=32
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
noREC system ready
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.008s
prefilling_size=896526 prefilling_elapsed_millis=1.001s
prefilling_size=981905 prefilling_elapsed_millis=1.101s
prefilling_size=1066324 prefilling_elapsed_millis=1.201s
prefilling_size=1150239 prefilling_elapsed_millis=1.301s
prefilling_size=1233213 prefilling_elapsed_millis=1.401s
prefilling_size=1315278 prefilling_elapsed_millis=1.502s
prefilling_size=1396667 prefilling_elapsed_millis=1.602s
prefilling_size=1477360 prefilling_elapsed_millis=1.702s
prefilling_size=1557194 prefilling_elapsed_millis=1.802s
prefilling_size=1636205 prefilling_elapsed_millis=1.902s
prefilling_size=1714774 prefilling_elapsed_millis=2.002s
prefilling_size=1792885 prefilling_elapsed_millis=2.103s
prefilling_size=1870175 prefilling_elapsed_millis=2.203s
prefilling_size=1946507 prefilling_elapsed_millis=2.303s
prefilling_size=2022009 prefilling_elapsed_millis=2.403s
prefilling_size=2097133 prefilling_elapsed_millis=2.503s
prefilling_size=2171649 prefilling_elapsed_millis=2.604s
prefilling_size=2245115 prefilling_elapsed_millis=2.704s
prefilling_size=2318149 prefilling_elapsed_millis=2.804s
prefilling_size=2389983 prefilling_elapsed_millis=2.904s
prefilling_size=2461255 prefilling_elapsed_millis=3.004s
prefilling_size=2532649 prefilling_elapsed_millis=3.104s
prefilling_size=2602780 prefilling_elapsed_millis=3.205s
prefilling_size=2672333 prefilling_elapsed_millis=3.305s
prefilling_size=2741059 prefilling_elapsed_millis=3.405s
prefilling_size=2809096 prefilling_elapsed_millis=3.505s
prefilling_size=2876372 prefilling_elapsed_millis=3.605s
prefilling_size=2943137 prefilling_elapsed_millis=3.706s
prefilling_size=3009388 prefilling_elapsed_millis=3.806s
prefilling_size=3074354 prefilling_elapsed_millis=3.906s
prefilling_size=3139120 prefilling_elapsed_millis=4.006s
prefilling_size=3203584 prefilling_elapsed_millis=4.106s
prefilling_size=3267146 prefilling_elapsed_millis=4.207s
prefilling_size=3330035 prefilling_elapsed_millis=4.307s
prefilling_size=3392667 prefilling_elapsed_millis=4.407s
prefilling_size=3454228 prefilling_elapsed_millis=4.507s
prefilling_size=3515186 prefilling_elapsed_millis=4.607s
prefilling_size=3576009 prefilling_elapsed_millis=4.708s
prefilling_size=3635940 prefilling_elapsed_millis=4.808s
prefilling_size=3694728 prefilling_elapsed_millis=4.908s
prefilling_size=3753669 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6246273 keys with 48 threads...
tid=0 thread_prefill_amount=74988 percent_done_estimate=57.6 elapsed_s=5 est_remaining_s=9 / 14
tid=0 thread_prefill_amount=79154 percent_done_estimate=60.8 elapsed_s=5 est_remaining_s=8 / 13
tid=0 thread_prefill_amount=83320 percent_done_estimate=64.0 elapsed_s=6 est_remaining_s=7 / 13
tid=0 thread_prefill_amount=87486 percent_done_estimate=67.2 elapsed_s=6 est_remaining_s=7 / 12
tid=0 thread_prefill_amount=91652 percent_done_estimate=70.4 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=95818 percent_done_estimate=73.6 elapsed_s=6 est_remaining_s=5 / 12
tid=0 thread_prefill_amount=99984 percent_done_estimate=76.8 elapsed_s=7 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=104150 percent_done_estimate=80.0 elapsed_s=7 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=108316 percent_done_estimate=83.2 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=112482 percent_done_estimate=86.4 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=116648 percent_done_estimate=89.6 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=120814 percent_done_estimate=92.8 elapsed_s=8 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=124980 percent_done_estimate=96.0 elapsed_s=8 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=129146 percent_done_estimate=99.2 elapsed_s=8 est_remaining_s=0 / 8
tid=0 thread_prefill_amount=133312 percent_done_estimate=102.4 elapsed_s=8 est_remaining_s=-0 / 8
tid=0 thread_prefill_amount=137478 percent_done_estimate=105.6 elapsed_s=9 est_remaining_s=-1 / 8
tid=0 thread_prefill_amount=141644 percent_done_estimate=108.8 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=145810 percent_done_estimate=112.0 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=149976 percent_done_estimate=115.3 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=154142 percent_done_estimate=118.5 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=158308 percent_done_estimate=121.7 elapsed_s=10 est_remaining_s=-4 / 6
tid=0 thread_prefill_amount=162474 percent_done_estimate=124.9 elapsed_s=10 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=166640 percent_done_estimate=128.1 elapsed_s=10 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=170806 percent_done_estimate=131.3 elapsed_s=10 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=174972 percent_done_estimate=134.5 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=179138 percent_done_estimate=137.7 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=183304 percent_done_estimate=140.9 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=187470 percent_done_estimate=144.1 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=191636 percent_done_estimate=147.3 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=195802 percent_done_estimate=150.5 elapsed_s=11 est_remaining_s=-9 / 3
tid=0 thread_prefill_amount=199968 percent_done_estimate=153.7 elapsed_s=12 est_remaining_s=-9 / 2
timing_elapsed 6.52148s
prefilling_size=10000000 prefilling_elapsed_millis=6.537s
finished prefilling to size 10000000 for expected size 10000000 keysum=99983221285248, performing 19264856 updates; total_prefilling_elapsed_ms=6537 ms)
pref_size=10000000
pref_millis=6537
prefill_elapsed_ms=6537
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=953
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6991115.532167394
REALTIME_END_PERF_FORMAT=6991125.532244449
###############################################################################
################################## 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 (48 threads)...
partitioned into 251 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.175s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 249 484 927 1745 3202 5734 9838 16253 25636 38914 56861 79849 108615 142919 182588 227599 275686 325521 373969 416456 450612 472062 478459 469185 444645 406991 359884 306904 251222 199628 153135 112973 81360 56784 38169 25224 15952 9816 5819 3415 1931 1123 617 307 122 50 24 8 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 2 7 20 59 158 350 847 1800 3613 6331 10755 17541 26216 38348 52919 70688 92326 116114 142189 169684 195883 219764 238096 249836 252830 246580 232247 210429 185276 154066 125503 98599 73691 53823 38454 25839 17544 11138 7031 4170 2480 1386 823 453 264 96 35 21 7 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 251 491 947 1804 3360 6084 10685 18053 29249 45245 67616 97390 134831 181267 235507 298287 368012 441635 516158 586140 646495 691826 716555 719021 697475 653571 592131 517333 436498 353694 278638 211572 155051 110607 76623 51063 33496 20954 12850 7585 4411 2509 1440 760 386 146 59 29 9 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 251 491 947 1804 3360 6084 10685 18053 29249 45245 67616 97390 134831 181267 235507 298287 368012 441635 516158 586140 646495 691826 716555 719021 697475 653571 592131 517333 436498 353694 278638 211572 155051 110607 76623 51063 33496 20954 12850 7585 4411 2509 1440 760 386 146 59 29 9 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.96875 1.96414 1.94297 1.92608 1.89523 1.85774 1.81377 1.76883 1.71988 1.67042 1.63437 1.5994 1.56455 1.53884 1.51078 1.49128 1.47073 1.45093 1.43166 1.41106 1.39246 1.37311 1.3534 1.33572 1.3175 1.29955 1.28327 1.2659 1.2505 1.23476 1.22339 1.20972 1.19888 1.18863 1.17936 1.16828 1.162 1.14933 1.14479 1.13743 1.13131 1.13104 1.12635 1.09931 1.10395 1.06218 1.06164 1.08475 1.03448 1.11111 1
tree_stats_height=58
tree_stats_numInternals=6639722
tree_stats_numLeaves=3396335
tree_stats_numNodes=10036057
tree_stats_numKeys=10036057
tree_stats_avgDegreeInternal=1.51152
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.33841
tree_stats_avgKeyDepth=30.3164
4NodeIxPvL9tx_safety0EE_object_size=32
4NodeIxPvL9tx_safety0EE_allocated_count=0
4NodeIxPvL9tx_safety0EE_allocated_size=0MB
4NodeIxPvL9tx_safety0EE_get_from_pool=0
4NodeIxPvL9tx_safety0EE_deallocated=0
4NodeIxPvL9tx_safety0EE_limbo_count=0
4NodeIxPvL9tx_safety0EE_limbo_details=0 0 0
global_epoch_counter=5484
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:30 2:786 4:25269 8:166327 16:24961
[2^00, 2^01]: 30
(2^01, 2^02]: 786
(2^02, 2^03]: 25269
(2^03, 2^04]: 166327
(2^04, 2^05]: 24961
sum_limbo_reclamation_event_size_total=2474595
count_limbo_reclamation_event_size_by_thread=4529 4529 4529 4528 4528 4529 4529 4528 4528 4529 4529 4529 4528 4529 4529 4528 4528 4529 4528 4529 4529 4528 4528 4528 4529 4529 4529 4529 4529 4528 4529 4529 4529 4529 4528 4529 4529 4528 4529 4529 4529 4528 4528 4528 4529 4529 4528 4528
count_limbo_reclamation_event_size_total=217373
sum_limbo_reclamation_event_count_total=217535
first_thread_announced_epoch_by_thread=10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968 10968
sum_commit_tx_reader_total=94792378
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 1984720 1962738 1968449 2001434 1957615 1959455 1968837 1990611 1959505 1956020 1983416 1957513 1961261 1976844 1997097 1964442 1968022 1968699 1981922 1945648 1946447 2010843 1987129 2005493 1982070 1976084 1972162 1962994 1968220 1974550 1970357 1964481 1970977 1996448 1978416 1971585 1972223 2001897 1972688 1987581 1991960 1993361 2007847 1972314 1982301 1959589 1961231 1936882
sum_commit_tx_writer_total=4987755
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 104592 102809 103833 105531 102584 102862 103635 105775 102942 102395 104986 103144 103229 104116 105104 103629 102875 103777 104407 102443 102603 106502 103920 105650 104212 103827 103992 103242 103098 103830 103248 104116 103180 104954 104301 103805 103399 105160 104220 104382 104399 104232 105788 103924 105279 102816 103381 101627
sum_abort_tx_total=218
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 7 4 5 1 3 2 7 9 6 4 6 5 5 6 4 5 7 5 1 8 4 3 3 2 1 7 7 2 4 1 3 4 2 6 4 8 8 6 3 5 8 4 3 4 2 5 7 2
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 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=30.8:35 39.6:384 48.4:2766 57.2:7050 66:12059 74.8:15796 83.6:7092 92.4:2510 101.2:268 110:39
[ 22.00, 30.80]: 35
( 30.80, 39.60]: 384
( 39.60, 48.40]: 2766
( 48.40, 57.20]: 7050
( 57.20, 66.00]: 12059
( 66.00, 74.80]: 15796
( 74.80, 83.60]: 7092
( 83.60, 92.40]: 2510
( 92.40, 101.20]: 268
( 101.20, 110.00]: 39
linear_histogram_of_none_tx_wrset_size_full_data=1.4:16024 3.4:7649
[ 1.00, 1.40]: 16024
( 3.00, 3.40]: 7649
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=101680 103179 103978 104246 105421 105621 103053 103024 104012 105812 103748 103249 103144 103415 103180 104666 104064 104713 103814 102626 104677 104282 103259 103210 103889 104519 102930 103444 104178 104417 103124 105214 105541 104872 105381 105942 102190 105230 103879 104174 105163 103132 103998 102470 103608 105736 103239 103230
sum_num_inserts_total=4991573
sum_num_deletes_by_thread=101697 103651 104078 103339 105246 105387 102025 103758 104314 106218 103283 103898 103121 103872 103331 103842 103708 104773 103838 103675 104787 103668 103375 103218 103702 104572 102950 103522 104015 104647 102766 104356 105846 103829 104964 104987 102431 105002 104322 104217 105161 102752 103818 102816 102904 105261 103251 102952
sum_num_deletes_total=4987145
sum_num_searches_by_thread=1835132 1867327 1867334 1864887 1896298 1885378 1843013 1864115 1882723 1905315 1866574 1865135 1858225 1865189 1859036 1874209 1868466 1878094 1868502 1856016 1886895 1878332 1857978 1864890 1870789 1880221 1852535 1868656 1868715 1882899 1856557 1888023 1902248 1877628 1896712 1891272 1844429 1891170 1860396 1872569 1878078 1856521 1872095 1854913 1861559 1900146 1854167 1860054
sum_num_searches_total=89801415
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2038509 2074157 2075390 2072472 2106965 2096386 2048091 2070897 2091049 2117345 2073605 2072282 2064490 2072476 2065547 2082717 2076238 2087580 2076154 2062317 2096359 2086282 2064612 2071318 2078380 2089312 2058415 2075622 2076908 2091963 2062447 2097593 2113635 2086329 2107057 2102201 2049050 2101402 2068597 2080960 2088402 2062405 2079911 2060199 2068071 2111143 2060657 2066236
average_num_operations_total=2078752
stdev_num_operations_total=2583
sum_num_operations_total=99780133
min_num_operations_total=2038509
max_num_operations_total=2117345
first_time_thread_terminate_by_thread=10000132 10000078 10000077 10000077 10000079 10000078 10000077 10000076 10000079 10000077 10000077 10000079 10000078 10000076 10000078 10000076 10000077 10000076 10000078 10000076 10000079 10000076 10000077 10000078 10000076 10000077 10000077 10000077 10000077 10000077 10000077 10000077 10000079 10000077 10000077 10000077 10000077 10000076 10000078 10000076 10000079 10000078 10000077 10000078 10000077 10000077 10000078 10000077
min_time_thread_terminate_total=10000076
max_time_thread_terminate_total=10000132
first_time_thread_start_by_thread=9 5 5 5 4 5 4 4 5 5 5 5 5 5 4 5 5 5 5 5 5 5 5 5 4 5 5 5 4 5 4 4 5 5 5 5 5 5 4 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=100346010246686
threads_final_size=10036057
final_keysum=100346010246686
final_size=10036057
validate_result=success
Validation OK.
Structural validation OK.
total_find=89801415
total_rq=0
total_inserts=4991573
total_deletes=4987145
total_updates=9978718
total_queries=89801415
total_ops=99780133
find_throughput=8980141
rq_throughput=0
update_throughput=997871
query_throughput=8980141
total_throughput=9978013
total find : 89801415
total rq : 0
total inserts : 4991573
total deletes : 4987145
total updates : 9978718
total queries : 89801415
total ops : 99780133
find throughput : 8980141
rq throughput : 0
update throughput : 997871
query throughput : 8980141
total throughput : 9978013
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=20.091
PAPI_L3_TCM=5.04722
PAPI_TOT_CYC=11818.4
PAPI_TOT_INS=8199.79
garbage=445934943707099
total_execution_walltime=22.35s
REALTIME_MAIN_END_PERF_FORMAT=6991126.23816598
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=22.50, faults_major=0, faults_minor=156578, mem_maxresident_kb=627036, user_cputime=1021.94, sys_cputime=24.51, percent_cpu=4650%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.norec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1