__step=000258
__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 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 2000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000258.txt
__path_data=data/data000258.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997662.93801483
binary=./brown_sigouin_int_avl_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_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=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=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,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=615277 prefilling_elapsed_millis=1.001s
prefilling_size=650241 prefilling_elapsed_millis=1.101s
prefilling_size=682370 prefilling_elapsed_millis=1.201s
prefilling_size=711485 prefilling_elapsed_millis=1.301s
prefilling_size=737352 prefilling_elapsed_millis=1.402s
prefilling_size=761049 prefilling_elapsed_millis=1.502s
prefilling_size=782807 prefilling_elapsed_millis=1.602s
prefilling_size=802526 prefilling_elapsed_millis=1.702s
prefilling_size=820050 prefilling_elapsed_millis=1.802s
prefilling_size=835831 prefilling_elapsed_millis=1.903s
prefilling_size=850836 prefilling_elapsed_millis=2.003s
prefilling_size=864129 prefilling_elapsed_millis=2.103s
prefilling_size=876179 prefilling_elapsed_millis=2.203s
prefilling_size=886769 prefilling_elapsed_millis=2.303s
prefilling_size=897257 prefilling_elapsed_millis=2.404s
prefilling_size=906570 prefilling_elapsed_millis=2.504s
prefilling_size=914665 prefilling_elapsed_millis=2.604s
prefilling_size=922451 prefilling_elapsed_millis=2.704s
prefilling_size=929204 prefilling_elapsed_millis=2.804s
prefilling_size=935570 prefilling_elapsed_millis=2.905s
prefilling_size=941492 prefilling_elapsed_millis=3.005s
prefilling_size=946958 prefilling_elapsed_millis=3.105s
prefilling_size=951559 prefilling_elapsed_millis=3.205s
prefilling_size=956002 prefilling_elapsed_millis=3.305s
prefilling_size=959803 prefilling_elapsed_millis=3.406s
prefilling_size=963486 prefilling_elapsed_millis=3.506s
prefilling_size=966883 prefilling_elapsed_millis=3.606s
prefilling_size=969439 prefilling_elapsed_millis=3.706s
prefilling_size=971645 prefilling_elapsed_millis=3.806s
prefilling_size=974197 prefilling_elapsed_millis=3.907s
prefilling_size=976074 prefilling_elapsed_millis=4.007s
prefilling_size=978680 prefilling_elapsed_millis=4.107s
prefilling_size=980612 prefilling_elapsed_millis=4.207s
finished prefilling to size 980608 for expected size 1000000 keysum=980643545705, performing 8309924 updates; total_prefilling_elapsed_ms=4308 ms)
pref_size=980608
pref_millis=4308
prefill_elapsed_ms=4335
main thread: starting timer...

###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################

REALTIME_START_PERF_FORMAT=6997666.603013248
REALTIME_END_PERF_FORMAT=6997676.603151925

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10s
PRODUCING OUTPUT
computing tree_stats in PARALLEL...
bounded depth BFS to partition into subtrees for parallel computation (192 threads)...
partitioned into 1024 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.038s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32760 64779 117072 157453 121779 42699 5443 142 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 7 678 10758 62435 147704 143732 53825 6874 172
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65457 127830 219888 269483 186431 59268 7016 172
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65457 127830 219888 269483 186431 59268 7016 172
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.99786 1.96324 1.80432 1.50949 1.23991 1.08887 1.02654 1.00428 1

tree_stats_height=24
tree_stats_numInternals=574894
tree_stats_numLeaves=426185
tree_stats_numNodes=1001079
tree_stats_numKeys=1001079

tree_stats_avgDegreeInternal=1.74133
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42573
tree_stats_avgKeyDepth=18.3279


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=281664
first_thread_announced_epoch_by_thread=12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512 12512
sum_commit_tx_reader_total=122972698
sum_commit_tx_reader_by_thread=0 2553982 2543926 2546186 2584978 2572546 2546847 2554909 2536463 2507027 2560183 2537474 2556523 2539753 2568608 2606997 2569696 2582128 2545206 2580116 2573094 2560309 2547082 2584904 2559021 2556785 2570314 2558500 2579660 2574036 2542315 2542558 2568677 2563401 2558901 2534005 2585587 2567615 2543621 2546047 2583871 2550195 2560078 2561955 2610425 2553586 2580234 2567856 2594518
sum_commit_tx_writer_total=6469087
sum_commit_tx_writer_by_thread=0 134133 133955 134186 135599 135072 133862 134901 134233 131565 135082 133167 134288 133197 135214 137260 135378 135666 133764 135772 135513 135392 133351 136055 134059 134663 135214 134896 136237 135574 133570 133616 134983 135301 134450 133438 135871 135083 133025 134002 135932 134626 134989 134432 137305 134054 135843 134776 136543
sum_abort_tx_total=1556
sum_abort_tx_by_thread=0 37 30 28 38 35 32 35 40 37 35 38 40 30 25 39 38 39 30 32 24 28 34 33 33 34 27 33 29 29 32 26 29 32 31 34 38 27 27 31 47 32 30 33 42 18 34 25 26
max_ht_capacity_by_thread=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=19.2:4 26.4:66 33.6:656 40.8:15643 48:29202 55.2:1808 62.4:492 69.6:102 76.8:21 84:5
    [       12.00,        19.20]: 4
    (       19.20,        26.40]: 66
    (       26.40,        33.60]: 656
    (       33.60,        40.80]: 15643
    (       40.80,        48.00]: 29202
    (       48.00,        55.20]: 1808
    (       55.20,        62.40]: 492
    (       62.40,        69.60]: 102
    (       69.60,        76.80]: 21
    (       76.80,        84.00]: 5

