__step=000114
__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 2000000 -t 10000 -pin 0-63,128-191,64-127,192-255
__file_data=data000114.txt
__path_data=data/data000114.txt
__hostname=nasus
REALTIME_MAIN_START_PERF_FORMAT=221206.279408626
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=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=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.024s
prefilling_size=311621 prefilling_elapsed_millis=1s
prefilling_size=336064 prefilling_elapsed_millis=1.101s
prefilling_size=359616 prefilling_elapsed_millis=1.201s
prefilling_size=381898 prefilling_elapsed_millis=1.301s
prefilling_size=403836 prefilling_elapsed_millis=1.401s
prefilling_size=425002 prefilling_elapsed_millis=1.501s
prefilling_size=445564 prefilling_elapsed_millis=1.601s
prefilling_size=465259 prefilling_elapsed_millis=1.701s
prefilling_size=484270 prefilling_elapsed_millis=1.801s
prefilling_size=502285 prefilling_elapsed_millis=1.901s
prefilling_size=520052 prefilling_elapsed_millis=2.002s
prefilling_size=536866 prefilling_elapsed_millis=2.102s
prefilling_size=553150 prefilling_elapsed_millis=2.202s
prefilling_size=568933 prefilling_elapsed_millis=2.302s
prefilling_size=584411 prefilling_elapsed_millis=2.402s
prefilling_size=599125 prefilling_elapsed_millis=2.502s
prefilling_size=613334 prefilling_elapsed_millis=2.602s
prefilling_size=626627 prefilling_elapsed_millis=2.702s
prefilling_size=639787 prefilling_elapsed_millis=2.802s
prefilling_size=652642 prefilling_elapsed_millis=2.903s
prefilling_size=665019 prefilling_elapsed_millis=3.003s
prefilling_size=676773 prefilling_elapsed_millis=3.103s
prefilling_size=687921 prefilling_elapsed_millis=3.203s
prefilling_size=698461 prefilling_elapsed_millis=3.304s
prefilling_size=708970 prefilling_elapsed_millis=3.404s
prefilling_size=719278 prefilling_elapsed_millis=3.504s
prefilling_size=729091 prefilling_elapsed_millis=3.604s
prefilling_size=738452 prefilling_elapsed_millis=3.704s
prefilling_size=747801 prefilling_elapsed_millis=3.804s
prefilling_size=756253 prefilling_elapsed_millis=3.904s
prefilling_size=764930 prefilling_elapsed_millis=4.004s
prefilling_size=773164 prefilling_elapsed_millis=4.105s
prefilling_size=781287 prefilling_elapsed_millis=4.205s
prefilling_size=788877 prefilling_elapsed_millis=4.305s
prefilling_size=796184 prefilling_elapsed_millis=4.405s
prefilling_size=803592 prefilling_elapsed_millis=4.505s
prefilling_size=810633 prefilling_elapsed_millis=4.605s
prefilling_size=817385 prefilling_elapsed_millis=4.705s
prefilling_size=823952 prefilling_elapsed_millis=4.805s
prefilling_size=830227 prefilling_elapsed_millis=4.906s
prefilling_size=836422 prefilling_elapsed_millis=5.006s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 163585 keys with 64 threads...
tid=0 thread_prefill_amount=21875 percent_done_estimate=855.8 elapsed_s=5 est_remaining_s=-10 / -5
timing_elapsed 0.384934s
prefilling_size=1000000 prefilling_elapsed_millis=0.404s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000436009103, performing 3970655 updates; total_prefilling_elapsed_ms=404 ms)
pref_size=1000000
pref_millis=404
prefill_elapsed_ms=404
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=221211.846875292
REALTIME_END_PERF_FORMAT=221221.846942997
###############################################################################
################################## 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.018s
tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16383 32758 64875 117092 156333 120550 43450 5657 150 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 1 7 589 11012 63445 146828 142031 54774 7120 182
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32765 65464 128104 219778 267378 185481 60431 7270 182
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32765 65464 128104 219778 267378 185481 60431 7270 182
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99988 1.9982 1.96586 1.80158 1.50526 1.24284 1.09155 1.02669 1.0044 1
tree_stats_height=24
tree_stats_numInternals=573631
tree_stats_numLeaves=425989
tree_stats_numNodes=999620
tree_stats_numKeys=999620
tree_stats_avgDegreeInternal=1.74262
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42615
tree_stats_avgKeyDepth=18.3286
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=337147
first_thread_announced_epoch_by_thread=10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934 10934
sum_commit_tx_reader_total=176090464
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 3151074 3114095 3074796 3052458 2993273 2778992 2519614 2516827 2818241 3032652 2763174 3058418 2769762 3086368 2784590 2783432 3037680 3110309 3041306 2784288 2667504 2629052 3107774 2692763 2759245 2829998 2783797 2687572 2645344 2647853 2591942 3027167 2638480 2448787 2522574 2509456 2482185 2505491 2792825 2506846 2665898 2637675 2621410 2529906 2494533 2483555 2489687 2499913 2685734 2480543 2525899 2685082 2804617 2549418 2613467 2771713 2643507 2658764 2785468 2774814 2665726 3032694 3085482 3156955
sum_commit_tx_writer_total=9264686
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 166085 164567 160574 160952 157180 146878 132144 132142 148358 158904 145204 160664 146587 162713 146666 146844 159595 163478 160086 146156 140387 138416 163418 142235 145502 148655 146129 141981 138748 139522 136740 159701 138632 129179 132480 131535 131503 131681 146551 132082 140043 138609 137923 133397 130904 130702 130184 131250 140836 130800 133073 140704 148059 133824 136921 145636 139832 139292 147415 146101 139787 159750 162372 166418
sum_abort_tx_total=3981
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 54 72 54 56 67 58 64 70 69 65 70 59 61 73 52 66 62 61 70 64 67 57 68 61 72 60 56 63 57 54 70 65 64 61 66 50 64 66 70 69 76 49 67 62 62 58 64 61 54 57 55 62 65 57 49 72 69 62 52 56 78 65 54 48
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.9:14 29.8:229 38.7:9011 47.6:51361 56.5:2613 65.4:608 74.3:111 83.2:32 92.1:15 101:5
[ 12.00, 20.90]: 14
( 20.90, 29.80]: 229
( 29.80, 38.70]: 9011
( 38.70, 47.60]: 51361
( 47.60, 56.50]: 2613
( 56.50, 65.40]: 608
( 65.40, 74.30]: 111
( 74.30, 83.20]: 32
( 83.20, 92.10]: 15
( 92.10, 101.00]: 5
linear_histogram_of_none_tx_wrset_size_full_data=3.9:19229 6.8:4542 9.7:14956 12.6:7684 15.5:11593 18.4:4511 21.3:926 24.2:507 27.1:50 30:1
[ 1.00, 3.90]: 19229
( 3.90, 6.80]: 4542
( 6.80, 9.70]: 14956
( 9.70, 12.60]: 7684
( 12.60, 15.50]: 11593
( 15.50, 18.40]: 4511
( 18.40, 21.30]: 926
( 21.30, 24.20]: 507
( 24.20, 27.10]: 50
( 27.10, 30.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=162650 166291 163235 162690 163427 164760 161140 166726 159712 160301 160188 160631 158885 157605 160262 159816 145925 147131 146733 147657 147891 146138 149203 145783 146895 145187 146262 146398 142293 146876 145212 146299 139152 140694 140536 141608 137993 141165 140418 138719 138208 139463 138851 140032 136612 136711 138687 140890 131308 131852 133198 131558 130149 128958 132241 130978 130787 131675 132755 130884 131490 132662 133834 132922
sum_num_inserts_total=9267192
sum_num_deletes_by_thread=162292 166498 163705 162720 164060 163690 160938 165349 160071 159984 160651 160794 159432 156741 160305 159299 145675 147303 147012 148511 148028 146046 149259 145874 146543 145891 146031 146928 141666 146087 145277 146546 138778 140302 140318 140994 138107 140985 139864 138831 138453 139398 140048 138997 136847 137621 139000 141581 131133 131736 133784 132110 130654 129357 132077 130626 130326 130904 132989 131666 132818 133484 134120 132215
sum_num_deletes_total=9269329
sum_num_searches_by_thread=2922912 2990584 2946847 2923671 2943705 2950212 2913292 2985084 2872661 2876990 2880553 2897657 2873239 2836107 2892843 2867753 2629315 2638449 2645631 2670431 2656757 2637742 2680191 2625692 2637818 2617300 2624056 2636950 2551039 2637481 2614258 2633025 2506162 2524945 2527037 2546951 2483233 2543636 2525231 2499562 2490807 2519195 2504440 2508346 2455223 2476056 2498597 2544099 2351247 2377403 2396321 2373504 2359068 2319651 2384651 2352653 2350230 2368584 2389310 2362887 2374620 2392826 2415288 2386621
sum_num_searches_total=166818629
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3247854 3323373 3273787 3249081 3271192 3278662 3235370 3317159 3192444 3197275 3201392 3219082 3191556 3150453 3213410 3186868 2920915 2932883 2939376 2966599 2952676 2929926 2978653 2917349 2931256 2908378 2916349 2930276 2834998 2930444 2904747 2925870 2784092 2805941 2807891 2829553 2759333 2825786 2805513 2777112 2767468 2798056 2783339 2787375 2728682 2750388 2776284 2826570 2613688 2640991 2663303 2637172 2619871 2577966 2648969 2614257 2611343 2631163 2655054 2625437 2638928 2658972 2683242 2651758
average_num_operations_total=2896174
stdev_num_operations_total=28339
sum_num_operations_total=185355150
min_num_operations_total=2577966
max_num_operations_total=3323373
first_time_thread_terminate_by_thread=10000105 10000068 10000068 10000068 10000072 10000066 10000066 10000072 10000066 10000070 10000067 10000066 10000070 10000070 10000070 10000070 10000070 10000070 10000070 10000070 10000071 10000071 10000071 10000071 10000069 10000069 10000069 10000069 10000071 10000070 10000071 10000071 10000070 10000067 10000070 10000067 10000070 10000070 10000071 10000070 10000069 10000069 10000069 10000069 10000069 10000068 10000068 10000068 10000071 10000071 10000068 10000068 10000072 10000072 10000072 10000072 10000071 10000072 10000072 10000072 10000071 10000071 10000071 10000071
min_time_thread_terminate_total=10000066
max_time_thread_terminate_total=10000105
first_time_thread_start_by_thread=5 2 2 2 3 3 3 3 4 4 4 4 4 4 4 4 6 7 6 7 5 5 5 5 5 5 5 5 5 5 5 5 4 4 4 4 6 6 6 6 6 6 6 6 5 5 5 5 4 4 4 4 5 6 5 6 6 6 6 6 3 3 3 3
min_time_thread_start_total=2
max_time_thread_start_total=7
sum_duration_all_ops_total=
threads_final_keysum=999347300872
threads_final_size=999620
final_keysum=999347300872
final_size=999620
validate_result=success
Validation OK.
Structural validation OK.
total_find=166818629
total_rq=0
total_inserts=9267192
total_deletes=9269329
total_updates=18536521
total_queries=166818629
total_ops=185355150
find_throughput=16681862
rq_throughput=0
update_throughput=1853652
query_throughput=16681862
total_throughput=18535515
total find : 166818629
total rq : 0
total inserts : 9267192
total deletes : 9269329
total updates : 18536521
total queries : 166818629
total ops : 185355150
find throughput : 16681862
rq throughput : 0
update throughput : 1853652
query throughput : 16681862
total throughput : 18535515
elapsed milliseconds : 10000
napping milliseconds overtime : 100
garbage=26614200287933
total_execution_walltime=15.842s
REALTIME_MAIN_END_PERF_FORMAT=221222.122345518
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=15.96, faults_major=0, faults_minor=148966, mem_maxresident_kb=593996, user_cputime=977.23, sys_cputime=19.16, percent_cpu=6239%
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=2