__step=000109
__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_abtree_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=data000109.txt
__path_data=data/data000109.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6995516.30978751
binary=./brown_abtree_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_abtree_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=200
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.057s
prefilling_size=3490940 prefilling_elapsed_millis=1s
prefilling_size=3790117 prefilling_elapsed_millis=1.101s
prefilling_size=4072936 prefilling_elapsed_millis=1.201s
prefilling_size=4347621 prefilling_elapsed_millis=1.301s
prefilling_size=4611315 prefilling_elapsed_millis=1.402s
prefilling_size=4862192 prefilling_elapsed_millis=1.502s
prefilling_size=5101824 prefilling_elapsed_millis=1.602s
prefilling_size=5333297 prefilling_elapsed_millis=1.702s
prefilling_size=5551332 prefilling_elapsed_millis=1.803s
prefilling_size=5762785 prefilling_elapsed_millis=1.903s
prefilling_size=5963042 prefilling_elapsed_millis=2.003s
prefilling_size=6155129 prefilling_elapsed_millis=2.103s
prefilling_size=6338174 prefilling_elapsed_millis=2.204s
prefilling_size=6511310 prefilling_elapsed_millis=2.304s
prefilling_size=6677685 prefilling_elapsed_millis=2.404s
prefilling_size=6836514 prefilling_elapsed_millis=2.505s
prefilling_size=6987094 prefilling_elapsed_millis=2.605s
prefilling_size=7130936 prefilling_elapsed_millis=2.705s
prefilling_size=7267707 prefilling_elapsed_millis=2.806s
prefilling_size=7399649 prefilling_elapsed_millis=2.906s
prefilling_size=7525315 prefilling_elapsed_millis=3.006s
prefilling_size=7645818 prefilling_elapsed_millis=3.107s
prefilling_size=7759247 prefilling_elapsed_millis=3.207s
prefilling_size=7866458 prefilling_elapsed_millis=3.307s
prefilling_size=7970823 prefilling_elapsed_millis=3.407s
prefilling_size=8069932 prefilling_elapsed_millis=3.508s
prefilling_size=8164242 prefilling_elapsed_millis=3.608s
prefilling_size=8254994 prefilling_elapsed_millis=3.708s
prefilling_size=8341326 prefilling_elapsed_millis=3.809s
prefilling_size=8423456 prefilling_elapsed_millis=3.909s
prefilling_size=8500693 prefilling_elapsed_millis=4.009s
prefilling_size=8574799 prefilling_elapsed_millis=4.11s
prefilling_size=8646520 prefilling_elapsed_millis=4.21s
prefilling_size=8713414 prefilling_elapsed_millis=4.31s
prefilling_size=8776367 prefilling_elapsed_millis=4.411s
prefilling_size=8835386 prefilling_elapsed_millis=4.511s
prefilling_size=8892635 prefilling_elapsed_millis=4.611s
prefilling_size=8946296 prefilling_elapsed_millis=4.712s
prefilling_size=8997086 prefilling_elapsed_millis=4.812s
prefilling_size=9046977 prefilling_elapsed_millis=4.912s
prefilling_size=9095172 prefilling_elapsed_millis=5.013s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 904722 keys with 144 threads...
tid=0 thread_prefill_amount=61072 percent_done_estimate=972.1 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=62460 percent_done_estimate=994.1 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=63848 percent_done_estimate=1016.2 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=65236 percent_done_estimate=1038.3 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=66624 percent_done_estimate=1060.4 elapsed_s=5 est_remaining_s=-11 / -6
timing_elapsed 0.202158s
prefilling_size=10000000 prefilling_elapsed_millis=0.202s
finished prefilling to size 10000000 for expected size 10000000 keysum=100018705587588, performing 50002755 updates; total_prefilling_elapsed_ms=202 ms)
pref_size=10000000
pref_millis=202
prefill_elapsed_ms=202
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6995521.652170274
REALTIME_END_PERF_FORMAT=6995531.652263487

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

