__step=000027
__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.tl2 -nwork 96 -nprefill 96 -prefill-hybrid -prefill-hybrid-min-ms 1000 -prefill-hybrid-max-ms 5000 -insdel 5.0 5.0 -k 20000000 -t 10000 -pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
__file_data=data000027.txt
__path_data=data/data000027.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992190.932133855
binary=./brown_int_bst_tm_auto.debra.tl2
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=20000000
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


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.017s
prefilling_size=4065498 prefilling_elapsed_millis=1.001s
prefilling_size=4371819 prefilling_elapsed_millis=1.101s
prefilling_size=4661853 prefilling_elapsed_millis=1.201s
prefilling_size=4937439 prefilling_elapsed_millis=1.301s
prefilling_size=5198169 prefilling_elapsed_millis=1.402s
prefilling_size=5446250 prefilling_elapsed_millis=1.502s
prefilling_size=5681070 prefilling_elapsed_millis=1.602s
prefilling_size=5903722 prefilling_elapsed_millis=1.702s
prefilling_size=6114739 prefilling_elapsed_millis=1.802s
prefilling_size=6315049 prefilling_elapsed_millis=1.903s
prefilling_size=6505379 prefilling_elapsed_millis=2.003s
prefilling_size=6686202 prefilling_elapsed_millis=2.103s
prefilling_size=6858125 prefilling_elapsed_millis=2.203s
prefilling_size=7019858 prefilling_elapsed_millis=2.303s
prefilling_size=7174092 prefilling_elapsed_millis=2.403s
prefilling_size=7320728 prefilling_elapsed_millis=2.504s
prefilling_size=7459653 prefilling_elapsed_millis=2.604s
prefilling_size=7591620 prefilling_elapsed_millis=2.704s
prefilling_size=7716610 prefilling_elapsed_millis=2.804s
prefilling_size=7834033 prefilling_elapsed_millis=2.904s
prefilling_size=7947437 prefilling_elapsed_millis=3.005s
prefilling_size=8053302 prefilling_elapsed_millis=3.105s
prefilling_size=8153875 prefilling_elapsed_millis=3.205s
prefilling_size=8248673 prefilling_elapsed_millis=3.305s
prefilling_size=8339731 prefilling_elapsed_millis=3.405s
prefilling_size=8424729 prefilling_elapsed_millis=3.506s
prefilling_size=8506434 prefilling_elapsed_millis=3.606s
prefilling_size=8582864 prefilling_elapsed_millis=3.706s
prefilling_size=8656917 prefilling_elapsed_millis=3.806s
prefilling_size=8726319 prefilling_elapsed_millis=3.906s
prefilling_size=8792981 prefilling_elapsed_millis=4.007s
prefilling_size=8855287 prefilling_elapsed_millis=4.107s
prefilling_size=8915166 prefilling_elapsed_millis=4.207s
prefilling_size=8971085 prefilling_elapsed_millis=4.307s
prefilling_size=9025034 prefilling_elapsed_millis=4.407s
prefilling_size=9074914 prefilling_elapsed_millis=4.507s
prefilling_size=9122088 prefilling_elapsed_millis=4.608s
prefilling_size=9167061 prefilling_elapsed_millis=4.708s
prefilling_size=9210961 prefilling_elapsed_millis=4.808s
prefilling_size=9251845 prefilling_elapsed_millis=4.908s
prefilling_size=9291637 prefilling_elapsed_millis=5.008s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 708407 keys with 96 threads...
tid=0 thread_prefill_amount=97901 percent_done_estimate=1326.7 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=99984 percent_done_estimate=1354.9 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=102067 percent_done_estimate=1383.2 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=104150 percent_done_estimate=1411.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.145823s
prefilling_size=10000000 prefilling_elapsed_millis=0.146s
finished prefilling to size 10000000 for expected size 10000000 keysum=99994644943501, performing 54342271 updates; total_prefilling_elapsed_ms=146 ms)
pref_size=10000000
pref_millis=146
prefill_elapsed_ms=146
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=623

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992196.400166235
REALTIME_END_PERF_FORMAT=6992206.400250191

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

