__step=000265
__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.hybridnorec -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=data000265.txt
__path_data=data/data000265.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997778.284131043
binary=./brown_sigouin_int_avl_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_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


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.007s
prefilling_size=564615 prefilling_elapsed_millis=1.001s
prefilling_size=599478 prefilling_elapsed_millis=1.101s
prefilling_size=631150 prefilling_elapsed_millis=1.201s
prefilling_size=660433 prefilling_elapsed_millis=1.301s
prefilling_size=687701 prefilling_elapsed_millis=1.402s
prefilling_size=712793 prefilling_elapsed_millis=1.502s
prefilling_size=735346 prefilling_elapsed_millis=1.602s
prefilling_size=756919 prefilling_elapsed_millis=1.702s
prefilling_size=776484 prefilling_elapsed_millis=1.802s
prefilling_size=794192 prefilling_elapsed_millis=1.903s
prefilling_size=810289 prefilling_elapsed_millis=2.003s
prefilling_size=825733 prefilling_elapsed_millis=2.103s
prefilling_size=839628 prefilling_elapsed_millis=2.203s
prefilling_size=852696 prefilling_elapsed_millis=2.303s
prefilling_size=864353 prefilling_elapsed_millis=2.404s
prefilling_size=875444 prefilling_elapsed_millis=2.504s
prefilling_size=885406 prefilling_elapsed_millis=2.604s
prefilling_size=894535 prefilling_elapsed_millis=2.704s
prefilling_size=902774 prefilling_elapsed_millis=2.804s
prefilling_size=910349 prefilling_elapsed_millis=2.905s
prefilling_size=918164 prefilling_elapsed_millis=3.005s
prefilling_size=924576 prefilling_elapsed_millis=3.105s
prefilling_size=930723 prefilling_elapsed_millis=3.205s
prefilling_size=936380 prefilling_elapsed_millis=3.305s
prefilling_size=941643 prefilling_elapsed_millis=3.406s
prefilling_size=946618 prefilling_elapsed_millis=3.506s
prefilling_size=951252 prefilling_elapsed_millis=3.606s
prefilling_size=955046 prefilling_elapsed_millis=3.706s
prefilling_size=958515 prefilling_elapsed_millis=3.806s
prefilling_size=961662 prefilling_elapsed_millis=3.907s
prefilling_size=964130 prefilling_elapsed_millis=4.007s
prefilling_size=967052 prefilling_elapsed_millis=4.107s
prefilling_size=969047 prefilling_elapsed_millis=4.207s
prefilling_size=971094 prefilling_elapsed_millis=4.307s
prefilling_size=973114 prefilling_elapsed_millis=4.408s
prefilling_size=974992 prefilling_elapsed_millis=4.508s
prefilling_size=977144 prefilling_elapsed_millis=4.608s
prefilling_size=979355 prefilling_elapsed_millis=4.708s
prefilling_size=981158 prefilling_elapsed_millis=4.808s
finished prefilling to size 981161 for expected size 1000000 keysum=981307521135, performing 8032488 updates; total_prefilling_elapsed_ms=4909 ms)
pref_size=981161
pref_millis=4909
prefill_elapsed_ms=4936
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997783.414471239
REALTIME_END_PERF_FORMAT=6997793.414631859

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

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32760 65002 118936 159568 117865 40713 6411 322 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 8 470 9634 63949 153469 138732 50722 8111 403
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65472 128570 223517 271334 179445 57133 8433 403
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32768 65472 128570 223517 271334 179445 57133 8433 403
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99829 1.97092 1.81342 1.50003 1.22695 1.0915 1.03539 1.00961 1

tree_stats_height=24
tree_stats_numInternals=574344
tree_stats_numLeaves=425498
tree_stats_numNodes=999842
tree_stats_numKeys=999842

