__step=000227
__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 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=data000227.txt
__path_data=data/data000227.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6997219.87223955
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=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=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,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.015s
prefilling_size=277904 prefilling_elapsed_millis=1.001s
prefilling_size=301103 prefilling_elapsed_millis=1.101s
prefilling_size=323704 prefilling_elapsed_millis=1.201s
prefilling_size=345429 prefilling_elapsed_millis=1.301s
prefilling_size=366286 prefilling_elapsed_millis=1.401s
prefilling_size=386142 prefilling_elapsed_millis=1.502s
prefilling_size=406003 prefilling_elapsed_millis=1.602s
prefilling_size=424659 prefilling_elapsed_millis=1.702s
prefilling_size=442836 prefilling_elapsed_millis=1.802s
prefilling_size=460510 prefilling_elapsed_millis=1.902s
prefilling_size=477796 prefilling_elapsed_millis=2.003s
prefilling_size=494671 prefilling_elapsed_millis=2.103s
prefilling_size=510887 prefilling_elapsed_millis=2.203s
prefilling_size=526585 prefilling_elapsed_millis=2.303s
prefilling_size=541809 prefilling_elapsed_millis=2.403s
prefilling_size=556453 prefilling_elapsed_millis=2.504s
prefilling_size=569920 prefilling_elapsed_millis=2.604s
prefilling_size=583558 prefilling_elapsed_millis=2.704s
prefilling_size=596574 prefilling_elapsed_millis=2.804s
prefilling_size=609183 prefilling_elapsed_millis=2.904s
prefilling_size=621437 prefilling_elapsed_millis=3.005s
prefilling_size=633483 prefilling_elapsed_millis=3.105s
prefilling_size=644766 prefilling_elapsed_millis=3.205s
prefilling_size=656263 prefilling_elapsed_millis=3.305s
prefilling_size=667166 prefilling_elapsed_millis=3.405s
prefilling_size=677539 prefilling_elapsed_millis=3.506s
prefilling_size=687702 prefilling_elapsed_millis=3.606s
prefilling_size=697492 prefilling_elapsed_millis=3.706s
prefilling_size=706976 prefilling_elapsed_millis=3.806s
prefilling_size=716127 prefilling_elapsed_millis=3.906s
prefilling_size=725178 prefilling_elapsed_millis=4.007s
prefilling_size=733561 prefilling_elapsed_millis=4.107s
prefilling_size=742070 prefilling_elapsed_millis=4.207s
prefilling_size=749956 prefilling_elapsed_millis=4.307s
prefilling_size=757713 prefilling_elapsed_millis=4.407s
prefilling_size=765172 prefilling_elapsed_millis=4.508s
prefilling_size=772318 prefilling_elapsed_millis=4.608s
prefilling_size=779557 prefilling_elapsed_millis=4.708s
prefilling_size=786861 prefilling_elapsed_millis=4.808s
prefilling_size=793487 prefilling_elapsed_millis=4.908s
prefilling_size=799908 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 200082 keys with 96 threads...
tid=0 thread_prefill_amount=10415 percent_done_estimate=499.7 elapsed_s=6 est_remaining_s=-10 / -5
timing_elapsed 0.616306s
prefilling_size=1000000 prefilling_elapsed_millis=0.617s
finished prefilling to size 1000000 for expected size 1000000 keysum=1000361169251, performing 3611094 updates; total_prefilling_elapsed_ms=617 ms)
pref_size=1000000
pref_millis=617
prefill_elapsed_ms=617
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=46

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6997225.162873
REALTIME_END_PERF_FORMAT=6997235.250741

###############################################################################
################################## 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 493 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 250 479 885 1608 2806 4696 7534 11348 16251 22132 28733 35613 42265 48087 51928 53795 53264 50345 46034 40555 34213 27605 21786 16500 12039 8462 5709 3710 2353 1373 737 428 225 115 63 29 15 3 3 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 2 14 42 86 214 495 1040 1979 3524 5572 8350 11653 15506 19432 23586 26601 28693 29622 28685 27025 24560 21533 17439 14236 10942 8046 5785 4017 2630 1699 1052 542 325 173 92 60 23 14 2 4
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 252 493 927 1694 3020 5191 8574 13327 19775 27704 37083 47266 57771 67519 75514 80396 81957 79967 74719 67580 58773 49138 39225 30736 22981 16508 11494 7727 4983 3072 1789 970 550 288 155 89 38 17 5 4
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 252 493 927 1694 3020 5191 8574 13327 19775 27704 37083 47266 57771 67519 75514 80396 81957 79967 74719 67580 58773 49138 39225 30736 22981 16508 11494 7727 4983 3072 1789 970 550 288 155 89 38 17 5 4
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.96875 1.96429 1.90872 1.87271 1.83353 1.78974 1.74706 1.67565 1.63233 1.57917 1.53967 1.49977 1.46879 1.43714 1.40621 1.37699 1.35029 1.32582 1.3048 1.28836 1.26958 1.25394 1.23648 1.22817 1.21086 1.19446 1.18367 1.17557 1.16475 1.14429 1.13542 1.13024 1.12577 1.11455 1.13889 1.16774 1.10112 1.05263 1.11765 1.2 1

