__step=000366
__cmd_run=LD_PRELOAD=../../setbench/lib/libjemalloc.so timeout 60 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" ./wang_openbwtree -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=data000366.txt
__path_data=data/data000366.txt
__hostname=jax
REALTIME_MAIN_START_PERF_FORMAT=6983197.336577154
binary=./wang_openbwtree
parsed custom binding: 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
DS_TYPENAME=wang_openbwtree
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
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


Info: prefilling using hybrid approach; mixed then insert-only if needed.
prefilling initialization took 0.016s
prefilling_size=3691513 prefilling_elapsed_millis=1.001s
prefilling_size=3975927 prefilling_elapsed_millis=1.101s
prefilling_size=4253355 prefilling_elapsed_millis=1.201s
prefilling_size=4513945 prefilling_elapsed_millis=1.301s
prefilling_size=4767526 prefilling_elapsed_millis=1.402s
prefilling_size=5005002 prefilling_elapsed_millis=1.502s
prefilling_size=5235637 prefilling_elapsed_millis=1.602s
prefilling_size=5452605 prefilling_elapsed_millis=1.702s
prefilling_size=5663612 prefilling_elapsed_millis=1.802s
prefilling_size=5859872 prefilling_elapsed_millis=1.903s
prefilling_size=6050264 prefilling_elapsed_millis=2.003s
prefilling_size=6227998 prefilling_elapsed_millis=2.103s
prefilling_size=6400634 prefilling_elapsed_millis=2.203s
prefilling_size=6561843 prefilling_elapsed_millis=2.303s
prefilling_size=6719896 prefilling_elapsed_millis=2.404s
prefilling_size=6867350 prefilling_elapsed_millis=2.504s
prefilling_size=7010322 prefilling_elapsed_millis=2.604s
prefilling_size=7144460 prefilling_elapsed_millis=2.704s
prefilling_size=7274725 prefilling_elapsed_millis=2.804s
prefilling_size=7397608 prefilling_elapsed_millis=2.905s
prefilling_size=7516634 prefilling_elapsed_millis=3.005s
prefilling_size=7630383 prefilling_elapsed_millis=3.105s
prefilling_size=7738905 prefilling_elapsed_millis=3.205s
prefilling_size=7842756 prefilling_elapsed_millis=3.305s
prefilling_size=7941694 prefilling_elapsed_millis=3.406s
prefilling_size=8036519 prefilling_elapsed_millis=3.506s
prefilling_size=8126331 prefilling_elapsed_millis=3.606s
prefilling_size=8210402 prefilling_elapsed_millis=3.706s
prefilling_size=8291426 prefilling_elapsed_millis=3.806s
prefilling_size=8369449 prefilling_elapsed_millis=3.907s
prefilling_size=8443304 prefilling_elapsed_millis=4.007s
prefilling_size=8512754 prefilling_elapsed_millis=4.107s
prefilling_size=8580498 prefilling_elapsed_millis=4.207s
prefilling_size=8645595 prefilling_elapsed_millis=4.307s
prefilling_size=8706955 prefilling_elapsed_millis=4.408s
prefilling_size=8765375 prefilling_elapsed_millis=4.508s
prefilling_size=8821679 prefilling_elapsed_millis=4.608s
prefilling_size=8875096 prefilling_elapsed_millis=4.708s
prefilling_size=8925966 prefilling_elapsed_millis=4.809s
prefilling_size=8974932 prefilling_elapsed_millis=4.909s
prefilling_size=9021325 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 978645 keys with 144 threads...
tid=0 thread_prefill_amount=63848 percent_done_estimate=939.5 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=65236 percent_done_estimate=959.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=66624 percent_done_estimate=980.3 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=68012 percent_done_estimate=1000.7 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=69400 percent_done_estimate=1021.2 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.133226s
prefilling_size=10000000 prefilling_elapsed_millis=0.133s
finished prefilling to size 10000000 for expected size 10000000 keysum=99991712987269, performing 48451625 updates; total_prefilling_elapsed_ms=133 ms)
pref_size=10000000
pref_millis=133
prefill_elapsed_ms=133
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6983202.822483742
REALTIME_END_PERF_FORMAT=6983212.822592875

###############################################################################
################################## TIME IS UP #################################
###############################################################################

joining threads...
###############################################################################
################################# END RUNNING #################################
###############################################################################

10.1s
PRODUCING OUTPUT
Open BwTree: createTreeStats iterating over all kv-pairs...
Open BwTree: createTreeStats finished iterating.

tree_stats_computeWalltime=0.185s

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=10000792
tree_stats_avgDegreeAtDepth=1.00008e+07

