__step=000002
__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=data000002.txt
__path_data=data/data000002.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6991126.235224083
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.005s
prefilling_size=903713 prefilling_elapsed_millis=1.001s
prefilling_size=989541 prefilling_elapsed_millis=1.101s
prefilling_size=1074596 prefilling_elapsed_millis=1.201s
prefilling_size=1158865 prefilling_elapsed_millis=1.301s
prefilling_size=1242401 prefilling_elapsed_millis=1.402s
prefilling_size=1325167 prefilling_elapsed_millis=1.502s
prefilling_size=1407429 prefilling_elapsed_millis=1.602s
prefilling_size=1488836 prefilling_elapsed_millis=1.702s
prefilling_size=1569844 prefilling_elapsed_millis=1.802s
prefilling_size=1649945 prefilling_elapsed_millis=1.903s
prefilling_size=1729047 prefilling_elapsed_millis=2.003s
prefilling_size=1807816 prefilling_elapsed_millis=2.103s
prefilling_size=1885256 prefilling_elapsed_millis=2.203s
prefilling_size=1962166 prefilling_elapsed_millis=2.303s
prefilling_size=2038518 prefilling_elapsed_millis=2.404s
prefilling_size=2114664 prefilling_elapsed_millis=2.504s
prefilling_size=2189575 prefilling_elapsed_millis=2.604s
prefilling_size=2263760 prefilling_elapsed_millis=2.704s
prefilling_size=2337232 prefilling_elapsed_millis=2.804s
prefilling_size=2410081 prefilling_elapsed_millis=2.905s
prefilling_size=2482335 prefilling_elapsed_millis=3.005s
prefilling_size=2553918 prefilling_elapsed_millis=3.105s
prefilling_size=2624842 prefilling_elapsed_millis=3.205s
prefilling_size=2695279 prefilling_elapsed_millis=3.305s
prefilling_size=2764857 prefilling_elapsed_millis=3.406s
prefilling_size=2833958 prefilling_elapsed_millis=3.506s
prefilling_size=2902316 prefilling_elapsed_millis=3.606s
prefilling_size=2970090 prefilling_elapsed_millis=3.706s
prefilling_size=3037360 prefilling_elapsed_millis=3.806s
prefilling_size=3103559 prefilling_elapsed_millis=3.907s
prefilling_size=3169646 prefilling_elapsed_millis=4.007s
prefilling_size=3235205 prefilling_elapsed_millis=4.107s
prefilling_size=3300108 prefilling_elapsed_millis=4.207s
prefilling_size=3364293 prefilling_elapsed_millis=4.307s
prefilling_size=3428061 prefilling_elapsed_millis=4.407s
prefilling_size=3490582 prefilling_elapsed_millis=4.508s
prefilling_size=3553080 prefilling_elapsed_millis=4.608s
prefilling_size=3614692 prefilling_elapsed_millis=4.708s
prefilling_size=3676400 prefilling_elapsed_millis=4.808s
prefilling_size=3737195 prefilling_elapsed_millis=4.908s
prefilling_size=3797101 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 6202848 keys with 48 threads...
tid=0 thread_prefill_amount=79154 percent_done_estimate=61.3 elapsed_s=5 est_remaining_s=8 / 13
tid=0 thread_prefill_amount=83320 percent_done_estimate=64.5 elapsed_s=6 est_remaining_s=7 / 12
tid=0 thread_prefill_amount=87486 percent_done_estimate=67.7 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=91652 percent_done_estimate=70.9 elapsed_s=6 est_remaining_s=6 / 12
tid=0 thread_prefill_amount=95818 percent_done_estimate=74.1 elapsed_s=6 est_remaining_s=5 / 11
tid=0 thread_prefill_amount=99984 percent_done_estimate=77.4 elapsed_s=6 est_remaining_s=4 / 11
tid=0 thread_prefill_amount=104150 percent_done_estimate=80.6 elapsed_s=7 est_remaining_s=4 / 10
tid=0 thread_prefill_amount=108316 percent_done_estimate=83.8 elapsed_s=7 est_remaining_s=3 / 10
tid=0 thread_prefill_amount=112482 percent_done_estimate=87.0 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=116648 percent_done_estimate=90.3 elapsed_s=7 est_remaining_s=2 / 9
tid=0 thread_prefill_amount=120814 percent_done_estimate=93.5 elapsed_s=8 est_remaining_s=1 / 9
tid=0 thread_prefill_amount=124980 percent_done_estimate=96.7 elapsed_s=8 est_remaining_s=1 / 8
tid=0 thread_prefill_amount=129146 percent_done_estimate=99.9 elapsed_s=8 est_remaining_s=0 / 8
tid=0 thread_prefill_amount=133312 percent_done_estimate=103.2 elapsed_s=8 est_remaining_s=-1 / 8
tid=0 thread_prefill_amount=137478 percent_done_estimate=106.4 elapsed_s=8 est_remaining_s=-1 / 7
tid=0 thread_prefill_amount=141644 percent_done_estimate=109.6 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=145810 percent_done_estimate=112.8 elapsed_s=9 est_remaining_s=-2 / 7
tid=0 thread_prefill_amount=149976 percent_done_estimate=116.1 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=154142 percent_done_estimate=119.3 elapsed_s=9 est_remaining_s=-3 / 6
tid=0 thread_prefill_amount=158308 percent_done_estimate=122.5 elapsed_s=9 est_remaining_s=-4 / 6
tid=0 thread_prefill_amount=162474 percent_done_estimate=125.7 elapsed_s=10 est_remaining_s=-4 / 5
tid=0 thread_prefill_amount=166640 percent_done_estimate=129.0 elapsed_s=10 est_remaining_s=-5 / 5
tid=0 thread_prefill_amount=170806 percent_done_estimate=132.2 elapsed_s=10 est_remaining_s=-6 / 5
tid=0 thread_prefill_amount=174972 percent_done_estimate=135.4 elapsed_s=10 est_remaining_s=-6 / 4
tid=0 thread_prefill_amount=179138 percent_done_estimate=138.6 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=183304 percent_done_estimate=141.8 elapsed_s=11 est_remaining_s=-7 / 4
tid=0 thread_prefill_amount=187470 percent_done_estimate=145.1 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=191636 percent_done_estimate=148.3 elapsed_s=11 est_remaining_s=-8 / 3
tid=0 thread_prefill_amount=195802 percent_done_estimate=151.5 elapsed_s=11 est_remaining_s=-9 / 3
tid=0 thread_prefill_amount=199968 percent_done_estimate=154.7 elapsed_s=11 est_remaining_s=-9 / 2
tid=0 thread_prefill_amount=204134 percent_done_estimate=158.0 elapsed_s=12 est_remaining_s=-10 / 2
timing_elapsed 6.42555s
prefilling_size=10000000 prefilling_elapsed_millis=6.44s
finished prefilling to size 10000000 for expected size 10000000 keysum=99992718298826, performing 19240775 updates; total_prefilling_elapsed_ms=6441 ms)
pref_size=10000000
pref_millis=6441
prefill_elapsed_ms=6441
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=921
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6991137.980159484
REALTIME_END_PERF_FORMAT=6991147.980240482
###############################################################################
################################## 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 255 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.125s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 495 942 1788 3323 6024 10564 17908 29311 46249 70256 102533 143951 193915 251078 312168 373599 428424 473629 503414 512541 502604 473743 429679 377014 319313 260987 207750 160003 120207 88444 63197 43891 29898 19536 12528 8002 4960 2974 1786 1084 640 405 218 96 48 18 13 6 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1 4 25 48 139 317 788 1670 3411 6373 11422 19556 31247 48340 69850 97216 128201 163197 196220 226764 252605 266547 271311 263905 246127 221419 191692 159868 129918 101281 77191 57686 41807 29461 20442 13460 8677 5605 3557 2098 1278 787 411 298 179 73 42 9 11 5 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 499 967 1836 3462 6341 11352 19578 32722 52622 81678 122089 175198 242255 320928 409384 501800 591621 669849 730178 765146 769151 745054 693584 623141 540732 452679 367618 289921 221488 165635 120883 85698 59359 39978 25988 16679 10565 6531 3884 2362 1427 816 516 275 121 60 22 17 7 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 499 967 1836 3462 6341 11352 19578 32722 52622 81678 122089 175198 242255 320928 409384 501800 591621 669849 730178 765146 769151 745054 693584 623141 540732 452679 367618 289921 221488 165635 120883 85698 59359 39978 25988 16679 10565 6531 3884 2362 1427 816 516 275 121 60 22 17 7 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.96078 1.94589 1.92451 1.91176 1.87175 1.84025 1.79405 1.75667 1.7124 1.67327 1.6346 1.59518 1.5611 1.52429 1.49328 1.46321 1.43448 1.40807 1.383 1.35845 1.33537 1.31522 1.29507 1.27893 1.26273 1.24664 1.23556 1.22352 1.21208 1.2051 1.19585 1.18614 1.18049 1.16981 1.16139 1.15973 1.15367 1.1487 1.13934 1.1483 1.14522 1.12334 1.13603 1.11047 1.09091 1.09917 1.06667 1.18182 1.05882 1 1
tree_stats_height=59
tree_stats_numInternals=6611667
tree_stats_numLeaves=3372541
tree_stats_numNodes=9984208
tree_stats_numKeys=9984208
tree_stats_avgDegreeInternal=1.51009
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.33779
tree_stats_avgKeyDepth=28.9204
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=5553
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:37 2:655 4:23474 8:171119 16:26949
[2^00, 2^01]: 37
(2^01, 2^02]: 655
(2^02, 2^03]: 23474
(2^03, 2^04]: 171119
(2^04, 2^05]: 26949
sum_limbo_reclamation_event_size_total=2562985
count_limbo_reclamation_event_size_by_thread=4627 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4629 4630 4630 4630 4630 4630 4629 4630 4630 4630 4629 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630 4630
count_limbo_reclamation_event_size_total=222234
sum_limbo_reclamation_event_count_total=222381
first_thread_announced_epoch_by_thread=11104 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106 11106
sum_commit_tx_reader_total=97223948
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 2029809 2010538 2013239 2034450 2016295 2023368 2040680 2015107 2011717 2037219 2045264 2029986 2033913 2033463 2021097 2023566 2037798 2043583 2021953 2027134 2039103 2032706 2038209 1998979 2006340 1993738 2015772 2010009 2018266 2018649 2019243 2020605 2043348 2021672 2036377 2014631 2040135 2014309 2042141 2048610 2032838 2016810 2039149 2016153 2012680 2032727 2037217 2013353
sum_commit_tx_writer_total=5112496
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 106444 105536 106364 106540 106018 106455 107325 105993 105053 107052 107463 106901 106765 107193 105900 106766 107045 107322 106448 106795 107154 106712 107232 104636 105390 105200 106262 105882 106428 105882 106540 105843 107016 106297 107177 105611 107435 105627 106895 107894 107520 106388 107236 105919 105970 107123 107703 106146
sum_abort_tx_total=214
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 3 3 1 8 3 6 4 5 4 3 4 6 2 2 4 2 2 5 5 6 3 2 8 8 6 3 7 2 6 3 3 3 6 4 4 5 6 3 9 6 5 7 6 4 5 1 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 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=32.9:60 41.8:658 50.7:4947 59.6:11303 68.5:17616 77.4:9093 86.3:3608 95.2:603 104.1:102 113:9
[ 24.00, 32.90]: 60
( 32.90, 41.80]: 658
( 41.80, 50.70]: 4947
( 50.70, 59.60]: 11303
( 59.60, 68.50]: 17616
( 68.50, 77.40]: 9093
( 77.40, 86.30]: 3608
( 86.30, 95.20]: 603
( 95.20, 104.10]: 102
( 104.10, 113.00]: 9
linear_histogram_of_none_tx_wrset_size_full_data=1.4:16085 3.4:7936
[ 1.00, 1.40]: 16085
( 3.00, 3.40]: 7936
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=105607 106029 107798 107306 106397 105051 106014 107201 107366 107051 106159 106210 106233 107292 107812 107113 106818 106783 105984 105649 106038 107595 105937 105351 105619 105225 107153 106588 107095 105139 106085 107056 107752 107136 106375 106444 106036 107164 107346 107291 107373 106582 106085 106892 106920 107233 106464 106549
sum_num_inserts_total=5116396
sum_num_deletes_by_thread=106103 105686 107341 107552 107068 104970 106667 107280 106368 106985 105587 105503 106649 106508 108116 107157 106559 106802 105795 106125 106755 107260 105696 105724 105420 105832 107769 106630 107256 104967 106543 107163 106961 107329 106411 105701 106183 107336 107108 107908 106876 106203 105718 106185 107188 107444 106543 106381
sum_num_deletes_total=5115311
sum_num_searches_by_thread=1907789 1910357 1929781 1928696 1920464 1893594 1915720 1930362 1932523 1936328 1910567 1906937 1911812 1926050 1940576 1930001 1922876 1916747 1908157 1909326 1909241 1931530 1904258 1904999 1905731 1900673 1933083 1923669 1926305 1888832 1913820 1926459 1936192 1930976 1917037 1914852 1912312 1925858 1933116 1937528 1926741 1915184 1908439 1910121 1925310 1934359 1912776 1906673
sum_num_searches_total=92104737
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2119499 2122072 2144920 2143554 2133929 2103615 2128401 2144843 2146257 2150364 2122313 2118650 2124694 2139850 2156504 2144271 2136253 2130332 2119936 2121100 2122034 2146385 2115891 2116074 2116770 2111730 2148005 2136887 2140656 2098938 2126448 2140678 2150905 2145441 2129823 2126997 2124531 2140358 2147570 2152727 2140990 2127969 2120242 2123198 2139418 2149036 2125783 2119603
average_num_operations_total=2132009
stdev_num_operations_total=1988
sum_num_operations_total=102336444
min_num_operations_total=2098938
max_num_operations_total=2156504
first_time_thread_terminate_by_thread=10000135 10000081 10000080 10000083 10000080 10000081 10000081 10000083 10000080 10000081 10000080 10000081 10000081 10000082 10000081 10000083 10000083 10000080 10000080 10000082 10000082 10000081 10000081 10000080 10000080 10000080 10000080 10000083 10000083 10000081 10000081 10000083 10000080 10000081 10000081 10000084 10000081 10000081 10000082 10000082 10000084 10000080 10000081 10000080 10000084 10000082 10000081 10000080
min_time_thread_terminate_total=10000080
max_time_thread_terminate_total=10000135
first_time_thread_start_by_thread=8 4 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 3 4 4 4 4 5 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4 4
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=99811284595129
threads_final_size=9984208
final_keysum=99811284595129
final_size=9984208
validate_result=success
Validation OK.
Structural validation OK.
total_find=92104737
total_rq=0
total_inserts=5116396
total_deletes=5115311
total_updates=10231707
total_queries=92104737
total_ops=102336444
find_throughput=9210473
rq_throughput=0
update_throughput=1023170
query_throughput=9210473
total_throughput=10233644
total find : 92104737
total rq : 0
total inserts : 5116396
total deletes : 5115311
total updates : 10231707
total queries : 92104737
total ops : 102336444
find throughput : 9210473
rq throughput : 0
update throughput : 1023170
query throughput : 9210473
total throughput : 10233644
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=20.0845
PAPI_L3_TCM=5.01664
PAPI_TOT_CYC=11518.2
PAPI_TOT_INS=7876.83
garbage=462156313637307
total_execution_walltime=22.183s
REALTIME_MAIN_END_PERF_FORMAT=6991148.419841015
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=22.33, faults_major=0, faults_minor=156015, mem_maxresident_kb=624708, user_cputime=1019.84, sys_cputime=23.71, percent_cpu=4672%
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=2