__step=000267
__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 96 -nprefill 96 -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=data000267.txt
__path_data=data/data000267.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997809.588136312
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=96
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=96
WORK_THREADS=96
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,24,25,26,27,28,29,30,31,32,33,34,35,36,37,38,39,40,41,42,43,44,45,46,47,120,121,122,123,124,125,126,127,128,129,130,131,132,133,134,135,136,137,138,139,140,141,142,143


Hybrid noREC system ready

Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.017s
prefilling_size=302779 prefilling_elapsed_millis=1.001s
prefilling_size=327563 prefilling_elapsed_millis=1.101s
prefilling_size=351378 prefilling_elapsed_millis=1.201s
prefilling_size=374535 prefilling_elapsed_millis=1.301s
prefilling_size=396576 prefilling_elapsed_millis=1.402s
prefilling_size=417709 prefilling_elapsed_millis=1.502s
prefilling_size=438304 prefilling_elapsed_millis=1.602s
prefilling_size=458148 prefilling_elapsed_millis=1.702s
prefilling_size=477129 prefilling_elapsed_millis=1.802s
prefilling_size=495551 prefilling_elapsed_millis=1.903s
prefilling_size=513061 prefilling_elapsed_millis=2.003s
prefilling_size=530069 prefilling_elapsed_millis=2.103s
prefilling_size=546662 prefilling_elapsed_millis=2.203s
prefilling_size=562744 prefilling_elapsed_millis=2.303s
prefilling_size=577904 prefilling_elapsed_millis=2.404s
prefilling_size=592554 prefilling_elapsed_millis=2.504s
prefilling_size=607296 prefilling_elapsed_millis=2.604s
prefilling_size=621241 prefilling_elapsed_millis=2.704s
prefilling_size=634897 prefilling_elapsed_millis=2.804s
prefilling_size=647889 prefilling_elapsed_millis=2.905s
prefilling_size=660235 prefilling_elapsed_millis=3.005s
prefilling_size=672389 prefilling_elapsed_millis=3.105s
prefilling_size=683773 prefilling_elapsed_millis=3.205s
prefilling_size=694675 prefilling_elapsed_millis=3.305s
prefilling_size=705380 prefilling_elapsed_millis=3.405s
prefilling_size=716135 prefilling_elapsed_millis=3.506s
prefilling_size=726261 prefilling_elapsed_millis=3.606s
prefilling_size=735973 prefilling_elapsed_millis=3.706s
prefilling_size=745684 prefilling_elapsed_millis=3.806s
prefilling_size=754472 prefilling_elapsed_millis=3.906s
prefilling_size=762993 prefilling_elapsed_millis=4.007s
prefilling_size=771379 prefilling_elapsed_millis=4.107s
prefilling_size=779512 prefilling_elapsed_millis=4.207s
prefilling_size=786848 prefilling_elapsed_millis=4.307s
prefilling_size=794306 prefilling_elapsed_millis=4.407s
prefilling_size=801384 prefilling_elapsed_millis=4.508s
prefilling_size=808417 prefilling_elapsed_millis=4.608s
prefilling_size=814992 prefilling_elapsed_millis=4.708s
prefilling_size=821405 prefilling_elapsed_millis=4.808s
prefilling_size=827834 prefilling_elapsed_millis=4.908s
prefilling_size=833734 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 166250 keys with 96 threads...
tid=0 thread_prefill_amount=12498 percent_done_estimate=721.7 elapsed_s=6 est_remaining_s=-11 / -5
timing_elapsed 0.434845s
prefilling_size=1000000 prefilling_elapsed_millis=0.435s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000831866841, performing 3910935 updates; total_prefilling_elapsed_ms=436 ms)
pref_size=1000000
pref_millis=436
prefill_elapsed_ms=436
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997815.332157247
REALTIME_END_PERF_FORMAT=6997825.332244246

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

tree_stats_computeWalltime=0.011s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32744 64282 114005 150732 119916 50157 9209 489 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 23 1075 12430 62489 138495 136818 62338 11729 605 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65357 126435 213221 258411 186975 71547 12218 607 4
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32767 65357 126435 213221 258411 186975 71547 12218 607 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99994 1.9953 1.95098 1.78472 1.50501 1.25951 1.1144 1.04206 1.00966 1.00329 1

tree_stats_height=25
tree_stats_numInternals=574303
tree_stats_numLeaves=426006
tree_stats_numNodes=1000309
tree_stats_numKeys=1000309