linear_histogram_of_none_tx_wrset_size_full_data=3.7:14265 6.4:3388 9.1:12247 11.8:4844 14.5:9036 17.2:3113 19.9:408 22.6:600 25.3:93 28:5
    [        1.00,         3.70]: 14265
    (        3.70,         6.40]: 3388
    (        6.40,         9.10]: 12247
    (        9.10,        11.80]: 4844
    (       11.80,        14.50]: 9036
    (       14.50,        17.20]: 3113
    (       17.20,        19.90]: 408
    (       19.90,        22.60]: 600
    (       22.60,        25.30]: 93
    (       25.30,        28.00]: 5
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=133319 133806 133101 135896 133296 137155 133597 134435 135751 135944 134235 134320 134242 135553 135955 134537 135379 134950 133831 134479 133837 135027 136439 134512 131552 133439 134992 135533 134006 134546 134875 135022 135942 136119 134765 134689 134525 135768 137486 136292 135557 134877 133605 134375 134053 137728 134739 133313
sum_num_inserts_total=6471394
sum_num_deletes_by_thread=133546 133830 133660 135004 133663 136331 133645 133432 134825 136056 133946 133832 133380 135275 134959 135024 135997 135468 133626 135277 134486 134858 135803 134356 132012 133183 133858 134895 134981 134838 135210 135037 135474 135985 134562 134815 134202 135124 137199 135530 135476 134850 133768 134794 134059 137037 135082 133340
sum_num_deletes_total=6465590
sum_num_searches_by_thread=2409309 2408249 2400682 2438710 2409687 2457575 2410639 2411103 2433246 2448577 2412191 2419963 2413087 2445060 2436704 2423835 2449583 2435110 2412976 2433904 2411726 2432813 2449216 2424483 2375028 2404019 2422598 2434646 2424093 2425881 2428617 2425642 2446378 2447699 2427060 2425563 2416094 2437715 2469572 2444075 2445044 2432905 2403323 2421642 2419528 2472965 2419989 2406297
sum_num_searches_total=116504801
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2676174 2675885 2667443 2709610 2676646 2731061 2677881 2678970 2703822 2720577 2680372 2688115 2680709 2715888 2707618 2693396 2720959 2705528 2680433 2703660 2680049 2702698 2721458 2693351 2638592 2670641 2691448 2705074 2693080 2695265 2698702 2695701 2717794 2719803 2696387 2695067 2684821 2708607 2744257 2715897 2716077 2702632 2670696 2690811 2687640 2747730 2689810 2672950
average_num_operations_total=2696703
stdev_num_operations_total=3027
sum_num_operations_total=129441785
min_num_operations_total=2638592
max_num_operations_total=2747730
first_time_thread_terminate_by_thread=10000138 10000138 10000139 10000138 10000138 10000138 10000138 10000138 10000139 10000138 10000139 10000138 10000139 10000138 10000139 10000139 10000138 10000139 10000139 10000138 10000139 10000139 10000138 10000139 10000138 10000139 10000139 10000138 10000138 10000138 10000139 10000139 10000139 10000138 10000139 10000138 10000138 10000138 10000138 10000139 10000138 10000139 10000139 10000139 10000138 10000138 10000139 10000138
min_time_thread_terminate_total=10000138
max_time_thread_terminate_total=10000139
first_time_thread_start_by_thread=3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3 3
min_time_thread_start_total=3
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=1000633070830
threads_final_size=1001079
final_keysum=1000633070830
final_size=1001079
validate_result=success
Validation OK.
Structural validation OK.

total_find=116504801
total_rq=0
total_inserts=6471394
total_deletes=6465590
total_updates=12936984
total_queries=116504801
total_ops=129441785
find_throughput=11650480
rq_throughput=0
update_throughput=1293698
query_throughput=11650480
total_throughput=12944178


total find                    : 116504801
total rq                      : 0
total inserts                 : 6471394
total deletes                 : 6465590
total updates                 : 12936984
total queries                 : 116504801
total ops                     : 129441785
find throughput               : 11650480
rq throughput                 : 0
update throughput             : 1293698
query throughput              : 11650480
total throughput              : 12944178

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=17.1286
PAPI_L3_TCM=1.58813
PAPI_TOT_CYC=8705.6
PAPI_TOT_INS=5867.34
garbage=56860957593996
total_execution_walltime=14.813s
REALTIME_MAIN_END_PERF_FORMAT=6997676.908283252
noREC system shutdown:
[time_cmd_output] time_elapsed_sec=14.95, faults_major=0, faults_minor=145170, mem_maxresident_kb=582460, user_cputime=691.49, sys_cputime=29.73, percent_cpu=4823%
timeout=false
algorithm=brown_sigouin_int_avl_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