__step=000029
__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 144 -nprefill 144 -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=data000029.txt
__path_data=data/data000029.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6992222.828131932
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=144
DESIRED_PREFILL_SIZE=-1
TOTAL_THREADS=144
WORK_THREADS=144
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,48,49,50,51,52,53,54,55,56,57,58,59,60,61,62,63,64,65,66,67,68,69,70,71,144,145,146,147,148,149,150,151,152,153,154,155,156,157,158,159,160,161,162,163,164,165,166,167


TL2 system ready: GV=GV4
Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.022s
prefilling_size=4118187 prefilling_elapsed_millis=1.001s
prefilling_size=4430610 prefilling_elapsed_millis=1.101s
prefilling_size=4726629 prefilling_elapsed_millis=1.201s
prefilling_size=5007288 prefilling_elapsed_millis=1.301s
prefilling_size=5273770 prefilling_elapsed_millis=1.402s
prefilling_size=5525047 prefilling_elapsed_millis=1.502s
prefilling_size=5764300 prefilling_elapsed_millis=1.602s
prefilling_size=5991228 prefilling_elapsed_millis=1.702s
prefilling_size=6206349 prefilling_elapsed_millis=1.802s
prefilling_size=6409893 prefilling_elapsed_millis=1.903s
prefilling_size=6602267 prefilling_elapsed_millis=2.003s
prefilling_size=6784502 prefilling_elapsed_millis=2.103s
prefilling_size=6957276 prefilling_elapsed_millis=2.203s
prefilling_size=7120990 prefilling_elapsed_millis=2.303s
prefilling_size=7275589 prefilling_elapsed_millis=2.404s
prefilling_size=7421448 prefilling_elapsed_millis=2.504s
prefilling_size=7559427 prefilling_elapsed_millis=2.604s
prefilling_size=7690267 prefilling_elapsed_millis=2.704s
prefilling_size=7814487 prefilling_elapsed_millis=2.804s
prefilling_size=7931306 prefilling_elapsed_millis=2.905s
prefilling_size=8042848 prefilling_elapsed_millis=3.005s
prefilling_size=8148270 prefilling_elapsed_millis=3.105s
prefilling_size=8247969 prefilling_elapsed_millis=3.205s
prefilling_size=8340874 prefilling_elapsed_millis=3.305s
prefilling_size=8430086 prefilling_elapsed_millis=3.406s
prefilling_size=8514707 prefilling_elapsed_millis=3.506s
prefilling_size=8593963 prefilling_elapsed_millis=3.606s
prefilling_size=8669493 prefilling_elapsed_millis=3.706s
prefilling_size=8741401 prefilling_elapsed_millis=3.806s
prefilling_size=8809078 prefilling_elapsed_millis=3.907s
prefilling_size=8873039 prefilling_elapsed_millis=4.007s
prefilling_size=8932768 prefilling_elapsed_millis=4.107s
prefilling_size=8991113 prefilling_elapsed_millis=4.207s
prefilling_size=9044688 prefilling_elapsed_millis=4.307s
prefilling_size=9095272 prefilling_elapsed_millis=4.408s
prefilling_size=9143586 prefilling_elapsed_millis=4.508s
prefilling_size=9188400 prefilling_elapsed_millis=4.608s
prefilling_size=9231555 prefilling_elapsed_millis=4.708s
prefilling_size=9273082 prefilling_elapsed_millis=4.808s
prefilling_size=9313076 prefilling_elapsed_millis=4.909s
prefilling_size=9348722 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 651231 keys with 144 threads...
tid=0 thread_prefill_amount=69400 percent_done_estimate=1534.6 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=70788 percent_done_estimate=1565.3 elapsed_s=5 est_remaining_s=-11 / -6
tid=0 thread_prefill_amount=72176 percent_done_estimate=1596.0 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.13523s
prefilling_size=10000000 prefilling_elapsed_millis=0.135s
finished prefilling to size 10000000 for expected size 10000000 keysum=99983045710494, performing 55837896 updates; total_prefilling_elapsed_ms=135 ms)
pref_size=10000000
pref_millis=135
prefill_elapsed_ms=135
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=308

