__step=000274
__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.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=data000274.txt
__path_data=data/data000274.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997926.588347615
binary=./brown_sigouin_int_avl_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_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


RH noREC w/postfix htm system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.004s
prefilling_size=238986 prefilling_elapsed_millis=1.001s
prefilling_size=259935 prefilling_elapsed_millis=1.101s
prefilling_size=280317 prefilling_elapsed_millis=1.201s
prefilling_size=300186 prefilling_elapsed_millis=1.301s
prefilling_size=319316 prefilling_elapsed_millis=1.402s
prefilling_size=338276 prefilling_elapsed_millis=1.502s
prefilling_size=356444 prefilling_elapsed_millis=1.602s
prefilling_size=374245 prefilling_elapsed_millis=1.702s
prefilling_size=391754 prefilling_elapsed_millis=1.802s
prefilling_size=409013 prefilling_elapsed_millis=1.903s
prefilling_size=425548 prefilling_elapsed_millis=2.003s
prefilling_size=441783 prefilling_elapsed_millis=2.103s
prefilling_size=457448 prefilling_elapsed_millis=2.203s
prefilling_size=473003 prefilling_elapsed_millis=2.303s
prefilling_size=487936 prefilling_elapsed_millis=2.404s
prefilling_size=502188 prefilling_elapsed_millis=2.504s
prefilling_size=516205 prefilling_elapsed_millis=2.604s
prefilling_size=529899 prefilling_elapsed_millis=2.704s
prefilling_size=543298 prefilling_elapsed_millis=2.804s
prefilling_size=556216 prefilling_elapsed_millis=2.905s
prefilling_size=568885 prefilling_elapsed_millis=3.005s
prefilling_size=581167 prefilling_elapsed_millis=3.105s
prefilling_size=592955 prefilling_elapsed_millis=3.205s
prefilling_size=604482 prefilling_elapsed_millis=3.305s
prefilling_size=615813 prefilling_elapsed_millis=3.406s
prefilling_size=626735 prefilling_elapsed_millis=3.506s
prefilling_size=637264 prefilling_elapsed_millis=3.606s
prefilling_size=647699 prefilling_elapsed_millis=3.706s
prefilling_size=658024 prefilling_elapsed_millis=3.806s
prefilling_size=667850 prefilling_elapsed_millis=3.907s
prefilling_size=677447 prefilling_elapsed_millis=4.007s
prefilling_size=686862 prefilling_elapsed_millis=4.107s
prefilling_size=695984 prefilling_elapsed_millis=4.207s
prefilling_size=704704 prefilling_elapsed_millis=4.307s
prefilling_size=713343 prefilling_elapsed_millis=4.408s
prefilling_size=721795 prefilling_elapsed_millis=4.508s
prefilling_size=730050 prefilling_elapsed_millis=4.608s
prefilling_size=737829 prefilling_elapsed_millis=4.708s
prefilling_size=745655 prefilling_elapsed_millis=4.808s
prefilling_size=753066 prefilling_elapsed_millis=4.909s
prefilling_size=760714 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 239253 keys with 48 threads...
tid=0 thread_prefill_amount=16664 percent_done_estimate=334.3 elapsed_s=5 est_remaining_s=-9 / -3
timing_elapsed 0.872372s
prefilling_size=1000000 prefilling_elapsed_millis=0.874s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000067356552, performing 3302466 updates; total_prefilling_elapsed_ms=874 ms)
pref_size=1000000
pref_millis=874
prefill_elapsed_ms=874
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997932.768158296
REALTIME_END_PERF_FORMAT=6997942.768238427

###############################################################################
################################## 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.007s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32753 64673 116341 154792 121429 45301 5719 94 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 749 11254 63238 143786 141706 57362 7220 118
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65422 127595 218030 265215 187007 63081 7314 118
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65422 127595 218030 265215 187007 63081 7314 118
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99698 1.96179 1.79697 1.50646 1.24726 1.09508 1.02528 1.00328 1

tree_stats_height=24
tree_stats_numInternals=573869
tree_stats_numLeaves=425448
tree_stats_numNodes=999317
tree_stats_numKeys=999317