tree_stats_computeWalltime=0.049s

tree_stats_numInternalsAtDepth=1 1 8 64 484 3559 26894 210380 0
tree_stats_numLeavesAtDepth=0 0 0 0 0 0 0 0 1660108
tree_stats_numNodesAtDepth=1 1 8 64 484 3559 26894 210380 1660108
tree_stats_numKeysAtDepth=0 0 0 0 0 0 0 0 10001142
tree_stats_avgDegreeAtDepth=1 8 8 7.5625 7.35331 7.55662 7.82256 7.891 6.02439

tree_stats_height=9
tree_stats_numInternals=241391
tree_stats_numLeaves=1660108
tree_stats_numNodes=1901499
tree_stats_numKeys=10001142

tree_stats_avgDegreeInternal=7.87726
tree_stats_avgDegreeLeaves=6.02439
tree_stats_avgDegree=6.25961
tree_stats_avgKeyDepth=8


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=307148
first_thread_announced_epoch_by_thread=4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796 4796
sum_commit_tx_reader_total=
sum_commit_tx_reader_by_thread=
sum_commit_tx_writer_total=
sum_commit_tx_writer_by_thread=
sum_abort_tx_total=
sum_abort_tx_by_thread=
max_ht_capacity_by_thread=

linear_histogram_of_none_tx_rdset_size_full_data=

