__step=000029
__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 0.5 0.5 -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=6979057.193012374
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=0.5
DEL_FRAC=0.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=0.5 0.5
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.025s
prefilling_size=3795453 prefilling_elapsed_millis=1.001s
prefilling_size=4077315 prefilling_elapsed_millis=1.101s
prefilling_size=4361522 prefilling_elapsed_millis=1.201s
prefilling_size=4617749 prefilling_elapsed_millis=1.302s
prefilling_size=4874695 prefilling_elapsed_millis=1.402s
prefilling_size=5108583 prefilling_elapsed_millis=1.502s
prefilling_size=5344013 prefilling_elapsed_millis=1.602s
prefilling_size=5557879 prefilling_elapsed_millis=1.702s
prefilling_size=5771350 prefilling_elapsed_millis=1.803s
prefilling_size=5961636 prefilling_elapsed_millis=1.903s
prefilling_size=6154953 prefilling_elapsed_millis=2.003s
prefilling_size=6328872 prefilling_elapsed_millis=2.103s
prefilling_size=6504160 prefilling_elapsed_millis=2.203s
prefilling_size=6665319 prefilling_elapsed_millis=2.303s
prefilling_size=6825358 prefilling_elapsed_millis=2.404s
prefilling_size=6969005 prefilling_elapsed_millis=2.504s
prefilling_size=7114474 prefilling_elapsed_millis=2.604s
prefilling_size=7247951 prefilling_elapsed_millis=2.704s
prefilling_size=7381023 prefilling_elapsed_millis=2.804s
prefilling_size=7499217 prefilling_elapsed_millis=2.905s
prefilling_size=7617852 prefilling_elapsed_millis=3.005s
prefilling_size=7726228 prefilling_elapsed_millis=3.105s
prefilling_size=7833820 prefilling_elapsed_millis=3.205s
prefilling_size=7930430 prefilling_elapsed_millis=3.305s
prefilling_size=8027799 prefilling_elapsed_millis=3.406s
prefilling_size=8116263 prefilling_elapsed_millis=3.506s
prefilling_size=8205197 prefilling_elapsed_millis=3.606s
prefilling_size=8286809 prefilling_elapsed_millis=3.706s
prefilling_size=8368611 prefilling_elapsed_millis=3.806s
prefilling_size=8442547 prefilling_elapsed_millis=3.907s
prefilling_size=8516740 prefilling_elapsed_millis=4.007s
prefilling_size=8584239 prefilling_elapsed_millis=4.107s
prefilling_size=8652320 prefilling_elapsed_millis=4.207s
prefilling_size=8714443 prefilling_elapsed_millis=4.307s
prefilling_size=8775495 prefilling_elapsed_millis=4.408s
prefilling_size=8831780 prefilling_elapsed_millis=4.508s
prefilling_size=8888396 prefilling_elapsed_millis=4.608s
prefilling_size=8939171 prefilling_elapsed_millis=4.708s
prefilling_size=8989154 prefilling_elapsed_millis=4.809s
prefilling_size=9036589 prefilling_elapsed_millis=4.909s
prefilling_size=9082486 prefilling_elapsed_millis=5.009s
Info: prefilling requires an additional insert phase to complete.
Info: prefilling using INSERTION ONLY.
timing_start inserting 917460 keys with 144 threads...
tid=0 thread_prefill_amount=65236 percent_done_estimate=1023.9 elapsed_s=5 est_remaining_s=-10 / -5
tid=0 thread_prefill_amount=66624 percent_done_estimate=1045.7 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=68012 percent_done_estimate=1067.5 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=69400 percent_done_estimate=1089.3 elapsed_s=5 est_remaining_s=-11 / -5
tid=0 thread_prefill_amount=70788 percent_done_estimate=1111.1 elapsed_s=5 est_remaining_s=-11 / -5
timing_elapsed 0.103525s
prefilling_size=10000000 prefilling_elapsed_millis=0.103s
finished prefilling to size 10000000 for expected size 10000000 keysum=100011455055763, performing 49595263 updates; total_prefilling_elapsed_ms=103 ms)
pref_size=10000000
pref_millis=103
prefill_elapsed_ms=103
main thread: starting timer...

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

REALTIME_START_PERF_FORMAT=6979062.676158324
REALTIME_END_PERF_FORMAT=6979072.676253412

###############################################################################
################################## 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.17s

tree_stats_numInternalsAtDepth=0
tree_stats_numLeavesAtDepth=1
tree_stats_numNodesAtDepth=1
tree_stats_numKeysAtDepth=9998846
tree_stats_avgDegreeAtDepth=9.99885e+06

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

