__step=000266
__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=data000266.txt
__path_data=data/data000266.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997793.798607883
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.021s
prefilling_size=558249 prefilling_elapsed_millis=1.001s
prefilling_size=592256 prefilling_elapsed_millis=1.101s
prefilling_size=623929 prefilling_elapsed_millis=1.201s
prefilling_size=653201 prefilling_elapsed_millis=1.302s
prefilling_size=679790 prefilling_elapsed_millis=1.402s
prefilling_size=704614 prefilling_elapsed_millis=1.502s
prefilling_size=727042 prefilling_elapsed_millis=1.602s
prefilling_size=747857 prefilling_elapsed_millis=1.702s
prefilling_size=767243 prefilling_elapsed_millis=1.803s
prefilling_size=785548 prefilling_elapsed_millis=1.903s
prefilling_size=801748 prefilling_elapsed_millis=2.003s
prefilling_size=816917 prefilling_elapsed_millis=2.103s
prefilling_size=830537 prefilling_elapsed_millis=2.203s
prefilling_size=843122 prefilling_elapsed_millis=2.303s
prefilling_size=854979 prefilling_elapsed_millis=2.404s
prefilling_size=866014 prefilling_elapsed_millis=2.504s
prefilling_size=875552 prefilling_elapsed_millis=2.604s
prefilling_size=884973 prefilling_elapsed_millis=2.704s
prefilling_size=893319 prefilling_elapsed_millis=2.804s
prefilling_size=901629 prefilling_elapsed_millis=2.904s
prefilling_size=909061 prefilling_elapsed_millis=3.005s
prefilling_size=916551 prefilling_elapsed_millis=3.105s
prefilling_size=923118 prefilling_elapsed_millis=3.205s
prefilling_size=928756 prefilling_elapsed_millis=3.305s
prefilling_size=934353 prefilling_elapsed_millis=3.405s
prefilling_size=939214 prefilling_elapsed_millis=3.505s
prefilling_size=943836 prefilling_elapsed_millis=3.606s
prefilling_size=947543 prefilling_elapsed_millis=3.706s
prefilling_size=951259 prefilling_elapsed_millis=3.806s
prefilling_size=954321 prefilling_elapsed_millis=3.906s
prefilling_size=957623 prefilling_elapsed_millis=4.006s
prefilling_size=960639 prefilling_elapsed_millis=4.107s
prefilling_size=963542 prefilling_elapsed_millis=4.207s
prefilling_size=965325 prefilling_elapsed_millis=4.307s
prefilling_size=967793 prefilling_elapsed_millis=4.407s
prefilling_size=970063 prefilling_elapsed_millis=4.507s
prefilling_size=972307 prefilling_elapsed_millis=4.608s
prefilling_size=973822 prefilling_elapsed_millis=4.708s
prefilling_size=975444 prefilling_elapsed_millis=4.808s
prefilling_size=976906 prefilling_elapsed_millis=4.908s
prefilling_size=978623 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 21373 keys with 48 threads...
timing_elapsed 0.0350493s
prefilling_size=1000000 prefilling_elapsed_millis=0.035s
finished prefilling to size 1000000 for expected size 1000000 keysum=999710022734, performing 8373116 updates; total_prefilling_elapsed_ms=35 ms)
pref_size=1000000
pref_millis=35
prefill_elapsed_ms=35
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997799.160160912
REALTIME_END_PERF_FORMAT=6997809.160237665

###############################################################################
################################## 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 32751 64411 114731 152861 121993 47633 6433 149 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 15 986 11967 62045 140505 141730 60112 8077 179
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65397 126698 214906 262498 189363 66545 8226 179
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32766 65397 126698 214906 262498 189363 66545 8226 179
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99988 1.99634 1.95244 1.79066 1.51016 1.25665 1.09987 1.02694 1.00365 1

tree_stats_height=24
tree_stats_numInternals=573729
tree_stats_numLeaves=425616
tree_stats_numNodes=999345
tree_stats_numKeys=999345

