__step=000098
__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 64 -nprefill 64 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000098.txt
__path_data=data/data000098.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=220981.104830868
binary=./brown_int_bst_tm_auto.debra.norec
parsed custom binding: 0-63,128-191,64-127,192-255
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=2000000
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=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,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.024s
prefilling_size=535674 prefilling_elapsed_millis=1s
prefilling_size=569903 prefilling_elapsed_millis=1.1s
prefilling_size=600880 prefilling_elapsed_millis=1.201s
prefilling_size=629667 prefilling_elapsed_millis=1.301s
prefilling_size=656487 prefilling_elapsed_millis=1.401s
prefilling_size=680968 prefilling_elapsed_millis=1.501s
prefilling_size=703511 prefilling_elapsed_millis=1.601s
prefilling_size=724818 prefilling_elapsed_millis=1.701s
prefilling_size=744511 prefilling_elapsed_millis=1.801s
prefilling_size=762635 prefilling_elapsed_millis=1.901s
prefilling_size=779616 prefilling_elapsed_millis=2.002s
prefilling_size=795270 prefilling_elapsed_millis=2.102s
prefilling_size=809585 prefilling_elapsed_millis=2.202s
prefilling_size=823165 prefilling_elapsed_millis=2.302s
prefilling_size=835317 prefilling_elapsed_millis=2.402s
prefilling_size=846719 prefilling_elapsed_millis=2.502s
prefilling_size=857390 prefilling_elapsed_millis=2.602s
prefilling_size=866888 prefilling_elapsed_millis=2.702s
prefilling_size=875595 prefilling_elapsed_millis=2.802s
prefilling_size=884046 prefilling_elapsed_millis=2.903s
prefilling_size=892604 prefilling_elapsed_millis=3.003s
prefilling_size=900170 prefilling_elapsed_millis=3.103s
prefilling_size=906742 prefilling_elapsed_millis=3.203s
prefilling_size=913414 prefilling_elapsed_millis=3.304s
prefilling_size=919196 prefilling_elapsed_millis=3.404s
prefilling_size=925091 prefilling_elapsed_millis=3.504s
prefilling_size=930861 prefilling_elapsed_millis=3.604s
prefilling_size=936234 prefilling_elapsed_millis=3.704s
prefilling_size=941003 prefilling_elapsed_millis=3.804s
prefilling_size=944772 prefilling_elapsed_millis=3.904s
prefilling_size=948557 prefilling_elapsed_millis=4.004s
prefilling_size=952617 prefilling_elapsed_millis=4.104s
prefilling_size=955986 prefilling_elapsed_millis=4.205s
prefilling_size=959130 prefilling_elapsed_millis=4.305s
prefilling_size=962411 prefilling_elapsed_millis=4.405s
prefilling_size=964929 prefilling_elapsed_millis=4.505s
prefilling_size=967589 prefilling_elapsed_millis=4.605s
prefilling_size=969826 prefilling_elapsed_millis=4.705s
prefilling_size=972122 prefilling_elapsed_millis=4.805s
prefilling_size=974551 prefilling_elapsed_millis=4.905s
prefilling_size=976060 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 23929 keys with 64 threads...
timing_elapsed 0.0450045s
prefilling_size=1000000 prefilling_elapsed_millis=0.047s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000360990129, performing 7402235 updates; total_prefilling_elapsed_ms=47 ms)
pref_size=1000000
pref_millis=47
prefill_elapsed_ms=47
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=251
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=220986.314878092
REALTIME_END_PERF_FORMAT=220996.314947029
###############################################################################
################################## 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.024s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 506 987 1849 3301 5628 9082 13697 19399 25979 32932 39495 44897 49046 51240 51517 50202 47122 42366 36904 31229 25713 20558 15822 11657 8154 5460 3577 2246 1331 805 464 236 95 43 16 11 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 4 16 80 231 554 1195 2449 4326 7020 10204 14182 18200 21654 24728 26966 27935 28102 27324 25274 22451 19218 16093 13138 10323 7936 5594 3804 2528 1664 953 588 355 188 73 37 12 12 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 510 1003 1929 3532 6182 10277 16146 23725 32999 43136 53677 63097 70700 75968 78483 78137 75224 69690 62178 53680 44931 36651 28960 21980 16090 11054 7381 4774 2995 1758 1052 591 283 116 53 23 14 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 510 1003 1929 3532 6182 10277 16146 23725 32999 43136 53677 63097 70700 75968 78483 78137 75224 69690 62178 53680 44931 36651 28960 21980 16090 11054 7381 4774 2995 1758 1052 591 283 116 53 23 14 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.99219 1.97451 1.93918 1.87247 1.81569 1.75202 1.68736 1.62108 1.57323 1.51992 1.48092 1.4397 1.40894 1.38079 1.35861 1.33918 1.32023 1.30001 1.28429 1.26981 1.25525 1.24344 1.22924 1.21264 1.20168 1.18024 1.17378 1.16217 1.15689 1.14257 1.1405 1.12072 1.07953 1.0742 1.08621 1.13208 1.13043 1 1
tree_stats_height=47
tree_stats_numInternals=654079
tree_stats_numLeaves=345413
tree_stats_numNodes=999492
tree_stats_numKeys=999492
tree_stats_avgDegreeInternal=1.52809
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34559
tree_stats_avgKeyDepth=23.8734
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=4694
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:32 2:305 4:14413 8:192728 16:76538 32:155 64:23
[2^00, 2^01]: 32
(2^01, 2^02]: 305
(2^02, 2^03]: 14413
(2^03, 2^04]: 192728
(2^04, 2^05]: 76538
(2^05, 2^06]: 155
(2^06, 2^07]: 23
sum_limbo_reclamation_event_size_total=3786566
count_limbo_reclamation_event_size_by_thread=4440 4440 4440 4438 4441 4441 4441 4440 4440 4441 4441 4441 4441 4440 4441 4441 4441 4441 4440 4439 4440 4440 4441 4441 4440 4441 4441 4440 4440 4441 4440 4441 4441 4441 4441 4440 4441 4440 4440 4440 4441 4441 4440 4441 4441 4441 4441 4441 4441 4440 4440 4440 4441 4441 4441 4440 4441 4441 4440 4440 4441 4441 4440 4441
count_limbo_reclamation_event_size_total=284194
sum_limbo_reclamation_event_count_total=284352
first_thread_announced_epoch_by_thread=9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388 9388
sum_commit_tx_reader_total=143934673
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 2185391 2027815 2168176 1994068 2200692 2053954 2191821 2163151 2028296 2152466 2004700 2009664 2614853 2047529 2601415 2636929 2040354 2038373 2618443 2642133 2177083 2195507 2059770 2584254 2183166 2588803 2152018 2595728 2590691 2157227 2046929 2017116 2005722 2026490 2028806 2611923 2625113 2163160 2641409 2159234 2193417 2182223 2165650 2204540 2174475 2156209 2174963 2180174 2186630 2188204 2167204 2604244 2172814 2610040 2617467 2154414 2174455 2034300 2640197 2199327 2196794 2164979 2164648 2196933
sum_commit_tx_writer_total=7576770
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 114748 106625 113702 104271 115799 108542 115449 113792 106715 113620 105885 105834 137571 107830 137312 138996 107350 106934 137836 139161 114885 115287 108510 135391 114898 136912 113352 137079 136168 113205 108179 106339 106263 106853 106739 137318 138034 114259 139361 113330 115074 115026 113490 115943 113970 112834 114877 114493 115188 115129 114039 137321 114093 136615 137365 113026 115004 107286 138641 116573 115650 114648 114403 115748
sum_abort_tx_total=3111
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 40 53 57 38 48 53 48 60 55 59 44 51 44 66 45 45 42 40 48 42 55 45 45 50 44 49 53 43 58 46 48 49 53 46 58 53 54 50 49 51 52 53 41 58 61 56 53 45 40 41 49 48 37 41 37 42 50 43 45 45 48 38 61 50
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=20.1:12 28.2:265 36.3:2704 44.4:10759 52.5:18743 60.6:17764 68.7:9857 76.8:3224 84.9:607 93:64
[ 12.00, 20.10]: 12
( 20.10, 28.20]: 265
( 28.20, 36.30]: 2704
( 36.30, 44.40]: 10759
( 44.40, 52.50]: 18743
( 52.50, 60.60]: 17764
( 60.60, 68.70]: 9857
( 68.70, 76.80]: 3224
( 76.80, 84.90]: 607
( 84.90, 93.00]: 64
linear_histogram_of_none_tx_wrset_size_full_data=1.4:21072 3.4:10911
[ 1.00, 1.40]: 21072
( 3.00, 3.40]: 10911
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=113825 115846 116357 114464 113247 115011 115159 114778 113454 115142 115671 114339 113561 115208 114960 114079 105704 107276 107169 107398 107016 108444 108089 106795 106495 107459 107878 105977 104356 106842 106494 105542 136909 138601 138571 136647 137967 138625 138353 137341 137189 138766 137403 136681 136134 137159 138018 135644 113551 114887 114810 113469 112931 114853 114969 113801 113115 114878 114678 113995 113479 115387 116130 114895
sum_num_inserts_total=7573871
sum_num_deletes_by_thread=114819 115491 116286 113905 114174 115925 115584 114732 114269 115517 115757 113968 114293 115055 114971 112984 105947 106283 106928 107436 106511 108476 109275 106691 105860 108251 108020 105996 105287 106231 106653 105874 135689 139491 139242 137177 137093 139068 138445 136813 137728 137420 137708 136659 136363 137211 137901 135480 113081 114807 114916 113346 113189 114419 114955 113727 113317 114898 115501 113728 113651 115782 115882 114262
sum_num_deletes_total=7576398
sum_num_searches_by_thread=2050407 2081344 2083257 2051258 2053822 2085555 2080051 2062458 2054155 2076611 2081016 2058600 2049089 2071555 2070208 2039023 1903847 1928027 1933607 1930473 1921484 1945576 1950916 1920954 1911100 1939398 1939461 1900012 1888696 1920270 1922398 1899169 2453117 2503202 2502957 2467741 2477364 2498232 2502040 2472501 2474324 2486961 2479721 2459467 2454362 2464357 2480360 2448521 2038738 2068370 2064941 2043617 2041320 2060187 2067325 2051612 2042611 2060064 2073154 2049696 2045434 2077322 2088471 2059288
sum_num_searches_total=136361174
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2279051 2312681 2315900 2279627 2281243 2316491 2310794 2291968 2281878 2307270 2312444 2286907 2276943 2301818 2300139 2266086 2115498 2141586 2147704 2145307 2135011 2162496 2168280 2134440 2123455 2155108 2155359 2111985 2098339 2133343 2135545 2110585 2725715 2781294 2780770 2741565 2752424 2775925 2778838 2746655 2749241 2763147 2754832 2732807 2726859 2738727 2756279 2719645 2265370 2298064 2294667 2270432 2267440 2289459 2297249 2279140 2269043 2289840 2303333 2277419 2272564 2308491 2320483 2288445
average_num_operations_total=2367366
stdev_num_operations_total=29380
sum_num_operations_total=151511443
min_num_operations_total=2098339
max_num_operations_total=2781294
first_time_thread_terminate_by_thread=10000110 10000068 10000071 10000071 10000070 10000070 10000074 10000074 10000073 10000073 10000073 10000073 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000071 10000076 10000076 10000071 10000076 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000072 10000073 10000075 10000070 10000070 10000070 10000070 10000070 10000069 10000070 10000071 10000071 10000071 10000071 10000070 10000070 10000072 10000070 10000075 10000075 10000075 10000075 10000072 10000072 10000072 10000072 10000069 10000069 10000071 10000071
min_time_thread_terminate_total=10000068
max_time_thread_terminate_total=10000110
first_time_thread_start_by_thread=6 3 3 3 4 4 4 4 7 7 7 7 7 7 7 7 6 6 6 6 4 4 4 4 6 6 6 6 5 5 5 5 6 6 6 6 5 5 5 5 5 5 5 5 5 5 5 5 6 6 6 6 8 8 8 8 7 7 7 7 7 7 7 7
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=
threads_final_keysum=999447653087
threads_final_size=999492
final_keysum=999447653087
final_size=999492
validate_result=success
Validation OK.
Structural validation OK.
total_find=136361174
total_rq=0
total_inserts=7573871
total_deletes=7576398
total_updates=15150269
total_queries=136361174
total_ops=151511443
find_throughput=13636117
rq_throughput=0
update_throughput=1515026
query_throughput=13636117
total_throughput=15151144
total find : 136361174
total rq : 0
total inserts : 7573871
total deletes : 7576398
total updates : 15150269
total queries : 136361174
total ops : 151511443
find throughput : 13636117
rq throughput : 0
update throughput : 1515026
query throughput : 13636117
total throughput : 15151144
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=10158540950290
total_execution_walltime=15.495s
REALTIME_MAIN_END_PERF_FORMAT=220996.600948118
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.60, faults_major=0, faults_minor=84753, mem_maxresident_kb=337408, user_cputime=959.63, sys_cputime=17.23, percent_cpu=6261%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=2