tree_stats_avgDegreeInternal=0
tree_stats_avgDegreeLeaves=9.99885e+06
tree_stats_avgDegree=9.99885e+06
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=36097 36436 36537 36402 36503 36095 36075 36555 36595 36538 36414 36160 36313 36547 36538 36917 36695 36488 36262 35891 36383 36665 36126 36206 36074 35997 36597 36319 36498 36303 36330 36385 36544 36823 36800 36383 36227 36298 36572 36680 36800 36546 35915 36199 36413 36288 36196 36502 36540 37003 37118 36601 36781 36554 36302 36421 37375 36958 36868 36529 36510 36819 37187 37367 36957 36461 36094 36407 36885 36789 36296 36497 36657 36870 37210 36662 36821 36199 36224 36269 36999 36985 36937 36490 36423 37070 37120 36744 37136 36811 36095 36719 37216 36776 36715 36608 36030 36323 36453 36156 36130 35889 35758 36137 36304 36603 36476 36013 35976 36449 36839 36638 36295 36223 36059 35654 36271 36351 35905 35750 36058 36095 36296 36728 36107 36034 36146 36148 36684 36997 36278 36201 35864 36371 36304 36767 36481 35946 35941 36123 36404 36580 36164 35971
sum_num_inserts_total=5249572
sum_num_deletes_by_thread=36052 36349 36597 36633 36508 36236 36532 36175 37159 36809 36181 36246 36126 36946 36528 36836 36645 36425 36170 36317 36384 36859 35928 35952 35747 36046 36581 36950 36560 36326 35974 36804 36964 36837 36412 36143 36544 36496 36846 37108 36629 36552 35764 36294 36426 36861 36325 36195 36405 36933 37204 36933 36650 36417 36352 36594 36904 37160 36781 36513 36527 36253 37350 37182 36734 36958 36339 36550 36588 36827 36671 36454 36338 36767 36826 37020 36852 36333 36229 36349 37131 37178 36977 36341 36300 36446 37222 37077 36911 36701 36180 36531 36765 36637 36501 36580 35721 36011 36137 36410 36126 36051 35988 36014 36729 36679 35943 36169 35993 36064 36472 36924 36532 35842 35758 35973 36073 36470 36017 35853 35843 36244 36447 36096 35907 35806 36277 35951 36388 36493 36138 36143 36207 36312 36524 36401 36271 35945 35764 35939 36571 36391 36238 35926
sum_num_deletes_total=5247619
sum_num_searches_by_thread=7151118 7187637 7235475 7248168 7230293 7176233 7168608 7203546 7294173 7286503 7201788 7181027 7195995 7252014 7288944 7282355 7231615 7222009 7146251 7185247 7214894 7250152 7169895 7139766 7164262 7183875 7242758 7252320 7229864 7190936 7169012 7205036 7294593 7294997 7204470 7183833 7200290 7242728 7277949 7282688 7232635 7219641 7144016 7182835 7203079 7254646 7168214 7147975 7234502 7280045 7322502 7322478 7262221 7220395 7229127 7259201 7343305 7347686 7291414 7261519 7255523 7285452 7351104 7347629 7307594 7259628 7166033 7212665 7294061 7284301 7256494 7231552 7225536 7275775 7311410 7316278 7261976 7212542 7217546 7253214 7335750 7341711 7289195 7262406 7249347 7280199 7339101 7318252 7298658 7251715 7168142 7195643 7282639 7274917 7234725 7213305 7109409 7158437 7198959 7199529 7154303 7103183 7131493 7161640 7238449 7251068 7184177 7136358 7162754 7201365 7258211 7252255 7183513 7137309 7103922 7122746 7167929 7203925 7161092 7095465 7117649 7167514 7211712 7205913 7169914 7112249 7144600 7173549 7242979 7254395 7180617 7133580 7156942 7191364 7260404 7255588 7189570 7141941 7103868 7132276 7174087 7194177 7161193 7091728
sum_num_searches_total=1039598071
sum_num_rq_by_thread=
sum_num_rq_total=
sum_num_operations_by_thread=7223267 7260422 7308609 7321203 7303304 7248564 7241215 7276276 7367927 7359850 7274383 7253433 7268434 7325507 7362010 7356108 7304955 7294922 7218683 7257455 7287661 7323676 7241949 7211924 7236083 7255918 7315936 7325589 7302922 7263565 7241316 7278225 7368101 7368657 7277682 7256359 7273061 7315522 7351367 7356476 7306064 7292739 7215695 7255328 7275918 7327795 7240735 7220672 7307447 7353981 7396824 7396012 7335652 7293366 7301781 7332216 7417584 7421804 7365063 7334561 7328560 7358524 7425641 7422178 7381285 7333047 7238466 7285622 7367534 7357917 7329461 7304503 7298531 7349412 7385446 7389960 7335649 7285074 7289999 7325832 7409880 7415874 7363109 7335237 7322070 7353715 7413443 7392073 7372705 7325227 7240417 7268893 7356620 7348330 7307941 7286493 7181160 7230771 7271549 7272095 7226559 7175123 7203239 7233791 7311482 7324350 7256596 7208540 7234723 7273878 7331522 7325817 7256340 7209374 7175739 7194373 7240273 7276746 7233014 7167068 7189550 7239853 7284455 7278737 7241928 7184089 7217023 7245648 7316051 7327885 7253033 7205924 7229013 7264047 7333232 7328756 7262322 7213832 7175573 7204338 7247062 7267148 7233595 7163625
average_num_operations_total=7292328
stdev_num_operations_total=5243
sum_num_operations_total=1050095262
min_num_operations_total=7163625
max_num_operations_total=7425641
first_time_thread_terminate_by_thread=10000192 10000095 10000095 10000097 10000095 10000096 10000097 10000098 10000095 10000096 10000097 10000097 10000096 10000096 10000096 10000095 10000095 10000097 10000094 10000099 10000096 10000096 10000098 10000098 10000094 10000095 10000095 10000097 10000095 10000095 10000097 10000098 10000095 10000096 10000097 10000097 10000096 10000094 10000096 10000096 10000096 10000096 10000094 10000099 10000096 10000095 10000098 10000098 10000097 10000099 10000098 10000098 10000099 10000097 10000098 10000099 10000099 10000097 10000099 10000097 10000096 10000098 10000097 10000099 10000099 10000097 10000097 10000097 10000098 10000098 10000097 10000099 10000097 10000099 10000098 10000098 10000099 10000097 10000098 10000099 10000099 10000097 10000099 10000097 10000096 10000096 10000097 10000097 10000099 10000097 10000097 10000097 10000098 10000096 10000097 10000097 10000096 10000096 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000096 10000095 10000096 10000095 10000095 10000095 10000095 10000096 10000095 10000096 10000095 10000095 10000096 10000095 10000096 10000096 10000096 10000095 10000095 10000095 10000095 10000095 10000095 10000095 10000096 10000095 10000096 10000095 10000095 10000097 10000095 10000096 10000095 10000096 10000095 10000095 10000096 10000095 10000096
min_time_thread_terminate_total=10000094
max_time_thread_terminate_total=10000192
first_time_thread_start_by_thread=12 8 8 8 8 8 7 7 8 8 8 7 7 8 7 7 8 7 7 8 8 8 8 7 7 8 8 8 8 8 7 7 8 8 8 7 7 8 7 7 8 7 7 8 8 8 8 7 8 8 8 8 9 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 9 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8 8
min_time_thread_start_total=7
max_time_thread_start_total=12
sum_duration_all_ops_total=

