__step=000234
__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.rhnorec_post -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=data000234.txt
__path_data=data/data000234.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997335.716135027
binary=./brown_int_bst_tm_auto.debra.rhnorec_post
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=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=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


RH noREC w/postfix htm system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=393048 prefilling_elapsed_millis=1.001s
prefilling_size=421928 prefilling_elapsed_millis=1.101s
prefilling_size=449932 prefilling_elapsed_millis=1.201s
prefilling_size=476383 prefilling_elapsed_millis=1.301s
prefilling_size=501315 prefilling_elapsed_millis=1.402s
prefilling_size=525103 prefilling_elapsed_millis=1.502s
prefilling_size=547712 prefilling_elapsed_millis=1.602s
prefilling_size=569049 prefilling_elapsed_millis=1.702s
prefilling_size=589934 prefilling_elapsed_millis=1.802s
prefilling_size=609219 prefilling_elapsed_millis=1.903s
prefilling_size=627685 prefilling_elapsed_millis=2.003s
prefilling_size=645525 prefilling_elapsed_millis=2.103s
prefilling_size=662818 prefilling_elapsed_millis=2.203s
prefilling_size=678952 prefilling_elapsed_millis=2.303s
prefilling_size=694268 prefilling_elapsed_millis=2.404s
prefilling_size=709053 prefilling_elapsed_millis=2.504s
prefilling_size=723216 prefilling_elapsed_millis=2.604s
prefilling_size=736122 prefilling_elapsed_millis=2.704s
prefilling_size=748815 prefilling_elapsed_millis=2.804s
prefilling_size=760687 prefilling_elapsed_millis=2.905s
prefilling_size=771666 prefilling_elapsed_millis=3.005s
prefilling_size=782406 prefilling_elapsed_millis=3.105s
prefilling_size=792311 prefilling_elapsed_millis=3.205s
prefilling_size=802116 prefilling_elapsed_millis=3.305s
prefilling_size=811574 prefilling_elapsed_millis=3.406s
prefilling_size=820751 prefilling_elapsed_millis=3.506s
prefilling_size=829261 prefilling_elapsed_millis=3.606s
prefilling_size=837296 prefilling_elapsed_millis=3.706s
prefilling_size=845077 prefilling_elapsed_millis=3.806s
prefilling_size=852194 prefilling_elapsed_millis=3.907s
prefilling_size=859279 prefilling_elapsed_millis=4.007s
prefilling_size=865925 prefilling_elapsed_millis=4.107s
prefilling_size=872297 prefilling_elapsed_millis=4.207s
prefilling_size=878511 prefilling_elapsed_millis=4.307s
prefilling_size=884120 prefilling_elapsed_millis=4.408s
prefilling_size=890261 prefilling_elapsed_millis=4.508s
prefilling_size=895100 prefilling_elapsed_millis=4.608s
prefilling_size=900462 prefilling_elapsed_millis=4.708s
prefilling_size=905230 prefilling_elapsed_millis=4.808s
prefilling_size=909486 prefilling_elapsed_millis=4.909s
prefilling_size=913729 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 86270 keys with 48 threads...
timing_elapsed 0.181107s
prefilling_size=1000000 prefilling_elapsed_millis=0.181s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000318847817, performing 5111184 updates; total_prefilling_elapsed_ms=181 ms)
pref_size=1000000
pref_millis=181
prefill_elapsed_ms=181
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=256

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997341.212154566
REALTIME_END_PERF_FORMAT=6997351.212227382

###############################################################################
################################## 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 256 subtrees; running parallel for...
computing stats for the top of the tree (above the partitions)...

tree_stats_computeWalltime=0.019s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 254 494 913 1670 2930 4945 7950 12219 17734 24506 32437 40639 48026 53743 57225 57875 55518 50937 44866 37277 29781 22926 16704 11473 7689 4971 3067 1826 976 514 274 117 51 27 15 6 2 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 6 37 81 230 496 1077 2042 3685 5953 9027 13021 17919 22650 26833 30024 32105 31824 30240 27674 23557 19107 15255 11499 7895 5436 3522 2258 1398 731 401 223 94 38 18 16 6 2 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 500 950 1751 3160 5441 9027 14261 21419 30459 41464 53660 65945 76393 84058 87899 87623 82761 75106 64951 53338 42033 31959 22972 15584 10407 6589 4084 2374 1245 675 340 145 65 33 22 8 3 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 500 950 1751 3160 5441 9027 14261 21419 30459 41464 53660 65945 76393 84058 87899 87623 82761 75106 64951 53338 42033 31959 22972 15584 10407 6589 4084 2374 1245 675 340 145 65 33 22 8 3 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 1.96094 1.912 1.88211 1.85094 1.79462 1.75023 1.69912 1.64512 1.5941 1.55675 1.51184 1.4716 1.43016 1.39683 1.36491 1.33843 1.31091 1.29203 1.26742 1.24728 1.2297 1.2149 1.19613 1.17896 1.17441 1.15547 1.15435 1.13418 1.11331 1.12932 1.09778 1.08235 1.09655 1.09231 1.21212 1.09091 1.125 1 1

tree_stats_height=47
tree_stats_numInternals=652833
tree_stats_numLeaves=346383
tree_stats_numNodes=999216
tree_stats_numKeys=999216