tree_stats_height=48
tree_stats_numInternals=654231
tree_stats_numLeaves=345295
tree_stats_numNodes=999526
tree_stats_numKeys=999526

tree_stats_avgDegreeInternal=1.52779
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34546
tree_stats_avgKeyDepth=24.2287

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:43 2:32 4:353 8:41249 16:57138 32:4
    [2^00, 2^01]: 43
    (2^01, 2^02]: 32
    (2^02, 2^03]: 353
    (2^03, 2^04]: 41249
    (2^04, 2^05]: 57138
    (2^05, 2^06]: 4
sum_limbo_reclamation_event_size_total=1608691
count_limbo_reclamation_event_size_by_thread=1030 1030 1029 1030 1030 1029 1030 1029 1030 1029 1030 1029 1029 1030 1028 1030 1029 1029 1029 1030 1029 1029 1029 1030 1030 1030 1029 1030 1030 1028 1029 1029 1030 1029 1030 1030 1029 1029 1030 1030 1029 1030 1030 1028 1028 1029 1030 1029 1030 1029 1030 1028 1030 1029 1029 1029 1029 1029 1029 1030 1030 1030 1030 1029 1030 1029 1029 1030 1029 1030 1030 1028 1029 1030 1030 1029 1030 1029 1030 1030 1028 1029 1029 1028 1028 1030 1029 1029 1030 1030 1030 1029 1030 1028 1030 1029
count_limbo_reclamation_event_size_total=98819
sum_limbo_reclamation_event_count_total=99029
first_thread_announced_epoch_by_thread=2156 2156 2156 2156 2156 2154 2156 2156 2156 2154 2156 2154 2154 2156 2154 2156 2154 2154 2154 2156 2156 2154 2154 2156 2156 2156 2154 2156 2156 2154 2154 2154 2156 2156 2156 2156 2154 2154 2156 2156 2154 2156 2156 2154 2154 2154 2156 2156 2156 2156 2156 2154 2156 2154 2154 2154 2154 2154 2154 2156 2156 2156 2156 2154 2156 2154 2154 2156 2154 2156 2156 2154 2154 2156 2156 2156 2156 2154 2156 2156 2154 2154 2154 2154 2154 2156 2154 2154 2156 2156 2156 2156 2156 2154 2156 2154
sum_num_inserts_by_thread=34117 34640 33797 35119 34110 33467 34969 34291 35035 34564 34059 33930 34182 34711 33845 34726 33961 32542 35145 33907 34491 34511 33693 33320 35285 34993 34650 35458 33482 34838 34030 34219 35056 34829 34469 33116 35267 34951 34421 34597 34339 34312 35170 33694 34711 35160 33188 33599 33423 32705 33479 33265 33160 31922 32388 33221 33224 33242 32348 32921 32204 33461 32418 33254 32332 33205 31845 32598 32345 31557 31759 32693 34044 33855 33229 33006 32382 31785 33238 33575 33473 33320 32875 33084 32773 32280 33585 33491 32648 33245 33081 32881 32747 33153 32732 32222
sum_num_inserts_total=3226639
sum_num_deletes_by_thread=34082 34592 33649 35280 33749 33115 35049 34528 35335 34384 34128 34186 34438 34930 33856 34194 33974 32751 35115 33877 34565 34619 33696 33145 34826 34685 34764 35129 33249 34941 34489 34336 34910 35157 34585 32885 35380 34824 34214 34405 34556 34242 35392 33531 34472 34870 33078 33579 33149 32522 33339 33373 33219 31614 32671 33487 32736 33366 32442 33137 32037 33165 32454 33219 32301 33097 32039 32897 32118 31490 31703 32435 33680 33909 33486 32935 32169 31836 33479 33166 33321 33256 32855 33249 32698 32319 33151 33727 32103 32798 33194 33227 32459 32891 32695 32284
sum_num_deletes_total=3222633
sum_num_searches_by_thread=619136 624475 608193 635409 614866 602041 631536 618222 633288 621829 613969 608772 619085 632505 608677 619402 614434 592037 628421 612258 624446 623901 605614 595034 632657 626254 621007 636585 595846 628012 619339 618810 631935 625850 620045 595325 635358 627522 618898 624154 619827 610905 634568 606480 624231 630062 594012 604549 605774 587980 596552 596753 597262 571421 586499 601955 590627 597510 584082 595448 578734 595966 588424 593810 583560 595599 575156 587421 581620 567860 574054 583526 609904 612871 602313 600054 581290 570135 600156 599424 604285 595760 590985 594946 590381 581480 601700 604790 581129 593059 597439 596344 585359 593167 590006 581045
sum_num_searches_total=58065396
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=687335 693707 675639 705808 682725 668623 701554 687041 703658 690777 682156 676888 687705 702146 676378 688322 682369 657330 698681 680042 693502 693031 673003 661499 702768 695932 690421 707172 662577 697791 687858 687365 701901 695836 689099 661326 706005 697297 687533 693156 688722 679459 705130 673705 693414 700092 660278 671727 672346 653207 663370 663391 663641 634957 651558 668663 656587 664118 648872 661506 642975 662592 653296 660283 648193 661901 639040 652916 646083 630907 637516 648654 677628 680635 669028 665995 645841 633756 666873 666165 671079 662336 656715 661279 655852 646079 668436 672008 645880 659102 663714 662452 650565 659211 655433 645551
average_num_operations_total=672027
stdev_num_operations_total=2023
sum_num_operations_total=64514668
min_num_operations_total=630907
max_num_operations_total=707172
first_time_thread_terminate_by_thread=10000147 10000243 10000211 10000088 10000087 10000153 10000246 10000087 10000155 10000093 10000207 10000147 10000137 10000181 10000173 10000087 10000143 10000159 10000116 10000087 10000087 10000248 10000135 10000209 10000222 10000235 10000158 10000178 10000087 10000164 10000212 10000103 10000234 10000245 10000176 10000214 10000205 10000142 10000087 10000087 10000122 10000090 10000161 10000165 10000247 10000249 10000191 10000240 10000231 10000221 10000242 10000219 10000217 10000239 10000133 10000202 10000250 10000183 10000226 10000238 10000118 10000125 10000088 10000251 10000089 10000112 10000251 10000203 10000131 10000090 10000252 10000100 10000129 10000193 10000195 10000232 10000228 10000096 10000227 10000200 10000250 10000184 10000225 10000237 10000107 10000114 10000168 10000197 10000089 10000239 10000249 10000189 10000231 10000186 10000242 10000128
min_time_thread_terminate_total=10000087
max_time_thread_terminate_total=10000252
first_time_thread_start_by_thread=8 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 3 4 4 4 4 4 4 4 3 4 4 4 4 4 4 4 4 4 4 4 5 4 4 4 5 4 5 5 4 4 5 5 5 4 4 4 4 5 4 4 4 5 4 4 4 5 5 5 5 4 5 5 4 4 4 5 5 4 4 4 4 5 4 4 4 5 4 4 4 5 5 5
min_time_thread_start_total=3
max_time_thread_start_total=8
sum_duration_all_ops_total=