threads_final_keysum=100003420584287
threads_final_size=9998846
final_keysum=100003420584287
final_size=9998846
validate_result=success
Validation OK.
Structural validation OK.

total_find=1039598071
total_rq=0
total_inserts=5249572
total_deletes=5247619
total_updates=10497191
total_queries=1039598071
total_ops=1050095262
find_throughput=103959807
rq_throughput=0
update_throughput=1049719
query_throughput=103959807
total_throughput=105009526


total find                    : 1039598071
total rq                      : 0
total inserts                 : 5249572
total deletes                 : 5247619
total updates                 : 10497191
total queries                 : 1039598071
total ops                     : 1050095262
find throughput               : 103959807
rq throughput                 : 0
update throughput             : 1049719
query throughput              : 103959807
total throughput              : 105009526

elapsed milliseconds          : 10000
napping milliseconds overtime : 100

PAPI_L2_TCM=35.1421
PAPI_L3_TCM=15.2631
PAPI_TOT_CYC=3736.7
PAPI_TOT_INS=774.676
garbage=5200636497716073
total_execution_walltime=15.757s
REALTIME_MAIN_END_PERF_FORMAT=6979072.965784231
[time_cmd_output] time_elapsed_sec=15.99, faults_major=0, faults_minor=388431, mem_maxresident_kb=1534268, user_cputime=2196.28, sys_cputime=8.81, percent_cpu=13782%
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=1