tree_stats_height=1
tree_stats_numInternals=0
tree_stats_numLeaves=1
tree_stats_numNodes=1
tree_stats_numKeys=10000792

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=1.00008e+07
tree_stats_avgDegree=1.00008e+07
tree_stats_avgKeyDepth=0


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=
first_thread_announced_epoch_by_thread=
sum_num_inserts_by_thread=149971 151683 151050 150539 151286 149855 150691 151569 152330 151834 151423 150468 151032 150964 151956 150820 151598 150790 150834 150625 150849 152179 150896 149825 150269 151097 151308 150473 151014 149889 151617 151205 151857 152127 152381 150922 151250 150362 151383 151001 151636 150968 150229 150098 150689 152348 151234 150437 150457 152022 152915 151528 152133 151307 151379 151980 153690 153314 154152 152570 152256 152683 151570 152017 151407 150289 149825 150354 151526 151727 152202 151627 150738 151046 151890 151428 151515 151087 150311 152256 153529 152907 153413 153018 152006 152830 150800 150990 151396 151068 150315 150096 151731 152140 150433 151277 155386 154799 155374 156490 155151 156292 156129 156047 156332 156385 156403 155322 156270 158152 155471 155738 155948 154967 156148 156074 155797 156627 155966 155366 155289 154737 156196 155710 156296 156069 155521 155889 157185 157094 156853 156031 156506 157081 156165 155662 156307 154875 155454 156080 156473 155703 155304 155635
sum_num_inserts_total=22020760
sum_num_deletes_by_thread=150469 150729 151051 149757 151611 149531 150771 152088 151810 152548 152603 150921 151868 150849 151835 150293 151583 150460 149700 150415 151285 152580 151051 150259 150498 151412 151497 150685 151218 150372 151191 151323 152567 151692 151158 150698 150898 150603 150658 150677 151496 150719 150743 150501 151487 153019 151203 150555 150977 151911 152894 152789 152396 150796 151928 151993 153965 153972 153468 152785 152372 153449 151615 151244 151761 150957 150006 150925 151971 151968 151798 151248 150574 151075 152246 150626 151376 151086 151453 151545 153169 152495 153627 152647 152844 153101 151153 151814 151557 149666 150085 150405 152259 151645 151607 151537 155453 154631 155837 155828 155732 155276 156176 155794 157470 157679 157251 155913 156428 157793 155628 155771 155235 154854 155860 155864 155893 156267 156225 155115 155123 154987 155951 155429 155674 156162 155303 155499 157235 157643 158182 156046 156575 157463 155832 156740 155919 155036 156035 156409 156854 156707 155823 155584
sum_num_deletes_total=22033901
sum_num_searches_by_thread=2697197 2724431 2724587 2711274 2728724 2700421 2725592 2734356 2739956 2740492 2728605 2712469 2720967 2711671 2729156 2710771 2732631 2718624 2712690 2708543 2712966 2739464 2719171 2698761 2708712 2722695 2725600 2710156 2727012 2697929 2724370 2729804 2736745 2735535 2728543 2712525 2718177 2713157 2730747 2712464 2734866 2722982 2712406 2712571 2712925 2739294 2718445 2704921 2719628 2729967 2741084 2738180 2734612 2720134 2736776 2742878 2765041 2761790 2765534 2751055 2746388 2761874 2727740 2729028 2727027 2714161 2701814 2701889 2737292 2737096 2733590 2721237 2717236 2719698 2736071 2728959 2723327 2713370 2720664 2725676 2758421 2748050 2756687 2744483 2738905 2752129 2724708 2717486 2726585 2710658 2700555 2704589 2734493 2735197 2718799 2719868 2791938 2798857 2806038 2800840 2805089 2803426 2799512 2804819 2829245 2826544 2827105 2799449 2819006 2831571 2806128 2804234 2806789 2788756 2797399 2802526 2812755 2811841 2809755 2793127 2792333 2795856 2808282 2804178 2802498 2807902 2802163 2806913 2833419 2837817 2830744 2807786 2822288 2839905 2809743 2808199 2806495 2796335 2803654 2809972 2811767 2812032 2800833 2799775
sum_num_searches_total=396526167
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=2997637 3026843 3026688 3011570 3031621 2999807 3027054 3038013 3044096 3044874 3032631 3013858 3023867 3013484 3032947 3011884 3035812 3019874 3013224 3009583 3015100 3044223 3021118 2998845 3009479 3025204 3028405 3011314 3029244 2998190 3027178 3032332 3041169 3039354 3032082 3014145 3020325 3014122 3032788 3014142 3037998 3024669 3013378 3013170 3015101 3044661 3020882 3005913 3021062 3033900 3046893 3042497 3039141 3022237 3040083 3046851 3072696 3069076 3073154 3056410 3051016 3068006 3030925 3032289 3030195 3015407 3001645 3003168 3040789 3040791 3037590 3024112 3018548 3021819 3040207 3031013 3026218 3015543 3022428 3029477 3065119 3053452 3063727 3050148 3043755 3058060 3026661 3020290 3029538 3011392 3000955 3005090 3038483 3038982 3020839 3022682 3102777 3108287 3117249 3113158 3115972 3114994 3111817 3116660 3143047 3140608 3140759 3110684 3131704 3147516 3117227 3115743 3117972 3098577 3109407 3114464 3124445 3124735 3121946 3103608 3102745 3105580 3120429 3115317 3114468 3120133 3112987 3118301 3147839 3152554 3145779 3119863 3135369 3154449 3121740 3120601 3118721 3106246 3115143 3122461 3125094 3124442 3111960 3110994
average_num_operations_total=3059589
stdev_num_operations_total=3885
sum_num_operations_total=440580828
min_num_operations_total=2997637
max_num_operations_total=3154449
first_time_thread_terminate_by_thread=10000190 10000111 10000112 10000112 10000113 10000113 10000112 10000111 10000109 10000110 10000111 10000112 10000111 10000111 10000109 10000109 10000108 10000109 10000112 10000113 10000109 10000109 10000109 10000112 10000112 10000109 10000112 10000111 10000113 10000113 10000112 10000111 10000109 10000110 10000111 10000112 10000111 10000109 10000109 10000109 10000108 10000109 10000109 10000113 10000113 10000109 10000109 10000112 10000112 10000110 10000109 10000110 10000109 10000112 10000109 10000111 10000111 10000110 10000109 10000111 10000111 10000110 10000111 10000111 10000109 10000110 10000113 10000110 10000110 10000109 10000111 10000111 10000111 10000110 10000109 10000110 10000109 10000112 10000109 10000111 10000110 10000110 10000109 10000111 10000109 10000110 10000111 10000111 10000110 10000110 10000113 10000110 10000110 10000109 10000111 10000111 10000113 10000109 10000114 10000114 10000113 10000109 10000109 10000114 10000109 10000109 10000113 10000109 10000113 10000110 10000112 10000109 10000109 10000113 10000109 10000112 10000113 10000113 10000112 10000109 10000109 10000109 10000114 10000114 10000113 10000114 10000109 10000114 10000109 10000114 10000113 10000109 10000113 10000110 10000112 10000109 10000109 10000113 10000109 10000112 10000113 10000109 10000112 10000109
min_time_thread_terminate_total=10000108
max_time_thread_terminate_total=10000190
first_time_thread_start_by_thread=11 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 4 5 5 5 5 6 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 5 6 5 5 6 6 6 5 6 6 5 6 5 5 5 5 6 6 5 5 6 6 6 6 6 6 5 5 6 6 6 5 6 6 5 6 5 5 5 5 6 6 5 5 6 6 6 6 6 5 5 6 5 5 5 5 6 5 5 6 6 5 6 5 6 5 5 5 5 6 6 6 5 5 5 6 5 5 5 5 6 5 5 6 6 5 6 5 6 5 5 5 5 6 6 6 5
min_time_thread_start_total=4
max_time_thread_start_total=11
sum_duration_all_ops_total=