linear_histogram_of_none_tx_wrset_size_full_data=
sum_fasthtm_commit_total=
sum_fasthtm_abort_total=
sum_htmpostfix_commit_total=
sum_htmpostfix_abort_total=
sum_htmprefix_commit_total=
sum_htmprefix_abort_total=
sum_htmprefix_abort_explicit_total=
sum_htmprefix_abort_conflict_total=
sum_htmprefix_abort_capacity_total=
sum_htmprefix_abort_nesting_total=
sum_htmprefix_abort_illegal_total=
sum_htmprefix_abort_zero_total=
sum_stmprefix_abort_total=
sum_stmprefix_commit_total=
sum_slow_commit_total=
sum_abtree_insert_repeat_total=
sum_abtree_erase_repeat_total=
sum_abtree_search_repeat_total=
sum_abtree_search_complete_total=
sum_abtree_fix_weight_viol_attempt_total=
sum_abtree_fix_degree_viol_attempt_total=
sum_abtree_rebalancing_kcas_attempt_total=
sum_abtree_update_kcas_attempt_total=
sum_abtree_internal_complex_search_while_iters_total=
sum_abtree_internal_basic_search_while_iters_total=
sum_kcas_desc_new_total=
sum_num_inserts_by_thread=137982 137835 138841 139545 139481 139232 137155 137564 138965 139174 138662 138457 137120 138774 139271 139449 138859 138350 137170 137956 138850 138357 138354 138393 137029 138191 138776 138944 140134 138980 138211 137765 138995 139207 139007 138557 137452 138740 139062 139599 139482 139183 136680 138256 138836 138265 138159 137787 147139 147835 148038 147852 147840 148082 146839 147632 148541 149208 148713 148217 146606 148243 148050 148996 149600 147506 146506 147852 149108 148877 148484 147365 146878 147915 147837 148091 147638 147986 147377 147539 148894 149465 147893 147800 148056 147705 148798 148790 148716 147484 146699 147828 148665 149419 148805 147938 119434 118944 120348 120610 121197 120537 119985 120038 120468 120789 119269 119661 119535 119874 119551 120208 120175 119246 119258 120646 121102 120825 120230 120140 119362 119423 120562 119663 121239 120483 119565 120506 121062 121574 121071 120173 120282 120282 120301 120205 120586 120198 120307 120156 120707 121313 121234 120168
sum_num_inserts_total=19528930
sum_num_deletes_by_thread=137682 138267 139426 139058 140003 138285 137621 137126 138923 139058 138588 138435 137951 137909 138355 139281 138566 139010 137370 137771 137866 138438 138064 137788 137500 137897 138504 138715 139522 139294 137674 138134 139116 138716 138231 139286 138132 139002 138472 139334 138752 138773 136871 138520 138358 138832 137354 138378 146658 146700 148046 148067 148614 147570 146948 147708 149363 148902 148324 147440 146867 148113 149251 148430 148918 147311 147288 147652 148216 149028 148579 146746 146301 147239 148070 147806 147882 147856 147103 146888 148573 148763 148817 147518 147676 148092 148235 149004 148340 146863 146868 147121 148525 148782 149080 148032 119257 119684 120978 120612 120634 120572 119848 120146 120913 120860 120383 119697 119661 119955 120047 119972 120227 119698 119737 120120 120043 120458 120442 119825 119088 120288 119996 119676 120926 119632 119788 119595 121514 121031 120778 119810 120063 120194 120306 119981 121238 120095 120152 120484 120403 120652 120855 120520
sum_num_deletes_total=19515215
sum_num_searches_by_thread=2479969 2490603 2500163 2510188 2507716 2494361 2479131 2481595 2502070 2502909 2498504 2494155 2477880 2491957 2502272 2505723 2502567 2498877 2465958 2474918 2488377 2492380 2487529 2483883 2477975 2479563 2493250 2509483 2504287 2493279 2478579 2484570 2506193 2506673 2493054 2495317 2480352 2493965 2502947 2505340 2504004 2495826 2463435 2478887 2489298 2501374 2492270 2487280 2638421 2648534 2661781 2665303 2665480 2662085 2646376 2661611 2678174 2680957 2673896 2657625 2653655 2660157 2666588 2678009 2676526 2655887 2644549 2658023 2675760 2678912 2670005 2659378 2644645 2652367 2671347 2670143 2670128 2664286 2644888 2654989 2674983 2682063 2677744 2657221 2649635 2660863 2669149 2674322 2671868 2652518 2640669 2656977 2679942 2683976 2676042 2661373 2147626 2149354 2167241 2170282 2173962 2167233 2154849 2164907 2176160 2169607 2165016 2159718 2156435 2162689 2162778 2163502 2169498 2151444 2152527 2160495 2167584 2172504 2166257 2156556 2151034 2152904 2165146 2166454 2172574 2164482 2157567 2165600 2176298 2179016 2173390 2164226 2162783 2165358 2167719 2167827 2167957 2158010 2155258 2163883 2172526 2176387 2172582 2162347
sum_num_searches_total=351380268
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2755633 2766705 2778430 2788791 2787200 2771878 2753907 2756285 2779958 2781141 2775754 2771047 2752951 2768640 2779898 2784453 2779992 2776237 2740498 2750645 2765093 2769175 2763947 2760064 2752504 2755651 2770530 2787142 2783943 2771553 2754464 2760469 2784304 2784596 2770292 2773160 2755936 2771707 2780481 2784273 2782238 2773782 2736986 2755663 2766492 2778471 2767783 2763445 2932218 2943069 2957865 2961222 2961934 2957737 2940163 2956951 2976078 2979067 2970933 2953282 2947128 2956513 2963889 2975435 2975044 2950704 2938343 2953527 2973084 2976817 2967068 2953489 2937824 2947521 2967254 2966040 2965648 2960128 2939368 2949416 2972450 2980291 2974454 2952539 2945367 2956660 2966182 2972116 2968924 2946865 2934236 2951926 2977132 2982177 2973927 2957343 2386317 2387982 2408567 2411504 2415793 2408342 2394682 2405091 2417541 2411256 2404668 2399076 2395631 2402518 2402376 2403682 2409900 2390388 2391522 2401261 2408729 2413787 2406929 2396521 2389484 2392615 2405704 2405793 2414739 2404597 2396920 2405701 2418874 2421621 2415239 2404209 2403128 2405834 2408326 2408013 2409781 2398303 2395717 2404523 2413636 2418352 2414671 2403035
average_num_operations_total=2711280
stdev_num_operations_total=19343
sum_num_operations_total=390424413
min_num_operations_total=2386317
max_num_operations_total=2982177
first_time_thread_terminate_by_thread=10000201 10000095 10000093 10000095 10000096 10000097 10000095 10000093 10000095 10000099 10000095 10000100 10000093 10000095 10000095 10000096 10000095 10000093 10000095 10000098 10000093 10000098 10000095 10000095 10000096 10000093 10000098 10000095 10000096 10000093 10000098 10000096 10000095 10000099 10000099 10000093 10000093 10000095 10000095 10000096 10000095 10000096 10000095 10000095 10000093 10000094 10000095 10000093 10000093 10000099 10000093 10000093 10000098 10000096 10000093 10000093 10000094 10000093 10000099 10000094 10000093 10000094 10000092 10000093 10000094 10000097 10000093 10000098 10000097 10000093 10000094 10000093 10000093 10000093 10000093 10000093 10000092 10000097 10000093 10000093 10000093 10000093 10000099 10000094 10000093 10000094 10000097 10000093 10000094 10000097 10000093 10000097 10000097 10000093 10000094 10000093 10000093 10000098 10000099 10000099 10000095 10000095 10000097 10000095 10000095 10000096 10000093 10000093 10000099 10000094 10000096 10000097 10000098 10000099 10000094 10000098 10000094 10000100 10000097 10000096 10000093 10000098 10000099 10000099 10000095 10000093 10000098 10000094 10000095 10000096 10000096 10000098 10000094 10000094 10000096 10000097 10000096 10000099 10000093 10000093 10000094 10000099 10000094 10000095
min_time_thread_terminate_total=10000092
max_time_thread_terminate_total=10000201
first_time_thread_start_by_thread=10 5 5 5 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 6 6 6 6 6 6 5 6 5 6 6 6 5 5 5 6 5 6 6 6 5 6 5 5 6 6 6 6 6 6 5 6 5 6 6 6 5 5 5 6 5 6 6 6 5 6 5 5 6 6 5 6 5 6 5 5 5 5 6 5 6 5 5 6 6 6 6 5 5 5 6 6 6 6 5 6 5 6 5 5 5 5 6 5 6 5 5 6 6 6 6 5 5 5 6 6
min_time_thread_start_total=4
max_time_thread_start_total=10
sum_duration_all_ops_total=