tree_stats_computeWalltime=0.13s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 255 506 994 1913 3616 6641 11854 20311 33527 53280 80972 117652 163300 217197 275913 335818 391497 437892 470135 486231 483616 467254 434873 392879 344387 293317 242978 195973 154647 119677 90982 67484 49027 35248 24911 17193 11511 7656 4791 2968 1713 1010 573 305 145 65 26 10 1 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 2 14 44 108 318 762 1845 3863 7429 13873 23730 38343 58465 84129 114847 148477 183170 213947 239535 257371 262690 262154 249820 230799 206462 179494 151259 123395 98572 77049 59418 44476 32436 23548 16838 11748 7900 5451 3385 2184 1239 724 442 242 111 52 20 11 1
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 255 508 1008 1957 3724 6959 12616 22156 37390 60709 94845 141382 201643 275662 360042 450665 539974 621062 684082 725766 740987 729944 697027 642699 575186 499779 422472 347232 278042 218249 168031 126902 93503 67684 48459 34031 23259 15556 10242 6353 3897 2249 1297 747 387 176 78 30 12 1
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 255 508 1008 1957 3724 6959 12616 22156 37390 60709 94845 141382 201643 275662 360042 450665 539974 621062 684082 725766 740987 729944 697027 642699 575186 499779 422472 347232 278042 218249 168031 126902 93503 67684 48459 34031 23259 15556 10242 6353 3897 2249 1297 747 387 176 78 30 12 1
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 1.99219 1.99216 1.98819 1.95536 1.9254 1.89769 1.8586 1.81658 1.77085 1.72699 1.68466 1.63693 1.59407 1.55723 1.51819 1.48537 1.45301 1.42514 1.3964 1.37368 1.35102 1.33243 1.31478 1.29816 1.28366 1.27016 1.25842 1.24677 1.23635 1.22875 1.22155 1.21377 1.20503 1.19953 1.19519 1.1882 1.17825 1.17391 1.16624 1.15251 1.14623 1.13754 1.12761 1.13416 1.10977 1.0801 1.07386 1.05128 1.06667 1 1

tree_stats_height=58
tree_stats_numInternals=6554979
tree_stats_numLeaves=3442192
tree_stats_numNodes=9997171
tree_stats_numKeys=9997171

tree_stats_avgDegreeInternal=1.52513
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34432
tree_stats_avgKeyDepth=28.7293

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:31 8:14406 16:299116 32:14191
    (2^02, 2^03]: 31
    (2^03, 2^04]: 14406
    (2^04, 2^05]: 299116
    (2^05, 2^06]: 14191