main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6992228.306276012
REALTIME_END_PERF_FORMAT=6992238.306364364

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

tree_stats_computeWalltime=0.06s

tree_stats_numInternalsAtDepth=1 2 4 8 16 32 64 128 256 507 992 1924 3625 6638 11734 20184 33360 53293 81312 118501 164823 219818 280225 341879 400808 450322 485252 502842 502631 482553 446335 399261 345679 288778 234384 184900 142226 106184 77537 55106 38533 26160 17556 11373 7210 4482 2776 1812 1150 703 406 250 121 57 35 20 7 2 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 0 5 13 33 123 339 843 1797 3824 7240 13663 23892 38600 58875 85224 116658 150062 186599 219596 246587 264958 274616 272271 258715 236458 210211 178653 147885 118316 92604 70093 51828 37033 26288 17992 12245 7940 5130 3228 1940 1261 805 522 279 199 105 44 25 15 7 2
tree_stats_numNodesAtDepth=1 2 4 8 16 32 64 128 256 512 1005 1957 3748 6977 12577 21981 37184 60533 94975 142393 203423 278693 365449 458537 550870 636921 704848 749429 767589 757169 718606 657976 582137 498989 413037 332785 260542 198788 147630 106934 75566 52448 35548 23618 15150 9612 6004 3752 2411 1508 928 529 320 162 79 45 22 9 2
tree_stats_numKeysAtDepth=1 2 4 8 16 32 64 128 256 512 1005 1957 3748 6977 12577 21981 37184 60533 94975 142393 203423 278693 365449 458537 550870 636921 704848 749429 767589 757169 718606 657976 582137 498989 413037 332785 260542 198788 147630 106934 75566 52448 35548 23618 15150 9612 6004 3752 2411 1508 928 529 320 162 79 45 22 9 2
tree_stats_avgDegreeAtDepth=2 2 2 2 2 2 2 2 2 1.97266 1.9602 1.93204 1.89434 1.85123 1.81474 1.7734 1.73077 1.68858 1.64313 1.59639 1.55977 1.52255 1.48793 1.45578 1.42862 1.39962 1.3748 1.35326 1.33161 1.31176 1.29452 1.27794 1.26336 1.24902 1.23824 1.2273 1.21709 1.20849 1.19913 1.19133 1.18414 1.179 1.17053 1.15992 1.15855 1.15834 1.16256 1.15965 1.14849 1.1492 1.13254 1.13233 1.12813 1.1358 1.12658 1.04444 1.09091 1 1

tree_stats_height=59
tree_stats_numInternals=6556777
tree_stats_numLeaves=3445641
tree_stats_numNodes=10002418
tree_stats_numKeys=10002418

tree_stats_avgDegreeInternal=1.52551
tree_stats_avgDegreeLeaves=1
tree_stats_avgDegree=1.34448
tree_stats_avgKeyDepth=28.51

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


log_histogram_of_none_limbo_reclamation_event_size_full_data=1:0 2:0 4:0 8:133 16:107420 32:174314 64:85
    (2^03, 2^04]: 133
    (2^04, 2^05]: 107420
    (2^05, 2^06]: 174314
    (2^06, 2^07]: 85
