__step=000268
__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=data000268.txt
__path_data=data/data000268.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997825.684131172
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=294919 prefilling_elapsed_millis=1.001s
prefilling_size=319129 prefilling_elapsed_millis=1.101s
prefilling_size=342698 prefilling_elapsed_millis=1.201s
prefilling_size=365405 prefilling_elapsed_millis=1.301s
prefilling_size=387267 prefilling_elapsed_millis=1.402s
prefilling_size=407986 prefilling_elapsed_millis=1.502s
prefilling_size=428357 prefilling_elapsed_millis=1.602s
prefilling_size=447986 prefilling_elapsed_millis=1.702s
prefilling_size=466732 prefilling_elapsed_millis=1.802s
prefilling_size=484694 prefilling_elapsed_millis=1.903s
prefilling_size=502523 prefilling_elapsed_millis=2.003s
prefilling_size=519610 prefilling_elapsed_millis=2.103s
prefilling_size=535651 prefilling_elapsed_millis=2.203s
prefilling_size=551712 prefilling_elapsed_millis=2.303s
prefilling_size=567035 prefilling_elapsed_millis=2.403s
prefilling_size=581755 prefilling_elapsed_millis=2.504s
prefilling_size=596132 prefilling_elapsed_millis=2.604s
prefilling_size=609840 prefilling_elapsed_millis=2.704s
prefilling_size=623506 prefilling_elapsed_millis=2.804s
prefilling_size=636182 prefilling_elapsed_millis=2.904s
prefilling_size=648332 prefilling_elapsed_millis=3.005s
prefilling_size=660169 prefilling_elapsed_millis=3.105s
prefilling_size=671707 prefilling_elapsed_millis=3.205s
prefilling_size=683194 prefilling_elapsed_millis=3.305s
prefilling_size=694144 prefilling_elapsed_millis=3.405s
prefilling_size=704365 prefilling_elapsed_millis=3.506s
prefilling_size=714447 prefilling_elapsed_millis=3.606s
prefilling_size=724224 prefilling_elapsed_millis=3.706s
prefilling_size=733498 prefilling_elapsed_millis=3.806s
prefilling_size=742738 prefilling_elapsed_millis=3.906s
prefilling_size=751752 prefilling_elapsed_millis=4.007s
prefilling_size=760128 prefilling_elapsed_millis=4.107s
prefilling_size=768448 prefilling_elapsed_millis=4.207s
prefilling_size=776246 prefilling_elapsed_millis=4.307s
prefilling_size=783782 prefilling_elapsed_millis=4.407s
prefilling_size=791017 prefilling_elapsed_millis=4.507s
prefilling_size=798179 prefilling_elapsed_millis=4.608s
prefilling_size=805377 prefilling_elapsed_millis=4.708s
prefilling_size=812125 prefilling_elapsed_millis=4.808s
prefilling_size=818177 prefilling_elapsed_millis=4.908s
prefilling_size=824621 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 175358 keys with 96 threads...
tid=0 thread_prefill_amount=10415 percent_done_estimate=570.2 elapsed_s=5 est_remaining_s=-10 / -5
timing_elapsed 0.444574s
prefilling_size=1000000 prefilling_elapsed_millis=0.445s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000583675970, performing 3810361 updates; total_prefilling_elapsed_ms=445 ms)
pref_size=1000000
pref_millis=445
prefill_elapsed_ms=445
main thread: starting timer...
###############################################################################
################################ BEGIN RUNNING ################################
###############################################################################
REALTIME_START_PERF_FORMAT=6997831.416160901
REALTIME_END_PERF_FORMAT=6997841.416255534
###############################################################################
################################## 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 32724 64255 114230 153559 123053 46564 6557 198 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 0 0 0 0 0 0 37 1059 12178 60759 140872 143511 58699 8268 235
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32761 65314 126408 214318 263925 190075 65256 8466 235
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1024 2048 4096 8192 16384 32761 65314 126408 214318 263925 190075 65256 8466 235
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 2 2 2 2 2 1.99957 1.99478 1.9516 1.79179 1.51496 1.25394 1.09834 1.02925 1.00437 1
tree_stats_height=24
tree_stats_numInternals=573907
tree_stats_numLeaves=425618
tree_stats_numNodes=999525
tree_stats_numKeys=999525
tree_stats_avgDegreeInternal=1.74161
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.42582
tree_stats_avgKeyDepth=18.3559
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=119099
first_thread_announced_epoch_by_thread=2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584 2584
sum_num_inserts_by_thread=43415 42812 43640 43842 43578 42758 44025 43041 43107 43575 42736 42492 43373 42959 43735 44226 43093 43410 43583 42997 42928 42757 42638 42205 43158 43468 44335 43137 42933 42773 44100 43349 43067 43480 42750 43063 43442 43452 43200 43694 42348 42786 43916 42349 43093 42915 42701 42205 30738 31365 30776 30917 30979 29043 30392 30208 31093 30726 30135 30432 30553 30638 31402 30732 30756 31031 30354 30967 30836 30197 29630 30440 31839 31147 31590 31009 30636 29241 30532 31147 30862 31220 30252 29841 30864 31193 30834 30840 30997 31199 29904 30868 31140 30773 30358 30223
sum_num_inserts_total=3545488
sum_num_deletes_by_thread=43764 43213 44083 44490 42741 42833 44138 42726 43088 43636 43104 42206 43648 42957 43619 44123 43090 43167 43296 42743 43246 43151 42147 42332 43260 43282 43914 43000 42767 43026 44109 43724 43705 43667 42628 42830 43655 43428 43362 43361 42577 42866 43743 42847 43027 43656 42163 42631 30783 30959 30596 30663 31033 29262 30331 30942 30694 30776 29417 30241 30458 30827 30919 30556 30525 31173 30136 30907 31281 29939 29709 30709 31859 30948 31524 30899 30813 29718 30474 30873 30733 31160 30096 29666 30819 31026 30885 31070 30837 30595 29888 30880 30821 30230 30343 30072
sum_num_deletes_total=3543834
sum_num_searches_by_thread=782024 773826 788297 789923 782628 767329 790915 769110 776861 787047 777679 759825 785162 772607 781782 793558 771543 778479 781071 771755 774482 776224 764146 760800 775621 785599 794975 779462 770383 772648 792966 785925 781211 783435 768649 770468 784301 787725 781001 782533 769204 771498 788435 765337 771831 776922 761291 764241 553002 560123 548122 552289 557569 529540 544448 551777 554737 552825 533828 549233 549409 552368 561437 552788 546402 562810 542581 556732 556827 541792 532995 550013 574847 555839 568540 560351 552515 532132 546460 559628 555329 559381 541714 532698 554866 563780 558181 555155 553420 556583 540374 553368 556036 549109 545929 541054
sum_num_searches_total=63783670
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=869203 859851 876020 878255 868947 852920 879078 854877 863056 874258 863519 844523 872183 858523 869136 881907 857726 865056 867950 857495 860656 862132 848931 845337 862039 872349 883224 865599 856083 858447 881175 872998 867983 870582 854027 856361 871398 874605 867563 869588 854129 857150 876094 850533 857951 863493 846155 849077 614523 622447 609494 613869 619581 587845 605171 612927 616524 614327 593380 609906 610420 613833 623758 614076 607683 625014 603071 618606 618944 601928 592334 611162 638545 617934 631654 622259 613964 591091 607466 621648 616924 621761 602062 592205 616549 625999 619900 617065 615254 618377 600166 615116 617997 610112 606630 601349
average_num_operations_total=738260
stdev_num_operations_total=13007
sum_num_operations_total=70872992
min_num_operations_total=587845
max_num_operations_total=883224
first_time_thread_terminate_by_thread=10000444 10000132 10000215 10000210 10000125 10000210 10000126 10000183 10000448 10000128 10000175 10000197 10000201 10000169 10000449 10000199 10000204 10000448 10000141 10000191 10000206 10000096 10000217 10000192 10000203 10000100 10000152 10000114 10000181 10000113 10000180 10000173 10000186 10000155 10000182 10000099 10000154 10000208 10000171 10000096 10000159 10000191 10000093 10000221 10000207 10000094 10000216 10000103 10000146 10000095 10000157 10000161 10000443 10000189 10000095 10000213 10000220 10000095 10000136 10000219 10000109 10000166 10000444 10000095 10000196 10000447 10000121 10000140 10000143 10000139 10000222 10000177 10000224 10000095 10000095 10000226 10000453 10000452 10000116 10000451 10000144 10000218 10000187 10000149 10000168 10000450 10000094 10000095 10000163 10000106 10000118 10000150 10000223 10000184 10000447 10000213
min_time_thread_terminate_total=10000093
max_time_thread_terminate_total=10000453
first_time_thread_start_by_thread=9 5 5 5 5 4 4 4 4 4 5 5 5 4 5 5 5 5 5 4 4 4 4 5 4 5 5 5 5 4 4 4 4 4 5 5 5 4 5 4 5 5 5 4 4 4 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=
threads_final_keysum=999034936762
threads_final_size=999525
final_keysum=999034936762
final_size=999525
validate_result=success
Validation OK.
Structural validation OK.
total_find=63783670
total_rq=0
total_inserts=3545488
total_deletes=3543834
total_updates=7089322
total_queries=63783670
total_ops=70872992
find_throughput=6378367
rq_throughput=0
update_throughput=708932
query_throughput=6378367
total_throughput=7087299
total find : 63783670
total rq : 0
total inserts : 3545488
total deletes : 3543834
total updates : 7089322
total queries : 63783670
total ops : 70872992
find throughput : 6378367
rq throughput : 0
update throughput : 708932
query throughput : 6378367
total throughput : 7087299
elapsed milliseconds : 10000
napping milliseconds overtime : 100
PAPI_L2_TCM=24.0587
PAPI_L3_TCM=5.01245
PAPI_TOT_CYC=37567.5
PAPI_TOT_INS=5800.47
garbage=31237043654219
total_execution_walltime=15.856s
REALTIME_MAIN_END_PERF_FORMAT=6997841.541612006
Hybrid noREC system shutdown:
HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit : 70387513
[slow htm]
[fallback]
total fallback commit : 4295841
seconds global lock is held : 0.739604
[time_cmd_output] time_elapsed_sec=16.02, faults_major=0, faults_minor=121228, mem_maxresident_kb=482816, user_cputime=1496.37, sys_cputime=5.45, percent_cpu=9374%
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