sum_limbo_reclamation_event_size_total=7601153
count_limbo_reclamation_event_size_by_thread=3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414 3414
count_limbo_reclamation_event_size_total=327744
sum_limbo_reclamation_event_count_total=328031
first_thread_announced_epoch_by_thread=8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078 8078
sum_num_inserts_by_thread=155542 156851 158559 159142 158725 156445 158091 158163 159731 159433 159180 157534 157600 157536 158394 159934 158382 158209 157304 157258 159352 159460 157995 156994 156269 156754 158211 158404 158061 156349 157691 158406 159114 160022 158395 157758 157085 157375 158912 159236 159099 157451 157186 157646 159326 159313 158445 156462 157991 158355 158865 158960 158677 159242 157504 158869 160353 160556 158998 158935 157686 158900 159462 160126 159089 157377 157298 157124 159853 160727 159588 158479 157176 157757 159153 159222 159031 158620 157284 158810 160701 159692 159621 159031 157754 158910 159808 160563 159320 157117 156833 158689 159779 159771 160174 158610
sum_num_inserts_total=15215224
sum_num_deletes_by_thread=156172 156995 158392 158200 157482 156455 156914 158791 159297 160107 159217 157882 156955 157074 159316 159263 159209 158348 157164 157442 159154 159793 157950 156802 155342 157592 158575 158506 158734 155235 157578 158112 159441 159810 159086 157598 157697 158141 158705 159328 159093 157642 157009 157561 158578 159984 157487 157041 157128 157711 157901 158999 158641 158641 157595 158886 160618 160862 160172 158862 158041 158534 159522 159781 159132 157142 156642 157902 160171 160283 159697 158813 156720 157551 158898 159399 157988 159155 157230 157932 160385 160041 160255 158424 158175 159558 159878 160310 159610 157629 156439 158676 160419 160198 159908 158251
sum_num_deletes_total=15212954
sum_num_searches_by_thread=2809204 2825995 2854031 2855117 2848364 2816763 2834928 2848227 2874473 2872436 2860149 2834385 2840382 2842351 2863056 2873023 2857401 2840484 2822961 2833085 2855632 2873031 2846456 2827149 2804196 2826661 2856787 2845874 2849061 2815299 2834490 2838321 2872813 2880518 2860567 2842577 2834881 2841423 2859477 2864579 2859089 2834771 2813753 2837486 2862706 2873822 2849161 2824426 2820615 2845193 2861096 2867125 2848792 2859691 2834509 2855279 2880216 2892213 2877110 2853835 2838850 2859036 2876271 2878671 2871732 2828634 2830933 2841533 2882821 2891942 2873228 2847505 2836563 2840595 2868922 2866979 2852308 2856178 2835942 2853773 2887684 2878460 2882144 2848881 2849539 2865186 2878835 2881334 2861716 2835726 2825952 2853068 2885845 2884235 2876610 2853393
sum_num_searches_total=273868519
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=3120918 3139841 3170982 3172459 3164571 3129663 3149933 3165181 3193501 3191976 3178546 3149801 3154937 3156961 3180766 3192220 3174992 3157041 3137429 3147785 3174138 3192284 3162401 3140945 3115807 3141007 3173573 3162784 3165856 3126883 3149759 3154839 3191368 3200350 3178048 3157933 3149663 3156939 3177094 3183143 3177281 3149864 3127948 3152693 3180610 3193119 3165093 3137929 3135734 3161259 3177862 3185084 3166110 3177574 3149608 3173034 3201187 3213631 3196280 3171632 3154577 3176470 3195255 3198578 3189953 3143153 3144873 3156559 3202845 3212952 3192513 3164797 3150459 3155903 3186973 3185600 3169327 3173953 3150456 3170515 3208770 3198193 3202020 3166336 3165468 3183654 3198521 3202207 3180646 3150472 3139224 3170433 3206043 3204204 3196692 3170254
average_num_operations_total=3169757
stdev_num_operations_total=2314
sum_num_operations_total=304296697
min_num_operations_total=3115807
max_num_operations_total=3213631
first_time_thread_terminate_by_thread=10000156 10000085 10000083 10000086 10000086 10000085 10000083 10000083 10000083 10000085 10000086 10000085 10000086 10000083 10000084 10000085 10000086 10000086 10000086 10000086 10000085 10000083 10000086 10000083 10000086 10000086 10000084 10000086 10000086 10000085 10000086 10000083 10000086 10000085 10000086 10000086 10000086 10000083 10000085 10000085 10000086 10000085 10000087 10000086 10000085 10000086 10000083 10000086 10000084 10000084 10000084 10000084 10000084 10000084 10000086 10000084 10000084 10000084 10000085 10000085 10000084 10000085 10000084 10000085 10000084 10000084 10000085 10000084 10000084 10000085 10000084 10000084 10000084 10000084 10000084 10000084 10000084 10000084 10000085 10000084 10000084 10000084 10000084 10000085 10000085 10000084 10000084 10000085 10000084 10000084 10000084 10000084 10000084 10000085 10000084 10000084
min_time_thread_terminate_total=10000083
max_time_thread_terminate_total=10000156
first_time_thread_start_by_thread=9 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 4 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 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 5
min_time_thread_start_total=4
max_time_thread_start_total=9
sum_duration_all_ops_total=

threads_final_keysum=99945332775528
threads_final_size=9997171
final_keysum=99945332775528
final_size=9997171
validate_result=success
Validation OK.
Structural validation OK.

total_find=273868519
total_rq=0
total_inserts=15215224
total_deletes=15212954
total_updates=30428178
total_queries=273868519
total_ops=304296697
find_throughput=27386851
rq_throughput=0
update_throughput=3042817
query_throughput=27386851
total_throughput=30429669


total find                    : 273868519
total rq                      : 0
total inserts                 : 15215224
total deletes                 : 15212954
total updates                 : 30428178
total queries                 : 273868519
total ops                     : 304296697
find throughput               : 27386851
rq throughput                 : 0
update throughput             : 3042817
query throughput              : 27386851
total throughput              : 30429669

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=43.7416
PAPI_L3_TCM=6.98391
PAPI_TOT_CYC=7157.59
PAPI_TOT_INS=2757.5
garbage=1368824768187258
total_execution_walltime=15.734s
REALTIME_MAIN_END_PERF_FORMAT=6992206.667886647
TL2 system shutdown:
  GCLOCK=0x2030E4E Starts=358033488 Aborts=749331
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.90, faults_major=0, faults_minor=247815, mem_maxresident_kb=665976, user_cputime=1400.98, sys_cputime=75.58, percent_cpu=9283%
timeout=false
algorithm=brown_int_bst_tm_auto.debra.tl2
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=1