__step=000441
__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.hybridnorec -nwork 48 -nprefill 48 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 200000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000441.txt
__path_data=data/data000441.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=7000113.128200637
binary=./brown_int_bst_tm_auto.debra.hybridnorec
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=200000
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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.006s
prefilling_size=100252 prefilling_elapsed_millis=1.001s
finished prefilling to size 100246 for expected size 100000 keysum=10036726091, performing 1662434 updates; total_prefilling_elapsed_ms=1102 ms)
pref_size=100246
pref_millis=1102
prefill_elapsed_ms=1125
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=85

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=7000114.465783194
REALTIME_END_PERF_FORMAT=7000124.465912241

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

tree_stats_computeWalltime=0.034s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 125 242 441 754 1245 1854 2706 3589 4479 5121 5502 5729 5690 5450 5032 4376 3650 2877 2163 1535 1056 685 400 216 124 64 23 7 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 3 25 72 136 368 548 967 1458 2029 2546 2821 3083 3144 3122 3012 2737 2317 1877 1433 1039 733 499 309 163 98 57 18 6 3
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 125 245 466 826 1381 2222 3254 4556 5937 7150 8048 8550 8773 8594 8154 7388 6387 5194 4040 2968 2095 1418 899 525 287 162 80 25 8 3
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 125 245 466 826 1381 2222 3254 4556 5937 7150 8048 8550 8773 8594 8154 7388 6387 5194 4040 2968 2095 1418 899 525 287 162 80 25 8 3
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 1.95312 1.96 1.91429 1.82618 1.75908 1.70746 1.63006 1.56853 1.51536 1.44989 1.40937 1.37873 1.35602 1.33102 1.31464 1.28894 1.2722 1.24174 1.22391 1.19926 1.18868 1.17279 1.15092 1.13904 1.13524 1.1324 1.09877 1.025 1.04 1.125 1

tree_stats_height=37
tree_stats_numInternals=65264
tree_stats_numLeaves=34623
tree_stats_numNodes=99887
tree_stats_numKeys=99887

tree_stats_avgDegreeInternal=1.53051
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34662
tree_stats_avgKeyDepth=19.3834

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:474 2:10717 4:190091 8:276685 16:1889
    [2^00, 2^01]: 474
    (2^01, 2^02]: 10717
    (2^02, 2^03]: 190091
    (2^03, 2^04]: 276685
    (2^04, 2^05]: 1889
sum_limbo_reclamation_event_size_total=3898551
count_limbo_reclamation_event_size_by_thread=9998 9996 9997 9995 9996 9997 9996 9998 9996 9997 9996 9998 9997 9996 9996 9995 9998 9998 9998 9998 9997 9998 9998 9998 9997 9998 9997 9995 9996 9997 9997 9998 9997 9997 9996 9997 9997 9998 9998 9998 9995 9997 9997 9998 9998 9997 9997 9997
count_limbo_reclamation_event_size_total=479856
sum_limbo_reclamation_event_count_total=573792
first_thread_announced_epoch_by_thread=24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078 24078
sum_num_inserts_by_thread=196694 185041 184950 191640 183904 188139 201064 202271 190602 190139 193580 195043 190938 192453 189180 188730 194087 186452 198927 201303 182829 194022 184483 195298 191392 193834 197342 196336 196953 190893 193256 206567 200197 193583 196728 197972 196295 196594 197438 196391 193702 189914 196679 202190 204819 201659 200718 192114
sum_num_inserts_total=9325335
sum_num_deletes_by_thread=195788 184392 184829 191876 183116 188096 201281 202674 190798 190608 194324 194030 191230 191853 190590 187956 194042 186076 197490 200196 182354 193414 184268 195955 191036 193100 197986 194986 198090 190704 193127 205835 200861 194254 196844 198891 197223 196554 197031 196230 194006 189780 196984 201586 205267 201791 199480 191982
sum_num_deletes_total=9320864
sum_num_searches_by_thread=3522613 3328589 3328390 3456170 3292084 3383383 3620307 3649387 3420215 3418717 3497762 3497043 3431073 3453466 3408878 3405811 3496184 3352435 3554881 3616610 3285734 3484172 3321331 3523130 3444308 3476037 3552156 3526582 3554970 3443673 3477987 3715270 3603639 3495444 3537165 3570662 3552298 3538256 3544434 3532492 3483972 3406356 3544187 3638662 3685395 3641189 3605332 3461509
sum_num_searches_total=167780340
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3915095 3698022 3698169 3839686 3659104 3759618 4022652 4054332 3801615 3799464 3885666 3886116 3813241 3837772 3788648 3782497 3884313 3724963 3951298 4018109 3650917 3871608 3690082 3914383 3826736 3862971 3947484 3917904 3950013 3825270 3864370 4127672 4004697 3883281 3930737 3967525 3945816 3931404 3938903 3925113 3871680 3786050 3937850 4042438 4095481 4044639 4005530 3845605
average_num_operations_total=3883886
stdev_num_operations_total=16523
sum_num_operations_total=186426539
min_num_operations_total=3650917
max_num_operations_total=4127672
first_time_thread_terminate_by_thread=10000127 10000138 10000129 10000129 10000138 10000147 10000150 10000128 10000127 10000128 10000150 10000128 10000146 10000137 10000143 10000127 10000150 10000128 10000144 10000140 10000138 10000131 10000148 10000138 10000131 10000132 10000129 10000135 10000147 10000128 10000127 10000145 10000151 10000143 10000127 10000147 10000128 10000140 10000139 10000128 10000127 10000128 10000149 10000133 10000130 10000141 10000148 10000146
min_time_thread_terminate_total=10000127
max_time_thread_terminate_total=10000151
first_time_thread_start_by_thread=3 2 3 2 3 2 3 3 3 3 2 2 3 3 3 3 3 3 2 3 3 3 2 3 3 2 3 2 3 2 3 3 3 3 2 2 3 3 3 3 3 3 2 3 3 3 2 3
min_time_thread_start_total=2
max_time_thread_start_total=3
sum_duration_all_ops_total=

threads_final_keysum=9986744166
threads_final_size=99887
final_keysum=9986744166
final_size=99887
validate_result=success
Validation OK.
Structural validation OK.

total_find=167780340
total_rq=0
total_inserts=9325335
total_deletes=9320864
total_updates=18646199
total_queries=167780340
total_ops=186426539
find_throughput=16778034
rq_throughput=0
update_throughput=1864619
query_throughput=16778034
total_throughput=18642653


total find                    : 167780340
total rq                      : 0
total inserts                 : 9325335
total deletes                 : 9320864
total updates                 : 18646199
total queries                 : 167780340
total ops                     : 186426539
find throughput               : 16778034
rq throughput                 : 0
update throughput             : 1864619
query throughput              : 16778034
total throughput              : 18642653

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=8.46692
PAPI_L3_TCM=0.000638938
PAPI_TOT_CYC=7136.13
PAPI_TOT_INS=2358.87
garbage=8390618156120
total_execution_walltime=11.48s
REALTIME_MAIN_END_PERF_FORMAT=7000124.609886382
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 184405305
[slow htm]
[fallback]
total fallback commit         : 3683669
seconds global lock is held   : 0.211389
[time_cmd_output] time_elapsed_sec=11.61, faults_major=0, faults_minor=76697, mem_maxresident_kb=308724, user_cputime=559.91, sys_cputime=3.49, percent_cpu=4850%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.hybridnorec
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1