__step=000233
__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=data000233.txt
__path_data=data/data000233.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997319.840131735
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.007s
prefilling_size=388593 prefilling_elapsed_millis=1.001s
prefilling_size=417782 prefilling_elapsed_millis=1.101s
prefilling_size=445350 prefilling_elapsed_millis=1.201s
prefilling_size=471703 prefilling_elapsed_millis=1.301s
prefilling_size=496397 prefilling_elapsed_millis=1.402s
prefilling_size=520140 prefilling_elapsed_millis=1.502s
prefilling_size=542331 prefilling_elapsed_millis=1.602s
prefilling_size=563682 prefilling_elapsed_millis=1.702s
prefilling_size=583876 prefilling_elapsed_millis=1.802s
prefilling_size=603356 prefilling_elapsed_millis=1.903s
prefilling_size=622111 prefilling_elapsed_millis=2.003s
prefilling_size=639991 prefilling_elapsed_millis=2.103s
prefilling_size=657024 prefilling_elapsed_millis=2.203s
prefilling_size=673074 prefilling_elapsed_millis=2.303s
prefilling_size=688518 prefilling_elapsed_millis=2.404s
prefilling_size=702764 prefilling_elapsed_millis=2.504s
prefilling_size=716685 prefilling_elapsed_millis=2.604s
prefilling_size=730187 prefilling_elapsed_millis=2.704s
prefilling_size=742951 prefilling_elapsed_millis=2.804s
prefilling_size=754712 prefilling_elapsed_millis=2.905s
prefilling_size=766407 prefilling_elapsed_millis=3.005s
prefilling_size=776915 prefilling_elapsed_millis=3.105s
prefilling_size=787611 prefilling_elapsed_millis=3.205s
prefilling_size=797749 prefilling_elapsed_millis=3.305s
prefilling_size=807396 prefilling_elapsed_millis=3.406s
prefilling_size=816234 prefilling_elapsed_millis=3.506s
prefilling_size=824657 prefilling_elapsed_millis=3.606s
prefilling_size=832879 prefilling_elapsed_millis=3.706s
prefilling_size=840735 prefilling_elapsed_millis=3.807s
prefilling_size=847915 prefilling_elapsed_millis=3.907s
prefilling_size=855155 prefilling_elapsed_millis=4.007s
prefilling_size=861904 prefilling_elapsed_millis=4.107s
prefilling_size=868266 prefilling_elapsed_millis=4.207s
prefilling_size=875125 prefilling_elapsed_millis=4.308s
prefilling_size=880897 prefilling_elapsed_millis=4.408s
prefilling_size=886200 prefilling_elapsed_millis=4.508s
prefilling_size=891577 prefilling_elapsed_millis=4.608s
prefilling_size=896870 prefilling_elapsed_millis=4.708s
prefilling_size=902041 prefilling_elapsed_millis=4.809s
prefilling_size=906774 prefilling_elapsed_millis=4.909s
prefilling_size=910938 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 89047 keys with 48 threads...
timing_elapsed 0.191955s
prefilling_size=1000000 prefilling_elapsed_millis=0.192s
finished prefilling to size 1000000 for expected size 1000000 keysum=999767863193, performing 5047011 updates; total_prefilling_elapsed_ms=192 ms)
pref_size=1000000
pref_millis=192
prefill_elapsed_ms=192
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=6997325.324154790
REALTIME_END_PERF_FORMAT=6997335.324233865

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

tree_stats_computeWalltime=0.015s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 127 251 484 904 1644 2900 4904 7909 12294 17993 24986 32932 41351 49117 55443 59041 59489 56525 51254 44238 36197 28593 21536 15479 10732 7046 4469 2749 1628 935 542 275 165 90 42 14 2 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 1 0 10 42 82 227 492 1064 1896 3601 5894 9178 13220 17984 22898 27465 30884 33061 32490 30524 27366 23019 18613 14275 10509 7541 4974 3188 1981 1188 688 416 189 125 71 34 14 1 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 251 494 946 1726 3127 5396 8973 14190 21594 30880 42110 54571 67101 78341 86506 90373 89586 83744 74762 63563 51612 40149 29754 21241 14587 9443 5937 3609 2123 1230 691 354 215 113 48 16 3 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 251 494 946 1726 3127 5396 8973 14190 21594 30880 42110 54571 67101 78341 86506 90373 89586 83744 74762 63563 51612 40149 29754 21241 14587 9443 5937 3609 2123 1230 691 354 215 113 48 16 3 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.96875 1.96813 1.93522 1.86892 1.85921 1.79821 1.75408 1.69999 1.65539 1.59679 1.55453 1.51387 1.47186 1.43552 1.39651 1.36219 1.33303 1.30383 1.28071 1.25849 1.24252 1.2239 1.20469 1.19365 1.18149 1.16432 1.15546 1.14485 1.13716 1.13895 1.12114 1.11433 1.14124 1.10698 1.0531 1.04167 1.0625 1 1

tree_stats_height=46
tree_stats_numInternals=654409
tree_stats_numLeaves=345207
tree_stats_numNodes=999616
tree_stats_numKeys=999616