tree_stats_avgDegreeInternal=1.74137
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42574
tree_stats_avgKeyDepth=18.338


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=175433
first_thread_announced_epoch_by_thread=7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640 7640
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=51859586
sum_fasthtm_abort_total=110050380
sum_htmpostfix_commit_total=2641063
sum_htmpostfix_abort_total=17425484
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=74573433
sum_stmprefix_commit_total=41
sum_slow_commit_total=82750
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=55733 55607 56730 56726 59165 56959 55457 55965 57536 57952 58575 57437 56594 55246 57104 57051 59213 58039 54911 55702 56227 56639 58022 57339 55551 55484 56483 56999 58374 56418 55041 56297 58051 58436 58627 57422 56376 55335 56542 56711 58644 58153 55620 55109 56403 57022 57834 56647
sum_num_inserts_total=2729508
sum_num_deletes_by_thread=55498 55924 56518 57014 58683 56989 55209 56182 57855 58357 58174 57480 55824 55917 56665 56753 58658 58389 54947 55196 56209 56668 57643 57035 55846 55919 56823 56748 58589 56141 55409 56065 57752 58293 58504 57311 56395 56018 57235 56782 58686 58307 55558 55224 56424 56828 58234 56773
sum_num_deletes_total=2729651
sum_num_searches_by_thread=999635 1001798 1019514 1023298 1064314 1025960 998135 1008837 1038911 1044809 1047841 1035786 1007463 997853 1026197 1025500 1055594 1041801 987054 997974 1012008 1020205 1039494 1032363 1006200 1000996 1020088 1026617 1056195 1013037 997778 1013453 1038741 1047368 1054141 1033077 1016209 999986 1024585 1025167 1056433 1054621 995363 982711 1019795 1025442 1041385 1022549
sum_num_searches_total=49124281
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=1110866 1113329 1132762 1137038 1182162 1139908 1108801 1120984 1154302 1161118 1164590 1150703 1119881 1109016 1139966 1139304 1173465 1158229 1096912 1108872 1124444 1133512 1155159 1146737 1117597 1112399 1133394 1140364 1173158 1125596 1108228 1125815 1154544 1164097 1171272 1147810 1128980 1111339 1138362 1138660 1173763 1171081 1106541 1093044 1132622 1139292 1157453 1135969
average_num_operations_total=1137155
stdev_num_operations_total=3318
sum_num_operations_total=54583440
min_num_operations_total=1093044
max_num_operations_total=1182162
first_time_thread_terminate_by_thread=10000137 10000119 10000104 10000127 10000113 10000120 10000118 10000079 10000081 10000110 10000121 10000081 10000126 10000114 10000107 10000080 10000123 10000079 10000132 10000080 10000133 10000079 10000111 10000124 10000095 10000122 10000100 10000087 10000129 10000079 10000116 10000079 10000092 10000080 10000125 10000079 10000079 10000079 10000104 10000080 10000094 10000084 10000131 10000081 10000131 10000098 10000097 10000079
min_time_thread_terminate_total=10000079
max_time_thread_terminate_total=10000137
first_time_thread_start_by_thread=10 5 5 5 6 6 5 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 6 4 5 5 5 6 6 5 5 5 5 5 6 6 5 5 5 5 5 5 5 5 5 5 6
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=999615021572
threads_final_size=999317
final_keysum=999615021572
final_size=999317
validate_result=success
Validation OK.
Structural validation OK.

total_find=49124281
total_rq=0
total_inserts=2729508
total_deletes=2729651
total_updates=5459159
total_queries=49124281
total_ops=54583440
find_throughput=4912428
rq_throughput=0
update_throughput=545915
query_throughput=4912428
total_throughput=5458344


total find                    : 49124281
total rq                      : 0
total inserts                 : 2729508
total deletes                 : 2729651
total updates                 : 5459159
total queries                 : 49124281
total ops                     : 54583440
find throughput               : 4912428
rq throughput                 : 0
update throughput             : 545915
query throughput              : 4912428
total throughput              : 5458344

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.0053
PAPI_L3_TCM=3.47984
PAPI_TOT_CYC=21433.7
PAPI_TOT_INS=4101.32
garbage=24075221436716
total_execution_walltime=16.314s
REALTIME_MAIN_END_PERF_FORMAT=6997942.903197485
RH noREC w/postfix htm system shutdown:
    HTM_ATTEMPT_THRESH=40
[time_cmd_output] time_elapsed_sec=16.45, faults_major=0, faults_minor=141119, mem_maxresident_kb=565252, user_cputime=749.52, sys_cputime=27.55, percent_cpu=4721%
timeout=false
algorithm=brown_sigouin_int_avl_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