threads_final_keysum=998931399085
threads_final_size=999526
final_keysum=998931399085
final_size=999526
validate_result=success
Validation OK.
Structural validation OK.

total_find=58065396
total_rq=0
total_inserts=3226639
total_deletes=3222633
total_updates=6449272
total_queries=58065396
total_ops=64514668
find_throughput=5806539
rq_throughput=0
update_throughput=644927
query_throughput=5806539
total_throughput=6451466


total find                    : 58065396
total rq                      : 0
total inserts                 : 3226639
total deletes                 : 3222633
total updates                 : 6449272
total queries                 : 58065396
total ops                     : 64514668
find throughput               : 5806539
rq throughput                 : 0
update throughput             : 644927
query throughput              : 5806539
total throughput              : 6451466

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=19.4118
PAPI_L3_TCM=2.61629
PAPI_TOT_CYC=41269.5
PAPI_TOT_INS=5328.32
garbage=29040283821332
total_execution_walltime=16.04s
REALTIME_MAIN_END_PERF_FORMAT=6997235.127962720
Hybrid noREC system shutdown:
    HTM_ATTEMPT_THRESH=40
[fast htm]
total fast htm commit         : 64200825
[slow htm]
[fallback]
total fallback commit         : 3924938
seconds global lock is held   : 0.319446
[time_cmd_output] time_elapsed_sec=16.16, faults_major=0, faults_minor=91201, mem_maxresident_kb=363036, user_cputime=1512.13, sys_cputime=5.57, percent_cpu=9390%
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