tree_stats_avgDegreeInternal=1.53058
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34665
tree_stats_avgKeyDepth=23.5774

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=7211


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:189 2:4986 4:108657 8:210354 16:9542
    [2^00, 2^01]: 189
    (2^01, 2^02]: 4986
    (2^02, 2^03]: 108657
    (2^03, 2^04]: 210354
    (2^04, 2^05]: 9542
sum_limbo_reclamation_event_size_total=2967990
count_limbo_reclamation_event_size_by_thread=6953 6953 6951 6952 6952 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6953 6952 6952 6953 6952 6953 6951 6953 6952 6952 6951 6953 6953 6953 6953 6953 6953 6952 6953 6953 6952 6952 6953 6953
count_limbo_reclamation_event_size_total=333728
sum_limbo_reclamation_event_count_total=333854
first_thread_announced_epoch_by_thread=14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422 14422
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=

linear_histogram_of_none_tx_rdset_size_full_data=

linear_histogram_of_none_tx_wrset_size_full_data=
sum_fasthtm_commit_total=114720488
sum_fasthtm_abort_total=167889171
sum_htmpostfix_commit_total=4054636
sum_htmpostfix_abort_total=4354219
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=53852678
sum_stmprefix_commit_total=56
sum_slow_commit_total=23379
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=123114 124410 125958 122066 120967 121128 123140 124913 126360 123654 123136 123136 125786 123933 126043 122979 123605 121493 124506 125705 125756 124531 122169 122866 123733 124296 125499 122563 121108 122032 123429 124736 125708 122739 123752 122223 124674 124684 125349 122784 123170 121975 124406 125193 125896 123212 121671 122975
sum_num_inserts_total=5939161
sum_num_deletes_by_thread=123139 124687 126442 122616 122132 121596 122794 125297 125818 122998 123662 123179 124809 123822 125713 123666 123493 121520 125395 124901 125871 124011 122031 123108 122682 124400 125208 122075 120639 122215 122932 125077 126092 123214 122733 122593 124802 123492 125226 122769 123733 121765 124443 125491 125673 124226 121850 122109
sum_num_deletes_total=5938139
sum_num_searches_by_thread=2217971 2234990 2273711 2203364 2188971 2183828 2217576 2248719 2262056 2217691 2219087 2217443 2255076 2227780 2265254 2219654 2237382 2195714 2244755 2256437 2263553 2235462 2205006 2211997 2220410 2235091 2259303 2204005 2176646 2196776 2210835 2250460 2266854 2218263 2213640 2208222 2243234 2240009 2249117 2214255 2222519 2197617 2240544 2257342 2265954 2220241 2196314 2210131
sum_num_searches_total=106921259
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2464224 2484087 2526111 2448046 2432070 2426552 2463510 2498929 2514234 2464343 2465885 2463758 2505671 2475535 2517010 2466299 2484480 2438727 2494656 2507043 2515180 2484004 2449206 2457971 2466825 2483787 2510010 2448643 2418393 2441023 2457196 2500273 2518654 2464216 2460125 2453038 2492710 2488185 2499692 2459808 2469422 2441357 2489393 2508026 2517523 2467679 2439835 2455215
average_num_operations_total=2474969
stdev_num_operations_total=4032
sum_num_operations_total=118798559
min_num_operations_total=2418393
max_num_operations_total=2526111
first_time_thread_terminate_by_thread=10000120 10000074 10000085 10000089 10000074 10000109 10000108 10000078 10000072 10000072 10000103 10000089 10000073 10000103 10000072 10000107 10000086 10000073 10000101 10000092 10000093 10000101 10000098 10000077 10000072 10000094 10000104 10000072 10000082 10000081 10000097 10000105 10000072 10000072 10000099 10000072 10000087 10000092 10000072 10000080 10000106 10000096 10000110 10000072 10000072 10000084 10000095 10000072
min_time_thread_terminate_total=10000072
max_time_thread_terminate_total=10000120
first_time_thread_start_by_thread=9 5 5 5 5 5 6 6 5 5 6 5 6 5 5 5 5 6 5 5 5 5 5 5 4 5 5 5 5 5 6 6 5 5 6 5 6 5 5 5 5 6 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=998539231412
threads_final_size=999216
final_keysum=998539231412
final_size=999216
validate_result=success
Validation OK.
Structural validation OK.

total_find=106921259
total_rq=0
total_inserts=5939161
total_deletes=5938139
total_updates=11877300
total_queries=106921259
total_ops=118798559
find_throughput=10692125
rq_throughput=0
update_throughput=1187730
query_throughput=10692125
total_throughput=11879855


total find                    : 106921259
total rq                      : 0
total inserts                 : 5939161
total deletes                 : 5938139
total updates                 : 11877300
total queries                 : 106921259
total ops                     : 118798559
find throughput               : 10692125
rq throughput                 : 0
update throughput             : 1187730
query throughput              : 10692125
total throughput              : 11879855

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=20.05
PAPI_L3_TCM=1.67349
PAPI_TOT_CYC=10371.2
PAPI_TOT_INS=2820.04
garbage=53440412089911
total_execution_walltime=15.665s
REALTIME_MAIN_END_PERF_FORMAT=6997351.382079818
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=15.82, faults_major=0, faults_minor=120628, mem_maxresident_kb=483420, user_cputime=724.28, sys_cputime=21.69, percent_cpu=4715%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.rhnorec_post
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2