tree_stats_avgDegreeInternal=1.74178
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42587
tree_stats_avgKeyDepth=18.3795


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=128016
first_thread_announced_epoch_by_thread=2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786 2786
sum_num_inserts_by_thread=46834 47087 47066 45320 44827 44613 46123 47056 47545 46109 45483 45292 47068 46416 46938 45449 45361 44966 46315 46830 46943 45773 45861 45285 47311 46518 47183 45434 45175 44587 47321 47605 47191 46319 45951 45608 46698 47274 47174 45636 45540 45557 47081 46890 46988 45734 45873 45756 27981 28043 28475 28042 27821 27616 28547 28678 28309 27553 27788 28565 27926 27820 28575 27324 27730 27794 28410 28049 28623 28365 28221 28149 28174 28026 28131 27747 27437 27456 28663 28515 28766 27987 27893 28627 28779 28456 28019 27636 28111 27678 28205 28196 28462 28279 28540 28345
sum_num_inserts_total=3569496
sum_num_deletes_by_thread=46937 47044 46650 45994 44788 44862 46914 47347 47319 46007 45441 45567 46976 46371 46687 45535 44993 45176 47574 46739 47386 45911 45966 45416 46994 46970 46714 45745 44979 44967 47473 46816 47321 46253 45860 45602 47017 47158 47226 45773 45942 45523 47424 46905 47110 45987 45589 45831 27909 27588 28709 27648 27651 27579 28675 28257 28309 27646 27890 28268 27923 28258 28204 27616 27572 28027 28355 28000 28340 28324 28019 28368 28134 27927 28270 27870 27555 27607 28731 28551 28645 27347 27907 28132 28568 28351 28588 27794 28082 27920 28313 28009 28777 28221 28243 28182
sum_num_deletes_total=3571638
sum_num_searches_by_thread=845397 849932 837084 817470 805292 805562 836936 850967 852174 830981 819073 818056 845781 839212 841586 818109 813888 809445 846443 836753 844043 824414 833450 815910 848756 840310 846517 817055 812800 808852 855085 848804 847480 832786 829107 818273 844127 852963 855564 820642 828094 822094 846926 846308 850269 826101 825045 825468 504701 500651 515672 497737 493932 494982 515133 516290 509684 499200 498538 511764 505523 503482 513118 492675 495637 503886 509893 504772 512968 511364 506390 505382 508964 505221 503850 501673 496769 494912 515707 514796 517989 502329 503428 511630 515402 515123 510743 499917 506670 503289 507429 509485 511801 506187 513129 506605
sum_num_searches_total=64293806
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=939168 944063 930800 908784 894907 895037 929973 945370 947038 923097 909997 908915 939825 931999 935211 909093 904242 899587 940332 930322 938372 916098 925277 906611 943061 933798 940414 908234 902954 898406 949879 943225 941992 925358 920918 909483 937842 947395 949964 912051 919576 913174 941431 940103 944367 917822 916507 917055 560591 556282 572856 553427 549404 550177 572355 573225 566302 554399 554216 568597 561372 559560 569897 547615 550939 559707 566658 560821 569931 568053 562630 561899 565272 561174 560251 557290 551761 549975 573101 571862 575400 557663 559228 568389 572749 571930 567350 555347 562863 558887 563947 565690 569040 562687 569912 563132
average_num_operations_total=744113
stdev_num_operations_total=18764
sum_num_operations_total=71434940
min_num_operations_total=547615
max_num_operations_total=949964
first_time_thread_terminate_by_thread=10000169 10000206 10000213 10000216 10000156 10000180 10000086 10000126 10000098 10000119 10000202 10000117 10000147 10000089 10000108 10000086 10000159 10000086 10000089 10000208 10000128 10000199 10000218 10000183 10000201 10000205 10000104 10000220 10000153 10000090 10000224 10000086 10000086 10000207 10000086 10000086 10000168 10000105 10000165 10000166 10000158 10000086 10000152 10000214 10000179 10000182 10000217 10000086 10000222 10000191 10000223 10000225 10000091 10000212 10000176 10000225 10000226 10000136 10000090 10000185 10000142 10000195 10000226 10000188 10000115 10000124 10000177 10000150 10000163 10000203 10000090 10000210 10000212 10000116 10000164 10000090 10000227 10000145 10000193 10000172 10000222 10000091 10000141 10000186 10000095 10000197 10000138 10000189 10000112 10000143 10000174 10000149 10000162 10000219 10000223 10000132
min_time_thread_terminate_total=10000086
max_time_thread_terminate_total=10000227
first_time_thread_start_by_thread=9 5 5 4 4 4 5 4 5 4 5 5 4 5 4 4 4 4 4 4 5 6 21 5 3 5 5 4 4 4 5 4 5 4 5 5 4 5 4 4 4 4 4 4 5 5 5 5 5 5 5 5 5 4 4 5 5 5 4 5 5 5 5 5 4 4 5 5 5 5 4 5 5 5 5 5 5 4 4 5 5 5 4 5 5 5 5 5 4 4 5 5 5 5 4 5
min_time_thread_start_total=3
max_time_thread_start_total=21
sum_duration_all_ops_total=

threads_final_keysum=1000683987885
threads_final_size=1000309
final_keysum=1000683987885
final_size=1000309
validate_result=success
Validation OK.
Structural validation OK.

total_find=64293806
total_rq=0
total_inserts=3569496
total_deletes=3571638
total_updates=7141134
total_queries=64293806
total_ops=71434940
find_throughput=6429380
rq_throughput=0
update_throughput=714113
query_throughput=6429380
total_throughput=7143494


total find                    : 64293806
total rq                      : 0
total inserts                 : 3569496
total deletes                 : 3571638
total updates                 : 7141134
total queries                 : 64293806
total ops                     : 71434940
find throughput               : 6429380
rq throughput                 : 0
update throughput             : 714113
query throughput              : 6429380
total throughput              : 7143494

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=23.8072
PAPI_L3_TCM=4.96482
PAPI_TOT_CYC=37271
PAPI_TOT_INS=5683.87
garbage=31735200822499
total_execution_walltime=15.868s
REALTIME_MAIN_END_PERF_FORMAT=6997825.457528775
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 71107775
[slow htm]
[fallback]
total fallback commit         : 4238101
seconds global lock is held   : 0.73665
[time_cmd_output] time_elapsed_sec=16.04, faults_major=0, faults_minor=122146, mem_maxresident_kb=486524, user_cputime=1496.06, sys_cputime=5.65, percent_cpu=9358%
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