tree_stats_avgDegreeInternal=1.74184
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42589
tree_stats_avgKeyDepth=18.3553


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=740594
first_thread_announced_epoch_by_thread=31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722 31722
sum_num_inserts_by_thread=322045 323861 326777 327453 322074 320235 323586 320738 325739 325996 327776 323775 324171 320822 323579 323769 323328 322649 323098 322370 325366 330320 324038 325445 324065 321005 327160 326912 322959 320726 320124 322286 329601 326809 325492 323708 325031 321598 324096 329428 329684 322476 319571 322367 328819 326626 324577 327078
sum_num_inserts_total=15571208
sum_num_deletes_by_thread=322147 323231 326929 327366 322173 320445 323390 321160 324369 326004 328272 323388 324786 320606 324120 324380 324817 322414 324371 322224 324530 330030 323865 325456 323356 322086 328128 327280 324168 321999 321004 322064 328615 326817 325013 323572 326256 321367 323931 330541 329344 322963 319758 323096 328102 326497 324553 325417
sum_num_deletes_total=15576400
sum_num_searches_by_thread=5791185 5836996 5881195 5890880 5805347 5758694 5809320 5780455 5862767 5871425 5900854 5809161 5837208 5772405 5816975 5840216 5841215 5814283 5824298 5793388 5842656 5938566 5849622 5871167 5834562 5773688 5895171 5907158 5816440 5789722 5759802 5800321 5925633 5889904 5848003 5820534 5851583 5791891 5829611 5942728 5926368 5803005 5763623 5814584 5921866 5880591 5848825 5870558
sum_num_searches_total=280346449
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=6435377 6484088 6534901 6545699 6449594 6399374 6456296 6422353 6512875 6523425 6556902 6456324 6486165 6413833 6464674 6488365 6489360 6459346 6471767 6437982 6492552 6598916 6497525 6522068 6481983 6416779 6550459 6561350 6463567 6432447 6400930 6444671 6583849 6543530 6498508 6467814 6502870 6434856 6477638 6602697 6585396 6448444 6402952 6460047 6578787 6533714 6497955 6523053
average_num_operations_total=6489459
stdev_num_operations_total=7990
sum_num_operations_total=311494057
min_num_operations_total=6399374
max_num_operations_total=6602697
first_time_thread_terminate_by_thread=10000132 10000076 10000077 10000094 10000077 10000076 10000076 10000076 10000076 10000077 10000076 10000077 10000077 10000076 10000077 10000077 10000076 10000076 10000077 10000077 10000077 10000076 10000077 10000077 10000076 10000077 10000077 10000077 10000076 10000076 10000076 10000076 10000076 10000077 10000076 10000077 10000076 10000076 10000076 10000077 10000077 10000076 10000076 10000077 10000077 10000077 10000076 10000077
min_time_thread_terminate_total=10000076
max_time_thread_terminate_total=10000132
first_time_thread_start_by_thread=10 6 5 5 6 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 6 5 5 6 5 5 5 5 5 5 5 5 5 6 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=5
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=999085381297
threads_final_size=999345
final_keysum=999085381297
final_size=999345
validate_result=success
Validation OK.
Structural validation OK.

total_find=280346449
total_rq=0
total_inserts=15571208
total_deletes=15576400
total_updates=31147608
total_queries=280346449
total_ops=311494057
find_throughput=28034644
rq_throughput=0
update_throughput=3114760
query_throughput=28034644
total_throughput=31149405


total find                    : 280346449
total rq                      : 0
total inserts                 : 15571208
total deletes                 : 15576400
total updates                 : 31147608
total queries                 : 280346449
total ops                     : 311494057
find throughput               : 28034644
rq throughput                 : 0
update throughput             : 3114760
query throughput              : 28034644
total throughput              : 31149405

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=14.5726
PAPI_L3_TCM=2.16577
PAPI_TOT_CYC=4287.26
PAPI_TOT_INS=1422.25
garbage=140167397429999
total_execution_walltime=15.527s
REALTIME_MAIN_END_PERF_FORMAT=6997809.327017111
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 317575032
[slow htm]
[fallback]
total fallback commit         : 2292142
seconds global lock is held   : 0.889459
[time_cmd_output] time_elapsed_sec=15.69, faults_major=0, faults_minor=199305, mem_maxresident_kb=797652, user_cputime=735.17, sys_cputime=3.33, percent_cpu=4706%
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=2