threads_final_keysum=100009802234990
threads_final_size=10001142
final_keysum=100009802234990
final_size=10001142
validate_result=success
Validation OK.
Structural validation OK.

total_find=351380268
total_rq=0
total_inserts=19528930
total_deletes=19515215
total_updates=39044145
total_queries=351380268
total_ops=390424413
find_throughput=35138026
rq_throughput=0
update_throughput=3904414
query_throughput=35138026
total_throughput=39042441


total find                    : 351380268
total rq                      : 0
total inserts                 : 19528930
total deletes                 : 19515215
total updates                 : 39044145
total queries                 : 351380268
total ops                     : 390424413
find throughput               : 35138026
rq throughput                 : 0
update throughput             : 3904414
query throughput              : 35138026
total throughput              : 39042441

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=34.0123
PAPI_L3_TCM=7.50294
PAPI_TOT_CYC=7572.64
PAPI_TOT_INS=2439.62
garbage=1734445408893859
total_execution_walltime=15.787s
REALTIME_MAIN_END_PERF_FORMAT=6995531.832948523
TL2 system shutdown:
  GCLOCK=0x18889AA Starts=448611850 Aborts=9904762
  Overflows: R=0 W=12207 L=0
[time_cmd_output] time_elapsed_sec=16.09, faults_major=0, faults_minor=874287, mem_maxresident_kb=2352372, user_cputime=2107.51, sys_cputime=123.44, percent_cpu=13857%
timeout=false
algorithm=brown_abtree_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