tree_stats_avgDegreeInternal=1.52751
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34534
tree_stats_avgKeyDepth=23.4747

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:67 2:1560 4:53110 8:370177 16:54962 32:1
    [2^00, 2^01]: 67
    (2^01, 2^02]: 1560
    (2^02, 2^03]: 53110
    (2^03, 2^04]: 370177
    (2^04, 2^05]: 54962
    (2^05, 2^06]: 1
sum_limbo_reclamation_event_size_total=5486948
count_limbo_reclamation_event_size_by_thread=9997 9998 9997 9997 9997 9997 9997 9998 9998 9998 9998 9997 9996 9998 9997 9997 9998 9997 9998 9998 9998 9998 9998 9996 9998 9998 9998 9998 9998 9997 9998 9996 9997 9997 9998 9997 9997 9998 9997 9998 9997 9997 9998 9998 9996 9998 9998 9997
count_limbo_reclamation_event_size_total=479877
sum_limbo_reclamation_event_count_total=952481
first_thread_announced_epoch_by_thread=40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198 40198
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=435757824
sum_fasthtm_abort_total=28898186
sum_htmpostfix_commit_total=1377
sum_htmpostfix_abort_total=27231
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=2734
sum_stmprefix_commit_total=12
sum_slow_commit_total=678
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=450092 448665 456586 454994 451779 451571 445486 454118 462052 458826 454313 453791 444976 456039 449345 460097 458685 454604 450336 456177 456051 454986 453398 449966 450516 452949 452427 456016 450927 451169 450740 453391 459924 461464 453535 449620 448086 456078 458120 458543 459041 452650 448644 456140 453940 460523 455192 453484
sum_num_inserts_total=21790052
sum_num_deletes_by_thread=450158 447314 456548 455047 451971 452398 446103 454413 461573 458538 452881 452124 446013 456467 450085 459653 458250 453360 449962 455191 456278 456208 452229 450677 450539 452693 453335 455177 450114 450390 452588 453841 459319 459761 454156 450283 446652 456067 458588 458154 458417 452586 448402 456388 454665 461237 453657 454403
sum_num_deletes_total=21784853
sum_num_searches_by_thread=8101959 8068057 8215265 8207113 8118165 8119246 8021759 8181869 8310473 8275192 8158766 8153840 8024266 8206921 8106452 8271429 8257723 8155816 8092203 8186451 8217309 8213215 8148554 8103683 8107223 8156639 8149604 8223973 8106854 8119832 8115118 8157625 8268967 8310086 8166714 8091595 8054340 8196447 8255744 8244637 8263920 8164595 8085452 8199379 8186021 8293888 8182218 8168389
sum_num_searches_total=392184986
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=9002209 8964036 9128399 9117154 9021915 9023215 8913348 9090400 9234098 9192556 9065960 9059755 8915255 9119427 9005882 9191179 9174658 9063780 8992501 9097819 9129638 9124409 9054181 9004326 9008278 9062281 9055366 9135166 9007895 9021391 9018446 9064857 9188210 9231311 9074405 8991498 8949078 9108592 9172452 9161334 9181378 9069831 8982498 9111907 9094626 9215648 9091067 9076276
average_num_operations_total=9078331
stdev_num_operations_total=11772
sum_num_operations_total=435759891
min_num_operations_total=8913348
max_num_operations_total=9234098
first_time_thread_terminate_by_thread=10000136 10000079 10000078 10000078 10000078 10000078 10000078 10000078 10000079 10000078 10000079 10000078 10000078 10000078 10000079 10000079 10000078 10000079 10000079 10000078 10000078 10000078 10000078 10000079 10000078 10000079 10000078 10000078 10000078 10000078 10000078 10000078 10000079 10000078 10000079 10000078 10000078 10000078 10000079 10000079 10000078 10000079 10000079 10000078 10000078 10000078 10000078 10000079
min_time_thread_terminate_total=10000078
max_time_thread_terminate_total=10000136
first_time_thread_start_by_thread=9 5 6 5 5 5 6 5 6 6 5 5 6 6 6 6 6 5 6 6 6 5 6 5 5 5 6 5 5 5 6 5 6 6 5 5 6 6 6 6 6 5 6 6 6 5 6 5
min_time_thread_start_total=5
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=998982077144
threads_final_size=999616
final_keysum=998982077144
final_size=999616
validate_result=success
Validation OK.
Structural validation OK.

total_find=392184986
total_rq=0
total_inserts=21790052
total_deletes=21784853
total_updates=43574905
total_queries=392184986
total_ops=435759891
find_throughput=39218498
rq_throughput=0
update_throughput=4357490
query_throughput=39218498
total_throughput=43575989


total find                    : 392184986
total rq                      : 0
total inserts                 : 21790052
total deletes                 : 21784853
total updates                 : 43574905
total queries                 : 392184986
total ops                     : 435759891
find throughput               : 39218498
rq throughput                 : 0
update throughput             : 4357490
query throughput              : 39218498
total throughput              : 43575989

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=10.3993
PAPI_L3_TCM=1.13771
PAPI_TOT_CYC=2993.79
PAPI_TOT_INS=1163.43
garbage=196068158996658
total_execution_walltime=15.667s
REALTIME_MAIN_END_PERF_FORMAT=6997335.507735407
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=15.80, faults_major=0, faults_minor=98819, mem_maxresident_kb=396292, user_cputime=734.86, sys_cputime=9.64, percent_cpu=4709%
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=1