sum_limbo_reclamation_event_size_total=9436569
count_limbo_reclamation_event_size_by_thread=1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958 1958
count_limbo_reclamation_event_size_total=281952
sum_limbo_reclamation_event_count_total=282364
first_thread_announced_epoch_by_thread=4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536 4536
sum_num_inserts_by_thread=135496 137105 138023 138012 138156 136706 136957 138487 139057 139333 138283 137780 137100 138207 138939 138413 137977 137469 136221 137959 138458 138497 137895 136996 136372 136893 138062 138856 137036 137006 137209 137270 139563 138935 137938 137220 137290 137190 137787 138953 138054 138000 136657 137184 137557 138511 137925 136423 119245 120553 121082 120779 120549 121292 120863 120507 121255 121597 120724 120931 120616 120918 121877 120837 121457 119820 119833 120371 121935 121421 121695 120442 119715 119924 121188 120729 121022 120624 119870 121117 121751 122260 121000 120462 119679 121102 121411 121163 120863 119551 119914 119846 120981 121708 121356 120435 134059 134822 135595 135472 135253 135044 134210 135621 136760 136868 135425 135100 134404 135774 136587 136164 134731 133160 134077 134660 136138 136864 135156 134786 133302 134559 135803 135466 135939 135003 135242 135785 136318 137421 135581 135082 134738 135751 136181 136282 135819 134437 134070 135679 136386 136496 135965 134206
sum_num_inserts_total=18907928
sum_num_deletes_by_thread=136015 137046 138869 137946 138487 136160 137784 137916 138657 138679 138382 137416 137327 137693 138518 139175 138378 137036 136273 137293 138036 138657 137843 136682 136106 137078 138121 137885 137669 135836 136194 137885 139479 139037 137582 137368 137010 137856 138020 138782 138728 137254 136087 137275 138165 138403 137681 136861 118968 119941 120601 121167 120085 120858 120313 120270 121708 120747 121893 120207 120187 120897 121149 121745 120910 119721 119096 120133 120813 121421 120661 120514 119289 119752 120902 121254 120120 120288 119902 120706 121523 121908 121603 120683 120402 120468 121466 121729 120680 119785 119374 120521 121783 121597 120784 120105 134136 134873 135915 135339 136274 135370 134732 135444 135539 136547 135681 134770 134793 135666 135732 135919 134962 133827 134744 135159 135146 135670 135301 134491 134160 134830 136289 135686 136918 135667 134870 134818 136613 136704 135957 134403 133713 135727 136468 135962 135200 133734 134564 135071 136083 137290 136011 134898
sum_num_deletes_total=18896925
sum_num_searches_by_thread=2449019 2467444 2486981 2487453 2485534 2459741 2463437 2475525 2502584 2501857 2488923 2473455 2468369 2475524 2490932 2493036 2488575 2477275 2455796 2473232 2490507 2494866 2482651 2467623 2452710 2470977 2486052 2485190 2484220 2457729 2468276 2479285 2498722 2503318 2485613 2475451 2468271 2477307 2493080 2495106 2492574 2468768 2455343 2469797 2479832 2497249 2484410 2463496 2153287 2161858 2178839 2178757 2168068 2171608 2159315 2170580 2189486 2192561 2183884 2167795 2163377 2176868 2181983 2185887 2171311 2154597 2153621 2165665 2183778 2185340 2178043 2165251 2151270 2162997 2177991 2180913 2168200 2172347 2160792 2170165 2189783 2191754 2184093 2168487 2169497 2176693 2183168 2186978 2177882 2157127 2156876 2164565 2185952 2187635 2175578 2167540 2407973 2426739 2436768 2441049 2447231 2438560 2420072 2433242 2451735 2457235 2445017 2424508 2419130 2436905 2449229 2453684 2435979 2407810 2413872 2431535 2447649 2455678 2439550 2416349 2410833 2423804 2441242 2446703 2452865 2440255 2426028 2437025 2456734 2462289 2442895 2430111 2425789 2438223 2451147 2454113 2437483 2413781 2419078 2433755 2451289 2460323 2444980 2425963
sum_num_searches_total=340267364
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2720530 2741595 2763873 2763411 2762177 2732607 2738178 2751928 2780298 2779869 2765588 2748651 2742796 2751424 2768389 2770624 2764930 2751780 2728290 2748484 2767001 2772020 2758389 2741301 2725188 2744948 2762235 2761931 2758925 2730571 2741679 2754440 2777764 2781290 2761133 2750039 2742571 2752353 2768887 2772841 2769356 2744022 2728087 2744256 2755554 2774163 2760016 2736780 2391500 2402352 2420522 2420703 2408702 2413758 2400491 2411357 2432449 2434905 2426501 2408933 2404180 2418683 2425009 2428469 2413678 2394138 2392550 2406169 2426526 2428182 2420399 2406207 2390274 2402673 2420081 2422896 2409342 2413259 2400564 2411988 2433057 2435922 2426696 2409632 2409578 2418263 2426045 2429870 2419425 2396463 2396164 2404932 2428716 2430940 2417718 2408080 2676168 2696434 2708278 2711860 2718758 2708974 2689014 2704307 2724034 2730650 2716123 2694378 2688327 2708345 2721548 2725767 2705672 2674797 2682693 2701354 2718933 2728212 2710007 2685626 2678295 2693193 2713334 2717855 2725722 2710925 2696140 2707628 2729665 2736414 2714433 2699596 2694240 2709701 2723796 2726357 2708502 2681952 2687712 2704505 2723758 2734109 2716956 2695067
average_num_operations_total=2625501
stdev_num_operations_total=12680
sum_num_operations_total=378072217
min_num_operations_total=2390274
max_num_operations_total=2781290
first_time_thread_terminate_by_thread=10000161 10000094 10000094 10000091 10000094 10000090 10000094 10000088 10000092 10000089 10000092 10000088 10000094 10000093 10000089 10000094 10000088 10000088 10000093 10000092 10000093 10000088 10000089 10000088 10000089 10000088 10000094 10000088 10000094 10000088 10000094 10000094 10000092 10000088 10000092 10000089 10000095 10000093 10000089 10000094 10000091 10000088 10000089 10000094 10000093 10000094 10000088 10000089 10000091 10000088 10000089 10000089 10000089 10000092 10000093 10000089 10000092 10000094 10000093 10000088 10000092 10000094 10000088 10000093 10000092 10000088 10000088 10000094 10000089 10000088 10000093 10000093 10000091 10000094 10000089 10000089 10000093 10000092 10000093 10000088 10000092 10000094 10000094 10000089 10000088 10000094 10000092 10000089 10000088 10000091 10000092 10000094 10000094 10000093 10000088 10000093 10000089 10000091 10000089 10000091 10000091 10000091 10000090 10000090 10000090 10000088 10000090 10000091 10000090 10000090 10000090 10000089 10000092 10000090 10000093 10000089 10000091 10000093 10000089 10000091 10000089 10000091 10000090 10000089 10000090 10000091 10000090 10000092 10000090 10000088 10000090 10000091 10000090 10000090 10000090 10000089 10000089 10000090 10000091 10000091 10000089 10000093 10000093 10000091
min_time_thread_terminate_total=10000088
max_time_thread_terminate_total=10000161
first_time_thread_start_by_thread=10 5 4 4 5 5 4 5 5 5 5 4 4 4 4 4 5 5 5 4 5 5 5 5 4 5 4 4 5 5 4 5 5 5 5 4 4 4 4 4 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 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 6 5 5 6 5 5 5 5 5 5 5 5 5
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100038784459456
threads_final_size=10002418
final_keysum=100038784459456
final_size=10002418
validate_result=success
Validation OK.
Structural validation OK.

total_find=340267364
total_rq=0
total_inserts=18907928
total_deletes=18896925
total_updates=37804853
total_queries=340267364
total_ops=378072217
find_throughput=34026736
rq_throughput=0
update_throughput=3780485
query_throughput=34026736
total_throughput=37807221


total find                    : 340267364
total rq                      : 0
total inserts                 : 18907928
total deletes                 : 18896925
total updates                 : 37804853
total queries                 : 340267364
total ops                     : 378072217
find throughput               : 34026736
rq throughput                 : 0
update throughput             : 3780485
query throughput              : 34026736
total throughput              : 37807221

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=43.2759
PAPI_L3_TCM=7.36137
PAPI_TOT_CYC=8503.74
PAPI_TOT_INS=2741.44
garbage=1689475116278448
total_execution_walltime=15.661s
REALTIME_MAIN_END_PERF_FORMAT=6992238.501164310
TL2 system shutdown:
  GCLOCK=0x1BFDAA0 Starts=434280240 Aborts=1621071
  Overflows: R=0 W=0 L=0
[time_cmd_output] time_elapsed_sec=15.84, faults_major=0, faults_minor=256900, mem_maxresident_kb=698032, user_cputime=2115.10, sys_cputime=95.24, percent_cpu=13948%
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