tree_stats_avgDegreeInternal=1.74084
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42557
tree_stats_avgKeyDepth=18.3158


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=746400
first_thread_announced_epoch_by_thread=31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938 31938
sum_num_inserts_by_thread=321001 326204 328401 328092 327090 325690 324152 327702 328422 333077 329629 327681 327322 327686 328251 329989 330186 329939 324156 326253 328878 327828 327910 328696 322096 325912 330068 328058 328561 326492 325819 327390 329873 332481 329687 329073 326674 328020 329187 330413 329800 330468 322788 325698 329180 330299 327773 328877
sum_num_inserts_total=15738922
sum_num_deletes_by_thread=321275 326180 328484 328885 327125 325480 324319 327510 328416 331350 329299 328639 327022 327563 328760 328751 330547 328988 323640 326170 329320 329644 327829 329504 321974 326577 329864 327577 329558 327787 325231 326847 331187 332845 329894 328905 326787 328401 329139 329170 330273 331636 322492 325328 328808 329717 329510 329703
sum_num_deletes_total=15743910
sum_num_searches_by_thread=5779002 5869686 5920957 5902385 5893786 5849930 5839378 5885816 5914641 5979495 5931336 5913518 5884085 5900483 5908744 5924133 5949094 5936054 5829492 5860957 5904410 5919538 5890180 5921403 5790122 5887954 5925999 5903130 5918403 5881605 5869265 5887052 5944630 5981043 5938026 5916290 5880961 5904539 5925613 5939862 5934602 5944750 5799295 5854488 5926882 5940667 5916656 5921282
sum_num_searches_total=283241619
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6421278 6522070 6577842 6559362 6548001 6501100 6487849 6541028 6571479 6643922 6590264 6569838 6538429 6555732 6565755 6582873 6609827 6594981 6477288 6513380 6562608 6577010 6545919 6579603 6434192 6540443 6585931 6558765 6576522 6535884 6520315 6541289 6605690 6646369 6597607 6574268 6534422 6560960 6583939 6599445 6594675 6606854 6444575 6505514 6584870 6600683 6573939 6579862
average_num_operations_total=6556759
stdev_num_operations_total=7013
sum_num_operations_total=314724451
min_num_operations_total=6421278
max_num_operations_total=6646369
first_time_thread_terminate_by_thread=10000160 10000160 10000160 10000160 10000160 10000160 10000159 10000159 10000160 10000159 10000160 10000159 10000160 10000160 10000160 10000160 10000160 10000160 10000160 10000160 10000159 10000160 10000160 10000160 10000160 10000160 10000160 10000160 10000160 10000160 10000159 10000159 10000160 10000159 10000160 10000159 10000160 10000160 10000160 10000160 10000160 10000160 10000159 10000159 10000159 10000160 10000160 10000160
min_time_thread_terminate_total=10000159
max_time_thread_terminate_total=10000160
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=999933788795
threads_final_size=999842
final_keysum=999933788795
final_size=999842
validate_result=success
Validation OK.
Structural validation OK.

total_find=283241619
total_rq=0
total_inserts=15738922
total_deletes=15743910
total_updates=31482832
total_queries=283241619
total_ops=314724451
find_throughput=28324161
rq_throughput=0
update_throughput=3148283
query_throughput=28324161
total_throughput=31472445


total find                    : 283241619
total rq                      : 0
total inserts                 : 15738922
total deletes                 : 15743910
total updates                 : 31482832
total queries                 : 283241619
total ops                     : 314724451
find throughput               : 28324161
rq throughput                 : 0
update throughput             : 3148283
query throughput              : 28324161
total throughput              : 31472445

elapsed milliseconds          : 10000
napping milliseconds overtime : 0

PAPI_L2_TCM=14.5555
PAPI_L3_TCM=2.14605
PAPI_TOT_CYC=4242.53
PAPI_TOT_INS=1410.69
garbage=141420855490461
total_execution_walltime=15.282s
REALTIME_MAIN_END_PERF_FORMAT=6997793.566943424
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 320566496
[slow htm]
[fallback]
total fallback commit         : 2190444
seconds global lock is held   : 0.872279
[time_cmd_output] time_elapsed_sec=15.46, faults_major=0, faults_minor=199172, mem_maxresident_kb=798196, user_cputime=742.50, sys_cputime=4.23, percent_cpu=4830%
timeout=false
algorithm=brown_sigouin_int_avl_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