threads_final_keysum=100016749822399
threads_final_size=10000792
final_keysum=100016749822399
final_size=10000792
validate_result=success
Validation OK.
Structural validation OK.

total_find=396526167
total_rq=0
total_inserts=22020760
total_deletes=22033901
total_updates=44054661
total_queries=396526167
total_ops=440580828
find_throughput=39652616
rq_throughput=0
update_throughput=4405466
query_throughput=39652616
total_throughput=44058082


total find                    : 396526167
total rq                      : 0
total inserts                 : 22020760
total deletes                 : 22033901
total updates                 : 44054661
total queries                 : 396526167
total ops                     : 440580828
find throughput               : 39652616
rq throughput                 : 0
update throughput             : 4405466
query throughput              : 39652616
total throughput              : 44058082

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=37.4611
PAPI_L3_TCM=16.748
PAPI_TOT_CYC=9081.82
PAPI_TOT_INS=829.288
garbage=1928017560099433
total_execution_walltime=15.772s
REALTIME_MAIN_END_PERF_FORMAT=6983213.125529194
[time_cmd_output] time_elapsed_sec=15.99, faults_major=0, faults_minor=371945, mem_maxresident_kb=1441784, user_cputime=2197.09, sys_cputime=7.06, percent_cpu=13776%
timeout=false
algorithm=wang_openbwtree
thread_pinning=-pin 0-23,96-119,24-47,120-143,48-71,144-167,72-95,168-191
millis=10000
__trials=2