__step=000097
__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=data000097.txt
__path_data=data/data000097.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=220965.46092202
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.067s
prefilling_size=331519 prefilling_elapsed_millis=1s
prefilling_size=356629 prefilling_elapsed_millis=1.1s
prefilling_size=380580 prefilling_elapsed_millis=1.201s
prefilling_size=403660 prefilling_elapsed_millis=1.301s
prefilling_size=426203 prefilling_elapsed_millis=1.401s
prefilling_size=447861 prefilling_elapsed_millis=1.501s
prefilling_size=468600 prefilling_elapsed_millis=1.601s
prefilling_size=488458 prefilling_elapsed_millis=1.701s
prefilling_size=507783 prefilling_elapsed_millis=1.801s
prefilling_size=526048 prefilling_elapsed_millis=1.901s
prefilling_size=543878 prefilling_elapsed_millis=2.001s
prefilling_size=561042 prefilling_elapsed_millis=2.102s
prefilling_size=577523 prefilling_elapsed_millis=2.202s
prefilling_size=593115 prefilling_elapsed_millis=2.302s
prefilling_size=608170 prefilling_elapsed_millis=2.402s
prefilling_size=622677 prefilling_elapsed_millis=2.502s
prefilling_size=636845 prefilling_elapsed_millis=2.602s
prefilling_size=650757 prefilling_elapsed_millis=2.702s
prefilling_size=663735 prefilling_elapsed_millis=2.802s
prefilling_size=676149 prefilling_elapsed_millis=2.903s
prefilling_size=688006 prefilling_elapsed_millis=3.003s
prefilling_size=699708 prefilling_elapsed_millis=3.103s
prefilling_size=710785 prefilling_elapsed_millis=3.203s
prefilling_size=721194 prefilling_elapsed_millis=3.303s
prefilling_size=731574 prefilling_elapsed_millis=3.404s
prefilling_size=741385 prefilling_elapsed_millis=3.504s
prefilling_size=750857 prefilling_elapsed_millis=3.604s
prefilling_size=760114 prefilling_elapsed_millis=3.704s
prefilling_size=768742 prefilling_elapsed_millis=3.804s
prefilling_size=777271 prefilling_elapsed_millis=3.904s
prefilling_size=785346 prefilling_elapsed_millis=4.004s
prefilling_size=793294 prefilling_elapsed_millis=4.104s
prefilling_size=800818 prefilling_elapsed_millis=4.204s
prefilling_size=808245 prefilling_elapsed_millis=4.305s
prefilling_size=815557 prefilling_elapsed_millis=4.405s
prefilling_size=821751 prefilling_elapsed_millis=4.505s
prefilling_size=828472 prefilling_elapsed_millis=4.605s
prefilling_size=834721 prefilling_elapsed_millis=4.705s
prefilling_size=840854 prefilling_elapsed_millis=4.805s
prefilling_size=846791 prefilling_elapsed_millis=4.905s
prefilling_size=852414 prefilling_elapsed_millis=5.005s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 147595 keys with 64 threads...
tid=0 thread_prefill_amount=12500 percent_done_estimate=542.0 elapsed_s=5 est_remaining_s=-10 / -4
timing_elapsed 0.336236s
prefilling_size=1000000 prefilling_elapsed_millis=0.353s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000129263861, performing 4101709 updates; total_prefilling_elapsed_ms=353 ms)
pref_size=1000000
pref_millis=353
prefill_elapsed_ms=353
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=191
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=220970.618873312
REALTIME_END_PERF_FORMAT=220980.618960296
###############################################################################
################################## 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 470 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...
tree_stats_computeWalltime=0.041s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 241 451 844 1542 2737 4519 7163 10818 15610 21469 27665 34000 39986 44965 48454 49922 49172 47036 43983 39928 35408 30540 25563 20553 15826 11707 8410 5868 3896 2469 1462 830 554 278 127 71 30 9 5 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 8 19 36 86 178 541 1019 1903 3236 5201 8246 11377 14944 18797 22039 24928 26898 27127 26472 25279 23313 21194 18611 15969 13204 10411 7785 5739 4031 2740 1814 1090 559 424 224 95 59 23 6 6 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 249 470 880 1628 2915 5060 8182 12721 18846 26670 35911 45377 54930 63762 70493 74850 76070 74163 70455 65207 58721 51734 44174 36522 29030 22118 16195 11607 7927 5209 3276 1920 1113 702 351 166 89 32 11 7 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 249 470 880 1628 2915 5060 8182 12721 18846 26670 35911 45377 54930 63762 70493 74850 76070 74163 70455 65207 58721 51734 44174 36522 29030 22118 16195 11607 7927 5209 3276 1920 1113 702 351 166 89 32 11 7 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.95312 1.91968 1.91277 1.89091 1.84337 1.79691 1.72391 1.6793 1.63108 1.58686 1.54151 1.49322 1.46125 1.43284 1.40036 1.37445 1.34934 1.32853 1.31578 1.30124 1.28821 1.27803 1.26354 1.24809 1.23211 1.21674 1.20291 1.19741 1.17739 1.16564 1.15492 1.1398 1.1474 1.13297 1.10399 1.11111 1.10843 1.02247 1.0625 1.18182 1 1
tree_stats_height=49
tree_stats_numInternals=654366
tree_stats_numLeaves=345633
tree_stats_numNodes=999999
tree_stats_numKeys=999999
tree_stats_avgDegreeInternal=1.5282
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34563
tree_stats_avgKeyDepth=24.6828
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=5521
log_histogram_of_none_limbo_reclamation_event_size_full_data=1:10 2:482 4:21239 8:234832 16:84185 32:209 64:35
[2^00, 2^01]: 10
(2^01, 2^02]: 482
(2^02, 2^03]: 21239
(2^03, 2^04]: 234832
(2^04, 2^05]: 84185
(2^05, 2^06]: 209
(2^06, 2^07]: 35
sum_limbo_reclamation_event_size_total=4445118
count_limbo_reclamation_event_size_by_thread=5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328 5328
count_limbo_reclamation_event_size_total=340992
sum_limbo_reclamation_event_count_total=341183
first_thread_announced_epoch_by_thread=11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042 11042
sum_commit_tx_reader_total=169030832
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 2309924 2289660 2279700 2327898 2997474 2279839 2527822 2473414 2491269 2456947 2279025 2646548 2258408 2504674 2312573 2312535 2679972 3047731 2460955 2270073 2677574 2717993 2627425 2652280 2676219 2646423 2612218 2648941 2653378 3014763 3035074 2708826 3154192 3199256 3157593 3168106 3136946 2273310 3192581 3048954 2473393 2343804 2543226 2544731 3071824 3040928 2466061 2522710 2474374 2466857 3007806 2511900 2650225 2508696 2341019 3100608 3086554 2330444 2551143 2679902 2362768 2714152 2746167 2283047
sum_commit_tx_writer_total=8894175
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 121307 120051 119873 122183 157653 120335 132631 130618 131165 129035 119947 138858 118624 131592 122026 121577 140960 160466 129825 119665 140906 143271 137859 139689 140843 139079 137511 139069 139609 158849 159835 142120 166676 168482 166066 166831 165522 119290 168823 159833 129931 123902 134033 133798 161840 159784 129601 132308 130084 129579 158421 132327 139296 132063 123095 163045 162369 123141 133590 140440 124910 142863 144525 120676
sum_abort_tx_total=4291
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 67 62 72 68 67 58 52 83 62 70 76 73 56 76 61 75 61 66 59 66 66 66 67 68 68 67 64 67 68 64 44 62 75 76 68 65 52 64 63 74 73 69 67 79 65 64 66 72 69 72 76 56 74 70 65 66 71 82 78 79 65 51 62 62
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=23.9:27 31.8:446 39.7:3294 47.6:10903 55.5:18369 63.4:16852 71.3:9912 79.2:3405 87.1:704 95:87
[ 16.00, 23.90]: 27
( 23.90, 31.80]: 446
( 31.80, 39.70]: 3294
( 39.70, 47.60]: 10903
( 47.60, 55.50]: 18369
( 55.50, 63.40]: 16852
( 63.40, 71.30]: 9912
( 71.30, 79.20]: 3405
( 79.20, 87.10]: 704
( 87.10, 95.00]: 87
linear_histogram_of_none_tx_wrset_size_full_data=1.4:21160 3.4:10709
[ 1.00, 1.40]: 21160
( 3.00, 3.40]: 10709
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=120320 123112 124188 119320 120039 121523 122459 120115 120161 121916 123838 120294 118967 122430 122610 120251 130599 132103 134442 129851 129855 132857 133798 131328 130698 132469 134050 131558 130340 129891 132420 129108 139487 138955 140473 142592 139017 143354 144334 141346 138010 140400 142527 139166 137915 139630 141032 139574 161973 162822 168708 165961 165336 166916 168076 166438 158174 160000 160497 157811 159323 159583 161486 160655
sum_num_inserts_total=8898481
sum_num_deletes_by_thread=120131 122018 124813 119414 119985 121756 122552 120322 119740 120616 123680 119879 119074 121490 123260 119199 129851 132541 133471 129629 129518 132304 134145 130960 129954 132734 133416 131282 129087 129765 132866 129225 139237 139218 141130 142192 140014 143098 144908 140279 138018 140720 142567 139614 137364 139323 140655 139798 162633 163218 168651 166208 165070 165863 168025 166541 158633 159789 159960 157887 158104 160496 160968 160177
sum_num_deletes_total=8889035
sum_num_searches_by_thread=2163272 2208455 2238677 2153866 2158948 2190833 2205070 2169274 2159672 2188699 2220188 2160001 2138991 2190679 2218244 2150288 2344008 2376115 2410616 2336956 2336289 2379066 2409316 2360146 2343380 2395250 2417267 2373426 2331353 2343668 2389732 2327649 2506682 2511348 2536877 2566162 2512938 2574812 2601450 2539307 2489256 2535942 2571921 2506722 2474450 2509057 2538655 2513615 2924317 2937613 3024045 2988699 2972062 2990880 3031637 3001958 2849420 2875120 2888330 2839429 2856185 2880633 2911210 2887365
sum_num_searches_total=160137491
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2403723 2453585 2487678 2392600 2398972 2434112 2450081 2409711 2399573 2431231 2467706 2400174 2377032 2434599 2464114 2389738 2604458 2640759 2678529 2596436 2595662 2644227 2677259 2622434 2604032 2660453 2684733 2636266 2590780 2603324 2655018 2585982 2785406 2789521 2818480 2850946 2791969 2861264 2890692 2820932 2765284 2817062 2857015 2785502 2749729 2788010 2820342 2792987 3248923 3263653 3361404 3320868 3302468 3323659 3367738 3334937 3166227 3194909 3208787 3155127 3173612 3200712 3233664 3208197
average_num_operations_total=2780078
stdev_num_operations_total=39282
sum_num_operations_total=177925007
min_num_operations_total=2377032
max_num_operations_total=3367738
first_time_thread_terminate_by_thread=10000198 10000085 10000090 10000090 10000086 10000086 10000086 10000086 10000088 10000087 10000087 10000087 10000088 10000088 10000088 10000089 10000089 10000089 10000089 10000089 10000090 10000090 10000090 10000089 10000091 10000090 10000090 10000091 10000088 10000088 10000088 10000088 10000091 10000090 10000090 10000091 10000088 10000088 10000088 10000088 10000090 10000090 10000090 10000090 10000089 10000089 10000089 10000089 10000088 10000087 10000088 10000087 10000086 10000083 10000086 10000086 10000084 10000084 10000085 10000090 10000085 10000085 10000090 10000085
min_time_thread_terminate_total=10000083
max_time_thread_terminate_total=10000198
first_time_thread_start_by_thread=6 3 3 3 7 7 7 7 4 4 4 4 4 4 4 4 5 5 5 5 6 6 6 6 6 6 6 6 5 5 5 5 5 5 5 5 4 4 4 4 4 4 4 4 4 4 4 4 7 7 7 7 6 6 6 6 6 6 6 6 5 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=7
sum_duration_all_ops_total=
threads_final_keysum=999943466628
threads_final_size=999999
final_keysum=999943466628
final_size=999999
validate_result=success
Validation OK.
Structural validation OK.
total_find=160137491
total_rq=0
total_inserts=8898481
total_deletes=8889035
total_updates=17787516
total_queries=160137491
total_ops=177925007
find_throughput=16013749
rq_throughput=0
update_throughput=1778751
query_throughput=16013749
total_throughput=17792500
total find : 160137491
total rq : 0
total inserts : 8898481
total deletes : 8889035
total updates : 17787516
total queries : 160137491
total ops : 177925007
find throughput : 16013749
rq throughput : 0
update throughput : 1778751
query throughput : 16013749
total throughput : 17792500
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=17855716808132
total_execution_walltime=15.885s
REALTIME_MAIN_END_PERF_FORMAT=220980.931563587
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.98, faults_major=0, faults_minor=84657, mem_maxresident_kb=337184, user_cputime=979.37, sys_cputime=19.08, percent_cpu=6245%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.norec
thread_pinning=-pin 0-63,128-191,64-127,192-255
millis=10000
__trials=1